2018-10-16T17:13:11.515Z,1539709991.515 [Supervisor](DEBUG): Initializing supervisor.
2018-10-16T17:13:11.518Z,1539709991.518 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-16T17:13:11.518Z,1539709991.518 [SyncHandler](INFO): Protected caller Thread ID is 797
2018-10-16T17:13:11.519Z,1539709991.519 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-16T17:13:11.520Z,1539709991.520 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-16T17:13:11.520Z,1539709991.520 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 798
2018-10-16T17:13:11.523Z,1539709991.523 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-16T17:13:11.535Z,1539709991.535 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-16T17:13:11.536Z,1539709991.536 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-16T17:13:11.536Z,1539709991.536 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 799
2018-10-16T17:13:11.537Z,1539709991.537 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-16T17:13:11.538Z,1539709991.538 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-16T17:13:11.538Z,1539709991.538 [logger ThreadHandler](INFO): Protected caller Thread ID is 800
2018-10-16T17:13:11.540Z,1539709991.540 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-16T17:13:11.541Z,1539709991.541 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-16T17:13:11.545Z,1539709991.545 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-16T17:13:11.903Z,1539709991.903 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-16T17:13:11.904Z,1539709991.904 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-16T17:13:12.124Z,1539709992.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-16T17:13:12.125Z,1539709992.125 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-16T17:13:12.448Z,1539709992.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-16T17:13:12.450Z,1539709992.450 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-16T17:13:12.604Z,1539709992.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-16T17:13:12.606Z,1539709992.606 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-16T17:13:12.708Z,1539709992.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-16T17:13:12.710Z,1539709992.710 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-16T17:13:13.205Z,1539709993.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-16T17:13:13.206Z,1539709993.206 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-16T17:13:13.703Z,1539709993.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-16T17:13:13.704Z,1539709993.704 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-16T17:13:14.206Z,1539709994.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-16T17:13:14.207Z,1539709994.207 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-16T17:13:14.315Z,1539709994.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-16T17:13:14.317Z,1539709994.317 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-16T17:13:14.416Z,1539709994.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-16T17:13:14.418Z,1539709994.418 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-16T17:13:14.753Z,1539709994.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-16T17:13:14.754Z,1539709994.754 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-16T17:13:15.105Z,1539709995.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-16T17:13:15.106Z,1539709995.106 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-16T17:13:15.482Z,1539709995.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-16T17:13:15.483Z,1539709995.483 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-16T17:13:15.636Z,1539709995.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-16T17:13:15.636Z,1539709995.636 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-16T17:13:15.721Z,1539709995.721 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-10-16T17:13:15.723Z,1539709995.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-10-16T17:13:15.832Z,1539709995.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-10-16T17:13:15.957Z,1539709995.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-10-16T17:13:16.056Z,1539709996.056 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-10-16T17:13:16.143Z,1539709996.143 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-10-16T17:13:16.319Z,1539709996.319 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-10-16T17:13:16.516Z,1539709996.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-10-16T17:13:16.593Z,1539709996.593 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-10-16T17:13:16.835Z,1539709996.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-16T17:13:16.836Z,1539709996.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-10-16T17:13:16.921Z,1539709996.921 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-10-16T17:13:17.015Z,1539709997.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-10-16T17:13:17.121Z,1539709997.121 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-10-16T17:13:17.255Z,1539709997.255 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-10-16T17:13:17.259Z,1539709997.259 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-16T17:13:17.559Z,1539709997.559 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-16T17:13:17.561Z,1539709997.561 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-16T17:13:17.621Z,1539709997.621 [DepthRateCalculator] Loaded
2018-10-16T17:13:17.621Z,1539709997.621 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-16T17:13:17.627Z,1539709997.627 [PitchRateCalculator] Loaded
2018-10-16T17:13:17.628Z,1539709997.628 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-16T17:13:17.645Z,1539709997.645 [SpeedCalculator] Loaded
2018-10-16T17:13:17.645Z,1539709997.645 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-16T17:13:17.667Z,1539709997.667 [TempGradientCalculator] Loaded
2018-10-16T17:13:17.667Z,1539709997.667 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-16T17:13:17.673Z,1539709997.673 [YawRateCalculator] Loaded
2018-10-16T17:13:17.674Z,1539709997.674 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-16T17:13:17.715Z,1539709997.715 [ElevatorOffsetCalculator] Loaded
2018-10-16T17:13:17.716Z,1539709997.716 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-16T17:13:17.716Z,1539709997.716 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-16T17:13:17.717Z,1539709997.717 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-16T17:13:17.823Z,1539709997.823 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-16T17:13:17.825Z,1539709997.825 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-16T17:13:17.963Z,1539709997.963 [BuoyancyServo] Loaded
2018-10-16T17:13:17.963Z,1539709997.963 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-16T17:13:17.979Z,1539709997.979 [ElevatorServo] Loaded
2018-10-16T17:13:17.979Z,1539709997.979 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-16T17:13:17.994Z,1539709997.994 [MassServo] Loaded
2018-10-16T17:13:17.994Z,1539709997.994 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-16T17:13:18.011Z,1539709998.011 [RudderServo] Loaded
2018-10-16T17:13:18.011Z,1539709998.011 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-16T17:13:18.026Z,1539709998.026 [ThrusterServo] Loaded
2018-10-16T17:13:18.026Z,1539709998.026 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-16T17:13:18.026Z,1539709998.026 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-16T17:13:18.028Z,1539709998.028 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-16T17:13:18.052Z,1539709998.052 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-16T17:13:18.054Z,1539709998.054 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-16T17:13:18.190Z,1539709998.190 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-16T17:13:18.190Z,1539709998.190 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-16T17:13:18.776Z,1539709998.776 [DataOverHttps] Loaded
2018-10-16T17:13:18.777Z,1539709998.777 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-16T17:13:18.791Z,1539709998.791 [Depth_Keller] Loaded
2018-10-16T17:13:18.791Z,1539709998.791 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-16T17:13:18.796Z,1539709998.796 [DropWeight] Loaded
2018-10-16T17:13:18.796Z,1539709998.796 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-10-16T17:13:18.899Z,1539709998.899 [NAL9602] Loaded
2018-10-16T17:13:18.899Z,1539709998.899 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-16T17:13:18.916Z,1539709998.916 [Onboard] Loaded
2018-10-16T17:13:18.916Z,1539709998.916 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-10-16T17:13:18.927Z,1539709998.927 [Radio_Surface] Loaded
2018-10-16T17:13:18.927Z,1539709998.927 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-16T17:13:18.929Z,1539709998.929 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088F4E0
2018-10-16T17:13:18.929Z,1539709998.929 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2018-10-16T17:13:19.084Z,1539709999.084 [PNI_TCM] Loaded
2018-10-16T17:13:19.084Z,1539709999.084 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-10-16T17:13:19.147Z,1539709999.147 [Rowe_600LCM] Loaded
2018-10-16T17:13:19.148Z,1539709999.148 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-10-16T17:13:19.149Z,1539709999.149 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408BF4E0
2018-10-16T17:13:19.149Z,1539709999.149 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 883
2018-10-16T17:13:20.971Z,1539710000.971 [BPC1] Loaded
2018-10-16T17:13:20.972Z,1539710000.972 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-10-16T17:13:20.972Z,1539710000.972 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-16T17:13:20.974Z,1539710000.974 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-16T17:13:21.760Z,1539710001.760 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-16T17:13:21.783Z,1539710001.783 [SBIT] Loaded
2018-10-16T17:13:21.783Z,1539710001.783 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-16T17:13:21.784Z,1539710001.784 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-16T17:13:21.796Z,1539710001.796 [IBIT] Loaded
2018-10-16T17:13:21.796Z,1539710001.796 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-16T17:13:21.799Z,1539710001.799 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-16T17:13:21.954Z,1539710001.954 [CBIT] Loaded
2018-10-16T17:13:21.955Z,1539710001.955 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-16T17:13:21.955Z,1539710001.955 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-16T17:13:21.956Z,1539710001.956 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-16T17:13:22.173Z,1539710002.173 [Aanderaa_O2] Loaded
2018-10-16T17:13:22.173Z,1539710002.173 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-10-16T17:13:22.258Z,1539710002.258 [CTD_NeilBrown] Loaded
2018-10-16T17:13:22.259Z,1539710002.259 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-10-16T17:13:22.260Z,1539710002.260 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0
2018-10-16T17:13:22.260Z,1539710002.260 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 884
2018-10-16T17:13:22.318Z,1539710002.318 [CTD_Seabird] Loaded
2018-10-16T17:13:22.318Z,1539710002.318 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-10-16T17:13:22.319Z,1539710002.319 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409BA4E0
2018-10-16T17:13:22.320Z,1539710002.320 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 885
2018-10-16T17:13:22.335Z,1539710002.335 [PAR_Licor] Loaded
2018-10-16T17:13:22.335Z,1539710002.335 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-10-16T17:13:22.387Z,1539710002.387 [WetLabsBB2FL] Loaded
2018-10-16T17:13:22.387Z,1539710002.387 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-10-16T17:13:22.388Z,1539710002.388 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409EA4E0
2018-10-16T17:13:22.389Z,1539710002.389 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 886
2018-10-16T17:13:22.389Z,1539710002.389 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-16T17:13:22.390Z,1539710002.390 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-16T17:13:22.484Z,1539710002.484 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-16T17:13:22.572Z,1539710002.572 [VerticalControl] Loaded
2018-10-16T17:13:22.572Z,1539710002.572 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-16T17:13:22.573Z,1539710002.573 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-16T17:13:22.633Z,1539710002.633 [HorizontalControl] Loaded
2018-10-16T17:13:22.633Z,1539710002.633 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-16T17:13:22.634Z,1539710002.634 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-16T17:13:22.636Z,1539710002.636 [SpeedControl] Loaded
2018-10-16T17:13:22.636Z,1539710002.636 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-16T17:13:22.637Z,1539710002.637 [LoopControl](DEBUG): Construct LoopControl.
2018-10-16T17:13:22.637Z,1539710002.637 [LoopControl] Loaded
2018-10-16T17:13:22.638Z,1539710002.638 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-16T17:13:22.638Z,1539710002.638 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-16T17:13:22.640Z,1539710002.640 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-16T17:13:22.678Z,1539710002.678 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-16T17:13:22.679Z,1539710002.679 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-16T17:13:22.789Z,1539710002.789 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-16T17:13:22.789Z,1539710002.789 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-16T17:13:22.806Z,1539710002.806 [NavChart] Loaded
2018-10-16T17:13:22.807Z,1539710002.807 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-10-16T17:13:22.811Z,1539710002.811 [UniversalFixResidualReporter] Loaded
2018-10-16T17:13:22.811Z,1539710002.811 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-16T17:13:22.811Z,1539710002.811 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-16T17:13:22.815Z,1539710002.815 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-16T17:13:22.816Z,1539710002.816 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-16T17:13:22.823Z,1539710002.823 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-16T17:13:22.824Z,1539710002.824 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A764E0
2018-10-16T17:13:22.824Z,1539710002.824 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 887
2018-10-16T17:13:22.829Z,1539710002.829 [Supervisor](INFO): Main Thread ID is 796
2018-10-16T17:13:22.829Z,1539710002.829 [Supervisor](DEBUG): Running supervisor.
2018-10-16T17:13:22.830Z,1539710002.830 [CommandLine ThreadHandler](INFO): Handler Thread ID is 888
2018-10-16T17:13:22.832Z,1539710002.832 [controlThread ThreadHandler](INFO): Handler Thread ID is 889
2018-10-16T17:13:22.832Z,1539710002.832 [controlThread](DEBUG): Initializing ControlThread
2018-10-16T17:13:22.833Z,1539710002.833 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-16T17:13:22.834Z,1539710002.834 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-16T17:13:22.834Z,1539710002.834 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-16T17:13:22.834Z,1539710002.834 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-16T17:13:22.835Z,1539710002.835 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-16T17:13:22.835Z,1539710002.835 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-16T17:13:22.843Z,1539710002.843 [SBIT](INFO): Initialize SBIT Component.
2018-10-16T17:13:22.844Z,1539710002.844 [SBIT](IMPORTANT): git: 2018-10-09-22-g812aa5f
2018-10-16T17:13:22.844Z,1539710002.844 [SBIT](INFO): git hash: 812aa5f738ae24d7d3f6c5c137b2640d1960a87f
2018-10-16T17:13:22.845Z,1539710002.845 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-16T17:13:22.845Z,1539710002.845 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-16T17:13:22.846Z,1539710002.846 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-10-16T17:13:22.847Z,1539710002.847 [IBIT](INFO): Initialize IBIT Component.
2018-10-16T17:13:22.847Z,1539710002.847 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-16T17:13:22.848Z,1539710002.848 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-10-16T17:13:22.848Z,1539710002.848 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-10-16T17:13:22.849Z,1539710002.849 [logger ThreadHandler](INFO): Handler Thread ID is 890
2018-10-16T17:13:22.865Z,1539710002.865 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 891
2018-10-16T17:13:22.871Z,1539710002.871 [Radio_Surface](INFO): Powering up
2018-10-16T17:13:22.877Z,1539710002.877 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 892
2018-10-16T17:13:22.878Z,1539710002.878 [Rowe_600LCM](INFO): Initializing
2018-10-16T17:13:22.878Z,1539710002.878 [Rowe_600LCM](INFO): Checking LCM
2018-10-16T17:13:22.985Z,1539710002.985 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-16T17:13:22.987Z,1539710002.987 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-16T17:13:22.988Z,1539710002.988 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-16T17:13:22.988Z,1539710002.988 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-16T17:13:22.989Z,1539710002.989 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:13:22.990Z,1539710002.990 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:13:22.990Z,1539710002.990 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:13:22.990Z,1539710002.990 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-10-16T17:13:22.991Z,1539710002.991 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-16T17:13:22.992Z,1539710002.992 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-16T17:13:23.002Z,1539710003.002 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 893
2018-10-16T17:13:23.004Z,1539710003.004 [CTD_NeilBrown](INFO): Powering down
2018-10-16T17:13:23.110Z,1539710003.110 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 894
2018-10-16T17:13:23.113Z,1539710003.113 [CTD_Seabird](INFO): Initializing
2018-10-16T17:13:23.114Z,1539710003.114 [CTD_Seabird](INFO): Checking LCM
2018-10-16T17:13:23.122Z,1539710003.122 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-16T17:13:23.124Z,1539710003.124 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 895
2018-10-16T17:13:23.125Z,1539710003.125 [WetLabsBB2FL](INFO): Powering down
2018-10-16T17:13:23.224Z,1539710003.224 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 896
2018-10-16T17:13:23.230Z,1539710003.230 [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-10-16T17:13:23.232Z,1539710003.232 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-16T17:13:23.235Z,1539710003.235 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-16T17:13:23.235Z,1539710003.235 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-16T17:13:23.235Z,1539710003.235 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-16T17:13:23.235Z,1539710003.235 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-16T17:13:23.235Z,1539710003.235 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-16T17:13:23.236Z,1539710003.236 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-16T17:13:23.236Z,1539710003.236 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-16T17:13:23.236Z,1539710003.236 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-16T17:13:23.236Z,1539710003.236 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-16T17:13:23.236Z,1539710003.236 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-16T17:13:23.237Z,1539710003.237 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-16T17:13:23.237Z,1539710003.237 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-16T17:13:23.237Z,1539710003.237 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-16T17:13:23.237Z,1539710003.237 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-16T17:13:23.237Z,1539710003.237 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-16T17:13:23.238Z,1539710003.238 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-16T17:13:23.323Z,1539710003.323 [CTD_Seabird](INFO): LCM OK
2018-10-16T17:13:23.323Z,1539710003.323 [CTD_Seabird](INFO): Powering up
2018-10-16T17:13:23.324Z,1539710003.324 [Rowe_600LCM](INFO): LCM OK
2018-10-16T17:13:23.324Z,1539710003.324 [Rowe_600LCM](INFO): Powering up
2018-10-16T17:13:23.343Z,1539710003.343 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-16T17:13:23.361Z,1539710003.361 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-16T17:13:23.363Z,1539710003.363 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-16T17:13:23.391Z,1539710003.391 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-16T17:13:23.409Z,1539710003.409 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-16T17:13:23.415Z,1539710003.415 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-16T17:13:23.435Z,1539710003.435 [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-10-16T17:13:23.440Z,1539710003.440 [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-10-16T17:13:23.468Z,1539710003.468 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-16T17:13:23.489Z,1539710003.489 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-10-16T17:13:23.626Z,1539710003.626 [Aanderaa_O2](INFO): Start
2018-10-16T17:13:23.762Z,1539710003.762 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-16T17:13:23.833Z,1539710003.833 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T17:13:24.073Z,1539710004.073 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-16T17:13:24.082Z,1539710004.082 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-16T17:13:24.104Z,1539710004.104 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-16T17:13:24.113Z,1539710004.113 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-16T17:13:24.119Z,1539710004.119 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-16T17:13:24.125Z,1539710004.125 [MassServo](DEBUG): Initializing MassServo.
2018-10-16T17:13:24.140Z,1539710004.140 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-16T17:13:24.145Z,1539710004.145 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-16T17:13:24.167Z,1539710004.167 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-16T17:13:24.173Z,1539710004.173 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-16T17:13:24.442Z,1539710004.442 [Aanderaa_O2](INFO): Starting
2018-10-16T17:13:24.457Z,1539710004.457 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T17:13:24.468Z,1539710004.468 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T17:13:24.679Z,1539710004.679 [Aanderaa_O2](INFO): Starting
2018-10-16T17:13:24.703Z,1539710004.703 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T17:13:25.049Z,1539710005.049 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-10-16T17:13:25.049Z,1539710005.049 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-10-16T17:13:25.049Z,1539710005.049 [BuoyancyServo] Communications Fault, FailCount= 1
2018-10-16T17:13:25.049Z,1539710005.049 [BuoyancyServo](ERROR): Communications Fault
2018-10-16T17:13:25.256Z,1539710005.256 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-10-16T17:13:25.284Z,1539710005.284 [Aanderaa_O2](INFO): Starting
2018-10-16T17:13:25.311Z,1539710005.311 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T17:13:25.333Z,1539710005.333 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-16T17:13:25.334Z,1539710005.334 [BuoyancyServo](INFO): Powering down
2018-10-16T17:13:25.702Z,1539710005.702 [Aanderaa_O2](INFO): Starting
2018-10-16T17:13:25.711Z,1539710005.711 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T17:13:26.087Z,1539710006.087 [Aanderaa_O2](INFO): Starting
2018-10-16T17:13:26.140Z,1539710006.140 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T17:13:26.496Z,1539710006.496 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T17:13:26.903Z,1539710006.903 [Aanderaa_O2](INFO): Stop
2018-10-16T17:13:27.021Z,1539710007.021 [Aanderaa_O2](INFO): Powering down
2018-10-16T17:13:27.099Z,1539710007.099 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T17:13:27.241Z,1539710007.241 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-10-16T17:13:27.347Z,1539710007.347 [Aanderaa_O2](INFO): Stopping
2018-10-16T17:13:27.372Z,1539710007.372 [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-10-16T17:13:27.539Z,1539710007.539 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-10-16T17:13:27.539Z,1539710007.539 [BuoyancyServo] No Fault, FailCount= 1
2018-10-16T17:13:27.546Z,1539710007.546 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-10-16T17:13:27.547Z,1539710007.547 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-10-16T17:13:27.547Z,1539710007.547 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-10-16T17:13:27.757Z,1539710007.757 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-16T17:13:27.757Z,1539710007.757 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-16T17:13:28.613Z,1539710008.613 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-16T17:13:28.845Z,1539710008.845 [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-10-16T17:13:28.848Z,1539710008.848 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-10-16T17:13:29.643Z,1539710009.643 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-16T17:13:29.643Z,1539710009.643 [CTD_Seabird](INFO): Powering down
2018-10-16T17:13:46.448Z,1539710026.448 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-16T17:13:46.460Z,1539710026.460 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-16T17:13:48.943Z,1539710028.943 [NAL9602](INFO): Powering up NAL9602
2018-10-16T17:13:57.826Z,1539710037.826 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): -0.006873
CHAN A2 (12V): -0.001986
CHAN A3 (5V): -0.001135
CHAN B0 (3.3V): -0.000025
CHAN B1 (3.15aV): 0.000078
CHAN B2 (3.15bV): 0.000266
CHAN B3 (GND): -0.000459
OPEN: -0.000151
Full Scale Calc: 4.765 mA, -1.589 mA
2018-10-16T17:14:00.131Z,1539710040.131 [NAL9602](INFO): NAL9602 initialized
2018-10-16T17:14:40.706Z,1539710080.706 [SBIT](IMPORTANT): SBIT PASSED
2018-10-16T17:14:40.791Z,1539710080.791 [CommandLine](IMPORTANT): got command configSet list
2018-10-16T17:14:40.791Z,1539710080.791 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-16T17:14:40.795Z,1539710080.795 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=25 count;

2018-10-16T17:14:40.795Z,1539710080.795 [CommandLine](IMPORTANT): BPC1.batteryMuxCycleTime=10 minute;

2018-10-16T17:14:40.795Z,1539710080.795 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=1 minute;

2018-10-16T17:14:40.795Z,1539710080.795 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2018-10-16T17:14:40.795Z,1539710080.795 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2018-10-16T17:14:40.796Z,1539710080.796 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-10-16T17:14:40.796Z,1539710080.796 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-10-16T17:14:40.796Z,1539710080.796 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-10-16T17:14:40.796Z,1539710080.796 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2018-10-16T17:14:40.796Z,1539710080.796 [CommandLine](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2018-10-16T17:14:40.796Z,1539710080.796 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=213 cubic_centimeter;

2018-10-16T17:14:40.796Z,1539710080.796 [CommandLine](IMPORTANT): VerticalControl.massDefault=4 millimeter;

2018-10-16T17:14:40.983Z,1539710080.983 [MissionManager](IMPORTANT): Started mission Startup
2018-10-16T17:14:40.983Z,1539710080.983 [Startup] Running Loop=1
2018-10-16T17:14:40.983Z,1539710080.983 [Startup](DEBUG): Aggregate::initialize Startup
2018-10-16T17:14:40.983Z,1539710080.983 [Startup:A.GoToSurface] Running Loop=1
2018-10-16T17:14:40.983Z,1539710080.983 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-16T17:14:40.984Z,1539710080.984 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-16T17:14:40.984Z,1539710080.984 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-16T17:14:40.985Z,1539710080.985 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-16T17:14:40.985Z,1539710080.985 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-16T17:14:40.986Z,1539710080.986 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-16T17:14:40.992Z,1539710080.992 [Startup:StartupSatComms] Running Loop=1
2018-10-16T17:14:40.992Z,1539710080.992 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-10-16T17:14:40.992Z,1539710080.992 [Startup:StartupSatComms:A] Running Loop=1
2018-10-16T17:14:41.389Z,1539710081.389 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-10-16T17:15:11.374Z,1539710111.374 [BPC1](FAULT): Battery stick #1 (s/n: 0138) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T17:15:11.375Z,1539710111.375 [BPC1](FAULT): Battery stick #7 (s/n: 012D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.375Z,1539710111.375 [BPC1](FAULT): Battery stick #8 (s/n: 00E7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.376Z,1539710111.376 [BPC1](FAULT): Battery stick #9 (s/n: 0100) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.377Z,1539710111.377 [BPC1](FAULT): Battery stick #14 (s/n: 011D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T17:15:11.378Z,1539710111.378 [BPC1](FAULT): Battery stick #15 (s/n: 014E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T17:15:11.379Z,1539710111.379 [BPC1](FAULT): Battery stick #18 (s/n: 0140) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T17:15:11.380Z,1539710111.380 [BPC1](FAULT): Battery stick #21 (s/n: 00CD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.380Z,1539710111.380 [BPC1](FAULT): Battery stick #22 (s/n: 009F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.381Z,1539710111.381 [BPC1](FAULT): Battery stick #25 (s/n: 00E8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.382Z,1539710111.382 [BPC1](FAULT): Battery stick #26 (s/n: 00D1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.383Z,1539710111.383 [BPC1](FAULT): Battery stick #28 (s/n: 0092) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.384Z,1539710111.384 [BPC1](FAULT): Battery stick #30 (s/n: 00E9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.385Z,1539710111.385 [BPC1](FAULT): Battery stick #32 (s/n: 0157) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.386Z,1539710111.386 [BPC1](FAULT): Battery stick #39 (s/n: 00FD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.386Z,1539710111.386 [BPC1](FAULT): Battery stick #40 (s/n: 0095) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.387Z,1539710111.387 [BPC1](FAULT): Battery stick #43 (s/n: 00AE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T17:15:11.388Z,1539710111.388 [BPC1](FAULT): Battery stick #44 (s/n: 00CB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T17:15:11.389Z,1539710111.389 [BPC1](FAULT): Battery stick #45 (s/n: 00F7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T17:15:11.390Z,1539710111.390 [BPC1](FAULT): Battery stick #46 (s/n: 00A1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.391Z,1539710111.391 [BPC1](FAULT): Battery stick #49 (s/n: 0103) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48E0.
2018-10-16T17:15:11.391Z,1539710111.391 [BPC1](FAULT): Battery stick #50 (s/n: 0152) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T17:15:11.392Z,1539710111.392 [BPC1](FAULT): Battery stick #51 (s/n: 014A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T17:15:11.393Z,1539710111.393 [BPC1](FAULT): Battery stick #53 (s/n: 015E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T17:15:11.394Z,1539710111.394 [BPC1](FAULT): Battery stick #54 (s/n: 008E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.395Z,1539710111.395 [BPC1](FAULT): Battery stick #56 (s/n: 0091) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T17:15:11.396Z,1539710111.396 [BPC1](FAULT): Battery stick #57 (s/n: 0153) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T17:15:11.396Z,1539710111.396 [BPC1](FAULT): Battery stick #62 (s/n: 00DA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T17:15:11.397Z,1539710111.397 [BPC1](INFO): Calculating totals. Valid battery stick count: 30. Valid reserve battery stick count: 4.
2018-10-16T17:15:41.379Z,1539710141.379 [Startup:StartupSatComms:A](INFO): Timed out from 2018-10-16T17:14:40.0Z
2018-10-16T17:15:41.379Z,1539710141.379 [Startup:StartupSatComms:A] Stopped
2018-10-16T17:15:41.380Z,1539710141.380 [Startup:StartupSatComms:B] Running Loop=1
2018-10-16T17:15:41.776Z,1539710141.776 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-16T17:15:54.255Z,1539710154.255 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004188
2018-10-16T17:15:58.651Z,1539710158.651 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181016T165949/Courier0007.lzma
2018-10-16T17:15:59.451Z,1539710159.451 [DataOverHttps](INFO): Moved sent file to Logs/20181016T165949/Courier0007.lzma.bak
2018-10-16T17:15:59.451Z,1539710159.451 [DataOverHttps](INFO): SBD MOMSN=8717692
2018-10-16T17:16:12.414Z,1539710172.414 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20181016T171311/Courier0000.lzma
2018-10-16T17:16:13.198Z,1539710173.198 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0000.lzma.bak
2018-10-16T17:16:13.199Z,1539710173.199 [DataOverHttps](INFO): SBD MOMSN=8717776
2018-10-16T17:16:25.660Z,1539710185.660 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:16:25.660Z,1539710185.660 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-16T17:16:25.660Z,1539710185.660 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:16:25.673Z,1539710185.673 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:16:25.684Z,1539710185.684 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181016T165949/Express0008.lzma
2018-10-16T17:16:25.841Z,1539710185.841 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:16:25.841Z,1539710185.841 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-16T17:16:26.207Z,1539710186.207 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:16:26.208Z,1539710186.208 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:16:26.217Z,1539710186.217 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:16:26.482Z,1539710186.482 [DataOverHttps](INFO): Moved sent file to Logs/20181016T165949/Express0008.lzma.bak
2018-10-16T17:16:26.483Z,1539710186.483 [DataOverHttps](INFO): SBD MOMSN=8717808
2018-10-16T17:16:35.918Z,1539710195.918 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181016T171112/Express0001.lzma
2018-10-16T17:16:36.720Z,1539710196.720 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171112/Express0001.lzma.bak
2018-10-16T17:16:36.720Z,1539710196.720 [DataOverHttps](INFO): SBD MOMSN=8717848
2018-10-16T17:16:41.559Z,1539710201.559 [Startup:StartupSatComms:B](INFO): Timed out from 2018-10-16T17:15:41.4Z
2018-10-16T17:16:41.559Z,1539710201.559 [Startup:StartupSatComms:B] Stopped
2018-10-16T17:16:41.560Z,1539710201.560 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-10-16T17:16:41.560Z,1539710201.560 [Startup:StartupSatComms] Stopped
2018-10-16T17:16:41.560Z,1539710201.560 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-10-16T17:16:41.565Z,1539710201.565 [Startup](INFO): Completed Startup
2018-10-16T17:16:41.565Z,1539710201.565 [MissionManager](INFO): Startup is completed.
2018-10-16T17:16:41.565Z,1539710201.565 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-16T17:16:41.566Z,1539710201.566 [Startup] Stopped
2018-10-16T17:16:41.566Z,1539710201.566 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-10-16T17:16:41.566Z,1539710201.566 [Startup:A.GoToSurface] Stopped
2018-10-16T17:16:41.566Z,1539710201.566 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-16T17:16:41.972Z,1539710201.972 [MissionManager](IMPORTANT): Started mission Default
2018-10-16T17:16:41.972Z,1539710201.972 [Default] Running Loop=1
2018-10-16T17:16:41.972Z,1539710201.972 [Default](DEBUG): Aggregate::initialize Default
2018-10-16T17:16:41.972Z,1539710201.972 [Default:B.GoToSurface] Running Loop=1
2018-10-16T17:16:41.973Z,1539710201.973 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-16T17:16:41.973Z,1539710201.973 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-16T17:16:41.973Z,1539710201.973 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-16T17:16:41.974Z,1539710201.974 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-16T17:16:41.974Z,1539710201.974 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-16T17:16:41.974Z,1539710201.974 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-16T17:16:41.975Z,1539710201.975 [Default:A.Wait] Running Loop=1
2018-10-16T17:16:41.975Z,1539710201.975 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-10-16T17:16:46.183Z,1539710206.183 [DataOverHttps](INFO): Sending 1291 bytes from file Logs/20181016T171311/Express0001.lzma
2018-10-16T17:16:46.942Z,1539710206.942 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0001.lzma.bak
2018-10-16T17:16:46.943Z,1539710206.943 [DataOverHttps](INFO): SBD MOMSN=8717856
2018-10-16T17:16:54.997Z,1539710214.997 [Default:A.Wait](INFO): Done Waiting.
2018-10-16T17:16:54.998Z,1539710214.998 [Default:A.Wait] Stopped
2018-10-16T17:16:54.998Z,1539710214.998 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T17:16:55.393Z,1539710215.393 [Default:CheckIn] Running Loop=1
2018-10-16T17:16:55.394Z,1539710215.394 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T17:16:55.394Z,1539710215.394 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T17:16:55.806Z,1539710215.806 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-10-16T17:17:02.955Z,1539710222.955 [NAL9602](INFO): SBD MO Status=2, MOMSN=10405, MT Status=2, MTMSN=0
2018-10-16T17:17:02.955Z,1539710222.955 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-10-16T17:19:03.371Z,1539710343.371 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-10-16T17:19:26.269Z,1539710366.269 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:19:26.269Z,1539710366.269 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-10-16T17:19:26.269Z,1539710366.269 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:19:26.282Z,1539710366.282 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:19:26.639Z,1539710366.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:19:26.639Z,1539710366.639 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-10-16T17:19:27.031Z,1539710367.031 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:19:27.031Z,1539710367.031 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:19:27.031Z,1539710367.031 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:21:55.440Z,1539710515.440 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-16T17:16:55.4Z
2018-10-16T17:21:55.440Z,1539710515.440 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T17:21:55.440Z,1539710515.440 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-16T17:21:55.838Z,1539710515.838 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-16T17:22:00.179Z,1539710520.179 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181016T171311/Courier0004.lzma
2018-10-16T17:22:00.946Z,1539710520.946 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0004.lzma.bak
2018-10-16T17:22:00.946Z,1539710520.946 [DataOverHttps](INFO): SBD MOMSN=8717977
2018-10-16T17:22:10.287Z,1539710530.287 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20181016T171311/Express0005.lzma
2018-10-16T17:22:11.078Z,1539710531.078 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0005.lzma.bak
2018-10-16T17:22:11.079Z,1539710531.079 [DataOverHttps](INFO): SBD MOMSN=8717980
2018-10-16T17:22:11.664Z,1539710531.664 [Default:CheckIn:Read_Iridium] Stopped
2018-10-16T17:22:11.665Z,1539710531.665 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-16T17:22:11.665Z,1539710531.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-16T17:22:27.375Z,1539710547.375 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:22:27.375Z,1539710547.375 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-10-16T17:22:27.375Z,1539710547.375 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:22:27.424Z,1539710547.424 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:22:27.770Z,1539710547.770 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:22:27.771Z,1539710547.771 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-10-16T17:22:28.233Z,1539710548.233 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:22:28.241Z,1539710548.241 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:22:28.241Z,1539710548.241 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:24:03.507Z,1539710643.507 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-16T17:24:03.507Z,1539710643.507 [NAL9602] Data Fault, FailCount= 1
2018-10-16T17:24:03.507Z,1539710643.507 [NAL9602](ERROR): Data Fault
2018-10-16T17:24:03.592Z,1539710643.592 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-16T17:24:03.906Z,1539710643.906 [NAL9602](INFO): Powering down
2018-10-16T17:24:04.759Z,1539710644.759 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-16T17:24:04.759Z,1539710644.759 [NAL9602] No Fault, FailCount= 1
2018-10-16T17:24:34.123Z,1539710674.123 [NAL9602](INFO): Powering up NAL9602
2018-10-16T17:24:45.319Z,1539710685.319 [NAL9602](INFO): NAL9602 initialized
2018-10-16T17:25:28.391Z,1539710728.391 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:25:28.391Z,1539710728.391 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-10-16T17:25:28.392Z,1539710728.392 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:25:28.404Z,1539710728.404 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:25:28.803Z,1539710728.803 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:25:28.804Z,1539710728.804 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-10-16T17:25:29.183Z,1539710729.183 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:25:29.184Z,1539710729.184 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:25:29.184Z,1539710729.184 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:27:11.996Z,1539710831.996 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-16T17:27:11.997Z,1539710831.997 [Default:CheckIn:C.Wait] Stopped
2018-10-16T17:27:11.997Z,1539710831.997 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T17:27:11.997Z,1539710831.997 [Default:CheckIn:D] Running Loop=1
2018-10-16T17:27:12.398Z,1539710832.398 [Default:CheckIn:D] Stopped
2018-10-16T17:27:12.398Z,1539710832.398 [Default:CheckIn:E] Running Loop=1
2018-10-16T17:27:12.810Z,1539710832.810 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.507067 min 
2018-10-16T17:27:12.812Z,1539710832.812 [Default:CheckIn:E] Stopped
2018-10-16T17:27:12.817Z,1539710832.817 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-16T17:27:12.817Z,1539710832.817 [Default:CheckIn] Stopped
2018-10-16T17:27:12.817Z,1539710832.817 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T17:27:12.817Z,1539710832.817 [Default:CheckIn](INFO): Running loop #2
2018-10-16T17:27:12.817Z,1539710832.817 [Default:CheckIn] Running Loop=2
2018-10-16T17:27:12.817Z,1539710832.817 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T17:27:12.818Z,1539710832.818 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T17:28:29.432Z,1539710909.432 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:28:29.437Z,1539710909.437 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-10-16T17:28:29.437Z,1539710909.437 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:28:29.465Z,1539710909.465 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:28:29.849Z,1539710909.849 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:28:29.849Z,1539710909.849 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-10-16T17:28:30.257Z,1539710910.257 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:28:30.257Z,1539710910.257 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:28:30.258Z,1539710910.258 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:31:30.592Z,1539711090.592 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:31:30.592Z,1539711090.592 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-10-16T17:31:30.592Z,1539711090.592 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:31:30.662Z,1539711090.662 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:31:30.966Z,1539711090.966 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:31:30.966Z,1539711090.966 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-10-16T17:31:31.392Z,1539711091.392 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:31:31.393Z,1539711091.393 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:31:31.393Z,1539711091.393 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:32:12.980Z,1539711132.980 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-16T17:27:12.8Z
2018-10-16T17:32:12.980Z,1539711132.980 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T17:32:12.980Z,1539711132.980 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-16T17:32:17.569Z,1539711137.569 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181016T171311/Courier0007.lzma
2018-10-16T17:32:18.322Z,1539711138.322 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0007.lzma.bak
2018-10-16T17:32:18.323Z,1539711138.323 [DataOverHttps](INFO): SBD MOMSN=8718095
2018-10-16T17:32:27.756Z,1539711147.756 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20181016T171311/Express0008.lzma
2018-10-16T17:32:28.554Z,1539711148.554 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0008.lzma.bak
2018-10-16T17:32:28.555Z,1539711148.555 [DataOverHttps](INFO): SBD MOMSN=8718098
2018-10-16T17:32:29.162Z,1539711149.162 [Default:CheckIn:Read_Iridium] Stopped
2018-10-16T17:32:29.162Z,1539711149.162 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-16T17:32:29.162Z,1539711149.162 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-16T17:34:33.631Z,1539711273.631 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:34:33.632Z,1539711273.632 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-10-16T17:34:33.632Z,1539711273.632 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:34:33.644Z,1539711273.644 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:34:33.816Z,1539711273.816 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:34:33.816Z,1539711273.816 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-10-16T17:34:34.160Z,1539711274.160 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:34:34.161Z,1539711274.161 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:34:34.162Z,1539711274.162 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:34:47.659Z,1539711287.659 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-16T17:34:47.659Z,1539711287.659 [NAL9602] Data Fault, FailCount= 2
2018-10-16T17:34:47.659Z,1539711287.659 [NAL9602](ERROR): Data Fault
2018-10-16T17:34:47.758Z,1539711287.758 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-16T17:34:48.059Z,1539711288.059 [NAL9602](INFO): Powering down
2018-10-16T17:34:48.925Z,1539711288.925 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-16T17:34:48.926Z,1539711288.926 [NAL9602] No Fault, FailCount= 2
2018-10-16T17:35:18.072Z,1539711318.072 [NAL9602](INFO): Powering up NAL9602
2018-10-16T17:35:29.259Z,1539711329.259 [NAL9602](INFO): NAL9602 initialized
2018-10-16T17:37:29.774Z,1539711449.774 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-16T17:37:29.774Z,1539711449.774 [Default:CheckIn:C.Wait] Stopped
2018-10-16T17:37:29.774Z,1539711449.774 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T17:37:29.774Z,1539711449.774 [Default:CheckIn:D] Running Loop=1
2018-10-16T17:37:30.184Z,1539711450.184 [Default:CheckIn:D] Stopped
2018-10-16T17:37:30.184Z,1539711450.184 [Default:CheckIn:E] Running Loop=1
2018-10-16T17:37:30.585Z,1539711450.585 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.803520 min 
2018-10-16T17:37:30.585Z,1539711450.585 [Default:CheckIn:E] Stopped
2018-10-16T17:37:30.585Z,1539711450.585 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-16T17:37:30.586Z,1539711450.586 [Default:CheckIn] Stopped
2018-10-16T17:37:30.586Z,1539711450.586 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T17:37:30.586Z,1539711450.586 [Default:CheckIn](INFO): Running loop #3
2018-10-16T17:37:30.586Z,1539711450.586 [Default:CheckIn] Running Loop=3
2018-10-16T17:37:30.586Z,1539711450.586 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T17:37:30.586Z,1539711450.586 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T17:37:34.384Z,1539711454.384 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:37:34.384Z,1539711454.384 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-10-16T17:37:34.384Z,1539711454.384 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:37:34.405Z,1539711454.405 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:37:34.839Z,1539711454.839 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:37:34.840Z,1539711454.840 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-10-16T17:37:35.183Z,1539711455.183 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:37:35.183Z,1539711455.183 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:37:35.183Z,1539711455.183 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:40:35.575Z,1539711635.575 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:40:35.575Z,1539711635.575 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-10-16T17:40:35.575Z,1539711635.575 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:40:35.587Z,1539711635.587 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:40:35.958Z,1539711635.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:40:35.958Z,1539711635.958 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-10-16T17:40:36.346Z,1539711636.346 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:40:36.347Z,1539711636.347 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:40:36.347Z,1539711636.347 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:42:30.757Z,1539711750.757 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-16T17:37:30.6Z
2018-10-16T17:42:30.757Z,1539711750.757 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T17:42:30.757Z,1539711750.757 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-16T17:42:35.204Z,1539711755.204 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181016T171311/Courier0010.lzma
2018-10-16T17:42:35.995Z,1539711755.995 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0010.lzma.bak
2018-10-16T17:42:35.995Z,1539711755.995 [DataOverHttps](INFO): SBD MOMSN=8718204
2018-10-16T17:42:45.430Z,1539711765.430 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20181016T171311/Express0011.lzma
2018-10-16T17:42:46.231Z,1539711766.231 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0011.lzma.bak
2018-10-16T17:42:46.232Z,1539711766.232 [DataOverHttps](INFO): SBD MOMSN=8718206
2018-10-16T17:42:46.812Z,1539711766.812 [Default:CheckIn:Read_Iridium] Stopped
2018-10-16T17:42:46.812Z,1539711766.812 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-16T17:42:46.812Z,1539711766.812 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-16T17:43:36.746Z,1539711816.746 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:43:36.747Z,1539711816.747 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-10-16T17:43:36.747Z,1539711816.747 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:43:36.792Z,1539711816.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:43:37.116Z,1539711817.116 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:43:37.116Z,1539711817.116 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-10-16T17:43:37.479Z,1539711817.479 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:43:37.479Z,1539711817.479 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:43:37.480Z,1539711817.480 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:45:32.270Z,1539711932.270 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-16T17:45:32.270Z,1539711932.270 [NAL9602] Data Fault, FailCount= 3
2018-10-16T17:45:32.271Z,1539711932.271 [NAL9602](ERROR): Data Fault
2018-10-16T17:45:32.363Z,1539711932.363 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-16T17:45:32.670Z,1539711932.670 [NAL9602](INFO): Powering down
2018-10-16T17:45:33.534Z,1539711933.534 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-16T17:45:33.534Z,1539711933.534 [NAL9602] No Fault, FailCount= 3
2018-10-16T17:46:03.050Z,1539711963.050 [NAL9602](INFO): Powering up NAL9602
2018-10-16T17:46:13.851Z,1539711973.851 [NAL9602](INFO): NAL9602 initialized
2018-10-16T17:46:37.498Z,1539711997.498 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:46:37.498Z,1539711997.498 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-10-16T17:46:37.498Z,1539711997.498 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:46:37.518Z,1539711997.518 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:46:37.917Z,1539711997.917 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:46:37.917Z,1539711997.917 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-10-16T17:46:38.302Z,1539711998.302 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:46:38.302Z,1539711998.302 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:46:38.302Z,1539711998.302 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:47:47.356Z,1539712067.356 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-16T17:47:47.357Z,1539712067.357 [Default:CheckIn:C.Wait] Stopped
2018-10-16T17:47:47.357Z,1539712067.357 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T17:47:47.357Z,1539712067.357 [Default:CheckIn:D] Running Loop=1
2018-10-16T17:47:47.759Z,1539712067.759 [Default:CheckIn:D] Stopped
2018-10-16T17:47:47.759Z,1539712067.759 [Default:CheckIn:E] Running Loop=1
2018-10-16T17:47:48.157Z,1539712068.157 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.096452 min 
2018-10-16T17:47:48.157Z,1539712068.157 [Default:CheckIn:E] Stopped
2018-10-16T17:47:48.157Z,1539712068.157 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-16T17:47:48.157Z,1539712068.157 [Default:CheckIn] Stopped
2018-10-16T17:47:48.157Z,1539712068.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T17:47:48.158Z,1539712068.158 [Default:CheckIn](INFO): Running loop #4
2018-10-16T17:47:48.158Z,1539712068.158 [Default:CheckIn] Running Loop=4
2018-10-16T17:47:48.158Z,1539712068.158 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T17:47:48.158Z,1539712068.158 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T17:48:34.947Z,1539712114.947 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.399409 seconds old.
2018-10-16T17:48:35.353Z,1539712115.353 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.805784 seconds old.
2018-10-16T17:49:38.388Z,1539712178.388 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:49:38.389Z,1539712178.389 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-10-16T17:49:38.389Z,1539712178.389 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:49:38.476Z,1539712178.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:49:38.762Z,1539712178.762 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:49:38.762Z,1539712178.762 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-10-16T17:49:39.147Z,1539712179.147 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:49:39.147Z,1539712179.147 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:49:39.148Z,1539712179.148 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:52:39.223Z,1539712359.223 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:52:39.224Z,1539712359.224 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-10-16T17:52:39.224Z,1539712359.224 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:52:39.264Z,1539712359.264 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:52:39.615Z,1539712359.615 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:52:39.616Z,1539712359.616 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-10-16T17:52:40.012Z,1539712360.012 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:52:40.013Z,1539712360.013 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:52:40.013Z,1539712360.013 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:52:48.424Z,1539712368.424 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-16T17:47:48.2Z
2018-10-16T17:52:48.424Z,1539712368.424 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T17:52:48.424Z,1539712368.424 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-16T17:52:52.756Z,1539712372.756 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181016T171311/Courier0013.lzma
2018-10-16T17:52:53.554Z,1539712373.554 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0013.lzma.bak
2018-10-16T17:52:53.555Z,1539712373.555 [DataOverHttps](INFO): SBD MOMSN=8718311
2018-10-16T17:53:03.052Z,1539712383.052 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20181016T171311/Express0014.lzma
2018-10-16T17:53:03.843Z,1539712383.843 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0014.lzma.bak
2018-10-16T17:53:03.843Z,1539712383.843 [DataOverHttps](INFO): SBD MOMSN=8718314
2018-10-16T17:53:04.441Z,1539712384.441 [Default:CheckIn:Read_Iridium] Stopped
2018-10-16T17:53:04.441Z,1539712384.441 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-16T17:53:04.441Z,1539712384.441 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-16T17:55:40.370Z,1539712540.370 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:55:40.370Z,1539712540.370 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-10-16T17:55:40.371Z,1539712540.371 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:55:40.383Z,1539712540.383 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:55:40.786Z,1539712540.786 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:55:40.786Z,1539712540.786 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-10-16T17:55:41.168Z,1539712541.168 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:55:41.168Z,1539712541.168 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:55:41.169Z,1539712541.169 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T17:56:14.699Z,1539712574.699 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-16T17:56:14.699Z,1539712574.699 [NAL9602] Data Fault, FailCount= 4
2018-10-16T17:56:14.699Z,1539712574.699 [NAL9602](ERROR): Data Fault
2018-10-16T17:56:14.804Z,1539712574.804 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-16T17:56:15.094Z,1539712575.094 [NAL9602](INFO): Powering down
2018-10-16T17:56:15.995Z,1539712575.995 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-16T17:56:15.996Z,1539712575.996 [NAL9602] No Fault, FailCount= 4
2018-10-16T17:56:45.102Z,1539712605.102 [NAL9602](INFO): Powering up NAL9602
2018-10-16T17:56:56.299Z,1539712616.299 [NAL9602](INFO): NAL9602 initialized
2018-10-16T17:58:04.798Z,1539712684.798 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-16T17:58:04.798Z,1539712684.798 [Default:CheckIn:C.Wait] Stopped
2018-10-16T17:58:04.798Z,1539712684.798 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T17:58:04.798Z,1539712684.798 [Default:CheckIn:D] Running Loop=1
2018-10-16T17:58:05.176Z,1539712685.176 [Default:CheckIn:D] Stopped
2018-10-16T17:58:05.176Z,1539712685.176 [Default:CheckIn:E] Running Loop=1
2018-10-16T17:58:05.574Z,1539712685.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.386735 min 
2018-10-16T17:58:05.575Z,1539712685.575 [Default:CheckIn:E] Stopped
2018-10-16T17:58:05.575Z,1539712685.575 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-16T17:58:05.575Z,1539712685.575 [Default:CheckIn] Stopped
2018-10-16T17:58:05.575Z,1539712685.575 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T17:58:05.575Z,1539712685.575 [Default:CheckIn](INFO): Running loop #5
2018-10-16T17:58:05.575Z,1539712685.575 [Default:CheckIn] Running Loop=5
2018-10-16T17:58:05.575Z,1539712685.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T17:58:05.576Z,1539712685.576 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T17:58:41.200Z,1539712721.200 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T17:58:41.200Z,1539712721.200 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-10-16T17:58:41.200Z,1539712721.200 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T17:58:41.212Z,1539712721.212 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T17:58:41.613Z,1539712721.613 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T17:58:41.614Z,1539712721.614 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-10-16T17:58:42.014Z,1539712722.014 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T17:58:42.016Z,1539712722.016 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T17:58:42.029Z,1539712722.029 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:01:42.224Z,1539712902.224 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:01:42.224Z,1539712902.224 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-10-16T18:01:42.224Z,1539712902.224 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:01:42.237Z,1539712902.237 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:01:42.586Z,1539712902.586 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:01:42.587Z,1539712902.587 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-10-16T18:01:42.966Z,1539712902.966 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:01:42.966Z,1539712902.966 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:01:42.967Z,1539712902.967 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:03:05.597Z,1539712985.597 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-16T17:58:05.6Z
2018-10-16T18:03:05.597Z,1539712985.597 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T18:03:05.597Z,1539712985.597 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-16T18:03:15.604Z,1539712995.604 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181016T171311/Courier0016.lzma
2018-10-16T18:03:16.402Z,1539712996.402 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0016.lzma.bak
2018-10-16T18:03:16.403Z,1539712996.403 [DataOverHttps](INFO): SBD MOMSN=8718517
2018-10-16T18:03:25.920Z,1539713005.920 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20181016T171311/Express0017.lzma
2018-10-16T18:03:26.722Z,1539713006.722 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0017.lzma.bak
2018-10-16T18:03:26.722Z,1539713006.722 [DataOverHttps](INFO): SBD MOMSN=8718520
2018-10-16T18:03:27.321Z,1539713007.321 [Default:CheckIn:Read_Iridium] Stopped
2018-10-16T18:03:27.321Z,1539713007.321 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-16T18:03:27.322Z,1539713007.322 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-16T18:04:42.967Z,1539713082.967 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:04:42.967Z,1539713082.967 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-10-16T18:04:42.968Z,1539713082.968 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:04:43.023Z,1539713083.023 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:04:43.395Z,1539713083.395 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:04:43.395Z,1539713083.395 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-10-16T18:04:43.762Z,1539713083.762 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:04:43.763Z,1539713083.763 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:04:43.763Z,1539713083.763 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:06:57.410Z,1539713217.410 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-16T18:06:57.410Z,1539713217.410 [NAL9602] Data Fault, FailCount= 5
2018-10-16T18:06:57.410Z,1539713217.410 [NAL9602](ERROR): Data Fault
2018-10-16T18:06:57.483Z,1539713217.483 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-16T18:06:57.483Z,1539713217.483 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-10-16T18:06:57.802Z,1539713217.802 [NAL9602](INFO): Powering down
2018-10-16T18:06:57.877Z,1539713217.877 [CommandLine](FAULT): Scheduling is paused
2018-10-16T18:06:57.877Z,1539713217.877 [CBIT](INFO): Critical error at 20181016T180657
2018-10-16T18:06:57.877Z,1539713217.877 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-10-16T18:07:43.779Z,1539713263.779 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:07:43.779Z,1539713263.779 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-10-16T18:07:43.779Z,1539713263.779 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:07:43.809Z,1539713263.809 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:07:44.249Z,1539713264.249 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:07:44.250Z,1539713264.250 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-10-16T18:07:44.583Z,1539713264.583 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:07:44.584Z,1539713264.584 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:07:44.584Z,1539713264.584 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:08:27.805Z,1539713307.805 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-16T18:08:27.805Z,1539713307.805 [Default:CheckIn:C.Wait] Stopped
2018-10-16T18:08:27.805Z,1539713307.805 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T18:08:27.805Z,1539713307.805 [Default:CheckIn:D] Running Loop=1
2018-10-16T18:08:28.237Z,1539713308.237 [Default:CheckIn:D] Stopped
2018-10-16T18:08:28.237Z,1539713308.237 [Default:CheckIn:E] Running Loop=1
2018-10-16T18:08:28.580Z,1539713308.580 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.771082 min 
2018-10-16T18:08:28.580Z,1539713308.580 [Default:CheckIn:E] Stopped
2018-10-16T18:08:28.585Z,1539713308.585 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-16T18:08:28.585Z,1539713308.585 [Default:CheckIn] Stopped
2018-10-16T18:08:28.585Z,1539713308.585 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T18:08:28.585Z,1539713308.585 [Default:CheckIn](INFO): Running loop #6
2018-10-16T18:08:28.585Z,1539713308.585 [Default:CheckIn] Running Loop=6
2018-10-16T18:08:28.585Z,1539713308.585 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T18:08:28.586Z,1539713308.586 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T18:08:57.786Z,1539713337.786 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-16T18:08:57.786Z,1539713337.786 [NAL9602] No Fault, FailCount= 5
2018-10-16T18:08:58.135Z,1539713338.135 [NAL9602](INFO): Powering up NAL9602
2018-10-16T18:09:09.311Z,1539713349.311 [NAL9602](INFO): NAL9602 initialized
2018-10-16T18:10:44.844Z,1539713444.844 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:10:44.845Z,1539713444.845 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2018-10-16T18:10:44.845Z,1539713444.845 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:10:44.857Z,1539713444.857 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:10:45.244Z,1539713445.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:10:45.244Z,1539713445.244 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2018-10-16T18:10:45.646Z,1539713445.646 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:10:45.647Z,1539713445.647 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:10:45.647Z,1539713445.647 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:13:28.899Z,1539713608.899 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-16T18:08:28.6Z
2018-10-16T18:13:28.899Z,1539713608.899 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T18:13:28.899Z,1539713608.899 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-16T18:13:33.781Z,1539713613.781 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20181016T171311/Courier0019.lzma
2018-10-16T18:13:34.575Z,1539713614.575 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0019.lzma.bak
2018-10-16T18:13:34.575Z,1539713614.575 [DataOverHttps](INFO): SBD MOMSN=8718629
2018-10-16T18:13:44.387Z,1539713624.387 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20181016T171311/Express0020.lzma
2018-10-16T18:13:45.090Z,1539713625.090 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0020.lzma.bak
2018-10-16T18:13:45.091Z,1539713625.091 [DataOverHttps](INFO): SBD MOMSN=8718633
2018-10-16T18:13:45.676Z,1539713625.676 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:13:45.676Z,1539713625.676 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2018-10-16T18:13:45.676Z,1539713625.676 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:13:45.678Z,1539713625.678 [Default:CheckIn:Read_Iridium] Stopped
2018-10-16T18:13:45.678Z,1539713625.678 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-16T18:13:45.678Z,1539713625.678 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-16T18:13:45.714Z,1539713625.714 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:13:45.714Z,1539713625.714 [CBIT](FAULT): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:18:46.254Z,1539713926.254 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-16T18:18:46.254Z,1539713926.254 [Default:CheckIn:C.Wait] Stopped
2018-10-16T18:18:46.254Z,1539713926.254 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T18:18:46.254Z,1539713926.254 [Default:CheckIn:D] Running Loop=1
2018-10-16T18:18:46.591Z,1539713926.591 [Default:CheckIn:D] Stopped
2018-10-16T18:18:46.591Z,1539713926.591 [Default:CheckIn:E] Running Loop=1
2018-10-16T18:18:46.988Z,1539713926.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.076982 min 
2018-10-16T18:18:46.988Z,1539713926.988 [Default:CheckIn:E] Stopped
2018-10-16T18:18:46.988Z,1539713926.988 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-16T18:18:46.988Z,1539713926.988 [Default:CheckIn] Stopped
2018-10-16T18:18:46.988Z,1539713926.988 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T18:18:46.989Z,1539713926.989 [Default:CheckIn](INFO): Running loop #7
2018-10-16T18:18:46.989Z,1539713926.989 [Default:CheckIn] Running Loop=7
2018-10-16T18:18:46.989Z,1539713926.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T18:18:46.989Z,1539713926.989 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T18:19:10.419Z,1539713950.419 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-16T18:19:10.419Z,1539713950.419 [NAL9602] Data Fault, FailCount= 1
2018-10-16T18:19:10.419Z,1539713950.419 [NAL9602](ERROR): Data Fault
2018-10-16T18:19:10.494Z,1539713950.494 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-16T18:19:10.823Z,1539713950.823 [NAL9602](INFO): Powering down
2018-10-16T18:19:11.675Z,1539713951.675 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-16T18:19:11.675Z,1539713951.675 [NAL9602] No Fault, FailCount= 1
2018-10-16T18:19:41.226Z,1539713981.226 [NAL9602](INFO): Powering up NAL9602
2018-10-16T18:19:52.146Z,1539713992.146 [NAL9602](INFO): NAL9602 initialized
2018-10-16T18:22:17.476Z,1539714137.476 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2018-10-16T18:23:47.145Z,1539714227.145 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-16T18:18:46.0Z
2018-10-16T18:23:47.145Z,1539714227.145 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T18:23:47.145Z,1539714227.145 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-16T18:23:51.360Z,1539714231.360 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181016T171311/Courier0022.lzma
2018-10-16T18:23:52.152Z,1539714232.152 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0022.lzma.bak
2018-10-16T18:23:52.153Z,1539714232.153 [DataOverHttps](INFO): SBD MOMSN=8718744
2018-10-16T18:24:02.338Z,1539714242.338 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20181016T171311/Express0023.lzma
2018-10-16T18:24:03.139Z,1539714243.139 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0023.lzma.bak
2018-10-16T18:24:03.139Z,1539714243.139 [DataOverHttps](INFO): SBD MOMSN=8718747
2018-10-16T18:24:03.765Z,1539714243.765 [Default:CheckIn:Read_Iridium] Stopped
2018-10-16T18:24:03.766Z,1539714243.766 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-16T18:24:03.766Z,1539714243.766 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-16T18:24:33.125Z,1539714273.125 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-10-16T18:28:45.798Z,1539714525.798 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:28:45.798Z,1539714525.798 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2018-10-16T18:28:46.205Z,1539714526.205 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:28:46.205Z,1539714526.205 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:28:46.206Z,1539714526.206 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:29:04.172Z,1539714544.172 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-16T18:29:04.177Z,1539714544.177 [Default:CheckIn:C.Wait] Stopped
2018-10-16T18:29:04.177Z,1539714544.177 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T18:29:04.178Z,1539714544.178 [Default:CheckIn:D] Running Loop=1
2018-10-16T18:29:04.577Z,1539714544.577 [Default:CheckIn:D] Stopped
2018-10-16T18:29:04.577Z,1539714544.577 [Default:CheckIn:E] Running Loop=1
2018-10-16T18:29:04.977Z,1539714544.977 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.376742 min 
2018-10-16T18:29:04.977Z,1539714544.977 [Default:CheckIn:E] Stopped
2018-10-16T18:29:04.977Z,1539714544.977 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-16T18:29:04.977Z,1539714544.977 [Default:CheckIn] Stopped
2018-10-16T18:29:04.978Z,1539714544.978 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T18:29:04.978Z,1539714544.978 [Default:CheckIn](INFO): Running loop #8
2018-10-16T18:29:04.978Z,1539714544.978 [Default:CheckIn] Running Loop=8
2018-10-16T18:29:04.978Z,1539714544.978 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T18:29:04.978Z,1539714544.978 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T18:29:54.519Z,1539714594.519 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-16T18:29:54.519Z,1539714594.519 [NAL9602] Data Fault, FailCount= 2
2018-10-16T18:29:54.519Z,1539714594.519 [NAL9602](ERROR): Data Fault
2018-10-16T18:29:54.584Z,1539714594.584 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-16T18:29:54.918Z,1539714594.918 [NAL9602](INFO): Powering down
2018-10-16T18:29:55.784Z,1539714595.784 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-16T18:29:55.789Z,1539714595.789 [NAL9602] No Fault, FailCount= 2
2018-10-16T18:30:25.259Z,1539714625.259 [NAL9602](INFO): Powering up NAL9602
2018-10-16T18:30:36.459Z,1539714636.459 [NAL9602](INFO): NAL9602 initialized
2018-10-16T18:31:46.361Z,1539714706.361 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:31:46.361Z,1539714706.361 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-16T18:31:46.361Z,1539714706.361 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:31:46.381Z,1539714706.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:31:46.772Z,1539714706.772 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:31:46.773Z,1539714706.773 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-16T18:31:47.161Z,1539714707.161 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:31:47.161Z,1539714707.161 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:31:47.162Z,1539714707.162 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:34:05.354Z,1539714845.354 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-16T18:29:04.0Z
2018-10-16T18:34:05.354Z,1539714845.354 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T18:34:05.354Z,1539714845.354 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-16T18:34:10.260Z,1539714850.260 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181016T171311/Courier0025.lzma
2018-10-16T18:34:11.034Z,1539714851.034 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0025.lzma.bak
2018-10-16T18:34:11.035Z,1539714851.035 [DataOverHttps](INFO): SBD MOMSN=8718874
2018-10-16T18:34:21.678Z,1539714861.678 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20181016T171311/Express0026.lzma
2018-10-16T18:34:22.471Z,1539714862.471 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0026.lzma.bak
2018-10-16T18:34:22.471Z,1539714862.471 [DataOverHttps](INFO): SBD MOMSN=8718877
2018-10-16T18:34:23.070Z,1539714863.070 [Default:CheckIn:Read_Iridium] Stopped
2018-10-16T18:34:23.070Z,1539714863.070 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-16T18:34:23.070Z,1539714863.070 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-16T18:34:47.420Z,1539714887.420 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:34:47.420Z,1539714887.420 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-10-16T18:34:47.420Z,1539714887.420 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:34:47.441Z,1539714887.441 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:34:47.822Z,1539714887.822 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:34:47.822Z,1539714887.822 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-10-16T18:34:48.208Z,1539714888.208 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:34:48.209Z,1539714888.209 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:34:48.209Z,1539714888.209 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:37:48.456Z,1539715068.456 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:37:48.456Z,1539715068.456 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-10-16T18:37:48.456Z,1539715068.456 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:37:48.498Z,1539715068.498 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:37:48.859Z,1539715068.859 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:37:48.859Z,1539715068.859 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-10-16T18:37:49.250Z,1539715069.250 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:37:49.251Z,1539715069.251 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:37:49.251Z,1539715069.251 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:39:23.473Z,1539715163.473 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-16T18:39:23.477Z,1539715163.477 [Default:CheckIn:C.Wait] Stopped
2018-10-16T18:39:23.477Z,1539715163.477 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T18:39:23.477Z,1539715163.477 [Default:CheckIn:D] Running Loop=1
2018-10-16T18:39:23.866Z,1539715163.866 [Default:CheckIn:D] Stopped
2018-10-16T18:39:23.866Z,1539715163.866 [Default:CheckIn:E] Running Loop=1
2018-10-16T18:39:24.269Z,1539715164.269 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.698226 min 
2018-10-16T18:39:24.269Z,1539715164.269 [Default:CheckIn:E] Stopped
2018-10-16T18:39:24.269Z,1539715164.269 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-16T18:39:24.269Z,1539715164.269 [Default:CheckIn] Stopped
2018-10-16T18:39:24.269Z,1539715164.269 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T18:39:24.270Z,1539715164.270 [Default:CheckIn](INFO): Running loop #9
2018-10-16T18:39:24.270Z,1539715164.270 [Default:CheckIn] Running Loop=9
2018-10-16T18:39:24.270Z,1539715164.270 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T18:39:24.270Z,1539715164.270 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T18:40:37.907Z,1539715237.907 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-16T18:40:37.907Z,1539715237.907 [NAL9602] Data Fault, FailCount= 3
2018-10-16T18:40:37.907Z,1539715237.907 [NAL9602](ERROR): Data Fault
2018-10-16T18:40:38.071Z,1539715238.071 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-16T18:40:38.294Z,1539715238.294 [NAL9602](INFO): Powering down
2018-10-16T18:40:39.145Z,1539715239.145 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-16T18:40:39.145Z,1539715239.145 [NAL9602] No Fault, FailCount= 3
2018-10-16T18:40:49.543Z,1539715249.543 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:40:49.544Z,1539715249.544 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-10-16T18:40:49.544Z,1539715249.544 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:40:49.556Z,1539715249.556 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:40:50.073Z,1539715250.073 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:40:50.073Z,1539715250.073 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-10-16T18:40:50.327Z,1539715250.327 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:40:50.327Z,1539715250.327 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:40:50.328Z,1539715250.328 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:41:08.299Z,1539715268.299 [NAL9602](INFO): Powering up NAL9602
2018-10-16T18:41:19.494Z,1539715279.494 [NAL9602](INFO): NAL9602 initialized
2018-10-16T18:43:50.623Z,1539715430.623 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:43:50.623Z,1539715430.623 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-10-16T18:43:50.623Z,1539715430.623 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:43:50.699Z,1539715430.699 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:43:50.976Z,1539715430.976 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:43:50.976Z,1539715430.976 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-10-16T18:43:51.343Z,1539715431.343 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:43:51.344Z,1539715431.344 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:43:51.344Z,1539715431.344 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:44:24.554Z,1539715464.554 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-16T18:39:24.3Z
2018-10-16T18:44:24.554Z,1539715464.554 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T18:44:24.554Z,1539715464.554 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-16T18:44:29.015Z,1539715469.015 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181016T171311/Courier0028.lzma
2018-10-16T18:44:29.791Z,1539715469.791 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0028.lzma.bak
2018-10-16T18:44:29.791Z,1539715469.791 [DataOverHttps](INFO): SBD MOMSN=8718987
2018-10-16T18:44:39.365Z,1539715479.365 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20181016T171311/Express0029.lzma
2018-10-16T18:44:40.166Z,1539715480.166 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0029.lzma.bak
2018-10-16T18:44:40.166Z,1539715480.166 [DataOverHttps](INFO): SBD MOMSN=8718990
2018-10-16T18:44:40.746Z,1539715480.746 [Default:CheckIn:Read_Iridium] Stopped
2018-10-16T18:44:40.746Z,1539715480.746 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-16T18:44:40.746Z,1539715480.746 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-16T18:46:12.246Z,1539715572.246 [BPC1](ERROR): Battery stick #1 (s/n: 0138) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T18:46:12.247Z,1539715572.247 [BPC1](ERROR): Battery stick #7 (s/n: 012D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.247Z,1539715572.247 [BPC1](ERROR): Battery stick #8 (s/n: 00E7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.248Z,1539715572.248 [BPC1](ERROR): Battery stick #9 (s/n: 0100) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.249Z,1539715572.249 [BPC1](ERROR): Battery stick #14 (s/n: 011D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T18:46:12.250Z,1539715572.250 [BPC1](ERROR): Battery stick #15 (s/n: 014E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T18:46:12.251Z,1539715572.251 [BPC1](ERROR): Battery stick #18 (s/n: 0140) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T18:46:12.251Z,1539715572.251 [BPC1](ERROR): Battery stick #21 (s/n: 00CD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.252Z,1539715572.252 [BPC1](ERROR): Battery stick #22 (s/n: 009F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.253Z,1539715572.253 [BPC1](ERROR): Battery stick #25 (s/n: 00E8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.254Z,1539715572.254 [BPC1](ERROR): Battery stick #26 (s/n: 00D1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.255Z,1539715572.255 [BPC1](ERROR): Battery stick #28 (s/n: 0092) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.255Z,1539715572.255 [BPC1](ERROR): Battery stick #30 (s/n: 00E9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.256Z,1539715572.256 [BPC1](ERROR): Battery stick #32 (s/n: 0157) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.257Z,1539715572.257 [BPC1](ERROR): Battery stick #39 (s/n: 00FD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.258Z,1539715572.258 [BPC1](ERROR): Battery stick #40 (s/n: 0095) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.259Z,1539715572.259 [BPC1](ERROR): Battery stick #43 (s/n: 00AE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T18:46:12.260Z,1539715572.260 [BPC1](ERROR): Battery stick #44 (s/n: 00CB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T18:46:12.260Z,1539715572.260 [BPC1](ERROR): Battery stick #45 (s/n: 00F7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T18:46:12.261Z,1539715572.261 [BPC1](ERROR): Battery stick #46 (s/n: 00A1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.262Z,1539715572.262 [BPC1](ERROR): Battery stick #49 (s/n: 0103) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48E0.
2018-10-16T18:46:12.263Z,1539715572.263 [BPC1](ERROR): Battery stick #50 (s/n: 0152) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T18:46:12.263Z,1539715572.263 [BPC1](ERROR): Battery stick #51 (s/n: 014A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T18:46:12.264Z,1539715572.264 [BPC1](ERROR): Battery stick #53 (s/n: 015E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T18:46:12.288Z,1539715572.288 [BPC1](ERROR): Battery stick #54 (s/n: 008E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.288Z,1539715572.288 [BPC1](ERROR): Battery stick #56 (s/n: 0091) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-10-16T18:46:12.294Z,1539715572.294 [BPC1](ERROR): Battery stick #57 (s/n: 0153) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-10-16T18:46:12.294Z,1539715572.294 [BPC1](ERROR): Battery stick #62 (s/n: 00DA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-10-16T18:46:12.295Z,1539715572.295 [BPC1](INFO): Calculating totals. Valid battery stick count: 30. Valid reserve battery stick count: 4.
2018-10-16T18:46:51.444Z,1539715611.444 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:46:51.444Z,1539715611.444 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-10-16T18:46:51.444Z,1539715611.444 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:46:51.469Z,1539715611.469 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:46:51.866Z,1539715611.866 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:46:51.866Z,1539715611.866 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-10-16T18:46:52.224Z,1539715612.224 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:46:52.224Z,1539715612.224 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:46:52.224Z,1539715612.224 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:49:41.251Z,1539715781.251 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-16T18:49:41.251Z,1539715781.251 [Default:CheckIn:C.Wait] Stopped
2018-10-16T18:49:41.251Z,1539715781.251 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T18:49:41.251Z,1539715781.251 [Default:CheckIn:D] Running Loop=1
2018-10-16T18:49:41.657Z,1539715781.657 [Default:CheckIn:D] Stopped
2018-10-16T18:49:41.657Z,1539715781.657 [Default:CheckIn:E] Running Loop=1
2018-10-16T18:49:45.121Z,1539715785.121 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.994670 min 
2018-10-16T18:49:45.121Z,1539715785.121 [Default:CheckIn:E] Stopped
2018-10-16T18:49:45.121Z,1539715785.121 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-16T18:49:45.121Z,1539715785.121 [Default:CheckIn] Stopped
2018-10-16T18:49:45.122Z,1539715785.122 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T18:49:45.122Z,1539715785.122 [Default:CheckIn](INFO): Running loop #10
2018-10-16T18:49:45.122Z,1539715785.122 [Default:CheckIn] Running Loop=10
2018-10-16T18:49:45.122Z,1539715785.122 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T18:49:45.122Z,1539715785.122 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T18:49:52.454Z,1539715792.454 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:49:52.454Z,1539715792.454 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-10-16T18:49:52.454Z,1539715792.454 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:49:52.535Z,1539715792.535 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:49:52.859Z,1539715792.859 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:49:52.859Z,1539715792.859 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-10-16T18:49:53.220Z,1539715793.220 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:49:53.220Z,1539715793.220 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:49:53.221Z,1539715793.221 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:51:20.646Z,1539715880.646 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-16T18:51:20.647Z,1539715880.647 [NAL9602] Data Fault, FailCount= 4
2018-10-16T18:51:20.647Z,1539715880.647 [NAL9602](ERROR): Data Fault
2018-10-16T18:51:20.752Z,1539715880.752 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-16T18:51:21.058Z,1539715881.058 [NAL9602](INFO): Powering down
2018-10-16T18:51:22.068Z,1539715882.068 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-16T18:51:22.068Z,1539715882.068 [NAL9602] No Fault, FailCount= 4
2018-10-16T18:51:51.335Z,1539715911.335 [NAL9602](INFO): Powering up NAL9602
2018-10-16T18:52:02.531Z,1539715922.531 [NAL9602](INFO): NAL9602 initialized
2018-10-16T18:52:53.380Z,1539715973.380 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:52:53.380Z,1539715973.380 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-10-16T18:52:53.380Z,1539715973.380 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:52:53.417Z,1539715973.417 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:52:53.815Z,1539715973.815 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:52:53.815Z,1539715973.815 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-10-16T18:52:54.187Z,1539715974.187 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:52:54.188Z,1539715974.188 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:52:54.188Z,1539715974.188 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:54:46.378Z,1539716086.378 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-16T18:49:45.1Z
2018-10-16T18:54:46.379Z,1539716086.379 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T18:54:46.379Z,1539716086.379 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-16T18:54:51.744Z,1539716091.744 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20181016T171311/Courier0031.lzma
2018-10-16T18:54:52.535Z,1539716092.535 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Courier0031.lzma.bak
2018-10-16T18:54:52.535Z,1539716092.535 [DataOverHttps](INFO): SBD MOMSN=8719099
2018-10-16T18:55:02.087Z,1539716102.087 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20181016T171311/Express0032.lzma
2018-10-16T18:55:02.871Z,1539716102.871 [DataOverHttps](INFO): Moved sent file to Logs/20181016T171311/Express0032.lzma.bak
2018-10-16T18:55:02.871Z,1539716102.871 [DataOverHttps](INFO): SBD MOMSN=8719102
2018-10-16T18:55:03.480Z,1539716103.480 [Default:CheckIn:Read_Iridium] Stopped
2018-10-16T18:55:03.481Z,1539716103.481 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-16T18:55:03.481Z,1539716103.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-16T18:55:54.446Z,1539716154.446 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:55:54.446Z,1539716154.446 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-10-16T18:55:54.446Z,1539716154.446 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:55:54.475Z,1539716154.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:55:54.889Z,1539716154.889 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:55:54.889Z,1539716154.889 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-10-16T18:55:55.249Z,1539716155.249 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:55:55.250Z,1539716155.250 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:55:55.250Z,1539716155.250 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T18:58:55.508Z,1539716335.508 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T18:58:55.508Z,1539716335.508 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-10-16T18:58:55.508Z,1539716335.508 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T18:58:55.520Z,1539716335.520 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T18:58:55.921Z,1539716335.921 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T18:58:55.921Z,1539716335.921 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-10-16T18:58:56.326Z,1539716336.326 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T18:58:56.327Z,1539716336.327 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T18:58:56.327Z,1539716336.327 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T19:00:03.938Z,1539716403.938 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-16T19:00:03.939Z,1539716403.939 [Default:CheckIn:C.Wait] Stopped
2018-10-16T19:00:03.939Z,1539716403.939 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T19:00:03.939Z,1539716403.939 [Default:CheckIn:D] Running Loop=1
2018-10-16T19:00:04.347Z,1539716404.347 [Default:CheckIn:D] Stopped
2018-10-16T19:00:04.347Z,1539716404.347 [Default:CheckIn:E] Running Loop=1
2018-10-16T19:00:04.750Z,1539716404.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.372917 min 
2018-10-16T19:00:04.750Z,1539716404.750 [Default:CheckIn:E] Stopped
2018-10-16T19:00:04.751Z,1539716404.751 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-16T19:00:04.751Z,1539716404.751 [Default:CheckIn] Stopped
2018-10-16T19:00:04.751Z,1539716404.751 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T19:00:04.751Z,1539716404.751 [Default:CheckIn](INFO): Running loop #11
2018-10-16T19:00:04.751Z,1539716404.751 [Default:CheckIn] Running Loop=11
2018-10-16T19:00:04.751Z,1539716404.751 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T19:00:04.751Z,1539716404.751 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T19:01:56.448Z,1539716516.448 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T19:01:56.448Z,1539716516.448 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-10-16T19:01:56.448Z,1539716516.448 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T19:01:56.480Z,1539716516.480 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T19:01:56.858Z,1539716516.858 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T19:01:56.859Z,1539716516.859 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-10-16T19:01:57.239Z,1539716517.239 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T19:01:57.240Z,1539716517.240 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T19:01:57.240Z,1539716517.240 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T19:02:04.015Z,1539716524.015 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-16T19:02:04.015Z,1539716524.015 [NAL9602] Data Fault, FailCount= 5
2018-10-16T19:02:04.015Z,1539716524.015 [NAL9602](ERROR): Data Fault
2018-10-16T19:02:04.178Z,1539716524.178 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-16T19:02:04.178Z,1539716524.178 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-10-16T19:02:04.394Z,1539716524.394 [NAL9602](INFO): Powering down
2018-10-16T19:02:04.478Z,1539716524.478 [CBIT](INFO): Critical error at 20181016T190204
2018-10-16T19:04:04.116Z,1539716644.116 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-16T19:04:04.116Z,1539716644.116 [NAL9602] No Fault, FailCount= 5
2018-10-16T19:04:04.450Z,1539716644.450 [NAL9602](INFO): Powering up NAL9602
2018-10-16T19:04:15.307Z,1539716655.307 [NAL9602](INFO): NAL9602 initialized
2018-10-16T19:04:57.345Z,1539716697.345 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T19:04:57.345Z,1539716697.345 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-10-16T19:04:57.345Z,1539716697.345 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T19:04:57.358Z,1539716697.358 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T19:04:57.766Z,1539716697.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T19:04:57.766Z,1539716697.766 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-10-16T19:04:58.220Z,1539716698.220 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T19:04:58.221Z,1539716698.221 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T19:04:58.221Z,1539716698.221 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T19:05:00.435Z,1539716700.435 [CommandLine](IMPORTANT): got command restart application
2018-10-16T19:05:01.444Z,1539716701.444 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T19:05:01.444Z,1539716701.444 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:01.641Z,1539716701.641 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-16T19:05:01.641Z,1539716701.641 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:01.642Z,1539716701.642 [CommandLine](INFO): Join timeout helper Thread ID is 1014
2018-10-16T19:05:01.642Z,1539716701.642 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-16T19:05:01.642Z,1539716701.642 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:01.643Z,1539716701.643 [NavChartDb](INFO): Join timeout helper Thread ID is 1015
2018-10-16T19:05:01.701Z,1539716701.701 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T19:05:01.701Z,1539716701.701 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:01.705Z,1539716701.705 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-10-16T19:05:01.705Z,1539716701.705 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:01.705Z,1539716701.705 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1016
2018-10-16T19:05:02.029Z,1539716702.029 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T19:05:02.029Z,1539716702.029 [WetLabsBB2FL](INFO): Powering down
2018-10-16T19:05:02.030Z,1539716702.030 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:02.049Z,1539716702.049 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-10-16T19:05:02.049Z,1539716702.049 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:02.049Z,1539716702.049 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1017
2018-10-16T19:05:02.113Z,1539716702.113 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T19:05:02.311Z,1539716702.311 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-16T19:05:02.312Z,1539716702.312 [CTD_Seabird](INFO): Powering down
2018-10-16T19:05:02.313Z,1539716702.313 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:02.314Z,1539716702.314 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-10-16T19:05:02.314Z,1539716702.314 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:02.314Z,1539716702.314 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1019
2018-10-16T19:05:02.405Z,1539716702.405 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T19:05:02.405Z,1539716702.405 [CTD_NeilBrown](INFO): Powering down
2018-10-16T19:05:02.406Z,1539716702.406 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:02.414Z,1539716702.414 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-10-16T19:05:02.414Z,1539716702.414 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:02.414Z,1539716702.414 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1020
2018-10-16T19:05:03.073Z,1539716703.073 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T19:05:03.073Z,1539716703.073 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-10-16T19:05:03.173Z,1539716703.173 [Rowe_600LCM](INFO): Powering down
2018-10-16T19:05:03.174Z,1539716703.174 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.186Z,1539716703.186 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-16T19:05:03.186Z,1539716703.186 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.186Z,1539716703.186 [Radio_Surface](INFO): Join timeout helper Thread ID is 1022
2018-10-16T19:05:03.545Z,1539716703.545 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T19:05:03.545Z,1539716703.545 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.566Z,1539716703.566 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-16T19:05:03.566Z,1539716703.566 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.566Z,1539716703.566 [logger](INFO): Join timeout helper Thread ID is 1023
2018-10-16T19:05:03.605Z,1539716703.605 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T19:05:03.605Z,1539716703.605 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.625Z,1539716703.625 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-16T19:05:03.626Z,1539716703.626 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.626Z,1539716703.626 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-16T19:05:03.626Z,1539716703.626 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.626Z,1539716703.626 [controlThread](INFO): Join timeout helper Thread ID is 1024
2018-10-16T19:05:03.693Z,1539716703.693 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T19:05:03.693Z,1539716703.693 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-16T19:05:03.694Z,1539716703.694 [NAL9602](INFO): Powering down
2018-10-16T19:05:03.696Z,1539716703.696 [PNI_TCM](INFO): Powering down
2018-10-16T19:05:03.781Z,1539716703.781 [Aanderaa_O2](INFO): Powering down
2018-10-16T19:05:03.783Z,1539716703.783 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-16T19:05:03.784Z,1539716703.784 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-16T19:05:03.784Z,1539716703.784 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-10-16T19:05:03.785Z,1539716703.785 [MissionManager](INFO): Uninitializing Mission Default
2018-10-16T19:05:03.785Z,1539716703.785 [Default] Stopped
2018-10-16T19:05:03.785Z,1539716703.785 [Default](DEBUG): Aggregate::uninitialize Default
2018-10-16T19:05:03.786Z,1539716703.786 [Default:B.GoToSurface] Stopped
2018-10-16T19:05:03.786Z,1539716703.786 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-16T19:05:03.786Z,1539716703.786 [Default:CheckIn] Stopped
2018-10-16T19:05:03.786Z,1539716703.786 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T19:05:03.786Z,1539716703.786 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T19:05:03.790Z,1539716703.790 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-16T19:05:03.790Z,1539716703.790 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-16T19:05:03.790Z,1539716703.790 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-16T19:05:03.791Z,1539716703.791 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-16T19:05:03.791Z,1539716703.791 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-16T19:05:03.791Z,1539716703.791 [BuoyancyServo](INFO): Powering down
2018-10-16T19:05:03.805Z,1539716703.805 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-16T19:05:03.805Z,1539716703.805 [ElevatorServo](INFO): Powering down
2018-10-16T19:05:03.806Z,1539716703.806 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-16T19:05:03.806Z,1539716703.806 [MassServo](INFO): Powering down
2018-10-16T19:05:03.807Z,1539716703.807 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-16T19:05:03.807Z,1539716703.807 [RudderServo](INFO): Powering down
2018-10-16T19:05:03.808Z,1539716703.808 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-16T19:05:03.808Z,1539716703.808 [ThrusterServo](INFO): Powering down
2018-10-16T19:05:03.809Z,1539716703.809 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-16T19:05:03.809Z,1539716703.809 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-16T19:05:03.809Z,1539716703.809 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-16T19:05:03.810Z,1539716703.810 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.933Z,1539716703.933 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.940Z,1539716703.940 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.981Z,1539716703.981 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.985Z,1539716703.985 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:03.987Z,1539716703.987 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:04.025Z,1539716704.025 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-16T19:05:04.083Z,1539716704.083 [logger ThreadHandler](INFO): Thread cancelled.