2018-03-14T22:46:35.253Z,1521067595.253 [Supervisor](DEBUG): Initializing supervisor. 2018-03-14T22:46:35.256Z,1521067595.256 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-03-14T22:46:35.257Z,1521067595.257 [SyncHandler](INFO): Protected caller Thread ID is 919 2018-03-14T22:46:35.258Z,1521067595.258 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-03-14T22:46:35.259Z,1521067595.259 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-03-14T22:46:35.259Z,1521067595.259 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 920 2018-03-14T22:46:35.262Z,1521067595.262 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-03-14T22:46:35.274Z,1521067595.274 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-03-14T22:46:35.275Z,1521067595.275 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-03-14T22:46:35.275Z,1521067595.275 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 921 2018-03-14T22:46:35.276Z,1521067595.276 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-03-14T22:46:35.277Z,1521067595.277 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-03-14T22:46:35.277Z,1521067595.277 [logger ThreadHandler](INFO): Protected caller Thread ID is 922 2018-03-14T22:46:35.279Z,1521067595.279 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-03-14T22:46:35.279Z,1521067595.279 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-03-14T22:46:35.281Z,1521067595.281 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-03-14T22:46:35.578Z,1521067595.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-03-14T22:46:35.578Z,1521067595.578 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-03-14T22:46:35.775Z,1521067595.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-03-14T22:46:35.775Z,1521067595.775 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-03-14T22:46:36.106Z,1521067596.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-03-14T22:46:36.107Z,1521067596.107 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-03-14T22:46:36.242Z,1521067596.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-03-14T22:46:36.242Z,1521067596.242 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-03-14T22:46:36.337Z,1521067596.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-03-14T22:46:36.338Z,1521067596.338 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-03-14T22:46:36.763Z,1521067596.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-03-14T22:46:36.764Z,1521067596.764 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-03-14T22:46:37.081Z,1521067597.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-03-14T22:46:37.082Z,1521067597.082 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-03-14T22:46:37.486Z,1521067597.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-03-14T22:46:37.487Z,1521067597.487 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-03-14T22:46:37.587Z,1521067597.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-03-14T22:46:37.587Z,1521067597.587 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-03-14T22:46:37.681Z,1521067597.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-03-14T22:46:37.682Z,1521067597.682 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-03-14T22:46:37.876Z,1521067597.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-03-14T22:46:37.877Z,1521067597.877 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-03-14T22:46:38.071Z,1521067598.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-03-14T22:46:38.071Z,1521067598.071 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-03-14T22:46:38.384Z,1521067598.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-03-14T22:46:38.385Z,1521067598.385 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-03-14T22:46:38.519Z,1521067598.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-03-14T22:46:38.520Z,1521067598.520 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-03-14T22:46:38.600Z,1521067598.600 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2018-03-14T22:46:38.601Z,1521067598.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2018-03-14T22:46:38.698Z,1521067598.698 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2018-03-14T22:46:38.786Z,1521067598.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2018-03-14T22:46:38.877Z,1521067598.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2018-03-14T22:46:38.959Z,1521067598.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2018-03-14T22:46:39.099Z,1521067599.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2018-03-14T22:46:39.260Z,1521067599.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2018-03-14T22:46:39.335Z,1521067599.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2018-03-14T22:46:39.555Z,1521067599.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-03-14T22:46:39.556Z,1521067599.556 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2018-03-14T22:46:39.637Z,1521067599.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2018-03-14T22:46:39.724Z,1521067599.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2018-03-14T22:46:39.820Z,1521067599.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2018-03-14T22:46:39.940Z,1521067599.940 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2018-03-14T22:46:39.941Z,1521067599.941 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-03-14T22:46:39.946Z,1521067599.946 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-03-14T22:46:40.196Z,1521067600.196 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-03-14T22:46:40.197Z,1521067600.197 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-03-14T22:46:40.239Z,1521067600.239 [DepthRateCalculator] Loaded 2018-03-14T22:46:40.240Z,1521067600.240 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-03-14T22:46:40.245Z,1521067600.245 [PitchRateCalculator] Loaded 2018-03-14T22:46:40.246Z,1521067600.246 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-03-14T22:46:40.261Z,1521067600.261 [SpeedCalculator] Loaded 2018-03-14T22:46:40.262Z,1521067600.262 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-03-14T22:46:40.282Z,1521067600.282 [TempGradientCalculator] Loaded 2018-03-14T22:46:40.282Z,1521067600.282 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-03-14T22:46:40.297Z,1521067600.297 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-03-14T22:46:40.298Z,1521067600.298 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-03-14T22:46:40.303Z,1521067600.303 [YawRateCalculator] Loaded 2018-03-14T22:46:40.303Z,1521067600.303 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-03-14T22:46:40.304Z,1521067600.304 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-03-14T22:46:40.304Z,1521067600.304 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-03-14T22:46:40.389Z,1521067600.389 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-03-14T22:46:40.389Z,1521067600.389 [StratificationFrontDetector](DEBUG): (re)initializing 2018-03-14T22:46:40.389Z,1521067600.389 [StratificationFrontDetector] Loaded 2018-03-14T22:46:40.389Z,1521067600.389 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-03-14T22:46:40.390Z,1521067600.390 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-03-14T22:46:40.391Z,1521067600.391 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-03-14T22:46:40.507Z,1521067600.507 [BuoyancyServo] Loaded 2018-03-14T22:46:40.507Z,1521067600.507 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-03-14T22:46:40.522Z,1521067600.522 [ElevatorServo] Loaded 2018-03-14T22:46:40.522Z,1521067600.522 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-03-14T22:46:40.536Z,1521067600.536 [MassServo] Loaded 2018-03-14T22:46:40.537Z,1521067600.537 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-03-14T22:46:40.551Z,1521067600.551 [RudderServo] Loaded 2018-03-14T22:46:40.551Z,1521067600.551 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-03-14T22:46:40.565Z,1521067600.565 [ThrusterServo] Loaded 2018-03-14T22:46:40.565Z,1521067600.565 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-03-14T22:46:40.566Z,1521067600.566 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-03-14T22:46:40.566Z,1521067600.566 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-03-14T22:46:40.580Z,1521067600.580 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-03-14T22:46:40.581Z,1521067600.581 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-03-14T22:46:40.681Z,1521067600.681 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-03-14T22:46:40.682Z,1521067600.682 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-03-14T22:46:40.957Z,1521067600.957 [DataOverHttps] Loaded 2018-03-14T22:46:40.957Z,1521067600.957 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-03-14T22:46:40.970Z,1521067600.970 [Depth_Keller] Loaded 2018-03-14T22:46:40.971Z,1521067600.971 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-03-14T22:46:40.975Z,1521067600.975 [DropWeight] Loaded 2018-03-14T22:46:40.976Z,1521067600.976 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-03-14T22:46:41.074Z,1521067601.074 [NAL9602] Loaded 2018-03-14T22:46:41.074Z,1521067601.074 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-03-14T22:46:41.089Z,1521067601.089 [Onboard] Loaded 2018-03-14T22:46:41.090Z,1521067601.090 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-03-14T22:46:41.099Z,1521067601.099 [Radio_Surface] Loaded 2018-03-14T22:46:41.100Z,1521067601.100 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-03-14T22:46:41.101Z,1521067601.101 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408714E0 2018-03-14T22:46:41.101Z,1521067601.101 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1004 2018-03-14T22:46:41.148Z,1521067601.148 [PNI_TCM] Loaded 2018-03-14T22:46:41.149Z,1521067601.149 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-03-14T22:46:43.323Z,1521067603.323 [BPC1] Loaded 2018-03-14T22:46:43.323Z,1521067603.323 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-03-14T22:46:43.323Z,1521067603.323 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-03-14T22:46:43.324Z,1521067603.324 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-03-14T22:46:43.459Z,1521067603.459 [SBIT](DEBUG): Construct Startup Built In Test. 2018-03-14T22:46:43.484Z,1521067603.484 [SBIT] Loaded 2018-03-14T22:46:43.485Z,1521067603.485 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-03-14T22:46:43.485Z,1521067603.485 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-03-14T22:46:43.496Z,1521067603.496 [IBIT] Loaded 2018-03-14T22:46:43.496Z,1521067603.496 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-03-14T22:46:43.499Z,1521067603.499 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-03-14T22:46:43.650Z,1521067603.650 [CBIT] Loaded 2018-03-14T22:46:43.650Z,1521067603.650 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-03-14T22:46:43.651Z,1521067603.651 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-03-14T22:46:43.651Z,1521067603.651 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-03-14T22:46:43.802Z,1521067603.802 [Aanderaa_O2] Loaded 2018-03-14T22:46:43.803Z,1521067603.803 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-03-14T22:46:43.814Z,1521067603.814 [CANONSampler] Loaded 2018-03-14T22:46:43.815Z,1521067603.815 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2018-03-14T22:46:43.828Z,1521067603.828 [PAR_Licor] Loaded 2018-03-14T22:46:43.829Z,1521067603.829 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-03-14T22:46:43.877Z,1521067603.877 [WetLabsBB2FL] Loaded 2018-03-14T22:46:43.878Z,1521067603.878 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-03-14T22:46:43.879Z,1521067603.879 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409314E0 2018-03-14T22:46:43.879Z,1521067603.879 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1005 2018-03-14T22:46:43.880Z,1521067603.880 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-03-14T22:46:43.880Z,1521067603.880 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-03-14T22:46:43.952Z,1521067603.952 [VerticalControl](DEBUG): Construct VerticalControl. 2018-03-14T22:46:44.037Z,1521067604.037 [VerticalControl] Loaded 2018-03-14T22:46:44.038Z,1521067604.038 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-03-14T22:46:44.038Z,1521067604.038 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-03-14T22:46:44.094Z,1521067604.094 [HorizontalControl] Loaded 2018-03-14T22:46:44.094Z,1521067604.094 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-03-14T22:46:44.095Z,1521067604.095 [SpeedControl](DEBUG): Construct SpeedControl. 2018-03-14T22:46:44.097Z,1521067604.097 [SpeedControl] Loaded 2018-03-14T22:46:44.097Z,1521067604.097 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-03-14T22:46:44.097Z,1521067604.097 [LoopControl](DEBUG): Construct LoopControl. 2018-03-14T22:46:44.098Z,1521067604.098 [LoopControl] Loaded 2018-03-14T22:46:44.098Z,1521067604.098 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-03-14T22:46:44.099Z,1521067604.099 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-03-14T22:46:44.099Z,1521067604.099 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-03-14T22:46:44.124Z,1521067604.124 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-03-14T22:46:44.124Z,1521067604.124 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-03-14T22:46:44.216Z,1521067604.216 [DeadReckonUsingSpeedCalculator] Loaded 2018-03-14T22:46:44.216Z,1521067604.216 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-03-14T22:46:44.270Z,1521067604.270 [DeadReckonWithRespectToSeafloor] Loaded 2018-03-14T22:46:44.270Z,1521067604.270 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2018-03-14T22:46:44.287Z,1521067604.287 [NavChart] Loaded 2018-03-14T22:46:44.287Z,1521067604.287 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-03-14T22:46:44.291Z,1521067604.291 [UniversalFixResidualReporter] Loaded 2018-03-14T22:46:44.291Z,1521067604.291 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-03-14T22:46:44.292Z,1521067604.292 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-03-14T22:46:44.295Z,1521067604.295 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-03-14T22:46:44.296Z,1521067604.296 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-03-14T22:46:44.302Z,1521067604.302 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-03-14T22:46:44.303Z,1521067604.303 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409BD4E0 2018-03-14T22:46:44.304Z,1521067604.304 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1006 2018-03-14T22:46:44.308Z,1521067604.308 [Supervisor](INFO): Main Thread ID is 9710 2018-03-14T22:46:44.308Z,1521067604.308 [Supervisor](DEBUG): Running supervisor. 2018-03-14T22:46:44.309Z,1521067604.309 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1007 2018-03-14T22:46:44.312Z,1521067604.312 [controlThread ThreadHandler](INFO): Handler Thread ID is 1008 2018-03-14T22:46:44.312Z,1521067604.312 [controlThread](DEBUG): Initializing ControlThread 2018-03-14T22:46:44.313Z,1521067604.313 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-03-14T22:46:44.313Z,1521067604.313 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-03-14T22:46:44.314Z,1521067604.314 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-03-14T22:46:44.314Z,1521067604.314 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-03-14T22:46:44.315Z,1521067604.315 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-03-14T22:46:44.315Z,1521067604.315 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-03-14T22:46:44.321Z,1521067604.321 [SBIT](INFO): Initialize SBIT Component. 2018-03-14T22:46:44.321Z,1521067604.321 [SBIT](IMPORTANT): git: 2018-03-10-3-g1088291 2018-03-14T22:46:44.321Z,1521067604.321 [SBIT](INFO): git hash: 10882917f95cfba58cc6008bb2c6cc253db9d857 2018-03-14T22:46:44.322Z,1521067604.322 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-03-14T22:46:44.323Z,1521067604.323 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-03-14T22:46:44.324Z,1521067604.324 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2018-03-14T22:46:44.324Z,1521067604.324 [IBIT](INFO): Initialize IBIT Component. 2018-03-14T22:46:44.325Z,1521067604.325 [CBIT](DEBUG): Initialize CBIT Component. 2018-03-14T22:46:44.325Z,1521067604.325 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-03-14T22:46:44.326Z,1521067604.326 [logger ThreadHandler](INFO): Handler Thread ID is 1009 2018-03-14T22:46:44.343Z,1521067604.343 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1010 2018-03-14T22:46:44.349Z,1521067604.349 [Radio_Surface](INFO): Powering up 2018-03-14T22:46:44.355Z,1521067604.355 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1011 2018-03-14T22:46:44.356Z,1521067604.356 [WetLabsBB2FL](INFO): Powering down 2018-03-14T22:46:44.379Z,1521067604.379 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1012 2018-03-14T22:46:44.382Z,1521067604.382 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-03-14T22:46:44.382Z,1521067604.382 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-03-14T22:46:44.383Z,1521067604.383 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-03-14T22:46:44.383Z,1521067604.383 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-03-14T22:46:44.383Z,1521067604.383 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-03-14T22:46:44.383Z,1521067604.383 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-03-14T22:46:44.383Z,1521067604.383 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-03-14T22:46:44.384Z,1521067604.384 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-03-14T22:46:44.384Z,1521067604.384 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-03-14T22:46:44.384Z,1521067604.384 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-03-14T22:46:44.384Z,1521067604.384 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-03-14T22:46:44.384Z,1521067604.384 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-03-14T22:46:44.384Z,1521067604.384 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-03-14T22:46:44.385Z,1521067604.385 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-03-14T22:46:44.385Z,1521067604.385 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-03-14T22:46:44.385Z,1521067604.385 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-03-14T22:46:44.435Z,1521067604.435 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-03-14T22:46:44.436Z,1521067604.436 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-03-14T22:46:44.437Z,1521067604.437 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-03-14T22:46:44.438Z,1521067604.438 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-03-14T22:46:44.438Z,1521067604.438 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-14T22:46:44.439Z,1521067604.439 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T22:46:44.439Z,1521067604.439 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T22:46:44.440Z,1521067604.440 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-14T22:46:44.440Z,1521067604.440 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T22:46:44.440Z,1521067604.440 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T22:46:44.441Z,1521067604.441 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-03-14T22:46:44.441Z,1521067604.441 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-03-14T22:46:44.442Z,1521067604.442 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-03-14T22:46:44.469Z,1521067604.469 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-14T22:46:44.496Z,1521067604.496 [MissionManager](DEBUG): 2018-03-14T22:46:44.497Z,1521067604.497 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-03-14T22:46:44.582Z,1521067604.582 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-03-14T22:46:44.599Z,1521067604.599 [Default:A.Wait](DEBUG): Construct Wait. 2018-03-14T22:46:44.601Z,1521067604.601 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-14T22:46:44.616Z,1521067604.616 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-03-14T22:46:44.618Z,1521067604.618 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-03-14T22:46:44.652Z,1521067604.652 [Default:E.Execute](DEBUG): Construct Execute. 2018-03-14T22:46:44.671Z,1521067604.671 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-03-14T22:46:44.676Z,1521067604.676 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-03-14T22:46:44.700Z,1521067604.700 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-03-14T22:46:44.732Z,1521067604.732 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2018-03-14T22:46:44.848Z,1521067604.848 [BPC1](INFO): Start 2018-03-14T22:46:44.968Z,1521067604.968 [CANONSampler](INFO): Powering down 2018-03-14T22:46:45.072Z,1521067605.072 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-03-14T22:46:45.166Z,1521067605.166 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:45.189Z,1521067605.189 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:45.303Z,1521067605.303 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-03-14T22:46:45.311Z,1521067605.311 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-03-14T22:46:45.325Z,1521067605.325 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-03-14T22:46:45.327Z,1521067605.327 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-03-14T22:46:45.348Z,1521067605.348 [MassServo](DEBUG): Initializing EZServoServo. 2018-03-14T22:46:45.359Z,1521067605.359 [MassServo](DEBUG): Initializing MassServo. 2018-03-14T22:46:45.380Z,1521067605.380 [RudderServo](DEBUG): Initializing EZServoServo. 2018-03-14T22:46:45.388Z,1521067605.388 [RudderServo](DEBUG): Initializing RudderServo. 2018-03-14T22:46:45.393Z,1521067605.393 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-03-14T22:46:45.399Z,1521067605.399 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-03-14T22:46:45.635Z,1521067605.635 [BPC1](INFO): Starting 2018-03-14T22:46:45.659Z,1521067605.659 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:45.660Z,1521067605.660 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:45.897Z,1521067605.897 [BPC1](INFO): Starting 2018-03-14T22:46:45.924Z,1521067605.924 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:45.924Z,1521067605.924 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:46.394Z,1521067606.394 [BPC1](INFO): Starting 2018-03-14T22:46:46.431Z,1521067606.431 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:46.432Z,1521067606.432 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:46.803Z,1521067606.803 [BPC1](INFO): Starting 2018-03-14T22:46:46.822Z,1521067606.822 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:46.831Z,1521067606.831 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:47.197Z,1521067607.197 [BPC1](INFO): Starting 2018-03-14T22:46:47.222Z,1521067607.222 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:47.231Z,1521067607.231 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:47.597Z,1521067607.597 [BPC1](INFO): Starting 2018-03-14T22:46:47.642Z,1521067607.642 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:47.642Z,1521067607.642 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:48.011Z,1521067608.011 [BPC1](INFO): Starting 2018-03-14T22:46:48.131Z,1521067608.131 [Aanderaa_O2](INFO): Powering down 2018-03-14T22:46:48.189Z,1521067608.189 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:48.189Z,1521067608.189 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-14T22:46:48.414Z,1521067608.414 [BPC1](INFO): Starting 2018-03-14T22:46:48.805Z,1521067608.805 [BPC1](INFO): Starting 2018-03-14T22:46:49.289Z,1521067609.289 [BPC1](INFO): Starting 2018-03-14T22:46:49.659Z,1521067609.659 [BPC1](INFO): Starting 2018-03-14T22:46:50.049Z,1521067610.049 [BPC1](INFO): Starting 2018-03-14T22:46:50.475Z,1521067610.475 [BPC1](INFO): Starting 2018-03-14T22:46:50.855Z,1521067610.855 [BPC1](INFO): Starting 2018-03-14T22:46:51.248Z,1521067611.248 [BPC1](INFO): Starting 2018-03-14T22:46:51.648Z,1521067611.648 [BPC1](INFO): Starting 2018-03-14T22:46:52.052Z,1521067612.052 [BPC1](INFO): Starting 2018-03-14T22:46:52.459Z,1521067612.459 [BPC1](INFO): Starting 2018-03-14T22:46:52.848Z,1521067612.848 [BPC1](INFO): Starting 2018-03-14T22:46:53.249Z,1521067613.249 [BPC1](INFO): Starting 2018-03-14T22:46:53.653Z,1521067613.653 [BPC1](INFO): Starting 2018-03-14T22:46:54.055Z,1521067614.055 [BPC1](INFO): Starting 2018-03-14T22:46:54.452Z,1521067614.452 [BPC1](INFO): Starting 2018-03-14T22:46:54.848Z,1521067614.848 [BPC1](INFO): Starting 2018-03-14T22:46:55.256Z,1521067615.256 [BPC1](INFO): Starting 2018-03-14T22:46:55.659Z,1521067615.659 [BPC1](INFO): Starting 2018-03-14T22:46:56.048Z,1521067616.048 [BPC1](INFO): Starting 2018-03-14T22:46:56.449Z,1521067616.449 [BPC1](INFO): Starting 2018-03-14T22:46:56.860Z,1521067616.860 [BPC1](INFO): Starting 2018-03-14T22:46:57.259Z,1521067617.259 [BPC1](INFO): Starting 2018-03-14T22:46:57.648Z,1521067617.648 [BPC1](INFO): Starting 2018-03-14T22:46:58.046Z,1521067618.046 [BPC1](INFO): Starting 2018-03-14T22:46:58.452Z,1521067618.452 [BPC1](INFO): Starting 2018-03-14T22:46:58.859Z,1521067618.859 [BPC1](INFO): Starting 2018-03-14T22:46:59.245Z,1521067619.245 [BPC1](INFO): Starting 2018-03-14T22:46:59.668Z,1521067619.668 [BPC1](INFO): Starting 2018-03-14T22:47:00.052Z,1521067620.052 [BPC1](INFO): Starting 2018-03-14T22:47:00.459Z,1521067620.459 [BPC1](INFO): Starting 2018-03-14T22:47:00.848Z,1521067620.848 [BPC1](INFO): Starting 2018-03-14T22:47:01.248Z,1521067621.248 [BPC1](INFO): Starting 2018-03-14T22:47:01.651Z,1521067621.651 [BPC1](INFO): Starting 2018-03-14T22:47:02.049Z,1521067622.049 [BPC1](INFO): Starting 2018-03-14T22:47:02.449Z,1521067622.449 [BPC1](INFO): Starting 2018-03-14T22:47:02.848Z,1521067622.848 [BPC1](INFO): Starting 2018-03-14T22:47:03.252Z,1521067623.252 [BPC1](INFO): Starting 2018-03-14T22:47:03.659Z,1521067623.659 [BPC1](INFO): Starting 2018-03-14T22:47:04.048Z,1521067624.048 [BPC1](INFO): Starting 2018-03-14T22:47:04.446Z,1521067624.446 [BPC1](INFO): Starting 2018-03-14T22:47:04.860Z,1521067624.860 [BPC1](INFO): Starting 2018-03-14T22:47:05.259Z,1521067625.259 [BPC1](INFO): Starting 2018-03-14T22:47:05.648Z,1521067625.648 [BPC1](INFO): Starting 2018-03-14T22:47:06.050Z,1521067626.050 [BPC1](INFO): Starting 2018-03-14T22:47:06.463Z,1521067626.463 [BPC1](INFO): Starting 2018-03-14T22:47:06.863Z,1521067626.863 [BPC1](INFO): Starting 2018-03-14T22:47:07.266Z,1521067627.266 [BPC1](INFO): Starting 2018-03-14T22:47:07.645Z,1521067627.645 [BPC1](INFO): Starting 2018-03-14T22:47:08.048Z,1521067628.048 [BPC1](INFO): Starting 2018-03-14T22:47:08.086Z,1521067628.086 [SBIT](IMPORTANT): Beginning Startup BIT 2018-03-14T22:47:08.106Z,1521067628.106 [CBIT](IMPORTANT): Beginning ground fault scan 2018-03-14T22:47:08.449Z,1521067628.449 [BPC1](INFO): Starting 2018-03-14T22:47:08.856Z,1521067628.856 [BPC1](INFO): Starting 2018-03-14T22:47:09.248Z,1521067629.248 [BPC1](INFO): Starting 2018-03-14T22:47:09.663Z,1521067629.663 [BPC1](INFO): Starting 2018-03-14T22:47:10.059Z,1521067630.059 [BPC1](INFO): Starting 2018-03-14T22:47:10.627Z,1521067630.627 [BPC1](INFO): Starting 2018-03-14T22:47:11.001Z,1521067631.001 [BPC1](INFO): Starting 2018-03-14T22:47:11.372Z,1521067631.372 [NAL9602](INFO): Powering up NAL9602 2018-03-14T22:47:11.398Z,1521067631.398 [BPC1](INFO): Starting 2018-03-14T22:47:11.800Z,1521067631.800 [BPC1](INFO): Starting 2018-03-14T22:47:12.201Z,1521067632.201 [BPC1](INFO): Starting 2018-03-14T22:47:12.605Z,1521067632.605 [BPC1](INFO): Starting 2018-03-14T22:47:12.997Z,1521067632.997 [BPC1](INFO): Starting 2018-03-14T22:47:13.405Z,1521067633.405 [BPC1](INFO): Starting 2018-03-14T22:47:13.801Z,1521067633.801 [BPC1](INFO): Starting 2018-03-14T22:47:14.227Z,1521067634.227 [BPC1](INFO): Starting 2018-03-14T22:47:14.602Z,1521067634.602 [BPC1](INFO): Starting 2018-03-14T22:47:15.002Z,1521067635.002 [BPC1](INFO): Starting 2018-03-14T22:47:15.398Z,1521067635.398 [BPC1](INFO): Starting 2018-03-14T22:47:15.797Z,1521067635.797 [BPC1](INFO): Starting 2018-03-14T22:47:16.196Z,1521067636.196 [BPC1](INFO): Starting 2018-03-14T22:47:16.597Z,1521067636.597 [BPC1](INFO): Starting 2018-03-14T22:47:17.001Z,1521067637.001 [BPC1](INFO): Starting 2018-03-14T22:47:17.397Z,1521067637.397 [BPC1](INFO): Starting 2018-03-14T22:47:17.807Z,1521067637.807 [BPC1](INFO): Starting 2018-03-14T22:47:18.197Z,1521067638.197 [BPC1](INFO): Starting 2018-03-14T22:47:18.600Z,1521067638.600 [BPC1](INFO): Starting 2018-03-14T22:47:18.999Z,1521067638.999 [BPC1](INFO): Starting 2018-03-14T22:47:19.090Z,1521067639.090 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.001493 CHAN A1 (24V): 4.657929 CHAN A2 (12V): -0.002594 CHAN A3 (5V): -0.001750 CHAN B0 (3.3V): 0.000160 CHAN B1 (3.15aV): -0.000361 CHAN B2 (3.15bV): -0.000340 CHAN B3 (GND): -0.000098 OPEN: -0.000677 Full Scale Calc: 4.765 mA, -1.589 mA 2018-03-14T22:47:19.399Z,1521067639.399 [BPC1](INFO): Starting 2018-03-14T22:47:19.804Z,1521067639.804 [BPC1](INFO): Starting 2018-03-14T22:47:20.197Z,1521067640.197 [BPC1](INFO): Starting 2018-03-14T22:47:20.600Z,1521067640.600 [BPC1](INFO): Starting 2018-03-14T22:47:20.000Z,1521067641.000 [BPC1](INFO): Starting 2018-03-14T22:47:21.400Z,1521067641.400 [BPC1](INFO): Starting 2018-03-14T22:47:21.817Z,1521067641.817 [BPC1](INFO): Starting 2018-03-14T22:47:22.176Z,1521067642.176 [NAL9602](INFO): NAL9602 initialized 2018-03-14T22:47:22.203Z,1521067642.203 [BPC1](INFO): Starting 2018-03-14T22:47:22.610Z,1521067642.610 [BPC1](INFO): Starting 2018-03-14T22:47:23.015Z,1521067643.015 [BPC1](INFO): Starting 2018-03-14T22:47:23.427Z,1521067643.427 [BPC1](INFO): Starting 2018-03-14T22:47:24.071Z,1521067644.071 [BPC1](INFO): Starting 2018-03-14T22:47:24.441Z,1521067644.441 [BPC1](INFO): Starting 2018-03-14T22:47:24.826Z,1521067644.826 [BPC1](INFO): Starting 2018-03-14T22:47:25.227Z,1521067645.227 [BPC1](INFO): Starting 2018-03-14T22:47:25.656Z,1521067645.656 [BPC1](INFO): Starting 2018-03-14T22:47:26.059Z,1521067646.059 [BPC1](INFO): Starting 2018-03-14T22:47:26.440Z,1521067646.440 [BPC1](INFO): Starting 2018-03-14T22:47:26.833Z,1521067646.833 [BPC1](INFO): Starting 2018-03-14T22:47:27.233Z,1521067647.233 [BPC1](INFO): Starting 2018-03-14T22:47:27.620Z,1521067647.620 [BPC1](INFO): Starting 2018-03-14T22:47:28.034Z,1521067648.034 [BPC1](INFO): Starting 2018-03-14T22:47:28.425Z,1521067648.425 [BPC1](INFO): Starting 2018-03-14T22:47:28.825Z,1521067648.825 [BPC1](INFO): Starting 2018-03-14T22:47:29.224Z,1521067649.224 [BPC1](INFO): Starting 2018-03-14T22:47:29.632Z,1521067649.632 [BPC1](INFO): Starting 2018-03-14T22:47:30.034Z,1521067650.034 [BPC1](INFO): Starting 2018-03-14T22:47:30.443Z,1521067650.443 [BPC1](INFO): Starting 2018-03-14T22:47:30.843Z,1521067650.843 [BPC1](INFO): Starting 2018-03-14T22:47:31.244Z,1521067651.244 [BPC1](INFO): Starting 2018-03-14T22:47:31.631Z,1521067651.631 [BPC1](INFO): Starting 2018-03-14T22:47:32.037Z,1521067652.037 [BPC1](INFO): Starting 2018-03-14T22:47:32.428Z,1521067652.428 [BPC1](INFO): Starting 2018-03-14T22:47:32.830Z,1521067652.830 [BPC1](INFO): Starting 2018-03-14T22:47:33.232Z,1521067653.232 [BPC1](INFO): Starting 2018-03-14T22:47:33.620Z,1521067653.620 [BPC1](INFO): Starting 2018-03-14T22:47:34.021Z,1521067654.021 [BPC1](INFO): Starting 2018-03-14T22:47:34.425Z,1521067654.425 [BPC1](INFO): Starting 2018-03-14T22:47:34.830Z,1521067654.830 [BPC1](INFO): Starting 2018-03-14T22:47:35.232Z,1521067655.232 [BPC1](INFO): Starting 2018-03-14T22:47:35.647Z,1521067655.647 [BPC1](INFO): Starting 2018-03-14T22:47:36.044Z,1521067656.044 [BPC1](INFO): Starting 2018-03-14T22:47:36.444Z,1521067656.444 [BPC1](INFO): Starting 2018-03-14T22:47:36.862Z,1521067656.862 [BPC1](INFO): Starting 2018-03-14T22:47:37.235Z,1521067657.235 [BPC1](INFO): Starting 2018-03-14T22:47:37.625Z,1521067657.625 [BPC1](INFO): Starting 2018-03-14T22:47:38.030Z,1521067658.030 [BPC1](INFO): Starting 2018-03-14T22:47:38.431Z,1521067658.431 [BPC1](INFO): Starting 2018-03-14T22:47:38.829Z,1521067658.829 [BPC1](INFO): Starting 2018-03-14T22:47:39.233Z,1521067659.233 [BPC1](INFO): Starting 2018-03-14T22:47:39.624Z,1521067659.624 [BPC1](INFO): Starting 2018-03-14T22:47:40.024Z,1521067660.024 [BPC1](INFO): Starting 2018-03-14T22:47:40.424Z,1521067660.424 [BPC1](INFO): Starting 2018-03-14T22:47:40.833Z,1521067660.833 [BPC1](INFO): Starting 2018-03-14T22:47:41.233Z,1521067661.233 [BPC1](INFO): Starting 2018-03-14T22:47:41.643Z,1521067661.643 [BPC1](INFO): Starting 2018-03-14T22:47:42.041Z,1521067662.041 [BPC1](INFO): Starting 2018-03-14T22:47:42.425Z,1521067662.425 [BPC1](INFO): Starting 2018-03-14T22:47:42.824Z,1521067662.824 [BPC1](INFO): Starting 2018-03-14T22:47:43.220Z,1521067663.220 [BPC1](INFO): Starting 2018-03-14T22:47:43.625Z,1521067663.625 [BPC1](INFO): Starting 2018-03-14T22:47:44.026Z,1521067664.026 [BPC1](INFO): Starting 2018-03-14T22:47:44.429Z,1521067664.429 [BPC1](INFO): Starting 2018-03-14T22:47:44.832Z,1521067664.832 [BPC1](INFO): Starting 2018-03-14T22:47:45.248Z,1521067665.248 [BPC1](INFO): Starting 2018-03-14T22:47:45.248Z,1521067665.248 [BPC1](INFO): Initialized. 2018-03-14T22:47:45.642Z,1521067665.642 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:47:52.937Z,1521067672.937 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:47:56.954Z,1521067676.954 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:47:56.961Z,1521067676.961 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:47:57.358Z,1521067677.358 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:47:58.925Z,1521067678.925 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:48:02.181Z,1521067682.181 [SBIT](IMPORTANT): SBIT PASSED 2018-03-14T22:48:02.538Z,1521067682.538 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:48:02.544Z,1521067682.544 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:48:02.590Z,1521067682.590 [MissionManager](IMPORTANT): Started mission Startup 2018-03-14T22:48:02.591Z,1521067682.591 [Startup] Running Loop=1 2018-03-14T22:48:02.591Z,1521067682.591 [Startup](DEBUG): Aggregate::initialize Startup 2018-03-14T22:48:02.591Z,1521067682.591 [Startup:A.GoToSurface] Running Loop=1 2018-03-14T22:48:02.591Z,1521067682.591 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-03-14T22:48:02.592Z,1521067682.592 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-03-14T22:48:02.592Z,1521067682.592 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-03-14T22:48:02.592Z,1521067682.592 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-03-14T22:48:02.593Z,1521067682.593 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 30.000000 seconds. 2018-03-14T22:48:02.593Z,1521067682.593 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-03-14T22:48:02.599Z,1521067682.599 [Startup:StartupSatComms] Running Loop=1 2018-03-14T22:48:02.599Z,1521067682.599 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-03-14T22:48:02.599Z,1521067682.599 [Startup:StartupSatComms:A] Running Loop=1 2018-03-14T22:48:02.954Z,1521067682.954 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:48:02.965Z,1521067682.965 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-03-14T22:48:10.133Z,1521067690.133 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:48:14.124Z,1521067694.124 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:48:14.131Z,1521067694.131 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:48:14.551Z,1521067694.551 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:48:16.128Z,1521067696.128 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:48:20.132Z,1521067700.132 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:48:20.148Z,1521067700.148 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:48:20.542Z,1521067700.542 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:48:28.143Z,1521067708.143 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:48:32.944Z,1521067712.944 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:48:32.967Z,1521067712.967 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:48:33.358Z,1521067713.358 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:48:41.335Z,1521067721.335 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:48:45.735Z,1521067725.735 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:48:45.741Z,1521067725.741 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:48:46.139Z,1521067726.139 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:48:53.740Z,1521067733.740 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:48:57.739Z,1521067737.739 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:48:57.745Z,1521067737.745 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:48:58.143Z,1521067738.143 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:49:02.944Z,1521067742.944 [Startup:StartupSatComms:A](INFO): Timed out from 2018-03-14T22:48:02.6Z 2018-03-14T22:49:02.944Z,1521067742.944 [Startup:StartupSatComms:A] Stopped 2018-03-14T22:49:02.944Z,1521067742.944 [Startup:StartupSatComms:B] Running Loop=1 2018-03-14T22:49:03.347Z,1521067743.347 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-03-14T22:49:04.947Z,1521067744.947 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:49:08.532Z,1521067748.532 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:49:08.537Z,1521067748.537 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:49:08.955Z,1521067748.955 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:49:10.532Z,1521067750.532 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:49:14.547Z,1521067754.547 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:49:14.554Z,1521067754.554 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:49:14.958Z,1521067754.958 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:49:22.524Z,1521067762.524 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:49:26.940Z,1521067766.940 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:49:26.947Z,1521067766.947 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:49:27.338Z,1521067767.338 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:49:30.228Z,1521067770.228 [CommandLine](IMPORTANT): got command report mod platform_battery_charge 2018-03-14T22:49:30.567Z,1521067770.567 [Reporter](INFO): platform_battery_charge no_value 2018-03-14T22:49:35.337Z,1521067775.337 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:49:37.108Z,1521067777.108 [CommandLine](IMPORTANT): got command report mod platform_battery_voltage 2018-03-14T22:49:37.356Z,1521067777.356 [Reporter](INFO): platform_battery_voltage no_value 2018-03-14T22:49:39.735Z,1521067779.735 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:49:39.741Z,1521067779.741 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:49:40.158Z,1521067780.158 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:49:44.549Z,1521067784.549 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-14T22:49:44.549Z,1521067784.549 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-03-14T22:49:44.549Z,1521067784.549 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-03-14T22:49:44.551Z,1521067784.551 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-14T22:49:44.551Z,1521067784.551 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-03-14T22:49:44.551Z,1521067784.551 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-03-14T22:49:44.593Z,1521067784.593 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-03-14T22:49:44.593Z,1521067784.593 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-03-14T22:49:44.966Z,1521067784.966 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-03-14T22:49:44.966Z,1521067784.966 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-03-14T22:49:44.966Z,1521067784.966 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-03-14T22:49:44.966Z,1521067784.966 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-03-14T22:49:45.360Z,1521067785.360 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-14T22:49:45.360Z,1521067785.360 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T22:49:45.361Z,1521067785.361 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T22:49:45.361Z,1521067785.361 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-14T22:49:45.361Z,1521067785.361 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T22:49:45.362Z,1521067785.362 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T22:49:48.860Z,1521067788.860 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:49:49.727Z,1521067789.727 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003743 2018-03-14T22:49:53.667Z,1521067793.667 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:49:53.673Z,1521067793.673 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:49:53.747Z,1521067793.747 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180314T223457/Courier0004.lzma 2018-03-14T22:49:53.810Z,1521067793.810 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:49:54.543Z,1521067794.543 [DataOverHttps](INFO): Moved sent file to Logs/20180314T223457/Courier0004.lzma.bak 2018-03-14T22:49:54.544Z,1521067794.544 [DataOverHttps](INFO): SBD MOMSN=8061949 2018-03-14T22:49:55.395Z,1521067795.395 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:49:58.972Z,1521067798.972 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:49:58.978Z,1521067798.978 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:49:59.382Z,1521067799.382 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:50:03.290Z,1521067803.290 [Startup:StartupSatComms:B](INFO): Timed out from 2018-03-14T22:49:02.9Z 2018-03-14T22:50:03.290Z,1521067803.290 [Startup:StartupSatComms:B] Stopped 2018-03-14T22:50:03.290Z,1521067803.290 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-03-14T22:50:03.290Z,1521067803.290 [Startup:StartupSatComms] Stopped 2018-03-14T22:50:03.290Z,1521067803.290 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-03-14T22:50:03.291Z,1521067803.291 [Startup](INFO): Completed Startup 2018-03-14T22:50:03.291Z,1521067803.291 [MissionManager](INFO): Startup is completed. 2018-03-14T22:50:03.292Z,1521067803.292 [MissionManager](INFO): Uninitializing Mission Startup 2018-03-14T22:50:03.292Z,1521067803.292 [Startup] Stopped 2018-03-14T22:50:03.292Z,1521067803.292 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-03-14T22:50:03.292Z,1521067803.292 [Startup:A.GoToSurface] Stopped 2018-03-14T22:50:03.292Z,1521067803.292 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-03-14T22:50:03.351Z,1521067803.351 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180314T224311/Courier0004.lzma 2018-03-14T22:50:03.457Z,1521067803.457 [MissionManager](IMPORTANT): Started mission Default 2018-03-14T22:50:03.458Z,1521067803.458 [Default] Running Loop=1 2018-03-14T22:50:03.458Z,1521067803.458 [Default](DEBUG): Aggregate::initialize Default 2018-03-14T22:50:03.458Z,1521067803.458 [Default:B.GoToSurface] Running Loop=1 2018-03-14T22:50:03.458Z,1521067803.458 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-03-14T22:50:03.462Z,1521067803.462 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-03-14T22:50:03.463Z,1521067803.463 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-03-14T22:50:03.463Z,1521067803.463 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-03-14T22:50:03.463Z,1521067803.463 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 30.000000 seconds. 2018-03-14T22:50:03.464Z,1521067803.464 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-03-14T22:50:03.464Z,1521067803.464 [Default:A.Wait] Running Loop=1 2018-03-14T22:50:03.464Z,1521067803.464 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-03-14T22:50:04.139Z,1521067804.139 [DataOverHttps](INFO): Moved sent file to Logs/20180314T224311/Courier0004.lzma.bak 2018-03-14T22:50:04.139Z,1521067804.139 [DataOverHttps](INFO): SBD MOMSN=8061952 2018-03-14T22:50:08.393Z,1521067808.393 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:50:10.459Z,1521067810.459 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:50:10.465Z,1521067810.465 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:50:10.859Z,1521067810.859 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:50:16.489Z,1521067816.489 [Default:A.Wait](INFO): Done Waiting. 2018-03-14T22:50:16.489Z,1521067816.489 [Default:A.Wait] Stopped 2018-03-14T22:50:16.489Z,1521067816.489 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-03-14T22:50:16.883Z,1521067816.883 [Default:CheckIn] Running Loop=1 2018-03-14T22:50:16.883Z,1521067816.883 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-03-14T22:50:16.883Z,1521067816.883 [Default:CheckIn:Read_GPS] Running Loop=1 2018-03-14T22:50:17.275Z,1521067817.275 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-03-14T22:50:18.468Z,1521067818.468 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:50:22.855Z,1521067822.855 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:50:22.871Z,1521067822.871 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:50:23.274Z,1521067823.274 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:50:30.860Z,1521067830.860 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:50:35.271Z,1521067835.271 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:50:35.278Z,1521067835.278 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:50:35.670Z,1521067835.670 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:50:42.845Z,1521067842.845 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:52:25.972Z,1521067945.972 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-03-14T22:52:45.601Z,1521067965.601 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-14T22:52:45.601Z,1521067965.601 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-03-14T22:52:45.601Z,1521067965.601 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-03-14T22:52:45.603Z,1521067965.603 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-14T22:52:45.603Z,1521067965.603 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-03-14T22:52:45.603Z,1521067965.603 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-03-14T22:52:45.654Z,1521067965.654 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-03-14T22:52:45.655Z,1521067965.655 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-03-14T22:52:46.031Z,1521067966.031 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-03-14T22:52:46.031Z,1521067966.031 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-03-14T22:52:46.032Z,1521067966.032 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-03-14T22:52:46.032Z,1521067966.032 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-03-14T22:52:46.413Z,1521067966.413 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-14T22:52:46.414Z,1521067966.414 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T22:52:46.414Z,1521067966.414 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T22:52:46.415Z,1521067966.415 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-14T22:52:46.415Z,1521067966.415 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T22:52:46.415Z,1521067966.415 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T22:55:17.251Z,1521068117.251 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-03-14T22:50:16.9Z 2018-03-14T22:55:17.251Z,1521068117.251 [Default:CheckIn:Read_GPS] Stopped 2018-03-14T22:55:17.252Z,1521068117.252 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-03-14T22:55:17.653Z,1521068117.653 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-03-14T22:55:24.372Z,1521068124.372 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180314T224635/Courier0004.lzma 2018-03-14T22:55:25.171Z,1521068125.171 [DataOverHttps](INFO): Moved sent file to Logs/20180314T224635/Courier0004.lzma.bak 2018-03-14T22:55:25.171Z,1521068125.171 [DataOverHttps](INFO): SBD MOMSN=8061959 2018-03-14T22:55:33.896Z,1521068133.896 [DataOverHttps](INFO): Sending 693 bytes from file Logs/20180314T223457/Express0001.lzma 2018-03-14T22:55:34.695Z,1521068134.695 [DataOverHttps](INFO): Moved sent file to Logs/20180314T223457/Express0001.lzma.bak 2018-03-14T22:55:34.695Z,1521068134.695 [DataOverHttps](INFO): SBD MOMSN=8061962 2018-03-14T22:55:35.423Z,1521068135.423 [BPC1](ERROR): Failed to receive data from both battery packs 2018-03-14T22:55:35.423Z,1521068135.423 [BPC1] Communications Fault, FailCount= 1 2018-03-14T22:55:35.423Z,1521068135.423 [BPC1](ERROR): Communications Fault 2018-03-14T22:55:35.449Z,1521068135.449 [CBIT](ERROR): Communications Fault in component: BPC1 2018-03-14T22:55:35.560Z,1521068135.560 [BPC1](INFO): Stop 2018-03-14T22:55:35.560Z,1521068135.560 [BPC1](INFO): uninitialize 2018-03-14T22:55:38.616Z,1521068138.616 [BPC1](INFO): Stopping 2018-03-14T22:55:38.735Z,1521068138.735 [BPC1](INFO): Stopped 2018-03-14T22:55:38.735Z,1521068138.735 [BPC1](INFO): Data requested. Hibernation time: 431531.591111 hours. 2018-03-14T22:55:38.756Z,1521068138.756 [CBIT](INFO): Clearing failed state for component BPC1 2018-03-14T22:55:38.757Z,1521068138.757 [BPC1] No Fault, FailCount= 1 2018-03-14T22:55:39.119Z,1521068139.119 [BPC1](INFO): Start 2018-03-14T22:55:39.522Z,1521068139.522 [BPC1](INFO): Starting 2018-03-14T22:55:39.926Z,1521068139.926 [BPC1](INFO): Starting 2018-03-14T22:55:40.321Z,1521068140.321 [BPC1](INFO): Starting 2018-03-14T22:55:43.399Z,1521068143.399 [BPC1](INFO): Starting 2018-03-14T22:55:43.479Z,1521068143.479 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20180314T223457/Express0005.lzma 2018-03-14T22:55:43.539Z,1521068143.539 [BPC1](INFO): Starting 2018-03-14T22:55:43.899Z,1521068143.899 [BPC1](INFO): Starting 2018-03-14T22:55:44.255Z,1521068144.255 [DataOverHttps](INFO): Moved sent file to Logs/20180314T223457/Express0005.lzma.bak 2018-03-14T22:55:44.255Z,1521068144.255 [DataOverHttps](INFO): SBD MOMSN=8061979 2018-03-14T22:55:44.320Z,1521068144.320 [BPC1](INFO): Starting 2018-03-14T22:55:44.981Z,1521068144.981 [BPC1](INFO): Starting 2018-03-14T22:55:45.095Z,1521068145.095 [BPC1](INFO): Starting 2018-03-14T22:55:48.530Z,1521068148.530 [BPC1](INFO): Starting 2018-03-14T22:55:48.538Z,1521068148.538 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-14T22:55:48.538Z,1521068148.538 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-03-14T22:55:48.539Z,1521068148.539 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-03-14T22:55:48.540Z,1521068148.540 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-14T22:55:48.540Z,1521068148.540 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2018-03-14T22:55:48.540Z,1521068148.540 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-03-14T22:55:48.552Z,1521068148.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-03-14T22:55:48.552Z,1521068148.552 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-03-14T22:55:48.667Z,1521068148.667 [BPC1](INFO): Starting 2018-03-14T22:55:48.708Z,1521068148.708 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-03-14T22:55:48.709Z,1521068148.709 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-03-14T22:55:48.709Z,1521068148.709 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-03-14T22:55:48.709Z,1521068148.709 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2018-03-14T22:55:49.009Z,1521068149.009 [BPC1](INFO): Starting 2018-03-14T22:55:49.016Z,1521068149.016 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-14T22:55:49.017Z,1521068149.017 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T22:55:49.017Z,1521068149.017 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T22:55:49.017Z,1521068149.017 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-14T22:55:49.018Z,1521068149.018 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T22:55:49.018Z,1521068149.018 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T22:55:49.417Z,1521068149.417 [BPC1](INFO): Starting 2018-03-14T22:55:49.823Z,1521068149.823 [BPC1](INFO): Starting 2018-03-14T22:55:50.197Z,1521068150.197 [BPC1](INFO): Starting 2018-03-14T22:55:53.602Z,1521068153.602 [BPC1](INFO): Starting 2018-03-14T22:55:53.671Z,1521068153.671 [DataOverHttps](INFO): Sending 573 bytes from file Logs/20180314T224311/Express0001.lzma 2018-03-14T22:55:53.740Z,1521068153.740 [BPC1](INFO): Starting 2018-03-14T22:55:54.115Z,1521068154.115 [BPC1](INFO): Starting 2018-03-14T22:55:54.471Z,1521068154.471 [DataOverHttps](INFO): Moved sent file to Logs/20180314T224311/Express0001.lzma.bak 2018-03-14T22:55:54.471Z,1521068154.471 [DataOverHttps](INFO): SBD MOMSN=8061986 2018-03-14T22:55:54.523Z,1521068154.523 [BPC1](INFO): Starting 2018-03-14T22:55:55.177Z,1521068155.177 [BPC1](INFO): Starting 2018-03-14T22:55:55.306Z,1521068155.306 [BPC1](INFO): Starting 2018-03-14T22:55:58.392Z,1521068158.392 [BPC1](INFO): Starting 2018-03-14T22:55:58.535Z,1521068158.535 [BPC1](INFO): Starting 2018-03-14T22:55:58.894Z,1521068158.894 [BPC1](INFO): Starting 2018-03-14T22:55:59.287Z,1521068159.287 [BPC1](INFO): Starting 2018-03-14T22:55:59.683Z,1521068159.683 [BPC1](INFO): Starting 2018-03-14T22:56:00.086Z,1521068160.086 [BPC1](INFO): Starting 2018-03-14T22:56:03.448Z,1521068163.448 [BPC1](INFO): Starting 2018-03-14T22:56:03.504Z,1521068163.504 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20180314T224311/Express0005.lzma 2018-03-14T22:56:03.587Z,1521068163.587 [BPC1](INFO): Starting 2018-03-14T22:56:03.957Z,1521068163.957 [BPC1](INFO): Starting 2018-03-14T22:56:04.303Z,1521068164.303 [DataOverHttps](INFO): Moved sent file to Logs/20180314T224311/Express0005.lzma.bak 2018-03-14T22:56:04.303Z,1521068164.303 [DataOverHttps](INFO): SBD MOMSN=8061997 2018-03-14T22:56:04.383Z,1521068164.383 [BPC1](INFO): Starting 2018-03-14T22:56:05.110Z,1521068165.110 [BPC1](INFO): Starting 2018-03-14T22:56:05.263Z,1521068165.263 [BPC1](INFO): Starting 2018-03-14T22:56:05.715Z,1521068165.715 [CommandLine](IMPORTANT): got command get platform_battery_charge 2018-03-14T22:56:05.716Z,1521068165.716 [CommandLine](FAULT): Element has no value 2018-03-14T22:56:08.309Z,1521068168.309 [BPC1](INFO): Starting 2018-03-14T22:56:08.439Z,1521068168.439 [BPC1](INFO): Starting 2018-03-14T22:56:08.781Z,1521068168.781 [BPC1](INFO): Starting 2018-03-14T22:56:09.189Z,1521068169.189 [BPC1](INFO): Starting 2018-03-14T22:56:09.587Z,1521068169.587 [BPC1](INFO): Starting 2018-03-14T22:56:09.965Z,1521068169.965 [BPC1](INFO): Starting 2018-03-14T22:56:13.065Z,1521068173.065 [BPC1](INFO): Starting 2018-03-14T22:56:13.143Z,1521068173.143 [DataOverHttps](INFO): Sending 527 bytes from file Logs/20180314T224635/Express0001.lzma 2018-03-14T22:56:13.195Z,1521068173.195 [BPC1](INFO): Starting 2018-03-14T22:56:13.571Z,1521068173.571 [BPC1](INFO): Starting 2018-03-14T22:56:13.931Z,1521068173.931 [DataOverHttps](INFO): Moved sent file to Logs/20180314T224635/Express0001.lzma.bak 2018-03-14T22:56:13.931Z,1521068173.931 [DataOverHttps](INFO): SBD MOMSN=8062000 2018-03-14T22:56:13.988Z,1521068173.988 [BPC1](INFO): Starting 2018-03-14T22:56:14.658Z,1521068174.658 [BPC1](INFO): Starting 2018-03-14T22:56:14.795Z,1521068174.795 [BPC1](INFO): Starting 2018-03-14T22:56:17.872Z,1521068177.872 [BPC1](INFO): Starting 2018-03-14T22:56:18.007Z,1521068178.007 [BPC1](INFO): Starting 2018-03-14T22:56:18.341Z,1521068178.341 [BPC1](INFO): Starting 2018-03-14T22:56:18.745Z,1521068178.745 [BPC1](INFO): Starting 2018-03-14T22:56:19.142Z,1521068179.142 [BPC1](INFO): Starting 2018-03-14T22:56:19.541Z,1521068179.541 [BPC1](INFO): Starting 2018-03-14T22:56:19.939Z,1521068179.939 [BPC1](INFO): Starting 2018-03-14T22:56:20.329Z,1521068180.329 [BPC1](INFO): Starting 2018-03-14T22:56:23.419Z,1521068183.419 [BPC1](INFO): Starting 2018-03-14T22:56:23.490Z,1521068183.490 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20180314T224635/Express0005.lzma 2018-03-14T22:56:23.575Z,1521068183.575 [BPC1](INFO): Starting 2018-03-14T22:56:23.929Z,1521068183.929 [BPC1](INFO): Starting 2018-03-14T22:56:24.283Z,1521068184.283 [DataOverHttps](INFO): Moved sent file to Logs/20180314T224635/Express0005.lzma.bak 2018-03-14T22:56:24.283Z,1521068184.283 [DataOverHttps](INFO): SBD MOMSN=8062010 2018-03-14T22:56:24.336Z,1521068184.336 [BPC1](INFO): Starting 2018-03-14T22:56:25.001Z,1521068185.001 [BPC1](INFO): Starting 2018-03-14T22:56:25.011Z,1521068185.011 [Default:CheckIn:Read_Iridium] Stopped 2018-03-14T22:56:25.011Z,1521068185.011 [Default:CheckIn:C.Wait] Running Loop=1 2018-03-14T22:56:25.011Z,1521068185.011 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-03-14T22:56:25.127Z,1521068185.127 [BPC1](INFO): Starting 2018-03-14T22:56:28.337Z,1521068188.337 [BPC1](INFO): Starting 2018-03-14T22:56:28.471Z,1521068188.471 [BPC1](INFO): Starting 2018-03-14T22:56:28.805Z,1521068188.805 [BPC1](INFO): Starting 2018-03-14T22:56:29.214Z,1521068189.214 [BPC1](INFO): Starting 2018-03-14T22:56:29.611Z,1521068189.611 [BPC1](INFO): Starting 2018-03-14T22:56:29.992Z,1521068189.992 [BPC1](INFO): Starting 2018-03-14T22:56:30.393Z,1521068190.393 [BPC1](INFO): Starting 2018-03-14T22:56:30.789Z,1521068190.789 [BPC1](INFO): Starting 2018-03-14T22:56:31.215Z,1521068191.215 [BPC1](INFO): Starting 2018-03-14T22:56:31.606Z,1521068191.606 [BPC1](INFO): Starting 2018-03-14T22:56:32.007Z,1521068192.007 [BPC1](INFO): Starting 2018-03-14T22:56:32.399Z,1521068192.399 [BPC1](INFO): Starting 2018-03-14T22:56:32.884Z,1521068192.884 [BPC1](INFO): Starting 2018-03-14T22:56:33.219Z,1521068193.219 [BPC1](INFO): Starting 2018-03-14T22:56:33.601Z,1521068193.601 [BPC1](INFO): Starting 2018-03-14T22:56:34.005Z,1521068194.005 [BPC1](INFO): Starting 2018-03-14T22:56:34.410Z,1521068194.410 [BPC1](INFO): Starting 2018-03-14T22:56:34.819Z,1521068194.819 [BPC1](INFO): Starting 2018-03-14T22:56:35.218Z,1521068195.218 [BPC1](INFO): Starting 2018-03-14T22:56:35.589Z,1521068195.589 [BPC1](INFO): Starting 2018-03-14T22:56:35.996Z,1521068195.996 [BPC1](INFO): Starting 2018-03-14T22:56:36.397Z,1521068196.397 [BPC1](INFO): Starting 2018-03-14T22:56:36.793Z,1521068196.793 [BPC1](INFO): Starting 2018-03-14T22:56:37.203Z,1521068197.203 [BPC1](INFO): Starting 2018-03-14T22:56:37.607Z,1521068197.607 [BPC1](INFO): Starting 2018-03-14T22:56:38.004Z,1521068198.004 [BPC1](INFO): Starting 2018-03-14T22:56:38.405Z,1521068198.405 [BPC1](INFO): Starting 2018-03-14T22:56:38.801Z,1521068198.801 [BPC1](INFO): Starting 2018-03-14T22:56:39.200Z,1521068199.200 [BPC1](INFO): Starting 2018-03-14T22:56:39.201Z,1521068199.201 [BPC1](INFO): Initialized. 2018-03-14T22:56:39.607Z,1521068199.607 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:56:41.222Z,1521068201.222 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:56:44.436Z,1521068204.436 [CBIT](DEBUG): EFC running - data check-sum false 2018-03-14T22:56:44.796Z,1521068204.796 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:56:44.815Z,1521068204.815 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:56:45.210Z,1521068205.210 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:56:52.418Z,1521068212.418 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:56:56.801Z,1521068216.801 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:56:56.808Z,1521068216.808 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:56:57.214Z,1521068217.214 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:57:04.414Z,1521068224.414 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:57:08.830Z,1521068228.830 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:57:08.836Z,1521068228.836 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:57:09.223Z,1521068229.223 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:57:16.808Z,1521068236.808 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:57:21.605Z,1521068241.605 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:57:21.612Z,1521068241.612 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:57:22.014Z,1521068242.014 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:57:25.168Z,1521068245.168 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-03-14T22:57:25.168Z,1521068245.168 [NAL9602] Data Fault, FailCount= 1 2018-03-14T22:57:25.168Z,1521068245.168 [NAL9602](ERROR): Data Fault 2018-03-14T22:57:25.248Z,1521068245.248 [CBIT](ERROR): Data Fault in component: NAL9602 2018-03-14T22:57:25.568Z,1521068245.568 [NAL9602](INFO): Powering down 2018-03-14T22:57:28.979Z,1521068248.979 [CBIT](INFO): Clearing failed state for component NAL9602 2018-03-14T22:57:28.979Z,1521068248.979 [NAL9602] No Fault, FailCount= 1 2018-03-14T22:57:29.732Z,1521068249.732 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:57:33.729Z,1521068253.729 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:57:33.735Z,1521068253.735 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:57:34.135Z,1521068254.135 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:57:36.130Z,1521068256.130 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:57:39.331Z,1521068259.331 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:57:39.337Z,1521068259.337 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:57:39.734Z,1521068259.734 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:57:46.520Z,1521068266.520 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:57:50.926Z,1521068270.926 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:57:50.933Z,1521068270.933 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:57:51.343Z,1521068271.343 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:57:55.696Z,1521068275.696 [NAL9602](INFO): Powering up NAL9602 2018-03-14T22:57:58.930Z,1521068278.930 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:58:03.320Z,1521068283.320 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:58:03.339Z,1521068283.339 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:58:03.734Z,1521068283.734 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:58:06.496Z,1521068286.496 [NAL9602](INFO): NAL9602 initialized 2018-03-14T22:58:10.929Z,1521068290.929 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:58:15.744Z,1521068295.744 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:58:15.751Z,1521068295.751 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:58:16.154Z,1521068296.154 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:58:23.736Z,1521068303.736 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:58:28.842Z,1521068308.842 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:58:28.848Z,1521068308.848 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:58:28.979Z,1521068308.979 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:58:30.913Z,1521068310.913 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:58:34.104Z,1521068314.104 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:58:34.122Z,1521068314.122 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:58:34.542Z,1521068314.542 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:58:41.322Z,1521068321.322 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:58:45.315Z,1521068325.315 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:58:45.321Z,1521068325.321 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:58:45.723Z,1521068325.723 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:58:47.708Z,1521068327.708 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:58:49.343Z,1521068329.343 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-14T22:58:49.343Z,1521068329.343 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-03-14T22:58:49.343Z,1521068329.343 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-03-14T22:58:49.345Z,1521068329.345 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-14T22:58:49.345Z,1521068329.345 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2018-03-14T22:58:49.345Z,1521068329.345 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-03-14T22:58:49.357Z,1521068329.357 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-03-14T22:58:49.357Z,1521068329.357 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-03-14T22:58:49.756Z,1521068329.756 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-03-14T22:58:49.756Z,1521068329.756 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-03-14T22:58:49.756Z,1521068329.756 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-03-14T22:58:49.756Z,1521068329.756 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2018-03-14T22:58:50.140Z,1521068330.140 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-14T22:58:50.141Z,1521068330.141 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T22:58:50.141Z,1521068330.141 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T22:58:50.142Z,1521068330.142 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-14T22:58:50.142Z,1521068330.142 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T22:58:50.142Z,1521068330.142 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T22:58:51.713Z,1521068331.713 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:58:51.720Z,1521068331.720 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:58:52.126Z,1521068332.126 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:58:59.713Z,1521068339.713 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:59:04.105Z,1521068344.105 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:59:04.112Z,1521068344.112 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:59:04.542Z,1521068344.542 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:59:12.112Z,1521068352.112 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:59:16.523Z,1521068356.523 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:59:16.530Z,1521068356.530 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:59:16.938Z,1521068356.938 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:59:24.515Z,1521068364.515 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:59:29.232Z,1521068369.232 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:59:29.238Z,1521068369.238 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:59:29.391Z,1521068369.391 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:59:31.305Z,1521068371.305 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:59:34.923Z,1521068374.923 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T22:59:34.929Z,1521068374.929 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:59:35.334Z,1521068375.334 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:59:42.507Z,1521068382.507 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:59:46.915Z,1521068386.915 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:59:46.921Z,1521068386.921 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:59:47.319Z,1521068387.319 [BPC1](INFO): Bank B: initialized. 2018-03-14T22:59:54.910Z,1521068394.910 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T22:59:59.315Z,1521068399.315 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T22:59:59.346Z,1521068399.346 [BPC1](INFO): Bank B: data parsed. 2018-03-14T22:59:59.714Z,1521068399.714 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:00:07.296Z,1521068407.296 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:00:11.692Z,1521068411.692 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T23:00:11.711Z,1521068411.711 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:00:12.115Z,1521068412.115 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:00:19.715Z,1521068419.715 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:00:23.700Z,1521068423.700 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T23:00:23.707Z,1521068423.707 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:00:24.118Z,1521068424.118 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:00:26.118Z,1521068426.118 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:00:29.613Z,1521068429.613 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T23:00:29.619Z,1521068429.619 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:00:29.755Z,1521068429.755 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:00:36.893Z,1521068436.893 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:00:40.877Z,1521068440.877 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T23:00:40.883Z,1521068440.883 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:00:41.302Z,1521068441.302 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:00:42.882Z,1521068442.882 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:00:46.881Z,1521068446.881 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T23:00:46.888Z,1521068446.888 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:00:47.294Z,1521068447.294 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:00:54.505Z,1521068454.505 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:00:59.288Z,1521068459.288 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T23:00:59.294Z,1521068459.294 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:00:59.707Z,1521068459.707 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:01:07.278Z,1521068467.278 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:01:11.692Z,1521068471.692 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T23:01:11.708Z,1521068471.708 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:01:12.114Z,1521068472.114 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:01:19.687Z,1521068479.687 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:01:23.702Z,1521068483.702 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T23:01:23.713Z,1521068483.713 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:01:24.110Z,1521068484.110 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:01:25.306Z,1521068485.306 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-03-14T23:01:25.307Z,1521068485.307 [Default:CheckIn:C.Wait] Stopped 2018-03-14T23:01:25.307Z,1521068485.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-03-14T23:01:25.307Z,1521068485.307 [Default:CheckIn:D] Running Loop=1 2018-03-14T23:01:25.686Z,1521068485.686 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:01:25.725Z,1521068485.725 [Default:CheckIn:D] Stopped 2018-03-14T23:01:25.725Z,1521068485.725 [Default:CheckIn:E] Running Loop=1 2018-03-14T23:01:26.105Z,1521068486.105 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.371092 min 2018-03-14T23:01:26.107Z,1521068486.107 [Default:CheckIn:E] Stopped 2018-03-14T23:01:26.107Z,1521068486.107 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-03-14T23:01:26.107Z,1521068486.107 [Default:CheckIn] Stopped 2018-03-14T23:01:26.107Z,1521068486.107 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-03-14T23:01:26.107Z,1521068486.107 [Default:CheckIn](INFO): Running loop #2 2018-03-14T23:01:26.107Z,1521068486.107 [Default:CheckIn] Running Loop=2 2018-03-14T23:01:26.108Z,1521068486.108 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-03-14T23:01:26.108Z,1521068486.108 [Default:CheckIn:Read_GPS] Running Loop=1 2018-03-14T23:01:30.002Z,1521068490.002 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T23:01:30.008Z,1521068490.008 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:01:30.147Z,1521068490.147 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:01:37.275Z,1521068497.275 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:01:41.669Z,1521068501.669 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T23:01:41.675Z,1521068501.675 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:01:42.097Z,1521068502.097 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:01:49.686Z,1521068509.686 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:01:50.500Z,1521068510.500 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-14T23:01:50.500Z,1521068510.500 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-03-14T23:01:50.500Z,1521068510.500 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-03-14T23:01:50.502Z,1521068510.502 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-14T23:01:50.502Z,1521068510.502 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5 2018-03-14T23:01:50.502Z,1521068510.502 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-03-14T23:01:50.514Z,1521068510.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-03-14T23:01:50.514Z,1521068510.514 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-03-14T23:01:50.903Z,1521068510.903 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-03-14T23:01:50.903Z,1521068510.903 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2018-03-14T23:01:50.903Z,1521068510.903 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-03-14T23:01:50.903Z,1521068510.903 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5 2018-03-14T23:01:51.273Z,1521068511.273 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-14T23:01:51.273Z,1521068511.273 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T23:01:51.273Z,1521068511.273 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T23:01:51.274Z,1521068511.274 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-14T23:01:51.274Z,1521068511.274 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-14T23:01:51.287Z,1521068511.287 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-14T23:01:54.071Z,1521068514.071 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T23:01:54.078Z,1521068514.078 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:01:54.482Z,1521068514.482 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:02:02.087Z,1521068522.087 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:02:06.463Z,1521068526.463 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T23:02:06.470Z,1521068526.470 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:02:06.886Z,1521068526.886 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:02:14.472Z,1521068534.472 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:02:18.475Z,1521068538.475 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T23:02:18.482Z,1521068538.482 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:02:18.882Z,1521068538.882 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:02:20.481Z,1521068540.481 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:02:24.069Z,1521068544.069 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T23:02:24.084Z,1521068544.084 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:02:24.486Z,1521068544.486 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:02:31.651Z,1521068551.651 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:02:35.635Z,1521068555.635 [BPC1](INFO): Bank B valid message: 1596 bytes. 2018-03-14T23:02:35.641Z,1521068555.641 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:02:36.039Z,1521068556.039 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:02:38.043Z,1521068558.043 [BPC1](INFO): Bank B: sending IBPS break. 2018-03-14T23:02:41.645Z,1521068561.645 [BPC1](INFO): Bank B valid message: 1824 bytes. 2018-03-14T23:02:41.653Z,1521068561.653 [BPC1](INFO): Bank B: data parsed. 2018-03-14T23:02:42.079Z,1521068562.079 [BPC1](INFO): Bank B: initialized. 2018-03-14T23:02:46.755Z,1521068566.755 [CommandLine](IMPORTANT): got command restart application 2018-03-14T23:02:47.758Z,1521068567.758 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2018-03-14T23:02:47.761Z,1521068567.761 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-14T23:02:47.762Z,1521068567.762 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:47.854Z,1521068567.854 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-03-14T23:02:47.854Z,1521068567.854 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:47.855Z,1521068567.855 [CommandLine](INFO): Join timeout helper Thread ID is 1042 2018-03-14T23:02:47.862Z,1521068567.862 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-03-14T23:02:47.862Z,1521068567.862 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:47.862Z,1521068567.862 [NavChartDb](INFO): Join timeout helper Thread ID is 1043 2018-03-14T23:02:47.963Z,1521068567.963 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-14T23:02:47.963Z,1521068567.963 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:47.982Z,1521068567.982 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-03-14T23:02:47.982Z,1521068567.982 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:47.983Z,1521068567.983 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1044 2018-03-14T23:02:48.079Z,1521068568.079 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-14T23:02:48.079Z,1521068568.079 [WetLabsBB2FL](INFO): Powering down 2018-03-14T23:02:48.080Z,1521068568.080 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.083Z,1521068568.083 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-03-14T23:02:48.083Z,1521068568.083 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.083Z,1521068568.083 [Radio_Surface](INFO): Join timeout helper Thread ID is 1045 2018-03-14T23:02:48.391Z,1521068568.391 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-14T23:02:48.391Z,1521068568.391 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.403Z,1521068568.403 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-03-14T23:02:48.403Z,1521068568.403 [logger ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.403Z,1521068568.403 [logger](INFO): Join timeout helper Thread ID is 1046 2018-03-14T23:02:48.411Z,1521068568.411 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-14T23:02:48.411Z,1521068568.411 [logger ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.425Z,1521068568.425 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-03-14T23:02:48.425Z,1521068568.425 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.425Z,1521068568.425 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-03-14T23:02:48.425Z,1521068568.425 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.425Z,1521068568.425 [controlThread](INFO): Join timeout helper Thread ID is 1047 2018-03-14T23:02:48.469Z,1521068568.469 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-14T23:02:48.469Z,1521068568.469 [controlThread](DEBUG): Uninitializing ControlThread 2018-03-14T23:02:48.470Z,1521068568.470 [NAL9602](INFO): Powering down 2018-03-14T23:02:48.471Z,1521068568.471 [PNI_TCM](INFO): Powering down 2018-03-14T23:02:48.559Z,1521068568.559 [BPC1](INFO): uninitialize 2018-03-14T23:02:48.559Z,1521068568.559 [Aanderaa_O2](INFO): Powering down 2018-03-14T23:02:48.561Z,1521068568.561 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-03-14T23:02:48.563Z,1521068568.563 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-03-14T23:02:48.563Z,1521068568.563 [MissionManager](INFO): Uninitializing Mission Default 2018-03-14T23:02:48.564Z,1521068568.564 [Default] Stopped 2018-03-14T23:02:48.564Z,1521068568.564 [Default](DEBUG): Aggregate::uninitialize Default 2018-03-14T23:02:48.564Z,1521068568.564 [Default:B.GoToSurface] Stopped 2018-03-14T23:02:48.564Z,1521068568.564 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-03-14T23:02:48.564Z,1521068568.564 [Default:CheckIn] Stopped 2018-03-14T23:02:48.564Z,1521068568.564 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-03-14T23:02:48.564Z,1521068568.564 [Default:CheckIn:Read_GPS] Stopped 2018-03-14T23:02:48.567Z,1521068568.567 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-03-14T23:02:48.567Z,1521068568.567 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-03-14T23:02:48.567Z,1521068568.567 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-03-14T23:02:48.568Z,1521068568.568 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-03-14T23:02:48.568Z,1521068568.568 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-03-14T23:02:48.568Z,1521068568.568 [BuoyancyServo](INFO): Powering down 2018-03-14T23:02:48.583Z,1521068568.583 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-03-14T23:02:48.583Z,1521068568.583 [ElevatorServo](INFO): Powering down 2018-03-14T23:02:48.584Z,1521068568.584 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-03-14T23:02:48.584Z,1521068568.584 [MassServo](INFO): Powering down 2018-03-14T23:02:48.584Z,1521068568.584 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-03-14T23:02:48.585Z,1521068568.585 [RudderServo](INFO): Powering down 2018-03-14T23:02:48.585Z,1521068568.585 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-03-14T23:02:48.585Z,1521068568.585 [ThrusterServo](INFO): Powering down 2018-03-14T23:02:48.586Z,1521068568.586 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-03-14T23:02:48.586Z,1521068568.586 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-03-14T23:02:48.587Z,1521068568.587 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-03-14T23:02:48.588Z,1521068568.588 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.634Z,1521068568.634 [BPC1](INFO): uninitialize 2018-03-14T23:02:48.680Z,1521068568.680 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.717Z,1521068568.717 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.742Z,1521068568.742 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-03-14T23:02:48.790Z,1521068568.790 [logger ThreadHandler](INFO): Thread cancelled.