2018-09-20T05:29:46.263Z,1537421386.263 [Supervisor](DEBUG): Initializing supervisor.
2018-09-20T05:29:46.266Z,1537421386.266 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-09-20T05:29:46.266Z,1537421386.266 [SyncHandler](INFO): Protected caller Thread ID is 3129
2018-09-20T05:29:46.267Z,1537421386.267 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-09-20T05:29:46.268Z,1537421386.268 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-09-20T05:29:46.268Z,1537421386.268 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3130
2018-09-20T05:29:46.271Z,1537421386.271 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-09-20T05:29:46.286Z,1537421386.286 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-09-20T05:29:46.287Z,1537421386.287 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-09-20T05:29:46.287Z,1537421386.287 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3131
2018-09-20T05:29:46.288Z,1537421386.288 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-09-20T05:29:46.289Z,1537421386.289 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-09-20T05:29:46.290Z,1537421386.290 [logger ThreadHandler](INFO): Protected caller Thread ID is 3132
2018-09-20T05:29:46.292Z,1537421386.292 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-09-20T05:29:46.292Z,1537421386.292 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-09-20T05:29:46.294Z,1537421386.294 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-09-20T05:29:46.719Z,1537421386.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-09-20T05:29:46.719Z,1537421386.719 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-09-20T05:29:46.933Z,1537421386.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-09-20T05:29:46.934Z,1537421386.934 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-09-20T05:29:47.258Z,1537421387.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-09-20T05:29:47.258Z,1537421387.258 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-09-20T05:29:47.405Z,1537421387.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-09-20T05:29:47.405Z,1537421387.405 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-09-20T05:29:47.509Z,1537421387.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-09-20T05:29:47.510Z,1537421387.510 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-09-20T05:29:48.017Z,1537421388.017 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-09-20T05:29:48.018Z,1537421388.018 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-09-20T05:29:48.508Z,1537421388.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-09-20T05:29:48.509Z,1537421388.509 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-09-20T05:29:49.213Z,1537421389.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-09-20T05:29:49.214Z,1537421389.214 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-09-20T05:29:49.323Z,1537421389.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-09-20T05:29:49.324Z,1537421389.324 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-09-20T05:29:49.425Z,1537421389.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-09-20T05:29:49.426Z,1537421389.426 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-09-20T05:29:49.645Z,1537421389.645 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-09-20T05:29:49.645Z,1537421389.645 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-09-20T05:29:49.860Z,1537421389.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-09-20T05:29:49.860Z,1537421389.860 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-09-20T05:29:50.235Z,1537421390.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-09-20T05:29:50.235Z,1537421390.235 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-09-20T05:29:50.385Z,1537421390.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-09-20T05:29:50.386Z,1537421390.386 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-09-20T05:29:50.470Z,1537421390.470 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-09-20T05:29:50.471Z,1537421390.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-09-20T05:29:50.578Z,1537421390.578 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-09-20T05:29:50.703Z,1537421390.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-09-20T05:29:50.801Z,1537421390.801 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-09-20T05:29:50.889Z,1537421390.889 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-09-20T05:29:51.057Z,1537421391.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-09-20T05:29:51.246Z,1537421391.246 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-09-20T05:29:51.324Z,1537421391.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-09-20T05:29:51.566Z,1537421391.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-09-20T05:29:51.567Z,1537421391.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-09-20T05:29:51.653Z,1537421391.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-09-20T05:29:51.746Z,1537421391.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-09-20T05:29:51.852Z,1537421391.852 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-09-20T05:29:51.987Z,1537421391.987 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2018-09-20T05:29:51.987Z,1537421391.987 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-09-20T05:29:52.007Z,1537421392.007 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-09-20T05:29:52.271Z,1537421392.271 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-09-20T05:29:52.271Z,1537421392.271 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-09-20T05:29:52.318Z,1537421392.318 [DepthRateCalculator] Loaded
2018-09-20T05:29:52.318Z,1537421392.318 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-09-20T05:29:52.325Z,1537421392.325 [PitchRateCalculator] Loaded
2018-09-20T05:29:52.325Z,1537421392.325 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-09-20T05:29:52.343Z,1537421392.343 [SpeedCalculator] Loaded
2018-09-20T05:29:52.343Z,1537421392.343 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-09-20T05:29:52.366Z,1537421392.366 [TempGradientCalculator] Loaded
2018-09-20T05:29:52.366Z,1537421392.366 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-09-20T05:29:52.372Z,1537421392.372 [YawRateCalculator] Loaded
2018-09-20T05:29:52.373Z,1537421392.373 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-09-20T05:29:52.416Z,1537421392.416 [ElevatorOffsetCalculator] Loaded
2018-09-20T05:29:52.416Z,1537421392.416 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-09-20T05:29:52.417Z,1537421392.417 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-09-20T05:29:52.417Z,1537421392.417 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-09-20T05:29:52.490Z,1537421392.490 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-09-20T05:29:52.491Z,1537421392.491 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-09-20T05:29:52.675Z,1537421392.675 [BuoyancyServo] Loaded
2018-09-20T05:29:52.675Z,1537421392.675 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-09-20T05:29:52.676Z,1537421392.676 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-09-20T05:29:52.677Z,1537421392.677 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-09-20T05:29:52.691Z,1537421392.691 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-09-20T05:29:52.692Z,1537421392.692 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-09-20T05:29:52.803Z,1537421392.803 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-09-20T05:29:52.804Z,1537421392.804 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-09-20T05:29:53.120Z,1537421393.120 [DataOverHttps] Loaded
2018-09-20T05:29:53.121Z,1537421393.121 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-09-20T05:29:53.135Z,1537421393.135 [Depth_Keller] Loaded
2018-09-20T05:29:53.136Z,1537421393.136 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-09-20T05:29:53.141Z,1537421393.141 [DropWeight] Loaded
2018-09-20T05:29:53.141Z,1537421393.141 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-09-20T05:29:53.248Z,1537421393.248 [NAL9602] Loaded
2018-09-20T05:29:53.248Z,1537421393.248 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-09-20T05:29:53.265Z,1537421393.265 [Onboard] Loaded
2018-09-20T05:29:53.265Z,1537421393.265 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-09-20T05:29:53.277Z,1537421393.277 [Radio_Surface] Loaded
2018-09-20T05:29:53.277Z,1537421393.277 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-09-20T05:29:53.278Z,1537421393.278 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088F4E0
2018-09-20T05:29:53.279Z,1537421393.279 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3214
2018-09-20T05:29:53.330Z,1537421393.330 [PNI_TCM] Loaded
2018-09-20T05:29:53.331Z,1537421393.331 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-09-20T05:29:55.546Z,1537421395.546 [BPC1] Loaded
2018-09-20T05:29:55.546Z,1537421395.546 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-09-20T05:29:55.547Z,1537421395.547 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-09-20T05:29:55.547Z,1537421395.547 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-09-20T05:29:55.689Z,1537421395.689 [SBIT](DEBUG): Construct Startup Built In Test.
2018-09-20T05:29:55.714Z,1537421395.714 [SBIT] Loaded
2018-09-20T05:29:55.714Z,1537421395.714 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-09-20T05:29:55.715Z,1537421395.715 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-09-20T05:29:55.727Z,1537421395.727 [IBIT] Loaded
2018-09-20T05:29:55.727Z,1537421395.727 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-09-20T05:29:55.730Z,1537421395.730 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-09-20T05:29:55.890Z,1537421395.890 [CBIT] Loaded
2018-09-20T05:29:55.890Z,1537421395.890 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-09-20T05:29:55.891Z,1537421395.891 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-09-20T05:29:55.891Z,1537421395.891 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-09-20T05:29:56.065Z,1537421396.065 [Aanderaa_O2] Loaded
2018-09-20T05:29:56.065Z,1537421396.065 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-09-20T05:29:56.150Z,1537421396.150 [CTD_NeilBrown] Loaded
2018-09-20T05:29:56.150Z,1537421396.150 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-09-20T05:29:56.151Z,1537421396.151 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409594E0
2018-09-20T05:29:56.152Z,1537421396.152 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 3215
2018-09-20T05:29:56.206Z,1537421396.206 [CTD_Seabird] Loaded
2018-09-20T05:29:56.207Z,1537421396.207 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-09-20T05:29:56.208Z,1537421396.208 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409894E0
2018-09-20T05:29:56.208Z,1537421396.208 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3216
2018-09-20T05:29:56.226Z,1537421396.226 [ESPComponent] Loaded
2018-09-20T05:29:56.226Z,1537421396.226 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-09-20T05:29:56.241Z,1537421396.241 [PAR_Licor] Loaded
2018-09-20T05:29:56.242Z,1537421396.242 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-09-20T05:29:56.295Z,1537421396.295 [WetLabsBB2FL] Loaded
2018-09-20T05:29:56.295Z,1537421396.295 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-09-20T05:29:56.296Z,1537421396.296 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0
2018-09-20T05:29:56.297Z,1537421396.297 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3217
2018-09-20T05:29:56.297Z,1537421396.297 [Module Loader](CRITICAL): Could not find element WetLabsUBAT.loadAtStartup
2018-09-20T05:29:56.298Z,1537421396.298 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-09-20T05:29:56.298Z,1537421396.298 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-09-20T05:29:56.373Z,1537421396.373 [VerticalControl](DEBUG): Construct VerticalControl.
2018-09-20T05:29:56.470Z,1537421396.470 [VerticalControl] Loaded
2018-09-20T05:29:56.471Z,1537421396.471 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-09-20T05:29:56.471Z,1537421396.471 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-09-20T05:29:56.538Z,1537421396.538 [HorizontalControl] Loaded
2018-09-20T05:29:56.538Z,1537421396.538 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-09-20T05:29:56.539Z,1537421396.539 [SpeedControl](DEBUG): Construct SpeedControl.
2018-09-20T05:29:56.545Z,1537421396.545 [SpeedControl] Loaded
2018-09-20T05:29:56.545Z,1537421396.545 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-09-20T05:29:56.546Z,1537421396.546 [LoopControl](DEBUG): Construct LoopControl.
2018-09-20T05:29:56.546Z,1537421396.546 [LoopControl] Loaded
2018-09-20T05:29:56.546Z,1537421396.546 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-09-20T05:29:56.547Z,1537421396.547 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-09-20T05:29:56.547Z,1537421396.547 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-09-20T05:29:56.574Z,1537421396.574 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-09-20T05:29:56.575Z,1537421396.575 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-09-20T05:29:56.673Z,1537421396.673 [DeadReckonUsingSpeedCalculator] Loaded
2018-09-20T05:29:56.674Z,1537421396.674 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-09-20T05:29:56.691Z,1537421396.691 [NavChart] Loaded
2018-09-20T05:29:56.692Z,1537421396.692 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-09-20T05:29:56.696Z,1537421396.696 [UniversalFixResidualReporter] Loaded
2018-09-20T05:29:56.696Z,1537421396.696 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-09-20T05:29:56.697Z,1537421396.697 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-09-20T05:29:56.700Z,1537421396.700 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-09-20T05:29:56.701Z,1537421396.701 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-09-20T05:29:56.708Z,1537421396.708 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-09-20T05:29:56.709Z,1537421396.709 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0
2018-09-20T05:29:56.710Z,1537421396.710 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3218
2018-09-20T05:29:56.715Z,1537421396.715 [Supervisor](INFO): Main Thread ID is 9268
2018-09-20T05:29:56.715Z,1537421396.715 [Supervisor](DEBUG): Running supervisor.
2018-09-20T05:29:56.715Z,1537421396.715 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3219
2018-09-20T05:29:56.718Z,1537421396.718 [controlThread ThreadHandler](INFO): Handler Thread ID is 3220
2018-09-20T05:29:56.718Z,1537421396.718 [controlThread](DEBUG): Initializing ControlThread
2018-09-20T05:29:56.719Z,1537421396.719 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-20T05:29:56.720Z,1537421396.720 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-09-20T05:29:56.720Z,1537421396.720 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-09-20T05:29:56.720Z,1537421396.720 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-09-20T05:29:56.721Z,1537421396.721 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-09-20T05:29:56.721Z,1537421396.721 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-09-20T05:29:56.727Z,1537421396.727 [SBIT](INFO): Initialize SBIT Component.
2018-09-20T05:29:56.728Z,1537421396.728 [SBIT](IMPORTANT): git: 2018-09-04-31-g9aafebe
2018-09-20T05:29:56.728Z,1537421396.728 [SBIT](INFO): git hash: 9aafebe78f005c8514597d31056703f9c944862b
2018-09-20T05:29:56.728Z,1537421396.728 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-09-20T05:29:56.729Z,1537421396.729 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-09-20T05:29:56.730Z,1537421396.730 [SBIT](INFO): Beginning SBIT in 160.000000 seconds.
2018-09-20T05:29:56.730Z,1537421396.730 [IBIT](INFO): Initialize IBIT Component.
2018-09-20T05:29:56.731Z,1537421396.731 [CBIT](DEBUG): Initialize CBIT Component.
2018-09-20T05:29:56.731Z,1537421396.731 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-09-20T05:29:56.732Z,1537421396.732 [logger ThreadHandler](INFO): Handler Thread ID is 3221
2018-09-20T05:29:56.749Z,1537421396.749 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3222
2018-09-20T05:29:56.755Z,1537421396.755 [Radio_Surface](INFO): Powering up
2018-09-20T05:29:56.761Z,1537421396.761 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 3223
2018-09-20T05:29:56.762Z,1537421396.762 [CTD_NeilBrown](INFO): Powering down
2018-09-20T05:29:56.793Z,1537421396.793 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3224
2018-09-20T05:29:56.794Z,1537421396.794 [CTD_Seabird](INFO): Initializing
2018-09-20T05:29:56.794Z,1537421396.794 [CTD_Seabird](INFO): Checking LCM
2018-09-20T05:29:56.803Z,1537421396.803 [CTD_Seabird](INFO): LCM OK
2018-09-20T05:29:56.803Z,1537421396.803 [CTD_Seabird](INFO): Powering up
2018-09-20T05:29:56.809Z,1537421396.809 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3225
2018-09-20T05:29:56.810Z,1537421396.810 [WetLabsBB2FL](INFO): Powering down
2018-09-20T05:29:56.841Z,1537421396.841 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-09-20T05:29:56.843Z,1537421396.843 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-09-20T05:29:56.843Z,1537421396.843 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-09-20T05:29:56.844Z,1537421396.844 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-09-20T05:29:56.845Z,1537421396.845 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T05:29:56.845Z,1537421396.845 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T05:29:56.846Z,1537421396.846 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T05:29:56.846Z,1537421396.846 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-09-20T05:29:56.847Z,1537421396.847 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-09-20T05:29:56.847Z,1537421396.847 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-09-20T05:29:56.861Z,1537421396.861 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3226
2018-09-20T05:29:56.871Z,1537421396.871 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-09-20T05:29:56.871Z,1537421396.871 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-09-20T05:29:56.871Z,1537421396.871 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-09-20T05:29:56.871Z,1537421396.871 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-09-20T05:29:56.872Z,1537421396.872 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-09-20T05:29:56.872Z,1537421396.872 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-09-20T05:29:56.872Z,1537421396.872 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-09-20T05:29:56.873Z,1537421396.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-09-20T05:29:56.873Z,1537421396.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-09-20T05:29:56.873Z,1537421396.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-09-20T05:29:56.873Z,1537421396.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-09-20T05:29:56.873Z,1537421396.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-09-20T05:29:56.874Z,1537421396.874 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-09-20T05:29:56.874Z,1537421396.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-09-20T05:29:56.874Z,1537421396.874 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-09-20T05:29:56.874Z,1537421396.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-09-20T05:29:56.902Z,1537421396.902 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-20T05:29:56.929Z,1537421396.929 [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-09-20T05:29:56.930Z,1537421396.930 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-09-20T05:29:57.019Z,1537421397.019 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-09-20T05:29:57.021Z,1537421397.021 [Default:A.Wait](DEBUG): Construct Wait.
2018-09-20T05:29:57.023Z,1537421397.023 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-20T05:29:57.050Z,1537421397.050 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-09-20T05:29:57.069Z,1537421397.069 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-09-20T05:29:57.075Z,1537421397.075 [Default:E.Execute](DEBUG): Construct Execute.
2018-09-20T05:29:57.095Z,1537421397.095 [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-09-20T05:29:57.099Z,1537421397.099 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-20T05:29:57.141Z,1537421397.141 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-09-20T05:29:57.149Z,1537421397.149 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-09-20T05:29:57.474Z,1537421397.474 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-20T05:29:57.623Z,1537421397.623 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-20T05:29:57.917Z,1537421397.917 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T05:29:57.930Z,1537421397.930 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T05:29:57.985Z,1537421397.985 [CommandLine](FAULT): Scheduling is paused
2018-09-20T05:29:57.986Z,1537421397.986 [CBIT](INFO): Critical error at 20180920T052956
2018-09-20T05:29:57.986Z,1537421397.986 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-09-20T05:29:58.179Z,1537421398.179 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-09-20T05:29:58.179Z,1537421398.179 [DropWeight] Hardware Fault, FailCount= 1
2018-09-20T05:29:58.179Z,1537421398.179 [DropWeight](ERROR): Hardware Fault
2018-09-20T05:29:58.233Z,1537421398.233 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-20T05:29:58.248Z,1537421398.248 [CBIT](INFO): Critical error at 20180920T052958
2018-09-20T05:29:58.251Z,1537421398.251 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-09-20T05:29:58.251Z,1537421398.251 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-09-20T05:29:58.496Z,1537421398.496 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-20T05:29:58.845Z,1537421398.845 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-09-20T05:29:58.845Z,1537421398.845 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-09-20T05:29:58.845Z,1537421398.845 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-20T05:29:58.845Z,1537421398.845 [BuoyancyServo](ERROR): Communications Fault
2018-09-20T05:29:58.847Z,1537421398.847 [CBIT](INFO): Critical error at 20180920T052958
2018-09-20T05:29:58.849Z,1537421398.849 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-20T05:29:58.919Z,1537421398.919 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-20T05:29:58.925Z,1537421398.925 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T05:29:58.926Z,1537421398.926 [BuoyancyServo](INFO): Powering down
2018-09-20T05:29:59.303Z,1537421399.303 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-20T05:29:59.700Z,1537421399.700 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-20T05:30:00.108Z,1537421400.108 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-20T05:30:00.613Z,1537421400.613 [Aanderaa_O2](INFO): Powering down
2018-09-20T05:30:00.686Z,1537421400.686 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-20T05:30:00.961Z,1537421400.961 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T05:30:00.961Z,1537421400.961 [BuoyancyServo] No Fault, FailCount= 1
2018-09-20T05:30:01.329Z,1537421401.329 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T05:30:01.449Z,1537421401.449 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T05:30:02.362Z,1537421402.362 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-20T05:30:02.428Z,1537421402.428 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-09-20T05:30:02.519Z,1537421402.519 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-09-20T05:30:02.832Z,1537421402.832 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-20T05:30:02.832Z,1537421402.832 [CTD_Seabird](INFO): Powering down
2018-09-20T05:30:23.262Z,1537421423.262 [NAL9602](INFO): Powering up NAL9602
2018-09-20T05:30:34.460Z,1537421434.460 [NAL9602](INFO): NAL9602 initialized
2018-09-20T05:31:44.129Z,1537421504.129 [BPC1](FAULT): Battery stick #1 (s/n: 0138) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:31:44.130Z,1537421504.130 [BPC1](FAULT): Battery stick #8 (s/n: 00E7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.131Z,1537421504.131 [BPC1](FAULT): Battery stick #14 (s/n: 011D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:31:44.131Z,1537421504.131 [BPC1](FAULT): Battery stick #15 (s/n: 014E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:31:44.132Z,1537421504.132 [BPC1](FAULT): Battery stick #18 (s/n: 0140) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:31:44.133Z,1537421504.133 [BPC1](FAULT): Battery stick #21 (s/n: 00CD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.134Z,1537421504.134 [BPC1](FAULT): Battery stick #22 (s/n: 009F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.135Z,1537421504.135 [BPC1](FAULT): Battery stick #26 (s/n: 00D1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.136Z,1537421504.136 [BPC1](FAULT): Battery stick #28 (s/n: 0092) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.141Z,1537421504.141 [BPC1](FAULT): Battery stick #30 (s/n: 00E9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.142Z,1537421504.142 [BPC1](FAULT): Battery stick #32 (s/n: 0157) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.143Z,1537421504.143 [BPC1](FAULT): Battery stick #39 (s/n: 00FD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.144Z,1537421504.144 [BPC1](FAULT): Battery stick #43 (s/n: 00AE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:31:44.149Z,1537421504.149 [BPC1](FAULT): Battery stick #44 (s/n: 00CB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:31:44.150Z,1537421504.150 [BPC1](FAULT): Battery stick #45 (s/n: 00F7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:31:44.151Z,1537421504.151 [BPC1](FAULT): Battery stick #46 (s/n: 00A1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.156Z,1537421504.156 [BPC1](FAULT): Battery stick #50 (s/n: 0152) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:31:44.157Z,1537421504.157 [BPC1](FAULT): Battery stick #51 (s/n: 014A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:31:44.158Z,1537421504.158 [BPC1](FAULT): Battery stick #53 (s/n: 015E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:31:44.159Z,1537421504.159 [BPC1](FAULT): Battery stick #56 (s/n: 0091) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.160Z,1537421504.160 [BPC1](FAULT): Battery stick #57 (s/n: 0153) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:31:44.165Z,1537421504.165 [BPC1](FAULT): Battery stick #62 (s/n: 00DA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:31:44.166Z,1537421504.166 [BPC1](INFO): Calculating totals. Valid battery stick count: 36. Valid reserve battery stick count: 4.
2018-09-20T05:32:37.349Z,1537421557.349 [SBIT](IMPORTANT): Beginning Startup BIT
2018-09-20T05:32:37.353Z,1537421557.353 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-20T05:32:48.159Z,1537421568.159 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.133780
CHAN A1 (24V): -0.005365
CHAN A2 (12V): -0.002256
CHAN A3 (5V): -0.001491
CHAN B0 (3.3V): 0.000234
CHAN B1 (3.15aV): -0.000113
CHAN B2 (3.15bV): -0.000884
CHAN B3 (GND): -0.000445
OPEN: -0.000274
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-20T05:32:50.513Z,1537421570.513 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-20T05:32:50.513Z,1537421570.513 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-20T05:32:50.514Z,1537421570.514 [SBIT](ERROR): Could not read massPosReader_.
2018-09-20T05:32:56.912Z,1537421576.912 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T05:32:56.912Z,1537421576.912 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-09-20T05:32:56.912Z,1537421576.912 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T05:32:56.953Z,1537421576.953 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T05:32:57.376Z,1537421577.376 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T05:32:57.376Z,1537421577.376 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-09-20T05:32:57.710Z,1537421577.710 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T05:32:57.711Z,1537421577.711 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T05:32:57.711Z,1537421577.711 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T05:33:02.683Z,1537421582.683 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004466
2018-09-20T05:33:05.462Z,1537421585.462 [CommandLine](IMPORTANT): got command report mod BPC1.platform_battery_voltage
2018-09-20T05:33:05.555Z,1537421585.555 [Reporter](INFO): BPC1.platform_battery_voltage no_value
2018-09-20T05:33:17.128Z,1537421597.128 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-20T05:33:17.128Z,1537421597.128 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-20T05:33:17.128Z,1537421597.128 [SBIT](ERROR): Could not read massPosReader_.
2018-09-20T05:33:17.128Z,1537421597.128 [SBIT](FAULT): Control surface position failure.
2018-09-20T05:33:30.742Z,1537421610.742 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-20T05:33:30.742Z,1537421610.742 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-20T05:33:30.742Z,1537421610.742 [SBIT](ERROR): Could not read massPosReader_.
2018-09-20T05:33:30.742Z,1537421610.742 [SBIT](FAULT): Control surface position failure.
2018-09-20T05:33:31.154Z,1537421611.154 [SBIT](CRITICAL): SBIT FAILED
2018-09-20T05:33:31.197Z,1537421611.197 [CommandLine](IMPORTANT): got command configSet list
2018-09-20T05:33:31.197Z,1537421611.197 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-20T05:33:31.198Z,1537421611.198 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=1 minute;

2018-09-20T05:33:31.198Z,1537421611.198 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2018-09-20T05:33:31.198Z,1537421611.198 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2018-09-20T05:33:31.198Z,1537421611.198 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2018-09-20T05:33:31.198Z,1537421611.198 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2018-09-20T05:33:31.199Z,1537421611.199 [CommandLine](IMPORTANT): ElevatorServo.loadAtStartup=0 bool;

2018-09-20T05:33:31.199Z,1537421611.199 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_salinity;

2018-09-20T05:33:31.199Z,1537421611.199 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_temperature;

2018-09-20T05:33:31.199Z,1537421611.199 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_median_sea_water_temperature;

2018-09-20T05:33:31.199Z,1537421611.199 [CommandLine](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2018-09-20T05:33:31.199Z,1537421611.199 [CommandLine](IMPORTANT): Express none Rowe_600LCM.height_above_sea_floor;

2018-09-20T05:33:31.199Z,1537421611.199 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-09-20T05:33:31.199Z,1537421611.199 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-09-20T05:33:31.200Z,1537421611.200 [CommandLine](IMPORTANT): MassServo.loadAtStartup=0 bool;

2018-09-20T05:33:31.200Z,1537421611.200 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-09-20T05:33:31.200Z,1537421611.200 [CommandLine](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2018-09-20T05:33:31.200Z,1537421611.200 [CommandLine](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2018-09-20T05:33:31.200Z,1537421611.200 [CommandLine](IMPORTANT): ThrusterServo.loadAtStartup=0 bool;

2018-09-20T05:33:31.200Z,1537421611.200 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=497.852 cubic_centimeter;

2018-09-20T05:33:31.201Z,1537421611.201 [CommandLine](IMPORTANT): VerticalControl.massDefault=-35 millimeter;

2018-09-20T05:33:31.207Z,1537421611.207 [CBIT](INFO): Critical error at 20180920T053331
2018-09-20T05:33:31.524Z,1537421611.524 [MissionManager](IMPORTANT): Started mission Startup
2018-09-20T05:33:31.525Z,1537421611.525 [Startup] Running Loop=1
2018-09-20T05:33:31.525Z,1537421611.525 [Startup](DEBUG): Aggregate::initialize Startup
2018-09-20T05:33:31.525Z,1537421611.525 [Startup:A.GoToSurface] Running Loop=1
2018-09-20T05:33:31.525Z,1537421611.525 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-20T05:33:31.526Z,1537421611.526 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-20T05:33:31.526Z,1537421611.526 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-20T05:33:31.527Z,1537421611.527 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-20T05:33:31.527Z,1537421611.527 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-20T05:33:31.527Z,1537421611.527 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-20T05:33:31.546Z,1537421611.546 [Startup:StartupSatComms] Running Loop=1
2018-09-20T05:33:31.546Z,1537421611.546 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-09-20T05:33:31.546Z,1537421611.546 [Startup:StartupSatComms:A] Running Loop=1
2018-09-20T05:33:31.928Z,1537421611.928 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-20T05:34:08.606Z,1537421648.606 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_voltage
2018-09-20T05:34:08.607Z,1537421648.607 [CommandLine](IMPORTANT): BPC1.platform_battery_voltage 14.627056 V
2018-09-20T05:34:30.311Z,1537421670.311 [CommandLine](IMPORTANT): got command report mod BPC1.platform_battery_charge
2018-09-20T05:34:30.533Z,1537421670.533 [Reporter](INFO): BPC1.platform_battery_charge no_value
2018-09-20T05:34:31.719Z,1537421671.719 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-20T05:33:31.5Z
2018-09-20T05:34:31.719Z,1537421671.719 [Startup:StartupSatComms:A] Stopped
2018-09-20T05:34:31.719Z,1537421671.719 [Startup:StartupSatComms:B] Running Loop=1
2018-09-20T05:34:32.116Z,1537421672.116 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-20T05:34:36.077Z,1537421676.077 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180919T215103/Courier0136.lzma
2018-09-20T05:34:36.840Z,1537421676.840 [DataOverHttps](INFO): Moved sent file to Logs/20180919T215103/Courier0136.lzma.bak
2018-09-20T05:34:36.841Z,1537421676.841 [DataOverHttps](INFO): SBD MOMSN=8543664
2018-09-20T05:34:46.174Z,1537421686.174 [DataOverHttps](INFO): Sending 407 bytes from file Logs/20180920T052946/Courier0000.lzma
2018-09-20T05:34:46.968Z,1537421686.968 [DataOverHttps](INFO): Moved sent file to Logs/20180920T052946/Courier0000.lzma.bak
2018-09-20T05:34:46.968Z,1537421686.968 [DataOverHttps](INFO): SBD MOMSN=8543666
2018-09-20T05:34:50.605Z,1537421690.605 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_charge
2018-09-20T05:34:50.606Z,1537421690.606 [CommandLine](IMPORTANT): BPC1.platform_battery_charge 169.669115 Ah
2018-09-20T05:34:56.057Z,1537421696.057 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20180919T215103/Express0137.lzma
2018-09-20T05:34:56.852Z,1537421696.852 [DataOverHttps](INFO): Moved sent file to Logs/20180919T215103/Express0137.lzma.bak
2018-09-20T05:34:56.852Z,1537421696.852 [DataOverHttps](INFO): SBD MOMSN=8543674
2018-09-20T05:35:08.631Z,1537421708.631 [DataOverHttps](INFO): Sending 1431 bytes from file Logs/20180920T052946/Express0001.lzma
2018-09-20T05:35:09.396Z,1537421709.396 [DataOverHttps](INFO): Moved sent file to Logs/20180920T052946/Express0001.lzma.bak
2018-09-20T05:35:09.396Z,1537421709.396 [DataOverHttps](INFO): SBD MOMSN=8543678
2018-09-20T05:35:10.720Z,1537421710.720 [Startup:StartupSatComms:B] Stopped
2018-09-20T05:35:10.721Z,1537421710.721 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-09-20T05:35:10.721Z,1537421710.721 [Startup:StartupSatComms] Stopped
2018-09-20T05:35:10.721Z,1537421710.721 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-09-20T05:35:10.722Z,1537421710.722 [Startup](INFO): Completed Startup
2018-09-20T05:35:10.722Z,1537421710.722 [MissionManager](INFO): Startup is completed.
2018-09-20T05:35:10.722Z,1537421710.722 [MissionManager](INFO): Uninitializing Mission Startup
2018-09-20T05:35:10.722Z,1537421710.722 [Startup] Stopped
2018-09-20T05:35:10.723Z,1537421710.723 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-09-20T05:35:10.723Z,1537421710.723 [Startup:A.GoToSurface] Stopped
2018-09-20T05:35:10.723Z,1537421710.723 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-20T05:35:10.901Z,1537421710.901 [MissionManager](IMPORTANT): Started mission Default
2018-09-20T05:35:10.909Z,1537421710.909 [Default] Running Loop=1
2018-09-20T05:35:10.909Z,1537421710.909 [Default](DEBUG): Aggregate::initialize Default
2018-09-20T05:35:10.909Z,1537421710.909 [Default:B.GoToSurface] Running Loop=1
2018-09-20T05:35:10.909Z,1537421710.909 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-20T05:35:10.909Z,1537421710.909 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-20T05:35:10.910Z,1537421710.910 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-20T05:35:10.910Z,1537421710.910 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-20T05:35:10.910Z,1537421710.910 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-20T05:35:10.911Z,1537421710.911 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-20T05:35:10.911Z,1537421710.911 [Default:A.Wait] Running Loop=1
2018-09-20T05:35:10.911Z,1537421710.911 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-20T05:35:24.089Z,1537421724.089 [Default:A.Wait](INFO): Done Waiting.
2018-09-20T05:35:24.089Z,1537421724.089 [Default:A.Wait] Stopped
2018-09-20T05:35:24.089Z,1537421724.089 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-20T05:35:24.496Z,1537421724.496 [Default:CheckIn] Running Loop=1
2018-09-20T05:35:24.496Z,1537421724.496 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-20T05:35:24.496Z,1537421724.496 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-20T05:35:24.881Z,1537421724.881 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-20T05:35:37.638Z,1537421737.638 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-09-20T05:35:58.101Z,1537421758.101 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T05:35:58.101Z,1537421758.101 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-09-20T05:35:58.101Z,1537421758.101 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T05:35:58.113Z,1537421758.113 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T05:35:58.502Z,1537421758.502 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T05:35:58.502Z,1537421758.502 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-09-20T05:35:58.898Z,1537421758.898 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T05:35:58.899Z,1537421758.899 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T05:35:58.899Z,1537421758.899 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T05:38:59.295Z,1537421939.295 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T05:38:59.295Z,1537421939.295 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-09-20T05:38:59.295Z,1537421939.295 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T05:38:59.315Z,1537421939.315 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T05:38:59.690Z,1537421939.690 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T05:38:59.690Z,1537421939.690 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-09-20T05:39:00.068Z,1537421940.068 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T05:39:00.069Z,1537421940.069 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T05:39:00.069Z,1537421940.069 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T05:40:24.499Z,1537422024.499 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-20T05:35:24.5Z
2018-09-20T05:40:24.499Z,1537422024.499 [Default:CheckIn:Read_GPS] Stopped
2018-09-20T05:40:24.499Z,1537422024.499 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-20T05:40:24.918Z,1537422024.918 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-20T05:40:28.880Z,1537422028.880 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T052946/Courier0004.lzma
2018-09-20T05:40:29.671Z,1537422029.671 [DataOverHttps](INFO): Moved sent file to Logs/20180920T052946/Courier0004.lzma.bak
2018-09-20T05:40:29.672Z,1537422029.672 [DataOverHttps](INFO): SBD MOMSN=8543798
2018-09-20T05:40:38.963Z,1537422038.963 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20180920T052946/Express0005.lzma
2018-09-20T05:40:39.374Z,1537422039.374 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-20T05:40:39.374Z,1537422039.374 [NAL9602] Data Fault, FailCount= 1
2018-09-20T05:40:39.374Z,1537422039.374 [NAL9602](ERROR): Data Fault
2018-09-20T05:40:39.443Z,1537422039.443 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-20T05:40:39.763Z,1537422039.763 [DataOverHttps](INFO): Moved sent file to Logs/20180920T052946/Express0005.lzma.bak
2018-09-20T05:40:39.764Z,1537422039.764 [DataOverHttps](INFO): SBD MOMSN=8543800
2018-09-20T05:40:39.791Z,1537422039.791 [NAL9602](INFO): Powering down
2018-09-20T05:40:40.778Z,1537422040.778 [Default:CheckIn:Read_Iridium] Stopped
2018-09-20T05:40:40.778Z,1537422040.778 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-20T05:40:40.778Z,1537422040.778 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-20T05:40:40.934Z,1537422040.934 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-20T05:40:40.934Z,1537422040.934 [NAL9602] No Fault, FailCount= 1
2018-09-20T05:41:10.150Z,1537422070.150 [NAL9602](INFO): Powering up NAL9602
2018-09-20T05:41:20.950Z,1537422080.950 [NAL9602](INFO): NAL9602 initialized
2018-09-20T05:42:00.187Z,1537422120.187 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T05:42:00.187Z,1537422120.187 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-09-20T05:42:00.187Z,1537422120.187 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T05:42:00.199Z,1537422120.199 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T05:42:00.594Z,1537422120.594 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T05:42:00.594Z,1537422120.594 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-09-20T05:42:00.988Z,1537422120.988 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T05:42:00.989Z,1537422120.989 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T05:42:00.989Z,1537422120.989 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T05:42:45.791Z,1537422165.791 [Reporter](INFO): BPC1.platform_battery_voltage 14.627056 V
2018-09-20T05:42:45.792Z,1537422165.792 [Reporter](INFO): BPC1.platform_battery_charge 169.669115 Ah
2018-09-20T05:43:32.977Z,1537422212.977 [BPC1](ERROR): Battery stick #1 (s/n: 0138) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:43:32.978Z,1537422212.978 [BPC1](ERROR): Battery stick #8 (s/n: 00E7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:32.979Z,1537422212.979 [BPC1](ERROR): Battery stick #14 (s/n: 011D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:43:32.980Z,1537422212.980 [BPC1](ERROR): Battery stick #15 (s/n: 014E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:43:32.989Z,1537422212.989 [BPC1](ERROR): Battery stick #18 (s/n: 0140) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:43:32.990Z,1537422212.990 [BPC1](ERROR): Battery stick #21 (s/n: 00CD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:32.991Z,1537422212.991 [BPC1](ERROR): Battery stick #22 (s/n: 009F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:32.992Z,1537422212.992 [BPC1](ERROR): Battery stick #26 (s/n: 00D1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:32.997Z,1537422212.997 [BPC1](ERROR): Battery stick #28 (s/n: 0092) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:32.997Z,1537422212.997 [BPC1](ERROR): Battery stick #30 (s/n: 00E9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:32.998Z,1537422212.998 [BPC1](ERROR): Battery stick #32 (s/n: 0157) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:32.999Z,1537422212.999 [BPC1](ERROR): Battery stick #39 (s/n: 00FD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:32.000Z,1537422213.000 [BPC1](ERROR): Battery stick #43 (s/n: 00AE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:43:33.005Z,1537422213.005 [BPC1](ERROR): Battery stick #44 (s/n: 00CB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:43:33.006Z,1537422213.006 [BPC1](ERROR): Battery stick #45 (s/n: 00F7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:43:33.007Z,1537422213.007 [BPC1](ERROR): Battery stick #46 (s/n: 00A1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:33.007Z,1537422213.007 [BPC1](ERROR): Battery stick #50 (s/n: 0152) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:43:33.012Z,1537422213.012 [BPC1](ERROR): Battery stick #51 (s/n: 014A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:43:33.013Z,1537422213.013 [BPC1](ERROR): Battery stick #53 (s/n: 015E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:43:33.014Z,1537422213.014 [BPC1](ERROR): Battery stick #56 (s/n: 0091) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:33.015Z,1537422213.015 [BPC1](ERROR): Battery stick #57 (s/n: 0153) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:43:33.016Z,1537422213.016 [BPC1](ERROR): Battery stick #62 (s/n: 00DA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:43:33.021Z,1537422213.021 [BPC1](INFO): Calculating totals. Valid battery stick count: 36. Valid reserve battery stick count: 4.
2018-09-20T05:43:33.092Z,1537422213.092 [Reporter](INFO): BPC1.platform_battery_voltage no_value
2018-09-20T05:43:33.093Z,1537422213.093 [Reporter](INFO): BPC1.platform_battery_charge no_value
2018-09-20T05:44:58.556Z,1537422298.556 [CBIT](INFO): Clearing failed state for component DropWeight
2018-09-20T05:44:58.556Z,1537422298.556 [DropWeight] No Fault, FailCount= 1
2018-09-20T05:45:01.341Z,1537422301.341 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T05:45:01.341Z,1537422301.341 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-09-20T05:45:01.341Z,1537422301.341 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T05:45:01.365Z,1537422301.365 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T05:45:01.765Z,1537422301.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T05:45:01.765Z,1537422301.765 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-09-20T05:45:02.148Z,1537422302.148 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T05:45:02.149Z,1537422302.149 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T05:45:02.149Z,1537422302.149 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T05:45:40.963Z,1537422340.963 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-09-20T05:45:40.964Z,1537422340.964 [Default:CheckIn:C.Wait] Stopped
2018-09-20T05:45:40.964Z,1537422340.964 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-20T05:45:40.964Z,1537422340.964 [Default:CheckIn:D] Running Loop=1
2018-09-20T05:45:41.369Z,1537422341.369 [Default:CheckIn:D] Stopped
2018-09-20T05:45:41.370Z,1537422341.370 [Default:CheckIn:E] Running Loop=1
2018-09-20T05:45:41.755Z,1537422341.755 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.507648 min 
2018-09-20T05:45:41.758Z,1537422341.758 [Default:CheckIn:E] Stopped
2018-09-20T05:45:41.758Z,1537422341.758 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-09-20T05:45:41.758Z,1537422341.758 [Default:CheckIn] Stopped
2018-09-20T05:45:41.759Z,1537422341.759 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-20T05:45:41.759Z,1537422341.759 [Default:CheckIn](INFO): Running loop #2
2018-09-20T05:45:41.759Z,1537422341.759 [Default:CheckIn] Running Loop=2
2018-09-20T05:45:41.759Z,1537422341.759 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-20T05:45:41.759Z,1537422341.759 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-20T05:48:02.486Z,1537422482.486 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T05:48:02.486Z,1537422482.486 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-09-20T05:48:02.486Z,1537422482.486 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T05:48:02.503Z,1537422482.503 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T05:48:02.881Z,1537422482.881 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T05:48:02.882Z,1537422482.882 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-09-20T05:48:03.274Z,1537422483.274 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T05:48:03.275Z,1537422483.275 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T05:48:03.275Z,1537422483.275 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T05:50:42.060Z,1537422642.060 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-20T05:45:41.8Z
2018-09-20T05:50:42.061Z,1537422642.061 [Default:CheckIn:Read_GPS] Stopped
2018-09-20T05:50:42.061Z,1537422642.061 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-20T05:50:47.215Z,1537422647.215 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180920T052946/Courier0007.lzma
2018-09-20T05:50:48.015Z,1537422648.015 [DataOverHttps](INFO): Moved sent file to Logs/20180920T052946/Courier0007.lzma.bak
2018-09-20T05:50:48.016Z,1537422648.016 [DataOverHttps](INFO): SBD MOMSN=8543857
2018-09-20T05:50:56.906Z,1537422656.906 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20180920T052946/Express0008.lzma
2018-09-20T05:50:57.700Z,1537422657.700 [DataOverHttps](INFO): Moved sent file to Logs/20180920T052946/Express0008.lzma.bak
2018-09-20T05:50:57.700Z,1537422657.700 [DataOverHttps](INFO): SBD MOMSN=8543859
2018-09-20T05:50:58.573Z,1537422658.573 [Default:CheckIn:Read_Iridium] Stopped
2018-09-20T05:50:58.573Z,1537422658.573 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-20T05:50:58.573Z,1537422658.573 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-20T05:51:03.443Z,1537422663.443 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T05:51:03.443Z,1537422663.443 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-09-20T05:51:03.443Z,1537422663.443 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T05:51:03.454Z,1537422663.454 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T05:51:03.847Z,1537422663.847 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T05:51:03.847Z,1537422663.847 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-09-20T05:51:04.243Z,1537422664.243 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T05:51:04.243Z,1537422664.243 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T05:51:04.244Z,1537422664.244 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T05:51:21.795Z,1537422681.795 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-20T05:51:21.795Z,1537422681.795 [NAL9602] Data Fault, FailCount= 2
2018-09-20T05:51:21.795Z,1537422681.795 [NAL9602](ERROR): Data Fault
2018-09-20T05:51:21.872Z,1537422681.872 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-20T05:51:22.194Z,1537422682.194 [NAL9602](INFO): Powering down
2018-09-20T05:51:23.074Z,1537422683.074 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-20T05:51:23.074Z,1537422683.074 [NAL9602] No Fault, FailCount= 2
2018-09-20T05:51:52.594Z,1537422712.594 [NAL9602](INFO): Powering up NAL9602
2018-09-20T05:52:03.767Z,1537422723.767 [NAL9602](INFO): NAL9602 initialized
2018-09-20T05:54:04.587Z,1537422844.587 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T05:54:04.587Z,1537422844.587 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-09-20T05:54:04.588Z,1537422844.588 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T05:54:04.627Z,1537422844.627 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T05:54:05.027Z,1537422845.027 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T05:54:05.027Z,1537422845.027 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-09-20T05:54:05.430Z,1537422845.430 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T05:54:05.430Z,1537422845.430 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T05:54:05.431Z,1537422845.431 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T05:54:34.612Z,1537422874.612 [Reporter](INFO): BPC1.platform_battery_voltage 14.627000 V
2018-09-20T05:54:34.629Z,1537422874.629 [Reporter](INFO): BPC1.platform_battery_charge 169.669115 Ah
2018-09-20T05:55:21.125Z,1537422921.125 [BPC1](ERROR): Battery stick #1 (s/n: 0138) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:55:21.126Z,1537422921.126 [BPC1](ERROR): Battery stick #8 (s/n: 00E7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.127Z,1537422921.127 [BPC1](ERROR): Battery stick #14 (s/n: 011D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:55:21.128Z,1537422921.128 [BPC1](ERROR): Battery stick #15 (s/n: 014E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:55:21.141Z,1537422921.141 [BPC1](ERROR): Battery stick #18 (s/n: 0140) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:55:21.142Z,1537422921.142 [BPC1](ERROR): Battery stick #21 (s/n: 00CD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.142Z,1537422921.142 [BPC1](ERROR): Battery stick #22 (s/n: 009F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.143Z,1537422921.143 [BPC1](ERROR): Battery stick #26 (s/n: 00D1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.144Z,1537422921.144 [BPC1](ERROR): Battery stick #28 (s/n: 0092) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.145Z,1537422921.145 [BPC1](ERROR): Battery stick #30 (s/n: 00E9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.146Z,1537422921.146 [BPC1](ERROR): Battery stick #32 (s/n: 0157) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.147Z,1537422921.147 [BPC1](ERROR): Battery stick #39 (s/n: 00FD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.147Z,1537422921.147 [BPC1](ERROR): Battery stick #43 (s/n: 00AE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:55:21.148Z,1537422921.148 [BPC1](ERROR): Battery stick #44 (s/n: 00CB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:55:21.149Z,1537422921.149 [BPC1](ERROR): Battery stick #45 (s/n: 00F7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:55:21.150Z,1537422921.150 [BPC1](ERROR): Battery stick #46 (s/n: 00A1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.151Z,1537422921.151 [BPC1](ERROR): Battery stick #50 (s/n: 0152) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:55:21.152Z,1537422921.152 [BPC1](ERROR): Battery stick #51 (s/n: 014A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-09-20T05:55:21.168Z,1537422921.168 [BPC1](ERROR): Battery stick #53 (s/n: 015E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:55:21.173Z,1537422921.173 [BPC1](ERROR): Battery stick #56 (s/n: 0091) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.174Z,1537422921.174 [BPC1](ERROR): Battery stick #57 (s/n: 0153) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-09-20T05:55:21.175Z,1537422921.175 [BPC1](ERROR): Battery stick #62 (s/n: 00DA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-09-20T05:55:21.175Z,1537422921.175 [BPC1](INFO): Calculating totals. Valid battery stick count: 36. Valid reserve battery stick count: 4.
2018-09-20T05:55:21.267Z,1537422921.267 [Reporter](INFO): BPC1.platform_battery_voltage no_value
2018-09-20T05:55:21.268Z,1537422921.268 [Reporter](INFO): BPC1.platform_battery_charge no_value
2018-09-20T05:55:58.751Z,1537422958.751 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-09-20T05:55:58.752Z,1537422958.752 [Default:CheckIn:C.Wait] Stopped
2018-09-20T05:55:58.752Z,1537422958.752 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-20T05:55:58.752Z,1537422958.752 [Default:CheckIn:D] Running Loop=1
2018-09-20T05:55:59.109Z,1537422959.109 [Default:CheckIn:D] Stopped
2018-09-20T05:55:59.109Z,1537422959.109 [Default:CheckIn:E] Running Loop=1
2018-09-20T05:55:59.510Z,1537422959.510 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.803324 min 
2018-09-20T05:55:59.510Z,1537422959.510 [Default:CheckIn:E] Stopped
2018-09-20T05:55:59.510Z,1537422959.510 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-09-20T05:55:59.510Z,1537422959.510 [Default:CheckIn] Stopped
2018-09-20T05:55:59.511Z,1537422959.511 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-20T05:55:59.511Z,1537422959.511 [Default:CheckIn](INFO): Running loop #3
2018-09-20T05:55:59.511Z,1537422959.511 [Default:CheckIn] Running Loop=3
2018-09-20T05:55:59.511Z,1537422959.511 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-20T05:55:59.511Z,1537422959.511 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-20T05:57:05.475Z,1537423025.475 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T05:57:05.475Z,1537423025.475 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-09-20T05:57:05.475Z,1537423025.475 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T05:57:05.487Z,1537423025.487 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T05:57:05.885Z,1537423025.885 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T05:57:05.885Z,1537423025.885 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-09-20T05:57:06.255Z,1537423026.255 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T05:57:06.256Z,1537423026.256 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T05:57:06.268Z,1537423026.268 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T06:00:06.267Z,1537423206.267 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T06:00:06.267Z,1537423206.267 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-09-20T06:00:06.267Z,1537423206.267 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T06:00:06.279Z,1537423206.279 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T06:00:06.655Z,1537423206.655 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T06:00:06.655Z,1537423206.655 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-09-20T06:00:07.035Z,1537423207.035 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T06:00:07.035Z,1537423207.035 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T06:00:07.036Z,1537423207.036 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T06:00:59.868Z,1537423259.868 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-20T05:55:59.5Z
2018-09-20T06:00:59.868Z,1537423259.868 [Default:CheckIn:Read_GPS] Stopped
2018-09-20T06:00:59.868Z,1537423259.868 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-20T06:01:04.191Z,1537423264.191 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180920T052946/Courier0010.lzma
2018-09-20T06:01:04.992Z,1537423264.992 [DataOverHttps](INFO): Moved sent file to Logs/20180920T052946/Courier0010.lzma.bak
2018-09-20T06:01:04.992Z,1537423264.992 [DataOverHttps](INFO): SBD MOMSN=8543918
2018-09-20T06:01:13.894Z,1537423273.894 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20180920T052946/Express0011.lzma
2018-09-20T06:01:14.659Z,1537423274.659 [DataOverHttps](INFO): Moved sent file to Logs/20180920T052946/Express0011.lzma.bak
2018-09-20T06:01:14.660Z,1537423274.660 [DataOverHttps](INFO): SBD MOMSN=8543920
2018-09-20T06:01:15.521Z,1537423275.521 [Default:CheckIn:Read_Iridium] Stopped
2018-09-20T06:01:15.521Z,1537423275.521 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-20T06:01:15.522Z,1537423275.522 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-20T06:02:02.602Z,1537423322.602 [CommandLine](IMPORTANT): got command get platform_battery_voltage
2018-09-20T06:02:02.602Z,1537423322.602 [CommandLine](FAULT): Element has no value
2018-09-20T06:02:07.150Z,1537423327.150 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-20T06:02:07.150Z,1537423327.150 [NAL9602] Data Fault, FailCount= 3
2018-09-20T06:02:07.150Z,1537423327.150 [NAL9602](ERROR): Data Fault
2018-09-20T06:02:07.249Z,1537423327.249 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-20T06:02:07.542Z,1537423327.542 [NAL9602](INFO): Powering down
2018-09-20T06:02:08.404Z,1537423328.404 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-20T06:02:08.405Z,1537423328.405 [NAL9602] No Fault, FailCount= 3
2018-09-20T06:02:14.989Z,1537423334.989 [CommandLine](IMPORTANT): got command failComponent
2018-09-20T06:02:14.989Z,1537423334.989 [CommandLine](IMPORTANT): Failed components:
2018-09-20T06:02:14.990Z,1537423334.990 [CommandLine](IMPORTANT): No failed Components.
2018-09-20T06:02:34.598Z,1537423354.598 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_voltage
2018-09-20T06:02:34.599Z,1537423354.599 [CommandLine](IMPORTANT): BPC1.platform_battery_voltage 14.626750 V
2018-09-20T06:02:37.558Z,1537423357.558 [NAL9602](INFO): Powering up NAL9602
2018-09-20T06:02:48.758Z,1537423368.758 [NAL9602](INFO): NAL9602 initialized
2018-09-20T06:03:07.211Z,1537423387.211 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-20T06:03:07.211Z,1537423387.211 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-09-20T06:03:07.211Z,1537423387.211 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-20T06:03:07.250Z,1537423387.250 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-20T06:03:07.638Z,1537423387.638 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-20T06:03:07.639Z,1537423387.639 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-09-20T06:03:08.021Z,1537423388.021 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-20T06:03:08.021Z,1537423388.021 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-20T06:03:08.022Z,1537423388.022 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-20T06:04:13.390Z,1537423453.390 [CommandLine](IMPORTANT): got command restart application
2018-09-20T06:04:14.400Z,1537423454.400 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T06:04:14.400Z,1537423454.400 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:14.584Z,1537423454.584 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-09-20T06:04:14.585Z,1537423454.585 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:14.585Z,1537423454.585 [CommandLine](INFO): Join timeout helper Thread ID is 3262
2018-09-20T06:04:14.586Z,1537423454.586 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-20T06:04:14.586Z,1537423454.586 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:14.586Z,1537423454.586 [NavChartDb](INFO): Join timeout helper Thread ID is 3263
2018-09-20T06:04:14.981Z,1537423454.981 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T06:04:14.981Z,1537423454.981 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:14.988Z,1537423454.988 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-09-20T06:04:14.989Z,1537423454.989 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:14.989Z,1537423454.989 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3264
2018-09-20T06:04:15.413Z,1537423455.413 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T06:04:15.413Z,1537423455.413 [WetLabsBB2FL](INFO): Powering down
2018-09-20T06:04:15.414Z,1537423455.414 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:15.416Z,1537423455.416 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-09-20T06:04:15.417Z,1537423455.417 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:15.417Z,1537423455.417 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3265
2018-09-20T06:04:15.709Z,1537423455.709 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T06:04:15.796Z,1537423455.796 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-20T06:04:15.796Z,1537423455.796 [CTD_Seabird](INFO): Powering down
2018-09-20T06:04:15.797Z,1537423455.797 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:15.801Z,1537423455.801 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-09-20T06:04:15.801Z,1537423455.801 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:15.801Z,1537423455.801 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 3267
2018-09-20T06:04:16.161Z,1537423456.161 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T06:04:16.161Z,1537423456.161 [CTD_NeilBrown](INFO): Powering down
2018-09-20T06:04:16.162Z,1537423456.162 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:16.166Z,1537423456.166 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-20T06:04:16.166Z,1537423456.166 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:16.166Z,1537423456.166 [Radio_Surface](INFO): Join timeout helper Thread ID is 3268
2018-09-20T06:04:16.457Z,1537423456.457 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T06:04:16.457Z,1537423456.457 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:16.473Z,1537423456.473 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-20T06:04:16.473Z,1537423456.473 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:16.474Z,1537423456.474 [logger](INFO): Join timeout helper Thread ID is 3269
2018-09-20T06:04:16.477Z,1537423456.477 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T06:04:16.477Z,1537423456.477 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:16.493Z,1537423456.493 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-20T06:04:16.493Z,1537423456.493 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:16.494Z,1537423456.494 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-20T06:04:16.494Z,1537423456.494 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:16.494Z,1537423456.494 [controlThread](INFO): Join timeout helper Thread ID is 3270
2018-09-20T06:04:16.745Z,1537423456.745 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T06:04:16.745Z,1537423456.745 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-20T06:04:16.746Z,1537423456.746 [NAL9602](INFO): Powering down
2018-09-20T06:04:16.748Z,1537423456.748 [PNI_TCM](INFO): Powering down
2018-09-20T06:04:16.837Z,1537423456.837 [Aanderaa_O2](INFO): Powering down
2018-09-20T06:04:16.839Z,1537423456.839 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-20T06:04:16.840Z,1537423456.840 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-20T06:04:16.840Z,1537423456.840 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-20T06:04:16.841Z,1537423456.841 [MissionManager](INFO): Uninitializing Mission Default
2018-09-20T06:04:16.841Z,1537423456.841 [Default] Stopped
2018-09-20T06:04:16.841Z,1537423456.841 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-20T06:04:16.841Z,1537423456.841 [Default:B.GoToSurface] Stopped
2018-09-20T06:04:16.842Z,1537423456.842 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-20T06:04:16.842Z,1537423456.842 [Default:CheckIn] Stopped
2018-09-20T06:04:16.842Z,1537423456.842 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-20T06:04:16.842Z,1537423456.842 [Default:CheckIn:C.Wait] Stopped
2018-09-20T06:04:16.842Z,1537423456.842 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-20T06:04:16.846Z,1537423456.846 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-20T06:04:16.846Z,1537423456.846 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-20T06:04:16.847Z,1537423456.847 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-20T06:04:16.847Z,1537423456.847 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-20T06:04:16.847Z,1537423456.847 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T06:04:16.847Z,1537423456.847 [BuoyancyServo](INFO): Powering down
2018-09-20T06:04:16.861Z,1537423456.861 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-20T06:04:16.861Z,1537423456.861 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-20T06:04:16.861Z,1537423456.861 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-20T06:04:16.862Z,1537423456.862 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:16.974Z,1537423456.974 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:17.017Z,1537423457.017 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:17.021Z,1537423457.021 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:17.024Z,1537423457.024 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:17.055Z,1537423457.055 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-20T06:04:17.116Z,1537423457.116 [logger ThreadHandler](INFO): Thread cancelled.