2019-02-22T01:03:41.325Z,1550797421.325 [Supervisor](DEBUG): Initializing supervisor.
2019-02-22T01:03:41.327Z,1550797421.327 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-22T01:03:41.328Z,1550797421.328 [SyncHandler](INFO): Protected caller Thread ID is 807
2019-02-22T01:03:41.328Z,1550797421.328 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-22T01:03:41.329Z,1550797421.329 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-22T01:03:41.330Z,1550797421.330 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 808
2019-02-22T01:03:41.332Z,1550797421.332 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-22T01:03:41.343Z,1550797421.343 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-22T01:03:41.344Z,1550797421.344 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-22T01:03:41.345Z,1550797421.345 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 809
2019-02-22T01:03:41.346Z,1550797421.346 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-22T01:03:41.346Z,1550797421.346 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-22T01:03:41.347Z,1550797421.347 [logger ThreadHandler](INFO): Protected caller Thread ID is 810
2019-02-22T01:03:41.349Z,1550797421.349 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-22T01:03:41.349Z,1550797421.349 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-22T01:03:41.353Z,1550797421.353 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-22T01:03:41.547Z,1550797421.547 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-22T01:03:41.548Z,1550797421.548 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-22T01:03:41.630Z,1550797421.630 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-22T01:03:42.065Z,1550797422.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-22T01:03:42.066Z,1550797422.066 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-22T01:03:42.386Z,1550797422.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-22T01:03:42.387Z,1550797422.387 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-22T01:03:42.482Z,1550797422.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-22T01:03:42.483Z,1550797422.483 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-22T01:03:42.776Z,1550797422.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-22T01:03:42.777Z,1550797422.777 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-22T01:03:42.971Z,1550797422.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-22T01:03:42.972Z,1550797422.972 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-22T01:03:43.410Z,1550797423.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-22T01:03:43.411Z,1550797423.411 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-22T01:03:43.514Z,1550797423.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-22T01:03:43.516Z,1550797423.516 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-22T01:03:43.618Z,1550797423.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-22T01:03:43.619Z,1550797423.619 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-22T01:03:44.093Z,1550797424.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-22T01:03:44.094Z,1550797424.094 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-22T01:03:44.484Z,1550797424.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-22T01:03:44.485Z,1550797424.485 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-22T01:03:44.702Z,1550797424.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-22T01:03:44.703Z,1550797424.703 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-22T01:03:45.203Z,1550797425.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-22T01:03:45.203Z,1550797425.203 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-22T01:03:45.786Z,1550797425.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-22T01:03:45.789Z,1550797425.789 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-02-22T01:03:45.794Z,1550797425.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-02-22T01:03:45.885Z,1550797425.885 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-02-22T01:03:45.971Z,1550797425.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-02-22T01:03:46.075Z,1550797426.075 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-02-22T01:03:46.158Z,1550797426.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-02-22T01:03:46.254Z,1550797426.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-02-22T01:03:46.356Z,1550797426.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-02-22T01:03:46.542Z,1550797426.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-02-22T01:03:46.619Z,1550797426.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-02-22T01:03:46.778Z,1550797426.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-02-22T01:03:46.903Z,1550797426.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-02-22T01:03:47.019Z,1550797427.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-02-22T01:03:47.244Z,1550797427.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-22T01:03:47.245Z,1550797427.245 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-02-22T01:03:47.249Z,1550797427.249 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-22T01:03:47.337Z,1550797427.337 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-22T01:03:47.453Z,1550797427.453 [VerticalControl] Loaded
2019-02-22T01:03:47.453Z,1550797427.453 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-22T01:03:47.454Z,1550797427.454 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-22T01:03:47.523Z,1550797427.523 [HorizontalControl] Loaded
2019-02-22T01:03:47.523Z,1550797427.523 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-22T01:03:47.524Z,1550797427.524 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-22T01:03:47.529Z,1550797427.529 [SpeedControl] Loaded
2019-02-22T01:03:47.530Z,1550797427.530 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-22T01:03:47.530Z,1550797427.530 [LoopControl](DEBUG): Construct LoopControl.
2019-02-22T01:03:47.531Z,1550797427.531 [LoopControl] Loaded
2019-02-22T01:03:47.532Z,1550797427.532 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-22T01:03:47.532Z,1550797427.532 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-22T01:03:47.533Z,1550797427.533 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-22T01:03:47.557Z,1550797427.557 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-22T01:03:47.558Z,1550797427.558 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-22T01:03:47.686Z,1550797427.686 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-22T01:03:47.687Z,1550797427.687 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-22T01:03:47.831Z,1550797427.831 [BuoyancyServo] Loaded
2019-02-22T01:03:47.831Z,1550797427.831 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-22T01:03:47.842Z,1550797427.842 [ElevatorServo] Loaded
2019-02-22T01:03:47.843Z,1550797427.843 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-22T01:03:47.853Z,1550797427.853 [MassServo] Loaded
2019-02-22T01:03:47.854Z,1550797427.854 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-22T01:03:47.865Z,1550797427.865 [RudderServo] Loaded
2019-02-22T01:03:47.865Z,1550797427.865 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-22T01:03:47.876Z,1550797427.876 [ThrusterServo] Loaded
2019-02-22T01:03:47.876Z,1550797427.876 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-22T01:03:47.877Z,1550797427.877 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-22T01:03:47.877Z,1550797427.877 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-22T01:03:48.002Z,1550797428.002 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-22T01:03:48.002Z,1550797428.002 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-22T01:03:48.062Z,1550797428.062 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-22T01:03:48.062Z,1550797428.062 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-22T01:03:48.121Z,1550797428.121 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-22T01:03:48.121Z,1550797428.121 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-22T01:03:48.137Z,1550797428.137 [NavChart] Loaded
2019-02-22T01:03:48.137Z,1550797428.137 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-22T01:03:48.141Z,1550797428.141 [UniversalFixResidualReporter] Loaded
2019-02-22T01:03:48.142Z,1550797428.142 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-22T01:03:48.142Z,1550797428.142 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-22T01:03:48.143Z,1550797428.143 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-22T01:03:48.246Z,1550797428.246 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-22T01:03:48.247Z,1550797428.247 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-22T01:03:48.839Z,1550797428.839 [DataOverHttps] Loaded
2019-02-22T01:03:48.839Z,1550797428.839 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-22T01:03:48.852Z,1550797428.852 [Depth_Keller] Loaded
2019-02-22T01:03:48.853Z,1550797428.853 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-22T01:03:48.858Z,1550797428.858 [DropWeight] Loaded
2019-02-22T01:03:48.858Z,1550797428.858 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-22T01:03:48.956Z,1550797428.956 [NAL9602] Loaded
2019-02-22T01:03:48.956Z,1550797428.956 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-22T01:03:48.973Z,1550797428.973 [Onboard] Loaded
2019-02-22T01:03:48.973Z,1550797428.973 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-22T01:03:48.980Z,1550797428.980 [Radio_Surface] Loaded
2019-02-22T01:03:48.980Z,1550797428.980 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-22T01:03:48.981Z,1550797428.981 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408624E0
2019-02-22T01:03:48.982Z,1550797428.982 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 892
2019-02-22T01:03:49.135Z,1550797429.135 [PNI_TCM] Loaded
2019-02-22T01:03:49.135Z,1550797429.135 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-02-22T01:03:49.195Z,1550797429.195 [Rowe_600LCM] Loaded
2019-02-22T01:03:49.195Z,1550797429.195 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-02-22T01:03:49.196Z,1550797429.196 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2019-02-22T01:03:49.197Z,1550797429.197 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 893
2019-02-22T01:03:49.197Z,1550797429.197 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-22T01:03:49.198Z,1550797429.198 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-22T01:03:49.254Z,1550797429.254 [DepthRateCalculator] Loaded
2019-02-22T01:03:49.254Z,1550797429.254 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-22T01:03:49.259Z,1550797429.259 [PitchRateCalculator] Loaded
2019-02-22T01:03:49.260Z,1550797429.260 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-22T01:03:49.271Z,1550797429.271 [SpeedCalculator] Loaded
2019-02-22T01:03:49.271Z,1550797429.271 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-22T01:03:49.293Z,1550797429.293 [TempGradientCalculator] Loaded
2019-02-22T01:03:49.294Z,1550797429.294 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-22T01:03:49.299Z,1550797429.299 [YawRateCalculator] Loaded
2019-02-22T01:03:49.300Z,1550797429.300 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-22T01:03:49.331Z,1550797429.330 [ElevatorOffsetCalculator] Loaded
2019-02-22T01:03:49.331Z,1550797429.331 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-22T01:03:49.331Z,1550797429.331 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-22T01:03:49.332Z,1550797429.332 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-22T01:03:49.567Z,1550797429.567 [Aanderaa_O2] Loaded
2019-02-22T01:03:49.567Z,1550797429.567 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-22T01:03:49.653Z,1550797429.653 [CTD_NeilBrown] Loaded
2019-02-22T01:03:49.653Z,1550797429.653 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-22T01:03:49.654Z,1550797429.654 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0
2019-02-22T01:03:49.655Z,1550797429.655 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 894
2019-02-22T01:03:49.664Z,1550797429.664 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-02-22T01:03:49.669Z,1550797429.669 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-02-22T01:03:49.671Z,1550797429.671 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-02-22T01:03:49.676Z,1550797429.676 [CTD_Seabird](INFO): created writer for : depth
2019-02-22T01:03:49.676Z,1550797429.676 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-02-22T01:03:49.682Z,1550797429.682 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-02-22T01:03:49.682Z,1550797429.682 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-02-22T01:03:49.687Z,1550797429.687 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-02-22T01:03:49.688Z,1550797429.688 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-02-22T01:03:49.693Z,1550797429.693 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-02-22T01:03:49.694Z,1550797429.694 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-02-22T01:03:49.699Z,1550797429.699 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-02-22T01:03:49.699Z,1550797429.699 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-02-22T01:03:49.705Z,1550797429.705 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-02-22T01:03:49.732Z,1550797429.732 [CTD_Seabird] Loaded
2019-02-22T01:03:49.732Z,1550797429.732 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-02-22T01:03:49.733Z,1550797429.733 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4098C4E0
2019-02-22T01:03:49.734Z,1550797429.734 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 895
2019-02-22T01:03:49.748Z,1550797429.748 [PAR_Licor] Loaded
2019-02-22T01:03:49.749Z,1550797429.749 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-02-22T01:03:49.797Z,1550797429.797 [WetLabsBB2FL] Loaded
2019-02-22T01:03:49.797Z,1550797429.797 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-02-22T01:03:49.798Z,1550797429.798 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409BC4E0
2019-02-22T01:03:49.799Z,1550797429.799 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 896
2019-02-22T01:03:49.799Z,1550797429.799 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-22T01:03:49.800Z,1550797429.800 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-22T01:03:49.891Z,1550797429.891 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-22T01:03:49.892Z,1550797429.892 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-22T01:03:50.562Z,1550797430.562 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-22T01:03:50.563Z,1550797430.563 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-22T01:03:50.725Z,1550797430.725 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-22T01:03:50.737Z,1550797430.737 [SBIT] Loaded
2019-02-22T01:03:50.737Z,1550797430.737 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-22T01:03:50.738Z,1550797430.738 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-22T01:03:50.751Z,1550797430.751 [IBIT] Loaded
2019-02-22T01:03:50.751Z,1550797430.751 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-22T01:03:50.754Z,1550797430.754 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-22T01:03:51.147Z,1550797431.147 [CBIT] Loaded
2019-02-22T01:03:51.147Z,1550797431.147 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-22T01:03:51.148Z,1550797431.148 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-22T01:03:51.151Z,1550797431.151 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-22T01:03:51.152Z,1550797431.152 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-22T01:03:51.159Z,1550797431.159 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-22T01:03:51.160Z,1550797431.160 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA64E0
2019-02-22T01:03:51.161Z,1550797431.161 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 897
2019-02-22T01:03:51.165Z,1550797431.165 [Supervisor](INFO): Main Thread ID is 803
2019-02-22T01:03:51.165Z,1550797431.165 [Supervisor](DEBUG): Running supervisor.
2019-02-22T01:03:51.166Z,1550797431.166 [CommandLine ThreadHandler](INFO): Handler Thread ID is 898
2019-02-22T01:03:51.168Z,1550797431.168 [controlThread ThreadHandler](INFO): Handler Thread ID is 899
2019-02-22T01:03:51.168Z,1550797431.168 [controlThread](DEBUG): Initializing ControlThread
2019-02-22T01:03:51.169Z,1550797431.169 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-22T01:03:51.171Z,1550797431.171 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-22T01:03:51.172Z,1550797431.172 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-22T01:03:51.172Z,1550797431.172 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-22T01:03:51.174Z,1550797431.174 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T01:03:51.175Z,1550797431.175 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-22T01:03:51.175Z,1550797431.175 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-22T01:03:51.179Z,1550797431.179 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-22T01:03:51.179Z,1550797431.179 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-22T01:03:51.179Z,1550797431.179 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-22T01:03:51.180Z,1550797431.180 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-22T01:03:51.180Z,1550797431.180 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-22T01:03:51.181Z,1550797431.181 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-22T01:03:51.185Z,1550797431.185 [SBIT](INFO): Initialize SBIT Component.
2019-02-22T01:03:51.186Z,1550797431.186 [SBIT](IMPORTANT): git: 2019-01-14A-50-g9cfb34a
2019-02-22T01:03:51.186Z,1550797431.186 [SBIT](INFO): git hash: 9cfb34a5380b67c155f39980646156fa9a5482a4
2019-02-22T01:03:51.186Z,1550797431.186 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-22T01:03:51.187Z,1550797431.187 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-02-22T01:03:51.188Z,1550797431.188 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2019-02-22T01:03:51.189Z,1550797431.189 [IBIT](INFO): Initialize IBIT Component.
2019-02-22T01:03:51.190Z,1550797431.190 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-22T01:03:51.191Z,1550797431.191 [logger ThreadHandler](INFO): Handler Thread ID is 900
2019-02-22T01:03:51.201Z,1550797431.201 [CBIT](DEBUG): Initialized mux pins.
2019-02-22T01:03:51.201Z,1550797431.201 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-02-22T01:03:51.201Z,1550797431.201 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-22T01:03:51.209Z,1550797431.209 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 901
2019-02-22T01:03:51.215Z,1550797431.215 [Radio_Surface](INFO): Powering up
2019-02-22T01:03:51.221Z,1550797431.221 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 902
2019-02-22T01:03:51.222Z,1550797431.222 [Rowe_600LCM](INFO): Initializing
2019-02-22T01:03:51.229Z,1550797431.229 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-22T01:03:51.229Z,1550797431.229 [CBIT](DEBUG): Initializing heartbeat.
2019-02-22T01:03:51.325Z,1550797431.325 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 903
2019-02-22T01:03:51.326Z,1550797431.326 [CTD_NeilBrown](INFO): Powering down
2019-02-22T01:03:51.346Z,1550797431.346 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-22T01:03:51.346Z,1550797431.346 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-22T01:03:51.425Z,1550797431.425 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-22T01:03:51.437Z,1550797431.437 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 904
2019-02-22T01:03:51.438Z,1550797431.438 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T01:03:51.440Z,1550797431.440 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 906
2019-02-22T01:03:51.442Z,1550797431.442 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T01:03:51.444Z,1550797431.444 [WetLabsBB2FL](INFO): Powering down
2019-02-22T01:03:51.578Z,1550797431.578 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 907
2019-02-22T01:03:51.584Z,1550797431.584 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2019-02-22T01:03:51.614Z,1550797431.614 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-22T01:03:51.615Z,1550797431.615 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2019-02-22T01:03:51.616Z,1550797431.616 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-22T01:03:51.625Z,1550797431.625 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-22T01:03:51.665Z,1550797431.665 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2019-02-22T01:03:51.666Z,1550797431.666 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-22T01:03:51.667Z,1550797431.667 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2019-02-22T01:03:51.667Z,1550797431.667 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-22T01:03:51.669Z,1550797431.669 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2019-02-22T01:03:51.669Z,1550797431.669 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-22T01:03:51.670Z,1550797431.670 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2019-02-22T01:03:51.671Z,1550797431.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T01:03:51.672Z,1550797431.672 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2019-02-22T01:03:51.672Z,1550797431.672 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T01:03:51.692Z,1550797431.692 [Rowe_600LCM](INFO): Powering up
2019-02-22T01:03:51.695Z,1550797431.695 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-02-22T01:03:51.696Z,1550797431.696 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-22T01:03:51.710Z,1550797431.710 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2019-02-22T01:03:51.710Z,1550797431.710 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T01:03:51.712Z,1550797431.712 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2019-02-22T01:03:51.732Z,1550797431.732 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T01:03:51.815Z,1550797431.815 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-22T01:03:51.817Z,1550797431.817 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-22T01:03:51.818Z,1550797431.818 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-22T01:03:51.890Z,1550797431.890 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-22T01:03:51.893Z,1550797431.893 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-22T01:03:51.915Z,1550797431.915 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-22T01:03:51.918Z,1550797431.918 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-02-22T01:03:51.957Z,1550797431.957 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-22T01:03:51.969Z,1550797431.969 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-22T01:03:51.998Z,1550797431.998 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-02-22T01:03:52.206Z,1550797432.206 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-22T01:03:52.593Z,1550797432.593 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-22T01:03:52.601Z,1550797432.601 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-22T01:03:52.624Z,1550797432.624 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-22T01:03:52.649Z,1550797432.649 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-22T01:03:52.667Z,1550797432.667 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-22T01:03:52.677Z,1550797432.677 [MassServo](DEBUG): Initializing MassServo.
2019-02-22T01:03:52.697Z,1550797432.697 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-22T01:03:52.713Z,1550797432.713 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-22T01:03:52.731Z,1550797432.731 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-22T01:03:52.757Z,1550797432.757 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-22T01:03:53.023Z,1550797433.023 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-02-22T01:03:53.023Z,1550797433.023 [DropWeight] Hardware Fault, FailCount= 1
2019-02-22T01:03:53.023Z,1550797433.023 [DropWeight](ERROR): Hardware Fault
2019-02-22T01:03:53.489Z,1550797433.489 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-02-22T01:03:53.489Z,1550797433.489 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-02-22T01:03:53.489Z,1550797433.489 [BuoyancyServo] Communications Fault, FailCount= 1
2019-02-22T01:03:53.489Z,1550797433.489 [BuoyancyServo](ERROR): Communications Fault
2019-02-22T01:03:53.754Z,1550797433.754 [CommandLine](FAULT): Scheduling is paused
2019-02-22T01:03:53.755Z,1550797433.755 [CBIT](INFO): Critical error at 20190222T010353
2019-02-22T01:03:53.755Z,1550797433.755 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-22T01:03:53.758Z,1550797433.758 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-02-22T01:03:53.759Z,1550797433.759 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-02-22T01:03:53.759Z,1550797433.759 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-02-22T01:03:53.881Z,1550797433.881 [CBIT](INFO): Critical error at 20190222T010353
2019-02-22T01:03:55.723Z,1550797435.723 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T01:03:55.864Z,1550797435.864 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-22T01:03:56.110Z,1550797436.110 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-02-22T01:03:56.110Z,1550797436.110 [BuoyancyServo] No Fault, FailCount= 1
2019-02-22T01:03:56.297Z,1550797436.297 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-22T01:03:56.298Z,1550797436.298 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-22T01:04:01.009Z,1550797441.009 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2019-02-22T01:04:01.009Z,1550797441.009 [PNI_TCM] Data Fault, FailCount= 1
2019-02-22T01:04:01.009Z,1550797441.009 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:04:01.105Z,1550797441.105 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:04:01.406Z,1550797441.406 [PNI_TCM](INFO): Powering down
2019-02-22T01:04:01.515Z,1550797441.515 [CBIT](CRITICAL): Environmental Failure. Press:14.581689 PSI. Humidity:15%. Temp:24 C. ABORTING MISSION
2019-02-22T01:04:01.828Z,1550797441.828 [CBIT](INFO): Critical error at 20190222T010401
2019-02-22T01:04:02.190Z,1550797442.190 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T01:04:02.190Z,1550797442.190 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-22T01:04:02.190Z,1550797442.190 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T01:04:02.321Z,1550797442.321 [PAR_Licor](INFO): A/D timeout, 7 tries over 127 ms

2019-02-22T01:04:02.321Z,1550797442.321 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T01:04:02.321Z,1550797442.321 [PAR_Licor](ERROR): Data Fault
2019-02-22T01:04:02.392Z,1550797442.392 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T01:04:02.393Z,1550797442.393 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T01:04:02.393Z,1550797442.393 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T01:04:02.393Z,1550797442.393 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T01:04:02.590Z,1550797442.590 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:04:02.678Z,1550797442.678 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:04:02.678Z,1550797442.678 [PNI_TCM] No Fault, FailCount= 1
2019-02-22T01:04:03.441Z,1550797443.441 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T01:04:03.441Z,1550797443.441 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-22T01:04:05.493Z,1550797445.493 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T01:04:05.493Z,1550797445.493 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-22T01:04:05.493Z,1550797445.493 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T01:04:05.897Z,1550797445.897 [CTD_Seabird](INFO): Powering down
2019-02-22T01:04:05.918Z,1550797445.918 [PAR_Licor](INFO): A/D timeout, 7 tries over 125 ms

2019-02-22T01:04:05.918Z,1550797445.918 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T01:04:05.918Z,1550797445.918 [PAR_Licor](ERROR): Data Fault
2019-02-22T01:04:05.970Z,1550797445.970 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T01:04:05.970Z,1550797445.970 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T01:04:05.970Z,1550797445.970 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T01:04:05.971Z,1550797445.971 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T01:04:07.129Z,1550797447.129 [PAR_Licor](INFO): A/D timeout, 8 tries over 135 ms

2019-02-22T01:04:07.129Z,1550797447.129 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T01:04:07.129Z,1550797447.129 [PAR_Licor](ERROR): Data Fault
2019-02-22T01:04:07.208Z,1550797447.208 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T01:04:07.208Z,1550797447.208 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T01:04:07.208Z,1550797447.208 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T01:04:07.969Z,1550797447.969 [PAR_Licor](INFO): A/D timeout, 8 tries over 151 ms

2019-02-22T01:04:07.969Z,1550797447.969 [PAR_Licor] Data Fault, FailCount= 2
2019-02-22T01:04:07.969Z,1550797447.969 [PAR_Licor](ERROR): Data Fault
2019-02-22T01:04:08.015Z,1550797448.015 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T01:04:08.015Z,1550797448.015 [CTD_Seabird] No Fault, FailCount= 1
2019-02-22T01:04:08.016Z,1550797448.016 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T01:04:08.016Z,1550797448.016 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T01:04:08.016Z,1550797448.016 [PAR_Licor] No Fault, FailCount= 2
2019-02-22T01:04:08.253Z,1550797448.253 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T01:04:08.717Z,1550797448.717 [PAR_Licor](INFO): A/D timeout, 7 tries over 124 ms

2019-02-22T01:04:08.717Z,1550797448.717 [PAR_Licor] Data Fault, FailCount= 3
2019-02-22T01:04:08.717Z,1550797448.717 [PAR_Licor](ERROR): Data Fault
2019-02-22T01:04:08.745Z,1550797448.745 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T01:04:08.746Z,1550797448.746 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T01:04:08.748Z,1550797448.748 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T01:04:08.748Z,1550797448.748 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-02-22T01:04:09.034Z,1550797449.034 [CBIT](INFO): Critical error at 20190222T010408
2019-02-22T01:04:10.188Z,1550797450.188 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:04:10.188Z,1550797450.188 [PNI_TCM] Data Fault, FailCount= 2
2019-02-22T01:04:10.188Z,1550797450.188 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:04:10.234Z,1550797450.234 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:04:10.589Z,1550797450.589 [PNI_TCM](INFO): Powering down
2019-02-22T01:04:11.820Z,1550797451.820 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:04:11.820Z,1550797451.820 [PNI_TCM] No Fault, FailCount= 2
2019-02-22T01:04:14.211Z,1550797454.211 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T01:04:14.211Z,1550797454.211 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-22T01:04:14.211Z,1550797454.211 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T01:04:14.281Z,1550797454.281 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T01:04:14.589Z,1550797454.589 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:04:14.611Z,1550797454.611 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-22T01:04:14.632Z,1550797454.632 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-22T01:04:15.480Z,1550797455.480 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T01:04:15.480Z,1550797455.480 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-22T01:04:19.114Z,1550797459.114 [NAL9602](INFO): Powering up NAL9602
2019-02-22T01:04:19.521Z,1550797459.521 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:04:19.521Z,1550797459.521 [PNI_TCM] Data Fault, FailCount= 3
2019-02-22T01:04:19.521Z,1550797459.521 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:04:19.591Z,1550797459.591 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:04:19.917Z,1550797459.917 [PNI_TCM](INFO): Powering down
2019-02-22T01:04:21.219Z,1550797461.219 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:04:21.219Z,1550797461.219 [PNI_TCM] No Fault, FailCount= 3
2019-02-22T01:04:22.785Z,1550797462.785 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T01:04:22.785Z,1550797462.785 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-22T01:04:22.785Z,1550797462.785 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T01:04:22.803Z,1550797462.803 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T01:04:23.189Z,1550797463.189 [CTD_Seabird](INFO): Powering down
2019-02-22T01:04:25.161Z,1550797465.161 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T01:04:25.161Z,1550797465.161 [CTD_Seabird] No Fault, FailCount= 2
2019-02-22T01:04:25.590Z,1550797465.590 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001705
CHAN A1 (24V): -0.005599
CHAN A2 (12V): -0.001452
CHAN A3 (5V): -0.001534
CHAN B0 (3.3V): 0.000652
CHAN B1 (3.15aV): -0.000649
CHAN B2 (3.15bV): -0.000685
CHAN B3 (GND): 0.000250
OPEN: -0.000442
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-22T01:04:25.917Z,1550797465.917 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T01:04:25.918Z,1550797465.918 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-22T01:04:25.918Z,1550797465.918 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T01:04:25.970Z,1550797465.970 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T01:04:25.970Z,1550797465.970 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-22T01:04:26.017Z,1550797466.017 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T01:04:26.018Z,1550797466.018 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T01:04:26.318Z,1550797466.318 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:04:28.724Z,1550797468.724 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:04:28.733Z,1550797468.733 [PNI_TCM] Data Fault, FailCount= 4
2019-02-22T01:04:28.733Z,1550797468.733 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:04:28.808Z,1550797468.808 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:04:29.130Z,1550797469.130 [PNI_TCM](INFO): Powering down
2019-02-22T01:04:30.718Z,1550797470.718 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:04:30.718Z,1550797470.718 [PNI_TCM] No Fault, FailCount= 4
2019-02-22T01:04:38.122Z,1550797478.122 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:04:38.122Z,1550797478.122 [PNI_TCM] Data Fault, FailCount= 5
2019-02-22T01:04:38.122Z,1550797478.122 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:04:38.219Z,1550797478.219 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:04:38.220Z,1550797478.220 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-02-22T01:04:38.525Z,1550797478.525 [PNI_TCM](INFO): Powering down
2019-02-22T01:04:38.622Z,1550797478.622 [CBIT](INFO): Critical error at 20190222T010438
2019-02-22T01:04:40.057Z,1550797480.057 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T01:04:40.057Z,1550797480.057 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-22T01:04:40.057Z,1550797480.057 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T01:04:40.102Z,1550797480.102 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T01:04:40.102Z,1550797480.102 [NAL9602] Communications Fault, FailCount= 1
2019-02-22T01:04:40.102Z,1550797480.102 [NAL9602](ERROR): Communications Fault
2019-02-22T01:04:40.191Z,1550797480.191 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T01:04:40.192Z,1550797480.192 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T01:04:40.192Z,1550797480.192 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-22T01:04:40.461Z,1550797480.461 [CTD_Seabird](INFO): Powering down
2019-02-22T01:04:40.502Z,1550797480.502 [NAL9602](INFO): Powering down
2019-02-22T01:04:41.387Z,1550797481.387 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T01:04:41.387Z,1550797481.387 [NAL9602] No Fault, FailCount= 1
2019-02-22T01:04:47.022Z,1550797487.022 [NavChartDb](INFO): # of records loaded: 5000
2019-02-22T01:05:08.675Z,1550797508.675 [SBIT](IMPORTANT): SBIT PASSED
2019-02-22T01:05:08.742Z,1550797508.742 [CommandLine](IMPORTANT): got command configSet list
2019-02-22T01:05:08.742Z,1550797508.742 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-22T01:05:08.743Z,1550797508.743 [CommandLine](IMPORTANT): No configSet variables persisted
2019-02-22T01:05:09.063Z,1550797509.063 [MissionManager](IMPORTANT): Started mission Startup
2019-02-22T01:05:09.063Z,1550797509.063 [Startup] Running Loop=1
2019-02-22T01:05:09.064Z,1550797509.064 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-22T01:05:09.064Z,1550797509.064 [Startup:A.GoToSurface] Running Loop=1
2019-02-22T01:05:09.064Z,1550797509.064 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-22T01:05:09.064Z,1550797509.064 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-22T01:05:09.065Z,1550797509.065 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-22T01:05:09.065Z,1550797509.065 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-22T01:05:09.066Z,1550797509.066 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-22T01:05:09.066Z,1550797509.066 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-22T01:05:09.084Z,1550797509.084 [Startup:StartupSatComms] Running Loop=1
2019-02-22T01:05:09.084Z,1550797509.084 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-22T01:05:09.084Z,1550797509.084 [Startup:StartupSatComms:A] Running Loop=1
2019-02-22T01:05:09.456Z,1550797509.456 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-22T01:05:10.610Z,1550797510.610 [NAL9602](INFO): Powering up NAL9602
2019-02-22T01:05:31.810Z,1550797531.810 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T01:05:31.810Z,1550797531.810 [NAL9602] Communications Fault, FailCount= 2
2019-02-22T01:05:31.810Z,1550797531.810 [NAL9602](ERROR): Communications Fault
2019-02-22T01:05:31.896Z,1550797531.896 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T01:05:32.210Z,1550797532.210 [NAL9602](INFO): Powering down
2019-02-22T01:05:33.099Z,1550797533.099 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T01:05:33.099Z,1550797533.099 [NAL9602] No Fault, FailCount= 2
2019-02-22T01:05:35.783Z,1550797535.783 [NavChartDb](INFO): # of records loaded: 10000
2019-02-22T01:05:46.832Z,1550797546.832 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T01:05:51.441Z,1550797551.441 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:05:51.441Z,1550797551.441 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-22T01:05:51.441Z,1550797551.441 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T01:05:51.443Z,1550797551.443 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:05:51.443Z,1550797551.443 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-22T01:05:51.443Z,1550797551.443 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T01:05:51.445Z,1550797551.445 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:05:51.445Z,1550797551.445 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-22T01:05:51.445Z,1550797551.445 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T01:05:51.474Z,1550797551.474 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T01:05:51.474Z,1550797551.474 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T01:05:51.474Z,1550797551.474 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T01:05:51.805Z,1550797551.805 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-22T01:05:51.805Z,1550797551.805 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-02-22T01:05:51.805Z,1550797551.805 [Rowe_600LCM](ERROR): Communications Fault
2019-02-22T01:05:51.893Z,1550797551.893 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T01:05:51.893Z,1550797551.893 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-22T01:05:51.893Z,1550797551.893 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T01:05:51.893Z,1550797551.893 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-22T01:05:51.894Z,1550797551.894 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T01:05:51.894Z,1550797551.894 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-22T01:05:51.895Z,1550797551.895 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-22T01:05:52.209Z,1550797552.209 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T01:05:52.262Z,1550797552.262 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T01:05:52.470Z,1550797552.470 [Rowe_600LCM](INFO): Powering down
2019-02-22T01:05:52.759Z,1550797552.759 [NavChartDb](INFO): # of records loaded: 15000
2019-02-22T01:05:53.870Z,1550797553.870 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-22T01:05:53.871Z,1550797553.871 [Rowe_600LCM] No Fault, FailCount= 1
2019-02-22T01:05:54.085Z,1550797554.085 [Rowe_600LCM](INFO): Initializing
2019-02-22T01:05:54.085Z,1550797554.085 [Rowe_600LCM](INFO): Powering up
2019-02-22T01:05:54.754Z,1550797554.754 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T01:05:58.358Z,1550797558.358 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T01:05:58.426Z,1550797558.426 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-22T01:06:02.610Z,1550797562.610 [NAL9602](INFO): Powering up NAL9602
2019-02-22T01:06:09.451Z,1550797569.451 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-22T01:05:09.1Z
2019-02-22T01:06:09.451Z,1550797569.451 [Startup:StartupSatComms:A] Stopped
2019-02-22T01:06:09.451Z,1550797569.451 [Startup:StartupSatComms:B] Running Loop=1
2019-02-22T01:06:09.862Z,1550797569.862 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-22T01:06:21.863Z,1550797581.863 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005089
2019-02-22T01:06:28.667Z,1550797588.667 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T01:06:28.717Z,1550797588.717 [NavChartDb](INFO): # of records loaded: 19732
2019-02-22T01:06:28.735Z,1550797588.735 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-22T01:06:28.745Z,1550797588.745 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T01:06:29.380Z,1550797589.380 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T01:06:29.380Z,1550797589.380 [NAL9602] Communications Fault, FailCount= 3
2019-02-22T01:06:29.381Z,1550797589.381 [NAL9602](ERROR): Communications Fault
2019-02-22T01:06:29.509Z,1550797589.509 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T01:06:29.532Z,1550797589.532 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190222T004743/Courier0007.lzma
2019-02-22T01:06:29.542Z,1550797589.542 [NAL9602](INFO): Powering down
2019-02-22T01:06:30.326Z,1550797590.326 [DataOverHttps](INFO): Moved sent file to Logs/20190222T004743/Courier0007.lzma.bak
2019-02-22T01:06:30.326Z,1550797590.326 [DataOverHttps](INFO): SBD MOMSN=10068671
2019-02-22T01:06:30.435Z,1550797590.435 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T01:06:30.435Z,1550797590.435 [NAL9602] No Fault, FailCount= 3
2019-02-22T01:06:38.053Z,1550797598.053 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T01:06:50.607Z,1550797610.607 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20190222T010341/Courier0000.lzma
2019-02-22T01:06:51.378Z,1550797611.378 [DataOverHttps](INFO): Moved sent file to Logs/20190222T010341/Courier0000.lzma.bak
2019-02-22T01:06:51.378Z,1550797611.378 [DataOverHttps](INFO): SBD MOMSN=10068673
2019-02-22T01:07:01.375Z,1550797621.375 [NAL9602](INFO): Powering up NAL9602
2019-02-22T01:07:01.489Z,1550797621.489 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T01:07:01.489Z,1550797621.489 [Aanderaa_O2] No Fault, FailCount= 3
2019-02-22T01:07:03.617Z,1550797623.617 [NavChartDb](INFO): # of records loaded: 5000
2019-02-22T01:07:11.921Z,1550797631.921 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T01:07:11.921Z,1550797631.921 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-22T01:07:11.921Z,1550797631.921 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T01:07:11.939Z,1550797631.939 [Startup:StartupSatComms:B](INFO): Timed out from 2019-02-22T01:06:09.5Z
2019-02-22T01:07:11.940Z,1550797631.940 [Startup:StartupSatComms:B] Stopped
2019-02-22T01:07:11.940Z,1550797631.940 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-22T01:07:11.940Z,1550797631.940 [Startup:StartupSatComms] Stopped
2019-02-22T01:07:11.940Z,1550797631.940 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-22T01:07:11.941Z,1550797631.941 [Startup](INFO): Completed Startup
2019-02-22T01:07:11.941Z,1550797631.941 [MissionManager](INFO): Startup is completed.
2019-02-22T01:07:11.941Z,1550797631.941 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-22T01:07:11.941Z,1550797631.941 [Startup] Stopped
2019-02-22T01:07:11.941Z,1550797631.941 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-22T01:07:11.942Z,1550797631.942 [Startup:A.GoToSurface] Stopped
2019-02-22T01:07:11.942Z,1550797631.942 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-22T01:07:12.015Z,1550797632.015 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T01:07:12.015Z,1550797632.015 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T01:07:12.016Z,1550797632.016 [CTD_Seabird] No Fault, FailCount= 3
2019-02-22T01:07:12.069Z,1550797632.069 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T01:07:12.071Z,1550797632.071 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T01:07:12.074Z,1550797632.074 [DataOverHttps](INFO): Sending 535 bytes from file Logs/20190222T004743/Express0005.lzma
2019-02-22T01:07:12.257Z,1550797632.257 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:07:12.276Z,1550797632.276 [MissionManager](IMPORTANT): Started mission Default
2019-02-22T01:07:12.276Z,1550797632.276 [Default] Running Loop=1
2019-02-22T01:07:12.281Z,1550797632.281 [Default](DEBUG): Aggregate::initialize Default
2019-02-22T01:07:12.281Z,1550797632.281 [Default:B.GoToSurface] Running Loop=1
2019-02-22T01:07:12.281Z,1550797632.281 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-22T01:07:12.281Z,1550797632.281 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-22T01:07:12.281Z,1550797632.281 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-22T01:07:12.282Z,1550797632.282 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-22T01:07:12.282Z,1550797632.282 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-22T01:07:12.282Z,1550797632.282 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-22T01:07:12.283Z,1550797632.283 [Default:A.Wait] Running Loop=1
2019-02-22T01:07:12.283Z,1550797632.283 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-22T01:07:12.830Z,1550797632.830 [DataOverHttps](INFO): Moved sent file to Logs/20190222T004743/Express0005.lzma.bak
2019-02-22T01:07:12.830Z,1550797632.830 [DataOverHttps](INFO): SBD MOMSN=10068681
2019-02-22T01:07:12.914Z,1550797632.914 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T01:07:12.914Z,1550797632.914 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-22T01:07:26.125Z,1550797646.125 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T01:07:26.125Z,1550797646.125 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-22T01:07:26.125Z,1550797646.125 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T01:07:26.311Z,1550797646.311 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T01:07:26.311Z,1550797646.311 [NAL9602] Communications Fault, FailCount= 4
2019-02-22T01:07:26.311Z,1550797646.311 [NAL9602](ERROR): Communications Fault
2019-02-22T01:07:26.335Z,1550797646.335 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T01:07:26.335Z,1550797646.335 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-22T01:07:26.335Z,1550797646.335 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T01:07:26.352Z,1550797646.352 [Default:A.Wait](INFO): Done Waiting.
2019-02-22T01:07:26.352Z,1550797646.352 [Default:A.Wait] Stopped
2019-02-22T01:07:26.352Z,1550797646.352 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-22T01:07:26.401Z,1550797646.401 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T01:07:26.402Z,1550797646.402 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T01:07:26.402Z,1550797646.402 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T01:07:26.431Z,1550797646.431 [NAL9602](INFO): Powering down
2019-02-22T01:07:26.529Z,1550797646.529 [CTD_Seabird](INFO): Powering down
2019-02-22T01:07:26.585Z,1550797646.585 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:07:26.596Z,1550797646.596 [Default:CheckIn] Running Loop=1
2019-02-22T01:07:26.596Z,1550797646.596 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-22T01:07:26.597Z,1550797646.597 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-22T01:07:26.856Z,1550797646.856 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-22T01:07:27.341Z,1550797647.341 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T01:07:27.341Z,1550797647.341 [NAL9602] No Fault, FailCount= 4
2019-02-22T01:07:27.342Z,1550797647.342 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T01:07:27.342Z,1550797647.342 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-22T01:07:28.857Z,1550797648.857 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T01:07:28.857Z,1550797648.857 [CTD_Seabird] No Fault, FailCount= 1
2019-02-22T01:07:29.477Z,1550797649.477 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T01:07:29.486Z,1550797649.486 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T01:07:32.481Z,1550797652.481 [NavChartDb](INFO): # of records loaded: 10000
2019-02-22T01:07:38.028Z,1550797658.028 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T01:07:38.028Z,1550797658.028 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-22T01:07:38.028Z,1550797658.028 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T01:07:38.058Z,1550797658.058 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T01:07:38.059Z,1550797658.059 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-22T01:07:38.549Z,1550797658.549 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:07:43.537Z,1550797663.537 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T01:07:43.537Z,1550797663.537 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-22T01:07:43.537Z,1550797663.537 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T01:07:43.662Z,1550797663.662 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T01:07:43.941Z,1550797663.941 [CTD_Seabird](INFO): Powering down
2019-02-22T01:07:46.072Z,1550797666.072 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T01:07:46.072Z,1550797666.072 [CTD_Seabird] No Fault, FailCount= 2
2019-02-22T01:07:46.889Z,1550797666.889 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T01:07:46.897Z,1550797666.897 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T01:07:52.449Z,1550797672.449 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:07:52.449Z,1550797672.449 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-02-22T01:07:52.449Z,1550797672.449 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T01:07:52.451Z,1550797672.451 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:07:52.451Z,1550797672.451 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-02-22T01:07:52.451Z,1550797672.451 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T01:07:52.452Z,1550797672.452 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:07:52.452Z,1550797672.452 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-22T01:07:52.452Z,1550797672.452 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T01:07:52.534Z,1550797672.534 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T01:07:52.534Z,1550797672.534 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T01:07:52.534Z,1550797672.534 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T01:07:52.929Z,1550797672.929 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T01:07:52.929Z,1550797672.929 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-02-22T01:07:52.929Z,1550797672.929 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T01:07:52.929Z,1550797672.929 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-02-22T01:07:52.930Z,1550797672.930 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T01:07:52.930Z,1550797672.930 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-22T01:07:53.261Z,1550797673.261 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T01:07:54.805Z,1550797674.805 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-22T01:07:54.805Z,1550797674.805 [Rowe_600LCM] Communications Fault, FailCount= 2
2019-02-22T01:07:54.805Z,1550797674.805 [Rowe_600LCM](ERROR): Communications Fault
2019-02-22T01:07:54.883Z,1550797674.883 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-22T01:07:55.209Z,1550797675.209 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T01:07:55.441Z,1550797675.441 [Rowe_600LCM](INFO): Powering down
2019-02-22T01:07:56.814Z,1550797676.814 [NAL9602](INFO): Powering up NAL9602
2019-02-22T01:07:56.851Z,1550797676.851 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-22T01:07:56.851Z,1550797676.851 [Rowe_600LCM] No Fault, FailCount= 2
2019-02-22T01:07:57.057Z,1550797677.057 [Rowe_600LCM](INFO): Initializing
2019-02-22T01:07:57.058Z,1550797677.058 [Rowe_600LCM](INFO): Powering up
2019-02-22T01:07:58.221Z,1550797678.221 [NavChartDb](INFO): # of records loaded: 15000
2019-02-22T01:08:00.937Z,1550797680.937 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T01:08:00.937Z,1550797680.937 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-22T01:08:00.937Z,1550797680.937 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T01:08:01.294Z,1550797681.294 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T01:08:01.294Z,1550797681.294 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-22T01:08:01.341Z,1550797681.341 [CTD_Seabird](INFO): Powering down
2019-02-22T01:08:01.461Z,1550797681.461 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T01:08:01.528Z,1550797681.528 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-22T01:08:11.442Z,1550797691.442 [NavChartDb](INFO): # of records loaded: 20000
2019-02-22T01:08:19.487Z,1550797699.487 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T01:08:19.487Z,1550797699.487 [NAL9602] Communications Fault, FailCount= 5
2019-02-22T01:08:19.487Z,1550797699.487 [NAL9602](ERROR): Communications Fault
2019-02-22T01:08:19.589Z,1550797699.589 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T01:08:19.589Z,1550797699.589 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-02-22T01:08:19.606Z,1550797699.606 [NAL9602](INFO): Powering down
2019-02-22T01:08:19.710Z,1550797699.710 [CBIT](INFO): Critical error at 20190222T010819
2019-02-22T01:08:29.316Z,1550797709.316 [NavChartDb](INFO): # of records loaded: 25000
2019-02-22T01:08:44.263Z,1550797724.263 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T01:08:52.333Z,1550797732.333 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T01:08:52.773Z,1550797732.773 [NavChartDb](INFO): # of records loaded: 30000
2019-02-22T01:09:21.552Z,1550797761.552 [NavChartDb](INFO): # of records loaded: 35000
2019-02-22T01:09:38.222Z,1550797778.222 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:09:38.222Z,1550797778.222 [PNI_TCM] No Fault, FailCount= 5
2019-02-22T01:09:39.930Z,1550797779.930 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T01:09:39.992Z,1550797779.992 [NavChartDb](INFO): # of records loaded: 37376
2019-02-22T01:09:40.008Z,1550797780.008 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-22T01:09:40.029Z,1550797780.029 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T01:09:45.762Z,1550797785.762 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:09:45.763Z,1550797785.763 [PNI_TCM] Data Fault, FailCount= 1
2019-02-22T01:09:45.763Z,1550797785.763 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:09:45.857Z,1550797785.857 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:09:46.153Z,1550797786.153 [PNI_TCM](INFO): Powering down
2019-02-22T01:09:47.404Z,1550797787.404 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:09:47.404Z,1550797787.404 [PNI_TCM] No Fault, FailCount= 1
2019-02-22T01:09:47.560Z,1550797787.560 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T01:09:53.361Z,1550797793.361 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:09:53.361Z,1550797793.361 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-02-22T01:09:53.361Z,1550797793.361 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T01:09:53.362Z,1550797793.362 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:09:53.363Z,1550797793.363 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-02-22T01:09:53.363Z,1550797793.363 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T01:09:53.364Z,1550797793.364 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:09:53.364Z,1550797793.364 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-02-22T01:09:53.364Z,1550797793.364 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T01:09:53.388Z,1550797793.388 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T01:09:53.388Z,1550797793.388 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T01:09:53.388Z,1550797793.388 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T01:09:53.796Z,1550797793.796 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T01:09:53.796Z,1550797793.796 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-02-22T01:09:53.805Z,1550797793.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T01:09:53.805Z,1550797793.805 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-02-22T01:09:53.806Z,1550797793.806 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T01:09:53.806Z,1550797793.806 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-02-22T01:09:54.157Z,1550797794.157 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T01:09:54.949Z,1550797794.949 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:09:54.949Z,1550797794.949 [PNI_TCM] Data Fault, FailCount= 2
2019-02-22T01:09:54.950Z,1550797794.950 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:09:55.023Z,1550797795.023 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:09:55.352Z,1550797795.352 [PNI_TCM](INFO): Powering down
2019-02-22T01:09:56.613Z,1550797796.613 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:09:56.613Z,1550797796.613 [PNI_TCM] No Fault, FailCount= 2
2019-02-22T01:09:57.789Z,1550797797.789 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-22T01:09:57.789Z,1550797797.789 [Rowe_600LCM] Communications Fault, FailCount= 3
2019-02-22T01:09:57.789Z,1550797797.789 [Rowe_600LCM](ERROR): Communications Fault
2019-02-22T01:09:57.861Z,1550797797.861 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-22T01:09:58.193Z,1550797798.193 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T01:09:58.426Z,1550797798.426 [Rowe_600LCM](INFO): Powering down
2019-02-22T01:09:59.772Z,1550797799.772 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-22T01:09:59.772Z,1550797799.772 [Rowe_600LCM] No Fault, FailCount= 3
2019-02-22T01:10:00.041Z,1550797800.041 [Rowe_600LCM](INFO): Initializing
2019-02-22T01:10:00.041Z,1550797800.041 [Rowe_600LCM](INFO): Powering up
2019-02-22T01:10:04.158Z,1550797804.158 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:10:04.158Z,1550797804.158 [PNI_TCM] Data Fault, FailCount= 3
2019-02-22T01:10:04.158Z,1550797804.158 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:10:04.218Z,1550797804.218 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:10:04.317Z,1550797804.317 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T01:10:04.404Z,1550797804.404 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-22T01:10:04.565Z,1550797804.565 [PNI_TCM](INFO): Powering down
2019-02-22T01:10:05.779Z,1550797805.779 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:10:05.779Z,1550797805.779 [PNI_TCM] No Fault, FailCount= 3
2019-02-22T01:10:06.867Z,1550797806.867 [NavChartDb](INFO): # of records loaded: 5000
2019-02-22T01:10:08.228Z,1550797808.228 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T01:10:08.228Z,1550797808.228 [Aanderaa_O2] No Fault, FailCount= 3
2019-02-22T01:10:13.348Z,1550797813.348 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:10:13.348Z,1550797813.348 [PNI_TCM] Data Fault, FailCount= 4
2019-02-22T01:10:13.348Z,1550797813.348 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:10:13.399Z,1550797813.399 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:10:13.749Z,1550797813.749 [PNI_TCM](INFO): Powering down
2019-02-22T01:10:17.170Z,1550797817.170 [NavChartDb](INFO): # of records loaded: 10000
2019-02-22T01:10:20.202Z,1550797820.202 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T01:10:20.202Z,1550797820.202 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-22T01:10:20.202Z,1550797820.202 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T01:10:20.250Z,1550797820.250 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T01:10:20.251Z,1550797820.251 [NAL9602] No Fault, FailCount= 5
2019-02-22T01:10:20.251Z,1550797820.251 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:10:20.251Z,1550797820.251 [PNI_TCM] No Fault, FailCount= 4
2019-02-22T01:10:20.251Z,1550797820.251 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T01:10:20.320Z,1550797820.320 [NAL9602](INFO): Powering up NAL9602
2019-02-22T01:10:20.490Z,1550797820.490 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:10:21.147Z,1550797821.147 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T01:10:21.147Z,1550797821.147 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-22T01:10:26.637Z,1550797826.637 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T01:10:27.496Z,1550797827.496 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:10:27.496Z,1550797827.496 [PNI_TCM] Data Fault, FailCount= 5
2019-02-22T01:10:27.496Z,1550797827.496 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:10:27.534Z,1550797827.534 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:10:27.534Z,1550797827.534 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-02-22T01:10:27.897Z,1550797827.897 [PNI_TCM](INFO): Powering down
2019-02-22T01:10:27.968Z,1550797827.968 [CBIT](INFO): Critical error at 20190222T011027
2019-02-22T01:10:28.259Z,1550797828.259 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T01:10:29.844Z,1550797829.844 [NavChartDb](INFO): # of records loaded: 15000
2019-02-22T01:10:31.164Z,1550797831.164 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T01:10:31.164Z,1550797831.164 [CTD_Seabird] No Fault, FailCount= 3
2019-02-22T01:10:31.353Z,1550797831.353 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T01:10:31.355Z,1550797831.355 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T01:10:31.518Z,1550797831.518 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T01:10:31.518Z,1550797831.518 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-22T01:10:31.518Z,1550797831.518 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T01:10:31.603Z,1550797831.603 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T01:10:32.025Z,1550797832.025 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:10:32.798Z,1550797832.798 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T01:10:32.798Z,1550797832.798 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-22T01:10:41.482Z,1550797841.482 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T01:10:41.482Z,1550797841.482 [NAL9602] Communications Fault, FailCount= 1
2019-02-22T01:10:41.482Z,1550797841.482 [NAL9602](ERROR): Communications Fault
2019-02-22T01:10:41.518Z,1550797841.518 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T01:10:41.882Z,1550797841.882 [NAL9602](INFO): Powering down
2019-02-22T01:10:42.794Z,1550797842.794 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T01:10:42.794Z,1550797842.794 [NAL9602] No Fault, FailCount= 1
2019-02-22T01:10:43.498Z,1550797843.498 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T01:10:43.498Z,1550797843.498 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-22T01:10:43.499Z,1550797843.499 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T01:10:43.537Z,1550797843.537 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T01:10:43.538Z,1550797843.538 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-22T01:10:44.033Z,1550797844.033 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:10:45.413Z,1550797845.413 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T01:10:45.413Z,1550797845.413 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-22T01:10:45.413Z,1550797845.413 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T01:10:45.520Z,1550797845.520 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T01:10:45.817Z,1550797845.817 [CTD_Seabird](INFO): Powering down
2019-02-22T01:10:47.918Z,1550797847.918 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T01:10:47.918Z,1550797847.918 [CTD_Seabird] No Fault, FailCount= 1
2019-02-22T01:10:48.327Z,1550797848.327 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T01:10:48.765Z,1550797848.765 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T01:10:48.777Z,1550797848.777 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T01:10:48.779Z,1550797848.779 [NavChartDb](INFO): # of records loaded: 16998
2019-02-22T01:10:48.827Z,1550797848.827 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-22T01:10:48.828Z,1550797848.828 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-22T01:10:56.873Z,1550797856.873 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-22T01:11:02.821Z,1550797862.821 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T01:11:02.821Z,1550797862.821 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-22T01:11:02.821Z,1550797862.821 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T01:11:03.151Z,1550797863.151 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T01:11:03.225Z,1550797863.225 [CTD_Seabird](INFO): Powering down
2019-02-22T01:11:05.528Z,1550797865.528 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T01:11:05.528Z,1550797865.528 [CTD_Seabird] No Fault, FailCount= 2
2019-02-22T01:11:06.173Z,1550797866.173 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T01:11:06.181Z,1550797866.181 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T01:11:11.886Z,1550797871.886 [NAL9602](INFO): Powering up NAL9602
2019-02-22T01:11:20.229Z,1550797880.229 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T01:11:20.229Z,1550797880.229 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-22T01:11:20.229Z,1550797880.229 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T01:11:20.633Z,1550797880.633 [CTD_Seabird](INFO): Powering down
2019-02-22T01:11:25.122Z,1550797885.122 [NavChartDb](INFO): # of records loaded: 5000
2019-02-22T01:11:25.195Z,1550797885.195 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T01:11:25.195Z,1550797885.195 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-22T01:11:35.618Z,1550797895.618 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T01:11:35.618Z,1550797895.618 [NAL9602] Communications Fault, FailCount= 2
2019-02-22T01:11:35.618Z,1550797895.618 [NAL9602](ERROR): Communications Fault
2019-02-22T01:11:35.709Z,1550797895.709 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T01:11:36.021Z,1550797896.021 [NAL9602](INFO): Powering down
2019-02-22T01:11:36.879Z,1550797896.879 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T01:11:36.879Z,1550797896.879 [NAL9602] No Fault, FailCount= 2
2019-02-22T01:11:40.886Z,1550797900.886 [NavChartDb](INFO): # of records loaded: 10000
2019-02-22T01:11:54.445Z,1550797914.445 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:11:54.445Z,1550797914.445 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-02-22T01:11:54.445Z,1550797914.445 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T01:11:54.447Z,1550797914.447 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:11:54.447Z,1550797914.447 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-02-22T01:11:54.448Z,1550797914.448 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T01:11:54.494Z,1550797914.494 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:11:54.494Z,1550797914.494 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-02-22T01:11:54.494Z,1550797914.494 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T01:11:54.553Z,1550797914.553 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T01:11:54.553Z,1550797914.553 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T01:11:54.554Z,1550797914.554 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T01:11:54.852Z,1550797914.852 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T01:11:54.852Z,1550797914.852 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-02-22T01:11:54.852Z,1550797914.852 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T01:11:54.853Z,1550797914.853 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-02-22T01:11:54.853Z,1550797914.853 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T01:11:54.853Z,1550797914.853 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-02-22T01:11:55.244Z,1550797915.244 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T01:11:56.219Z,1550797916.219 [NavChartDb](INFO): # of records loaded: 15000
2019-02-22T01:11:56.577Z,1550797916.577 [CommandLine](IMPORTANT): got command quit
2019-02-22T01:11:57.584Z,1550797917.584 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T01:11:57.584Z,1550797917.584 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:57.761Z,1550797917.761 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-22T01:11:57.761Z,1550797917.761 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:57.762Z,1550797917.762 [CommandLine](INFO): Join timeout helper Thread ID is 966
2019-02-22T01:11:57.777Z,1550797917.777 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-22T01:11:57.777Z,1550797917.777 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:57.777Z,1550797917.777 [NavChartDb](INFO): Join timeout helper Thread ID is 967
2019-02-22T01:11:58.241Z,1550797918.241 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T01:11:58.241Z,1550797918.241 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:58.261Z,1550797918.261 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-02-22T01:11:58.261Z,1550797918.261 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:58.261Z,1550797918.261 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 968
2019-02-22T01:11:58.397Z,1550797918.397 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T01:11:58.397Z,1550797918.397 [WetLabsBB2FL](INFO): Powering down
2019-02-22T01:11:58.398Z,1550797918.398 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:58.413Z,1550797918.413 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-02-22T01:11:58.413Z,1550797918.413 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:58.414Z,1550797918.414 [CTD_Seabird](INFO): Join timeout helper Thread ID is 969
2019-02-22T01:11:58.749Z,1550797918.749 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T01:11:58.749Z,1550797918.749 [CTD_Seabird](INFO): Powering down
2019-02-22T01:11:58.761Z,1550797918.761 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:58.773Z,1550797918.773 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-22T01:11:58.773Z,1550797918.773 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:58.773Z,1550797918.773 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 970
2019-02-22T01:11:58.957Z,1550797918.957 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T01:11:58.957Z,1550797918.957 [CTD_NeilBrown](INFO): Powering down
2019-02-22T01:11:58.969Z,1550797918.969 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:58.978Z,1550797918.978 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-02-22T01:11:58.978Z,1550797918.978 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:58.978Z,1550797918.978 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 971
2019-02-22T01:11:59.669Z,1550797919.669 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T01:11:59.669Z,1550797919.669 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T01:11:59.871Z,1550797919.871 [Rowe_600LCM](INFO): Powering down
2019-02-22T01:11:59.872Z,1550797919.872 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:59.890Z,1550797919.890 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-22T01:11:59.890Z,1550797919.890 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:59.890Z,1550797919.890 [Radio_Surface](INFO): Join timeout helper Thread ID is 973
2019-02-22T01:11:59.921Z,1550797919.921 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T01:11:59.922Z,1550797919.922 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:59.942Z,1550797919.942 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-22T01:11:59.942Z,1550797919.942 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:59.942Z,1550797919.942 [logger](INFO): Join timeout helper Thread ID is 974
2019-02-22T01:11:59.943Z,1550797919.943 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T01:11:59.943Z,1550797919.943 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:59.944Z,1550797919.944 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-22T01:11:59.945Z,1550797919.945 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:59.945Z,1550797919.945 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-22T01:11:59.945Z,1550797919.945 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:11:59.945Z,1550797919.945 [controlThread](INFO): Join timeout helper Thread ID is 975
2019-02-22T01:12:00.009Z,1550797920.009 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T01:12:00.009Z,1550797920.009 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-22T01:12:00.010Z,1550797920.010 [NAL9602](INFO): Powering down
2019-02-22T01:12:00.012Z,1550797920.012 [PNI_TCM](INFO): Powering down
2019-02-22T01:12:00.013Z,1550797920.013 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:12:00.014Z,1550797920.014 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-22T01:12:00.015Z,1550797920.015 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-22T01:12:00.015Z,1550797920.015 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-22T01:12:00.016Z,1550797920.016 [MissionManager](INFO): Uninitializing Mission Default
2019-02-22T01:12:00.016Z,1550797920.016 [Default] Stopped
2019-02-22T01:12:00.016Z,1550797920.016 [Default](DEBUG): Aggregate::uninitialize Default
2019-02-22T01:12:00.016Z,1550797920.016 [Default:B.GoToSurface] Stopped
2019-02-22T01:12:00.016Z,1550797920.016 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-22T01:12:00.016Z,1550797920.016 [Default:CheckIn] Stopped
2019-02-22T01:12:00.016Z,1550797920.016 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-22T01:12:00.016Z,1550797920.016 [Default:CheckIn:Read_GPS] Stopped
2019-02-22T01:12:00.020Z,1550797920.020 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-22T01:12:00.020Z,1550797920.020 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-22T01:12:00.020Z,1550797920.020 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-22T01:12:00.021Z,1550797920.021 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-22T01:12:00.021Z,1550797920.021 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-22T01:12:00.021Z,1550797920.021 [BuoyancyServo](INFO): Powering down
2019-02-22T01:12:00.033Z,1550797920.033 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-22T01:12:00.033Z,1550797920.033 [ElevatorServo](INFO): Powering down
2019-02-22T01:12:00.034Z,1550797920.034 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-22T01:12:00.034Z,1550797920.034 [MassServo](INFO): Powering down
2019-02-22T01:12:00.035Z,1550797920.035 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-22T01:12:00.035Z,1550797920.035 [RudderServo](INFO): Powering down
2019-02-22T01:12:00.036Z,1550797920.036 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-22T01:12:00.036Z,1550797920.036 [ThrusterServo](INFO): Powering down
2019-02-22T01:12:00.037Z,1550797920.037 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-22T01:12:00.038Z,1550797920.038 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-22T01:12:00.038Z,1550797920.038 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-22T01:12:00.038Z,1550797920.038 [CBIT](DEBUG): Powering off loads.
2019-02-22T01:12:00.049Z,1550797920.049 [CBIT](DEBUG): Disabling WDT.
2019-02-22T01:12:00.061Z,1550797920.061 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-22T01:12:00.062Z,1550797920.062 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:12:00.119Z,1550797920.119 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:12:00.125Z,1550797920.125 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:12:00.158Z,1550797920.158 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:12:00.162Z,1550797920.162 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:12:00.166Z,1550797920.166 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:12:00.201Z,1550797920.201 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-22T01:12:00.248Z,1550797920.248 [logger ThreadHandler](INFO): Thread cancelled.