2019-05-17T23:26:52.353Z,1558135612.353 [Supervisor](DEBUG): Initializing supervisor.
2019-05-17T23:26:52.356Z,1558135612.356 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-17T23:26:52.357Z,1558135612.357 [SyncHandler](INFO): Protected caller Thread ID is 2228
2019-05-17T23:26:52.357Z,1558135612.357 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-17T23:26:52.358Z,1558135612.358 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-17T23:26:52.358Z,1558135612.358 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2229
2019-05-17T23:26:52.361Z,1558135612.361 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-17T23:26:52.372Z,1558135612.372 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-17T23:26:52.373Z,1558135612.373 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-17T23:26:52.374Z,1558135612.374 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2230
2019-05-17T23:26:52.375Z,1558135612.375 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-17T23:26:52.376Z,1558135612.376 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-17T23:26:52.376Z,1558135612.376 [logger ThreadHandler](INFO): Protected caller Thread ID is 2231
2019-05-17T23:26:52.378Z,1558135612.378 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-17T23:26:52.379Z,1558135612.379 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-17T23:26:52.380Z,1558135612.380 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-17T23:26:52.581Z,1558135612.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-17T23:26:52.581Z,1558135612.581 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-17T23:26:52.661Z,1558135612.661 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-17T23:26:53.100Z,1558135613.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-17T23:26:53.100Z,1558135613.100 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-17T23:26:53.435Z,1558135613.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-17T23:26:53.436Z,1558135613.436 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-17T23:26:53.531Z,1558135613.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-17T23:26:53.532Z,1558135613.532 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-17T23:26:53.830Z,1558135613.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-17T23:26:53.831Z,1558135613.831 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-17T23:26:54.027Z,1558135614.027 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-17T23:26:54.027Z,1558135614.027 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-17T23:26:54.483Z,1558135614.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-17T23:26:54.484Z,1558135614.484 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-17T23:26:54.587Z,1558135614.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-17T23:26:54.588Z,1558135614.588 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-17T23:26:54.687Z,1558135614.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-17T23:26:54.688Z,1558135614.688 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-17T23:26:55.265Z,1558135615.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-17T23:26:55.266Z,1558135615.266 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-17T23:26:55.656Z,1558135615.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-17T23:26:55.656Z,1558135615.656 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-17T23:26:55.851Z,1558135615.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-17T23:26:55.852Z,1558135615.852 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-17T23:26:55.997Z,1558135615.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-17T23:26:55.997Z,1558135615.997 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-17T23:26:56.147Z,1558135616.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-17T23:26:56.149Z,1558135616.149 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-05-17T23:26:56.150Z,1558135616.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-05-17T23:26:56.239Z,1558135616.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-05-17T23:26:56.324Z,1558135616.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-05-17T23:26:56.426Z,1558135616.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-05-17T23:26:56.508Z,1558135616.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-05-17T23:26:56.602Z,1558135616.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-05-17T23:26:56.700Z,1558135616.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-05-17T23:26:56.908Z,1558135616.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-05-17T23:26:56.984Z,1558135616.984 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-05-17T23:26:57.238Z,1558135617.238 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-05-17T23:26:57.541Z,1558135617.541 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-05-17T23:26:57.805Z,1558135617.805 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-05-17T23:26:58.248Z,1558135618.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-17T23:26:58.249Z,1558135618.249 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-17T23:26:58.263Z,1558135618.263 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-17T23:26:58.332Z,1558135618.332 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-17T23:26:58.446Z,1558135618.446 [VerticalControl] Loaded
2019-05-17T23:26:58.446Z,1558135618.446 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-17T23:26:58.447Z,1558135618.447 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-17T23:26:58.517Z,1558135618.517 [HorizontalControl] Loaded
2019-05-17T23:26:58.517Z,1558135618.517 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-17T23:26:58.518Z,1558135618.518 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-17T23:26:58.524Z,1558135618.524 [SpeedControl] Loaded
2019-05-17T23:26:58.524Z,1558135618.524 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-17T23:26:58.525Z,1558135618.525 [LoopControl](DEBUG): Construct LoopControl.
2019-05-17T23:26:58.525Z,1558135618.525 [LoopControl] Loaded
2019-05-17T23:26:58.525Z,1558135618.525 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-17T23:26:58.526Z,1558135618.526 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-17T23:26:58.527Z,1558135618.527 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-17T23:26:58.540Z,1558135618.540 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-17T23:26:58.540Z,1558135618.540 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-17T23:26:58.642Z,1558135618.642 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-17T23:26:58.643Z,1558135618.643 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-17T23:26:58.772Z,1558135618.772 [BuoyancyServo] Loaded
2019-05-17T23:26:58.772Z,1558135618.772 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-17T23:26:58.783Z,1558135618.783 [ElevatorServo] Loaded
2019-05-17T23:26:58.784Z,1558135618.784 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-17T23:26:58.795Z,1558135618.795 [MassServo] Loaded
2019-05-17T23:26:58.795Z,1558135618.795 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-17T23:26:58.806Z,1558135618.806 [RudderServo] Loaded
2019-05-17T23:26:58.806Z,1558135618.806 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-17T23:26:58.817Z,1558135618.817 [ThrusterServo] Loaded
2019-05-17T23:26:58.818Z,1558135618.818 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-17T23:26:58.818Z,1558135618.818 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-17T23:26:58.819Z,1558135618.819 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-17T23:26:58.928Z,1558135618.928 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-17T23:26:58.928Z,1558135618.928 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-17T23:26:58.949Z,1558135618.949 [NavChart] Loaded
2019-05-17T23:26:58.950Z,1558135618.950 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-17T23:26:58.953Z,1558135618.953 [UniversalFixResidualReporter] Loaded
2019-05-17T23:26:58.954Z,1558135618.954 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-17T23:26:58.954Z,1558135618.954 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-17T23:26:58.955Z,1558135618.955 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-17T23:26:59.026Z,1558135619.026 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-17T23:26:59.027Z,1558135619.027 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-17T23:26:59.363Z,1558135619.363 [DataOverHttps] Loaded
2019-05-17T23:26:59.363Z,1558135619.363 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-17T23:26:59.364Z,1558135619.364 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087B4E0
2019-05-17T23:26:59.365Z,1558135619.365 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2314
2019-05-17T23:26:59.378Z,1558135619.378 [Depth_Keller] Loaded
2019-05-17T23:26:59.379Z,1558135619.379 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-17T23:26:59.478Z,1558135619.478 [NAL9602] Loaded
2019-05-17T23:26:59.479Z,1558135619.479 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-17T23:26:59.495Z,1558135619.495 [Onboard] Loaded
2019-05-17T23:26:59.495Z,1558135619.495 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-17T23:26:59.498Z,1558135619.498 [Radio_Surface] Loaded
2019-05-17T23:26:59.499Z,1558135619.499 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-17T23:26:59.500Z,1558135619.500 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AB4E0
2019-05-17T23:26:59.500Z,1558135619.500 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2315
2019-05-17T23:26:59.631Z,1558135619.631 [DAT] Loaded
2019-05-17T23:26:59.632Z,1558135619.632 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-05-17T23:26:59.632Z,1558135619.632 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-17T23:26:59.633Z,1558135619.633 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-17T23:26:59.675Z,1558135619.675 [DepthRateCalculator] Loaded
2019-05-17T23:26:59.675Z,1558135619.675 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-17T23:26:59.680Z,1558135619.680 [PitchRateCalculator] Loaded
2019-05-17T23:26:59.681Z,1558135619.681 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-17T23:26:59.693Z,1558135619.693 [SpeedCalculator] Loaded
2019-05-17T23:26:59.693Z,1558135619.693 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-17T23:26:59.715Z,1558135619.715 [TempGradientCalculator] Loaded
2019-05-17T23:26:59.715Z,1558135619.715 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-17T23:26:59.720Z,1558135619.720 [YawRateCalculator] Loaded
2019-05-17T23:26:59.721Z,1558135619.721 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-17T23:26:59.750Z,1558135619.750 [ElevatorOffsetCalculator] Loaded
2019-05-17T23:26:59.751Z,1558135619.751 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-17T23:26:59.751Z,1558135619.751 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-17T23:26:59.752Z,1558135619.752 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-17T23:27:00.027Z,1558135620.027 [CTD_NeilBrown] Loaded
2019-05-17T23:27:00.028Z,1558135620.028 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-17T23:27:00.029Z,1558135620.029 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409AD4E0
2019-05-17T23:27:00.029Z,1558135620.029 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2317
2019-05-17T23:27:00.071Z,1558135620.071 [ESPComponent] Loaded
2019-05-17T23:27:00.072Z,1558135620.072 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-05-17T23:27:00.086Z,1558135620.086 [PAR_Licor] Loaded
2019-05-17T23:27:00.086Z,1558135620.086 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-17T23:27:00.135Z,1558135620.135 [WetLabsBB2FL] Loaded
2019-05-17T23:27:00.136Z,1558135620.136 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-17T23:27:00.137Z,1558135620.137 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2019-05-17T23:27:00.137Z,1558135620.137 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2318
2019-05-17T23:27:00.138Z,1558135620.138 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-17T23:27:00.138Z,1558135620.138 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-17T23:27:00.170Z,1558135620.170 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-17T23:27:00.171Z,1558135620.171 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-17T23:27:00.477Z,1558135620.477 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-17T23:27:00.478Z,1558135620.478 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-17T23:27:00.617Z,1558135620.617 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-17T23:27:00.628Z,1558135620.628 [SBIT] Loaded
2019-05-17T23:27:00.628Z,1558135620.628 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-17T23:27:00.629Z,1558135620.629 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-17T23:27:00.642Z,1558135620.642 [IBIT] Loaded
2019-05-17T23:27:00.642Z,1558135620.642 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-17T23:27:00.645Z,1558135620.645 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-17T23:27:01.040Z,1558135621.040 [CBIT] Loaded
2019-05-17T23:27:01.041Z,1558135621.041 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-17T23:27:01.041Z,1558135621.041 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-17T23:27:01.045Z,1558135621.045 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-17T23:27:01.046Z,1558135621.046 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-17T23:27:01.053Z,1558135621.053 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-17T23:27:01.054Z,1558135621.054 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADC4E0
2019-05-17T23:27:01.054Z,1558135621.054 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2319
2019-05-17T23:27:01.059Z,1558135621.059 [Supervisor](INFO): Main Thread ID is 2227
2019-05-17T23:27:01.059Z,1558135621.059 [Supervisor](DEBUG): Running supervisor.
2019-05-17T23:27:01.060Z,1558135621.060 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2320
2019-05-17T23:27:01.062Z,1558135621.062 [controlThread ThreadHandler](INFO): Handler Thread ID is 2321
2019-05-17T23:27:01.063Z,1558135621.063 [controlThread](DEBUG): Initializing ControlThread
2019-05-17T23:27:01.063Z,1558135621.063 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-17T23:27:01.065Z,1558135621.065 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-17T23:27:01.066Z,1558135621.066 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-17T23:27:01.066Z,1558135621.066 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-17T23:27:01.068Z,1558135621.068 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-17T23:27:01.068Z,1558135621.068 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-17T23:27:01.071Z,1558135621.071 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-17T23:27:01.071Z,1558135621.071 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-17T23:27:01.072Z,1558135621.072 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-17T23:27:01.072Z,1558135621.072 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-17T23:27:01.072Z,1558135621.072 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-17T23:27:01.073Z,1558135621.073 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-17T23:27:01.074Z,1558135621.074 [SBIT](INFO): Initialize SBIT Component.
2019-05-17T23:27:01.075Z,1558135621.075 [SBIT](IMPORTANT): git: 2019-05-01-18-gebccc08
2019-05-17T23:27:01.075Z,1558135621.075 [SBIT](INFO): git hash: ebccc08b74c64e8c330b6ee85a06f50705035b59
2019-05-17T23:27:01.075Z,1558135621.075 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-17T23:27:01.076Z,1558135621.076 [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-05-17T23:27:01.077Z,1558135621.077 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-05-17T23:27:01.078Z,1558135621.078 [IBIT](INFO): Initialize IBIT Component.
2019-05-17T23:27:01.079Z,1558135621.079 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-17T23:27:01.080Z,1558135621.080 [logger ThreadHandler](INFO): Handler Thread ID is 2322
2019-05-17T23:27:01.090Z,1558135621.090 [CBIT](DEBUG): Initialized mux pins.
2019-05-17T23:27:01.090Z,1558135621.090 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-17T23:27:01.098Z,1558135621.098 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2323
2019-05-17T23:27:01.099Z,1558135621.099 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-17T23:27:01.110Z,1558135621.110 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2324
2019-05-17T23:27:01.114Z,1558135621.114 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-17T23:27:01.114Z,1558135621.114 [CBIT](DEBUG): Initializing heartbeat.
2019-05-17T23:27:01.122Z,1558135621.122 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2325
2019-05-17T23:27:01.123Z,1558135621.123 [CTD_NeilBrown](INFO): Powering down
2019-05-17T23:27:01.151Z,1558135621.151 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2326
2019-05-17T23:27:01.152Z,1558135621.152 [WetLabsBB2FL](INFO): Powering down
2019-05-17T23:27:01.178Z,1558135621.178 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2327
2019-05-17T23:27:01.182Z,1558135621.182 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-17T23:27:01.182Z,1558135621.182 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-17T23:27:01.182Z,1558135621.182 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-17T23:27:01.183Z,1558135621.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-17T23:27:01.183Z,1558135621.183 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-17T23:27:01.183Z,1558135621.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-17T23:27:01.183Z,1558135621.183 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-17T23:27:01.183Z,1558135621.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-17T23:27:01.184Z,1558135621.184 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-17T23:27:01.184Z,1558135621.184 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-17T23:27:01.184Z,1558135621.184 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-17T23:27:01.184Z,1558135621.184 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-17T23:27:01.184Z,1558135621.184 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-17T23:27:01.185Z,1558135621.185 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-17T23:27:01.185Z,1558135621.185 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-17T23:27:01.185Z,1558135621.185 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-17T23:27:01.186Z,1558135621.186 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-17T23:27:01.186Z,1558135621.186 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-17T23:27:01.222Z,1558135621.222 [CBIT](DEBUG): Backplane powered.
2019-05-17T23:27:01.223Z,1558135621.223 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-17T23:27:01.233Z,1558135621.233 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-17T23:27:01.252Z,1558135621.252 [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-05-17T23:27:01.252Z,1558135621.252 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-17T23:27:01.325Z,1558135621.325 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-17T23:27:01.342Z,1558135621.342 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-17T23:27:01.344Z,1558135621.344 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-17T23:27:01.371Z,1558135621.371 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-17T23:27:01.374Z,1558135621.374 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-17T23:27:01.399Z,1558135621.399 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-17T23:27:01.412Z,1558135621.412 [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-05-17T23:27:01.423Z,1558135621.423 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,DAT,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-17T23:27:01.437Z,1558135621.437 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-05-17T23:27:01.461Z,1558135621.461 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-05-17T23:27:01.461Z,1558135621.461 [DAT](INFO): Powering up
2019-05-17T23:27:01.461Z,1558135621.461 [DAT](DEBUG): Initializing DAT.
2019-05-17T23:27:01.480Z,1558135621.480 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-17T23:27:01.504Z,1558135621.504 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-17T23:27:01.510Z,1558135621.510 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-17T23:27:01.511Z,1558135621.511 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-17T23:27:01.514Z,1558135621.514 [Radio_Surface](INFO): Powering up
2019-05-17T23:27:01.518Z,1558135621.518 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-17T23:27:01.519Z,1558135621.519 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-17T23:27:01.526Z,1558135621.526 [MassServo](DEBUG): Initializing MassServo.
2019-05-17T23:27:01.527Z,1558135621.527 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-17T23:27:01.546Z,1558135621.546 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-17T23:27:01.547Z,1558135621.547 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-17T23:27:01.554Z,1558135621.554 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-17T23:27:01.558Z,1558135621.558 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-05-17T23:27:11.218Z,1558135631.218 [CBIT](CRITICAL): Environmental Failure. Press:14.745592 PSI. Humidity:28%. Temp:26 C. ABORTING MISSION
2019-05-17T23:27:11.219Z,1558135631.219 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2019-05-17T23:27:11.644Z,1558135631.644 [CommandLine](FAULT): Scheduling is paused
2019-05-17T23:27:11.645Z,1558135631.645 [CBIT](INFO): Critical error at 20190517T232711
2019-05-17T23:27:14.791Z,1558135634.791 [DAT](INFO): setting local address to 2
2019-05-17T23:27:15.182Z,1558135635.182 [DAT](INFO): set local address to 2
2019-05-17T23:27:29.711Z,1558135649.711 [NAL9602](INFO): Powering up NAL9602
2019-05-17T23:27:40.619Z,1558135660.619 [NAL9602](INFO): NAL9602 initialized
2019-05-17T23:27:41.438Z,1558135661.438 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:28:00.856Z,1558135680.856 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-17T23:28:00.860Z,1558135680.860 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-17T23:28:11.859Z,1558135691.859 [CBIT](IMPORTANT): Low side ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): 0.967177
CHAN A2 (12V): 4.697929
CHAN A3 (5V): 1.050747
CHAN B0 (3.3V): 0.791511
CHAN B1 (3.15aV): 1.114679
CHAN B2 (3.15bV): 1.114340
CHAN B3 (GND): 0.053399
OPEN: 0.026964
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-17T23:28:54.536Z,1558135734.536 [SBIT](IMPORTANT): SBIT PASSED
2019-05-17T23:28:54.587Z,1558135734.587 [CommandLine](IMPORTANT): got command configSet list
2019-05-17T23:28:54.587Z,1558135734.587 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-17T23:28:54.588Z,1558135734.588 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-05-17T23:28:54.588Z,1558135734.588 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-05-17T23:28:54.588Z,1558135734.588 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=8 count;

2019-05-17T23:28:54.588Z,1558135734.588 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=3 hour;

2019-05-17T23:28:54.589Z,1558135734.589 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2019-05-17T23:28:54.589Z,1558135734.589 [CommandLine](IMPORTANT): CBIT.gf24Offset=172 microampere;

2019-05-17T23:28:54.589Z,1558135734.589 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-05-17T23:28:54.589Z,1558135734.589 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-05-17T23:28:54.589Z,1558135734.589 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-05-17T23:28:54.589Z,1558135734.589 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2019-05-17T23:28:54.589Z,1558135734.589 [CommandLine](IMPORTANT): Express none DVL_micro.height_above_sea_floor;

2019-05-17T23:28:54.589Z,1558135734.589 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-05-17T23:28:54.589Z,1558135734.589 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=90 cubic_centimeter;

2019-05-17T23:28:54.589Z,1558135734.589 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 centimeter;

2019-05-17T23:28:54.590Z,1558135734.590 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-33 millimeter;

2019-05-17T23:28:54.591Z,1558135734.591 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=27 millimeter;

2019-05-17T23:28:54.927Z,1558135734.927 [MissionManager](IMPORTANT): Started mission Startup
2019-05-17T23:28:54.927Z,1558135734.927 [Startup] Running Loop=1
2019-05-17T23:28:54.927Z,1558135734.927 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-17T23:28:54.927Z,1558135734.927 [Startup:A.GoToSurface] Running Loop=1
2019-05-17T23:28:54.927Z,1558135734.927 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-17T23:28:54.928Z,1558135734.928 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-17T23:28:54.929Z,1558135734.929 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-17T23:28:54.929Z,1558135734.929 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-17T23:28:54.929Z,1558135734.929 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-17T23:28:54.930Z,1558135734.930 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-17T23:28:54.931Z,1558135734.931 [Startup:StartupSatComms] Running Loop=1
2019-05-17T23:28:54.931Z,1558135734.931 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-17T23:28:54.931Z,1558135734.931 [Startup:StartupSatComms:A] Running Loop=1
2019-05-17T23:28:55.332Z,1558135735.332 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-17T23:29:01.398Z,1558135741.398 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-17T23:29:01.398Z,1558135741.398 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-17T23:29:01.398Z,1558135741.398 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-17T23:29:01.408Z,1558135741.408 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-17T23:29:01.818Z,1558135741.818 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-17T23:29:01.818Z,1558135741.818 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-17T23:29:40.176Z,1558135780.176 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004850
2019-05-17T23:29:55.136Z,1558135795.136 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-17T23:28:54.9Z
2019-05-17T23:29:55.136Z,1558135795.136 [Startup:StartupSatComms:A] Stopped
2019-05-17T23:29:55.136Z,1558135795.136 [Startup:StartupSatComms:B] Running Loop=1
2019-05-17T23:29:55.528Z,1558135795.528 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-17T23:30:01.445Z,1558135801.445 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190517T213142/Courier0037.lzma
2019-05-17T23:30:02.252Z,1558135802.252 [DataOverHttps](INFO): Moved sent file to Logs/20190517T213142/Courier0037.lzma.bak
2019-05-17T23:30:02.252Z,1558135802.252 [DataOverHttps](INFO): SBD MOMSN=11098373
2019-05-17T23:30:13.777Z,1558135813.777 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20190517T232652/Courier0000.lzma
2019-05-17T23:30:14.583Z,1558135814.583 [DataOverHttps](INFO): Moved sent file to Logs/20190517T232652/Courier0000.lzma.bak
2019-05-17T23:30:14.584Z,1558135814.583 [DataOverHttps](INFO): SBD MOMSN=11098377
2019-05-17T23:30:27.289Z,1558135827.289 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20190517T213142/Express0038.lzma
2019-05-17T23:30:28.096Z,1558135828.096 [DataOverHttps](INFO): Moved sent file to Logs/20190517T213142/Express0038.lzma.bak
2019-05-17T23:30:28.096Z,1558135828.096 [DataOverHttps](INFO): SBD MOMSN=11098384
2019-05-17T23:30:40.687Z,1558135840.687 [DataOverHttps](INFO): Sending 1045 bytes from file Logs/20190517T232652/Express0001.lzma
2019-05-17T23:30:41.491Z,1558135841.491 [DataOverHttps](INFO): Moved sent file to Logs/20190517T232652/Express0001.lzma.bak
2019-05-17T23:30:41.491Z,1558135841.491 [DataOverHttps](INFO): SBD MOMSN=11098387
2019-05-17T23:30:44.423Z,1558135844.423 [Startup:StartupSatComms:B] Stopped
2019-05-17T23:30:44.423Z,1558135844.423 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-17T23:30:44.423Z,1558135844.423 [Startup:StartupSatComms] Stopped
2019-05-17T23:30:44.423Z,1558135844.423 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-17T23:30:44.424Z,1558135844.424 [Startup](INFO): Completed Startup
2019-05-17T23:30:44.424Z,1558135844.424 [MissionManager](INFO): Startup is completed.
2019-05-17T23:30:44.424Z,1558135844.424 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-17T23:30:44.424Z,1558135844.424 [Startup] Stopped
2019-05-17T23:30:44.424Z,1558135844.424 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-17T23:30:44.424Z,1558135844.424 [Startup:A.GoToSurface] Stopped
2019-05-17T23:30:44.424Z,1558135844.424 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-17T23:30:44.823Z,1558135844.823 [MissionManager](IMPORTANT): Started mission Default
2019-05-17T23:30:44.823Z,1558135844.823 [Default] Running Loop=1
2019-05-17T23:30:44.823Z,1558135844.823 [Default](DEBUG): Aggregate::initialize Default
2019-05-17T23:30:44.823Z,1558135844.823 [Default:B.GoToSurface] Running Loop=1
2019-05-17T23:30:44.823Z,1558135844.823 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-17T23:30:44.823Z,1558135844.823 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-17T23:30:44.824Z,1558135844.824 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-17T23:30:44.824Z,1558135844.824 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-17T23:30:44.824Z,1558135844.824 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-17T23:30:44.825Z,1558135844.825 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-17T23:30:44.825Z,1558135844.825 [Default:A.Wait] Running Loop=1
2019-05-17T23:30:44.825Z,1558135844.825 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-17T23:30:58.184Z,1558135858.184 [Default:A.Wait](INFO): Done Waiting.
2019-05-17T23:30:58.184Z,1558135858.184 [Default:A.Wait] Stopped
2019-05-17T23:30:58.184Z,1558135858.184 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-17T23:30:58.598Z,1558135858.598 [Default:CheckIn] Running Loop=1
2019-05-17T23:30:58.599Z,1558135858.599 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-17T23:30:58.599Z,1558135858.599 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-17T23:30:58.983Z,1558135858.983 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-17T23:31:02.206Z,1558135862.206 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-17T23:31:02.206Z,1558135862.206 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-17T23:31:02.206Z,1558135862.206 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-17T23:31:02.217Z,1558135862.217 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-17T23:31:02.624Z,1558135862.624 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-17T23:31:02.624Z,1558135862.624 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-17T23:32:43.975Z,1558135963.975 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-05-17T23:32:44.798Z,1558135964.798 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:32:49.642Z,1558135969.642 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:32:52.474Z,1558135972.474 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:32:55.706Z,1558135975.706 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:32:58.530Z,1558135978.530 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:01.766Z,1558135981.766 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:03.391Z,1558135983.391 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-17T23:33:03.391Z,1558135983.391 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-05-17T23:33:03.391Z,1558135983.391 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-17T23:33:03.401Z,1558135983.401 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-17T23:33:03.807Z,1558135983.807 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-17T23:33:03.807Z,1558135983.807 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-05-17T23:33:04.594Z,1558135984.594 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:07.822Z,1558135987.822 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:10.654Z,1558135990.654 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:13.482Z,1558135993.482 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:15.502Z,1558135995.502 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:18.734Z,1558135998.734 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:21.558Z,1558136001.558 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:24.790Z,1558136004.790 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:27.618Z,1558136007.618 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:30.850Z,1558136010.850 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:33.682Z,1558136013.682 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:36.510Z,1558136016.510 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:38.530Z,1558136018.530 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:40.546Z,1558136020.546 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:43.782Z,1558136023.782 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:46.610Z,1558136026.610 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:49.842Z,1558136029.842 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:52.670Z,1558136032.670 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:55.906Z,1558136035.906 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:33:58.726Z,1558136038.726 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:01.558Z,1558136041.558 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:03.578Z,1558136043.578 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:06.810Z,1558136046.810 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:09.634Z,1558136049.634 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:12.870Z,1558136052.870 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:15.694Z,1558136055.694 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:18.930Z,1558136058.930 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:21.758Z,1558136061.758 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:24.582Z,1558136064.582 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:26.606Z,1558136066.606 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:28.622Z,1558136068.622 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:31.858Z,1558136071.858 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:34.686Z,1558136074.686 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:37.918Z,1558136077.918 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:40.746Z,1558136080.746 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:43.978Z,1558136083.978 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:46.806Z,1558136086.806 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:49.634Z,1558136089.634 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:51.654Z,1558136091.654 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:54.886Z,1558136094.886 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:34:57.730Z,1558136097.730 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:00.946Z,1558136100.946 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:03.778Z,1558136103.778 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:04.602Z,1558136104.602 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-17T23:35:04.602Z,1558136104.602 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-05-17T23:35:04.602Z,1558136104.602 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-17T23:35:04.612Z,1558136104.612 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-17T23:35:05.012Z,1558136105.012 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-17T23:35:05.012Z,1558136105.012 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-05-17T23:35:07.006Z,1558136107.006 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:09.830Z,1558136109.830 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:12.662Z,1558136112.662 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:14.686Z,1558136114.686 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:16.698Z,1558136116.698 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:19.930Z,1558136119.930 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:22.758Z,1558136122.758 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:25.994Z,1558136125.994 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:28.834Z,1558136128.834 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:32.066Z,1558136132.066 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:34.890Z,1558136134.890 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:37.722Z,1558136137.722 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:39.742Z,1558136139.742 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:42.970Z,1558136142.970 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:45.802Z,1558136145.802 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:49.030Z,1558136149.030 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:51.858Z,1558136151.858 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:55.114Z,1558136155.114 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:57.918Z,1558136157.918 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:35:58.759Z,1558136158.759 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-17T23:30:58.6Z
2019-05-17T23:35:58.759Z,1558136158.759 [Default:CheckIn:Read_GPS] Stopped
2019-05-17T23:35:58.759Z,1558136158.759 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-17T23:35:59.163Z,1558136159.163 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-17T23:36:00.786Z,1558136160.786 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:02.317Z,1558136162.317 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190517T232652/Courier0004.lzma
2019-05-17T23:36:03.127Z,1558136163.127 [DataOverHttps](INFO): Moved sent file to Logs/20190517T232652/Courier0004.lzma.bak
2019-05-17T23:36:03.127Z,1558136163.127 [DataOverHttps](INFO): SBD MOMSN=11098447
2019-05-17T23:36:04.018Z,1558136164.018 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:06.846Z,1558136166.846 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:10.078Z,1558136170.078 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:12.906Z,1558136172.906 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:15.734Z,1558136175.734 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:15.806Z,1558136175.806 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20190517T232652/Express0005.lzma
2019-05-17T23:36:16.611Z,1558136176.611 [DataOverHttps](INFO): Moved sent file to Logs/20190517T232652/Express0005.lzma.bak
2019-05-17T23:36:16.612Z,1558136176.612 [DataOverHttps](INFO): SBD MOMSN=11098449
2019-05-17T23:36:17.754Z,1558136177.754 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:19.400Z,1558136179.400 [Default:CheckIn:Read_Iridium] Stopped
2019-05-17T23:36:19.400Z,1558136179.400 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-17T23:36:19.400Z,1558136179.400 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-17T23:36:19.774Z,1558136179.774 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:21.798Z,1558136181.798 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:25.026Z,1558136185.026 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:27.858Z,1558136187.858 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:31.086Z,1558136191.086 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:33.914Z,1558136193.914 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:37.150Z,1558136197.150 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:39.978Z,1558136199.978 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:42.802Z,1558136202.802 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:44.826Z,1558136204.826 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:48.058Z,1558136208.058 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:50.882Z,1558136210.882 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:54.118Z,1558136214.118 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:36:56.942Z,1558136216.942 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:00.174Z,1558136220.174 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:03.006Z,1558136223.006 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:05.442Z,1558136225.442 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-17T23:37:05.442Z,1558136225.442 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-05-17T23:37:05.442Z,1558136225.442 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-17T23:37:05.453Z,1558136225.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-17T23:37:05.830Z,1558136225.830 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:05.863Z,1558136225.863 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-17T23:37:05.863Z,1558136225.863 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-05-17T23:37:07.850Z,1558136227.850 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:09.874Z,1558136229.874 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:13.106Z,1558136233.106 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:15.934Z,1558136235.934 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:19.162Z,1558136239.162 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:21.994Z,1558136241.994 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:25.226Z,1558136245.226 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:28.050Z,1558136248.050 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:30.878Z,1558136250.878 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:32.902Z,1558136252.902 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:36.130Z,1558136256.130 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:38.962Z,1558136258.962 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:37:42.183Z,1558136262.183 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-05-17T23:37:42.183Z,1558136262.183 [NAL9602] Data Fault, FailCount= 1
2019-05-17T23:37:42.183Z,1558136262.183 [NAL9602](ERROR): Data Fault
2019-05-17T23:37:42.215Z,1558136262.215 [CBIT](ERROR): Data Fault in component: NAL9602
2019-05-17T23:37:42.583Z,1558136262.583 [NAL9602](INFO): Powering down
2019-05-17T23:37:43.419Z,1558136263.419 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-17T23:37:43.419Z,1558136263.419 [NAL9602] No Fault, FailCount= 1
2019-05-17T23:38:12.887Z,1558136292.887 [NAL9602](INFO): Powering up NAL9602
2019-05-17T23:38:14.171Z,1558136294.171 [CommandLine](IMPORTANT): got command gfscan
2019-05-17T23:38:14.540Z,1558136294.540 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-17T23:38:23.795Z,1558136303.795 [NAL9602](INFO): NAL9602 initialized
2019-05-17T23:38:24.618Z,1558136304.618 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:25.457Z,1558136305.457 [CBIT](IMPORTANT): Low side ground fault detected
mA:
CHAN A0 (Batt): 1.034299
CHAN A1 (24V): 0.965699
CHAN A2 (12V): 1.074318
CHAN A3 (5V): 1.054990
CHAN B0 (3.3V): 0.792477
CHAN B1 (3.15aV): 1.117000
CHAN B2 (3.15bV): 1.116438
CHAN B3 (GND): 0.052296
OPEN: 0.027162
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-17T23:38:27.442Z,1558136307.442 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:30.266Z,1558136310.266 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:33.498Z,1558136313.498 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:36.330Z,1558136316.330 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:39.154Z,1558136319.154 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:41.178Z,1558136321.178 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:43.198Z,1558136323.198 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:46.426Z,1558136326.426 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:49.258Z,1558136329.258 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:52.486Z,1558136332.486 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:55.318Z,1558136335.318 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:38:58.550Z,1558136338.550 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:01.374Z,1558136341.374 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:04.202Z,1558136344.202 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:06.222Z,1558136346.222 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:06.656Z,1558136346.656 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-17T23:39:06.656Z,1558136346.656 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-05-17T23:39:06.656Z,1558136346.656 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-17T23:39:06.666Z,1558136346.666 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-17T23:39:07.069Z,1558136347.069 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-17T23:39:07.069Z,1558136347.069 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-05-17T23:39:08.242Z,1558136348.242 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:11.474Z,1558136351.474 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:14.302Z,1558136354.302 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:17.538Z,1558136357.538 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:20.362Z,1558136360.362 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:23.598Z,1558136363.598 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:26.422Z,1558136366.422 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:29.250Z,1558136369.250 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:31.270Z,1558136371.270 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:34.502Z,1558136374.502 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:37.330Z,1558136377.330 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:40.566Z,1558136380.566 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:43.390Z,1558136383.390 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:46.622Z,1558136386.622 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:49.450Z,1558136389.450 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:52.282Z,1558136392.282 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:54.298Z,1558136394.298 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:39:57.534Z,1558136397.534 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:00.358Z,1558136400.358 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:03.594Z,1558136403.594 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:06.418Z,1558136406.418 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:09.654Z,1558136409.654 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:12.478Z,1558136412.478 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:15.314Z,1558136415.314 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:17.326Z,1558136417.326 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:19.350Z,1558136419.350 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:22.582Z,1558136422.582 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:25.410Z,1558136425.410 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:28.642Z,1558136428.642 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:31.466Z,1558136431.466 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:34.702Z,1558136434.702 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:37.530Z,1558136437.530 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:39.072Z,1558136439.072 [CommandLine](IMPORTANT): got command restart system
2019-05-17T23:40:40.363Z,1558136440.363 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:41.370Z,1558136441.370 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-17T23:40:41.370Z,1558136441.370 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:41.438Z,1558136441.438 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-17T23:40:41.438Z,1558136441.438 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:41.439Z,1558136441.439 [CommandLine](INFO): Join timeout helper Thread ID is 2396
2019-05-17T23:40:41.439Z,1558136441.439 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-17T23:40:41.439Z,1558136441.439 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:41.440Z,1558136441.440 [NavChartDb](INFO): Join timeout helper Thread ID is 2397
2019-05-17T23:40:41.762Z,1558136441.762 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-17T23:40:41.762Z,1558136441.762 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:41.778Z,1558136441.778 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-17T23:40:41.778Z,1558136441.778 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:41.778Z,1558136441.778 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2398
2019-05-17T23:40:42.066Z,1558136442.066 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-17T23:40:42.067Z,1558136442.067 [WetLabsBB2FL](INFO): Powering down
2019-05-17T23:40:42.067Z,1558136442.067 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.078Z,1558136442.078 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-17T23:40:42.078Z,1558136442.078 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.078Z,1558136442.078 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 2399
2019-05-17T23:40:42.146Z,1558136442.146 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-17T23:40:42.146Z,1558136442.146 [CTD_NeilBrown](INFO): Powering down
2019-05-17T23:40:42.158Z,1558136442.158 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.159Z,1558136442.159 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-17T23:40:42.159Z,1558136442.159 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.159Z,1558136442.159 [Radio_Surface](INFO): Join timeout helper Thread ID is 2400
2019-05-17T23:40:42.382Z,1558136442.382 [NAL9602](DEBUG): Fix Requested
2019-05-17T23:40:42.518Z,1558136442.518 [Radio_Surface](INFO): Powering down
2019-05-17T23:40:42.519Z,1558136442.519 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-17T23:40:42.519Z,1558136442.519 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.531Z,1558136442.531 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-17T23:40:42.531Z,1558136442.531 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.531Z,1558136442.531 [DataOverHttps](INFO): Join timeout helper Thread ID is 2401
2019-05-17T23:40:42.718Z,1558136442.718 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-17T23:40:42.718Z,1558136442.718 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.731Z,1558136442.731 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-17T23:40:42.731Z,1558136442.731 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.731Z,1558136442.731 [logger](INFO): Join timeout helper Thread ID is 2402
2019-05-17T23:40:42.766Z,1558136442.766 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-17T23:40:42.766Z,1558136442.766 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.771Z,1558136442.771 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-17T23:40:42.771Z,1558136442.771 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.771Z,1558136442.771 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-17T23:40:42.771Z,1558136442.771 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:42.771Z,1558136442.771 [controlThread](INFO): Join timeout helper Thread ID is 2403
2019-05-17T23:40:42.812Z,1558136442.812 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-17T23:40:42.812Z,1558136442.812 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-17T23:40:42.813Z,1558136442.813 [NAL9602](INFO): Powering down
2019-05-17T23:40:42.814Z,1558136442.814 [DAT](INFO): Powering down
2019-05-17T23:40:42.955Z,1558136442.955 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-17T23:40:42.956Z,1558136442.956 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-17T23:40:42.956Z,1558136442.956 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-17T23:40:42.957Z,1558136442.957 [MissionManager](INFO): Uninitializing Mission Default
2019-05-17T23:40:42.957Z,1558136442.957 [Default] Stopped
2019-05-17T23:40:42.957Z,1558136442.957 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-17T23:40:42.957Z,1558136442.957 [Default:B.GoToSurface] Stopped
2019-05-17T23:40:42.957Z,1558136442.957 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-17T23:40:42.957Z,1558136442.957 [Default:CheckIn] Stopped
2019-05-17T23:40:42.957Z,1558136442.957 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-17T23:40:42.957Z,1558136442.957 [Default:CheckIn:C.Wait] Stopped
2019-05-17T23:40:42.957Z,1558136442.957 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-17T23:40:42.960Z,1558136442.960 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-17T23:40:42.960Z,1558136442.960 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-17T23:40:42.961Z,1558136442.961 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-17T23:40:42.961Z,1558136442.961 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-17T23:40:42.961Z,1558136442.961 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-17T23:40:42.961Z,1558136442.961 [BuoyancyServo](INFO): Powering down
2019-05-17T23:40:42.974Z,1558136442.974 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-17T23:40:42.974Z,1558136442.974 [ElevatorServo](INFO): Powering down
2019-05-17T23:40:42.975Z,1558136442.975 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-17T23:40:42.975Z,1558136442.975 [MassServo](INFO): Powering down
2019-05-17T23:40:42.976Z,1558136442.976 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-17T23:40:42.976Z,1558136442.976 [RudderServo](INFO): Powering down
2019-05-17T23:40:42.977Z,1558136442.977 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-17T23:40:42.977Z,1558136442.977 [ThrusterServo](INFO): Powering down
2019-05-17T23:40:42.978Z,1558136442.978 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-17T23:40:42.978Z,1558136442.978 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-17T23:40:42.979Z,1558136442.979 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-17T23:40:42.979Z,1558136442.979 [CBIT](DEBUG): Powering off loads.
2019-05-17T23:40:42.990Z,1558136442.990 [CBIT](DEBUG): Disabling WDT.
2019-05-17T23:40:43.002Z,1558136443.002 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-17T23:40:43.003Z,1558136443.003 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:43.047Z,1558136443.047 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:43.053Z,1558136443.053 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:43.081Z,1558136443.081 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:43.086Z,1558136443.086 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:43.122Z,1558136443.122 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-17T23:40:43.179Z,1558136443.179 [logger ThreadHandler](INFO): Thread cancelled.