2018-02-11T21:24:00.625Z,1518384240.625 [Supervisor](DEBUG): Initializing supervisor.
2018-02-11T21:24:00.627Z,1518384240.627 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-02-11T21:24:00.628Z,1518384240.628 [SyncHandler](INFO): Protected caller Thread ID is 1094
2018-02-11T21:24:00.628Z,1518384240.628 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-02-11T21:24:00.629Z,1518384240.629 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-02-11T21:24:00.630Z,1518384240.630 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1095
2018-02-11T21:24:00.632Z,1518384240.632 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-02-11T21:24:00.643Z,1518384240.643 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-02-11T21:24:00.644Z,1518384240.644 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-02-11T21:24:00.644Z,1518384240.644 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1096
2018-02-11T21:24:00.645Z,1518384240.645 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-02-11T21:24:00.646Z,1518384240.646 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-02-11T21:24:00.646Z,1518384240.646 [logger ThreadHandler](INFO): Protected caller Thread ID is 1097
2018-02-11T21:24:00.648Z,1518384240.648 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-02-11T21:24:00.648Z,1518384240.648 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-02-11T21:24:00.650Z,1518384240.650 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-02-11T21:24:00.767Z,1518384240.767 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-02-11T21:24:00.768Z,1518384240.768 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-02-11T21:24:00.864Z,1518384240.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-02-11T21:24:00.865Z,1518384240.865 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-02-11T21:24:01.046Z,1518384241.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-02-11T21:24:01.047Z,1518384241.047 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-02-11T21:24:01.331Z,1518384241.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-02-11T21:24:01.332Z,1518384241.332 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-02-11T21:24:01.629Z,1518384241.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-02-11T21:24:01.629Z,1518384241.629 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-02-11T21:24:02.058Z,1518384242.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-02-11T21:24:02.059Z,1518384242.059 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-02-11T21:24:02.375Z,1518384242.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-02-11T21:24:02.376Z,1518384242.376 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-02-11T21:24:02.780Z,1518384242.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-02-11T21:24:02.781Z,1518384242.781 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-02-11T21:24:02.859Z,1518384242.859 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-02-11T21:24:03.133Z,1518384243.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-02-11T21:24:03.133Z,1518384243.133 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-02-11T21:24:03.268Z,1518384243.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-02-11T21:24:03.269Z,1518384243.269 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-02-11T21:24:03.459Z,1518384243.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-02-11T21:24:03.460Z,1518384243.460 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-02-11T21:24:03.656Z,1518384243.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-02-11T21:24:03.657Z,1518384243.657 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-02-11T21:24:03.751Z,1518384243.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-02-11T21:24:03.752Z,1518384243.752 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-02-11T21:24:03.887Z,1518384243.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-02-11T21:24:03.889Z,1518384243.889 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-02-11T21:24:03.890Z,1518384243.890 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-02-11T21:24:04.110Z,1518384244.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-02-11T21:24:04.111Z,1518384244.111 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-02-11T21:24:04.186Z,1518384244.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-02-11T21:24:04.319Z,1518384244.319 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-02-11T21:24:04.408Z,1518384244.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-02-11T21:24:04.490Z,1518384244.490 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-02-11T21:24:04.618Z,1518384244.618 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-02-11T21:24:04.777Z,1518384244.777 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-02-11T21:24:04.870Z,1518384244.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-02-11T21:24:04.959Z,1518384244.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-02-11T21:24:05.052Z,1518384245.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-02-11T21:24:05.133Z,1518384245.133 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-02-11T21:24:05.134Z,1518384245.134 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-02-11T21:24:05.147Z,1518384245.147 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-02-11T21:24:05.241Z,1518384245.241 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-02-11T21:24:05.241Z,1518384245.241 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-02-11T21:24:05.254Z,1518384245.254 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-02-11T21:24:05.254Z,1518384245.254 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-02-11T21:24:05.294Z,1518384245.294 [DepthRateCalculator] Loaded
2018-02-11T21:24:05.294Z,1518384245.294 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-02-11T21:24:05.300Z,1518384245.300 [PitchRateCalculator] Loaded
2018-02-11T21:24:05.300Z,1518384245.300 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-02-11T21:24:05.315Z,1518384245.315 [SpeedCalculator] Loaded
2018-02-11T21:24:05.315Z,1518384245.315 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-02-11T21:24:05.335Z,1518384245.335 [TempGradientCalculator] Loaded
2018-02-11T21:24:05.335Z,1518384245.335 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-02-11T21:24:05.350Z,1518384245.350 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-02-11T21:24:05.350Z,1518384245.350 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-02-11T21:24:05.356Z,1518384245.356 [YawRateCalculator] Loaded
2018-02-11T21:24:05.356Z,1518384245.356 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-02-11T21:24:05.394Z,1518384245.394 [ElevatorOffsetCalculator] Loaded
2018-02-11T21:24:05.394Z,1518384245.394 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-02-11T21:24:05.395Z,1518384245.395 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-02-11T21:24:05.395Z,1518384245.395 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-02-11T21:24:05.419Z,1518384245.419 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-02-11T21:24:05.419Z,1518384245.419 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-02-11T21:24:05.657Z,1518384245.657 [AcousticModem_Benthos_ATM900] Loaded
2018-02-11T21:24:05.657Z,1518384245.657 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-02-11T21:24:05.724Z,1518384245.724 [DataOverHttps] Loaded
2018-02-11T21:24:05.724Z,1518384245.724 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-02-11T21:24:05.737Z,1518384245.737 [Depth_Keller] Loaded
2018-02-11T21:24:05.737Z,1518384245.737 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-02-11T21:24:05.742Z,1518384245.742 [DropWeight] Loaded
2018-02-11T21:24:05.742Z,1518384245.742 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-02-11T21:24:05.835Z,1518384245.835 [NAL9602] Loaded
2018-02-11T21:24:05.835Z,1518384245.835 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-02-11T21:24:05.850Z,1518384245.850 [Onboard] Loaded
2018-02-11T21:24:05.850Z,1518384245.850 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-02-11T21:24:05.860Z,1518384245.860 [Radio_Surface] Loaded
2018-02-11T21:24:05.860Z,1518384245.860 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-02-11T21:24:05.861Z,1518384245.861 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A54E0
2018-02-11T21:24:05.862Z,1518384245.862 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1176
2018-02-11T21:24:05.906Z,1518384245.906 [PNI_TCM] Loaded
2018-02-11T21:24:05.906Z,1518384245.906 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-02-11T21:24:08.013Z,1518384248.013 [BPC1] Loaded
2018-02-11T21:24:08.014Z,1518384248.014 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-02-11T21:24:08.014Z,1518384248.014 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-02-11T21:24:08.015Z,1518384248.015 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-02-11T21:24:08.137Z,1518384248.137 [SBIT](DEBUG): Construct Startup Built In Test.
2018-02-11T21:24:08.159Z,1518384248.159 [SBIT] Loaded
2018-02-11T21:24:08.159Z,1518384248.159 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-02-11T21:24:08.160Z,1518384248.160 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-02-11T21:24:08.170Z,1518384248.170 [IBIT] Loaded
2018-02-11T21:24:08.171Z,1518384248.171 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-02-11T21:24:08.174Z,1518384248.174 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-02-11T21:24:08.315Z,1518384248.315 [CBIT] Loaded
2018-02-11T21:24:08.316Z,1518384248.316 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-02-11T21:24:08.316Z,1518384248.316 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-02-11T21:24:08.317Z,1518384248.317 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-02-11T21:24:08.451Z,1518384248.451 [Aanderaa_O2] Loaded
2018-02-11T21:24:08.452Z,1518384248.452 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-02-11T21:24:08.497Z,1518384248.497 [CTD_Seabird] Loaded
2018-02-11T21:24:08.497Z,1518384248.497 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-02-11T21:24:08.498Z,1518384248.498 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408654E0
2018-02-11T21:24:08.498Z,1518384248.498 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1177
2018-02-11T21:24:08.512Z,1518384248.512 [PAR_Licor] Loaded
2018-02-11T21:24:08.512Z,1518384248.512 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-02-11T21:24:08.559Z,1518384248.559 [WetLabsBB2FL] Loaded
2018-02-11T21:24:08.559Z,1518384248.559 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-02-11T21:24:08.560Z,1518384248.560 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408954E0
2018-02-11T21:24:08.561Z,1518384248.561 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1178
2018-02-11T21:24:08.561Z,1518384248.561 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-02-11T21:24:08.562Z,1518384248.562 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-02-11T21:24:08.785Z,1518384248.785 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-02-11T21:24:08.786Z,1518384248.786 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-02-11T21:24:08.849Z,1518384248.849 [VerticalControl](DEBUG): Construct VerticalControl.
2018-02-11T21:24:08.940Z,1518384248.940 [VerticalControl] Loaded
2018-02-11T21:24:08.940Z,1518384248.940 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-02-11T21:24:08.941Z,1518384248.941 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-02-11T21:24:08.999Z,1518384248.999 [HorizontalControl] Loaded
2018-02-11T21:24:08.999Z,1518384248.999 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-02-11T21:24:08.000Z,1518384249.000 [SpeedControl](DEBUG): Construct SpeedControl.
2018-02-11T21:24:09.005Z,1518384249.005 [SpeedControl] Loaded
2018-02-11T21:24:09.005Z,1518384249.005 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-02-11T21:24:09.006Z,1518384249.006 [LoopControl](DEBUG): Construct LoopControl.
2018-02-11T21:24:09.006Z,1518384249.006 [LoopControl] Loaded
2018-02-11T21:24:09.006Z,1518384249.006 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-02-11T21:24:09.007Z,1518384249.007 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-02-11T21:24:09.007Z,1518384249.007 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-02-11T21:24:09.086Z,1518384249.086 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-02-11T21:24:09.086Z,1518384249.086 [StratificationFrontDetector](DEBUG): (re)initializing
2018-02-11T21:24:09.086Z,1518384249.086 [StratificationFrontDetector] Loaded
2018-02-11T21:24:09.087Z,1518384249.087 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-02-11T21:24:09.087Z,1518384249.087 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-02-11T21:24:09.088Z,1518384249.088 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-02-11T21:24:09.181Z,1518384249.181 [BuoyancyServo] Loaded
2018-02-11T21:24:09.181Z,1518384249.181 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-02-11T21:24:09.192Z,1518384249.192 [ElevatorServo] Loaded
2018-02-11T21:24:09.193Z,1518384249.193 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-02-11T21:24:09.203Z,1518384249.203 [MassServo] Loaded
2018-02-11T21:24:09.203Z,1518384249.203 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-02-11T21:24:09.214Z,1518384249.214 [RudderServo] Loaded
2018-02-11T21:24:09.214Z,1518384249.214 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-02-11T21:24:09.225Z,1518384249.225 [ThrusterServo] Loaded
2018-02-11T21:24:09.225Z,1518384249.225 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-02-11T21:24:09.225Z,1518384249.225 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-02-11T21:24:09.226Z,1518384249.226 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-02-11T21:24:09.319Z,1518384249.319 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-02-11T21:24:09.319Z,1518384249.319 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-02-11T21:24:09.372Z,1518384249.372 [DeadReckonUsingSpeedCalculator] Loaded
2018-02-11T21:24:09.372Z,1518384249.372 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-02-11T21:24:09.388Z,1518384249.388 [NavChart] Loaded
2018-02-11T21:24:09.388Z,1518384249.388 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-02-11T21:24:09.392Z,1518384249.392 [UniversalFixResidualReporter] Loaded
2018-02-11T21:24:09.392Z,1518384249.392 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-02-11T21:24:09.393Z,1518384249.393 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-02-11T21:24:09.396Z,1518384249.396 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-02-11T21:24:09.397Z,1518384249.397 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-02-11T21:24:09.403Z,1518384249.403 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-02-11T21:24:09.404Z,1518384249.404 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409EA4E0
2018-02-11T21:24:09.405Z,1518384249.405 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1179
2018-02-11T21:24:09.409Z,1518384249.409 [Supervisor](INFO): Main Thread ID is 638
2018-02-11T21:24:09.409Z,1518384249.409 [Supervisor](DEBUG): Running supervisor.
2018-02-11T21:24:09.410Z,1518384249.410 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1180
2018-02-11T21:24:09.413Z,1518384249.413 [controlThread ThreadHandler](INFO): Handler Thread ID is 1181
2018-02-11T21:24:09.413Z,1518384249.413 [controlThread](DEBUG): Initializing ControlThread
2018-02-11T21:24:09.414Z,1518384249.414 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-02-11T21:24:09.414Z,1518384249.414 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-02-11T21:24:09.415Z,1518384249.415 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-02-11T21:24:09.415Z,1518384249.415 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-02-11T21:24:09.415Z,1518384249.415 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-02-11T21:24:09.416Z,1518384249.416 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-02-11T21:24:09.416Z,1518384249.416 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-02-11T21:24:09.423Z,1518384249.423 [SBIT](INFO): Initialize SBIT Component.
2018-02-11T21:24:09.423Z,1518384249.423 [SBIT](IMPORTANT): git: 2018-02-09A
2018-02-11T21:24:09.424Z,1518384249.424 [SBIT](INFO): git hash: 30da1bf2046909e0dad541153321d39c5053adc1
2018-02-11T21:24:09.424Z,1518384249.424 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-02-11T21:24:09.425Z,1518384249.425 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017
2018-02-11T21:24:09.426Z,1518384249.426 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2018-02-11T21:24:09.427Z,1518384249.427 [IBIT](INFO): Initialize IBIT Component.
2018-02-11T21:24:09.427Z,1518384249.427 [CBIT](DEBUG): Initialize CBIT Component.
2018-02-11T21:24:09.428Z,1518384249.428 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-02-11T21:24:09.429Z,1518384249.429 [logger ThreadHandler](INFO): Handler Thread ID is 1182
2018-02-11T21:24:09.449Z,1518384249.449 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1183
2018-02-11T21:24:09.455Z,1518384249.455 [Radio_Surface](INFO): Powering up
2018-02-11T21:24:09.461Z,1518384249.461 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1184
2018-02-11T21:24:09.462Z,1518384249.462 [CTD_Seabird](INFO): Initializing
2018-02-11T21:24:09.462Z,1518384249.462 [CTD_Seabird](INFO): Checking LCM
2018-02-11T21:24:09.471Z,1518384249.471 [CTD_Seabird](INFO): LCM OK
2018-02-11T21:24:09.472Z,1518384249.472 [CTD_Seabird](INFO): Powering up
2018-02-11T21:24:09.477Z,1518384249.477 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1185
2018-02-11T21:24:09.478Z,1518384249.478 [WetLabsBB2FL](INFO): Powering down
2018-02-11T21:24:09.505Z,1518384249.505 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1186
2018-02-11T21:24:09.508Z,1518384249.508 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-02-11T21:24:09.508Z,1518384249.508 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-02-11T21:24:09.509Z,1518384249.509 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-02-11T21:24:09.509Z,1518384249.509 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-02-11T21:24:09.509Z,1518384249.509 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-02-11T21:24:09.509Z,1518384249.509 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-02-11T21:24:09.510Z,1518384249.510 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-02-11T21:24:09.510Z,1518384249.510 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-02-11T21:24:09.510Z,1518384249.510 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-02-11T21:24:09.510Z,1518384249.510 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-02-11T21:24:09.510Z,1518384249.510 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-02-11T21:24:09.511Z,1518384249.511 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-02-11T21:24:09.511Z,1518384249.511 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-02-11T21:24:09.511Z,1518384249.511 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-02-11T21:24:09.511Z,1518384249.511 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-02-11T21:24:09.511Z,1518384249.511 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-02-11T21:24:09.541Z,1518384249.541 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-02-11T21:24:09.543Z,1518384249.543 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-02-11T21:24:09.543Z,1518384249.543 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-02-11T21:24:09.544Z,1518384249.544 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-02-11T21:24:09.546Z,1518384249.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-02-11T21:24:09.546Z,1518384249.546 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:24:09.547Z,1518384249.547 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:24:09.547Z,1518384249.547 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-11T21:24:09.548Z,1518384249.548 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:24:09.548Z,1518384249.548 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:24:09.548Z,1518384249.548 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-02-11T21:24:09.549Z,1518384249.549 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-02-11T21:24:09.550Z,1518384249.550 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-02-11T21:24:09.568Z,1518384249.568 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-02-11T21:24:09.606Z,1518384249.606 [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-02-11T21:24:09.606Z,1518384249.606 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-02-11T21:24:09.666Z,1518384249.666 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-02-11T21:24:09.667Z,1518384249.667 [Default:A.Wait](DEBUG): Construct Wait.
2018-02-11T21:24:09.685Z,1518384249.685 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-02-11T21:24:09.710Z,1518384249.710 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-02-11T21:24:09.713Z,1518384249.713 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-02-11T21:24:09.742Z,1518384249.742 [Default:E.Execute](DEBUG): Construct Execute.
2018-02-11T21:24:09.766Z,1518384249.766 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</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-02-11T21:24:09.771Z,1518384249.771 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-02-11T21:24:09.822Z,1518384249.822 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-02-11T21:24:09.822Z,1518384249.822 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-02-11T21:24:09.847Z,1518384249.847 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-02-11T21:24:10.118Z,1518384250.118 [BPC1](INFO): Start
2018-02-11T21:24:10.501Z,1518384250.501 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:10.512Z,1518384250.512 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:10.625Z,1518384250.625 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:24:10.633Z,1518384250.633 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-11T21:24:10.647Z,1518384250.647 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:24:10.653Z,1518384250.653 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-02-11T21:24:10.674Z,1518384250.674 [MassServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:24:10.681Z,1518384250.681 [MassServo](DEBUG): Initializing MassServo.
2018-02-11T21:24:10.694Z,1518384250.694 [RudderServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:24:10.701Z,1518384250.701 [RudderServo](DEBUG): Initializing RudderServo.
2018-02-11T21:24:10.714Z,1518384250.714 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:24:10.721Z,1518384250.721 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-02-11T21:24:10.762Z,1518384250.762 [CommandLine](FAULT): Scheduling is paused
2018-02-11T21:24:10.763Z,1518384250.763 [CBIT](INFO): Critical error at 20180211T212409
2018-02-11T21:24:10.763Z,1518384250.763 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-02-11T21:24:10.874Z,1518384250.874 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-02-11T21:24:10.874Z,1518384250.874 [DropWeight] Hardware Fault, FailCount= 1
2018-02-11T21:24:10.874Z,1518384250.874 [DropWeight](ERROR): Hardware Fault
2018-02-11T21:24:10.909Z,1518384250.909 [BPC1](INFO): Starting
2018-02-11T21:24:10.969Z,1518384250.969 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:10.970Z,1518384250.970 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:10.998Z,1518384250.998 [CBIT](INFO): Critical error at 20180211T212410
2018-02-11T21:24:10.000Z,1518384251.000 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-02-11T21:24:11.001Z,1518384251.001 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-02-11T21:24:11.235Z,1518384251.235 [BPC1](INFO): Starting
2018-02-11T21:24:11.276Z,1518384251.276 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:11.285Z,1518384251.285 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:11.613Z,1518384251.613 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-02-11T21:24:11.613Z,1518384251.613 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-02-11T21:24:11.613Z,1518384251.613 [BuoyancyServo] Communications Fault, FailCount= 1
2018-02-11T21:24:11.613Z,1518384251.613 [BuoyancyServo](ERROR): Communications Fault
2018-02-11T21:24:11.811Z,1518384251.811 [CBIT](INFO): Critical error at 20180211T212411
2018-02-11T21:24:11.813Z,1518384251.813 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-02-11T21:24:11.844Z,1518384251.844 [BPC1](INFO): Starting
2018-02-11T21:24:11.870Z,1518384251.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:11.871Z,1518384251.871 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:11.876Z,1518384251.876 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-11T21:24:11.892Z,1518384251.892 [BuoyancyServo](INFO): Powering down
2018-02-11T21:24:12.253Z,1518384252.253 [BPC1](INFO): Starting
2018-02-11T21:24:12.301Z,1518384252.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:12.302Z,1518384252.302 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:12.642Z,1518384252.642 [BPC1](INFO): Starting
2018-02-11T21:24:12.673Z,1518384252.673 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:12.674Z,1518384252.674 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:13.044Z,1518384253.044 [BPC1](INFO): Starting
2018-02-11T21:24:13.111Z,1518384253.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:13.129Z,1518384253.129 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:13.462Z,1518384253.462 [BPC1](INFO): Starting
2018-02-11T21:24:13.581Z,1518384253.581 [Aanderaa_O2](INFO): Powering down
2018-02-11T21:24:13.692Z,1518384253.692 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:13.693Z,1518384253.693 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:24:13.854Z,1518384253.854 [BPC1](INFO): Starting
2018-02-11T21:24:13.950Z,1518384253.950 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-02-11T21:24:13.950Z,1518384253.950 [BuoyancyServo] No Fault, FailCount= 1
2018-02-11T21:24:14.250Z,1518384254.250 [BPC1](INFO): Starting
2018-02-11T21:24:14.325Z,1518384254.325 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:24:14.446Z,1518384254.446 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-11T21:24:14.676Z,1518384254.676 [BPC1](INFO): Starting
2018-02-11T21:24:15.051Z,1518384255.051 [BPC1](INFO): Starting
2018-02-11T21:24:15.241Z,1518384255.241 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-11T21:24:15.324Z,1518384255.324 [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-02-11T21:24:15.370Z,1518384255.370 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-02-11T21:24:15.461Z,1518384255.461 [BPC1](INFO): Starting
2018-02-11T21:24:15.781Z,1518384255.781 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-11T21:24:15.781Z,1518384255.781 [CTD_Seabird](INFO): Powering down
2018-02-11T21:24:15.859Z,1518384255.859 [BPC1](INFO): Starting
2018-02-11T21:24:16.250Z,1518384256.250 [BPC1](INFO): Starting
2018-02-11T21:24:16.649Z,1518384256.649 [BPC1](INFO): Starting
2018-02-11T21:24:17.050Z,1518384257.050 [BPC1](INFO): Starting
2018-02-11T21:24:17.420Z,1518384257.420 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-02-11T21:24:17.451Z,1518384257.451 [BPC1](INFO): Starting
2018-02-11T21:24:17.854Z,1518384257.854 [BPC1](INFO): Starting
2018-02-11T21:24:18.251Z,1518384258.251 [BPC1](INFO): Starting
2018-02-11T21:24:18.621Z,1518384258.621 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-02-11T21:24:18.622Z,1518384258.622 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-02-11T21:24:18.623Z,1518384258.623 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-02-11T21:24:18.623Z,1518384258.623 [AcousticModem_Benthos_ATM900](DEBUG): Mar  4 2000 11:49:32 
2018-02-11T21:24:18.652Z,1518384258.652 [BPC1](INFO): Starting
2018-02-11T21:24:19.021Z,1518384259.021 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-02-11T21:24:19.050Z,1518384259.050 [BPC1](INFO): Starting
2018-02-11T21:24:19.463Z,1518384259.463 [BPC1](INFO): Starting
2018-02-11T21:24:19.820Z,1518384259.820 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-02-11T21:24:19.851Z,1518384259.851 [BPC1](INFO): Starting
2018-02-11T21:24:20.220Z,1518384260.220 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-02-11T21:24:20.221Z,1518384260.221 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-02-11T21:24:20.221Z,1518384260.221 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-02-11T21:24:20.250Z,1518384260.250 [BPC1](INFO): Starting
2018-02-11T21:24:20.621Z,1518384260.621 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-02-11T21:24:20.659Z,1518384260.659 [BPC1](INFO): Starting
2018-02-11T21:24:21.020Z,1518384261.020 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-02-11T21:24:21.021Z,1518384261.021 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-02-11T21:24:21.050Z,1518384261.050 [BPC1](INFO): Starting
2018-02-11T21:24:21.051Z,1518384261.051 [BPC1](INFO): Initialized.
2018-02-11T21:24:21.421Z,1518384261.421 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-02-11T21:24:21.451Z,1518384261.451 [BPC1](INFO): Runnable
2018-02-11T21:24:21.465Z,1518384261.465 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:24:21.477Z,1518384261.477 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:24:21.851Z,1518384261.851 [BPC1](INFO): Runnable
2018-02-11T21:24:22.262Z,1518384262.262 [BPC1](INFO): Runnable
2018-02-11T21:24:22.652Z,1518384262.652 [BPC1](INFO): Runnable
2018-02-11T21:24:23.051Z,1518384263.051 [BPC1](INFO): Runnable
2018-02-11T21:24:23.466Z,1518384263.466 [BPC1](INFO): Runnable
2018-02-11T21:24:23.467Z,1518384263.467 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:24:23.467Z,1518384263.467 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:24:23.851Z,1518384263.851 [BPC1](INFO): Runnable
2018-02-11T21:24:24.250Z,1518384264.250 [BPC1](INFO): Runnable
2018-02-11T21:24:24.651Z,1518384264.651 [BPC1](INFO): Runnable
2018-02-11T21:24:25.051Z,1518384265.051 [BPC1](INFO): Runnable
2018-02-11T21:24:25.450Z,1518384265.450 [BPC1](INFO): Runnable
2018-02-11T21:24:25.870Z,1518384265.870 [BPC1](INFO): Runnable
2018-02-11T21:24:26.251Z,1518384266.251 [BPC1](INFO): Runnable
2018-02-11T21:24:26.651Z,1518384266.651 [BPC1](INFO): Runnable
2018-02-11T21:24:27.050Z,1518384267.050 [BPC1](INFO): Runnable
2018-02-11T21:24:27.451Z,1518384267.451 [BPC1](INFO): Runnable
2018-02-11T21:24:27.451Z,1518384267.451 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:24:27.467Z,1518384267.467 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:24:27.468Z,1518384267.468 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:24:27.492Z,1518384267.492 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:24:27.863Z,1518384267.863 [BPC1](INFO): Runnable
2018-02-11T21:24:27.877Z,1518384267.877 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:24:27.889Z,1518384267.889 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:24:28.249Z,1518384268.249 [BPC1](INFO): Runnable
2018-02-11T21:24:28.651Z,1518384268.651 [BPC1](INFO): Runnable
2018-02-11T21:24:29.051Z,1518384269.051 [BPC1](INFO): Runnable
2018-02-11T21:24:29.452Z,1518384269.452 [BPC1](INFO): Runnable
2018-02-11T21:24:29.850Z,1518384269.850 [BPC1](INFO): Runnable
2018-02-11T21:24:30.262Z,1518384270.262 [BPC1](INFO): Runnable
2018-02-11T21:24:30.650Z,1518384270.650 [BPC1](INFO): Runnable
2018-02-11T21:24:31.050Z,1518384271.050 [BPC1](INFO): Runnable
2018-02-11T21:24:31.450Z,1518384271.450 [BPC1](INFO): Runnable
2018-02-11T21:24:31.850Z,1518384271.850 [BPC1](INFO): Runnable
2018-02-11T21:24:32.250Z,1518384272.250 [BPC1](INFO): Runnable
2018-02-11T21:24:32.660Z,1518384272.660 [BPC1](INFO): Runnable
2018-02-11T21:24:33.050Z,1518384273.050 [BPC1](INFO): Runnable
2018-02-11T21:24:33.450Z,1518384273.450 [BPC1](INFO): Runnable
2018-02-11T21:24:33.863Z,1518384273.863 [BPC1](INFO): Runnable
2018-02-11T21:24:34.251Z,1518384274.251 [BPC1](INFO): Runnable
2018-02-11T21:24:34.654Z,1518384274.654 [BPC1](INFO): Runnable
2018-02-11T21:24:35.049Z,1518384275.049 [BPC1](INFO): Runnable
2018-02-11T21:24:35.450Z,1518384275.450 [BPC1](INFO): Runnable
2018-02-11T21:24:35.450Z,1518384275.450 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:24:35.850Z,1518384275.850 [BPC1](INFO): Runnable
2018-02-11T21:24:35.851Z,1518384275.851 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:24:36.226Z,1518384276.226 [NAL9602](INFO): Powering up NAL9602
2018-02-11T21:24:36.252Z,1518384276.252 [BPC1](INFO): Runnable
2018-02-11T21:24:36.653Z,1518384276.653 [BPC1](INFO): Runnable
2018-02-11T21:24:37.059Z,1518384277.059 [BPC1](INFO): Runnable
2018-02-11T21:24:37.458Z,1518384277.458 [BPC1](INFO): Runnable
2018-02-11T21:24:37.851Z,1518384277.851 [BPC1](INFO): Runnable
2018-02-11T21:24:38.267Z,1518384278.267 [BPC1](INFO): Runnable
2018-02-11T21:24:38.652Z,1518384278.652 [BPC1](INFO): Runnable
2018-02-11T21:24:39.051Z,1518384279.051 [BPC1](INFO): Runnable
2018-02-11T21:24:39.464Z,1518384279.464 [BPC1](INFO): Runnable
2018-02-11T21:24:39.850Z,1518384279.850 [BPC1](INFO): Runnable
2018-02-11T21:24:40.250Z,1518384280.250 [BPC1](INFO): Runnable
2018-02-11T21:24:40.250Z,1518384280.250 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:24:40.270Z,1518384280.270 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:24:40.270Z,1518384280.270 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:24:40.294Z,1518384280.294 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:24:40.674Z,1518384280.674 [BPC1](INFO): Runnable
2018-02-11T21:24:40.692Z,1518384280.692 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:24:40.705Z,1518384280.705 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:24:41.059Z,1518384281.059 [BPC1](INFO): Runnable
2018-02-11T21:24:41.458Z,1518384281.458 [BPC1](INFO): Runnable
2018-02-11T21:24:41.873Z,1518384281.873 [BPC1](INFO): Runnable
2018-02-11T21:24:42.258Z,1518384282.258 [BPC1](INFO): Runnable
2018-02-11T21:24:42.660Z,1518384282.660 [BPC1](INFO): Runnable
2018-02-11T21:24:43.061Z,1518384283.061 [BPC1](INFO): Runnable
2018-02-11T21:24:43.450Z,1518384283.450 [BPC1](INFO): Runnable
2018-02-11T21:24:43.848Z,1518384283.848 [BPC1](INFO): Runnable
2018-02-11T21:24:44.251Z,1518384284.251 [BPC1](INFO): Runnable
2018-02-11T21:24:44.656Z,1518384284.656 [BPC1](INFO): Runnable
2018-02-11T21:24:45.059Z,1518384285.059 [BPC1](INFO): Runnable
2018-02-11T21:24:45.451Z,1518384285.451 [BPC1](INFO): Runnable
2018-02-11T21:24:45.855Z,1518384285.855 [BPC1](INFO): Runnable
2018-02-11T21:24:46.255Z,1518384286.255 [BPC1](INFO): Runnable
2018-02-11T21:24:46.662Z,1518384286.662 [BPC1](INFO): Runnable
2018-02-11T21:24:47.042Z,1518384287.042 [NAL9602](INFO): NAL9602 initialized
2018-02-11T21:24:47.067Z,1518384287.067 [BPC1](INFO): Runnable
2018-02-11T21:24:47.479Z,1518384287.479 [BPC1](INFO): Runnable
2018-02-11T21:24:47.870Z,1518384287.870 [BPC1](INFO): Runnable
2018-02-11T21:24:48.272Z,1518384288.272 [BPC1](INFO): Runnable
2018-02-11T21:24:48.272Z,1518384288.272 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:24:48.272Z,1518384288.272 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:24:48.694Z,1518384288.694 [BPC1](INFO): Runnable
2018-02-11T21:24:49.057Z,1518384289.057 [BPC1](INFO): Runnable
2018-02-11T21:24:49.470Z,1518384289.470 [BPC1](INFO): Runnable
2018-02-11T21:24:49.867Z,1518384289.867 [BPC1](INFO): Runnable
2018-02-11T21:24:50.267Z,1518384290.267 [BPC1](INFO): Runnable
2018-02-11T21:24:50.666Z,1518384290.666 [BPC1](INFO): Runnable
2018-02-11T21:24:51.059Z,1518384291.059 [BPC1](INFO): Runnable
2018-02-11T21:24:51.451Z,1518384291.451 [BPC1](INFO): Runnable
2018-02-11T21:24:51.851Z,1518384291.851 [BPC1](INFO): Runnable
2018-02-11T21:24:52.250Z,1518384292.250 [BPC1](INFO): Runnable
2018-02-11T21:24:52.651Z,1518384292.651 [BPC1](INFO): Runnable
2018-02-11T21:24:52.651Z,1518384292.651 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:24:52.658Z,1518384292.658 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:24:53.057Z,1518384293.057 [BPC1](INFO): Runnable
2018-02-11T21:24:53.057Z,1518384293.057 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:24:53.064Z,1518384293.064 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:24:53.105Z,1518384293.105 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:24:53.451Z,1518384293.451 [BPC1](INFO): Runnable
2018-02-11T21:24:53.465Z,1518384293.465 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:24:53.855Z,1518384293.855 [BPC1](INFO): Runnable
2018-02-11T21:24:54.258Z,1518384294.258 [BPC1](INFO): Runnable
2018-02-11T21:24:54.660Z,1518384294.660 [BPC1](INFO): Runnable
2018-02-11T21:24:55.057Z,1518384295.057 [BPC1](INFO): Runnable
2018-02-11T21:24:55.458Z,1518384295.458 [BPC1](INFO): Runnable
2018-02-11T21:24:55.860Z,1518384295.860 [BPC1](INFO): Runnable
2018-02-11T21:24:56.259Z,1518384296.259 [BPC1](INFO): Runnable
2018-02-11T21:24:56.651Z,1518384296.651 [BPC1](INFO): Runnable
2018-02-11T21:24:57.051Z,1518384297.051 [BPC1](INFO): Runnable
2018-02-11T21:24:57.452Z,1518384297.452 [BPC1](INFO): Runnable
2018-02-11T21:24:57.852Z,1518384297.852 [BPC1](INFO): Runnable
2018-02-11T21:24:58.251Z,1518384298.251 [BPC1](INFO): Runnable
2018-02-11T21:24:58.666Z,1518384298.666 [BPC1](INFO): Runnable
2018-02-11T21:24:59.062Z,1518384299.062 [BPC1](INFO): Runnable
2018-02-11T21:24:59.455Z,1518384299.455 [BPC1](INFO): Runnable
2018-02-11T21:24:59.851Z,1518384299.851 [BPC1](INFO): Runnable
2018-02-11T21:24:59.851Z,1518384299.851 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:25:00.254Z,1518384300.254 [BPC1](INFO): Runnable
2018-02-11T21:25:00.255Z,1518384300.255 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:25:00.658Z,1518384300.658 [BPC1](INFO): Runnable
2018-02-11T21:25:01.057Z,1518384301.057 [BPC1](INFO): Runnable
2018-02-11T21:25:01.462Z,1518384301.462 [BPC1](INFO): Runnable
2018-02-11T21:25:01.858Z,1518384301.858 [BPC1](INFO): Runnable
2018-02-11T21:25:01.916Z,1518384301.916 [SBIT](IMPORTANT): Beginning Startup BIT
2018-02-11T21:25:01.928Z,1518384301.928 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-11T21:25:02.258Z,1518384302.258 [BPC1](INFO): Runnable
2018-02-11T21:25:02.668Z,1518384302.668 [BPC1](INFO): Runnable
2018-02-11T21:25:03.070Z,1518384303.070 [BPC1](INFO): Runnable
2018-02-11T21:25:03.450Z,1518384303.450 [BPC1](INFO): Runnable
2018-02-11T21:25:03.851Z,1518384303.851 [BPC1](INFO): Runnable
2018-02-11T21:25:03.851Z,1518384303.851 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:25:03.893Z,1518384303.893 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:25:04.447Z,1518384304.447 [BPC1](INFO): Runnable
2018-02-11T21:25:04.476Z,1518384304.476 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:25:04.476Z,1518384304.476 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:25:04.491Z,1518384304.491 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:25:04.842Z,1518384304.842 [BPC1](INFO): Runnable
2018-02-11T21:25:04.853Z,1518384304.853 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:25:05.219Z,1518384305.219 [BPC1](INFO): Runnable
2018-02-11T21:25:05.631Z,1518384305.631 [BPC1](INFO): Runnable
2018-02-11T21:25:06.031Z,1518384306.031 [BPC1](INFO): Runnable
2018-02-11T21:25:06.431Z,1518384306.431 [BPC1](INFO): Runnable
2018-02-11T21:25:06.834Z,1518384306.834 [BPC1](INFO): Runnable
2018-02-11T21:25:07.228Z,1518384307.228 [BPC1](INFO): Runnable
2018-02-11T21:25:07.631Z,1518384307.631 [BPC1](INFO): Runnable
2018-02-11T21:25:08.031Z,1518384308.031 [BPC1](INFO): Runnable
2018-02-11T21:25:08.425Z,1518384308.425 [BPC1](INFO): Runnable
2018-02-11T21:25:08.829Z,1518384308.829 [BPC1](INFO): Runnable
2018-02-11T21:25:09.235Z,1518384309.235 [BPC1](INFO): Runnable
2018-02-11T21:25:09.620Z,1518384309.620 [BPC1](INFO): Runnable
2018-02-11T21:25:10.019Z,1518384310.019 [BPC1](INFO): Runnable
2018-02-11T21:25:10.432Z,1518384310.432 [BPC1](INFO): Runnable
2018-02-11T21:25:10.834Z,1518384310.834 [BPC1](INFO): Runnable
2018-02-11T21:25:11.227Z,1518384311.227 [BPC1](INFO): Runnable
2018-02-11T21:25:11.227Z,1518384311.227 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:25:11.648Z,1518384311.648 [BPC1](INFO): Runnable
2018-02-11T21:25:12.027Z,1518384312.027 [BPC1](INFO): Runnable
2018-02-11T21:25:12.027Z,1518384312.027 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:25:12.427Z,1518384312.427 [BPC1](INFO): Runnable
2018-02-11T21:25:12.827Z,1518384312.827 [BPC1](INFO): Runnable
2018-02-11T21:25:12.919Z,1518384312.919 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007790
CHAN A1 (24V): -0.011378
CHAN A2 (12V): -0.005450
CHAN A3 (5V): -0.002599
CHAN B0 (3.3V): -0.001026
CHAN B1 (3.15aV): -0.001568
CHAN B2 (3.15bV): -0.000604
CHAN B3 (GND): -0.000478
OPEN: 0.004450
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-11T21:25:13.231Z,1518384313.231 [BPC1](INFO): Runnable
2018-02-11T21:25:13.627Z,1518384313.627 [BPC1](INFO): Runnable
2018-02-11T21:25:14.036Z,1518384314.036 [BPC1](INFO): Runnable
2018-02-11T21:25:14.433Z,1518384314.433 [BPC1](INFO): Runnable
2018-02-11T21:25:14.826Z,1518384314.826 [BPC1](INFO): Runnable
2018-02-11T21:25:15.229Z,1518384315.229 [BPC1](INFO): Runnable
2018-02-11T21:25:15.630Z,1518384315.630 [BPC1](INFO): Runnable
2018-02-11T21:25:15.631Z,1518384315.631 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:25:15.638Z,1518384315.638 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:25:16.035Z,1518384316.035 [BPC1](INFO): Runnable
2018-02-11T21:25:16.049Z,1518384316.049 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:25:16.429Z,1518384316.429 [BPC1](INFO): Runnable
2018-02-11T21:25:16.430Z,1518384316.430 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:25:16.437Z,1518384316.437 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:25:16.437Z,1518384316.437 [BPC1](DEBUG): Received data from all battery sticks
2018-02-11T21:25:16.826Z,1518384316.826 [BPC1](INFO): Stop
2018-02-11T21:25:16.826Z,1518384316.826 [BPC1](INFO): uninitialize
2018-02-11T21:25:17.235Z,1518384317.235 [BPC1](INFO): Stopping
2018-02-11T21:25:17.815Z,1518384317.815 [BPC1](INFO): Stopped
2018-02-11T21:25:18.215Z,1518384318.215 [BPC1](INFO): Stopped
2018-02-11T21:25:18.619Z,1518384318.619 [BPC1](INFO): Stopped
2018-02-11T21:25:19.015Z,1518384319.015 [BPC1](INFO): Stopped
2018-02-11T21:25:19.418Z,1518384319.418 [BPC1](INFO): Stopped
2018-02-11T21:25:19.820Z,1518384319.820 [BPC1](INFO): Stopped
2018-02-11T21:25:20.224Z,1518384320.224 [BPC1](INFO): Stopped
2018-02-11T21:25:20.623Z,1518384320.623 [BPC1](INFO): Stopped
2018-02-11T21:25:21.023Z,1518384321.023 [BPC1](INFO): Stopped
2018-02-11T21:25:21.429Z,1518384321.429 [BPC1](INFO): Stopped
2018-02-11T21:25:21.825Z,1518384321.825 [BPC1](INFO): Stopped
2018-02-11T21:25:22.225Z,1518384322.225 [BPC1](INFO): Stopped
2018-02-11T21:25:22.627Z,1518384322.627 [BPC1](INFO): Stopped
2018-02-11T21:25:23.026Z,1518384323.026 [BPC1](INFO): Stopped
2018-02-11T21:25:23.427Z,1518384323.427 [BPC1](INFO): Stopped
2018-02-11T21:25:23.825Z,1518384323.825 [BPC1](INFO): Stopped
2018-02-11T21:25:24.225Z,1518384324.225 [BPC1](INFO): Stopped
2018-02-11T21:25:24.622Z,1518384324.622 [BPC1](INFO): Stopped
2018-02-11T21:25:25.023Z,1518384325.023 [BPC1](INFO): Stopped
2018-02-11T21:25:25.396Z,1518384325.396 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:25:25.396Z,1518384325.396 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:25:25.444Z,1518384325.444 [BPC1](INFO): Stopped
2018-02-11T21:25:25.838Z,1518384325.838 [BPC1](INFO): Stopped
2018-02-11T21:25:26.215Z,1518384326.215 [BPC1](INFO): Stopped
2018-02-11T21:25:26.615Z,1518384326.615 [BPC1](INFO): Stopped
2018-02-11T21:25:27.019Z,1518384327.019 [BPC1](INFO): Stopped
2018-02-11T21:25:27.415Z,1518384327.415 [BPC1](INFO): Stopped
2018-02-11T21:25:27.823Z,1518384327.823 [BPC1](INFO): Stopped
2018-02-11T21:25:28.224Z,1518384328.224 [BPC1](INFO): Stopped
2018-02-11T21:25:28.624Z,1518384328.624 [BPC1](INFO): Stopped
2018-02-11T21:25:29.023Z,1518384329.023 [BPC1](INFO): Stopped
2018-02-11T21:25:29.423Z,1518384329.423 [BPC1](INFO): Stopped
2018-02-11T21:25:29.845Z,1518384329.845 [BPC1](INFO): Stopped
2018-02-11T21:25:30.224Z,1518384330.224 [BPC1](INFO): Stopped
2018-02-11T21:25:30.623Z,1518384330.623 [BPC1](INFO): Stopped
2018-02-11T21:25:31.028Z,1518384331.028 [BPC1](INFO): Stopped
2018-02-11T21:25:31.425Z,1518384331.425 [BPC1](INFO): Stopped
2018-02-11T21:25:31.818Z,1518384331.818 [BPC1](INFO): Stopped
2018-02-11T21:25:32.228Z,1518384332.228 [BPC1](INFO): Stopped
2018-02-11T21:25:32.624Z,1518384332.624 [BPC1](INFO): Stopped
2018-02-11T21:25:33.027Z,1518384333.027 [BPC1](INFO): Stopped
2018-02-11T21:25:33.425Z,1518384333.425 [BPC1](INFO): Stopped
2018-02-11T21:25:33.828Z,1518384333.828 [BPC1](INFO): Stopped
2018-02-11T21:25:34.228Z,1518384334.228 [BPC1](INFO): Stopped
2018-02-11T21:25:34.623Z,1518384334.623 [BPC1](INFO): Stopped
2018-02-11T21:25:35.016Z,1518384335.016 [BPC1](INFO): Stopped
2018-02-11T21:25:35.415Z,1518384335.415 [BPC1](INFO): Stopped
2018-02-11T21:25:35.815Z,1518384335.815 [BPC1](INFO): Stopped
2018-02-11T21:25:36.215Z,1518384336.215 [BPC1](INFO): Stopped
2018-02-11T21:25:36.623Z,1518384336.623 [BPC1](INFO): Stopped
2018-02-11T21:25:37.023Z,1518384337.023 [BPC1](INFO): Stopped
2018-02-11T21:25:37.422Z,1518384337.422 [BPC1](INFO): Stopped
2018-02-11T21:25:37.821Z,1518384337.821 [BPC1](INFO): Stopped
2018-02-11T21:25:38.222Z,1518384338.222 [BPC1](INFO): Stopped
2018-02-11T21:25:38.622Z,1518384338.622 [BPC1](INFO): Stopped
2018-02-11T21:25:39.023Z,1518384339.023 [BPC1](INFO): Stopped
2018-02-11T21:25:39.424Z,1518384339.424 [BPC1](INFO): Stopped
2018-02-11T21:25:39.825Z,1518384339.825 [BPC1](INFO): Stopped
2018-02-11T21:25:40.225Z,1518384340.225 [BPC1](INFO): Stopped
2018-02-11T21:25:40.591Z,1518384340.591 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:25:40.592Z,1518384340.592 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:25:40.631Z,1518384340.631 [BPC1](INFO): Stopped
2018-02-11T21:25:41.031Z,1518384341.031 [BPC1](INFO): Stopped
2018-02-11T21:25:41.424Z,1518384341.424 [BPC1](INFO): Stopped
2018-02-11T21:25:41.823Z,1518384341.823 [BPC1](INFO): Stopped
2018-02-11T21:25:42.226Z,1518384342.226 [BPC1](INFO): Stopped
2018-02-11T21:25:42.630Z,1518384342.630 [BPC1](INFO): Stopped
2018-02-11T21:25:43.024Z,1518384343.024 [BPC1](INFO): Stopped
2018-02-11T21:25:43.427Z,1518384343.427 [BPC1](INFO): Stopped
2018-02-11T21:25:43.823Z,1518384343.823 [BPC1](INFO): Stopped
2018-02-11T21:25:44.227Z,1518384344.227 [BPC1](INFO): Stopped
2018-02-11T21:25:44.725Z,1518384344.725 [BPC1](INFO): Stopped
2018-02-11T21:25:45.144Z,1518384345.144 [BPC1](INFO): Stopped
2018-02-11T21:25:45.540Z,1518384345.540 [BPC1](INFO): Stopped
2018-02-11T21:25:45.933Z,1518384345.933 [BPC1](INFO): Stopped
2018-02-11T21:25:46.333Z,1518384346.333 [BPC1](INFO): Stopped
2018-02-11T21:25:46.731Z,1518384346.731 [BPC1](INFO): Stopped
2018-02-11T21:25:47.129Z,1518384347.129 [BPC1](INFO): Stopped
2018-02-11T21:25:47.533Z,1518384347.533 [BPC1](INFO): Stopped
2018-02-11T21:25:47.938Z,1518384347.938 [BPC1](INFO): Stopped
2018-02-11T21:25:48.347Z,1518384348.347 [BPC1](INFO): Stopped
2018-02-11T21:25:48.738Z,1518384348.738 [BPC1](INFO): Stopped
2018-02-11T21:25:49.130Z,1518384349.130 [BPC1](INFO): Stopped
2018-02-11T21:25:49.535Z,1518384349.535 [BPC1](INFO): Stopped
2018-02-11T21:25:49.937Z,1518384349.937 [BPC1](INFO): Stopped
2018-02-11T21:25:50.333Z,1518384350.333 [BPC1](INFO): Stopped
2018-02-11T21:25:50.730Z,1518384350.730 [BPC1](INFO): Stopped
2018-02-11T21:25:51.133Z,1518384351.133 [BPC1](INFO): Stopped
2018-02-11T21:25:51.530Z,1518384351.530 [BPC1](INFO): Stopped
2018-02-11T21:25:51.930Z,1518384351.930 [BPC1](INFO): Stopped
2018-02-11T21:25:52.337Z,1518384352.337 [BPC1](INFO): Stopped
2018-02-11T21:25:52.731Z,1518384352.731 [BPC1](INFO): Stopped
2018-02-11T21:25:53.130Z,1518384353.130 [BPC1](INFO): Stopped
2018-02-11T21:25:53.533Z,1518384353.533 [BPC1](INFO): Stopped
2018-02-11T21:25:53.933Z,1518384353.933 [BPC1](INFO): Stopped
2018-02-11T21:25:54.330Z,1518384354.330 [BPC1](INFO): Stopped
2018-02-11T21:25:54.741Z,1518384354.741 [BPC1](INFO): Stopped
2018-02-11T21:25:55.122Z,1518384355.122 [BPC1](INFO): Stopped
2018-02-11T21:25:55.522Z,1518384355.522 [BPC1](INFO): Stopped
2018-02-11T21:25:55.922Z,1518384355.922 [BPC1](INFO): Stopped
2018-02-11T21:25:55.980Z,1518384355.980 [SBIT](IMPORTANT): SBIT PASSED
2018-02-11T21:25:56.326Z,1518384356.326 [BPC1](INFO): Stopped
2018-02-11T21:25:56.336Z,1518384356.336 [MissionManager](IMPORTANT): Started mission Startup
2018-02-11T21:25:56.353Z,1518384356.353 [Startup] Running Loop=1
2018-02-11T21:25:56.354Z,1518384356.354 [Startup](DEBUG): Aggregate::initialize Startup
2018-02-11T21:25:56.354Z,1518384356.354 [Startup:A.GoToSurface] Running Loop=1
2018-02-11T21:25:56.354Z,1518384356.354 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-02-11T21:25:56.354Z,1518384356.354 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-02-11T21:25:56.355Z,1518384356.355 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-02-11T21:25:56.355Z,1518384356.355 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-02-11T21:25:56.356Z,1518384356.356 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-02-11T21:25:56.356Z,1518384356.356 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-02-11T21:25:56.371Z,1518384356.371 [Startup:StartupSatComms] Running Loop=1
2018-02-11T21:25:56.371Z,1518384356.371 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-02-11T21:25:56.371Z,1518384356.371 [Startup:StartupSatComms:A] Running Loop=1
2018-02-11T21:25:56.730Z,1518384356.730 [BPC1](INFO): Stopped
2018-02-11T21:25:56.771Z,1518384356.771 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-02-11T21:25:57.126Z,1518384357.126 [BPC1](INFO): Stopped
2018-02-11T21:25:57.508Z,1518384357.508 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:25:57.509Z,1518384357.509 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:25:57.533Z,1518384357.533 [BPC1](INFO): Stopped
2018-02-11T21:25:57.942Z,1518384357.942 [BPC1](INFO): Stopped
2018-02-11T21:25:58.330Z,1518384358.330 [BPC1](INFO): Stopped
2018-02-11T21:25:58.730Z,1518384358.730 [BPC1](INFO): Stopped
2018-02-11T21:25:59.130Z,1518384359.130 [BPC1](INFO): Stopped
2018-02-11T21:25:59.534Z,1518384359.534 [BPC1](INFO): Stopped
2018-02-11T21:25:59.929Z,1518384359.929 [BPC1](INFO): Stopped
2018-02-11T21:26:00.330Z,1518384360.330 [BPC1](INFO): Stopped
2018-02-11T21:26:00.737Z,1518384360.737 [BPC1](INFO): Stopped
2018-02-11T21:26:01.130Z,1518384361.130 [BPC1](INFO): Stopped
2018-02-11T21:26:01.537Z,1518384361.537 [BPC1](INFO): Stopped
2018-02-11T21:26:01.943Z,1518384361.943 [BPC1](INFO): Stopped
2018-02-11T21:26:02.330Z,1518384362.330 [BPC1](INFO): Stopped
2018-02-11T21:26:02.722Z,1518384362.722 [BPC1](INFO): Stopped
2018-02-11T21:26:03.134Z,1518384363.134 [BPC1](INFO): Stopped
2018-02-11T21:26:03.522Z,1518384363.522 [BPC1](INFO): Stopped
2018-02-11T21:26:03.922Z,1518384363.922 [BPC1](INFO): Stopped
2018-02-11T21:26:04.323Z,1518384364.323 [BPC1](INFO): Stopped
2018-02-11T21:26:04.699Z,1518384364.699 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:26:04.699Z,1518384364.699 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:26:04.724Z,1518384364.724 [BPC1](INFO): Stopped
2018-02-11T21:26:05.133Z,1518384365.133 [BPC1](INFO): Stopped
2018-02-11T21:26:05.526Z,1518384365.526 [BPC1](INFO): Stopped
2018-02-11T21:26:05.930Z,1518384365.930 [BPC1](INFO): Stopped
2018-02-11T21:26:06.327Z,1518384366.327 [BPC1](INFO): Stopped
2018-02-11T21:26:06.728Z,1518384366.728 [BPC1](INFO): Stopped
2018-02-11T21:26:07.130Z,1518384367.130 [BPC1](INFO): Stopped
2018-02-11T21:26:07.531Z,1518384367.531 [BPC1](INFO): Stopped
2018-02-11T21:26:07.932Z,1518384367.932 [BPC1](INFO): Stopped
2018-02-11T21:26:08.330Z,1518384368.330 [BPC1](INFO): Stopped
2018-02-11T21:26:08.734Z,1518384368.734 [BPC1](INFO): Stopped
2018-02-11T21:26:09.142Z,1518384369.142 [BPC1](INFO): Stopped
2018-02-11T21:26:09.531Z,1518384369.531 [BPC1](INFO): Stopped
2018-02-11T21:26:09.935Z,1518384369.935 [BPC1](INFO): Stopped
2018-02-11T21:26:10.332Z,1518384370.332 [BPC1](INFO): Stopped
2018-02-11T21:26:10.731Z,1518384370.731 [BPC1](INFO): Stopped
2018-02-11T21:26:11.137Z,1518384371.137 [BPC1](INFO): Stopped
2018-02-11T21:26:11.531Z,1518384371.531 [BPC1](INFO): Stopped
2018-02-11T21:26:11.936Z,1518384371.936 [BPC1](INFO): Stopped
2018-02-11T21:26:12.339Z,1518384372.339 [BPC1](INFO): Stopped
2018-02-11T21:26:12.733Z,1518384372.733 [BPC1](INFO): Stopped
2018-02-11T21:26:13.122Z,1518384373.122 [BPC1](INFO): Stopped
2018-02-11T21:26:13.499Z,1518384373.499 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:26:13.499Z,1518384373.499 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:26:13.524Z,1518384373.524 [BPC1](INFO): Stopped
2018-02-11T21:26:13.933Z,1518384373.933 [BPC1](INFO): Stopped
2018-02-11T21:26:14.322Z,1518384374.322 [BPC1](INFO): Stopped
2018-02-11T21:26:14.728Z,1518384374.728 [BPC1](INFO): Stopped
2018-02-11T21:26:15.125Z,1518384375.125 [BPC1](INFO): Stopped
2018-02-11T21:26:15.529Z,1518384375.529 [BPC1](INFO): Stopped
2018-02-11T21:26:15.930Z,1518384375.930 [BPC1](INFO): Stopped
2018-02-11T21:26:16.328Z,1518384376.328 [BPC1](INFO): Stopped
2018-02-11T21:26:16.731Z,1518384376.731 [BPC1](INFO): Stopped
2018-02-11T21:26:17.126Z,1518384377.126 [BPC1](INFO): Stopped
2018-02-11T21:26:17.539Z,1518384377.539 [BPC1](INFO): Stopped
2018-02-11T21:26:17.940Z,1518384377.940 [BPC1](INFO): Stopped
2018-02-11T21:26:18.333Z,1518384378.333 [BPC1](INFO): Stopped
2018-02-11T21:26:18.730Z,1518384378.730 [BPC1](INFO): Stopped
2018-02-11T21:26:19.131Z,1518384379.131 [BPC1](INFO): Stopped
2018-02-11T21:26:19.533Z,1518384379.533 [BPC1](INFO): Stopped
2018-02-11T21:26:19.930Z,1518384379.930 [BPC1](INFO): Stopped
2018-02-11T21:26:20.331Z,1518384380.331 [BPC1](INFO): Stopped
2018-02-11T21:26:20.734Z,1518384380.734 [BPC1](INFO): Stopped
2018-02-11T21:26:21.129Z,1518384381.129 [BPC1](INFO): Stopped
2018-02-11T21:26:21.545Z,1518384381.545 [BPC1](INFO): Stopped
2018-02-11T21:26:21.940Z,1518384381.940 [BPC1](INFO): Stopped
2018-02-11T21:26:22.330Z,1518384382.330 [BPC1](INFO): Stopped
2018-02-11T21:26:22.730Z,1518384382.730 [BPC1](INFO): Stopped
2018-02-11T21:26:23.128Z,1518384383.128 [BPC1](INFO): Stopped
2018-02-11T21:26:23.530Z,1518384383.530 [BPC1](INFO): Stopped
2018-02-11T21:26:23.939Z,1518384383.939 [BPC1](INFO): Stopped
2018-02-11T21:26:24.329Z,1518384384.329 [BPC1](INFO): Stopped
2018-02-11T21:26:24.731Z,1518384384.731 [BPC1](INFO): Stopped
2018-02-11T21:26:25.130Z,1518384385.130 [BPC1](INFO): Stopped
2018-02-11T21:26:25.537Z,1518384385.537 [BPC1](INFO): Stopped
2018-02-11T21:26:25.928Z,1518384385.928 [BPC1](INFO): Stopped
2018-02-11T21:26:26.331Z,1518384386.331 [BPC1](INFO): Stopped
2018-02-11T21:26:26.733Z,1518384386.733 [BPC1](INFO): Stopped
2018-02-11T21:26:27.129Z,1518384387.129 [BPC1](INFO): Stopped
2018-02-11T21:26:27.531Z,1518384387.531 [BPC1](INFO): Stopped
2018-02-11T21:26:27.936Z,1518384387.936 [BPC1](INFO): Stopped
2018-02-11T21:26:28.330Z,1518384388.330 [BPC1](INFO): Stopped
2018-02-11T21:26:28.744Z,1518384388.744 [BPC1](INFO): Stopped
2018-02-11T21:26:29.133Z,1518384389.133 [BPC1](INFO): Stopped
2018-02-11T21:26:29.539Z,1518384389.539 [BPC1](INFO): Stopped
2018-02-11T21:26:29.934Z,1518384389.934 [BPC1](INFO): Stopped
2018-02-11T21:26:30.333Z,1518384390.333 [BPC1](INFO): Stopped
2018-02-11T21:26:30.729Z,1518384390.729 [BPC1](INFO): Stopped
2018-02-11T21:26:31.132Z,1518384391.132 [BPC1](INFO): Stopped
2018-02-11T21:26:31.537Z,1518384391.537 [BPC1](INFO): Stopped
2018-02-11T21:26:31.929Z,1518384391.929 [BPC1](INFO): Stopped
2018-02-11T21:26:32.333Z,1518384392.333 [BPC1](INFO): Stopped
2018-02-11T21:26:32.729Z,1518384392.729 [BPC1](INFO): Stopped
2018-02-11T21:26:33.099Z,1518384393.099 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:26:33.099Z,1518384393.099 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:26:33.129Z,1518384393.129 [BPC1](INFO): Stopped
2018-02-11T21:26:33.545Z,1518384393.545 [BPC1](INFO): Stopped
2018-02-11T21:26:33.922Z,1518384393.922 [BPC1](INFO): Stopped
2018-02-11T21:26:34.322Z,1518384394.322 [BPC1](INFO): Stopped
2018-02-11T21:26:34.727Z,1518384394.727 [BPC1](INFO): Stopped
2018-02-11T21:26:35.133Z,1518384395.133 [BPC1](INFO): Stopped
2018-02-11T21:26:35.528Z,1518384395.528 [BPC1](INFO): Stopped
2018-02-11T21:26:35.933Z,1518384395.933 [BPC1](INFO): Stopped
2018-02-11T21:26:36.333Z,1518384396.333 [BPC1](INFO): Stopped
2018-02-11T21:26:36.729Z,1518384396.729 [BPC1](INFO): Stopped
2018-02-11T21:26:37.130Z,1518384397.130 [BPC1](INFO): Stopped
2018-02-11T21:26:37.537Z,1518384397.537 [BPC1](INFO): Stopped
2018-02-11T21:26:37.929Z,1518384397.929 [BPC1](INFO): Stopped
2018-02-11T21:26:38.331Z,1518384398.331 [BPC1](INFO): Stopped
2018-02-11T21:26:38.729Z,1518384398.729 [BPC1](INFO): Stopped
2018-02-11T21:26:39.129Z,1518384399.129 [BPC1](INFO): Stopped
2018-02-11T21:26:39.534Z,1518384399.534 [BPC1](INFO): Stopped
2018-02-11T21:26:39.933Z,1518384399.933 [BPC1](INFO): Stopped
2018-02-11T21:26:40.329Z,1518384400.329 [BPC1](INFO): Stopped
2018-02-11T21:26:40.737Z,1518384400.737 [BPC1](INFO): Stopped
2018-02-11T21:26:41.132Z,1518384401.132 [BPC1](INFO): Stopped
2018-02-11T21:26:41.534Z,1518384401.534 [BPC1](INFO): Stopped
2018-02-11T21:26:41.934Z,1518384401.934 [BPC1](INFO): Stopped
2018-02-11T21:26:42.328Z,1518384402.328 [BPC1](INFO): Stopped
2018-02-11T21:26:42.730Z,1518384402.730 [BPC1](INFO): Stopped
2018-02-11T21:26:43.134Z,1518384403.134 [BPC1](INFO): Stopped
2018-02-11T21:26:43.533Z,1518384403.533 [BPC1](INFO): Stopped
2018-02-11T21:26:43.929Z,1518384403.929 [BPC1](INFO): Stopped
2018-02-11T21:26:44.333Z,1518384404.333 [BPC1](INFO): Stopped
2018-02-11T21:26:44.730Z,1518384404.730 [BPC1](INFO): Stopped
2018-02-11T21:26:45.133Z,1518384405.133 [BPC1](INFO): Stopped
2018-02-11T21:26:45.531Z,1518384405.531 [BPC1](INFO): Stopped
2018-02-11T21:26:45.930Z,1518384405.930 [BPC1](INFO): Stopped
2018-02-11T21:26:46.333Z,1518384406.333 [BPC1](INFO): Stopped
2018-02-11T21:26:46.741Z,1518384406.741 [BPC1](INFO): Stopped
2018-02-11T21:26:47.130Z,1518384407.130 [BPC1](INFO): Stopped
2018-02-11T21:26:47.532Z,1518384407.532 [BPC1](INFO): Stopped
2018-02-11T21:26:47.931Z,1518384407.931 [BPC1](INFO): Stopped
2018-02-11T21:26:48.331Z,1518384408.331 [BPC1](INFO): Stopped
2018-02-11T21:26:48.733Z,1518384408.733 [BPC1](INFO): Stopped
2018-02-11T21:26:49.131Z,1518384409.131 [BPC1](INFO): Stopped
2018-02-11T21:26:49.535Z,1518384409.535 [BPC1](INFO): Stopped
2018-02-11T21:26:49.923Z,1518384409.923 [BPC1](INFO): Stopped
2018-02-11T21:26:50.320Z,1518384410.320 [BPC1](INFO): Stopped
2018-02-11T21:26:50.727Z,1518384410.727 [BPC1](INFO): Stopped
2018-02-11T21:26:51.103Z,1518384411.103 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:26:51.103Z,1518384411.103 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:26:51.127Z,1518384411.127 [BPC1](INFO): Stopped
2018-02-11T21:26:51.537Z,1518384411.537 [BPC1](INFO): Stopped
2018-02-11T21:26:51.928Z,1518384411.928 [BPC1](INFO): Stopped
2018-02-11T21:26:52.329Z,1518384412.329 [BPC1](INFO): Stopped
2018-02-11T21:26:52.729Z,1518384412.729 [BPC1](INFO): Stopped
2018-02-11T21:26:53.130Z,1518384413.130 [BPC1](INFO): Stopped
2018-02-11T21:26:53.529Z,1518384413.529 [BPC1](INFO): Stopped
2018-02-11T21:26:53.929Z,1518384413.929 [BPC1](INFO): Stopped
2018-02-11T21:26:54.331Z,1518384414.331 [BPC1](INFO): Stopped
2018-02-11T21:26:54.733Z,1518384414.733 [BPC1](INFO): Stopped
2018-02-11T21:26:55.130Z,1518384415.130 [BPC1](INFO): Stopped
2018-02-11T21:26:55.540Z,1518384415.540 [BPC1](INFO): Stopped
2018-02-11T21:26:55.929Z,1518384415.929 [BPC1](INFO): Stopped
2018-02-11T21:26:56.331Z,1518384416.331 [BPC1](INFO): Stopped
2018-02-11T21:26:56.729Z,1518384416.729 [BPC1](INFO): Stopped
2018-02-11T21:26:56.763Z,1518384416.763 [Startup:StartupSatComms:A](INFO): Timed out from 2018-02-11T21:25:56.4Z
2018-02-11T21:26:56.763Z,1518384416.763 [Startup:StartupSatComms:A] Stopped
2018-02-11T21:26:56.763Z,1518384416.763 [Startup:StartupSatComms:B] Running Loop=1
2018-02-11T21:26:57.130Z,1518384417.130 [BPC1](INFO): Stopped
2018-02-11T21:26:57.146Z,1518384417.146 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-02-11T21:26:57.519Z,1518384417.519 [BPC1](INFO): Stopped
2018-02-11T21:26:57.922Z,1518384417.922 [BPC1](INFO): Stopped
2018-02-11T21:26:58.322Z,1518384418.322 [BPC1](INFO): Stopped
2018-02-11T21:26:58.723Z,1518384418.723 [BPC1](INFO): Stopped
2018-02-11T21:26:59.128Z,1518384419.128 [BPC1](INFO): Stopped
2018-02-11T21:26:59.511Z,1518384419.511 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:26:59.511Z,1518384419.511 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:26:59.536Z,1518384419.536 [BPC1](INFO): Stopped
2018-02-11T21:26:59.933Z,1518384419.933 [BPC1](INFO): Stopped
2018-02-11T21:27:00.331Z,1518384420.331 [BPC1](INFO): Stopped
2018-02-11T21:27:00.729Z,1518384420.729 [BPC1](INFO): Stopped
2018-02-11T21:27:01.130Z,1518384421.130 [BPC1](INFO): Stopped
2018-02-11T21:27:01.530Z,1518384421.530 [BPC1](INFO): Stopped
2018-02-11T21:27:01.930Z,1518384421.930 [BPC1](INFO): Stopped
2018-02-11T21:27:02.330Z,1518384422.330 [BPC1](INFO): Stopped
2018-02-11T21:27:02.734Z,1518384422.734 [BPC1](INFO): Stopped
2018-02-11T21:27:03.128Z,1518384423.128 [BPC1](INFO): Stopped
2018-02-11T21:27:03.530Z,1518384423.530 [BPC1](INFO): Stopped
2018-02-11T21:27:03.942Z,1518384423.942 [BPC1](INFO): Stopped
2018-02-11T21:27:04.332Z,1518384424.332 [BPC1](INFO): Stopped
2018-02-11T21:27:04.730Z,1518384424.730 [BPC1](INFO): Stopped
2018-02-11T21:27:05.137Z,1518384425.137 [BPC1](INFO): Stopped
2018-02-11T21:27:05.529Z,1518384425.529 [BPC1](INFO): Stopped
2018-02-11T21:27:05.929Z,1518384425.929 [BPC1](INFO): Stopped
2018-02-11T21:27:06.336Z,1518384426.336 [BPC1](INFO): Stopped
2018-02-11T21:27:06.734Z,1518384426.734 [BPC1](INFO): Stopped
2018-02-11T21:27:07.127Z,1518384427.127 [BPC1](INFO): Stopped
2018-02-11T21:27:07.529Z,1518384427.529 [BPC1](INFO): Stopped
2018-02-11T21:27:07.933Z,1518384427.933 [BPC1](INFO): Stopped
2018-02-11T21:27:08.299Z,1518384428.299 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:27:08.300Z,1518384428.300 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:27:08.332Z,1518384428.332 [BPC1](INFO): Stopped
2018-02-11T21:27:08.745Z,1518384428.745 [BPC1](INFO): Stopped
2018-02-11T21:27:09.131Z,1518384429.131 [BPC1](INFO): Stopped
2018-02-11T21:27:09.530Z,1518384429.530 [BPC1](INFO): Stopped
2018-02-11T21:27:09.556Z,1518384429.556 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-11T21:27:09.557Z,1518384429.557 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-02-11T21:27:09.557Z,1518384429.557 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-02-11T21:27:09.558Z,1518384429.558 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-11T21:27:09.559Z,1518384429.559 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-02-11T21:27:09.559Z,1518384429.559 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-11T21:27:09.570Z,1518384429.570 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-02-11T21:27:09.571Z,1518384429.571 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-11T21:27:09.938Z,1518384429.938 [BPC1](INFO): Stopped
2018-02-11T21:27:09.956Z,1518384429.956 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-02-11T21:27:09.956Z,1518384429.956 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-02-11T21:27:09.957Z,1518384429.957 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-11T21:27:09.957Z,1518384429.957 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-02-11T21:27:10.330Z,1518384430.330 [BPC1](INFO): Stopped
2018-02-11T21:27:10.364Z,1518384430.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-02-11T21:27:10.364Z,1518384430.364 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:27:10.365Z,1518384430.365 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:27:10.365Z,1518384430.365 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-11T21:27:10.365Z,1518384430.365 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:27:10.366Z,1518384430.366 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:27:10.747Z,1518384430.747 [BPC1](INFO): Stopped
2018-02-11T21:27:10.755Z,1518384430.755 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-02-11T21:27:13.866Z,1518384433.866 [BPC1](INFO): Stopped
2018-02-11T21:27:13.874Z,1518384433.874 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s.
2018-02-11T21:27:13.998Z,1518384433.998 [BPC1](INFO): Stopped
2018-02-11T21:27:14.012Z,1518384434.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-02-11T21:27:14.327Z,1518384434.327 [BPC1](INFO): Stopped
2018-02-11T21:27:14.335Z,1518384434.335 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-02-11T21:27:14.697Z,1518384434.697 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003879
2018-02-11T21:27:14.737Z,1518384434.737 [BPC1](INFO): Stopped
2018-02-11T21:27:14.745Z,1518384434.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-02-11T21:27:15.834Z,1518384435.834 [BPC1](INFO): Stopped
2018-02-11T21:27:15.842Z,1518384435.842 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s.
2018-02-11T21:27:15.961Z,1518384435.961 [BPC1](INFO): Stopped
2018-02-11T21:27:15.987Z,1518384435.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-02-11T21:27:19.014Z,1518384439.014 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:27:19.014Z,1518384439.014 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:27:19.040Z,1518384439.040 [BPC1](INFO): Stopped
2018-02-11T21:27:19.048Z,1518384439.048 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.5 s old, using for 20.0 s.
2018-02-11T21:27:19.118Z,1518384439.118 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180211T211327/Courier0008.lzma
2018-02-11T21:27:19.189Z,1518384439.189 [BPC1](INFO): Stopped
2018-02-11T21:27:19.207Z,1518384439.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2018-02-11T21:27:19.559Z,1518384439.559 [BPC1](INFO): Stopped
2018-02-11T21:27:19.567Z,1518384439.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-02-11T21:27:19.918Z,1518384439.918 [DataOverHttps](INFO): Moved sent file to Logs/20180211T211327/Courier0008.lzma.bak
2018-02-11T21:27:19.918Z,1518384439.918 [DataOverHttps](INFO): SBD MOMSN=7852883
2018-02-11T21:27:19.970Z,1518384439.970 [BPC1](INFO): Stopped
2018-02-11T21:27:20.006Z,1518384440.006 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2018-02-11T21:27:21.145Z,1518384441.145 [BPC1](INFO): Stopped
2018-02-11T21:27:21.159Z,1518384441.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-02-11T21:27:21.277Z,1518384441.277 [BPC1](INFO): Stopped
2018-02-11T21:27:21.303Z,1518384441.303 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2018-02-11T21:27:24.329Z,1518384444.329 [BPC1](INFO): Stopped
2018-02-11T21:27:24.361Z,1518384444.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-02-11T21:27:24.453Z,1518384444.453 [BPC1](INFO): Stopped
2018-02-11T21:27:24.467Z,1518384444.467 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2018-02-11T21:27:24.826Z,1518384444.826 [BPC1](INFO): Stopped
2018-02-11T21:27:24.834Z,1518384444.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2018-02-11T21:27:25.226Z,1518384445.226 [BPC1](INFO): Stopped
2018-02-11T21:27:25.234Z,1518384445.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2018-02-11T21:27:25.603Z,1518384445.603 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:27:25.604Z,1518384445.604 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:27:25.629Z,1518384445.629 [BPC1](INFO): Stopped
2018-02-11T21:27:25.637Z,1518384445.637 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-02-11T21:27:26.034Z,1518384446.034 [BPC1](INFO): Stopped
2018-02-11T21:27:26.042Z,1518384446.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2018-02-11T21:27:29.130Z,1518384449.130 [BPC1](INFO): Stopped
2018-02-11T21:27:29.140Z,1518384449.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-02-11T21:27:29.191Z,1518384449.191 [DataOverHttps](INFO): Sending 330 bytes from file Logs/20180211T212400/Courier0000.lzma
2018-02-11T21:27:29.253Z,1518384449.253 [BPC1](INFO): Stopped
2018-02-11T21:27:29.280Z,1518384449.280 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2018-02-11T21:27:29.610Z,1518384449.610 [BPC1](INFO): Stopped
2018-02-11T21:27:29.982Z,1518384449.982 [DataOverHttps](INFO): Moved sent file to Logs/20180211T212400/Courier0000.lzma.bak
2018-02-11T21:27:29.982Z,1518384449.982 [DataOverHttps](INFO): SBD MOMSN=7852885
2018-02-11T21:27:30.049Z,1518384450.049 [BPC1](INFO): Stopped
2018-02-11T21:27:31.844Z,1518384451.844 [BPC1](INFO): Stopped
2018-02-11T21:27:31.974Z,1518384451.974 [BPC1](INFO): Stopped
2018-02-11T21:27:35.503Z,1518384455.503 [BPC1](INFO): Stopped
2018-02-11T21:27:35.777Z,1518384455.777 [BPC1](INFO): Stopped
2018-02-11T21:27:36.172Z,1518384456.172 [BPC1](INFO): Stopped
2018-02-11T21:27:36.529Z,1518384456.529 [BPC1](INFO): Stopped
2018-02-11T21:27:36.921Z,1518384456.921 [BPC1](INFO): Stopped
2018-02-11T21:27:37.315Z,1518384457.315 [BPC1](INFO): Stopped
2018-02-11T21:27:40.464Z,1518384460.464 [BPC1](INFO): Stopped
2018-02-11T21:27:40.515Z,1518384460.515 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20180211T211327/Express0009.lzma
2018-02-11T21:27:40.601Z,1518384460.601 [BPC1](INFO): Stopped
2018-02-11T21:27:40.948Z,1518384460.948 [BPC1](INFO): Stopped
2018-02-11T21:27:41.314Z,1518384461.314 [DataOverHttps](INFO): Moved sent file to Logs/20180211T211327/Express0009.lzma.bak
2018-02-11T21:27:41.314Z,1518384461.314 [DataOverHttps](INFO): SBD MOMSN=7852891
2018-02-11T21:27:41.363Z,1518384461.363 [BPC1](INFO): Stopped
2018-02-11T21:27:43.165Z,1518384463.165 [BPC1](INFO): Stopped
2018-02-11T21:27:43.293Z,1518384463.293 [BPC1](INFO): Stopped
2018-02-11T21:27:46.404Z,1518384466.404 [BPC1](INFO): Stopped
2018-02-11T21:27:46.521Z,1518384466.521 [BPC1](INFO): Stopped
2018-02-11T21:27:46.870Z,1518384466.870 [BPC1](INFO): Stopped
2018-02-11T21:27:47.266Z,1518384467.266 [BPC1](INFO): Stopped
2018-02-11T21:27:47.667Z,1518384467.667 [BPC1](INFO): Stopped
2018-02-11T21:27:48.067Z,1518384468.067 [BPC1](INFO): Stopped
2018-02-11T21:27:51.180Z,1518384471.180 [BPC1](INFO): Stopped
2018-02-11T21:27:51.255Z,1518384471.255 [DataOverHttps](INFO): Sending 600 bytes from file Logs/20180211T212400/Express0001.lzma
2018-02-11T21:27:51.278Z,1518384471.278 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:27:51.279Z,1518384471.279 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:27:51.325Z,1518384471.325 [BPC1](INFO): Stopped
2018-02-11T21:27:51.702Z,1518384471.702 [BPC1](INFO): Stopped
2018-02-11T21:27:52.046Z,1518384472.046 [DataOverHttps](INFO): Moved sent file to Logs/20180211T212400/Express0001.lzma.bak
2018-02-11T21:27:52.046Z,1518384472.046 [DataOverHttps](INFO): SBD MOMSN=7852898
2018-02-11T21:27:52.092Z,1518384472.092 [BPC1](INFO): Stopped
2018-02-11T21:27:53.894Z,1518384473.894 [BPC1](INFO): Stopped
2018-02-11T21:27:53.904Z,1518384473.904 [Startup:StartupSatComms:B] Stopped
2018-02-11T21:27:53.904Z,1518384473.904 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-02-11T21:27:53.904Z,1518384473.904 [Startup:StartupSatComms] Stopped
2018-02-11T21:27:53.904Z,1518384473.904 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-02-11T21:27:53.905Z,1518384473.905 [Startup](INFO): Completed Startup
2018-02-11T21:27:53.905Z,1518384473.905 [MissionManager](INFO): Startup is completed.
2018-02-11T21:27:53.905Z,1518384473.905 [MissionManager](INFO): Uninitializing Mission Startup
2018-02-11T21:27:53.905Z,1518384473.905 [Startup] Stopped
2018-02-11T21:27:53.905Z,1518384473.905 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-02-11T21:27:53.906Z,1518384473.906 [Startup:A.GoToSurface] Stopped
2018-02-11T21:27:53.906Z,1518384473.906 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-02-11T21:27:54.029Z,1518384474.029 [BPC1](INFO): Stopped
2018-02-11T21:27:54.058Z,1518384474.058 [MissionManager](IMPORTANT): Started mission Default
2018-02-11T21:27:54.058Z,1518384474.058 [Default] Running Loop=1
2018-02-11T21:27:54.058Z,1518384474.058 [Default](DEBUG): Aggregate::initialize Default
2018-02-11T21:27:54.058Z,1518384474.058 [Default:B.GoToSurface] Running Loop=1
2018-02-11T21:27:54.058Z,1518384474.058 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-02-11T21:27:54.058Z,1518384474.058 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-02-11T21:27:54.059Z,1518384474.059 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-02-11T21:27:54.059Z,1518384474.059 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-02-11T21:27:54.059Z,1518384474.059 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-02-11T21:27:54.060Z,1518384474.060 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-02-11T21:27:54.060Z,1518384474.060 [Default:A.Wait] Running Loop=1
2018-02-11T21:27:54.060Z,1518384474.060 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-02-11T21:27:57.114Z,1518384477.114 [BPC1](INFO): Stopped
2018-02-11T21:27:57.253Z,1518384477.253 [BPC1](INFO): Stopped
2018-02-11T21:27:57.612Z,1518384477.612 [BPC1](INFO): Stopped
2018-02-11T21:27:58.005Z,1518384478.005 [BPC1](INFO): Stopped
2018-02-11T21:27:58.406Z,1518384478.406 [BPC1](INFO): Stopped
2018-02-11T21:27:58.808Z,1518384478.808 [BPC1](INFO): Stopped
2018-02-11T21:27:59.205Z,1518384479.205 [BPC1](INFO): Stopped
2018-02-11T21:27:59.613Z,1518384479.613 [BPC1](INFO): Stopped
2018-02-11T21:28:00.011Z,1518384480.011 [BPC1](INFO): Stopped
2018-02-11T21:28:00.409Z,1518384480.409 [BPC1](INFO): Stopped
2018-02-11T21:28:00.807Z,1518384480.807 [BPC1](INFO): Stopped
2018-02-11T21:28:01.207Z,1518384481.207 [BPC1](INFO): Stopped
2018-02-11T21:28:01.609Z,1518384481.609 [BPC1](INFO): Stopped
2018-02-11T21:28:02.007Z,1518384482.007 [BPC1](INFO): Stopped
2018-02-11T21:28:02.413Z,1518384482.413 [BPC1](INFO): Stopped
2018-02-11T21:28:02.809Z,1518384482.809 [BPC1](INFO): Stopped
2018-02-11T21:28:03.208Z,1518384483.208 [BPC1](INFO): Stopped
2018-02-11T21:28:03.612Z,1518384483.612 [BPC1](INFO): Stopped
2018-02-11T21:28:04.017Z,1518384484.017 [BPC1](INFO): Stopped
2018-02-11T21:28:04.417Z,1518384484.417 [BPC1](INFO): Stopped
2018-02-11T21:28:04.809Z,1518384484.809 [BPC1](INFO): Stopped
2018-02-11T21:28:05.209Z,1518384485.209 [BPC1](INFO): Stopped
2018-02-11T21:28:05.606Z,1518384485.606 [BPC1](INFO): Stopped
2018-02-11T21:28:06.011Z,1518384486.011 [BPC1](INFO): Stopped
2018-02-11T21:28:06.409Z,1518384486.409 [BPC1](INFO): Stopped
2018-02-11T21:28:06.806Z,1518384486.806 [BPC1](INFO): Stopped
2018-02-11T21:28:07.210Z,1518384487.210 [BPC1](INFO): Stopped
2018-02-11T21:28:07.220Z,1518384487.220 [Default:A.Wait](INFO): Done Waiting.
2018-02-11T21:28:07.220Z,1518384487.220 [Default:A.Wait] Stopped
2018-02-11T21:28:07.221Z,1518384487.221 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-02-11T21:28:07.609Z,1518384487.609 [BPC1](INFO): Stopped
2018-02-11T21:28:07.626Z,1518384487.626 [Default:CheckIn] Running Loop=1
2018-02-11T21:28:07.626Z,1518384487.626 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-11T21:28:07.626Z,1518384487.626 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-11T21:28:08.025Z,1518384488.025 [BPC1](INFO): Stopped
2018-02-11T21:28:08.049Z,1518384488.049 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-02-11T21:28:08.398Z,1518384488.398 [BPC1](INFO): Stopped
2018-02-11T21:28:08.798Z,1518384488.798 [BPC1](INFO): Stopped
2018-02-11T21:28:09.198Z,1518384489.198 [BPC1](INFO): Stopped
2018-02-11T21:28:09.609Z,1518384489.609 [BPC1](INFO): Stopped
2018-02-11T21:28:09.998Z,1518384489.998 [BPC1](INFO): Stopped
2018-02-11T21:28:10.398Z,1518384490.398 [BPC1](INFO): Stopped
2018-02-11T21:28:10.803Z,1518384490.803 [BPC1](INFO): Stopped
2018-02-11T21:28:11.198Z,1518384491.198 [BPC1](INFO): Stopped
2018-02-11T21:28:11.598Z,1518384491.598 [BPC1](INFO): Stopped
2018-02-11T21:28:11.998Z,1518384491.998 [BPC1](INFO): Stopped
2018-02-11T21:28:12.398Z,1518384492.398 [BPC1](INFO): Stopped
2018-02-11T21:28:12.807Z,1518384492.807 [BPC1](INFO): Stopped
2018-02-11T21:28:13.202Z,1518384493.202 [BPC1](INFO): Stopped
2018-02-11T21:28:13.603Z,1518384493.603 [BPC1](INFO): Stopped
2018-02-11T21:28:14.012Z,1518384494.012 [BPC1](INFO): Stopped
2018-02-11T21:28:14.405Z,1518384494.405 [BPC1](INFO): Stopped
2018-02-11T21:28:14.809Z,1518384494.809 [BPC1](INFO): Stopped
2018-02-11T21:28:15.241Z,1518384495.241 [BPC1](INFO): Stopped
2018-02-11T21:28:15.613Z,1518384495.613 [BPC1](INFO): Stopped
2018-02-11T21:28:16.083Z,1518384496.083 [BPC1](INFO): Stopped
2018-02-11T21:28:16.420Z,1518384496.420 [BPC1](INFO): Stopped
2018-02-11T21:28:16.849Z,1518384496.849 [BPC1](INFO): Stopped
2018-02-11T21:28:16.850Z,1518384496.850 [BPC1](INFO): Hibernation time: 0.050114
2018-02-11T21:28:17.176Z,1518384497.176 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:28:17.176Z,1518384497.176 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:28:17.241Z,1518384497.241 [BPC1](INFO): Start
2018-02-11T21:28:17.622Z,1518384497.622 [BPC1](INFO): Starting
2018-02-11T21:28:17.622Z,1518384497.622 [BPC1](FAULT): Failed to initialize
2018-02-11T21:28:17.622Z,1518384497.622 [BPC1] Communications Fault, FailCount= 1
2018-02-11T21:28:17.622Z,1518384497.622 [BPC1](ERROR): Communications Fault
2018-02-11T21:28:17.695Z,1518384497.695 [CBIT](ERROR): Communications Fault in component: BPC1
2018-02-11T21:28:18.002Z,1518384498.002 [BPC1](INFO): Stop
2018-02-11T21:28:18.002Z,1518384498.002 [BPC1](INFO): uninitialize
2018-02-11T21:28:18.402Z,1518384498.402 [BPC1](INFO): Stopping
2018-02-11T21:28:18.802Z,1518384498.802 [BPC1](INFO): Stopped
2018-02-11T21:28:18.803Z,1518384498.803 [BPC1](INFO): Hibernation time: 0.050656
2018-02-11T21:28:18.874Z,1518384498.874 [CBIT](INFO): Clearing failed state for component BPC1
2018-02-11T21:28:18.874Z,1518384498.874 [BPC1] No Fault, FailCount= 1
2018-02-11T21:28:19.202Z,1518384499.202 [BPC1](INFO): Start
2018-02-11T21:28:19.599Z,1518384499.599 [BPC1](INFO): Starting
2018-02-11T21:28:19.599Z,1518384499.599 [BPC1](FAULT): Failed to initialize
2018-02-11T21:28:19.599Z,1518384499.599 [BPC1] Communications Fault, FailCount= 2
2018-02-11T21:28:19.599Z,1518384499.599 [BPC1](ERROR): Communications Fault
2018-02-11T21:28:19.675Z,1518384499.675 [CBIT](ERROR): Communications Fault in component: BPC1
2018-02-11T21:28:19.998Z,1518384499.998 [BPC1](INFO): Stop
2018-02-11T21:28:19.998Z,1518384499.998 [BPC1](INFO): uninitialize
2018-02-11T21:28:20.398Z,1518384500.398 [BPC1](INFO): Stopping
2018-02-11T21:28:20.803Z,1518384500.803 [BPC1](INFO): Stopped
2018-02-11T21:28:20.803Z,1518384500.803 [BPC1](INFO): Hibernation time: 0.051212
2018-02-11T21:28:20.857Z,1518384500.857 [CBIT](INFO): Clearing failed state for component BPC1
2018-02-11T21:28:20.857Z,1518384500.857 [BPC1] No Fault, FailCount= 2
2018-02-11T21:28:21.203Z,1518384501.203 [BPC1](INFO): Start
2018-02-11T21:28:21.611Z,1518384501.611 [BPC1](INFO): Starting
2018-02-11T21:28:21.611Z,1518384501.611 [BPC1](FAULT): Failed to initialize
2018-02-11T21:28:21.611Z,1518384501.611 [BPC1] Communications Fault, FailCount= 3
2018-02-11T21:28:21.611Z,1518384501.611 [BPC1](ERROR): Communications Fault
2018-02-11T21:28:21.657Z,1518384501.657 [CBIT](ERROR): Communications Fault in component: BPC1
2018-02-11T21:28:22.005Z,1518384502.005 [BPC1](INFO): Stop
2018-02-11T21:28:22.006Z,1518384502.006 [BPC1](INFO): uninitialize
2018-02-11T21:28:22.405Z,1518384502.405 [BPC1](INFO): Stopping
2018-02-11T21:28:22.809Z,1518384502.809 [BPC1](INFO): Stopped
2018-02-11T21:28:22.809Z,1518384502.809 [BPC1](INFO): Hibernation time: 0.051769
2018-02-11T21:28:22.829Z,1518384502.829 [CBIT](INFO): Clearing failed state for component BPC1
2018-02-11T21:28:22.829Z,1518384502.829 [BPC1] No Fault, FailCount= 3
2018-02-11T21:28:23.213Z,1518384503.213 [BPC1](INFO): Start
2018-02-11T21:28:23.607Z,1518384503.607 [BPC1](INFO): Starting
2018-02-11T21:28:23.608Z,1518384503.608 [BPC1](FAULT): Failed to initialize
2018-02-11T21:28:23.608Z,1518384503.608 [BPC1] Communications Fault, FailCount= 4
2018-02-11T21:28:23.608Z,1518384503.608 [BPC1](ERROR): Communications Fault
2018-02-11T21:28:23.629Z,1518384503.629 [CBIT](ERROR): Communications Fault in component: BPC1
2018-02-11T21:28:24.014Z,1518384504.014 [BPC1](INFO): Stop
2018-02-11T21:28:24.015Z,1518384504.015 [BPC1](INFO): uninitialize
2018-02-11T21:28:24.405Z,1518384504.405 [BPC1](INFO): Stopping
2018-02-11T21:28:24.799Z,1518384504.799 [BPC1](INFO): Stopped
2018-02-11T21:28:24.799Z,1518384504.799 [BPC1](INFO): Hibernation time: 0.052322
2018-02-11T21:28:24.860Z,1518384504.860 [CBIT](INFO): Clearing failed state for component BPC1
2018-02-11T21:28:24.860Z,1518384504.860 [BPC1] No Fault, FailCount= 4
2018-02-11T21:28:25.203Z,1518384505.203 [BPC1](INFO): Start
2018-02-11T21:28:25.579Z,1518384505.579 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:28:25.579Z,1518384505.579 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:28:25.604Z,1518384505.604 [BPC1](INFO): Starting
2018-02-11T21:28:25.604Z,1518384505.604 [BPC1](INFO): Initialized.
2018-02-11T21:28:26.010Z,1518384506.010 [BPC1](INFO): Runnable
2018-02-11T21:28:26.021Z,1518384506.021 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:28:26.033Z,1518384506.033 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:28:26.402Z,1518384506.402 [BPC1](INFO): Runnable
2018-02-11T21:28:26.805Z,1518384506.805 [BPC1](INFO): Runnable
2018-02-11T21:28:27.207Z,1518384507.207 [BPC1](INFO): Runnable
2018-02-11T21:28:27.605Z,1518384507.605 [BPC1](INFO): Runnable
2018-02-11T21:28:27.606Z,1518384507.606 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:28:28.014Z,1518384508.014 [BPC1](INFO): Runnable
2018-02-11T21:28:28.014Z,1518384508.014 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:28:28.405Z,1518384508.405 [BPC1](INFO): Runnable
2018-02-11T21:28:28.809Z,1518384508.809 [BPC1](INFO): Runnable
2018-02-11T21:28:29.207Z,1518384509.207 [BPC1](INFO): Runnable
2018-02-11T21:28:29.598Z,1518384509.598 [BPC1](INFO): Runnable
2018-02-11T21:28:30.020Z,1518384510.020 [BPC1](INFO): Runnable
2018-02-11T21:28:30.398Z,1518384510.398 [BPC1](INFO): Runnable
2018-02-11T21:28:30.799Z,1518384510.799 [BPC1](INFO): Runnable
2018-02-11T21:28:31.202Z,1518384511.202 [BPC1](INFO): Runnable
2018-02-11T21:28:31.601Z,1518384511.601 [BPC1](INFO): Runnable
2018-02-11T21:28:31.602Z,1518384511.602 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:28:31.608Z,1518384511.608 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:28:32.003Z,1518384512.003 [BPC1](INFO): Runnable
2018-02-11T21:28:32.017Z,1518384512.017 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:28:32.017Z,1518384512.017 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:28:32.028Z,1518384512.028 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:28:32.406Z,1518384512.406 [BPC1](INFO): Runnable
2018-02-11T21:28:32.417Z,1518384512.417 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:28:32.806Z,1518384512.806 [BPC1](INFO): Runnable
2018-02-11T21:28:33.205Z,1518384513.205 [BPC1](INFO): Runnable
2018-02-11T21:28:33.606Z,1518384513.606 [BPC1](INFO): Runnable
2018-02-11T21:28:34.002Z,1518384514.002 [BPC1](INFO): Runnable
2018-02-11T21:28:34.405Z,1518384514.405 [BPC1](INFO): Runnable
2018-02-11T21:28:34.783Z,1518384514.783 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:28:34.783Z,1518384514.783 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:28:34.808Z,1518384514.808 [BPC1](INFO): Runnable
2018-02-11T21:28:35.211Z,1518384515.211 [BPC1](INFO): Runnable
2018-02-11T21:28:35.605Z,1518384515.605 [BPC1](INFO): Runnable
2018-02-11T21:28:36.009Z,1518384516.009 [BPC1](INFO): Runnable
2018-02-11T21:28:36.405Z,1518384516.405 [BPC1](INFO): Runnable
2018-02-11T21:28:36.810Z,1518384516.810 [BPC1](INFO): Runnable
2018-02-11T21:28:37.209Z,1518384517.209 [BPC1](INFO): Runnable
2018-02-11T21:28:37.606Z,1518384517.606 [BPC1](INFO): Runnable
2018-02-11T21:28:38.006Z,1518384518.006 [BPC1](INFO): Runnable
2018-02-11T21:28:38.412Z,1518384518.412 [BPC1](INFO): Runnable
2018-02-11T21:28:38.806Z,1518384518.806 [BPC1](INFO): Runnable
2018-02-11T21:28:39.215Z,1518384519.215 [BPC1](INFO): Runnable
2018-02-11T21:28:39.614Z,1518384519.614 [BPC1](INFO): Runnable
2018-02-11T21:28:40.007Z,1518384520.007 [BPC1](INFO): Runnable
2018-02-11T21:28:40.008Z,1518384520.008 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:28:40.410Z,1518384520.410 [BPC1](INFO): Runnable
2018-02-11T21:28:40.411Z,1518384520.411 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:28:40.810Z,1518384520.810 [BPC1](INFO): Runnable
2018-02-11T21:28:41.205Z,1518384521.205 [BPC1](INFO): Runnable
2018-02-11T21:28:41.609Z,1518384521.609 [BPC1](INFO): Runnable
2018-02-11T21:28:42.006Z,1518384522.006 [BPC1](INFO): Runnable
2018-02-11T21:28:42.419Z,1518384522.419 [BPC1](INFO): Runnable
2018-02-11T21:28:42.806Z,1518384522.806 [BPC1](INFO): Runnable
2018-02-11T21:28:43.214Z,1518384523.214 [BPC1](INFO): Runnable
2018-02-11T21:28:43.606Z,1518384523.606 [BPC1](INFO): Runnable
2018-02-11T21:28:44.006Z,1518384524.006 [BPC1](INFO): Runnable
2018-02-11T21:28:44.409Z,1518384524.409 [BPC1](INFO): Runnable
2018-02-11T21:28:44.410Z,1518384524.410 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:28:44.416Z,1518384524.416 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:28:44.809Z,1518384524.809 [BPC1](INFO): Runnable
2018-02-11T21:28:44.821Z,1518384524.821 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:28:45.205Z,1518384525.205 [BPC1](INFO): Runnable
2018-02-11T21:28:45.206Z,1518384525.206 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:28:45.225Z,1518384525.225 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:28:45.610Z,1518384525.610 [BPC1](INFO): Runnable
2018-02-11T21:28:45.621Z,1518384525.621 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:28:46.002Z,1518384526.002 [BPC1](INFO): Runnable
2018-02-11T21:28:46.399Z,1518384526.399 [BPC1](INFO): Runnable
2018-02-11T21:28:46.798Z,1518384526.798 [BPC1](INFO): Runnable
2018-02-11T21:28:47.202Z,1518384527.202 [BPC1](INFO): Runnable
2018-02-11T21:28:47.606Z,1518384527.606 [BPC1](INFO): Runnable
2018-02-11T21:28:48.002Z,1518384528.002 [BPC1](INFO): Runnable
2018-02-11T21:28:48.403Z,1518384528.403 [BPC1](INFO): Runnable
2018-02-11T21:28:48.806Z,1518384528.806 [BPC1](INFO): Runnable
2018-02-11T21:28:49.203Z,1518384529.203 [BPC1](INFO): Runnable
2018-02-11T21:28:49.605Z,1518384529.605 [BPC1](INFO): Runnable
2018-02-11T21:28:50.003Z,1518384530.003 [BPC1](INFO): Runnable
2018-02-11T21:28:50.407Z,1518384530.407 [BPC1](INFO): Runnable
2018-02-11T21:28:50.805Z,1518384530.805 [BPC1](INFO): Runnable
2018-02-11T21:28:51.205Z,1518384531.205 [BPC1](INFO): Runnable
2018-02-11T21:28:51.598Z,1518384531.598 [BPC1](INFO): Runnable
2018-02-11T21:28:51.998Z,1518384531.998 [BPC1](INFO): Runnable
2018-02-11T21:28:52.398Z,1518384532.398 [BPC1](INFO): Runnable
2018-02-11T21:28:52.398Z,1518384532.398 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:28:52.799Z,1518384532.799 [BPC1](INFO): Runnable
2018-02-11T21:28:53.199Z,1518384533.199 [BPC1](INFO): Runnable
2018-02-11T21:28:53.200Z,1518384533.200 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:28:53.603Z,1518384533.603 [BPC1](INFO): Runnable
2018-02-11T21:28:54.002Z,1518384534.002 [BPC1](INFO): Runnable
2018-02-11T21:28:54.403Z,1518384534.403 [BPC1](INFO): Runnable
2018-02-11T21:28:57.545Z,1518384537.545 [BPC1](INFO): Runnable
2018-02-11T21:28:57.546Z,1518384537.546 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:28:57.553Z,1518384537.553 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:28:57.685Z,1518384537.685 [BPC1](INFO): Runnable
2018-02-11T21:28:57.697Z,1518384537.697 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:28:58.021Z,1518384538.021 [BPC1](INFO): Runnable
2018-02-11T21:28:58.021Z,1518384538.021 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:28:58.028Z,1518384538.028 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:28:58.422Z,1518384538.422 [BPC1](INFO): Runnable
2018-02-11T21:28:58.437Z,1518384538.437 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:28:58.817Z,1518384538.817 [BPC1](INFO): Runnable
2018-02-11T21:28:59.221Z,1518384539.221 [BPC1](INFO): Runnable
2018-02-11T21:28:59.617Z,1518384539.617 [BPC1](INFO): Runnable
2018-02-11T21:29:00.025Z,1518384540.025 [BPC1](INFO): Runnable
2018-02-11T21:29:00.425Z,1518384540.425 [BPC1](INFO): Runnable
2018-02-11T21:29:00.787Z,1518384540.787 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:29:00.788Z,1518384540.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:29:00.817Z,1518384540.817 [BPC1](INFO): Runnable
2018-02-11T21:29:01.229Z,1518384541.229 [BPC1](INFO): Runnable
2018-02-11T21:29:01.614Z,1518384541.614 [BPC1](INFO): Runnable
2018-02-11T21:29:02.011Z,1518384542.011 [BPC1](INFO): Runnable
2018-02-11T21:29:02.414Z,1518384542.414 [BPC1](INFO): Runnable
2018-02-11T21:29:02.814Z,1518384542.814 [BPC1](INFO): Runnable
2018-02-11T21:29:03.222Z,1518384543.222 [BPC1](INFO): Runnable
2018-02-11T21:29:03.615Z,1518384543.615 [BPC1](INFO): Runnable
2018-02-11T21:29:04.020Z,1518384544.020 [BPC1](INFO): Runnable
2018-02-11T21:29:04.419Z,1518384544.419 [BPC1](INFO): Runnable
2018-02-11T21:29:04.819Z,1518384544.819 [BPC1](INFO): Runnable
2018-02-11T21:29:05.226Z,1518384545.226 [BPC1](INFO): Runnable
2018-02-11T21:29:05.226Z,1518384545.226 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:29:05.622Z,1518384545.622 [BPC1](INFO): Runnable
2018-02-11T21:29:05.622Z,1518384545.622 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:29:06.019Z,1518384546.019 [BPC1](INFO): Runnable
2018-02-11T21:29:06.423Z,1518384546.423 [BPC1](INFO): Runnable
2018-02-11T21:29:06.840Z,1518384546.840 [BPC1](INFO): Runnable
2018-02-11T21:29:07.218Z,1518384547.218 [BPC1](INFO): Runnable
2018-02-11T21:29:07.625Z,1518384547.625 [BPC1](INFO): Runnable
2018-02-11T21:29:08.018Z,1518384548.018 [BPC1](INFO): Runnable
2018-02-11T21:29:08.410Z,1518384548.410 [BPC1](INFO): Runnable
2018-02-11T21:29:08.810Z,1518384548.810 [BPC1](INFO): Runnable
2018-02-11T21:29:09.210Z,1518384549.210 [BPC1](INFO): Runnable
2018-02-11T21:29:09.211Z,1518384549.211 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:29:09.216Z,1518384549.216 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:29:09.611Z,1518384549.611 [BPC1](INFO): Runnable
2018-02-11T21:29:09.626Z,1518384549.626 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:29:09.627Z,1518384549.627 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:29:09.642Z,1518384549.642 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:29:09.642Z,1518384549.642 [BPC1](DEBUG): Received data from all battery sticks
2018-02-11T21:29:10.025Z,1518384550.025 [BPC1](INFO): Stop
2018-02-11T21:29:10.025Z,1518384550.025 [BPC1](INFO): uninitialize
2018-02-11T21:29:10.421Z,1518384550.421 [BPC1](INFO): Stopping
2018-02-11T21:29:10.824Z,1518384550.824 [BPC1](INFO): Stopped
2018-02-11T21:29:11.217Z,1518384551.217 [BPC1](INFO): Stopped
2018-02-11T21:29:11.617Z,1518384551.617 [BPC1](INFO): Stopped
2018-02-11T21:29:12.010Z,1518384552.010 [BPC1](INFO): Stopped
2018-02-11T21:29:12.414Z,1518384552.414 [BPC1](INFO): Stopped
2018-02-11T21:29:12.810Z,1518384552.810 [BPC1](INFO): Stopped
2018-02-11T21:29:13.210Z,1518384553.210 [BPC1](INFO): Stopped
2018-02-11T21:29:13.610Z,1518384553.610 [BPC1](INFO): Stopped
2018-02-11T21:29:14.010Z,1518384554.010 [BPC1](INFO): Stopped
2018-02-11T21:29:14.415Z,1518384554.415 [BPC1](INFO): Stopped
2018-02-11T21:29:14.814Z,1518384554.814 [BPC1](INFO): Stopped
2018-02-11T21:29:15.219Z,1518384555.219 [BPC1](INFO): Stopped
2018-02-11T21:29:15.616Z,1518384555.616 [BPC1](INFO): Stopped
2018-02-11T21:29:16.018Z,1518384556.018 [BPC1](INFO): Stopped
2018-02-11T21:29:16.418Z,1518384556.418 [BPC1](INFO): Stopped
2018-02-11T21:29:16.818Z,1518384556.818 [BPC1](INFO): Stopped
2018-02-11T21:29:17.218Z,1518384557.218 [BPC1](INFO): Stopped
2018-02-11T21:29:17.621Z,1518384557.621 [BPC1](INFO): Stopped
2018-02-11T21:29:18.017Z,1518384558.017 [BPC1](INFO): Stopped
2018-02-11T21:29:18.414Z,1518384558.414 [BPC1](INFO): Stopped
2018-02-11T21:29:18.818Z,1518384558.818 [BPC1](INFO): Stopped
2018-02-11T21:29:19.221Z,1518384559.221 [BPC1](INFO): Stopped
2018-02-11T21:29:19.621Z,1518384559.621 [BPC1](INFO): Stopped
2018-02-11T21:29:20.018Z,1518384560.018 [BPC1](INFO): Stopped
2018-02-11T21:29:20.417Z,1518384560.417 [BPC1](INFO): Stopped
2018-02-11T21:29:20.824Z,1518384560.824 [BPC1](INFO): Stopped
2018-02-11T21:29:21.191Z,1518384561.191 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:29:21.192Z,1518384561.192 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:29:21.236Z,1518384561.236 [BPC1](INFO): Stopped
2018-02-11T21:29:21.628Z,1518384561.628 [BPC1](INFO): Stopped
2018-02-11T21:29:22.014Z,1518384562.014 [BPC1](INFO): Stopped
2018-02-11T21:29:22.412Z,1518384562.412 [BPC1](INFO): Stopped
2018-02-11T21:29:22.812Z,1518384562.812 [BPC1](INFO): Stopped
2018-02-11T21:29:23.214Z,1518384563.214 [BPC1](INFO): Stopped
2018-02-11T21:29:23.614Z,1518384563.614 [BPC1](INFO): Stopped
2018-02-11T21:29:24.013Z,1518384564.013 [BPC1](INFO): Stopped
2018-02-11T21:29:24.412Z,1518384564.412 [BPC1](INFO): Stopped
2018-02-11T21:29:24.813Z,1518384564.813 [BPC1](INFO): Stopped
2018-02-11T21:29:25.214Z,1518384565.214 [BPC1](INFO): Stopped
2018-02-11T21:29:25.632Z,1518384565.632 [BPC1](INFO): Stopped
2018-02-11T21:29:26.021Z,1518384566.021 [BPC1](INFO): Stopped
2018-02-11T21:29:26.417Z,1518384566.417 [BPC1](INFO): Stopped
2018-02-11T21:29:26.817Z,1518384566.817 [BPC1](INFO): Stopped
2018-02-11T21:29:27.221Z,1518384567.221 [BPC1](INFO): Stopped
2018-02-11T21:29:27.589Z,1518384567.589 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:29:27.590Z,1518384567.590 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:29:27.636Z,1518384567.636 [BPC1](INFO): Stopped
2018-02-11T21:29:28.034Z,1518384568.034 [BPC1](INFO): Stopped
2018-02-11T21:29:28.410Z,1518384568.410 [BPC1](INFO): Stopped
2018-02-11T21:29:28.815Z,1518384568.815 [BPC1](INFO): Stopped
2018-02-11T21:29:29.212Z,1518384569.212 [BPC1](INFO): Stopped
2018-02-11T21:29:29.612Z,1518384569.612 [BPC1](INFO): Stopped
2018-02-11T21:29:30.011Z,1518384570.011 [BPC1](INFO): Stopped
2018-02-11T21:29:30.421Z,1518384570.421 [BPC1](INFO): Stopped
2018-02-11T21:29:30.827Z,1518384570.827 [BPC1](INFO): Stopped
2018-02-11T21:29:31.220Z,1518384571.220 [BPC1](INFO): Stopped
2018-02-11T21:29:31.618Z,1518384571.618 [BPC1](INFO): Stopped
2018-02-11T21:29:32.027Z,1518384572.027 [BPC1](INFO): Stopped
2018-02-11T21:29:32.422Z,1518384572.422 [BPC1](INFO): Stopped
2018-02-11T21:29:32.821Z,1518384572.821 [BPC1](INFO): Stopped
2018-02-11T21:29:33.222Z,1518384573.222 [BPC1](INFO): Stopped
2018-02-11T21:29:33.624Z,1518384573.624 [BPC1](INFO): Stopped
2018-02-11T21:29:34.021Z,1518384574.021 [BPC1](INFO): Stopped
2018-02-11T21:29:34.422Z,1518384574.422 [BPC1](INFO): Stopped
2018-02-11T21:29:34.817Z,1518384574.817 [BPC1](INFO): Stopped
2018-02-11T21:29:35.222Z,1518384575.222 [BPC1](INFO): Stopped
2018-02-11T21:29:35.622Z,1518384575.622 [BPC1](INFO): Stopped
2018-02-11T21:29:36.021Z,1518384576.021 [BPC1](INFO): Stopped
2018-02-11T21:29:36.419Z,1518384576.419 [BPC1](INFO): Stopped
2018-02-11T21:29:36.823Z,1518384576.823 [BPC1](INFO): Stopped
2018-02-11T21:29:37.222Z,1518384577.222 [BPC1](INFO): Stopped
2018-02-11T21:29:37.624Z,1518384577.624 [BPC1](INFO): Stopped
2018-02-11T21:29:38.021Z,1518384578.021 [BPC1](INFO): Stopped
2018-02-11T21:29:38.429Z,1518384578.429 [BPC1](INFO): Stopped
2018-02-11T21:29:38.833Z,1518384578.833 [BPC1](INFO): Stopped
2018-02-11T21:29:39.221Z,1518384579.221 [BPC1](INFO): Stopped
2018-02-11T21:29:39.623Z,1518384579.623 [BPC1](INFO): Stopped
2018-02-11T21:29:40.029Z,1518384580.029 [BPC1](INFO): Stopped
2018-02-11T21:29:40.433Z,1518384580.433 [BPC1](INFO): Stopped
2018-02-11T21:29:40.823Z,1518384580.823 [BPC1](INFO): Stopped
2018-02-11T21:29:41.225Z,1518384581.225 [BPC1](INFO): Stopped
2018-02-11T21:29:41.633Z,1518384581.633 [BPC1](INFO): Stopped
2018-02-11T21:29:42.033Z,1518384582.033 [BPC1](INFO): Stopped
2018-02-11T21:29:42.426Z,1518384582.426 [BPC1](INFO): Stopped
2018-02-11T21:29:42.827Z,1518384582.827 [BPC1](INFO): Stopped
2018-02-11T21:29:43.230Z,1518384583.230 [BPC1](INFO): Stopped
2018-02-11T21:29:43.623Z,1518384583.623 [BPC1](INFO): Stopped
2018-02-11T21:29:44.025Z,1518384584.025 [BPC1](INFO): Stopped
2018-02-11T21:29:44.425Z,1518384584.425 [BPC1](INFO): Stopped
2018-02-11T21:29:44.791Z,1518384584.791 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:29:44.791Z,1518384584.791 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:29:44.845Z,1518384584.845 [BPC1](INFO): Stopped
2018-02-11T21:29:45.241Z,1518384585.241 [BPC1](INFO): Stopped
2018-02-11T21:29:45.624Z,1518384585.624 [BPC1](INFO): Stopped
2018-02-11T21:29:46.025Z,1518384586.025 [BPC1](INFO): Stopped
2018-02-11T21:29:46.421Z,1518384586.421 [BPC1](INFO): Stopped
2018-02-11T21:29:46.820Z,1518384586.820 [BPC1](INFO): Stopped
2018-02-11T21:29:47.227Z,1518384587.227 [BPC1](INFO): Stopped
2018-02-11T21:29:47.628Z,1518384587.628 [BPC1](INFO): Stopped
2018-02-11T21:29:48.030Z,1518384588.030 [BPC1](INFO): Stopped
2018-02-11T21:29:48.418Z,1518384588.418 [BPC1](INFO): Stopped
2018-02-11T21:29:48.831Z,1518384588.831 [BPC1](INFO): Stopped
2018-02-11T21:29:49.232Z,1518384589.232 [BPC1](INFO): Stopped
2018-02-11T21:29:49.625Z,1518384589.625 [BPC1](INFO): Stopped
2018-02-11T21:29:50.009Z,1518384590.009 [BPC1](INFO): Stopped
2018-02-11T21:29:50.390Z,1518384590.390 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-02-11T21:29:50.437Z,1518384590.437 [BPC1](INFO): Stopped
2018-02-11T21:29:50.815Z,1518384590.815 [BPC1](INFO): Stopped
2018-02-11T21:29:51.237Z,1518384591.237 [BPC1](INFO): Stopped
2018-02-11T21:29:51.614Z,1518384591.614 [BPC1](INFO): Stopped
2018-02-11T21:29:52.011Z,1518384592.011 [BPC1](INFO): Stopped
2018-02-11T21:29:52.418Z,1518384592.418 [BPC1](INFO): Stopped
2018-02-11T21:29:52.842Z,1518384592.842 [BPC1](INFO): Stopped
2018-02-11T21:29:53.232Z,1518384593.232 [BPC1](INFO): Stopped
2018-02-11T21:29:53.622Z,1518384593.622 [BPC1](INFO): Stopped
2018-02-11T21:29:54.021Z,1518384594.021 [BPC1](INFO): Stopped
2018-02-11T21:29:54.425Z,1518384594.425 [BPC1](INFO): Stopped
2018-02-11T21:29:57.533Z,1518384597.533 [BPC1](INFO): Stopped
2018-02-11T21:29:57.665Z,1518384597.665 [BPC1](INFO): Stopped
2018-02-11T21:29:58.052Z,1518384598.052 [BPC1](INFO): Stopped
2018-02-11T21:29:58.441Z,1518384598.441 [BPC1](INFO): Stopped
2018-02-11T21:29:58.844Z,1518384598.844 [BPC1](INFO): Stopped
2018-02-11T21:29:59.250Z,1518384599.250 [BPC1](INFO): Stopped
2018-02-11T21:29:59.650Z,1518384599.650 [BPC1](INFO): Stopped
2018-02-11T21:30:00.050Z,1518384600.050 [BPC1](INFO): Stopped
2018-02-11T21:30:00.450Z,1518384600.450 [BPC1](INFO): Stopped
2018-02-11T21:30:00.845Z,1518384600.845 [BPC1](INFO): Stopped
2018-02-11T21:30:01.242Z,1518384601.242 [BPC1](INFO): Stopped
2018-02-11T21:30:01.643Z,1518384601.643 [BPC1](INFO): Stopped
2018-02-11T21:30:02.054Z,1518384602.054 [BPC1](INFO): Stopped
2018-02-11T21:30:02.473Z,1518384602.473 [BPC1](INFO): Stopped
2018-02-11T21:30:02.869Z,1518384602.869 [BPC1](INFO): Stopped
2018-02-11T21:30:03.251Z,1518384603.251 [BPC1](INFO): Stopped
2018-02-11T21:30:03.655Z,1518384603.655 [BPC1](INFO): Stopped
2018-02-11T21:30:04.073Z,1518384604.073 [BPC1](INFO): Stopped
2018-02-11T21:30:04.441Z,1518384604.441 [BPC1](INFO): Stopped
2018-02-11T21:30:04.861Z,1518384604.861 [BPC1](INFO): Stopped
2018-02-11T21:30:05.260Z,1518384605.260 [BPC1](INFO): Stopped
2018-02-11T21:30:05.665Z,1518384605.665 [BPC1](INFO): Stopped
2018-02-11T21:30:06.042Z,1518384606.042 [BPC1](INFO): Stopped
2018-02-11T21:30:06.430Z,1518384606.430 [BPC1](INFO): Stopped
2018-02-11T21:30:06.865Z,1518384606.865 [BPC1](INFO): Stopped
2018-02-11T21:30:07.269Z,1518384607.269 [BPC1](INFO): Stopped
2018-02-11T21:30:07.651Z,1518384607.651 [BPC1](INFO): Stopped
2018-02-11T21:30:08.056Z,1518384608.056 [BPC1](INFO): Stopped
2018-02-11T21:30:08.466Z,1518384608.466 [BPC1](INFO): Stopped
2018-02-11T21:30:08.857Z,1518384608.857 [BPC1](INFO): Stopped
2018-02-11T21:30:09.265Z,1518384609.265 [BPC1](INFO): Stopped
2018-02-11T21:30:09.638Z,1518384609.638 [BPC1](INFO): Stopped
2018-02-11T21:30:10.047Z,1518384610.047 [BPC1](INFO): Stopped
2018-02-11T21:30:10.449Z,1518384610.449 [BPC1](INFO): Stopped
2018-02-11T21:30:10.467Z,1518384610.467 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-11T21:30:10.467Z,1518384610.467 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-02-11T21:30:10.468Z,1518384610.468 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-02-11T21:30:10.471Z,1518384610.471 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-11T21:30:10.471Z,1518384610.471 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-02-11T21:30:10.471Z,1518384610.471 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-11T21:30:10.500Z,1518384610.500 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-02-11T21:30:10.500Z,1518384610.500 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-11T21:30:10.883Z,1518384610.883 [BPC1](INFO): Stopped
2018-02-11T21:30:10.925Z,1518384610.925 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-02-11T21:30:10.925Z,1518384610.925 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-02-11T21:30:10.926Z,1518384610.926 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-11T21:30:10.926Z,1518384610.926 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-02-11T21:30:11.265Z,1518384611.265 [BPC1](INFO): Stopped
2018-02-11T21:30:11.273Z,1518384611.273 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-02-11T21:30:11.273Z,1518384611.273 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:30:11.274Z,1518384611.274 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:30:11.274Z,1518384611.274 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-11T21:30:11.275Z,1518384611.275 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:30:11.275Z,1518384611.275 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:30:11.652Z,1518384611.652 [BPC1](INFO): Stopped
2018-02-11T21:30:11.697Z,1518384611.697 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-02-11T21:30:12.049Z,1518384612.049 [BPC1](INFO): Stopped
2018-02-11T21:30:12.060Z,1518384612.060 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-02-11T21:30:12.457Z,1518384612.457 [BPC1](INFO): Stopped
2018-02-11T21:30:12.499Z,1518384612.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-02-11T21:30:12.857Z,1518384612.857 [BPC1](INFO): Stopped
2018-02-11T21:30:12.901Z,1518384612.901 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-02-11T21:30:13.247Z,1518384613.247 [BPC1](INFO): Stopped
2018-02-11T21:30:13.264Z,1518384613.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-02-11T21:30:13.649Z,1518384613.649 [BPC1](INFO): Stopped
2018-02-11T21:30:13.666Z,1518384613.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-02-11T21:30:14.055Z,1518384614.055 [BPC1](INFO): Stopped
2018-02-11T21:30:14.082Z,1518384614.082 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-02-11T21:30:14.464Z,1518384614.464 [BPC1](INFO): Stopped
2018-02-11T21:30:14.488Z,1518384614.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-02-11T21:30:14.866Z,1518384614.866 [BPC1](INFO): Stopped
2018-02-11T21:30:14.883Z,1518384614.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-02-11T21:30:15.245Z,1518384615.245 [BPC1](INFO): Stopped
2018-02-11T21:30:15.292Z,1518384615.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-02-11T21:30:15.639Z,1518384615.639 [BPC1](INFO): Stopped
2018-02-11T21:30:15.690Z,1518384615.690 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-02-11T21:30:16.040Z,1518384616.040 [BPC1](INFO): Stopped
2018-02-11T21:30:16.060Z,1518384616.060 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-02-11T21:30:16.440Z,1518384616.440 [BPC1](INFO): Stopped
2018-02-11T21:30:16.497Z,1518384616.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-02-11T21:30:16.858Z,1518384616.858 [BPC1](INFO): Stopped
2018-02-11T21:30:16.895Z,1518384616.895 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-02-11T21:30:17.252Z,1518384617.252 [BPC1](INFO): Stopped
2018-02-11T21:30:17.261Z,1518384617.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-02-11T21:30:17.648Z,1518384617.648 [BPC1](INFO): Stopped
2018-02-11T21:30:17.666Z,1518384617.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-02-11T21:30:18.042Z,1518384618.042 [BPC1](INFO): Stopped
2018-02-11T21:30:18.055Z,1518384618.055 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-02-11T21:30:18.448Z,1518384618.448 [BPC1](INFO): Stopped
2018-02-11T21:30:18.457Z,1518384618.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-02-11T21:30:18.840Z,1518384618.840 [BPC1](INFO): Stopped
2018-02-11T21:30:18.855Z,1518384618.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-02-11T21:30:19.245Z,1518384619.245 [BPC1](INFO): Stopped
2018-02-11T21:30:19.253Z,1518384619.253 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-02-11T21:30:19.650Z,1518384619.650 [BPC1](INFO): Stopped
2018-02-11T21:30:19.663Z,1518384619.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-02-11T21:30:20.050Z,1518384620.050 [BPC1](INFO): Stopped
2018-02-11T21:30:20.063Z,1518384620.063 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-02-11T21:30:20.455Z,1518384620.455 [BPC1](INFO): Stopped
2018-02-11T21:30:20.463Z,1518384620.463 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-02-11T21:30:20.851Z,1518384620.851 [BPC1](INFO): Stopped
2018-02-11T21:30:20.862Z,1518384620.862 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-02-11T21:30:21.230Z,1518384621.230 [BPC1](INFO): Stopped
2018-02-11T21:30:21.264Z,1518384621.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-02-11T21:30:21.630Z,1518384621.630 [BPC1](INFO): Stopped
2018-02-11T21:30:21.668Z,1518384621.668 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-02-11T21:30:22.036Z,1518384622.036 [BPC1](INFO): Stopped
2018-02-11T21:30:22.044Z,1518384622.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-02-11T21:30:22.429Z,1518384622.429 [BPC1](INFO): Stopped
2018-02-11T21:30:22.443Z,1518384622.443 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-02-11T21:30:22.845Z,1518384622.845 [BPC1](INFO): Stopped
2018-02-11T21:30:22.879Z,1518384622.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-02-11T21:30:23.245Z,1518384623.245 [BPC1](INFO): Stopped
2018-02-11T21:30:23.253Z,1518384623.253 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-02-11T21:30:23.644Z,1518384623.644 [BPC1](INFO): Stopped
2018-02-11T21:30:23.659Z,1518384623.659 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-02-11T21:30:24.041Z,1518384624.041 [BPC1](INFO): Stopped
2018-02-11T21:30:24.107Z,1518384624.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-02-11T21:30:24.455Z,1518384624.455 [BPC1](INFO): Stopped
2018-02-11T21:30:24.472Z,1518384624.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-02-11T21:30:24.852Z,1518384624.852 [BPC1](INFO): Stopped
2018-02-11T21:30:24.875Z,1518384624.875 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-02-11T21:30:25.234Z,1518384625.234 [BPC1](INFO): Stopped
2018-02-11T21:30:25.273Z,1518384625.273 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-02-11T21:30:25.644Z,1518384625.644 [BPC1](INFO): Stopped
2018-02-11T21:30:25.706Z,1518384625.706 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-02-11T21:30:26.047Z,1518384626.047 [BPC1](INFO): Stopped
2018-02-11T21:30:26.122Z,1518384626.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2018-02-11T21:30:26.447Z,1518384626.447 [BPC1](INFO): Stopped
2018-02-11T21:30:26.517Z,1518384626.517 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-02-11T21:30:26.844Z,1518384626.844 [BPC1](INFO): Stopped
2018-02-11T21:30:26.888Z,1518384626.888 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-02-11T21:30:27.236Z,1518384627.236 [BPC1](INFO): Stopped
2018-02-11T21:30:27.294Z,1518384627.294 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-02-11T21:30:27.638Z,1518384627.638 [BPC1](INFO): Stopped
2018-02-11T21:30:27.665Z,1518384627.665 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-02-11T21:30:28.032Z,1518384628.032 [BPC1](INFO): Stopped
2018-02-11T21:30:28.099Z,1518384628.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-02-11T21:30:28.431Z,1518384628.431 [BPC1](INFO): Stopped
2018-02-11T21:30:28.494Z,1518384628.494 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-02-11T21:30:28.859Z,1518384628.859 [BPC1](INFO): Stopped
2018-02-11T21:30:28.922Z,1518384628.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2018-02-11T21:30:29.254Z,1518384629.254 [BPC1](INFO): Stopped
2018-02-11T21:30:29.373Z,1518384629.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2018-02-11T21:30:29.643Z,1518384629.643 [BPC1](INFO): Stopped
2018-02-11T21:30:29.728Z,1518384629.728 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2018-02-11T21:30:30.038Z,1518384630.038 [BPC1](INFO): Stopped
2018-02-11T21:30:30.071Z,1518384630.071 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-02-11T21:30:30.434Z,1518384630.434 [BPC1](INFO): Stopped
2018-02-11T21:30:30.870Z,1518384630.870 [BPC1](INFO): Stopped
2018-02-11T21:30:31.237Z,1518384631.237 [BPC1](INFO): Stopped
2018-02-11T21:30:31.653Z,1518384631.653 [BPC1](INFO): Stopped
2018-02-11T21:30:32.043Z,1518384632.043 [BPC1](INFO): Stopped
2018-02-11T21:30:32.445Z,1518384632.445 [BPC1](INFO): Stopped
2018-02-11T21:30:32.845Z,1518384632.845 [BPC1](INFO): Stopped
2018-02-11T21:30:33.238Z,1518384633.238 [BPC1](INFO): Stopped
2018-02-11T21:30:33.640Z,1518384633.640 [BPC1](INFO): Stopped
2018-02-11T21:30:34.045Z,1518384634.045 [BPC1](INFO): Stopped
2018-02-11T21:30:34.439Z,1518384634.439 [BPC1](INFO): Stopped
2018-02-11T21:30:34.850Z,1518384634.850 [BPC1](INFO): Stopped
2018-02-11T21:30:35.251Z,1518384635.251 [BPC1](INFO): Stopped
2018-02-11T21:30:35.644Z,1518384635.644 [BPC1](INFO): Stopped
2018-02-11T21:30:36.039Z,1518384636.039 [BPC1](INFO): Stopped
2018-02-11T21:30:36.443Z,1518384636.443 [BPC1](INFO): Stopped
2018-02-11T21:30:36.841Z,1518384636.841 [BPC1](INFO): Stopped
2018-02-11T21:30:37.237Z,1518384637.237 [BPC1](INFO): Stopped
2018-02-11T21:30:37.635Z,1518384637.635 [BPC1](INFO): Stopped
2018-02-11T21:30:38.053Z,1518384638.053 [BPC1](INFO): Stopped
2018-02-11T21:30:38.447Z,1518384638.447 [BPC1](INFO): Stopped
2018-02-11T21:30:38.846Z,1518384638.846 [BPC1](INFO): Stopped
2018-02-11T21:30:39.239Z,1518384639.239 [BPC1](INFO): Stopped
2018-02-11T21:30:39.637Z,1518384639.637 [BPC1](INFO): Stopped
2018-02-11T21:30:40.080Z,1518384640.080 [BPC1](INFO): Stopped
2018-02-11T21:30:40.457Z,1518384640.457 [BPC1](INFO): Stopped
2018-02-11T21:30:40.881Z,1518384640.881 [BPC1](INFO): Stopped
2018-02-11T21:30:41.284Z,1518384641.284 [BPC1](INFO): Stopped
2018-02-11T21:30:41.665Z,1518384641.665 [BPC1](INFO): Stopped
2018-02-11T21:30:42.072Z,1518384642.072 [BPC1](INFO): Stopped
2018-02-11T21:30:42.490Z,1518384642.490 [BPC1](INFO): Stopped
2018-02-11T21:30:42.842Z,1518384642.842 [BPC1](INFO): Stopped
2018-02-11T21:30:43.142Z,1518384643.142 [CommandLine](IMPORTANT): got command restart application
2018-02-11T21:30:43.250Z,1518384643.250 [BPC1](INFO): Stopped
2018-02-11T21:30:43.634Z,1518384643.634 [BPC1](INFO): Stopped
2018-02-11T21:30:44.045Z,1518384644.045 [BPC1](INFO): Stopped
2018-02-11T21:30:44.152Z,1518384644.152 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:30:44.152Z,1518384644.152 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.192Z,1518384644.192 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-02-11T21:30:44.193Z,1518384644.193 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.193Z,1518384644.193 [CommandLine](INFO): Join timeout helper Thread ID is 1220
2018-02-11T21:30:44.194Z,1518384644.194 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-02-11T21:30:44.194Z,1518384644.194 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.194Z,1518384644.194 [NavChartDb](INFO): Join timeout helper Thread ID is 1221
2018-02-11T21:30:44.214Z,1518384644.214 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:30:44.214Z,1518384644.214 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.233Z,1518384644.233 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-02-11T21:30:44.233Z,1518384644.233 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.233Z,1518384644.233 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1222
2018-02-11T21:30:44.465Z,1518384644.465 [BPC1](INFO): Stopped
2018-02-11T21:30:44.477Z,1518384644.477 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:30:44.477Z,1518384644.477 [WetLabsBB2FL](INFO): Powering down
2018-02-11T21:30:44.478Z,1518384644.478 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.493Z,1518384644.493 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-02-11T21:30:44.493Z,1518384644.493 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.493Z,1518384644.493 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1223
2018-02-11T21:30:44.641Z,1518384644.641 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:30:44.728Z,1518384644.728 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-11T21:30:44.728Z,1518384644.728 [CTD_Seabird](INFO): Powering down
2018-02-11T21:30:44.729Z,1518384644.729 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.741Z,1518384644.741 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-02-11T21:30:44.741Z,1518384644.741 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.741Z,1518384644.741 [Radio_Surface](INFO): Join timeout helper Thread ID is 1225
2018-02-11T21:30:44.769Z,1518384644.769 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:30:44.769Z,1518384644.769 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.771Z,1518384644.771 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-02-11T21:30:44.771Z,1518384644.771 [logger ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.772Z,1518384644.772 [logger](INFO): Join timeout helper Thread ID is 1226
2018-02-11T21:30:44.772Z,1518384644.772 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:30:44.772Z,1518384644.772 [logger ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.774Z,1518384644.774 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-02-11T21:30:44.774Z,1518384644.774 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.774Z,1518384644.774 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-02-11T21:30:44.774Z,1518384644.774 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:44.775Z,1518384644.775 [controlThread](INFO): Join timeout helper Thread ID is 1227
2018-02-11T21:30:44.797Z,1518384644.797 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:30:44.797Z,1518384644.797 [controlThread](DEBUG): Uninitializing ControlThread
2018-02-11T21:30:44.798Z,1518384644.798 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-02-11T21:30:44.918Z,1518384644.918 [NAL9602](INFO): Powering down
2018-02-11T21:30:44.919Z,1518384644.919 [PNI_TCM](INFO): Powering down
2018-02-11T21:30:45.005Z,1518384645.005 [BPC1](INFO): uninitialize
2018-02-11T21:30:45.005Z,1518384645.005 [Aanderaa_O2](INFO): Powering down
2018-02-11T21:30:45.007Z,1518384645.007 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-02-11T21:30:45.008Z,1518384645.008 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-02-11T21:30:45.009Z,1518384645.009 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-02-11T21:30:45.009Z,1518384645.009 [MissionManager](INFO): Uninitializing Mission Default
2018-02-11T21:30:45.010Z,1518384645.010 [Default] Stopped
2018-02-11T21:30:45.010Z,1518384645.010 [Default](DEBUG): Aggregate::uninitialize Default
2018-02-11T21:30:45.010Z,1518384645.010 [Default:B.GoToSurface] Stopped
2018-02-11T21:30:45.010Z,1518384645.010 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-02-11T21:30:45.010Z,1518384645.010 [Default:CheckIn] Stopped
2018-02-11T21:30:45.010Z,1518384645.010 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-11T21:30:45.010Z,1518384645.010 [Default:CheckIn:Read_GPS] Stopped
2018-02-11T21:30:45.014Z,1518384645.014 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-02-11T21:30:45.014Z,1518384645.014 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-02-11T21:30:45.015Z,1518384645.015 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-02-11T21:30:45.015Z,1518384645.015 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-02-11T21:30:45.015Z,1518384645.015 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-11T21:30:45.015Z,1518384645.015 [BuoyancyServo](INFO): Powering down
2018-02-11T21:30:45.029Z,1518384645.029 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-02-11T21:30:45.029Z,1518384645.029 [ElevatorServo](INFO): Powering down
2018-02-11T21:30:45.030Z,1518384645.030 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-02-11T21:30:45.030Z,1518384645.030 [MassServo](INFO): Powering down
2018-02-11T21:30:45.031Z,1518384645.031 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-02-11T21:30:45.031Z,1518384645.031 [RudderServo](INFO): Powering down
2018-02-11T21:30:45.032Z,1518384645.032 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-02-11T21:30:45.032Z,1518384645.032 [ThrusterServo](INFO): Powering down
2018-02-11T21:30:45.033Z,1518384645.033 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-02-11T21:30:45.033Z,1518384645.033 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-02-11T21:30:45.033Z,1518384645.033 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-02-11T21:30:45.034Z,1518384645.034 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:45.136Z,1518384645.136 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:45.178Z,1518384645.178 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:45.181Z,1518384645.181 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:45.227Z,1518384645.227 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:30:45.278Z,1518384645.278 [logger ThreadHandler](INFO): Thread cancelled.