2018-02-11T21:05:31.392Z,1518383131.392 [Supervisor](DEBUG): Initializing supervisor.
2018-02-11T21:05:31.395Z,1518383131.395 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-02-11T21:05:31.396Z,1518383131.396 [SyncHandler](INFO): Protected caller Thread ID is 825
2018-02-11T21:05:31.396Z,1518383131.396 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-02-11T21:05:31.397Z,1518383131.397 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-02-11T21:05:31.397Z,1518383131.397 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 826
2018-02-11T21:05:31.400Z,1518383131.400 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-02-11T21:05:31.412Z,1518383131.412 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-02-11T21:05:31.413Z,1518383131.413 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-02-11T21:05:31.413Z,1518383131.413 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 827
2018-02-11T21:05:31.414Z,1518383131.414 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-02-11T21:05:31.415Z,1518383131.415 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-02-11T21:05:31.415Z,1518383131.415 [logger ThreadHandler](INFO): Protected caller Thread ID is 828
2018-02-11T21:05:31.417Z,1518383131.417 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-02-11T21:05:31.417Z,1518383131.417 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-02-11T21:05:31.419Z,1518383131.419 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-02-11T21:05:31.579Z,1518383131.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-02-11T21:05:31.579Z,1518383131.579 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-02-11T21:05:31.681Z,1518383131.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-02-11T21:05:31.682Z,1518383131.682 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-02-11T21:05:31.897Z,1518383131.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-02-11T21:05:31.898Z,1518383131.898 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-02-11T21:05:32.186Z,1518383132.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-02-11T21:05:32.186Z,1518383132.186 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-02-11T21:05:32.485Z,1518383132.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-02-11T21:05:32.486Z,1518383132.486 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-02-11T21:05:32.920Z,1518383132.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-02-11T21:05:32.920Z,1518383132.920 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-02-11T21:05:33.239Z,1518383133.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-02-11T21:05:33.240Z,1518383133.240 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-02-11T21:05:33.646Z,1518383133.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-02-11T21:05:33.647Z,1518383133.647 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-02-11T21:05:33.726Z,1518383133.726 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-02-11T21:05:34.001Z,1518383134.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-02-11T21:05:34.002Z,1518383134.002 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-02-11T21:05:34.139Z,1518383134.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-02-11T21:05:34.139Z,1518383134.139 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-02-11T21:05:34.331Z,1518383134.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-02-11T21:05:34.332Z,1518383134.332 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-02-11T21:05:34.528Z,1518383134.528 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-02-11T21:05:34.529Z,1518383134.529 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-02-11T21:05:34.624Z,1518383134.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-02-11T21:05:34.625Z,1518383134.625 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-02-11T21:05:34.762Z,1518383134.762 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-02-11T21:05:34.764Z,1518383134.764 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-02-11T21:05:34.765Z,1518383134.765 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-02-11T21:05:34.988Z,1518383134.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-02-11T21:05:34.988Z,1518383134.988 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-02-11T21:05:35.065Z,1518383135.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-02-11T21:05:35.200Z,1518383135.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-02-11T21:05:35.290Z,1518383135.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-02-11T21:05:35.373Z,1518383135.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-02-11T21:05:35.502Z,1518383135.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-02-11T21:05:35.658Z,1518383135.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-02-11T21:05:35.753Z,1518383135.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-02-11T21:05:35.843Z,1518383135.843 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-02-11T21:05:35.937Z,1518383135.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-02-11T21:05:36.020Z,1518383136.020 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-02-11T21:05:36.020Z,1518383136.020 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-02-11T21:05:36.034Z,1518383136.034 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-02-11T21:05:36.129Z,1518383136.129 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-02-11T21:05:36.130Z,1518383136.130 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-02-11T21:05:36.143Z,1518383136.143 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-02-11T21:05:36.144Z,1518383136.144 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-02-11T21:05:36.184Z,1518383136.184 [DepthRateCalculator] Loaded
2018-02-11T21:05:36.184Z,1518383136.184 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-02-11T21:05:36.190Z,1518383136.190 [PitchRateCalculator] Loaded
2018-02-11T21:05:36.190Z,1518383136.190 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-02-11T21:05:36.206Z,1518383136.206 [SpeedCalculator] Loaded
2018-02-11T21:05:36.206Z,1518383136.206 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-02-11T21:05:36.226Z,1518383136.226 [TempGradientCalculator] Loaded
2018-02-11T21:05:36.226Z,1518383136.226 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-02-11T21:05:36.241Z,1518383136.241 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-02-11T21:05:36.241Z,1518383136.241 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-02-11T21:05:36.247Z,1518383136.247 [YawRateCalculator] Loaded
2018-02-11T21:05:36.247Z,1518383136.247 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-02-11T21:05:36.285Z,1518383136.285 [ElevatorOffsetCalculator] Loaded
2018-02-11T21:05:36.285Z,1518383136.285 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-02-11T21:05:36.286Z,1518383136.286 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-02-11T21:05:36.286Z,1518383136.286 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-02-11T21:05:36.310Z,1518383136.310 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-02-11T21:05:36.311Z,1518383136.311 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-02-11T21:05:36.562Z,1518383136.562 [AcousticModem_Benthos_ATM900] Loaded
2018-02-11T21:05:36.562Z,1518383136.562 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-02-11T21:05:36.629Z,1518383136.629 [DataOverHttps] Loaded
2018-02-11T21:05:36.629Z,1518383136.629 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-02-11T21:05:36.642Z,1518383136.642 [Depth_Keller] Loaded
2018-02-11T21:05:36.642Z,1518383136.642 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-02-11T21:05:36.647Z,1518383136.647 [DropWeight] Loaded
2018-02-11T21:05:36.647Z,1518383136.647 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-02-11T21:05:36.741Z,1518383136.741 [NAL9602] Loaded
2018-02-11T21:05:36.741Z,1518383136.741 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-02-11T21:05:36.756Z,1518383136.756 [Onboard] Loaded
2018-02-11T21:05:36.757Z,1518383136.757 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-02-11T21:05:36.766Z,1518383136.766 [Radio_Surface] Loaded
2018-02-11T21:05:36.767Z,1518383136.767 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-02-11T21:05:36.768Z,1518383136.768 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A54E0
2018-02-11T21:05:36.768Z,1518383136.768 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 907
2018-02-11T21:05:36.813Z,1518383136.813 [PNI_TCM] Loaded
2018-02-11T21:05:36.814Z,1518383136.814 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-02-11T21:05:38.501Z,1518383138.501 [BPC1] Loaded
2018-02-11T21:05:38.502Z,1518383138.502 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-02-11T21:05:38.502Z,1518383138.502 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-02-11T21:05:38.502Z,1518383138.502 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-02-11T21:05:38.848Z,1518383138.848 [SBIT](DEBUG): Construct Startup Built In Test.
2018-02-11T21:05:38.870Z,1518383138.870 [SBIT] Loaded
2018-02-11T21:05:38.870Z,1518383138.870 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-02-11T21:05:38.871Z,1518383138.871 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-02-11T21:05:38.882Z,1518383138.882 [IBIT] Loaded
2018-02-11T21:05:38.882Z,1518383138.882 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-02-11T21:05:38.885Z,1518383138.885 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-02-11T21:05:39.159Z,1518383139.159 [CBIT] Loaded
2018-02-11T21:05:39.160Z,1518383139.160 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-02-11T21:05:39.160Z,1518383139.160 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-02-11T21:05:39.161Z,1518383139.161 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-02-11T21:05:39.502Z,1518383139.502 [Aanderaa_O2] Loaded
2018-02-11T21:05:39.503Z,1518383139.503 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-02-11T21:05:39.548Z,1518383139.548 [CTD_Seabird] Loaded
2018-02-11T21:05:39.549Z,1518383139.549 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-02-11T21:05:39.550Z,1518383139.550 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408654E0
2018-02-11T21:05:39.550Z,1518383139.550 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 908
2018-02-11T21:05:39.564Z,1518383139.564 [PAR_Licor] Loaded
2018-02-11T21:05:39.564Z,1518383139.564 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-02-11T21:05:39.611Z,1518383139.611 [WetLabsBB2FL] Loaded
2018-02-11T21:05:39.611Z,1518383139.611 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-02-11T21:05:39.612Z,1518383139.612 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408954E0
2018-02-11T21:05:39.612Z,1518383139.612 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 909
2018-02-11T21:05:39.613Z,1518383139.613 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-02-11T21:05:39.614Z,1518383139.614 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-02-11T21:05:39.845Z,1518383139.845 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-02-11T21:05:39.846Z,1518383139.846 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-02-11T21:05:39.912Z,1518383139.912 [VerticalControl](DEBUG): Construct VerticalControl.
2018-02-11T21:05:40.003Z,1518383140.003 [VerticalControl] Loaded
2018-02-11T21:05:40.003Z,1518383140.003 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-02-11T21:05:40.004Z,1518383140.004 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-02-11T21:05:40.062Z,1518383140.062 [HorizontalControl] Loaded
2018-02-11T21:05:40.063Z,1518383140.063 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-02-11T21:05:40.063Z,1518383140.063 [SpeedControl](DEBUG): Construct SpeedControl.
2018-02-11T21:05:40.068Z,1518383140.068 [SpeedControl] Loaded
2018-02-11T21:05:40.069Z,1518383140.069 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-02-11T21:05:40.069Z,1518383140.069 [LoopControl](DEBUG): Construct LoopControl.
2018-02-11T21:05:40.070Z,1518383140.070 [LoopControl] Loaded
2018-02-11T21:05:40.070Z,1518383140.070 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-02-11T21:05:40.070Z,1518383140.070 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-02-11T21:05:40.071Z,1518383140.071 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-02-11T21:05:40.151Z,1518383140.151 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-02-11T21:05:40.151Z,1518383140.151 [StratificationFrontDetector](DEBUG): (re)initializing
2018-02-11T21:05:40.152Z,1518383140.152 [StratificationFrontDetector] Loaded
2018-02-11T21:05:40.152Z,1518383140.152 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-02-11T21:05:40.153Z,1518383140.153 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-02-11T21:05:40.153Z,1518383140.153 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-02-11T21:05:40.248Z,1518383140.248 [BuoyancyServo] Loaded
2018-02-11T21:05:40.248Z,1518383140.248 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-02-11T21:05:40.259Z,1518383140.259 [ElevatorServo] Loaded
2018-02-11T21:05:40.260Z,1518383140.260 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-02-11T21:05:40.270Z,1518383140.270 [MassServo] Loaded
2018-02-11T21:05:40.271Z,1518383140.271 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-02-11T21:05:40.281Z,1518383140.281 [RudderServo] Loaded
2018-02-11T21:05:40.282Z,1518383140.282 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-02-11T21:05:40.292Z,1518383140.292 [ThrusterServo] Loaded
2018-02-11T21:05:40.292Z,1518383140.292 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-02-11T21:05:40.293Z,1518383140.293 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-02-11T21:05:40.293Z,1518383140.293 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-02-11T21:05:40.387Z,1518383140.387 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-02-11T21:05:40.387Z,1518383140.387 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-02-11T21:05:40.441Z,1518383140.441 [DeadReckonUsingSpeedCalculator] Loaded
2018-02-11T21:05:40.441Z,1518383140.441 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-02-11T21:05:40.457Z,1518383140.457 [NavChart] Loaded
2018-02-11T21:05:40.457Z,1518383140.457 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-02-11T21:05:40.461Z,1518383140.461 [UniversalFixResidualReporter] Loaded
2018-02-11T21:05:40.461Z,1518383140.461 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-02-11T21:05:40.462Z,1518383140.462 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-02-11T21:05:40.465Z,1518383140.465 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-02-11T21:05:40.466Z,1518383140.466 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-02-11T21:05:40.472Z,1518383140.472 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-02-11T21:05:40.473Z,1518383140.473 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409EA4E0
2018-02-11T21:05:40.474Z,1518383140.474 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 910
2018-02-11T21:05:40.478Z,1518383140.478 [Supervisor](INFO): Main Thread ID is 638
2018-02-11T21:05:40.478Z,1518383140.478 [Supervisor](DEBUG): Running supervisor.
2018-02-11T21:05:40.479Z,1518383140.479 [CommandLine ThreadHandler](INFO): Handler Thread ID is 911
2018-02-11T21:05:40.482Z,1518383140.482 [controlThread ThreadHandler](INFO): Handler Thread ID is 912
2018-02-11T21:05:40.482Z,1518383140.482 [controlThread](DEBUG): Initializing ControlThread
2018-02-11T21:05:40.483Z,1518383140.483 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-02-11T21:05:40.483Z,1518383140.483 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-02-11T21:05:40.484Z,1518383140.484 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-02-11T21:05:40.484Z,1518383140.484 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-02-11T21:05:40.485Z,1518383140.485 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-02-11T21:05:40.485Z,1518383140.485 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-02-11T21:05:40.486Z,1518383140.486 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-02-11T21:05:40.491Z,1518383140.491 [SBIT](INFO): Initialize SBIT Component.
2018-02-11T21:05:40.492Z,1518383140.492 [SBIT](IMPORTANT): git: 2018-02-09A
2018-02-11T21:05:40.492Z,1518383140.492 [SBIT](INFO): git hash: 30da1bf2046909e0dad541153321d39c5053adc1
2018-02-11T21:05:40.492Z,1518383140.492 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-02-11T21:05:40.494Z,1518383140.494 [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:05:40.495Z,1518383140.495 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2018-02-11T21:05:40.495Z,1518383140.495 [IBIT](INFO): Initialize IBIT Component.
2018-02-11T21:05:40.496Z,1518383140.496 [CBIT](DEBUG): Initialize CBIT Component.
2018-02-11T21:05:40.496Z,1518383140.496 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-02-11T21:05:40.497Z,1518383140.497 [logger ThreadHandler](INFO): Handler Thread ID is 913
2018-02-11T21:05:40.517Z,1518383140.517 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 914
2018-02-11T21:05:40.523Z,1518383140.523 [Radio_Surface](INFO): Powering up
2018-02-11T21:05:40.529Z,1518383140.529 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 915
2018-02-11T21:05:40.530Z,1518383140.530 [CTD_Seabird](INFO): Initializing
2018-02-11T21:05:40.530Z,1518383140.530 [CTD_Seabird](INFO): Checking LCM
2018-02-11T21:05:40.539Z,1518383140.539 [CTD_Seabird](INFO): LCM OK
2018-02-11T21:05:40.539Z,1518383140.539 [CTD_Seabird](INFO): Powering up
2018-02-11T21:05:40.545Z,1518383140.545 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 916
2018-02-11T21:05:40.547Z,1518383140.547 [WetLabsBB2FL](INFO): Powering down
2018-02-11T21:05:40.573Z,1518383140.573 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 917
2018-02-11T21:05:40.579Z,1518383140.579 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-02-11T21:05:40.579Z,1518383140.579 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-02-11T21:05:40.579Z,1518383140.579 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-02-11T21:05:40.580Z,1518383140.580 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-02-11T21:05:40.580Z,1518383140.580 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-02-11T21:05:40.580Z,1518383140.580 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-02-11T21:05:40.580Z,1518383140.580 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-02-11T21:05:40.580Z,1518383140.580 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-02-11T21:05:40.581Z,1518383140.581 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-02-11T21:05:40.581Z,1518383140.581 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-02-11T21:05:40.581Z,1518383140.581 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-02-11T21:05:40.581Z,1518383140.581 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-02-11T21:05:40.582Z,1518383140.582 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-02-11T21:05:40.582Z,1518383140.582 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-02-11T21:05:40.582Z,1518383140.582 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-02-11T21:05:40.582Z,1518383140.582 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-02-11T21:05:40.605Z,1518383140.605 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-02-11T21:05:40.607Z,1518383140.607 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-02-11T21:05:40.607Z,1518383140.607 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-02-11T21:05:40.608Z,1518383140.608 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-02-11T21:05:40.610Z,1518383140.610 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-02-11T21:05:40.610Z,1518383140.610 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:05:40.611Z,1518383140.611 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:05:40.611Z,1518383140.611 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-11T21:05:40.612Z,1518383140.612 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:05:40.612Z,1518383140.612 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:05:40.613Z,1518383140.613 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-02-11T21:05:40.613Z,1518383140.613 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-02-11T21:05:40.614Z,1518383140.614 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-02-11T21:05:40.639Z,1518383140.639 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-02-11T21:05:40.672Z,1518383140.672 [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:05:40.673Z,1518383140.673 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-02-11T21:05:40.739Z,1518383140.739 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-02-11T21:05:40.740Z,1518383140.740 [Default:A.Wait](DEBUG): Construct Wait.
2018-02-11T21:05:40.742Z,1518383140.742 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-02-11T21:05:40.779Z,1518383140.779 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-02-11T21:05:40.802Z,1518383140.802 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-02-11T21:05:40.807Z,1518383140.807 [Default:E.Execute](DEBUG): Construct Execute.
2018-02-11T21:05:40.827Z,1518383140.827 [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:05:40.832Z,1518383140.832 [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:05:40.853Z,1518383140.853 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-02-11T21:05:40.853Z,1518383140.853 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-02-11T21:05:40.879Z,1518383140.879 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-02-11T21:05:41.079Z,1518383141.079 [BPC1](INFO): Start
2018-02-11T21:05:41.349Z,1518383141.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:41.397Z,1518383141.397 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:41.489Z,1518383141.489 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:05:41.497Z,1518383141.497 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-11T21:05:41.519Z,1518383141.519 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:05:41.525Z,1518383141.525 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-02-11T21:05:41.538Z,1518383141.538 [MassServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:05:41.545Z,1518383141.545 [MassServo](DEBUG): Initializing MassServo.
2018-02-11T21:05:41.551Z,1518383141.551 [RudderServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:05:41.557Z,1518383141.557 [RudderServo](DEBUG): Initializing RudderServo.
2018-02-11T21:05:41.563Z,1518383141.563 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:05:41.569Z,1518383141.569 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-02-11T21:05:41.610Z,1518383141.610 [CommandLine](FAULT): Scheduling is paused
2018-02-11T21:05:41.610Z,1518383141.610 [CBIT](INFO): Critical error at 20180211T210540
2018-02-11T21:05:41.610Z,1518383141.610 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-02-11T21:05:41.709Z,1518383141.709 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-02-11T21:05:41.709Z,1518383141.709 [DropWeight] Hardware Fault, FailCount= 1
2018-02-11T21:05:41.709Z,1518383141.709 [DropWeight](ERROR): Hardware Fault
2018-02-11T21:05:41.737Z,1518383141.737 [BPC1](INFO): Starting
2018-02-11T21:05:41.759Z,1518383141.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:41.760Z,1518383141.760 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:41.777Z,1518383141.777 [CBIT](INFO): Critical error at 20180211T210541
2018-02-11T21:05:41.779Z,1518383141.779 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-02-11T21:05:41.780Z,1518383141.780 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-02-11T21:05:42.070Z,1518383142.070 [BPC1](INFO): Starting
2018-02-11T21:05:42.098Z,1518383142.098 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:42.099Z,1518383142.099 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:42.445Z,1518383142.445 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-02-11T21:05:42.445Z,1518383142.445 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-02-11T21:05:42.445Z,1518383142.445 [BuoyancyServo] Communications Fault, FailCount= 1
2018-02-11T21:05:42.445Z,1518383142.445 [BuoyancyServo](ERROR): Communications Fault
2018-02-11T21:05:42.654Z,1518383142.654 [CBIT](INFO): Critical error at 20180211T210541
2018-02-11T21:05:42.657Z,1518383142.657 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-02-11T21:05:42.697Z,1518383142.697 [BPC1](INFO): Starting
2018-02-11T21:05:42.723Z,1518383142.723 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:42.724Z,1518383142.724 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:42.745Z,1518383142.745 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-11T21:05:42.746Z,1518383142.746 [BuoyancyServo](INFO): Powering down
2018-02-11T21:05:43.097Z,1518383143.097 [BPC1](INFO): Starting
2018-02-11T21:05:43.125Z,1518383143.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:43.126Z,1518383143.126 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:43.486Z,1518383143.486 [BPC1](INFO): Starting
2018-02-11T21:05:43.517Z,1518383143.517 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:43.518Z,1518383143.518 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:43.887Z,1518383143.887 [BPC1](INFO): Starting
2018-02-11T21:05:43.887Z,1518383143.887 [BPC1](INFO): Initialized.
2018-02-11T21:05:43.920Z,1518383143.920 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:43.929Z,1518383143.929 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:44.297Z,1518383144.297 [BPC1](INFO): Runnable
2018-02-11T21:05:44.309Z,1518383144.309 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:05:44.321Z,1518383144.321 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:05:44.441Z,1518383144.441 [Aanderaa_O2](INFO): Powering down
2018-02-11T21:05:44.552Z,1518383144.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:44.553Z,1518383144.553 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-11T21:05:44.698Z,1518383144.698 [BPC1](INFO): Runnable
2018-02-11T21:05:44.810Z,1518383144.810 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-02-11T21:05:44.810Z,1518383144.810 [BuoyancyServo] No Fault, FailCount= 1
2018-02-11T21:05:45.094Z,1518383145.094 [BPC1](INFO): Runnable
2018-02-11T21:05:45.140Z,1518383145.140 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-11T21:05:45.258Z,1518383145.258 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-11T21:05:45.515Z,1518383145.515 [BPC1](INFO): Runnable
2018-02-11T21:05:45.967Z,1518383145.967 [BPC1](INFO): Runnable
2018-02-11T21:05:45.967Z,1518383145.967 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:05:45.968Z,1518383145.968 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:05:46.258Z,1518383146.258 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-11T21:05:46.360Z,1518383146.360 [BPC1](INFO): Runnable
2018-02-11T21:05:46.396Z,1518383146.396 [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:05:46.482Z,1518383146.482 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-02-11T21:05:46.809Z,1518383146.809 [BPC1](INFO): Runnable
2018-02-11T21:05:46.879Z,1518383146.879 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-11T21:05:46.879Z,1518383146.879 [CTD_Seabird](INFO): Powering down
2018-02-11T21:05:47.181Z,1518383147.181 [BPC1](INFO): Runnable
2018-02-11T21:05:47.576Z,1518383147.576 [BPC1](INFO): Runnable
2018-02-11T21:05:47.956Z,1518383147.956 [BPC1](INFO): Runnable
2018-02-11T21:05:48.320Z,1518383148.320 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-02-11T21:05:48.355Z,1518383148.355 [BPC1](INFO): Runnable
2018-02-11T21:05:48.791Z,1518383148.791 [BPC1](INFO): Runnable
2018-02-11T21:05:49.167Z,1518383149.167 [BPC1](INFO): Runnable
2018-02-11T21:05:49.523Z,1518383149.523 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-02-11T21:05:49.523Z,1518383149.523 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-02-11T21:05:49.524Z,1518383149.524 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-02-11T21:05:49.524Z,1518383149.524 [AcousticModem_Benthos_ATM900](DEBUG): Mar  4 2000 11:31:04 
2018-02-11T21:05:49.571Z,1518383149.571 [BPC1](INFO): Runnable
2018-02-11T21:05:49.571Z,1518383149.571 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:05:49.599Z,1518383149.599 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:05:49.600Z,1518383149.600 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:05:49.627Z,1518383149.627 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:05:49.922Z,1518383149.922 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-02-11T21:05:50.006Z,1518383150.006 [BPC1](INFO): Runnable
2018-02-11T21:05:50.017Z,1518383150.017 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:05:50.029Z,1518383150.029 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:05:50.377Z,1518383150.377 [BPC1](INFO): Runnable
2018-02-11T21:05:50.720Z,1518383150.720 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-02-11T21:05:50.769Z,1518383150.769 [BPC1](INFO): Runnable
2018-02-11T21:05:51.120Z,1518383151.120 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-02-11T21:05:51.121Z,1518383151.121 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-02-11T21:05:51.121Z,1518383151.121 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-02-11T21:05:51.169Z,1518383151.169 [BPC1](INFO): Runnable
2018-02-11T21:05:51.520Z,1518383151.520 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-02-11T21:05:51.578Z,1518383151.578 [BPC1](INFO): Runnable
2018-02-11T21:05:51.578Z,1518383151.578 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:05:51.921Z,1518383151.921 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-02-11T21:05:51.922Z,1518383151.922 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-02-11T21:05:51.961Z,1518383151.961 [BPC1](INFO): Runnable
2018-02-11T21:05:51.961Z,1518383151.961 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:05:52.321Z,1518383152.321 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-02-11T21:05:52.353Z,1518383152.353 [BPC1](INFO): Runnable
2018-02-11T21:05:52.772Z,1518383152.772 [BPC1](INFO): Runnable
2018-02-11T21:05:53.184Z,1518383153.184 [BPC1](INFO): Runnable
2018-02-11T21:05:53.561Z,1518383153.561 [BPC1](INFO): Runnable
2018-02-11T21:05:53.951Z,1518383153.951 [BPC1](INFO): Runnable
2018-02-11T21:05:54.383Z,1518383154.383 [BPC1](INFO): Runnable
2018-02-11T21:05:54.755Z,1518383154.755 [BPC1](INFO): Runnable
2018-02-11T21:05:55.174Z,1518383155.174 [BPC1](INFO): Runnable
2018-02-11T21:05:55.550Z,1518383155.550 [BPC1](INFO): Runnable
2018-02-11T21:05:55.551Z,1518383155.551 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:05:55.571Z,1518383155.571 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:05:55.969Z,1518383155.969 [BPC1](INFO): Runnable
2018-02-11T21:05:55.969Z,1518383155.969 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:05:55.993Z,1518383155.993 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:05:56.005Z,1518383156.005 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:05:56.367Z,1518383156.367 [BPC1](INFO): Runnable
2018-02-11T21:05:56.381Z,1518383156.381 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:05:56.761Z,1518383156.761 [BPC1](INFO): Runnable
2018-02-11T21:05:57.151Z,1518383157.151 [BPC1](INFO): Runnable
2018-02-11T21:05:57.552Z,1518383157.552 [BPC1](INFO): Runnable
2018-02-11T21:05:57.961Z,1518383157.961 [BPC1](INFO): Runnable
2018-02-11T21:05:58.361Z,1518383158.361 [BPC1](INFO): Runnable
2018-02-11T21:05:58.764Z,1518383158.764 [BPC1](INFO): Runnable
2018-02-11T21:05:59.151Z,1518383159.151 [BPC1](INFO): Runnable
2018-02-11T21:05:59.574Z,1518383159.574 [BPC1](INFO): Runnable
2018-02-11T21:05:59.973Z,1518383159.973 [BPC1](INFO): Runnable
2018-02-11T21:06:00.357Z,1518383160.357 [BPC1](INFO): Runnable
2018-02-11T21:06:00.752Z,1518383160.752 [BPC1](INFO): Runnable
2018-02-11T21:06:01.167Z,1518383161.167 [BPC1](INFO): Runnable
2018-02-11T21:06:01.561Z,1518383161.561 [BPC1](INFO): Runnable
2018-02-11T21:06:01.954Z,1518383161.954 [BPC1](INFO): Runnable
2018-02-11T21:06:02.351Z,1518383162.351 [BPC1](INFO): Runnable
2018-02-11T21:06:02.755Z,1518383162.755 [BPC1](INFO): Runnable
2018-02-11T21:06:03.149Z,1518383163.149 [BPC1](INFO): Runnable
2018-02-11T21:06:03.561Z,1518383163.561 [BPC1](INFO): Runnable
2018-02-11T21:06:03.562Z,1518383163.562 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:06:03.949Z,1518383163.949 [BPC1](INFO): Runnable
2018-02-11T21:06:03.949Z,1518383163.949 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:06:04.365Z,1518383164.365 [BPC1](INFO): Runnable
2018-02-11T21:06:04.751Z,1518383164.751 [BPC1](INFO): Runnable
2018-02-11T21:06:05.149Z,1518383165.149 [BPC1](INFO): Runnable
2018-02-11T21:06:05.551Z,1518383165.551 [BPC1](INFO): Runnable
2018-02-11T21:06:05.949Z,1518383165.949 [BPC1](INFO): Runnable
2018-02-11T21:06:06.357Z,1518383166.357 [BPC1](INFO): Runnable
2018-02-11T21:06:06.766Z,1518383166.766 [BPC1](INFO): Runnable
2018-02-11T21:06:07.126Z,1518383167.126 [NAL9602](INFO): Powering up NAL9602
2018-02-11T21:06:07.158Z,1518383167.158 [BPC1](INFO): Runnable
2018-02-11T21:06:07.554Z,1518383167.554 [BPC1](INFO): Runnable
2018-02-11T21:06:07.975Z,1518383167.975 [BPC1](INFO): Runnable
2018-02-11T21:06:07.975Z,1518383167.975 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:06:07.000Z,1518383168.000 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:06:08.361Z,1518383168.361 [BPC1](INFO): Runnable
2018-02-11T21:06:08.373Z,1518383168.373 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:06:08.750Z,1518383168.750 [BPC1](INFO): Runnable
2018-02-11T21:06:08.750Z,1518383168.750 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:06:08.781Z,1518383168.781 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:06:09.155Z,1518383169.155 [BPC1](INFO): Runnable
2018-02-11T21:06:09.169Z,1518383169.169 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:06:09.551Z,1518383169.551 [BPC1](INFO): Runnable
2018-02-11T21:06:09.951Z,1518383169.951 [BPC1](INFO): Runnable
2018-02-11T21:06:10.356Z,1518383170.356 [BPC1](INFO): Runnable
2018-02-11T21:06:10.779Z,1518383170.779 [BPC1](INFO): Runnable
2018-02-11T21:06:11.172Z,1518383171.172 [BPC1](INFO): Runnable
2018-02-11T21:06:11.550Z,1518383171.550 [BPC1](INFO): Runnable
2018-02-11T21:06:11.949Z,1518383171.949 [BPC1](INFO): Runnable
2018-02-11T21:06:12.350Z,1518383172.350 [BPC1](INFO): Runnable
2018-02-11T21:06:12.752Z,1518383172.752 [BPC1](INFO): Runnable
2018-02-11T21:06:13.150Z,1518383173.150 [BPC1](INFO): Runnable
2018-02-11T21:06:13.550Z,1518383173.550 [BPC1](INFO): Runnable
2018-02-11T21:06:13.950Z,1518383173.950 [BPC1](INFO): Runnable
2018-02-11T21:06:14.355Z,1518383174.355 [BPC1](INFO): Runnable
2018-02-11T21:06:14.756Z,1518383174.756 [BPC1](INFO): Runnable
2018-02-11T21:06:15.157Z,1518383175.157 [BPC1](INFO): Runnable
2018-02-11T21:06:15.557Z,1518383175.557 [BPC1](INFO): Runnable
2018-02-11T21:06:15.958Z,1518383175.958 [BPC1](INFO): Runnable
2018-02-11T21:06:15.958Z,1518383175.958 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:06:16.357Z,1518383176.357 [BPC1](INFO): Runnable
2018-02-11T21:06:16.357Z,1518383176.357 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:06:16.765Z,1518383176.765 [BPC1](INFO): Runnable
2018-02-11T21:06:17.159Z,1518383177.159 [BPC1](INFO): Runnable
2018-02-11T21:06:17.559Z,1518383177.559 [BPC1](INFO): Runnable
2018-02-11T21:06:17.968Z,1518383177.968 [BPC1](INFO): Runnable
2018-02-11T21:06:18.326Z,1518383178.326 [NAL9602](INFO): NAL9602 initialized
2018-02-11T21:06:18.350Z,1518383178.350 [BPC1](INFO): Runnable
2018-02-11T21:06:18.791Z,1518383178.791 [BPC1](INFO): Runnable
2018-02-11T21:06:19.193Z,1518383179.193 [BPC1](INFO): Runnable
2018-02-11T21:06:19.570Z,1518383179.570 [BPC1](INFO): Runnable
2018-02-11T21:06:20.010Z,1518383180.010 [BPC1](INFO): Runnable
2018-02-11T21:06:20.369Z,1518383180.369 [BPC1](INFO): Runnable
2018-02-11T21:06:20.369Z,1518383180.369 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:06:20.397Z,1518383180.397 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:06:20.758Z,1518383180.758 [BPC1](INFO): Runnable
2018-02-11T21:06:20.769Z,1518383180.769 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:06:21.167Z,1518383181.167 [BPC1](INFO): Runnable
2018-02-11T21:06:21.168Z,1518383181.168 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:06:21.183Z,1518383181.183 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:06:21.577Z,1518383181.577 [BPC1](INFO): Runnable
2018-02-11T21:06:21.589Z,1518383181.589 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:06:21.951Z,1518383181.951 [BPC1](INFO): Runnable
2018-02-11T21:06:22.351Z,1518383182.351 [BPC1](INFO): Runnable
2018-02-11T21:06:22.771Z,1518383182.771 [BPC1](INFO): Runnable
2018-02-11T21:06:23.150Z,1518383183.150 [BPC1](INFO): Runnable
2018-02-11T21:06:23.551Z,1518383183.551 [BPC1](INFO): Runnable
2018-02-11T21:06:23.978Z,1518383183.978 [BPC1](INFO): Runnable
2018-02-11T21:06:24.385Z,1518383184.385 [BPC1](INFO): Runnable
2018-02-11T21:06:24.751Z,1518383184.751 [BPC1](INFO): Runnable
2018-02-11T21:06:25.161Z,1518383185.161 [BPC1](INFO): Runnable
2018-02-11T21:06:25.558Z,1518383185.558 [BPC1](INFO): Runnable
2018-02-11T21:06:25.959Z,1518383185.959 [BPC1](INFO): Runnable
2018-02-11T21:06:26.377Z,1518383186.377 [BPC1](INFO): Runnable
2018-02-11T21:06:26.774Z,1518383186.774 [BPC1](INFO): Runnable
2018-02-11T21:06:27.173Z,1518383187.173 [BPC1](INFO): Runnable
2018-02-11T21:06:27.571Z,1518383187.571 [BPC1](INFO): Runnable
2018-02-11T21:06:27.957Z,1518383187.957 [BPC1](INFO): Runnable
2018-02-11T21:06:28.358Z,1518383188.358 [BPC1](INFO): Runnable
2018-02-11T21:06:28.358Z,1518383188.358 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:06:28.758Z,1518383188.758 [BPC1](INFO): Runnable
2018-02-11T21:06:29.158Z,1518383189.158 [BPC1](INFO): Runnable
2018-02-11T21:06:29.158Z,1518383189.158 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:06:29.559Z,1518383189.559 [BPC1](INFO): Runnable
2018-02-11T21:06:29.980Z,1518383189.980 [BPC1](INFO): Runnable
2018-02-11T21:06:30.370Z,1518383190.370 [BPC1](INFO): Runnable
2018-02-11T21:06:30.751Z,1518383190.751 [BPC1](INFO): Runnable
2018-02-11T21:06:31.150Z,1518383191.150 [BPC1](INFO): Runnable
2018-02-11T21:06:31.550Z,1518383191.550 [BPC1](INFO): Runnable
2018-02-11T21:06:31.963Z,1518383191.963 [BPC1](INFO): Runnable
2018-02-11T21:06:32.356Z,1518383192.356 [BPC1](INFO): Runnable
2018-02-11T21:06:32.361Z,1518383192.361 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:06:32.371Z,1518383192.371 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:06:32.761Z,1518383192.761 [BPC1](INFO): Runnable
2018-02-11T21:06:32.773Z,1518383192.773 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:06:33.158Z,1518383193.158 [BPC1](INFO): Runnable
2018-02-11T21:06:33.159Z,1518383193.159 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:06:33.173Z,1518383193.173 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:06:33.174Z,1518383193.174 [BPC1](DEBUG): Received data from all battery sticks
2018-02-11T21:06:33.239Z,1518383193.239 [SBIT](IMPORTANT): Beginning Startup BIT
2018-02-11T21:06:33.260Z,1518383193.260 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-11T21:06:33.552Z,1518383193.552 [BPC1](INFO): Stop
2018-02-11T21:06:33.552Z,1518383193.552 [BPC1](INFO): uninitialize
2018-02-11T21:06:33.951Z,1518383193.951 [BPC1](INFO): Stopping
2018-02-11T21:06:34.359Z,1518383194.359 [BPC1](INFO): Stopped
2018-02-11T21:06:34.768Z,1518383194.768 [BPC1](INFO): Start
2018-02-11T21:06:35.185Z,1518383195.185 [BPC1](INFO): Starting
2018-02-11T21:06:35.748Z,1518383195.748 [BPC1](INFO): Starting
2018-02-11T21:06:36.149Z,1518383196.149 [BPC1](INFO): Starting
2018-02-11T21:06:36.535Z,1518383196.535 [BPC1](INFO): Starting
2018-02-11T21:06:36.946Z,1518383196.946 [BPC1](INFO): Starting
2018-02-11T21:06:37.346Z,1518383197.346 [BPC1](INFO): Starting
2018-02-11T21:06:37.738Z,1518383197.738 [BPC1](INFO): Starting
2018-02-11T21:06:38.140Z,1518383198.140 [BPC1](INFO): Starting
2018-02-11T21:06:38.526Z,1518383198.526 [BPC1](INFO): Starting
2018-02-11T21:06:38.526Z,1518383198.526 [BPC1](INFO): Initialized.
2018-02-11T21:06:38.938Z,1518383198.938 [BPC1](INFO): Runnable
2018-02-11T21:06:38.949Z,1518383198.949 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:06:38.961Z,1518383198.961 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:06:39.326Z,1518383199.326 [BPC1](INFO): Runnable
2018-02-11T21:06:39.730Z,1518383199.730 [BPC1](INFO): Runnable
2018-02-11T21:06:40.126Z,1518383200.126 [BPC1](INFO): Runnable
2018-02-11T21:06:40.531Z,1518383200.531 [BPC1](INFO): Runnable
2018-02-11T21:06:40.930Z,1518383200.930 [BPC1](INFO): Runnable
2018-02-11T21:06:40.930Z,1518383200.930 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:06:41.330Z,1518383201.330 [BPC1](INFO): Runnable
2018-02-11T21:06:41.730Z,1518383201.730 [BPC1](INFO): Runnable
2018-02-11T21:06:42.126Z,1518383202.126 [BPC1](INFO): Runnable
2018-02-11T21:06:42.533Z,1518383202.533 [BPC1](INFO): Runnable
2018-02-11T21:06:42.931Z,1518383202.931 [BPC1](INFO): Runnable
2018-02-11T21:06:43.330Z,1518383203.330 [BPC1](INFO): Runnable
2018-02-11T21:06:43.734Z,1518383203.734 [BPC1](INFO): Runnable
2018-02-11T21:06:44.144Z,1518383204.144 [BPC1](INFO): Runnable
2018-02-11T21:06:44.145Z,1518383204.145 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:06:44.172Z,1518383204.172 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:06:44.267Z,1518383204.267 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.126416
CHAN A1 (24V): -0.011655
CHAN A2 (12V): -0.005585
CHAN A3 (5V): -0.002577
CHAN B0 (3.3V): -0.001305
CHAN B1 (3.15aV): -0.001500
CHAN B2 (3.15bV): -0.000955
CHAN B3 (GND): 0.000277
OPEN: 0.004780
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-11T21:06:44.541Z,1518383204.541 [BPC1](INFO): Runnable
2018-02-11T21:06:44.553Z,1518383204.553 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:06:44.936Z,1518383204.936 [BPC1](INFO): Runnable
2018-02-11T21:06:45.334Z,1518383205.334 [BPC1](INFO): Runnable
2018-02-11T21:06:45.734Z,1518383205.734 [BPC1](INFO): Runnable
2018-02-11T21:06:46.137Z,1518383206.137 [BPC1](INFO): Runnable
2018-02-11T21:06:46.137Z,1518383206.137 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:06:46.539Z,1518383206.539 [BPC1](INFO): Runnable
2018-02-11T21:06:46.928Z,1518383206.928 [BPC1](INFO): Runnable
2018-02-11T21:06:47.326Z,1518383207.326 [BPC1](INFO): Runnable
2018-02-11T21:06:47.727Z,1518383207.727 [BPC1](INFO): Runnable
2018-02-11T21:06:48.126Z,1518383208.126 [BPC1](INFO): Runnable
2018-02-11T21:06:48.548Z,1518383208.548 [BPC1](INFO): Runnable
2018-02-11T21:06:49.123Z,1518383209.123 [BPC1](INFO): Runnable
2018-02-11T21:06:49.518Z,1518383209.518 [BPC1](INFO): Runnable
2018-02-11T21:06:49.914Z,1518383209.914 [BPC1](INFO): Runnable
2018-02-11T21:06:49.915Z,1518383209.915 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:06:49.921Z,1518383209.921 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:06:50.318Z,1518383210.318 [BPC1](INFO): Runnable
2018-02-11T21:06:50.333Z,1518383210.333 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:06:50.719Z,1518383210.719 [BPC1](INFO): Runnable
2018-02-11T21:06:51.119Z,1518383211.119 [BPC1](INFO): Runnable
2018-02-11T21:06:51.522Z,1518383211.522 [BPC1](INFO): Runnable
2018-02-11T21:06:51.522Z,1518383211.522 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:06:51.923Z,1518383211.923 [BPC1](INFO): Runnable
2018-02-11T21:06:52.320Z,1518383212.320 [BPC1](INFO): Runnable
2018-02-11T21:06:52.320Z,1518383212.320 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:06:52.722Z,1518383212.722 [BPC1](INFO): Runnable
2018-02-11T21:06:53.122Z,1518383213.122 [BPC1](INFO): Runnable
2018-02-11T21:06:53.523Z,1518383213.523 [BPC1](INFO): Runnable
2018-02-11T21:06:53.925Z,1518383213.925 [BPC1](INFO): Runnable
2018-02-11T21:06:54.325Z,1518383214.325 [BPC1](INFO): Runnable
2018-02-11T21:06:54.723Z,1518383214.723 [BPC1](INFO): Runnable
2018-02-11T21:06:55.122Z,1518383215.122 [BPC1](INFO): Runnable
2018-02-11T21:06:55.523Z,1518383215.523 [BPC1](INFO): Runnable
2018-02-11T21:06:55.929Z,1518383215.929 [BPC1](INFO): Runnable
2018-02-11T21:06:56.333Z,1518383216.333 [BPC1](INFO): Runnable
2018-02-11T21:06:56.334Z,1518383216.334 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:06:56.341Z,1518383216.341 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:06:56.341Z,1518383216.341 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:06:56.348Z,1518383216.348 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:06:56.714Z,1518383216.714 [BPC1](INFO): Runnable
2018-02-11T21:06:56.729Z,1518383216.729 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:06:56.741Z,1518383216.741 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:06:57.118Z,1518383217.118 [BPC1](INFO): Runnable
2018-02-11T21:06:57.519Z,1518383217.519 [BPC1](INFO): Runnable
2018-02-11T21:06:57.914Z,1518383217.914 [BPC1](INFO): Runnable
2018-02-11T21:06:58.319Z,1518383218.319 [BPC1](INFO): Runnable
2018-02-11T21:06:58.725Z,1518383218.725 [BPC1](INFO): Runnable
2018-02-11T21:06:59.124Z,1518383219.124 [BPC1](INFO): Runnable
2018-02-11T21:06:59.526Z,1518383219.526 [BPC1](INFO): Runnable
2018-02-11T21:06:59.925Z,1518383219.925 [BPC1](INFO): Runnable
2018-02-11T21:07:00.315Z,1518383220.315 [BPC1](INFO): Runnable
2018-02-11T21:07:00.718Z,1518383220.718 [BPC1](INFO): Runnable
2018-02-11T21:07:01.119Z,1518383221.119 [BPC1](INFO): Runnable
2018-02-11T21:07:01.518Z,1518383221.518 [BPC1](INFO): Runnable
2018-02-11T21:07:01.919Z,1518383221.919 [BPC1](INFO): Runnable
2018-02-11T21:07:02.319Z,1518383222.319 [BPC1](INFO): Runnable
2018-02-11T21:07:02.718Z,1518383222.718 [BPC1](INFO): Runnable
2018-02-11T21:07:03.118Z,1518383223.118 [BPC1](INFO): Runnable
2018-02-11T21:07:03.514Z,1518383223.514 [BPC1](INFO): Runnable
2018-02-11T21:07:03.914Z,1518383223.914 [BPC1](INFO): Runnable
2018-02-11T21:07:03.915Z,1518383223.915 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:07:03.915Z,1518383223.915 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:07:04.323Z,1518383224.323 [BPC1](INFO): Runnable
2018-02-11T21:07:04.718Z,1518383224.718 [BPC1](INFO): Runnable
2018-02-11T21:07:05.121Z,1518383225.121 [BPC1](INFO): Runnable
2018-02-11T21:07:05.518Z,1518383225.518 [BPC1](INFO): Runnable
2018-02-11T21:07:05.922Z,1518383225.922 [BPC1](INFO): Runnable
2018-02-11T21:07:06.321Z,1518383226.321 [BPC1](INFO): Runnable
2018-02-11T21:07:06.721Z,1518383226.721 [BPC1](INFO): Runnable
2018-02-11T21:07:07.126Z,1518383227.126 [BPC1](INFO): Runnable
2018-02-11T21:07:07.492Z,1518383227.492 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:07:07.492Z,1518383227.492 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:07:07.541Z,1518383227.541 [BPC1](INFO): Runnable
2018-02-11T21:07:07.933Z,1518383227.933 [BPC1](INFO): Runnable
2018-02-11T21:07:08.328Z,1518383228.328 [BPC1](INFO): Runnable
2018-02-11T21:07:08.328Z,1518383228.328 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:07:08.335Z,1518383228.335 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:07:08.721Z,1518383228.721 [BPC1](INFO): Runnable
2018-02-11T21:07:08.733Z,1518383228.733 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:07:08.733Z,1518383228.733 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:07:08.740Z,1518383228.740 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:07:09.128Z,1518383229.128 [BPC1](INFO): Runnable
2018-02-11T21:07:09.141Z,1518383229.141 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:07:09.528Z,1518383229.528 [BPC1](INFO): Runnable
2018-02-11T21:07:09.926Z,1518383229.926 [BPC1](INFO): Runnable
2018-02-11T21:07:10.318Z,1518383230.318 [BPC1](INFO): Runnable
2018-02-11T21:07:10.715Z,1518383230.715 [BPC1](INFO): Runnable
2018-02-11T21:07:11.118Z,1518383231.118 [BPC1](INFO): Runnable
2018-02-11T21:07:11.513Z,1518383231.513 [BPC1](INFO): Runnable
2018-02-11T21:07:11.941Z,1518383231.941 [BPC1](INFO): Runnable
2018-02-11T21:07:12.336Z,1518383232.336 [BPC1](INFO): Runnable
2018-02-11T21:07:12.714Z,1518383232.714 [BPC1](INFO): Runnable
2018-02-11T21:07:13.141Z,1518383233.141 [BPC1](INFO): Runnable
2018-02-11T21:07:13.542Z,1518383233.542 [BPC1](INFO): Runnable
2018-02-11T21:07:13.926Z,1518383233.926 [BPC1](INFO): Runnable
2018-02-11T21:07:14.321Z,1518383234.321 [BPC1](INFO): Runnable
2018-02-11T21:07:14.734Z,1518383234.734 [BPC1](INFO): Runnable
2018-02-11T21:07:15.119Z,1518383235.119 [BPC1](INFO): Runnable
2018-02-11T21:07:15.524Z,1518383235.524 [BPC1](INFO): Runnable
2018-02-11T21:07:16.072Z,1518383236.072 [BPC1](INFO): Runnable
2018-02-11T21:07:16.073Z,1518383236.073 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:07:16.476Z,1518383236.476 [BPC1](INFO): Runnable
2018-02-11T21:07:16.511Z,1518383236.511 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:07:16.889Z,1518383236.889 [BPC1](INFO): Runnable
2018-02-11T21:07:17.274Z,1518383237.274 [BPC1](INFO): Runnable
2018-02-11T21:07:17.680Z,1518383237.680 [BPC1](INFO): Runnable
2018-02-11T21:07:18.083Z,1518383238.083 [BPC1](INFO): Runnable
2018-02-11T21:07:18.485Z,1518383238.485 [BPC1](INFO): Runnable
2018-02-11T21:07:18.884Z,1518383238.884 [BPC1](INFO): Runnable
2018-02-11T21:07:19.283Z,1518383239.283 [BPC1](INFO): Runnable
2018-02-11T21:07:19.680Z,1518383239.680 [BPC1](INFO): Runnable
2018-02-11T21:07:20.089Z,1518383240.089 [BPC1](INFO): Runnable
2018-02-11T21:07:20.481Z,1518383240.481 [BPC1](INFO): Runnable
2018-02-11T21:07:20.879Z,1518383240.879 [BPC1](INFO): Runnable
2018-02-11T21:07:20.879Z,1518383240.879 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:07:20.891Z,1518383240.891 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:07:21.266Z,1518383241.266 [BPC1](INFO): Runnable
2018-02-11T21:07:21.281Z,1518383241.281 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:07:21.281Z,1518383241.281 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:07:21.296Z,1518383241.296 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:07:21.677Z,1518383241.677 [BPC1](INFO): Runnable
2018-02-11T21:07:21.693Z,1518383241.693 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:07:22.097Z,1518383242.097 [BPC1](INFO): Runnable
2018-02-11T21:07:22.473Z,1518383242.473 [BPC1](INFO): Runnable
2018-02-11T21:07:22.871Z,1518383242.871 [BPC1](INFO): Runnable
2018-02-11T21:07:23.271Z,1518383243.271 [BPC1](INFO): Runnable
2018-02-11T21:07:23.674Z,1518383243.674 [BPC1](INFO): Runnable
2018-02-11T21:07:24.074Z,1518383244.074 [BPC1](INFO): Runnable
2018-02-11T21:07:24.474Z,1518383244.474 [BPC1](INFO): Runnable
2018-02-11T21:07:24.878Z,1518383244.878 [BPC1](INFO): Runnable
2018-02-11T21:07:25.274Z,1518383245.274 [BPC1](INFO): Runnable
2018-02-11T21:07:25.677Z,1518383245.677 [BPC1](INFO): Runnable
2018-02-11T21:07:26.086Z,1518383246.086 [BPC1](INFO): Runnable
2018-02-11T21:07:26.465Z,1518383246.465 [BPC1](INFO): Runnable
2018-02-11T21:07:26.617Z,1518383246.617 [CommandLine](IMPORTANT): got command report mod platform_battery_voltage
2018-02-11T21:07:26.878Z,1518383246.878 [BPC1](INFO): Runnable
2018-02-11T21:07:26.924Z,1518383246.924 [Reporter](INFO): platform_battery_voltage 15.354548 _
2018-02-11T21:07:27.274Z,1518383247.274 [BPC1](INFO): Runnable
2018-02-11T21:07:27.325Z,1518383247.325 [SBIT](IMPORTANT): SBIT PASSED
2018-02-11T21:07:27.678Z,1518383247.678 [BPC1](INFO): Runnable
2018-02-11T21:07:27.694Z,1518383247.694 [MissionManager](IMPORTANT): Started mission Startup
2018-02-11T21:07:27.694Z,1518383247.694 [Startup] Running Loop=1
2018-02-11T21:07:27.694Z,1518383247.694 [Startup](DEBUG): Aggregate::initialize Startup
2018-02-11T21:07:27.694Z,1518383247.694 [Startup:A.GoToSurface] Running Loop=1
2018-02-11T21:07:27.695Z,1518383247.695 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-02-11T21:07:27.695Z,1518383247.695 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-02-11T21:07:27.696Z,1518383247.696 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-02-11T21:07:27.696Z,1518383247.696 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-02-11T21:07:27.696Z,1518383247.696 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-02-11T21:07:27.709Z,1518383247.709 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-02-11T21:07:27.714Z,1518383247.714 [Startup:StartupSatComms] Running Loop=1
2018-02-11T21:07:27.714Z,1518383247.714 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-02-11T21:07:27.715Z,1518383247.715 [Startup:StartupSatComms:A] Running Loop=1
2018-02-11T21:07:28.081Z,1518383248.081 [BPC1](INFO): Runnable
2018-02-11T21:07:28.092Z,1518383248.092 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-02-11T21:07:28.482Z,1518383248.482 [BPC1](INFO): Runnable
2018-02-11T21:07:28.876Z,1518383248.876 [BPC1](INFO): Runnable
2018-02-11T21:07:28.876Z,1518383248.876 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:07:29.275Z,1518383249.275 [BPC1](INFO): Runnable
2018-02-11T21:07:29.275Z,1518383249.275 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:07:29.667Z,1518383249.667 [BPC1](INFO): Runnable
2018-02-11T21:07:30.067Z,1518383250.067 [BPC1](INFO): Runnable
2018-02-11T21:07:30.489Z,1518383250.489 [BPC1](INFO): Runnable
2018-02-11T21:07:30.892Z,1518383250.892 [BPC1](INFO): Runnable
2018-02-11T21:07:31.266Z,1518383251.266 [BPC1](INFO): Runnable
2018-02-11T21:07:31.666Z,1518383251.666 [BPC1](INFO): Runnable
2018-02-11T21:07:32.069Z,1518383252.069 [BPC1](INFO): Runnable
2018-02-11T21:07:32.470Z,1518383252.470 [BPC1](INFO): Runnable
2018-02-11T21:07:32.470Z,1518383252.470 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:07:32.476Z,1518383252.476 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:07:32.875Z,1518383252.875 [BPC1](INFO): Runnable
2018-02-11T21:07:32.893Z,1518383252.893 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:07:33.270Z,1518383253.270 [BPC1](INFO): Runnable
2018-02-11T21:07:33.270Z,1518383253.270 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:07:33.276Z,1518383253.276 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:07:33.277Z,1518383253.277 [BPC1](DEBUG): Received data from all battery sticks
2018-02-11T21:07:33.678Z,1518383253.678 [BPC1](INFO): Stop
2018-02-11T21:07:33.678Z,1518383253.678 [BPC1](INFO): uninitialize
2018-02-11T21:07:34.074Z,1518383254.074 [BPC1](INFO): Stopping
2018-02-11T21:07:34.476Z,1518383254.476 [BPC1](INFO): Stopped
2018-02-11T21:07:34.878Z,1518383254.878 [BPC1](INFO): Start
2018-02-11T21:07:35.273Z,1518383255.273 [BPC1](INFO): Starting
2018-02-11T21:07:35.677Z,1518383255.677 [BPC1](INFO): Starting
2018-02-11T21:07:36.074Z,1518383256.074 [BPC1](INFO): Starting
2018-02-11T21:07:36.474Z,1518383256.474 [BPC1](INFO): Starting
2018-02-11T21:07:36.869Z,1518383256.869 [BPC1](INFO): Starting
2018-02-11T21:07:37.277Z,1518383257.277 [BPC1](INFO): Starting
2018-02-11T21:07:37.667Z,1518383257.667 [BPC1](INFO): Starting
2018-02-11T21:07:38.077Z,1518383258.077 [BPC1](INFO): Starting
2018-02-11T21:07:38.466Z,1518383258.466 [BPC1](INFO): Starting
2018-02-11T21:07:38.889Z,1518383258.889 [BPC1](INFO): Starting
2018-02-11T21:07:38.889Z,1518383258.889 [BPC1](INFO): Initialized.
2018-02-11T21:07:38.917Z,1518383258.917 [Reporter](INFO): platform_battery_voltage 15.376936 _
2018-02-11T21:07:39.292Z,1518383259.292 [BPC1](INFO): Runnable
2018-02-11T21:07:39.305Z,1518383259.305 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:07:39.317Z,1518383259.317 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:07:39.671Z,1518383259.671 [BPC1](INFO): Runnable
2018-02-11T21:07:40.067Z,1518383260.067 [BPC1](INFO): Runnable
2018-02-11T21:07:40.470Z,1518383260.470 [BPC1](INFO): Runnable
2018-02-11T21:07:40.870Z,1518383260.870 [BPC1](INFO): Runnable
2018-02-11T21:07:41.275Z,1518383261.275 [BPC1](INFO): Runnable
2018-02-11T21:07:41.275Z,1518383261.275 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:07:41.675Z,1518383261.675 [BPC1](INFO): Runnable
2018-02-11T21:07:42.077Z,1518383262.077 [BPC1](INFO): Runnable
2018-02-11T21:07:42.470Z,1518383262.470 [BPC1](INFO): Runnable
2018-02-11T21:07:42.887Z,1518383262.887 [BPC1](INFO): Runnable
2018-02-11T21:07:43.274Z,1518383263.274 [BPC1](INFO): Runnable
2018-02-11T21:07:43.674Z,1518383263.674 [BPC1](INFO): Runnable
2018-02-11T21:07:44.080Z,1518383264.080 [BPC1](INFO): Runnable
2018-02-11T21:07:44.482Z,1518383264.482 [BPC1](INFO): Runnable
2018-02-11T21:07:44.483Z,1518383264.483 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:07:44.489Z,1518383264.489 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:07:44.877Z,1518383264.877 [BPC1](INFO): Runnable
2018-02-11T21:07:44.889Z,1518383264.889 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:07:45.266Z,1518383265.266 [BPC1](INFO): Runnable
2018-02-11T21:07:45.679Z,1518383265.679 [BPC1](INFO): Runnable
2018-02-11T21:07:46.066Z,1518383266.066 [BPC1](INFO): Runnable
2018-02-11T21:07:46.470Z,1518383266.470 [BPC1](INFO): Runnable
2018-02-11T21:07:46.471Z,1518383266.471 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:07:46.471Z,1518383266.471 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:07:46.870Z,1518383266.870 [BPC1](INFO): Runnable
2018-02-11T21:07:47.271Z,1518383267.271 [BPC1](INFO): Runnable
2018-02-11T21:07:47.673Z,1518383267.673 [BPC1](INFO): Runnable
2018-02-11T21:07:48.077Z,1518383268.077 [BPC1](INFO): Runnable
2018-02-11T21:07:48.474Z,1518383268.474 [BPC1](INFO): Runnable
2018-02-11T21:07:48.877Z,1518383268.877 [BPC1](INFO): Runnable
2018-02-11T21:07:49.276Z,1518383269.276 [BPC1](INFO): Runnable
2018-02-11T21:07:49.674Z,1518383269.674 [BPC1](INFO): Runnable
2018-02-11T21:07:50.081Z,1518383270.081 [BPC1](INFO): Runnable
2018-02-11T21:07:50.477Z,1518383270.477 [BPC1](INFO): Runnable
2018-02-11T21:07:50.478Z,1518383270.478 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:07:50.483Z,1518383270.483 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:07:50.874Z,1518383270.874 [BPC1](INFO): Runnable
2018-02-11T21:07:50.889Z,1518383270.889 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:07:50.889Z,1518383270.889 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:07:50.896Z,1518383270.896 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:07:51.281Z,1518383271.281 [BPC1](INFO): Runnable
2018-02-11T21:07:51.293Z,1518383271.293 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:07:51.679Z,1518383271.679 [BPC1](INFO): Runnable
2018-02-11T21:07:52.067Z,1518383272.067 [BPC1](INFO): Runnable
2018-02-11T21:07:52.466Z,1518383272.466 [BPC1](INFO): Runnable
2018-02-11T21:07:52.866Z,1518383272.866 [BPC1](INFO): Runnable
2018-02-11T21:07:52.866Z,1518383272.866 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:07:53.266Z,1518383273.266 [BPC1](INFO): Runnable
2018-02-11T21:07:53.678Z,1518383273.678 [BPC1](INFO): Runnable
2018-02-11T21:07:54.081Z,1518383274.081 [BPC1](INFO): Runnable
2018-02-11T21:07:54.466Z,1518383274.466 [BPC1](INFO): Runnable
2018-02-11T21:07:54.870Z,1518383274.870 [BPC1](INFO): Runnable
2018-02-11T21:07:55.270Z,1518383275.270 [BPC1](INFO): Runnable
2018-02-11T21:07:55.666Z,1518383275.666 [BPC1](INFO): Runnable
2018-02-11T21:07:56.070Z,1518383276.070 [BPC1](INFO): Runnable
2018-02-11T21:07:56.474Z,1518383276.474 [BPC1](INFO): Runnable
2018-02-11T21:07:56.875Z,1518383276.875 [BPC1](INFO): Runnable
2018-02-11T21:07:56.875Z,1518383276.875 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:07:56.882Z,1518383276.882 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:07:57.273Z,1518383277.273 [BPC1](INFO): Runnable
2018-02-11T21:07:57.285Z,1518383277.285 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:07:57.674Z,1518383277.674 [BPC1](INFO): Runnable
2018-02-11T21:07:58.073Z,1518383278.073 [BPC1](INFO): Runnable
2018-02-11T21:07:58.469Z,1518383278.469 [BPC1](INFO): Runnable
2018-02-11T21:07:58.875Z,1518383278.875 [BPC1](INFO): Runnable
2018-02-11T21:07:58.876Z,1518383278.876 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:07:59.274Z,1518383279.274 [BPC1](INFO): Runnable
2018-02-11T21:07:59.678Z,1518383279.678 [BPC1](INFO): Runnable
2018-02-11T21:08:00.073Z,1518383280.073 [BPC1](INFO): Runnable
2018-02-11T21:08:00.474Z,1518383280.474 [BPC1](INFO): Runnable
2018-02-11T21:08:00.881Z,1518383280.881 [BPC1](INFO): Runnable
2018-02-11T21:08:01.243Z,1518383281.243 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:08:01.244Z,1518383281.244 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:08:01.267Z,1518383281.267 [BPC1](INFO): Runnable
2018-02-11T21:08:01.689Z,1518383281.689 [BPC1](INFO): Runnable
2018-02-11T21:08:02.066Z,1518383282.066 [BPC1](INFO): Runnable
2018-02-11T21:08:02.467Z,1518383282.467 [BPC1](INFO): Runnable
2018-02-11T21:08:02.872Z,1518383282.872 [BPC1](INFO): Runnable
2018-02-11T21:08:03.274Z,1518383283.274 [BPC1](INFO): Runnable
2018-02-11T21:08:03.673Z,1518383283.673 [BPC1](INFO): Runnable
2018-02-11T21:08:03.674Z,1518383283.674 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:08:03.680Z,1518383283.681 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:08:04.074Z,1518383284.074 [BPC1](INFO): Runnable
2018-02-11T21:08:04.085Z,1518383284.085 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:08:04.477Z,1518383284.477 [BPC1](INFO): Runnable
2018-02-11T21:08:04.869Z,1518383284.869 [BPC1](INFO): Runnable
2018-02-11T21:08:04.869Z,1518383284.869 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:08:05.277Z,1518383285.277 [BPC1](INFO): Runnable
2018-02-11T21:08:05.683Z,1518383285.683 [BPC1](INFO): Runnable
2018-02-11T21:08:06.076Z,1518383286.076 [BPC1](INFO): Runnable
2018-02-11T21:08:06.476Z,1518383286.476 [BPC1](INFO): Runnable
2018-02-11T21:08:06.867Z,1518383286.867 [BPC1](INFO): Runnable
2018-02-11T21:08:07.266Z,1518383287.266 [BPC1](INFO): Runnable
2018-02-11T21:08:07.667Z,1518383287.667 [BPC1](INFO): Runnable
2018-02-11T21:08:08.067Z,1518383288.067 [BPC1](INFO): Runnable
2018-02-11T21:08:08.468Z,1518383288.468 [BPC1](INFO): Runnable
2018-02-11T21:08:08.871Z,1518383288.871 [BPC1](INFO): Runnable
2018-02-11T21:08:09.271Z,1518383289.271 [BPC1](INFO): Runnable
2018-02-11T21:08:09.272Z,1518383289.272 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:08:09.278Z,1518383289.278 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:08:09.666Z,1518383289.666 [BPC1](INFO): Runnable
2018-02-11T21:08:09.677Z,1518383289.677 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:08:10.066Z,1518383290.066 [BPC1](INFO): Runnable
2018-02-11T21:08:10.470Z,1518383290.470 [BPC1](INFO): Runnable
2018-02-11T21:08:10.873Z,1518383290.873 [BPC1](INFO): Runnable
2018-02-11T21:08:11.270Z,1518383291.270 [BPC1](INFO): Runnable
2018-02-11T21:08:11.675Z,1518383291.675 [BPC1](INFO): Runnable
2018-02-11T21:08:11.676Z,1518383291.676 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:08:12.073Z,1518383292.073 [BPC1](INFO): Runnable
2018-02-11T21:08:12.473Z,1518383292.473 [BPC1](INFO): Runnable
2018-02-11T21:08:12.876Z,1518383292.876 [BPC1](INFO): Runnable
2018-02-11T21:08:13.275Z,1518383293.275 [BPC1](INFO): Runnable
2018-02-11T21:08:13.675Z,1518383293.675 [BPC1](INFO): Runnable
2018-02-11T21:08:14.079Z,1518383294.079 [BPC1](INFO): Runnable
2018-02-11T21:08:14.473Z,1518383294.473 [BPC1](INFO): Runnable
2018-02-11T21:08:14.877Z,1518383294.877 [BPC1](INFO): Runnable
2018-02-11T21:08:15.275Z,1518383295.275 [BPC1](INFO): Runnable
2018-02-11T21:08:15.680Z,1518383295.680 [BPC1](INFO): Runnable
2018-02-11T21:08:16.074Z,1518383296.074 [BPC1](INFO): Runnable
2018-02-11T21:08:16.075Z,1518383296.075 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:08:16.082Z,1518383296.082 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:08:16.466Z,1518383296.466 [BPC1](INFO): Runnable
2018-02-11T21:08:16.477Z,1518383296.477 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:08:16.866Z,1518383296.866 [BPC1](INFO): Runnable
2018-02-11T21:08:16.866Z,1518383296.866 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:08:17.266Z,1518383297.266 [BPC1](INFO): Runnable
2018-02-11T21:08:17.666Z,1518383297.666 [BPC1](INFO): Runnable
2018-02-11T21:08:18.071Z,1518383298.071 [BPC1](INFO): Runnable
2018-02-11T21:08:18.443Z,1518383298.443 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:08:18.443Z,1518383298.443 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:08:18.468Z,1518383298.468 [BPC1](INFO): Runnable
2018-02-11T21:08:18.888Z,1518383298.888 [BPC1](INFO): Runnable
2018-02-11T21:08:19.266Z,1518383299.266 [BPC1](INFO): Runnable
2018-02-11T21:08:19.667Z,1518383299.667 [BPC1](INFO): Runnable
2018-02-11T21:08:20.067Z,1518383300.067 [BPC1](INFO): Runnable
2018-02-11T21:08:20.467Z,1518383300.467 [BPC1](INFO): Runnable
2018-02-11T21:08:20.871Z,1518383300.871 [BPC1](INFO): Runnable
2018-02-11T21:08:21.270Z,1518383301.270 [BPC1](INFO): Runnable
2018-02-11T21:08:21.670Z,1518383301.670 [BPC1](INFO): Runnable
2018-02-11T21:08:21.670Z,1518383301.670 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:08:21.677Z,1518383301.677 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:08:22.073Z,1518383302.073 [BPC1](INFO): Runnable
2018-02-11T21:08:22.086Z,1518383302.086 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:08:22.477Z,1518383302.477 [BPC1](INFO): Runnable
2018-02-11T21:08:22.889Z,1518383302.889 [BPC1](INFO): Runnable
2018-02-11T21:08:23.274Z,1518383303.274 [BPC1](INFO): Runnable
2018-02-11T21:08:23.676Z,1518383303.676 [BPC1](INFO): Runnable
2018-02-11T21:08:24.077Z,1518383304.077 [BPC1](INFO): Runnable
2018-02-11T21:08:24.077Z,1518383304.077 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:08:24.474Z,1518383304.474 [BPC1](INFO): Runnable
2018-02-11T21:08:24.878Z,1518383304.878 [BPC1](INFO): Runnable
2018-02-11T21:08:25.281Z,1518383305.281 [BPC1](INFO): Runnable
2018-02-11T21:08:25.675Z,1518383305.675 [BPC1](INFO): Runnable
2018-02-11T21:08:26.073Z,1518383306.073 [BPC1](INFO): Runnable
2018-02-11T21:08:26.477Z,1518383306.477 [BPC1](INFO): Runnable
2018-02-11T21:08:26.874Z,1518383306.874 [BPC1](INFO): Runnable
2018-02-11T21:08:27.281Z,1518383307.281 [BPC1](INFO): Runnable
2018-02-11T21:08:27.675Z,1518383307.675 [BPC1](INFO): Runnable
2018-02-11T21:08:28.073Z,1518383308.073 [BPC1](INFO): Runnable
2018-02-11T21:08:28.074Z,1518383308.074 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:08:28.084Z,1518383308.084 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:08:28.111Z,1518383308.111 [Startup:StartupSatComms:A](INFO): Timed out from 2018-02-11T21:07:27.7Z
2018-02-11T21:08:28.111Z,1518383308.111 [Startup:StartupSatComms:A] Stopped
2018-02-11T21:08:28.111Z,1518383308.111 [Startup:StartupSatComms:B] Running Loop=1
2018-02-11T21:08:28.466Z,1518383308.466 [BPC1](INFO): Runnable
2018-02-11T21:08:28.481Z,1518383308.481 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:08:28.518Z,1518383308.518 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-02-11T21:08:28.870Z,1518383308.870 [BPC1](INFO): Runnable
2018-02-11T21:08:29.266Z,1518383309.266 [BPC1](INFO): Runnable
2018-02-11T21:08:29.671Z,1518383309.671 [BPC1](INFO): Runnable
2018-02-11T21:08:29.671Z,1518383309.671 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:08:30.066Z,1518383310.066 [BPC1](INFO): Runnable
2018-02-11T21:08:30.466Z,1518383310.466 [BPC1](INFO): Runnable
2018-02-11T21:08:30.467Z,1518383310.467 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:08:30.873Z,1518383310.873 [BPC1](INFO): Runnable
2018-02-11T21:08:31.275Z,1518383311.275 [BPC1](INFO): Runnable
2018-02-11T21:08:31.674Z,1518383311.674 [BPC1](INFO): Runnable
2018-02-11T21:08:32.073Z,1518383312.073 [BPC1](INFO): Runnable
2018-02-11T21:08:32.444Z,1518383312.444 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:08:32.444Z,1518383312.444 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:08:32.490Z,1518383312.490 [BPC1](INFO): Runnable
2018-02-11T21:08:32.885Z,1518383312.885 [BPC1](INFO): Runnable
2018-02-11T21:08:33.277Z,1518383313.277 [BPC1](INFO): Runnable
2018-02-11T21:08:33.675Z,1518383313.675 [BPC1](INFO): Runnable
2018-02-11T21:08:33.675Z,1518383313.675 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:08:33.689Z,1518383313.689 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:08:33.690Z,1518383313.690 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:08:33.713Z,1518383313.713 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:08:33.713Z,1518383313.713 [BPC1](DEBUG): Received data from all battery sticks
2018-02-11T21:08:34.066Z,1518383314.066 [BPC1](INFO): Stop
2018-02-11T21:08:34.066Z,1518383314.066 [BPC1](INFO): uninitialize
2018-02-11T21:08:34.467Z,1518383314.467 [BPC1](INFO): Stopping
2018-02-11T21:08:34.866Z,1518383314.866 [BPC1](INFO): Stopped
2018-02-11T21:08:35.265Z,1518383315.265 [BPC1](INFO): Start
2018-02-11T21:08:35.670Z,1518383315.670 [BPC1](INFO): Starting
2018-02-11T21:08:36.070Z,1518383316.070 [BPC1](INFO): Starting
2018-02-11T21:08:36.466Z,1518383316.466 [BPC1](INFO): Starting
2018-02-11T21:08:36.888Z,1518383316.888 [BPC1](INFO): Starting
2018-02-11T21:08:37.285Z,1518383317.285 [BPC1](INFO): Starting
2018-02-11T21:08:37.666Z,1518383317.666 [BPC1](INFO): Starting
2018-02-11T21:08:38.070Z,1518383318.070 [BPC1](INFO): Starting
2018-02-11T21:08:38.466Z,1518383318.466 [BPC1](INFO): Starting
2018-02-11T21:08:38.871Z,1518383318.871 [BPC1](INFO): Starting
2018-02-11T21:08:38.871Z,1518383318.871 [BPC1](INFO): Initialized.
2018-02-11T21:08:38.918Z,1518383318.918 [Reporter](INFO): platform_battery_voltage 15.192742 _
2018-02-11T21:08:39.271Z,1518383319.271 [BPC1](INFO): Runnable
2018-02-11T21:08:39.293Z,1518383319.293 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:08:39.305Z,1518383319.305 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:08:39.670Z,1518383319.670 [BPC1](INFO): Runnable
2018-02-11T21:08:40.085Z,1518383320.085 [BPC1](INFO): Runnable
2018-02-11T21:08:40.471Z,1518383320.471 [BPC1](INFO): Runnable
2018-02-11T21:08:40.875Z,1518383320.875 [BPC1](INFO): Runnable
2018-02-11T21:08:40.896Z,1518383320.896 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-11T21:08:40.896Z,1518383320.896 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-02-11T21:08:40.896Z,1518383320.896 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-02-11T21:08:40.902Z,1518383320.902 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-11T21:08:40.902Z,1518383320.902 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-02-11T21:08:40.902Z,1518383320.902 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-11T21:08:40.930Z,1518383320.930 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-02-11T21:08:40.931Z,1518383320.931 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-11T21:08:41.277Z,1518383321.277 [BPC1](INFO): Runnable
2018-02-11T21:08:41.333Z,1518383321.333 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-02-11T21:08:41.333Z,1518383321.333 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-02-11T21:08:41.333Z,1518383321.333 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-11T21:08:41.333Z,1518383321.333 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-02-11T21:08:41.684Z,1518383321.684 [BPC1](INFO): Runnable
2018-02-11T21:08:41.699Z,1518383321.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-02-11T21:08:41.699Z,1518383321.699 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:08:41.700Z,1518383321.700 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:08:41.700Z,1518383321.700 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-11T21:08:41.701Z,1518383321.701 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:08:41.701Z,1518383321.701 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:08:42.086Z,1518383322.086 [BPC1](INFO): Runnable
2018-02-11T21:08:42.094Z,1518383322.094 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-02-11T21:08:45.197Z,1518383325.197 [BPC1](INFO): Runnable
2018-02-11T21:08:45.205Z,1518383325.205 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s.
2018-02-11T21:08:45.321Z,1518383325.321 [BPC1](INFO): Runnable
2018-02-11T21:08:45.352Z,1518383325.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-02-11T21:08:45.686Z,1518383325.686 [BPC1](INFO): Runnable
2018-02-11T21:08:45.699Z,1518383325.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-02-11T21:08:46.053Z,1518383326.053 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003804
2018-02-11T21:08:46.102Z,1518383326.102 [BPC1](INFO): Runnable
2018-02-11T21:08:46.111Z,1518383326.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-02-11T21:08:47.230Z,1518383327.230 [BPC1](INFO): Runnable
2018-02-11T21:08:47.230Z,1518383327.230 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:08:47.230Z,1518383327.230 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:08:47.239Z,1518383327.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s.
2018-02-11T21:08:47.365Z,1518383327.365 [BPC1](INFO): Runnable
2018-02-11T21:08:47.387Z,1518383327.387 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2018-02-11T21:08:50.442Z,1518383330.442 [BPC1](INFO): Runnable
2018-02-11T21:08:50.450Z,1518383330.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-02-11T21:08:50.472Z,1518383330.472 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20180211T202742/Courier0020.lzma
2018-02-11T21:08:50.577Z,1518383330.577 [BPC1](INFO): Runnable
2018-02-11T21:08:50.603Z,1518383330.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2018-02-11T21:08:50.909Z,1518383330.909 [BPC1](INFO): Runnable
2018-02-11T21:08:50.920Z,1518383330.920 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-02-11T21:08:51.270Z,1518383331.270 [DataOverHttps](INFO): Moved sent file to Logs/20180211T202742/Courier0020.lzma.bak
2018-02-11T21:08:51.270Z,1518383331.270 [DataOverHttps](INFO): SBD MOMSN=7852690
2018-02-11T21:08:51.321Z,1518383331.321 [BPC1](INFO): Runnable
2018-02-11T21:08:51.321Z,1518383331.321 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:08:51.330Z,1518383331.330 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:08:51.333Z,1518383331.333 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:08:51.341Z,1518383331.341 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:08:51.375Z,1518383331.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2018-02-11T21:08:52.422Z,1518383332.422 [BPC1](INFO): Runnable
2018-02-11T21:08:52.437Z,1518383332.437 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:08:52.449Z,1518383332.449 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:08:52.496Z,1518383332.496 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-02-11T21:08:52.589Z,1518383332.589 [BPC1](INFO): Runnable
2018-02-11T21:08:52.603Z,1518383332.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2018-02-11T21:08:55.699Z,1518383335.699 [BPC1](INFO): Runnable
2018-02-11T21:08:55.707Z,1518383335.707 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-02-11T21:08:55.818Z,1518383335.818 [BPC1](INFO): Runnable
2018-02-11T21:08:55.851Z,1518383335.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s.
2018-02-11T21:08:56.203Z,1518383336.203 [BPC1](INFO): Runnable
2018-02-11T21:08:56.212Z,1518383336.212 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2018-02-11T21:08:56.607Z,1518383336.607 [BPC1](INFO): Runnable
2018-02-11T21:08:56.632Z,1518383336.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2018-02-11T21:08:57.005Z,1518383337.005 [BPC1](INFO): Runnable
2018-02-11T21:08:57.013Z,1518383337.013 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-02-11T21:08:57.415Z,1518383337.415 [BPC1](INFO): Runnable
2018-02-11T21:08:57.428Z,1518383337.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2018-02-11T21:09:00.502Z,1518383340.502 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:09:00.502Z,1518383340.502 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:09:00.527Z,1518383340.527 [BPC1](INFO): Runnable
2018-02-11T21:09:00.527Z,1518383340.527 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:09:00.528Z,1518383340.528 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:09:00.536Z,1518383340.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-02-11T21:09:00.627Z,1518383340.627 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20180211T210531/Courier0000.lzma
2018-02-11T21:09:00.703Z,1518383340.703 [BPC1](INFO): Runnable
2018-02-11T21:09:00.713Z,1518383340.713 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.8 s old, using for 20.0 s.
2018-02-11T21:09:01.065Z,1518383341.065 [BPC1](INFO): Runnable
2018-02-11T21:09:01.422Z,1518383341.422 [DataOverHttps](INFO): Moved sent file to Logs/20180211T210531/Courier0000.lzma.bak
2018-02-11T21:09:01.422Z,1518383341.422 [DataOverHttps](INFO): SBD MOMSN=7852703
2018-02-11T21:09:01.482Z,1518383341.482 [BPC1](INFO): Runnable
2018-02-11T21:09:03.324Z,1518383343.324 [BPC1](INFO): Runnable
2018-02-11T21:09:03.453Z,1518383343.453 [BPC1](INFO): Runnable
2018-02-11T21:09:06.511Z,1518383346.511 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:09:06.511Z,1518383346.511 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:09:06.570Z,1518383346.570 [BPC1](INFO): Runnable
2018-02-11T21:09:06.570Z,1518383346.570 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:09:06.577Z,1518383346.577 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:09:06.577Z,1518383346.577 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:09:06.585Z,1518383346.585 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:09:06.722Z,1518383346.722 [BPC1](INFO): Runnable
2018-02-11T21:09:06.737Z,1518383346.737 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:09:06.749Z,1518383346.749 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:09:07.054Z,1518383347.054 [BPC1](INFO): Runnable
2018-02-11T21:09:07.461Z,1518383347.461 [BPC1](INFO): Runnable
2018-02-11T21:09:07.850Z,1518383347.850 [BPC1](INFO): Runnable
2018-02-11T21:09:08.258Z,1518383348.258 [BPC1](INFO): Runnable
2018-02-11T21:09:11.378Z,1518383351.378 [BPC1](INFO): Runnable
2018-02-11T21:09:11.461Z,1518383351.461 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20180211T202742/Express0021.lzma
2018-02-11T21:09:11.504Z,1518383351.504 [BPC1](INFO): Runnable
2018-02-11T21:09:11.865Z,1518383351.865 [BPC1](INFO): Runnable
2018-02-11T21:09:12.230Z,1518383352.230 [DataOverHttps](INFO): Moved sent file to Logs/20180211T202742/Express0021.lzma.bak
2018-02-11T21:09:12.230Z,1518383352.230 [DataOverHttps](INFO): SBD MOMSN=7852709
2018-02-11T21:09:12.289Z,1518383352.289 [BPC1](INFO): Runnable
2018-02-11T21:09:14.113Z,1518383354.113 [BPC1](INFO): Runnable
2018-02-11T21:09:14.113Z,1518383354.113 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:09:14.253Z,1518383354.253 [BPC1](INFO): Runnable
2018-02-11T21:09:14.253Z,1518383354.253 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:09:17.301Z,1518383357.301 [NAL9602](INFO): SBD MO Status=2, MOMSN=21568, MT Status=2, MTMSN=0
2018-02-11T21:09:17.302Z,1518383357.302 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-11T21:09:17.327Z,1518383357.327 [BPC1](INFO): Runnable
2018-02-11T21:09:17.482Z,1518383357.482 [BPC1](INFO): Runnable
2018-02-11T21:09:17.862Z,1518383357.862 [BPC1](INFO): Runnable
2018-02-11T21:09:18.239Z,1518383358.239 [BPC1](INFO): Runnable
2018-02-11T21:09:18.644Z,1518383358.644 [BPC1](INFO): Runnable
2018-02-11T21:09:18.645Z,1518383358.645 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:09:18.656Z,1518383358.656 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:09:19.051Z,1518383359.051 [BPC1](INFO): Runnable
2018-02-11T21:09:19.051Z,1518383359.051 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:09:19.058Z,1518383359.058 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:09:19.069Z,1518383359.069 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:09:23.991Z,1518383363.991 [BPC1](INFO): Runnable
2018-02-11T21:09:24.005Z,1518383364.005 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:09:24.096Z,1518383364.096 [DataOverHttps](INFO): Sending 643 bytes from file Logs/20180211T210531/Express0001.lzma
2018-02-11T21:09:24.182Z,1518383364.182 [BPC1](INFO): Runnable
2018-02-11T21:09:24.510Z,1518383364.510 [BPC1](INFO): Runnable
2018-02-11T21:09:24.874Z,1518383364.874 [DataOverHttps](INFO): Moved sent file to Logs/20180211T210531/Express0001.lzma.bak
2018-02-11T21:09:24.874Z,1518383364.874 [DataOverHttps](INFO): SBD MOMSN=7852713
2018-02-11T21:09:24.917Z,1518383364.917 [BPC1](INFO): Runnable
2018-02-11T21:09:26.769Z,1518383366.769 [BPC1](INFO): Runnable
2018-02-11T21:09:26.769Z,1518383366.769 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:09:26.786Z,1518383366.786 [Startup:StartupSatComms:B] Stopped
2018-02-11T21:09:26.786Z,1518383366.786 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-02-11T21:09:26.786Z,1518383366.786 [Startup:StartupSatComms] Stopped
2018-02-11T21:09:26.786Z,1518383366.786 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-02-11T21:09:26.787Z,1518383366.787 [Startup](INFO): Completed Startup
2018-02-11T21:09:26.787Z,1518383366.787 [MissionManager](INFO): Startup is completed.
2018-02-11T21:09:26.787Z,1518383366.787 [MissionManager](INFO): Uninitializing Mission Startup
2018-02-11T21:09:26.787Z,1518383366.787 [Startup] Stopped
2018-02-11T21:09:26.787Z,1518383366.787 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-02-11T21:09:26.787Z,1518383366.787 [Startup:A.GoToSurface] Stopped
2018-02-11T21:09:26.787Z,1518383366.787 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-02-11T21:09:26.899Z,1518383366.899 [BPC1](INFO): Runnable
2018-02-11T21:09:26.922Z,1518383366.922 [MissionManager](IMPORTANT): Started mission Default
2018-02-11T21:09:26.922Z,1518383366.922 [Default] Running Loop=1
2018-02-11T21:09:26.922Z,1518383366.922 [Default](DEBUG): Aggregate::initialize Default
2018-02-11T21:09:26.922Z,1518383366.922 [Default:B.GoToSurface] Running Loop=1
2018-02-11T21:09:26.923Z,1518383366.923 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-02-11T21:09:26.923Z,1518383366.923 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-02-11T21:09:26.923Z,1518383366.923 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-02-11T21:09:26.923Z,1518383366.923 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-02-11T21:09:26.924Z,1518383366.924 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-02-11T21:09:26.924Z,1518383366.924 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-02-11T21:09:26.924Z,1518383366.924 [Default:A.Wait] Running Loop=1
2018-02-11T21:09:26.924Z,1518383366.924 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-02-11T21:09:29.982Z,1518383369.982 [BPC1](INFO): Runnable
2018-02-11T21:09:30.020Z,1518383370.020 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:30.121Z,1518383370.121 [BPC1](INFO): Runnable
2018-02-11T21:09:30.122Z,1518383370.122 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:09:30.128Z,1518383370.128 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:09:30.420Z,1518383370.420 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:30.454Z,1518383370.454 [BPC1](INFO): Runnable
2018-02-11T21:09:30.473Z,1518383370.473 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:09:30.820Z,1518383370.820 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:30.873Z,1518383370.873 [BPC1](INFO): Runnable
2018-02-11T21:09:30.873Z,1518383370.873 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:09:31.220Z,1518383371.220 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:31.277Z,1518383371.277 [BPC1](INFO): Runnable
2018-02-11T21:09:31.620Z,1518383371.620 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:31.669Z,1518383371.669 [BPC1](INFO): Runnable
2018-02-11T21:09:32.020Z,1518383372.020 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:32.061Z,1518383372.061 [BPC1](INFO): Runnable
2018-02-11T21:09:32.420Z,1518383372.420 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:32.458Z,1518383372.458 [BPC1](INFO): Runnable
2018-02-11T21:09:32.820Z,1518383372.820 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:32.855Z,1518383372.855 [BPC1](INFO): Runnable
2018-02-11T21:09:33.220Z,1518383373.220 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:33.260Z,1518383373.260 [BPC1](INFO): Runnable
2018-02-11T21:09:33.620Z,1518383373.620 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:33.663Z,1518383373.663 [BPC1](INFO): Runnable
2018-02-11T21:09:34.020Z,1518383374.020 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:34.059Z,1518383374.059 [BPC1](INFO): Runnable
2018-02-11T21:09:34.420Z,1518383374.420 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:34.461Z,1518383374.461 [BPC1](INFO): Runnable
2018-02-11T21:09:34.820Z,1518383374.820 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:34.859Z,1518383374.859 [BPC1](INFO): Runnable
2018-02-11T21:09:34.860Z,1518383374.860 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:09:34.866Z,1518383374.866 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:09:34.866Z,1518383374.866 [BPC1](DEBUG): Received data from all battery sticks
2018-02-11T21:09:35.220Z,1518383375.220 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:35.261Z,1518383375.261 [BPC1](INFO): Stop
2018-02-11T21:09:35.261Z,1518383375.261 [BPC1](INFO): uninitialize
2018-02-11T21:09:35.620Z,1518383375.620 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:09:35.650Z,1518383375.650 [BPC1](INFO): Stopping
2018-02-11T21:09:36.051Z,1518383376.051 [BPC1](INFO): Stopped
2018-02-11T21:09:36.450Z,1518383376.450 [BPC1](INFO): Start
2018-02-11T21:09:36.850Z,1518383376.850 [BPC1](INFO): Starting
2018-02-11T21:09:37.251Z,1518383377.251 [BPC1](INFO): Starting
2018-02-11T21:09:37.651Z,1518383377.651 [BPC1](INFO): Starting
2018-02-11T21:09:38.052Z,1518383378.052 [BPC1](INFO): Starting
2018-02-11T21:09:38.451Z,1518383378.451 [BPC1](INFO): Starting
2018-02-11T21:09:38.851Z,1518383378.851 [BPC1](INFO): Starting
2018-02-11T21:09:39.265Z,1518383379.265 [BPC1](INFO): Starting
2018-02-11T21:09:39.666Z,1518383379.666 [BPC1](INFO): Starting
2018-02-11T21:09:40.061Z,1518383380.061 [BPC1](INFO): Starting
2018-02-11T21:09:40.062Z,1518383380.062 [BPC1](INFO): Initialized.
2018-02-11T21:09:40.075Z,1518383380.075 [Default:A.Wait](INFO): Done Waiting.
2018-02-11T21:09:40.075Z,1518383380.075 [Default:A.Wait] Stopped
2018-02-11T21:09:40.075Z,1518383380.075 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-02-11T21:09:40.114Z,1518383380.114 [Reporter](INFO): platform_battery_voltage 15.354839 _
2018-02-11T21:09:40.463Z,1518383380.463 [BPC1](INFO): Runnable
2018-02-11T21:09:40.477Z,1518383380.477 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:09:40.517Z,1518383380.517 [Default:CheckIn] Running Loop=1
2018-02-11T21:09:40.517Z,1518383380.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-11T21:09:40.517Z,1518383380.517 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-11T21:09:40.861Z,1518383380.861 [BPC1](INFO): Runnable
2018-02-11T21:09:40.873Z,1518383380.873 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-02-11T21:09:41.261Z,1518383381.261 [BPC1](INFO): Runnable
2018-02-11T21:09:41.657Z,1518383381.657 [BPC1](INFO): Runnable
2018-02-11T21:09:42.057Z,1518383382.057 [BPC1](INFO): Runnable
2018-02-11T21:09:42.458Z,1518383382.458 [BPC1](INFO): Runnable
2018-02-11T21:09:42.862Z,1518383382.862 [BPC1](INFO): Runnable
2018-02-11T21:09:43.257Z,1518383383.257 [BPC1](INFO): Runnable
2018-02-11T21:09:43.662Z,1518383383.662 [BPC1](INFO): Runnable
2018-02-11T21:09:44.065Z,1518383384.065 [BPC1](INFO): Runnable
2018-02-11T21:09:44.461Z,1518383384.461 [BPC1](INFO): Runnable
2018-02-11T21:09:44.861Z,1518383384.861 [BPC1](INFO): Runnable
2018-02-11T21:09:45.263Z,1518383385.263 [BPC1](INFO): Runnable
2018-02-11T21:09:45.661Z,1518383385.661 [BPC1](INFO): Runnable
2018-02-11T21:09:46.057Z,1518383386.057 [BPC1](INFO): Runnable
2018-02-11T21:09:46.466Z,1518383386.466 [BPC1](INFO): Runnable
2018-02-11T21:09:46.857Z,1518383386.857 [BPC1](INFO): Runnable
2018-02-11T21:09:47.261Z,1518383387.261 [BPC1](INFO): Runnable
2018-02-11T21:09:47.273Z,1518383387.273 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:09:47.659Z,1518383387.659 [BPC1](INFO): Runnable
2018-02-11T21:09:47.660Z,1518383387.660 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:09:48.073Z,1518383388.073 [BPC1](INFO): Runnable
2018-02-11T21:09:48.471Z,1518383388.471 [BPC1](INFO): Runnable
2018-02-11T21:09:48.868Z,1518383388.868 [BPC1](INFO): Runnable
2018-02-11T21:09:49.250Z,1518383389.250 [BPC1](INFO): Runnable
2018-02-11T21:09:49.251Z,1518383389.251 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:09:49.672Z,1518383389.672 [BPC1](INFO): Runnable
2018-02-11T21:09:50.073Z,1518383390.073 [BPC1](INFO): Runnable
2018-02-11T21:09:50.451Z,1518383390.451 [BPC1](INFO): Runnable
2018-02-11T21:09:50.854Z,1518383390.854 [BPC1](INFO): Runnable
2018-02-11T21:09:51.251Z,1518383391.251 [BPC1](INFO): Runnable
2018-02-11T21:09:51.651Z,1518383391.651 [BPC1](INFO): Runnable
2018-02-11T21:09:52.053Z,1518383392.053 [BPC1](INFO): Runnable
2018-02-11T21:09:52.460Z,1518383392.460 [BPC1](INFO): Runnable
2018-02-11T21:09:52.460Z,1518383392.460 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:09:52.467Z,1518383392.467 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:09:52.858Z,1518383392.858 [BPC1](INFO): Runnable
2018-02-11T21:09:52.873Z,1518383392.873 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:09:52.873Z,1518383392.873 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:09:52.916Z,1518383392.916 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:09:53.258Z,1518383393.258 [BPC1](INFO): Runnable
2018-02-11T21:09:53.273Z,1518383393.273 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:09:53.657Z,1518383393.657 [BPC1](INFO): Runnable
2018-02-11T21:09:54.054Z,1518383394.054 [BPC1](INFO): Runnable
2018-02-11T21:09:54.466Z,1518383394.466 [BPC1](INFO): Runnable
2018-02-11T21:09:54.860Z,1518383394.860 [BPC1](INFO): Runnable
2018-02-11T21:09:55.259Z,1518383395.259 [BPC1](INFO): Runnable
2018-02-11T21:09:55.661Z,1518383395.661 [BPC1](INFO): Runnable
2018-02-11T21:09:56.066Z,1518383396.066 [BPC1](INFO): Runnable
2018-02-11T21:09:56.458Z,1518383396.458 [BPC1](INFO): Runnable
2018-02-11T21:09:56.873Z,1518383396.873 [BPC1](INFO): Runnable
2018-02-11T21:09:57.287Z,1518383397.287 [BPC1](INFO): Runnable
2018-02-11T21:09:57.663Z,1518383397.663 [BPC1](INFO): Runnable
2018-02-11T21:09:58.071Z,1518383398.071 [BPC1](INFO): Runnable
2018-02-11T21:09:58.456Z,1518383398.456 [BPC1](INFO): Runnable
2018-02-11T21:09:58.855Z,1518383398.855 [BPC1](INFO): Runnable
2018-02-11T21:09:59.261Z,1518383399.261 [BPC1](INFO): Runnable
2018-02-11T21:09:59.666Z,1518383399.666 [BPC1](INFO): Runnable
2018-02-11T21:10:00.060Z,1518383400.060 [BPC1](INFO): Runnable
2018-02-11T21:10:00.474Z,1518383400.474 [BPC1](INFO): Runnable
2018-02-11T21:10:00.474Z,1518383400.474 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:10:00.474Z,1518383400.474 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:10:00.887Z,1518383400.887 [BPC1](INFO): Runnable
2018-02-11T21:10:01.282Z,1518383401.282 [BPC1](INFO): Runnable
2018-02-11T21:10:01.654Z,1518383401.654 [BPC1](INFO): Runnable
2018-02-11T21:10:02.051Z,1518383402.051 [BPC1](INFO): Runnable
2018-02-11T21:10:02.452Z,1518383402.452 [BPC1](INFO): Runnable
2018-02-11T21:10:02.852Z,1518383402.852 [BPC1](INFO): Runnable
2018-02-11T21:10:03.252Z,1518383403.252 [BPC1](INFO): Runnable
2018-02-11T21:10:03.656Z,1518383403.656 [BPC1](INFO): Runnable
2018-02-11T21:10:04.059Z,1518383404.059 [BPC1](INFO): Runnable
2018-02-11T21:10:04.460Z,1518383404.460 [BPC1](INFO): Runnable
2018-02-11T21:10:04.861Z,1518383404.861 [BPC1](INFO): Runnable
2018-02-11T21:10:04.861Z,1518383404.861 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:10:04.872Z,1518383404.872 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:10:04.872Z,1518383404.872 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:10:04.887Z,1518383404.887 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:10:05.267Z,1518383405.267 [BPC1](INFO): Runnable
2018-02-11T21:10:05.286Z,1518383405.286 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:10:05.297Z,1518383405.297 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:10:05.665Z,1518383405.665 [BPC1](INFO): Runnable
2018-02-11T21:10:06.060Z,1518383406.060 [BPC1](INFO): Runnable
2018-02-11T21:10:06.466Z,1518383406.466 [BPC1](INFO): Runnable
2018-02-11T21:10:06.866Z,1518383406.866 [BPC1](INFO): Runnable
2018-02-11T21:10:07.258Z,1518383407.258 [BPC1](INFO): Runnable
2018-02-11T21:10:07.662Z,1518383407.662 [BPC1](INFO): Runnable
2018-02-11T21:10:08.060Z,1518383408.060 [BPC1](INFO): Runnable
2018-02-11T21:10:08.464Z,1518383408.464 [BPC1](INFO): Runnable
2018-02-11T21:10:08.863Z,1518383408.863 [BPC1](INFO): Runnable
2018-02-11T21:10:09.264Z,1518383409.264 [BPC1](INFO): Runnable
2018-02-11T21:10:09.683Z,1518383409.683 [BPC1](INFO): Runnable
2018-02-11T21:10:10.065Z,1518383410.065 [BPC1](INFO): Runnable
2018-02-11T21:10:10.470Z,1518383410.470 [BPC1](INFO): Runnable
2018-02-11T21:10:10.850Z,1518383410.850 [BPC1](INFO): Runnable
2018-02-11T21:10:11.251Z,1518383411.251 [BPC1](INFO): Runnable
2018-02-11T21:10:11.665Z,1518383411.665 [BPC1](INFO): Runnable
2018-02-11T21:10:12.061Z,1518383412.061 [BPC1](INFO): Runnable
2018-02-11T21:10:12.464Z,1518383412.464 [BPC1](INFO): Runnable
2018-02-11T21:10:12.856Z,1518383412.856 [BPC1](INFO): Runnable
2018-02-11T21:10:12.856Z,1518383412.856 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:10:12.856Z,1518383412.856 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:10:13.260Z,1518383413.260 [BPC1](INFO): Runnable
2018-02-11T21:10:13.660Z,1518383413.660 [BPC1](INFO): Runnable
2018-02-11T21:10:14.073Z,1518383414.073 [BPC1](INFO): Runnable
2018-02-11T21:10:14.473Z,1518383414.473 [BPC1](INFO): Runnable
2018-02-11T21:10:14.867Z,1518383414.867 [BPC1](INFO): Runnable
2018-02-11T21:10:15.252Z,1518383415.252 [BPC1](INFO): Runnable
2018-02-11T21:10:15.652Z,1518383415.652 [BPC1](INFO): Runnable
2018-02-11T21:10:16.056Z,1518383416.056 [BPC1](INFO): Runnable
2018-02-11T21:10:16.459Z,1518383416.459 [BPC1](INFO): Runnable
2018-02-11T21:10:16.857Z,1518383416.857 [BPC1](INFO): Runnable
2018-02-11T21:10:17.259Z,1518383417.259 [BPC1](INFO): Runnable
2018-02-11T21:10:17.259Z,1518383417.259 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:10:17.266Z,1518383417.266 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:10:17.266Z,1518383417.266 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:10:17.273Z,1518383417.273 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:10:17.657Z,1518383417.657 [BPC1](INFO): Runnable
2018-02-11T21:10:17.669Z,1518383417.669 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:10:17.681Z,1518383417.681 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:10:18.061Z,1518383418.061 [BPC1](INFO): Runnable
2018-02-11T21:10:18.470Z,1518383418.470 [BPC1](INFO): Runnable
2018-02-11T21:10:18.868Z,1518383418.868 [BPC1](INFO): Runnable
2018-02-11T21:10:19.270Z,1518383419.270 [BPC1](INFO): Runnable
2018-02-11T21:10:19.669Z,1518383419.669 [BPC1](INFO): Runnable
2018-02-11T21:10:20.059Z,1518383420.059 [BPC1](INFO): Runnable
2018-02-11T21:10:20.458Z,1518383420.458 [BPC1](INFO): Runnable
2018-02-11T21:10:20.861Z,1518383420.861 [BPC1](INFO): Runnable
2018-02-11T21:10:21.261Z,1518383421.261 [BPC1](INFO): Runnable
2018-02-11T21:10:21.658Z,1518383421.658 [BPC1](INFO): Runnable
2018-02-11T21:10:22.061Z,1518383422.061 [BPC1](INFO): Runnable
2018-02-11T21:10:22.461Z,1518383422.461 [BPC1](INFO): Runnable
2018-02-11T21:10:22.858Z,1518383422.858 [BPC1](INFO): Runnable
2018-02-11T21:10:23.269Z,1518383423.269 [BPC1](INFO): Runnable
2018-02-11T21:10:23.649Z,1518383423.649 [BPC1](INFO): Runnable
2018-02-11T21:10:24.050Z,1518383424.050 [BPC1](INFO): Runnable
2018-02-11T21:10:24.450Z,1518383424.450 [BPC1](INFO): Runnable
2018-02-11T21:10:24.850Z,1518383424.850 [BPC1](INFO): Runnable
2018-02-11T21:10:24.850Z,1518383424.850 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:10:24.851Z,1518383424.851 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:10:25.266Z,1518383425.266 [BPC1](INFO): Runnable
2018-02-11T21:10:25.654Z,1518383425.654 [BPC1](INFO): Runnable
2018-02-11T21:10:26.055Z,1518383426.055 [BPC1](INFO): Runnable
2018-02-11T21:10:26.450Z,1518383426.450 [BPC1](INFO): Runnable
2018-02-11T21:10:26.854Z,1518383426.854 [BPC1](INFO): Runnable
2018-02-11T21:10:27.251Z,1518383427.251 [BPC1](INFO): Runnable
2018-02-11T21:10:30.394Z,1518383430.394 [BPC1](INFO): Runnable
2018-02-11T21:10:30.395Z,1518383430.395 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:10:30.401Z,1518383430.401 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:10:30.401Z,1518383430.401 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:10:30.407Z,1518383430.407 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:10:30.537Z,1518383430.537 [BPC1](INFO): Runnable
2018-02-11T21:10:30.549Z,1518383430.549 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:10:30.561Z,1518383430.561 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:10:30.912Z,1518383430.912 [BPC1](INFO): Runnable
2018-02-11T21:10:31.313Z,1518383431.313 [BPC1](INFO): Runnable
2018-02-11T21:10:31.712Z,1518383431.712 [BPC1](INFO): Runnable
2018-02-11T21:10:32.110Z,1518383432.110 [BPC1](INFO): Runnable
2018-02-11T21:10:32.517Z,1518383432.517 [BPC1](INFO): Runnable
2018-02-11T21:10:32.918Z,1518383432.918 [BPC1](INFO): Runnable
2018-02-11T21:10:33.310Z,1518383433.310 [BPC1](INFO): Runnable
2018-02-11T21:10:33.709Z,1518383433.709 [BPC1](INFO): Runnable
2018-02-11T21:10:34.110Z,1518383434.110 [BPC1](INFO): Runnable
2018-02-11T21:10:34.509Z,1518383434.509 [BPC1](INFO): Runnable
2018-02-11T21:10:34.924Z,1518383434.924 [BPC1](INFO): Runnable
2018-02-11T21:10:35.353Z,1518383435.353 [BPC1](INFO): Runnable
2018-02-11T21:10:35.747Z,1518383435.747 [BPC1](INFO): Runnable
2018-02-11T21:10:36.129Z,1518383436.129 [BPC1](INFO): Runnable
2018-02-11T21:10:36.562Z,1518383436.562 [BPC1](INFO): Runnable
2018-02-11T21:10:36.937Z,1518383436.937 [BPC1](INFO): Runnable
2018-02-11T21:10:37.328Z,1518383437.328 [BPC1](INFO): Runnable
2018-02-11T21:10:37.328Z,1518383437.328 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:10:37.328Z,1518383437.328 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:10:37.719Z,1518383437.719 [BPC1](INFO): Runnable
2018-02-11T21:10:38.101Z,1518383438.101 [BPC1](INFO): Runnable
2018-02-11T21:10:38.506Z,1518383438.506 [BPC1](INFO): Runnable
2018-02-11T21:10:38.903Z,1518383438.903 [BPC1](INFO): Runnable
2018-02-11T21:10:39.302Z,1518383439.302 [BPC1](INFO): Runnable
2018-02-11T21:10:39.708Z,1518383439.708 [BPC1](INFO): Runnable
2018-02-11T21:10:40.106Z,1518383440.106 [BPC1](INFO): Runnable
2018-02-11T21:10:40.507Z,1518383440.507 [BPC1](INFO): Runnable
2018-02-11T21:10:40.917Z,1518383440.917 [BPC1](INFO): Runnable
2018-02-11T21:10:41.314Z,1518383441.314 [BPC1](INFO): Runnable
2018-02-11T21:10:41.315Z,1518383441.315 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:10:41.321Z,1518383441.321 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:10:41.705Z,1518383441.705 [BPC1](INFO): Runnable
2018-02-11T21:10:41.717Z,1518383441.717 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:10:42.111Z,1518383442.111 [BPC1](INFO): Runnable
2018-02-11T21:10:42.111Z,1518383442.111 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:10:42.118Z,1518383442.118 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:10:42.119Z,1518383442.119 [BPC1](DEBUG): Received data from all battery sticks
2018-02-11T21:10:42.513Z,1518383442.513 [BPC1](INFO): Stop
2018-02-11T21:10:42.513Z,1518383442.513 [BPC1](INFO): uninitialize
2018-02-11T21:10:42.917Z,1518383442.917 [BPC1](INFO): Stopping
2018-02-11T21:10:43.315Z,1518383443.315 [BPC1](INFO): Stopped
2018-02-11T21:10:43.715Z,1518383443.715 [BPC1](INFO): Start
2018-02-11T21:10:44.115Z,1518383444.115 [BPC1](INFO): Starting
2018-02-11T21:10:44.515Z,1518383444.515 [BPC1](INFO): Starting
2018-02-11T21:10:44.915Z,1518383444.915 [BPC1](INFO): Starting
2018-02-11T21:10:45.321Z,1518383445.321 [BPC1](INFO): Starting
2018-02-11T21:10:45.721Z,1518383445.721 [BPC1](INFO): Starting
2018-02-11T21:10:46.115Z,1518383446.115 [BPC1](INFO): Starting
2018-02-11T21:10:46.517Z,1518383446.517 [BPC1](INFO): Starting
2018-02-11T21:10:46.903Z,1518383446.903 [BPC1](INFO): Starting
2018-02-11T21:10:47.302Z,1518383447.302 [BPC1](INFO): Starting
2018-02-11T21:10:47.702Z,1518383447.702 [BPC1](INFO): Starting
2018-02-11T21:10:48.106Z,1518383448.106 [BPC1](INFO): Starting
2018-02-11T21:10:48.107Z,1518383448.107 [BPC1](INFO): Initialized.
2018-02-11T21:10:48.163Z,1518383448.163 [Reporter](INFO): platform_battery_voltage 15.354145 _
2018-02-11T21:10:48.509Z,1518383448.509 [BPC1](INFO): Runnable
2018-02-11T21:10:48.521Z,1518383448.521 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:10:48.541Z,1518383448.541 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:10:48.908Z,1518383448.908 [BPC1](INFO): Runnable
2018-02-11T21:10:49.308Z,1518383449.308 [BPC1](INFO): Runnable
2018-02-11T21:10:49.722Z,1518383449.722 [BPC1](INFO): Runnable
2018-02-11T21:10:50.119Z,1518383450.119 [BPC1](INFO): Runnable
2018-02-11T21:10:50.510Z,1518383450.510 [BPC1](INFO): Runnable
2018-02-11T21:10:50.910Z,1518383450.910 [BPC1](INFO): Runnable
2018-02-11T21:10:51.311Z,1518383451.311 [BPC1](INFO): Runnable
2018-02-11T21:10:51.711Z,1518383451.711 [BPC1](INFO): Runnable
2018-02-11T21:10:52.116Z,1518383452.116 [BPC1](INFO): Runnable
2018-02-11T21:10:52.517Z,1518383452.517 [BPC1](INFO): Runnable
2018-02-11T21:10:52.910Z,1518383452.910 [BPC1](INFO): Runnable
2018-02-11T21:10:53.315Z,1518383453.315 [BPC1](INFO): Runnable
2018-02-11T21:10:53.711Z,1518383453.711 [BPC1](INFO): Runnable
2018-02-11T21:10:54.110Z,1518383454.110 [BPC1](INFO): Runnable
2018-02-11T21:10:54.514Z,1518383454.514 [BPC1](INFO): Runnable
2018-02-11T21:10:54.913Z,1518383454.913 [BPC1](INFO): Runnable
2018-02-11T21:10:55.310Z,1518383455.310 [BPC1](INFO): Runnable
2018-02-11T21:10:55.719Z,1518383455.719 [BPC1](INFO): Runnable
2018-02-11T21:10:56.121Z,1518383456.121 [BPC1](INFO): Runnable
2018-02-11T21:10:56.121Z,1518383456.121 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:10:56.121Z,1518383456.121 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:10:56.502Z,1518383456.502 [BPC1](INFO): Runnable
2018-02-11T21:10:56.914Z,1518383456.914 [BPC1](INFO): Runnable
2018-02-11T21:10:57.316Z,1518383457.316 [BPC1](INFO): Runnable
2018-02-11T21:10:57.703Z,1518383457.703 [BPC1](INFO): Runnable
2018-02-11T21:10:58.104Z,1518383458.104 [BPC1](INFO): Runnable
2018-02-11T21:10:58.503Z,1518383458.503 [BPC1](INFO): Runnable
2018-02-11T21:10:58.903Z,1518383458.903 [BPC1](INFO): Runnable
2018-02-11T21:10:59.306Z,1518383459.306 [BPC1](INFO): Runnable
2018-02-11T21:10:59.710Z,1518383459.710 [BPC1](INFO): Runnable
2018-02-11T21:11:00.106Z,1518383460.106 [BPC1](INFO): Runnable
2018-02-11T21:11:00.509Z,1518383460.509 [BPC1](INFO): Runnable
2018-02-11T21:11:00.510Z,1518383460.510 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:11:00.517Z,1518383460.517 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:11:00.909Z,1518383460.909 [BPC1](INFO): Runnable
2018-02-11T21:11:00.910Z,1518383460.910 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:11:00.916Z,1518383460.916 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:11:00.929Z,1518383460.929 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:11:01.310Z,1518383461.310 [BPC1](INFO): Runnable
2018-02-11T21:11:01.325Z,1518383461.325 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:11:01.710Z,1518383461.710 [BPC1](INFO): Runnable
2018-02-11T21:11:02.111Z,1518383462.111 [BPC1](INFO): Runnable
2018-02-11T21:11:02.510Z,1518383462.510 [BPC1](INFO): Runnable
2018-02-11T21:11:02.915Z,1518383462.915 [BPC1](INFO): Runnable
2018-02-11T21:11:03.311Z,1518383463.311 [BPC1](INFO): Runnable
2018-02-11T21:11:03.709Z,1518383463.709 [BPC1](INFO): Runnable
2018-02-11T21:11:04.119Z,1518383464.119 [BPC1](INFO): Runnable
2018-02-11T21:11:04.521Z,1518383464.521 [BPC1](INFO): Runnable
2018-02-11T21:11:04.903Z,1518383464.903 [BPC1](INFO): Runnable
2018-02-11T21:11:05.302Z,1518383465.302 [BPC1](INFO): Runnable
2018-02-11T21:11:05.725Z,1518383465.725 [BPC1](INFO): Runnable
2018-02-11T21:11:06.121Z,1518383466.121 [BPC1](INFO): Runnable
2018-02-11T21:11:06.506Z,1518383466.506 [BPC1](INFO): Runnable
2018-02-11T21:11:06.901Z,1518383466.901 [BPC1](INFO): Runnable
2018-02-11T21:11:07.301Z,1518383467.301 [BPC1](INFO): Runnable
2018-02-11T21:11:07.709Z,1518383467.709 [BPC1](INFO): Runnable
2018-02-11T21:11:08.106Z,1518383468.106 [BPC1](INFO): Runnable
2018-02-11T21:11:08.511Z,1518383468.511 [BPC1](INFO): Runnable
2018-02-11T21:11:08.512Z,1518383468.512 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:11:08.512Z,1518383468.512 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:11:08.910Z,1518383468.910 [BPC1](INFO): Runnable
2018-02-11T21:11:09.310Z,1518383469.310 [BPC1](INFO): Runnable
2018-02-11T21:11:09.708Z,1518383469.708 [BPC1](INFO): Runnable
2018-02-11T21:11:10.113Z,1518383470.113 [BPC1](INFO): Runnable
2018-02-11T21:11:10.519Z,1518383470.519 [BPC1](INFO): Runnable
2018-02-11T21:11:10.909Z,1518383470.909 [BPC1](INFO): Runnable
2018-02-11T21:11:11.313Z,1518383471.313 [BPC1](INFO): Runnable
2018-02-11T21:11:11.709Z,1518383471.709 [BPC1](INFO): Runnable
2018-02-11T21:11:12.110Z,1518383472.110 [BPC1](INFO): Runnable
2018-02-11T21:11:12.519Z,1518383472.519 [BPC1](INFO): Runnable
2018-02-11T21:11:12.927Z,1518383472.927 [BPC1](INFO): Runnable
2018-02-11T21:11:12.928Z,1518383472.928 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:11:12.934Z,1518383472.934 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:11:13.321Z,1518383473.321 [BPC1](INFO): Runnable
2018-02-11T21:11:13.321Z,1518383473.321 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:11:13.328Z,1518383473.328 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:11:13.341Z,1518383473.341 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:11:13.706Z,1518383473.706 [BPC1](INFO): Runnable
2018-02-11T21:11:13.725Z,1518383473.725 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:11:14.108Z,1518383474.108 [BPC1](INFO): Runnable
2018-02-11T21:11:14.503Z,1518383474.503 [BPC1](INFO): Runnable
2018-02-11T21:11:14.906Z,1518383474.906 [BPC1](INFO): Runnable
2018-02-11T21:11:15.310Z,1518383475.310 [BPC1](INFO): Runnable
2018-02-11T21:11:15.706Z,1518383475.706 [BPC1](INFO): Runnable
2018-02-11T21:11:16.109Z,1518383476.109 [BPC1](INFO): Runnable
2018-02-11T21:11:16.509Z,1518383476.509 [BPC1](INFO): Runnable
2018-02-11T21:11:16.910Z,1518383476.910 [BPC1](INFO): Runnable
2018-02-11T21:11:17.320Z,1518383477.320 [BPC1](INFO): Runnable
2018-02-11T21:11:17.718Z,1518383477.718 [BPC1](INFO): Runnable
2018-02-11T21:11:18.120Z,1518383478.120 [BPC1](INFO): Runnable
2018-02-11T21:11:18.522Z,1518383478.522 [BPC1](INFO): Runnable
2018-02-11T21:11:18.913Z,1518383478.913 [BPC1](INFO): Runnable
2018-02-11T21:11:19.313Z,1518383479.313 [BPC1](INFO): Runnable
2018-02-11T21:11:19.717Z,1518383479.717 [BPC1](INFO): Runnable
2018-02-11T21:11:20.115Z,1518383480.115 [BPC1](INFO): Runnable
2018-02-11T21:11:20.518Z,1518383480.518 [BPC1](INFO): Runnable
2018-02-11T21:11:20.910Z,1518383480.910 [BPC1](INFO): Runnable
2018-02-11T21:11:20.910Z,1518383480.910 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:11:21.286Z,1518383481.286 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-02-11T21:11:21.319Z,1518383481.319 [BPC1](INFO): Runnable
2018-02-11T21:11:21.702Z,1518383481.702 [BPC1](INFO): Runnable
2018-02-11T21:11:21.702Z,1518383481.702 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:11:22.125Z,1518383482.125 [BPC1](INFO): Runnable
2018-02-11T21:11:22.529Z,1518383482.529 [BPC1](INFO): Runnable
2018-02-11T21:11:22.913Z,1518383482.913 [BPC1](INFO): Runnable
2018-02-11T21:11:23.306Z,1518383483.306 [BPC1](INFO): Runnable
2018-02-11T21:11:23.709Z,1518383483.709 [BPC1](INFO): Runnable
2018-02-11T21:11:24.108Z,1518383484.108 [BPC1](INFO): Runnable
2018-02-11T21:11:24.519Z,1518383484.519 [BPC1](INFO): Runnable
2018-02-11T21:11:24.919Z,1518383484.919 [BPC1](INFO): Runnable
2018-02-11T21:11:24.920Z,1518383484.920 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:11:24.925Z,1518383484.925 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:11:25.329Z,1518383485.329 [BPC1](INFO): Runnable
2018-02-11T21:11:25.341Z,1518383485.341 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:11:25.729Z,1518383485.729 [BPC1](INFO): Runnable
2018-02-11T21:11:25.729Z,1518383485.729 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:11:25.735Z,1518383485.735 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:11:26.128Z,1518383486.128 [BPC1](INFO): Runnable
2018-02-11T21:11:26.141Z,1518383486.141 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:11:26.502Z,1518383486.502 [BPC1](INFO): Runnable
2018-02-11T21:11:26.906Z,1518383486.906 [BPC1](INFO): Runnable
2018-02-11T21:11:27.327Z,1518383487.327 [BPC1](INFO): Runnable
2018-02-11T21:11:30.429Z,1518383490.429 [BPC1](INFO): Runnable
2018-02-11T21:11:30.429Z,1518383490.429 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:11:30.566Z,1518383490.566 [BPC1](INFO): Runnable
2018-02-11T21:11:30.906Z,1518383490.906 [BPC1](INFO): Runnable
2018-02-11T21:11:31.307Z,1518383491.307 [BPC1](INFO): Runnable
2018-02-11T21:11:31.308Z,1518383491.308 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:11:31.314Z,1518383491.314 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:11:31.701Z,1518383491.701 [BPC1](INFO): Runnable
2018-02-11T21:11:31.713Z,1518383491.713 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:11:32.109Z,1518383492.109 [BPC1](INFO): Runnable
2018-02-11T21:11:32.503Z,1518383492.503 [BPC1](INFO): Runnable
2018-02-11T21:11:32.503Z,1518383492.503 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:11:32.900Z,1518383492.900 [BPC1](INFO): Runnable
2018-02-11T21:11:33.311Z,1518383493.311 [BPC1](INFO): Runnable
2018-02-11T21:11:33.710Z,1518383493.710 [BPC1](INFO): Runnable
2018-02-11T21:11:34.107Z,1518383494.107 [BPC1](INFO): Runnable
2018-02-11T21:11:34.498Z,1518383494.498 [BPC1](INFO): Runnable
2018-02-11T21:11:34.907Z,1518383494.907 [BPC1](INFO): Runnable
2018-02-11T21:11:35.306Z,1518383495.306 [BPC1](INFO): Runnable
2018-02-11T21:11:35.705Z,1518383495.705 [BPC1](INFO): Runnable
2018-02-11T21:11:36.101Z,1518383496.101 [BPC1](INFO): Runnable
2018-02-11T21:11:36.515Z,1518383496.515 [BPC1](INFO): Runnable
2018-02-11T21:11:36.913Z,1518383496.913 [BPC1](INFO): Runnable
2018-02-11T21:11:36.914Z,1518383496.914 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:11:36.925Z,1518383496.925 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:11:37.290Z,1518383497.290 [BPC1](INFO): Runnable
2018-02-11T21:11:37.305Z,1518383497.305 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:11:37.685Z,1518383497.685 [BPC1](INFO): Runnable
2018-02-11T21:11:38.111Z,1518383498.111 [BPC1](INFO): Runnable
2018-02-11T21:11:38.492Z,1518383498.492 [BPC1](INFO): Runnable
2018-02-11T21:11:38.894Z,1518383498.894 [BPC1](INFO): Runnable
2018-02-11T21:11:38.894Z,1518383498.894 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:11:39.302Z,1518383499.302 [BPC1](INFO): Runnable
2018-02-11T21:11:39.713Z,1518383499.713 [BPC1](INFO): Runnable
2018-02-11T21:11:40.117Z,1518383500.117 [BPC1](INFO): Runnable
2018-02-11T21:11:40.498Z,1518383500.498 [BPC1](INFO): Runnable
2018-02-11T21:11:40.893Z,1518383500.893 [BPC1](INFO): Runnable
2018-02-11T21:11:41.309Z,1518383501.309 [BPC1](INFO): Runnable
2018-02-11T21:11:41.696Z,1518383501.696 [BPC1](INFO): Runnable
2018-02-11T21:11:41.707Z,1518383501.707 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-11T21:11:41.708Z,1518383501.708 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-02-11T21:11:41.708Z,1518383501.708 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-02-11T21:11:41.710Z,1518383501.710 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-11T21:11:41.710Z,1518383501.710 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-02-11T21:11:41.710Z,1518383501.710 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-11T21:11:41.758Z,1518383501.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-02-11T21:11:41.758Z,1518383501.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-11T21:11:42.106Z,1518383502.106 [BPC1](INFO): Runnable
2018-02-11T21:11:42.153Z,1518383502.153 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-02-11T21:11:42.153Z,1518383502.153 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-02-11T21:11:42.154Z,1518383502.154 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-11T21:11:42.154Z,1518383502.154 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-02-11T21:11:42.509Z,1518383502.509 [BPC1](INFO): Runnable
2018-02-11T21:11:42.517Z,1518383502.517 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-02-11T21:11:42.517Z,1518383502.517 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:11:42.518Z,1518383502.518 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:11:42.518Z,1518383502.518 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-11T21:11:42.518Z,1518383502.518 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-11T21:11:42.519Z,1518383502.519 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-11T21:11:42.917Z,1518383502.917 [BPC1](INFO): Runnable
2018-02-11T21:11:42.931Z,1518383502.931 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-02-11T21:11:43.286Z,1518383503.286 [BPC1](INFO): Runnable
2018-02-11T21:11:43.287Z,1518383503.287 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:11:43.306Z,1518383503.306 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:11:43.306Z,1518383503.306 [BPC1](DEBUG): Received data from all battery sticks
2018-02-11T21:11:43.383Z,1518383503.383 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2018-02-11T21:11:43.687Z,1518383503.687 [BPC1](INFO): Stop
2018-02-11T21:11:43.688Z,1518383503.688 [BPC1](INFO): uninitialize
2018-02-11T21:11:43.700Z,1518383503.700 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-02-11T21:11:44.094Z,1518383504.094 [BPC1](INFO): Stopping
2018-02-11T21:11:44.102Z,1518383504.102 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-02-11T21:11:44.505Z,1518383504.505 [BPC1](INFO): Stopped
2018-02-11T21:11:44.532Z,1518383504.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-02-11T21:11:44.892Z,1518383504.892 [BPC1](INFO): Start
2018-02-11T21:11:44.974Z,1518383504.974 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2018-02-11T21:11:45.307Z,1518383505.307 [BPC1](INFO): Starting
2018-02-11T21:11:45.307Z,1518383505.307 [BPC1](INFO): Initialized.
2018-02-11T21:11:45.344Z,1518383505.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-02-11T21:11:45.375Z,1518383505.375 [Reporter](INFO): platform_battery_voltage 15.354758 _
2018-02-11T21:11:45.703Z,1518383505.703 [BPC1](INFO): Runnable
2018-02-11T21:11:45.729Z,1518383505.729 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:11:45.741Z,1518383505.741 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:11:45.749Z,1518383505.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-02-11T21:11:46.109Z,1518383506.109 [BPC1](INFO): Runnable
2018-02-11T21:11:46.137Z,1518383506.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-02-11T21:11:46.494Z,1518383506.494 [BPC1](INFO): Runnable
2018-02-11T21:11:46.505Z,1518383506.505 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-02-11T21:11:46.898Z,1518383506.898 [BPC1](INFO): Runnable
2018-02-11T21:11:46.908Z,1518383506.908 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-02-11T21:11:47.301Z,1518383507.301 [BPC1](INFO): Runnable
2018-02-11T21:11:47.315Z,1518383507.315 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-02-11T21:11:47.701Z,1518383507.701 [BPC1](INFO): Runnable
2018-02-11T21:11:47.719Z,1518383507.719 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-02-11T21:11:48.109Z,1518383508.109 [BPC1](INFO): Runnable
2018-02-11T21:11:48.118Z,1518383508.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-02-11T21:11:48.510Z,1518383508.510 [BPC1](INFO): Runnable
2018-02-11T21:11:48.519Z,1518383508.519 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-02-11T21:11:48.913Z,1518383508.913 [BPC1](INFO): Runnable
2018-02-11T21:11:48.938Z,1518383508.938 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-02-11T21:11:49.305Z,1518383509.305 [BPC1](INFO): Runnable
2018-02-11T21:11:49.313Z,1518383509.313 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-02-11T21:11:49.693Z,1518383509.693 [BPC1](INFO): Runnable
2018-02-11T21:11:49.702Z,1518383509.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-02-11T21:11:50.100Z,1518383510.100 [BPC1](INFO): Runnable
2018-02-11T21:11:50.110Z,1518383510.110 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-02-11T21:11:50.502Z,1518383510.502 [BPC1](INFO): Runnable
2018-02-11T21:11:50.534Z,1518383510.534 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-02-11T21:11:50.886Z,1518383510.886 [BPC1](INFO): Runnable
2018-02-11T21:11:50.923Z,1518383510.923 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-02-11T21:11:51.308Z,1518383511.308 [BPC1](INFO): Runnable
2018-02-11T21:11:51.321Z,1518383511.321 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-02-11T21:11:51.721Z,1518383511.721 [BPC1](INFO): Runnable
2018-02-11T21:11:51.729Z,1518383511.729 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-02-11T21:11:52.109Z,1518383512.109 [BPC1](INFO): Runnable
2018-02-11T21:11:52.117Z,1518383512.117 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-02-11T21:11:52.501Z,1518383512.501 [BPC1](INFO): Runnable
2018-02-11T21:11:52.524Z,1518383512.524 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-02-11T21:11:52.886Z,1518383512.886 [BPC1](INFO): Runnable
2018-02-11T21:11:52.929Z,1518383512.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-02-11T21:11:53.289Z,1518383513.289 [BPC1](INFO): Runnable
2018-02-11T21:11:53.289Z,1518383513.289 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:11:53.289Z,1518383513.289 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:11:53.310Z,1518383513.310 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-02-11T21:11:53.692Z,1518383513.692 [BPC1](INFO): Runnable
2018-02-11T21:11:53.704Z,1518383513.704 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-02-11T21:11:54.100Z,1518383514.100 [BPC1](INFO): Runnable
2018-02-11T21:11:54.108Z,1518383514.108 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-02-11T21:11:54.509Z,1518383514.509 [BPC1](INFO): Runnable
2018-02-11T21:11:54.542Z,1518383514.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-02-11T21:11:54.898Z,1518383514.898 [BPC1](INFO): Runnable
2018-02-11T21:11:54.915Z,1518383514.915 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-02-11T21:11:55.294Z,1518383515.294 [BPC1](INFO): Runnable
2018-02-11T21:11:55.307Z,1518383515.307 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-02-11T21:11:55.697Z,1518383515.697 [BPC1](INFO): Runnable
2018-02-11T21:11:55.705Z,1518383515.705 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-02-11T21:11:56.093Z,1518383516.093 [BPC1](INFO): Runnable
2018-02-11T21:11:56.102Z,1518383516.102 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-02-11T21:11:56.500Z,1518383516.500 [BPC1](INFO): Runnable
2018-02-11T21:11:56.512Z,1518383516.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-02-11T21:11:56.893Z,1518383516.893 [BPC1](INFO): Runnable
2018-02-11T21:11:56.911Z,1518383516.911 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-02-11T21:11:57.302Z,1518383517.302 [BPC1](INFO): Runnable
2018-02-11T21:11:57.310Z,1518383517.310 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-02-11T21:11:57.709Z,1518383517.709 [BPC1](INFO): Runnable
2018-02-11T21:11:57.710Z,1518383517.710 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:11:57.716Z,1518383517.716 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:11:57.717Z,1518383517.717 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:11:57.728Z,1518383517.728 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:11:57.775Z,1518383517.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-02-11T21:11:58.105Z,1518383518.105 [BPC1](INFO): Runnable
2018-02-11T21:11:58.117Z,1518383518.117 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:11:58.129Z,1518383518.129 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:11:58.172Z,1518383518.172 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2018-02-11T21:11:58.495Z,1518383518.495 [BPC1](INFO): Runnable
2018-02-11T21:11:58.509Z,1518383518.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-02-11T21:11:58.897Z,1518383518.897 [BPC1](INFO): Runnable
2018-02-11T21:11:58.919Z,1518383518.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-02-11T21:11:59.302Z,1518383519.302 [BPC1](INFO): Runnable
2018-02-11T21:11:59.311Z,1518383519.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-02-11T21:11:59.702Z,1518383519.702 [BPC1](INFO): Runnable
2018-02-11T21:11:59.711Z,1518383519.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-02-11T21:12:00.109Z,1518383520.109 [BPC1](INFO): Runnable
2018-02-11T21:12:00.118Z,1518383520.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-02-11T21:12:00.467Z,1518383520.467 [NAL9602](INFO): GPS fix at 20180211T210207: (36.802759, -121.788066)
2018-02-11T21:12:00.507Z,1518383520.507 [BPC1](INFO): Runnable
2018-02-11T21:12:00.519Z,1518383520.519 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-02-11T21:12:00.540Z,1518383520.540 [Default:CheckIn:Read_GPS] Stopped
2018-02-11T21:12:00.540Z,1518383520.540 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-02-11T21:12:00.901Z,1518383520.901 [BPC1](INFO): Runnable
2018-02-11T21:12:00.938Z,1518383520.938 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-02-11T21:12:00.947Z,1518383520.947 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-02-11T21:12:02.036Z,1518383522.036 [BPC1](INFO): Runnable
2018-02-11T21:12:02.182Z,1518383522.182 [BPC1](INFO): Runnable
2018-02-11T21:12:07.662Z,1518383527.662 [BPC1](INFO): Runnable
2018-02-11T21:12:07.663Z,1518383527.663 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:12:07.663Z,1518383527.663 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:12:07.741Z,1518383527.741 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20180211T210531/Courier0004.lzma
2018-02-11T21:12:07.780Z,1518383527.780 [BPC1](INFO): Runnable
2018-02-11T21:12:08.137Z,1518383528.137 [BPC1](INFO): Runnable
2018-02-11T21:12:08.494Z,1518383528.494 [DataOverHttps](INFO): Moved sent file to Logs/20180211T210531/Courier0004.lzma.bak
2018-02-11T21:12:08.494Z,1518383528.494 [DataOverHttps](INFO): SBD MOMSN=7852726
2018-02-11T21:12:08.565Z,1518383528.565 [BPC1](INFO): Runnable
2018-02-11T21:12:10.533Z,1518383530.533 [BPC1](INFO): Runnable
2018-02-11T21:12:10.533Z,1518383530.533 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:12:10.543Z,1518383530.543 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:12:10.544Z,1518383530.544 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:12:10.581Z,1518383530.581 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:12:10.715Z,1518383530.715 [BPC1](INFO): Runnable
2018-02-11T21:12:10.729Z,1518383530.729 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:12:10.741Z,1518383530.741 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:12:14.051Z,1518383534.051 [BPC1](INFO): Runnable
2018-02-11T21:12:14.180Z,1518383534.180 [BPC1](INFO): Runnable
2018-02-11T21:12:14.530Z,1518383534.530 [BPC1](INFO): Runnable
2018-02-11T21:12:14.915Z,1518383534.915 [BPC1](INFO): Runnable
2018-02-11T21:12:15.320Z,1518383535.320 [BPC1](INFO): Runnable
2018-02-11T21:12:15.718Z,1518383535.718 [BPC1](INFO): Runnable
2018-02-11T21:12:19.700Z,1518383539.700 [BPC1](INFO): Runnable
2018-02-11T21:12:19.700Z,1518383539.700 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:12:19.700Z,1518383539.700 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:12:19.821Z,1518383539.821 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20180211T210531/Express0005.lzma
2018-02-11T21:12:19.891Z,1518383539.891 [BPC1](INFO): Runnable
2018-02-11T21:12:20.277Z,1518383540.277 [BPC1](INFO): Runnable
2018-02-11T21:12:20.619Z,1518383540.619 [DataOverHttps](INFO): Moved sent file to Logs/20180211T210531/Express0005.lzma.bak
2018-02-11T21:12:20.619Z,1518383540.619 [DataOverHttps](INFO): SBD MOMSN=7852729
2018-02-11T21:12:20.719Z,1518383540.719 [BPC1](INFO): Runnable
2018-02-11T21:12:21.017Z,1518383541.017 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:22.592Z,1518383542.592 [BPC1](INFO): Runnable
2018-02-11T21:12:22.593Z,1518383542.593 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:12:22.599Z,1518383542.599 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:12:22.599Z,1518383542.599 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:12:22.605Z,1518383542.605 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:12:22.643Z,1518383542.643 [Default:CheckIn:Read_Iridium] Stopped
2018-02-11T21:12:22.643Z,1518383542.643 [Default:CheckIn:C.Wait] Running Loop=1
2018-02-11T21:12:22.643Z,1518383542.643 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-02-11T21:12:22.681Z,1518383542.681 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:22.737Z,1518383542.737 [BPC1](INFO): Runnable
2018-02-11T21:12:22.749Z,1518383542.749 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:12:22.761Z,1518383542.761 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:12:23.072Z,1518383543.072 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:25.922Z,1518383545.922 [BPC1](INFO): Runnable
2018-02-11T21:12:25.922Z,1518383545.922 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:12:26.016Z,1518383546.016 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:26.074Z,1518383546.074 [BPC1](INFO): Runnable
2018-02-11T21:12:26.404Z,1518383546.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:26.444Z,1518383546.444 [BPC1](INFO): Runnable
2018-02-11T21:12:26.804Z,1518383546.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:26.841Z,1518383546.841 [BPC1](INFO): Runnable
2018-02-11T21:12:27.204Z,1518383547.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:27.245Z,1518383547.245 [BPC1](INFO): Runnable
2018-02-11T21:12:27.604Z,1518383547.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:27.649Z,1518383547.649 [BPC1](INFO): Runnable
2018-02-11T21:12:27.649Z,1518383547.649 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:12:27.663Z,1518383547.663 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:12:28.004Z,1518383548.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:28.047Z,1518383548.047 [BPC1](INFO): Runnable
2018-02-11T21:12:28.061Z,1518383548.061 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:12:28.441Z,1518383548.441 [BPC1](INFO): Runnable
2018-02-11T21:12:28.834Z,1518383548.834 [BPC1](INFO): Runnable
2018-02-11T21:12:29.234Z,1518383549.234 [BPC1](INFO): Runnable
2018-02-11T21:12:29.633Z,1518383549.633 [BPC1](INFO): Runnable
2018-02-11T21:12:29.634Z,1518383549.634 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:12:30.038Z,1518383550.038 [BPC1](INFO): Runnable
2018-02-11T21:12:30.433Z,1518383550.433 [BPC1](INFO): Runnable
2018-02-11T21:12:30.838Z,1518383550.838 [BPC1](INFO): Runnable
2018-02-11T21:12:31.242Z,1518383551.242 [BPC1](INFO): Runnable
2018-02-11T21:12:31.637Z,1518383551.637 [BPC1](INFO): Runnable
2018-02-11T21:12:32.041Z,1518383552.041 [BPC1](INFO): Runnable
2018-02-11T21:12:32.445Z,1518383552.445 [BPC1](INFO): Runnable
2018-02-11T21:12:32.844Z,1518383552.844 [BPC1](INFO): Runnable
2018-02-11T21:12:33.242Z,1518383553.242 [BPC1](INFO): Runnable
2018-02-11T21:12:33.648Z,1518383553.648 [BPC1](INFO): Runnable
2018-02-11T21:12:34.045Z,1518383554.045 [BPC1](INFO): Runnable
2018-02-11T21:12:34.045Z,1518383554.045 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:12:34.052Z,1518383554.052 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:12:34.433Z,1518383554.433 [BPC1](INFO): Runnable
2018-02-11T21:12:34.449Z,1518383554.449 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:12:34.838Z,1518383554.838 [BPC1](INFO): Runnable
2018-02-11T21:12:35.233Z,1518383555.233 [BPC1](INFO): Runnable
2018-02-11T21:12:35.234Z,1518383555.234 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:12:35.641Z,1518383555.641 [BPC1](INFO): Runnable
2018-02-11T21:12:36.040Z,1518383556.040 [BPC1](INFO): Runnable
2018-02-11T21:12:36.441Z,1518383556.441 [BPC1](INFO): Runnable
2018-02-11T21:12:36.804Z,1518383556.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:36.849Z,1518383556.849 [BPC1](INFO): Runnable
2018-02-11T21:12:37.204Z,1518383557.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:37.243Z,1518383557.243 [BPC1](INFO): Runnable
2018-02-11T21:12:37.604Z,1518383557.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:37.642Z,1518383557.642 [BPC1](INFO): Runnable
2018-02-11T21:12:38.004Z,1518383558.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:38.009Z,1518383558.009 [NAL9602](INFO): Powering down
2018-02-11T21:12:38.059Z,1518383558.059 [BPC1](INFO): Runnable
2018-02-11T21:12:38.404Z,1518383558.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:38.449Z,1518383558.449 [BPC1](INFO): Runnable
2018-02-11T21:12:38.804Z,1518383558.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:38.845Z,1518383558.845 [BPC1](INFO): Runnable
2018-02-11T21:12:39.204Z,1518383559.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:39.233Z,1518383559.233 [BPC1](INFO): Runnable
2018-02-11T21:12:39.604Z,1518383559.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:39.638Z,1518383559.638 [BPC1](INFO): Runnable
2018-02-11T21:12:39.638Z,1518383559.638 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:12:39.657Z,1518383559.657 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:12:39.657Z,1518383559.657 [BPC1](DEBUG): Received data from all battery sticks
2018-02-11T21:12:40.004Z,1518383560.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:40.047Z,1518383560.047 [BPC1](INFO): Stop
2018-02-11T21:12:40.047Z,1518383560.047 [BPC1](INFO): uninitialize
2018-02-11T21:12:40.404Z,1518383560.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:40.442Z,1518383560.442 [BPC1](INFO): Stopping
2018-02-11T21:12:40.804Z,1518383560.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:40.845Z,1518383560.845 [BPC1](INFO): Stopped
2018-02-11T21:12:41.204Z,1518383561.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:41.239Z,1518383561.239 [BPC1](INFO): Start
2018-02-11T21:12:41.604Z,1518383561.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:41.642Z,1518383561.642 [BPC1](INFO): Starting
2018-02-11T21:12:41.642Z,1518383561.642 [BPC1](INFO): Initialized.
2018-02-11T21:12:41.668Z,1518383561.668 [Reporter](INFO): platform_battery_voltage 15.354096 _
2018-02-11T21:12:42.004Z,1518383562.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:42.045Z,1518383562.045 [BPC1](INFO): Runnable
2018-02-11T21:12:42.057Z,1518383562.057 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:12:42.404Z,1518383562.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:42.445Z,1518383562.445 [BPC1](INFO): Runnable
2018-02-11T21:12:42.804Z,1518383562.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:42.842Z,1518383562.842 [BPC1](INFO): Runnable
2018-02-11T21:12:43.204Z,1518383563.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:43.249Z,1518383563.249 [BPC1](INFO): Runnable
2018-02-11T21:12:43.604Z,1518383563.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:43.645Z,1518383563.645 [BPC1](INFO): Runnable
2018-02-11T21:12:44.004Z,1518383564.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:44.041Z,1518383564.041 [BPC1](INFO): Runnable
2018-02-11T21:12:44.404Z,1518383564.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:44.434Z,1518383564.434 [BPC1](INFO): Runnable
2018-02-11T21:12:44.804Z,1518383564.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:44.833Z,1518383564.833 [BPC1](INFO): Runnable
2018-02-11T21:12:45.204Z,1518383565.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:45.234Z,1518383565.234 [BPC1](INFO): Runnable
2018-02-11T21:12:45.604Z,1518383565.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:45.639Z,1518383565.639 [BPC1](INFO): Runnable
2018-02-11T21:12:46.004Z,1518383566.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:46.040Z,1518383566.040 [BPC1](INFO): Runnable
2018-02-11T21:12:46.404Z,1518383566.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:46.442Z,1518383566.442 [BPC1](INFO): Runnable
2018-02-11T21:12:46.804Z,1518383566.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:46.845Z,1518383566.845 [BPC1](INFO): Runnable
2018-02-11T21:12:46.861Z,1518383566.861 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:12:47.204Z,1518383567.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:47.238Z,1518383567.238 [BPC1](INFO): Runnable
2018-02-11T21:12:47.604Z,1518383567.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:47.643Z,1518383567.643 [BPC1](INFO): Runnable
2018-02-11T21:12:48.004Z,1518383568.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:48.045Z,1518383568.045 [BPC1](INFO): Runnable
2018-02-11T21:12:48.404Z,1518383568.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:48.445Z,1518383568.445 [BPC1](INFO): Runnable
2018-02-11T21:12:48.804Z,1518383568.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:48.845Z,1518383568.845 [BPC1](INFO): Runnable
2018-02-11T21:12:49.204Z,1518383569.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:49.233Z,1518383569.233 [BPC1](INFO): Runnable
2018-02-11T21:12:49.604Z,1518383569.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:49.633Z,1518383569.633 [BPC1](INFO): Runnable
2018-02-11T21:12:49.633Z,1518383569.633 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:12:50.004Z,1518383570.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:50.033Z,1518383570.033 [BPC1](INFO): Runnable
2018-02-11T21:12:50.404Z,1518383570.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:50.434Z,1518383570.434 [BPC1](INFO): Runnable
2018-02-11T21:12:50.804Z,1518383570.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:50.833Z,1518383570.833 [BPC1](INFO): Runnable
2018-02-11T21:12:51.204Z,1518383571.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:51.241Z,1518383571.241 [BPC1](INFO): Runnable
2018-02-11T21:12:51.604Z,1518383571.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:51.643Z,1518383571.643 [BPC1](INFO): Runnable
2018-02-11T21:12:52.004Z,1518383572.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:52.040Z,1518383572.040 [BPC1](INFO): Runnable
2018-02-11T21:12:52.404Z,1518383572.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:52.438Z,1518383572.438 [BPC1](INFO): Runnable
2018-02-11T21:12:52.804Z,1518383572.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:52.841Z,1518383572.841 [BPC1](INFO): Runnable
2018-02-11T21:12:53.204Z,1518383573.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:53.241Z,1518383573.241 [BPC1](INFO): Runnable
2018-02-11T21:12:53.604Z,1518383573.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:53.641Z,1518383573.641 [BPC1](INFO): Runnable
2018-02-11T21:12:54.004Z,1518383574.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:54.046Z,1518383574.046 [BPC1](INFO): Runnable
2018-02-11T21:12:54.404Z,1518383574.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:54.441Z,1518383574.441 [BPC1](INFO): Runnable
2018-02-11T21:12:54.442Z,1518383574.442 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:12:54.448Z,1518383574.448 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:12:54.804Z,1518383574.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:54.833Z,1518383574.833 [BPC1](INFO): Runnable
2018-02-11T21:12:54.845Z,1518383574.845 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:12:54.845Z,1518383574.845 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:12:55.204Z,1518383575.204 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:55.243Z,1518383575.243 [BPC1](INFO): Runnable
2018-02-11T21:12:55.604Z,1518383575.604 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:55.638Z,1518383575.638 [BPC1](INFO): Runnable
2018-02-11T21:12:56.004Z,1518383576.004 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:56.044Z,1518383576.044 [BPC1](INFO): Runnable
2018-02-11T21:12:56.404Z,1518383576.404 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:56.445Z,1518383576.445 [BPC1](INFO): Runnable
2018-02-11T21:12:56.804Z,1518383576.804 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-02-11T21:12:56.841Z,1518383576.841 [BPC1](INFO): Runnable
2018-02-11T21:12:57.241Z,1518383577.241 [BPC1](INFO): Runnable
2018-02-11T21:12:57.648Z,1518383577.648 [BPC1](INFO): Runnable
2018-02-11T21:12:58.043Z,1518383578.043 [BPC1](INFO): Runnable
2018-02-11T21:12:58.441Z,1518383578.441 [BPC1](INFO): Runnable
2018-02-11T21:12:58.845Z,1518383578.845 [BPC1](INFO): Runnable
2018-02-11T21:12:59.276Z,1518383579.276 [BPC1](INFO): Runnable
2018-02-11T21:12:59.276Z,1518383579.276 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-02-11T21:12:59.283Z,1518383579.283 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:12:59.641Z,1518383579.641 [BPC1](INFO): Runnable
2018-02-11T21:12:59.653Z,1518383579.653 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:13:00.037Z,1518383580.037 [BPC1](INFO): Runnable
2018-02-11T21:13:00.470Z,1518383580.470 [BPC1](INFO): Runnable
2018-02-11T21:13:00.854Z,1518383580.854 [BPC1](INFO): Runnable
2018-02-11T21:13:01.294Z,1518383581.294 [BPC1](INFO): Runnable
2018-02-11T21:13:01.635Z,1518383581.635 [BPC1](INFO): Runnable
2018-02-11T21:13:02.047Z,1518383582.047 [BPC1](INFO): Runnable
2018-02-11T21:13:02.439Z,1518383582.439 [BPC1](INFO): Runnable
2018-02-11T21:13:02.439Z,1518383582.439 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:13:02.853Z,1518383582.853 [BPC1](INFO): Runnable
2018-02-11T21:13:03.240Z,1518383583.240 [BPC1](INFO): Runnable
2018-02-11T21:13:03.646Z,1518383583.646 [BPC1](INFO): Runnable
2018-02-11T21:13:04.044Z,1518383584.044 [BPC1](INFO): Runnable
2018-02-11T21:13:04.435Z,1518383584.435 [BPC1](INFO): Runnable
2018-02-11T21:13:04.833Z,1518383584.833 [BPC1](INFO): Runnable
2018-02-11T21:13:05.254Z,1518383585.254 [BPC1](INFO): Runnable
2018-02-11T21:13:05.648Z,1518383585.648 [BPC1](INFO): Runnable
2018-02-11T21:13:06.039Z,1518383586.039 [BPC1](INFO): Runnable
2018-02-11T21:13:06.440Z,1518383586.440 [BPC1](INFO): Runnable
2018-02-11T21:13:06.843Z,1518383586.843 [BPC1](INFO): Runnable
2018-02-11T21:13:07.242Z,1518383587.242 [BPC1](INFO): Runnable
2018-02-11T21:13:07.243Z,1518383587.243 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-02-11T21:13:07.262Z,1518383587.262 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:13:07.655Z,1518383587.655 [BPC1](INFO): Runnable
2018-02-11T21:13:07.669Z,1518383587.669 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:13:07.685Z,1518383587.685 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:13:08.048Z,1518383588.048 [BPC1](INFO): Runnable
2018-02-11T21:13:08.451Z,1518383588.451 [BPC1](INFO): Runnable
2018-02-11T21:13:08.850Z,1518383588.850 [BPC1](INFO): Runnable
2018-02-11T21:13:09.250Z,1518383589.250 [BPC1](INFO): Runnable
2018-02-11T21:13:09.635Z,1518383589.635 [BPC1](INFO): Runnable
2018-02-11T21:13:10.036Z,1518383590.036 [BPC1](INFO): Runnable
2018-02-11T21:13:10.442Z,1518383590.442 [BPC1](INFO): Runnable
2018-02-11T21:13:10.835Z,1518383590.835 [BPC1](INFO): Runnable
2018-02-11T21:13:11.250Z,1518383591.250 [BPC1](INFO): Runnable
2018-02-11T21:13:11.639Z,1518383591.639 [BPC1](INFO): Runnable
2018-02-11T21:13:12.034Z,1518383592.034 [BPC1](INFO): Runnable
2018-02-11T21:13:12.034Z,1518383592.034 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:13:12.040Z,1518383592.040 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:13:12.454Z,1518383592.454 [BPC1](INFO): Runnable
2018-02-11T21:13:12.465Z,1518383592.465 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:13:12.835Z,1518383592.835 [BPC1](INFO): Runnable
2018-02-11T21:13:13.237Z,1518383593.237 [BPC1](INFO): Runnable
2018-02-11T21:13:13.638Z,1518383593.638 [BPC1](INFO): Runnable
2018-02-11T21:13:14.037Z,1518383594.037 [BPC1](INFO): Runnable
2018-02-11T21:13:14.445Z,1518383594.445 [BPC1](INFO): Runnable
2018-02-11T21:13:14.445Z,1518383594.445 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:13:14.842Z,1518383594.842 [BPC1](INFO): Runnable
2018-02-11T21:13:14.842Z,1518383594.842 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:13:15.243Z,1518383595.243 [BPC1](INFO): Runnable
2018-02-11T21:13:15.646Z,1518383595.646 [BPC1](INFO): Runnable
2018-02-11T21:13:16.045Z,1518383596.045 [BPC1](INFO): Runnable
2018-02-11T21:13:16.437Z,1518383596.437 [BPC1](INFO): Runnable
2018-02-11T21:13:16.847Z,1518383596.847 [BPC1](INFO): Runnable
2018-02-11T21:13:17.233Z,1518383597.233 [BPC1](INFO): Runnable
2018-02-11T21:13:17.634Z,1518383597.634 [BPC1](INFO): Runnable
2018-02-11T21:13:18.038Z,1518383598.038 [BPC1](INFO): Runnable
2018-02-11T21:13:18.038Z,1518383598.038 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-02-11T21:13:18.044Z,1518383598.044 [BPC1](INFO): Bank B: data parsed.
2018-02-11T21:13:18.434Z,1518383598.434 [BPC1](INFO): Runnable
2018-02-11T21:13:18.445Z,1518383598.445 [BPC1](INFO): Bank B: initialized.
2018-02-11T21:13:18.840Z,1518383598.840 [BPC1](INFO): Runnable
2018-02-11T21:13:18.840Z,1518383598.840 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:13:18.846Z,1518383598.846 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:13:19.249Z,1518383599.249 [BPC1](INFO): Runnable
2018-02-11T21:13:19.261Z,1518383599.261 [BPC1](INFO): Bank A: initialized.
2018-02-11T21:13:19.644Z,1518383599.644 [BPC1](INFO): Runnable
2018-02-11T21:13:20.050Z,1518383600.050 [BPC1](INFO): Runnable
2018-02-11T21:13:20.450Z,1518383600.450 [BPC1](INFO): Runnable
2018-02-11T21:13:20.833Z,1518383600.833 [BPC1](INFO): Runnable
2018-02-11T21:13:20.833Z,1518383600.833 [BPC1](INFO): Bank A: sending IBPS break.
2018-02-11T21:13:21.233Z,1518383601.233 [BPC1](INFO): Runnable
2018-02-11T21:13:21.637Z,1518383601.637 [BPC1](INFO): Runnable
2018-02-11T21:13:22.041Z,1518383602.041 [BPC1](INFO): Runnable
2018-02-11T21:13:22.447Z,1518383602.447 [BPC1](INFO): Runnable
2018-02-11T21:13:22.842Z,1518383602.842 [BPC1](INFO): Runnable
2018-02-11T21:13:23.239Z,1518383603.239 [BPC1](INFO): Runnable
2018-02-11T21:13:24.255Z,1518383604.255 [CommandLine](IMPORTANT): got command restart application
2018-02-11T21:13:25.260Z,1518383605.260 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:13:25.260Z,1518383605.260 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:25.341Z,1518383605.341 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-02-11T21:13:25.341Z,1518383605.341 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:25.341Z,1518383605.341 [CommandLine](INFO): Join timeout helper Thread ID is 954
2018-02-11T21:13:25.353Z,1518383605.353 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-02-11T21:13:25.353Z,1518383605.353 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:25.353Z,1518383605.353 [NavChartDb](INFO): Join timeout helper Thread ID is 955
2018-02-11T21:13:25.381Z,1518383605.381 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:13:25.381Z,1518383605.381 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:25.386Z,1518383605.386 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-02-11T21:13:25.386Z,1518383605.386 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:25.386Z,1518383605.386 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 956
2018-02-11T21:13:25.477Z,1518383605.477 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:13:25.477Z,1518383605.477 [WetLabsBB2FL](INFO): Powering down
2018-02-11T21:13:25.478Z,1518383605.478 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:25.485Z,1518383605.485 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-02-11T21:13:25.485Z,1518383605.485 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:25.485Z,1518383605.485 [CTD_Seabird](INFO): Join timeout helper Thread ID is 957
2018-02-11T21:13:25.609Z,1518383605.609 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:13:25.769Z,1518383605.769 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-11T21:13:25.769Z,1518383605.769 [CTD_Seabird](INFO): Powering down
2018-02-11T21:13:25.770Z,1518383605.770 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:25.777Z,1518383605.777 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-02-11T21:13:25.777Z,1518383605.777 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:25.777Z,1518383605.777 [Radio_Surface](INFO): Join timeout helper Thread ID is 959
2018-02-11T21:13:26.125Z,1518383606.125 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:13:26.125Z,1518383606.125 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:26.146Z,1518383606.146 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-02-11T21:13:26.146Z,1518383606.146 [logger ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:26.146Z,1518383606.146 [logger](INFO): Join timeout helper Thread ID is 960
2018-02-11T21:13:26.173Z,1518383606.173 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:13:26.173Z,1518383606.173 [logger ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:26.177Z,1518383606.177 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-02-11T21:13:26.178Z,1518383606.178 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:26.178Z,1518383606.178 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-02-11T21:13:26.178Z,1518383606.178 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:26.178Z,1518383606.178 [controlThread](INFO): Join timeout helper Thread ID is 961
2018-02-11T21:13:26.531Z,1518383606.531 [BPC1](INFO): Runnable
2018-02-11T21:13:26.532Z,1518383606.532 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-02-11T21:13:26.539Z,1518383606.539 [BPC1](INFO): Bank A: data parsed.
2018-02-11T21:13:26.539Z,1518383606.539 [BPC1](INFO): Bank B: sending IBPS break.
2018-02-11T21:13:26.570Z,1518383606.570 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-11T21:13:26.570Z,1518383606.570 [controlThread](DEBUG): Uninitializing ControlThread
2018-02-11T21:13:26.570Z,1518383606.570 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-02-11T21:13:26.693Z,1518383606.693 [NAL9602](INFO): Powering down
2018-02-11T21:13:26.694Z,1518383606.694 [PNI_TCM](INFO): Powering down
2018-02-11T21:13:26.781Z,1518383606.781 [BPC1](INFO): uninitialize
2018-02-11T21:13:26.782Z,1518383606.782 [Aanderaa_O2](INFO): Powering down
2018-02-11T21:13:26.783Z,1518383606.783 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-02-11T21:13:26.784Z,1518383606.784 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-02-11T21:13:26.785Z,1518383606.785 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-02-11T21:13:26.785Z,1518383606.785 [MissionManager](INFO): Uninitializing Mission Default
2018-02-11T21:13:26.785Z,1518383606.785 [Default] Stopped
2018-02-11T21:13:26.785Z,1518383606.785 [Default](DEBUG): Aggregate::uninitialize Default
2018-02-11T21:13:26.786Z,1518383606.786 [Default:B.GoToSurface] Stopped
2018-02-11T21:13:26.786Z,1518383606.786 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-02-11T21:13:26.786Z,1518383606.786 [Default:CheckIn] Stopped
2018-02-11T21:13:26.786Z,1518383606.786 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-11T21:13:26.786Z,1518383606.786 [Default:CheckIn:C.Wait] Stopped
2018-02-11T21:13:26.786Z,1518383606.786 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-02-11T21:13:26.789Z,1518383606.789 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-02-11T21:13:26.790Z,1518383606.790 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-02-11T21:13:26.790Z,1518383606.790 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-02-11T21:13:26.790Z,1518383606.790 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-02-11T21:13:26.790Z,1518383606.790 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-11T21:13:26.791Z,1518383606.791 [BuoyancyServo](INFO): Powering down
2018-02-11T21:13:26.805Z,1518383606.805 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-02-11T21:13:26.805Z,1518383606.805 [ElevatorServo](INFO): Powering down
2018-02-11T21:13:26.806Z,1518383606.806 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-02-11T21:13:26.806Z,1518383606.806 [MassServo](INFO): Powering down
2018-02-11T21:13:26.807Z,1518383606.807 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-02-11T21:13:26.807Z,1518383606.807 [RudderServo](INFO): Powering down
2018-02-11T21:13:26.807Z,1518383606.807 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-02-11T21:13:26.807Z,1518383606.807 [ThrusterServo](INFO): Powering down
2018-02-11T21:13:26.808Z,1518383606.808 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-02-11T21:13:26.809Z,1518383606.809 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-02-11T21:13:26.809Z,1518383606.809 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-02-11T21:13:26.810Z,1518383606.810 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:26.909Z,1518383606.909 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:26.949Z,1518383606.949 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:26.953Z,1518383606.953 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:27.002Z,1518383607.002 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-11T21:13:27.069Z,1518383607.069 [logger ThreadHandler](INFO): Thread cancelled.