2019-02-06T22:53:37.062Z,1549493617.062 [Supervisor](DEBUG): Initializing supervisor.
2019-02-06T22:53:37.077Z,1549493617.077 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-06T22:53:37.077Z,1549493617.077 [SyncHandler](INFO): Protected caller Thread ID is 799
2019-02-06T22:53:37.078Z,1549493617.078 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-06T22:53:37.084Z,1549493617.084 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-06T22:53:37.084Z,1549493617.084 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2019-02-06T22:53:37.087Z,1549493617.087 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-06T22:53:37.098Z,1549493617.098 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-06T22:53:37.099Z,1549493617.099 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-06T22:53:37.100Z,1549493617.100 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2019-02-06T22:53:37.100Z,1549493617.100 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-06T22:53:37.101Z,1549493617.101 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-06T22:53:37.102Z,1549493617.102 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2019-02-06T22:53:37.104Z,1549493617.104 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-06T22:53:37.104Z,1549493617.104 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-06T22:53:37.109Z,1549493617.109 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-06T22:53:37.465Z,1549493617.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-06T22:53:37.466Z,1549493617.466 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-06T22:53:38.007Z,1549493618.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-06T22:53:38.008Z,1549493618.008 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-06T22:53:38.338Z,1549493618.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-06T22:53:38.339Z,1549493618.339 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-06T22:53:38.533Z,1549493618.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-06T22:53:38.533Z,1549493618.533 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-06T22:53:38.982Z,1549493618.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-06T22:53:38.982Z,1549493618.982 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-06T22:53:39.461Z,1549493619.461 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-06T22:53:39.462Z,1549493619.462 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-06T22:53:39.569Z,1549493619.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-06T22:53:39.570Z,1549493619.570 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-06T22:53:40.068Z,1549493620.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-06T22:53:40.069Z,1549493620.069 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-06T22:53:40.215Z,1549493620.215 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-06T22:53:40.216Z,1549493620.216 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-06T22:53:40.297Z,1549493620.297 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-06T22:53:40.509Z,1549493620.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-06T22:53:40.510Z,1549493620.510 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-06T22:53:40.612Z,1549493620.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-06T22:53:40.613Z,1549493620.613 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-06T22:53:40.710Z,1549493620.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-06T22:53:40.711Z,1549493620.711 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-06T22:53:40.859Z,1549493620.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-06T22:53:40.860Z,1549493620.860 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-06T22:53:41.183Z,1549493621.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-06T22:53:41.185Z,1549493621.185 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-06T22:53:41.188Z,1549493621.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-06T22:53:41.325Z,1549493621.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-06T22:53:41.554Z,1549493621.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-06T22:53:41.555Z,1549493621.555 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-06T22:53:41.723Z,1549493621.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-06T22:53:41.824Z,1549493621.824 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-06T22:53:41.925Z,1549493621.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-06T22:53:42.117Z,1549493622.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-06T22:53:42.203Z,1549493622.203 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-06T22:53:42.301Z,1549493622.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-06T22:53:42.395Z,1549493622.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-06T22:53:42.478Z,1549493622.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-06T22:53:42.588Z,1549493622.588 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-02-06T22:53:42.589Z,1549493622.589 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-02-06T22:53:42.591Z,1549493622.591 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-06T22:53:42.752Z,1549493622.752 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-06T22:53:42.782Z,1549493622.782 [SBIT] Loaded
2019-02-06T22:53:42.783Z,1549493622.783 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-06T22:53:42.783Z,1549493622.783 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-06T22:53:42.811Z,1549493622.811 [IBIT] Loaded
2019-02-06T22:53:42.811Z,1549493622.811 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-06T22:53:42.815Z,1549493622.815 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-06T22:53:43.196Z,1549493623.196 [CBIT] Loaded
2019-02-06T22:53:43.196Z,1549493623.196 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-06T22:53:43.197Z,1549493623.197 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-06T22:53:43.199Z,1549493623.199 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-06T22:53:43.347Z,1549493623.347 [BuoyancyServo] Loaded
2019-02-06T22:53:43.347Z,1549493623.347 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-06T22:53:43.362Z,1549493623.362 [ElevatorServo] Loaded
2019-02-06T22:53:43.362Z,1549493623.362 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-06T22:53:43.376Z,1549493623.376 [MassServo] Loaded
2019-02-06T22:53:43.376Z,1549493623.376 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-06T22:53:43.391Z,1549493623.391 [RudderServo] Loaded
2019-02-06T22:53:43.391Z,1549493623.391 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-06T22:53:43.406Z,1549493623.406 [ThrusterServo] Loaded
2019-02-06T22:53:43.406Z,1549493623.406 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-06T22:53:43.407Z,1549493623.407 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-06T22:53:43.407Z,1549493623.407 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-06T22:53:43.528Z,1549493623.528 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-06T22:53:43.528Z,1549493623.528 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-06T22:53:43.587Z,1549493623.587 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-06T22:53:43.588Z,1549493623.588 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-06T22:53:43.646Z,1549493623.646 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-06T22:53:43.646Z,1549493623.646 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-06T22:53:43.662Z,1549493623.662 [NavChart] Loaded
2019-02-06T22:53:43.662Z,1549493623.662 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-06T22:53:43.666Z,1549493623.666 [UniversalFixResidualReporter] Loaded
2019-02-06T22:53:43.667Z,1549493623.667 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-06T22:53:43.667Z,1549493623.667 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-06T22:53:43.669Z,1549493623.669 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-06T22:53:43.693Z,1549493623.693 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-06T22:53:43.694Z,1549493623.694 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-06T22:53:43.967Z,1549493623.967 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-06T22:53:43.968Z,1549493623.968 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-06T22:53:44.615Z,1549493624.615 [AHRS_M2] Loaded
2019-02-06T22:53:44.615Z,1549493624.615 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-02-06T22:53:44.749Z,1549493624.749 [DataOverHttps] Loaded
2019-02-06T22:53:44.750Z,1549493624.750 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-06T22:53:44.763Z,1549493624.763 [Depth_Keller] Loaded
2019-02-06T22:53:44.763Z,1549493624.763 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-06T22:53:44.768Z,1549493624.768 [DropWeight] Loaded
2019-02-06T22:53:44.768Z,1549493624.768 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-06T22:53:44.856Z,1549493624.856 [NAL9602] Loaded
2019-02-06T22:53:44.856Z,1549493624.856 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-06T22:53:44.861Z,1549493624.861 [Onboard] Loaded
2019-02-06T22:53:44.862Z,1549493624.862 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-06T22:53:44.869Z,1549493624.869 [Radio_Surface] Loaded
2019-02-06T22:53:44.869Z,1549493624.869 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-06T22:53:44.870Z,1549493624.870 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408494E0
2019-02-06T22:53:44.871Z,1549493624.871 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2019-02-06T22:53:44.898Z,1549493624.898 [RDI_Pathfinder] Loaded
2019-02-06T22:53:44.899Z,1549493624.899 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-06T22:53:44.920Z,1549493624.920 [RDI_PathfinderUp] Loaded
2019-02-06T22:53:44.921Z,1549493624.921 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-06T22:53:46.456Z,1549493626.456 [BPC1] Loaded
2019-02-06T22:53:46.456Z,1549493626.456 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-06T22:53:46.456Z,1549493626.456 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-06T22:53:46.457Z,1549493626.457 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-06T22:53:46.707Z,1549493626.707 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-06T22:53:46.906Z,1549493626.906 [VerticalControl] Loaded
2019-02-06T22:53:46.907Z,1549493626.907 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-06T22:53:46.907Z,1549493626.907 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-06T22:53:46.964Z,1549493626.964 [HorizontalControl] Loaded
2019-02-06T22:53:46.965Z,1549493626.965 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-06T22:53:46.965Z,1549493626.965 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-06T22:53:46.968Z,1549493626.968 [SpeedControl] Loaded
2019-02-06T22:53:46.968Z,1549493626.968 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-06T22:53:46.969Z,1549493626.969 [LoopControl](DEBUG): Construct LoopControl.
2019-02-06T22:53:46.969Z,1549493626.969 [LoopControl] Loaded
2019-02-06T22:53:46.970Z,1549493626.970 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-06T22:53:46.970Z,1549493626.970 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-06T22:53:46.971Z,1549493626.971 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-06T22:53:47.290Z,1549493627.290 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-06T22:53:47.290Z,1549493627.290 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-06T22:53:47.523Z,1549493627.523 [Aanderaa_O2] Loaded
2019-02-06T22:53:47.523Z,1549493627.523 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-06T22:53:47.607Z,1549493627.607 [CTD_NeilBrown] Loaded
2019-02-06T22:53:47.607Z,1549493627.607 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-06T22:53:47.608Z,1549493627.608 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095A4E0
2019-02-06T22:53:47.608Z,1549493627.608 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 883
2019-02-06T22:53:47.653Z,1549493627.653 [WetLabsSeaOWL_UV_A] Loaded
2019-02-06T22:53:47.653Z,1549493627.653 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-06T22:53:47.654Z,1549493627.654 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0
2019-02-06T22:53:47.655Z,1549493627.655 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 884
2019-02-06T22:53:47.655Z,1549493627.655 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-06T22:53:47.656Z,1549493627.656 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-06T22:53:47.758Z,1549493627.758 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-06T22:53:47.759Z,1549493627.759 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-06T22:53:47.813Z,1549493627.813 [DepthRateCalculator] Loaded
2019-02-06T22:53:47.813Z,1549493627.813 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-06T22:53:47.819Z,1549493627.819 [PitchRateCalculator] Loaded
2019-02-06T22:53:47.819Z,1549493627.819 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-06T22:53:47.831Z,1549493627.831 [SpeedCalculator] Loaded
2019-02-06T22:53:47.831Z,1549493627.831 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-06T22:53:47.852Z,1549493627.852 [TempGradientCalculator] Loaded
2019-02-06T22:53:47.852Z,1549493627.852 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-06T22:53:47.857Z,1549493627.857 [YawRateCalculator] Loaded
2019-02-06T22:53:47.857Z,1549493627.857 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-06T22:53:47.887Z,1549493627.887 [ElevatorOffsetCalculator] Loaded
2019-02-06T22:53:47.887Z,1549493627.887 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-06T22:53:47.888Z,1549493627.888 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-06T22:53:47.889Z,1549493627.889 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-06T22:53:47.931Z,1549493627.931 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-06T22:53:47.936Z,1549493627.936 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-06T22:53:47.937Z,1549493627.937 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-06T22:53:47.943Z,1549493627.943 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-06T22:53:47.944Z,1549493627.944 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2019-02-06T22:53:47.945Z,1549493627.945 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 885
2019-02-06T22:53:47.949Z,1549493627.949 [Supervisor](INFO): Main Thread ID is 795
2019-02-06T22:53:47.949Z,1549493627.949 [Supervisor](DEBUG): Running supervisor.
2019-02-06T22:53:47.950Z,1549493627.950 [CommandLine ThreadHandler](INFO): Handler Thread ID is 886
2019-02-06T22:53:47.952Z,1549493627.952 [controlThread ThreadHandler](INFO): Handler Thread ID is 887
2019-02-06T22:53:47.953Z,1549493627.953 [controlThread](DEBUG): Initializing ControlThread
2019-02-06T22:53:47.953Z,1549493627.953 [SBIT](INFO): Initialize SBIT Component.
2019-02-06T22:53:47.954Z,1549493627.954 [SBIT](IMPORTANT): git: 2019-01-14A-54-g0d094c9
2019-02-06T22:53:47.954Z,1549493627.954 [SBIT](INFO): git hash: 0d094c9fe207dedbc4f0c5a93b42d412b4ef2ca6
2019-02-06T22:53:47.954Z,1549493627.954 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-06T22:53:47.955Z,1549493627.955 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-06T22:53:47.956Z,1549493627.956 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-06T22:53:47.956Z,1549493627.956 [IBIT](INFO): Initialize IBIT Component.
2019-02-06T22:53:47.957Z,1549493627.957 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-06T22:53:47.958Z,1549493627.958 [logger ThreadHandler](INFO): Handler Thread ID is 888
2019-02-06T22:53:47.969Z,1549493627.969 [CBIT](DEBUG): Initialized mux pins.
2019-02-06T22:53:47.969Z,1549493627.969 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-02-06T22:53:47.969Z,1549493627.969 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-06T22:53:47.977Z,1549493627.977 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 889
2019-02-06T22:53:47.983Z,1549493627.983 [Radio_Surface](INFO): Powering up
2019-02-06T22:53:47.989Z,1549493627.989 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 890
2019-02-06T22:53:47.990Z,1549493627.990 [CTD_NeilBrown](INFO): Powering down
2019-02-06T22:53:47.993Z,1549493627.993 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-02-06T22:53:47.993Z,1549493627.993 [CBIT](DEBUG): Initializing heartbeat.
2019-02-06T22:53:48.021Z,1549493628.021 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 891
2019-02-06T22:53:48.022Z,1549493628.022 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-06T22:53:48.048Z,1549493628.048 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 892
2019-02-06T22:53:48.059Z,1549493628.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-06T22:53:48.060Z,1549493628.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-06T22:53:48.060Z,1549493628.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-06T22:53:48.060Z,1549493628.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-06T22:53:48.060Z,1549493628.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-06T22:53:48.060Z,1549493628.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-06T22:53:48.061Z,1549493628.061 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-06T22:53:48.061Z,1549493628.061 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-06T22:53:48.061Z,1549493628.061 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-06T22:53:48.061Z,1549493628.061 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-06T22:53:48.061Z,1549493628.061 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-06T22:53:48.062Z,1549493628.062 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-06T22:53:48.062Z,1549493628.062 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-06T22:53:48.062Z,1549493628.062 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-06T22:53:48.062Z,1549493628.062 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-06T22:53:48.062Z,1549493628.062 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-06T22:53:48.065Z,1549493628.065 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-06T22:53:48.065Z,1549493628.065 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-06T22:53:48.102Z,1549493628.102 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-06T22:53:48.103Z,1549493628.103 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-06T22:53:48.104Z,1549493628.104 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-06T22:53:48.111Z,1549493628.111 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-06T22:53:48.113Z,1549493628.113 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-06T22:53:48.114Z,1549493628.114 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-06T22:53:48.115Z,1549493628.115 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-06T22:53:48.115Z,1549493628.115 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-06T22:53:48.115Z,1549493628.115 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-06T22:53:48.116Z,1549493628.116 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-06T22:53:48.116Z,1549493628.116 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-06T22:53:48.121Z,1549493628.121 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-06T22:53:48.121Z,1549493628.121 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-06T22:53:48.123Z,1549493628.123 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-06T22:53:48.186Z,1549493628.186 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-06T22:53:48.197Z,1549493628.197 [MissionManager](DEBUG):
2019-02-06T22:53:48.198Z,1549493628.198 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-06T22:53:48.274Z,1549493628.274 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-06T22:53:48.275Z,1549493628.275 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-06T22:53:48.277Z,1549493628.277 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-06T22:53:48.331Z,1549493628.331 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-06T22:53:48.339Z,1549493628.339 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-06T22:53:48.344Z,1549493628.344 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-06T22:53:48.372Z,1549493628.372 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-02-06T22:53:48.385Z,1549493628.385 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,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-06T22:53:48.409Z,1549493628.409 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-02-06T22:53:48.569Z,1549493628.569 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-06T22:53:48.577Z,1549493628.577 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-06T22:53:48.761Z,1549493628.761 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-06T22:53:48.989Z,1549493628.989 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-06T22:53:48.997Z,1549493628.997 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-06T22:53:49.003Z,1549493629.003 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-06T22:53:49.009Z,1549493629.009 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-06T22:53:49.015Z,1549493629.015 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-06T22:53:49.021Z,1549493629.021 [MassServo](DEBUG): Initializing MassServo.
2019-02-06T22:53:49.027Z,1549493629.027 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-06T22:53:49.033Z,1549493629.033 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-06T22:53:49.039Z,1549493629.039 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-06T22:53:49.045Z,1549493629.045 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-06T22:53:49.074Z,1549493629.074 [CommandLine](FAULT): Scheduling is paused
2019-02-06T22:53:49.074Z,1549493629.074 [CBIT](INFO): Critical error at 20190206T225347
2019-02-06T22:53:49.074Z,1549493629.074 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-06T22:53:49.277Z,1549493629.277 [RDI_PathfinderUp](INFO): Powering down
2019-02-06T22:53:49.321Z,1549493629.321 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-06T22:53:49.545Z,1549493629.545 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-06T22:53:49.889Z,1549493629.889 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-02-06T22:53:49.889Z,1549493629.889 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-02-06T22:53:49.889Z,1549493629.889 [BuoyancyServo] Communications Fault, FailCount= 1
2019-02-06T22:53:49.889Z,1549493629.889 [BuoyancyServo](ERROR): Communications Fault
2019-02-06T22:53:50.097Z,1549493630.097 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-02-06T22:53:52.269Z,1549493632.269 [Aanderaa_O2](INFO): Powering down
2019-02-06T22:53:52.402Z,1549493632.402 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-02-06T22:53:52.402Z,1549493632.402 [BuoyancyServo] No Fault, FailCount= 1
2019-02-06T22:53:52.598Z,1549493632.598 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-06T22:53:52.718Z,1549493632.718 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-06T22:54:02.212Z,1549493642.212 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-02-06T22:54:14.982Z,1549493654.982 [NAL9602](INFO): Powering up NAL9602
2019-02-06T22:54:16.667Z,1549493656.667 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-06T22:54:16.671Z,1549493656.671 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-06T22:54:25.902Z,1549493665.902 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:54:26.713Z,1549493666.713 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:54:27.631Z,1549493667.631 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.009724
CHAN A1 (24V): -0.199111
CHAN A2 (12V): -0.006131
CHAN A3 (5V): -0.002428
CHAN B0 (3.3V): -0.000263
CHAN B1 (3.15aV): 0.000263
CHAN B2 (3.15bV): 0.000087
CHAN B3 (GND): 0.001144
OPEN: 0.012236
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-06T22:55:10.362Z,1549493710.362 [SBIT](IMPORTANT): SBIT PASSED
2019-02-06T22:55:10.425Z,1549493710.425 [CommandLine](IMPORTANT): got command configSet list
2019-02-06T22:55:10.425Z,1549493710.425 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-06T22:55:10.428Z,1549493710.428 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool;
2019-02-06T22:55:10.428Z,1549493710.428 [CommandLine](IMPORTANT): NAL9602.requestGGA=1 bool;
2019-02-06T22:55:10.757Z,1549493710.757 [MissionManager](IMPORTANT): Started mission Startup
2019-02-06T22:55:10.757Z,1549493710.757 [Startup] Running Loop=1
2019-02-06T22:55:10.757Z,1549493710.757 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-06T22:55:10.757Z,1549493710.757 [Startup:A.GoToSurface] Running Loop=1
2019-02-06T22:55:10.757Z,1549493710.757 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-06T22:55:10.758Z,1549493710.758 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-06T22:55:10.758Z,1549493710.758 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-06T22:55:10.759Z,1549493710.759 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-06T22:55:10.759Z,1549493710.759 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-06T22:55:10.759Z,1549493710.759 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-06T22:55:10.773Z,1549493710.773 [Startup:StartupSatComms] Running Loop=1
2019-02-06T22:55:10.774Z,1549493710.774 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-06T22:55:10.774Z,1549493710.774 [Startup:StartupSatComms:A] Running Loop=1
2019-02-06T22:55:11.149Z,1549493711.149 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-06T22:56:11.137Z,1549493771.137 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-06T22:55:10.8Z
2019-02-06T22:56:11.137Z,1549493771.137 [Startup:StartupSatComms:A] Stopped
2019-02-06T22:56:11.137Z,1549493771.137 [Startup:StartupSatComms:B] Running Loop=1
2019-02-06T22:56:11.563Z,1549493771.563 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-06T22:56:19.037Z,1549493779.037 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009207
2019-02-06T22:56:24.568Z,1549493784.568 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T203829/Courier0043.lzma
2019-02-06T22:56:25.342Z,1549493785.342 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0043.lzma.bak
2019-02-06T22:56:25.342Z,1549493785.342 [DataOverHttps](INFO): SBD MOMSN=9637870
2019-02-06T22:56:35.068Z,1549493795.068 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20190206T225336/Courier0000.lzma
2019-02-06T22:56:35.857Z,1549493795.857 [DataOverHttps](INFO): Moved sent file to Logs/20190206T225336/Courier0000.lzma.bak
2019-02-06T22:56:35.857Z,1549493795.857 [DataOverHttps](INFO): SBD MOMSN=9637872
2019-02-06T22:56:46.596Z,1549493806.596 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20190206T203829/Express0044.lzma
2019-02-06T22:56:47.366Z,1549493807.366 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0044.lzma.bak
2019-02-06T22:56:47.366Z,1549493807.366 [DataOverHttps](INFO): SBD MOMSN=9637876
2019-02-06T22:56:49.811Z,1549493809.811 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-06T22:56:49.811Z,1549493809.811 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-06T22:56:49.812Z,1549493809.812 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-06T22:56:49.812Z,1549493809.812 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-06T22:56:49.814Z,1549493809.814 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-06T22:56:49.814Z,1549493809.814 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:56:49.855Z,1549493809.855 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-06T22:56:49.855Z,1549493809.855 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-06T22:56:49.855Z,1549493809.855 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:56:49.973Z,1549493809.973 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-06T22:56:49.973Z,1549493809.973 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-06T22:56:49.974Z,1549493809.974 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-06T22:56:49.974Z,1549493809.974 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-06T22:56:49.974Z,1549493809.974 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:56:49.974Z,1549493809.974 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-06T22:56:53.227Z,1549493813.227 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-06T22:56:58.221Z,1549493818.221 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190206T224827/Express0001.lzma
2019-02-06T22:56:59.013Z,1549493819.013 [DataOverHttps](INFO): Moved sent file to Logs/20190206T224827/Express0001.lzma.bak
2019-02-06T22:56:59.014Z,1549493819.014 [DataOverHttps](INFO): SBD MOMSN=9637880
2019-02-06T22:57:09.856Z,1549493829.856 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190206T225109/Express0001.lzma
2019-02-06T22:57:10.618Z,1549493830.618 [DataOverHttps](INFO): Moved sent file to Logs/20190206T225109/Express0001.lzma.bak
2019-02-06T22:57:10.618Z,1549493830.618 [DataOverHttps](INFO): SBD MOMSN=9637883
2019-02-06T22:57:13.014Z,1549493833.014 [Startup:StartupSatComms:B](INFO): Timed out from 2019-02-06T22:56:11.1Z
2019-02-06T22:57:13.014Z,1549493833.014 [Startup:StartupSatComms:B] Stopped
2019-02-06T22:57:13.014Z,1549493833.014 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-06T22:57:13.014Z,1549493833.014 [Startup:StartupSatComms] Stopped
2019-02-06T22:57:13.014Z,1549493833.014 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-06T22:57:13.015Z,1549493833.015 [Startup](INFO): Completed Startup
2019-02-06T22:57:13.015Z,1549493833.015 [MissionManager](INFO): Startup is completed.
2019-02-06T22:57:13.015Z,1549493833.015 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-06T22:57:13.015Z,1549493833.015 [Startup] Stopped
2019-02-06T22:57:13.015Z,1549493833.015 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-06T22:57:13.015Z,1549493833.015 [Startup:A.GoToSurface] Stopped
2019-02-06T22:57:13.016Z,1549493833.016 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-06T22:57:13.140Z,1549493833.140 [MissionManager](IMPORTANT): Started mission Default
2019-02-06T22:57:13.140Z,1549493833.140 [Default] Running Loop=1
2019-02-06T22:57:13.140Z,1549493833.140 [Default](DEBUG): Aggregate::initialize Default
2019-02-06T22:57:13.140Z,1549493833.140 [Default:B.GoToSurface] Running Loop=1
2019-02-06T22:57:13.140Z,1549493833.140 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-06T22:57:13.145Z,1549493833.145 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-06T22:57:13.145Z,1549493833.145 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-06T22:57:13.145Z,1549493833.145 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-06T22:57:13.146Z,1549493833.146 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-06T22:57:13.146Z,1549493833.146 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-06T22:57:13.146Z,1549493833.146 [Default:A.Wait] Running Loop=1
2019-02-06T22:57:13.146Z,1549493833.146 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-06T22:57:18.804Z,1549493838.804 [NAL9602](INFO): SBD MO Status=2, MOMSN=2085, MT Status=2, MTMSN=0
2019-02-06T22:57:18.805Z,1549493838.805 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T22:57:26.429Z,1549493846.429 [Default:A.Wait](INFO): Done Waiting.
2019-02-06T22:57:26.430Z,1549493846.430 [Default:A.Wait] Stopped
2019-02-06T22:57:26.430Z,1549493846.430 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T22:57:26.833Z,1549493846.833 [Default:CheckIn] Running Loop=1
2019-02-06T22:57:26.833Z,1549493846.833 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T22:57:26.833Z,1549493846.833 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T22:57:27.252Z,1549493847.252 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-06T22:57:51.758Z,1549493871.758 [DataOverHttps](INFO): Sending 765 bytes from file Logs/20190206T225336/Express0001.lzma
2019-02-06T22:57:52.514Z,1549493872.514 [DataOverHttps](INFO): Moved sent file to Logs/20190206T225336/Express0001.lzma.bak
2019-02-06T22:57:52.514Z,1549493872.514 [DataOverHttps](INFO): SBD MOMSN=9637886
2019-02-06T22:59:29.155Z,1549493969.155 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-06T22:59:29.977Z,1549493969.977 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:59:38.081Z,1549493978.081 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:59:40.481Z,1549493980.481 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:59:43.277Z,1549493983.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:59:46.481Z,1549493986.481 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:59:49.277Z,1549493989.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:59:52.477Z,1549493992.477 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:59:53.310Z,1549493993.310 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-02-06T22:59:53.310Z,1549493993.310 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-06T22:59:53.311Z,1549493993.311 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-02-06T22:59:53.311Z,1549493993.311 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-06T22:59:53.317Z,1549493993.317 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-06T22:59:53.317Z,1549493993.317 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:59:53.342Z,1549493993.342 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-06T22:59:53.343Z,1549493993.343 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-06T22:59:53.343Z,1549493993.343 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:59:53.720Z,1549493993.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-06T22:59:53.720Z,1549493993.720 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-02-06T22:59:53.720Z,1549493993.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-06T22:59:53.720Z,1549493993.720 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-02-06T22:59:53.721Z,1549493993.721 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:59:53.721Z,1549493993.721 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-06T22:59:54.118Z,1549493994.118 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-06T22:59:55.277Z,1549493995.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:59:58.481Z,1549493998.481 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:01.277Z,1549494001.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:04.485Z,1549494004.485 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:07.277Z,1549494007.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:13.113Z,1549494013.113 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:15.478Z,1549494015.478 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:18.277Z,1549494018.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:21.473Z,1549494021.473 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:24.273Z,1549494024.273 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:27.477Z,1549494027.477 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:30.277Z,1549494030.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:33.473Z,1549494033.473 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:36.277Z,1549494036.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:39.477Z,1549494039.477 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:42.277Z,1549494042.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:48.277Z,1549494048.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:50.465Z,1549494050.465 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:53.267Z,1549494053.267 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:56.461Z,1549494056.461 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:00:59.281Z,1549494059.281 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:01.316Z,1549494061.316 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-02-06T23:01:02.461Z,1549494062.461 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:05.261Z,1549494065.261 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:08.461Z,1549494068.461 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:11.261Z,1549494071.261 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:14.465Z,1549494074.465 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:17.269Z,1549494077.269 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:25.093Z,1549494085.093 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:27.381Z,1549494087.381 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:30.173Z,1549494090.173 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:33.375Z,1549494093.375 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:36.177Z,1549494096.177 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:39.381Z,1549494099.381 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:42.173Z,1549494102.173 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:45.373Z,1549494105.373 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:48.177Z,1549494108.177 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:51.377Z,1549494111.377 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:54.181Z,1549494114.181 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:01:59.077Z,1549494119.077 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:01.485Z,1549494121.485 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:04.277Z,1549494124.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:07.481Z,1549494127.481 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:08.302Z,1549494128.302 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,D,0e8c,0ffe, 15.719, -0.040, 0.000
2019-02-06T23:02:10.287Z,1549494130.287 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:13.477Z,1549494133.477 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:16.281Z,1549494136.281 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:19.485Z,1549494139.485 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:22.277Z,1549494142.277 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:25.485Z,1549494145.485 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:27.113Z,1549494147.113 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T22:57:26.8Z
2019-02-06T23:02:27.113Z,1549494147.113 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T23:02:27.113Z,1549494147.113 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T23:02:27.517Z,1549494147.517 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-06T23:02:28.957Z,1549494148.957 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:32.424Z,1549494152.424 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20190206T225336/Courier0004.lzma
2019-02-06T23:02:33.185Z,1549494153.185 [DataOverHttps](INFO): Moved sent file to Logs/20190206T225336/Courier0004.lzma.bak
2019-02-06T23:02:33.186Z,1549494153.186 [DataOverHttps](INFO): SBD MOMSN=9637904
2019-02-06T23:02:38.777Z,1549494158.777 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:43.769Z,1549494163.769 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20190206T225336/Express0005.lzma
2019-02-06T23:02:43.797Z,1549494163.797 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:44.525Z,1549494164.525 [DataOverHttps](INFO): Moved sent file to Logs/20190206T225336/Express0005.lzma.bak
2019-02-06T23:02:44.525Z,1549494164.525 [DataOverHttps](INFO): SBD MOMSN=9637906
2019-02-06T23:02:46.905Z,1549494166.905 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T23:02:46.905Z,1549494166.905 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T23:02:46.905Z,1549494166.905 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T23:02:50.129Z,1549494170.129 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:51.481Z,1549494171.481 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-02-06T23:02:53.473Z,1549494173.473 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:54.311Z,1549494174.311 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-02-06T23:02:54.311Z,1549494174.311 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-06T23:02:54.312Z,1549494174.312 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-02-06T23:02:54.312Z,1549494174.312 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-06T23:02:54.353Z,1549494174.353 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-02-06T23:02:54.353Z,1549494174.353 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T23:02:54.398Z,1549494174.398 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-06T23:02:54.398Z,1549494174.398 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-06T23:02:54.399Z,1549494174.399 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T23:02:54.751Z,1549494174.751 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-06T23:02:54.751Z,1549494174.751 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-02-06T23:02:54.752Z,1549494174.752 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-06T23:02:54.752Z,1549494174.752 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-02-06T23:02:54.752Z,1549494174.752 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T23:02:54.752Z,1549494174.752 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-02-06T23:02:55.141Z,1549494175.141 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-06T23:02:56.265Z,1549494176.265 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:02:59.471Z,1549494179.471 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:02.265Z,1549494182.265 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:05.473Z,1549494185.473 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:08.269Z,1549494188.269 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:11.473Z,1549494191.473 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:14.269Z,1549494194.269 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:17.473Z,1549494197.473 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:20.269Z,1549494200.269 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:25.945Z,1549494205.945 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:28.345Z,1549494208.345 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:31.143Z,1549494211.143 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:34.341Z,1549494214.341 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:37.141Z,1549494217.141 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:40.345Z,1549494220.345 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:43.149Z,1549494223.149 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:46.341Z,1549494226.341 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:49.145Z,1549494229.145 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:52.345Z,1549494232.345 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:03:55.149Z,1549494235.149 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:00.849Z,1549494240.849 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:03.201Z,1549494243.201 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:05.597Z,1549494245.597 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:08.401Z,1549494248.401 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:11.608Z,1549494251.608 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:14.401Z,1549494254.401 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:17.603Z,1549494257.603 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:20.397Z,1549494260.397 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:23.605Z,1549494263.605 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:26.401Z,1549494266.401 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:04:26.786Z,1549494266.786 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-06T23:04:26.786Z,1549494266.786 [NAL9602] Data Fault, FailCount= 1
2019-02-06T23:04:26.787Z,1549494266.787 [NAL9602](ERROR): Data Fault
2019-02-06T23:04:26.873Z,1549494266.873 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T23:04:27.186Z,1549494267.186 [NAL9602](INFO): Powering down
2019-02-06T23:04:28.057Z,1549494268.057 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T23:04:28.057Z,1549494268.057 [NAL9602] No Fault, FailCount= 1
2019-02-06T23:04:57.287Z,1549494297.287 [NAL9602](INFO): Powering up NAL9602
2019-02-06T23:05:11.282Z,1549494311.282 [NAL9602](INFO): NAL9602 initialized
2019-02-06T23:05:13.822Z,1549494313.822 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:17.013Z,1549494317.013 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:19.809Z,1549494319.809 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:23.009Z,1549494323.009 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:25.817Z,1549494325.817 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:29.013Z,1549494329.013 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:31.805Z,1549494331.805 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:35.017Z,1549494335.017 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:37.809Z,1549494337.809 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:41.005Z,1549494341.005 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:51.405Z,1549494351.405 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:54.097Z,1549494354.097 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:55.326Z,1549494355.326 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-02-06T23:05:55.326Z,1549494355.326 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-06T23:05:55.327Z,1549494355.327 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-02-06T23:05:55.327Z,1549494355.327 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-06T23:05:55.328Z,1549494355.328 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-02-06T23:05:55.328Z,1549494355.328 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T23:05:55.383Z,1549494355.383 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-06T23:05:55.384Z,1549494355.384 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-06T23:05:55.384Z,1549494355.384 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T23:05:55.774Z,1549494355.774 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-06T23:05:55.774Z,1549494355.774 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-02-06T23:05:55.774Z,1549494355.774 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-06T23:05:55.775Z,1549494355.775 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-02-06T23:05:55.775Z,1549494355.775 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T23:05:55.775Z,1549494355.775 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-02-06T23:05:56.154Z,1549494356.154 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-06T23:05:56.905Z,1549494356.905 [NAL9602](DEBUG): Fix Requested
2019-02-06T23:05:57.481Z,1549494357.481 [CommandLine](IMPORTANT): got command restart application
2019-02-06T23:05:58.488Z,1549494358.488 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T23:05:58.488Z,1549494358.488 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:58.581Z,1549494358.581 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-02-06T23:05:58.581Z,1549494358.581 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:58.581Z,1549494358.581 [CommandLine](INFO): Join timeout helper Thread ID is 940
2019-02-06T23:05:58.601Z,1549494358.601 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-06T23:05:58.601Z,1549494358.601 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:58.602Z,1549494358.602 [NavChartDb](INFO): Join timeout helper Thread ID is 941
2019-02-06T23:05:58.885Z,1549494358.885 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T23:05:58.886Z,1549494358.886 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:58.905Z,1549494358.905 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-06T23:05:58.905Z,1549494358.905 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:58.905Z,1549494358.905 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 942
2019-02-06T23:05:59.125Z,1549494359.125 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T23:05:59.125Z,1549494359.125 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-06T23:05:59.126Z,1549494359.126 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:59.133Z,1549494359.133 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-06T23:05:59.133Z,1549494359.133 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:59.133Z,1549494359.133 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 943
2019-02-06T23:05:59.237Z,1549494359.237 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T23:05:59.237Z,1549494359.237 [CTD_NeilBrown](INFO): Powering down
2019-02-06T23:05:59.249Z,1549494359.249 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:59.257Z,1549494359.257 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-06T23:05:59.257Z,1549494359.257 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:59.257Z,1549494359.257 [Radio_Surface](INFO): Join timeout helper Thread ID is 944
2019-02-06T23:05:59.473Z,1549494359.473 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T23:05:59.473Z,1549494359.473 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:59.490Z,1549494359.490 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-06T23:05:59.490Z,1549494359.490 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:59.490Z,1549494359.490 [logger](INFO): Join timeout helper Thread ID is 945
2019-02-06T23:05:59.510Z,1549494359.510 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T23:05:59.511Z,1549494359.511 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:59.529Z,1549494359.529 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-06T23:05:59.530Z,1549494359.530 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:59.530Z,1549494359.530 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-06T23:05:59.530Z,1549494359.530 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:05:59.530Z,1549494359.530 [controlThread](INFO): Join timeout helper Thread ID is 946
2019-02-06T23:05:59.669Z,1549494359.669 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T23:05:59.669Z,1549494359.669 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-06T23:05:59.670Z,1549494359.670 [AHRS_M2](INFO): Powering down
2019-02-06T23:05:59.742Z,1549494359.742 [NAL9602](INFO): Powering down
2019-02-06T23:05:59.813Z,1549494359.813 [RDI_Pathfinder](INFO): Powering down
2019-02-06T23:05:59.814Z,1549494359.814 [RDI_PathfinderUp](INFO): Powering down
2019-02-06T23:05:59.815Z,1549494359.815 [Aanderaa_O2](INFO): Powering down
2019-02-06T23:05:59.816Z,1549494359.816 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-06T23:05:59.817Z,1549494359.817 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-06T23:05:59.818Z,1549494359.818 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-06T23:05:59.818Z,1549494359.818 [MissionManager](INFO): Uninitializing Mission Default
2019-02-06T23:05:59.818Z,1549494359.818 [Default] Stopped
2019-02-06T23:05:59.818Z,1549494359.818 [Default](DEBUG): Aggregate::uninitialize Default
2019-02-06T23:05:59.819Z,1549494359.819 [Default:B.GoToSurface] Stopped
2019-02-06T23:05:59.819Z,1549494359.819 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-06T23:05:59.819Z,1549494359.819 [Default:CheckIn] Stopped
2019-02-06T23:05:59.819Z,1549494359.819 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T23:05:59.819Z,1549494359.819 [Default:CheckIn:C.Wait] Stopped
2019-02-06T23:05:59.819Z,1549494359.819 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T23:05:59.822Z,1549494359.822 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-06T23:05:59.822Z,1549494359.822 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-06T23:05:59.822Z,1549494359.822 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-06T23:05:59.823Z,1549494359.823 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-06T23:05:59.823Z,1549494359.823 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-06T23:05:59.823Z,1549494359.823 [BuoyancyServo](INFO): Powering down
2019-02-06T23:05:59.837Z,1549494359.837 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-06T23:05:59.837Z,1549494359.837 [ElevatorServo](INFO): Powering down
2019-02-06T23:05:59.838Z,1549494359.838 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-06T23:05:59.838Z,1549494359.838 [MassServo](INFO): Powering down
2019-02-06T23:05:59.839Z,1549494359.839 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-06T23:05:59.839Z,1549494359.839 [RudderServo](INFO): Powering down
2019-02-06T23:05:59.840Z,1549494359.840 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-06T23:05:59.840Z,1549494359.840 [ThrusterServo](INFO): Powering down
2019-02-06T23:05:59.841Z,1549494359.841 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-06T23:05:59.841Z,1549494359.841 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-06T23:05:59.841Z,1549494359.841 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-06T23:05:59.841Z,1549494359.841 [CBIT](DEBUG): Powering off loads.
2019-02-06T23:05:59.853Z,1549494359.853 [CBIT](DEBUG): Disabling WDT.
2019-02-06T23:05:59.865Z,1549494359.865 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-06T23:05:59.866Z,1549494359.866 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:06:00.059Z,1549494360.059 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:06:00.162Z,1549494360.162 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:06:00.164Z,1549494360.164 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:06:00.224Z,1549494360.224 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-06T23:06:00.361Z,1549494360.361 [logger ThreadHandler](INFO): Thread cancelled.