2019-05-15T16:56:03.011Z,1557939363.011 [Supervisor](DEBUG): Initializing supervisor.
2019-05-15T16:56:03.014Z,1557939363.014 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-15T16:56:03.015Z,1557939363.015 [SyncHandler](INFO): Protected caller Thread ID is 977
2019-05-15T16:56:03.015Z,1557939363.015 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-15T16:56:03.016Z,1557939363.016 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-15T16:56:03.017Z,1557939363.017 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 978
2019-05-15T16:56:03.020Z,1557939363.020 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-15T16:56:03.032Z,1557939363.032 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-15T16:56:03.033Z,1557939363.033 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-15T16:56:03.033Z,1557939363.033 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 979
2019-05-15T16:56:03.034Z,1557939363.034 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-15T16:56:03.035Z,1557939363.035 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-15T16:56:03.035Z,1557939363.035 [logger ThreadHandler](INFO): Protected caller Thread ID is 980
2019-05-15T16:56:03.037Z,1557939363.037 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-15T16:56:03.038Z,1557939363.038 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-15T16:56:03.039Z,1557939363.039 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-15T16:56:03.134Z,1557939363.134 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-15T16:56:03.135Z,1557939363.135 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-15T16:56:03.658Z,1557939363.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-15T16:56:03.659Z,1557939363.659 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-15T16:56:03.756Z,1557939363.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-15T16:56:03.757Z,1557939363.757 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-15T16:56:03.858Z,1557939363.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-15T16:56:03.859Z,1557939363.859 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-15T16:56:03.938Z,1557939363.938 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-15T16:56:04.076Z,1557939364.076 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-15T16:56:04.077Z,1557939364.077 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-15T16:56:04.371Z,1557939364.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-15T16:56:04.372Z,1557939364.372 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-15T16:56:04.845Z,1557939364.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-15T16:56:04.846Z,1557939364.846 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-15T16:56:05.101Z,1557939365.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-15T16:56:05.102Z,1557939365.102 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-15T16:56:05.338Z,1557939365.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-15T16:56:05.338Z,1557939365.338 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-15T16:56:05.799Z,1557939365.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-15T16:56:05.799Z,1557939365.799 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-15T16:56:06.011Z,1557939366.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-15T16:56:06.012Z,1557939366.012 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-15T16:56:06.212Z,1557939366.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-15T16:56:06.213Z,1557939366.213 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-15T16:56:06.600Z,1557939366.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-15T16:56:06.600Z,1557939366.600 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-15T16:56:06.933Z,1557939366.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-15T16:56:06.935Z,1557939366.935 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-05-15T16:56:06.936Z,1557939366.936 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-05-15T16:56:07.018Z,1557939367.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-05-15T16:56:07.164Z,1557939367.164 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-05-15T16:56:07.271Z,1557939367.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-05-15T16:56:07.355Z,1557939367.355 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-05-15T16:56:07.449Z,1557939367.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-05-15T16:56:07.630Z,1557939367.630 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-05-15T16:56:07.857Z,1557939367.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-15T16:56:07.857Z,1557939367.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-05-15T16:56:07.950Z,1557939367.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-05-15T16:56:08.048Z,1557939368.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-05-15T16:56:08.164Z,1557939368.164 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-05-15T16:56:08.264Z,1557939368.264 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2019-05-15T16:56:08.265Z,1557939368.265 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-15T16:56:08.271Z,1557939368.271 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-15T16:56:08.337Z,1557939368.337 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-15T16:56:08.449Z,1557939368.449 [VerticalControl] Loaded
2019-05-15T16:56:08.449Z,1557939368.449 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-15T16:56:08.450Z,1557939368.450 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-15T16:56:08.518Z,1557939368.518 [HorizontalControl] Loaded
2019-05-15T16:56:08.518Z,1557939368.518 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-15T16:56:08.519Z,1557939368.519 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-15T16:56:08.524Z,1557939368.524 [SpeedControl] Loaded
2019-05-15T16:56:08.525Z,1557939368.525 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-15T16:56:08.525Z,1557939368.525 [LoopControl](DEBUG): Construct LoopControl.
2019-05-15T16:56:08.526Z,1557939368.526 [LoopControl] Loaded
2019-05-15T16:56:08.526Z,1557939368.526 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-15T16:56:08.527Z,1557939368.527 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-15T16:56:08.527Z,1557939368.527 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-15T16:56:08.566Z,1557939368.566 [DepthRateCalculator] Loaded
2019-05-15T16:56:08.567Z,1557939368.567 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-15T16:56:08.572Z,1557939368.572 [PitchRateCalculator] Loaded
2019-05-15T16:56:08.572Z,1557939368.572 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-15T16:56:08.588Z,1557939368.588 [SpeedCalculator] Loaded
2019-05-15T16:56:08.588Z,1557939368.588 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-15T16:56:08.609Z,1557939368.609 [TempGradientCalculator] Loaded
2019-05-15T16:56:08.609Z,1557939368.609 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-15T16:56:08.614Z,1557939368.614 [YawRateCalculator] Loaded
2019-05-15T16:56:08.614Z,1557939368.614 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-15T16:56:08.643Z,1557939368.643 [ElevatorOffsetCalculator] Loaded
2019-05-15T16:56:08.643Z,1557939368.643 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-15T16:56:08.644Z,1557939368.644 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-15T16:56:08.644Z,1557939368.644 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-15T16:56:08.671Z,1557939368.671 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-15T16:56:08.671Z,1557939368.671 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-15T16:56:08.738Z,1557939368.738 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-15T16:56:08.739Z,1557939368.739 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-15T16:56:09.022Z,1557939369.022 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-15T16:56:09.023Z,1557939369.023 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-15T16:56:09.117Z,1557939369.117 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-15T16:56:09.118Z,1557939369.118 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-15T16:56:09.444Z,1557939369.444 [DataOverHttps] Loaded
2019-05-15T16:56:09.444Z,1557939369.444 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-15T16:56:09.445Z,1557939369.445 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408C74E0
2019-05-15T16:56:09.446Z,1557939369.446 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1059
2019-05-15T16:56:09.484Z,1557939369.484 [Depth_Keller] Loaded
2019-05-15T16:56:09.484Z,1557939369.484 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-15T16:56:09.582Z,1557939369.582 [NAL9602] Loaded
2019-05-15T16:56:09.582Z,1557939369.582 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-15T16:56:09.598Z,1557939369.598 [Onboard] Loaded
2019-05-15T16:56:09.598Z,1557939369.598 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-15T16:56:09.601Z,1557939369.601 [Radio_Surface] Loaded
2019-05-15T16:56:09.602Z,1557939369.602 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-15T16:56:09.603Z,1557939369.603 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408F74E0
2019-05-15T16:56:09.603Z,1557939369.603 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1060
2019-05-15T16:56:11.711Z,1557939371.711 [BPC1] Loaded
2019-05-15T16:56:11.711Z,1557939371.711 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-15T16:56:11.712Z,1557939371.712 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-15T16:56:11.712Z,1557939371.712 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-15T16:56:11.814Z,1557939371.814 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-15T16:56:11.814Z,1557939371.814 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-15T16:56:11.835Z,1557939371.835 [NavChart] Loaded
2019-05-15T16:56:11.835Z,1557939371.835 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-15T16:56:11.839Z,1557939371.839 [UniversalFixResidualReporter] Loaded
2019-05-15T16:56:11.840Z,1557939371.840 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-15T16:56:11.840Z,1557939371.840 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-15T16:56:11.841Z,1557939371.841 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-15T16:56:11.971Z,1557939371.971 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-15T16:56:11.982Z,1557939371.982 [SBIT] Loaded
2019-05-15T16:56:11.982Z,1557939371.982 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-15T16:56:11.983Z,1557939371.983 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-15T16:56:11.994Z,1557939371.994 [IBIT] Loaded
2019-05-15T16:56:11.995Z,1557939371.995 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-15T16:56:11.998Z,1557939371.998 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-15T16:56:12.133Z,1557939372.133 [CBIT] Loaded
2019-05-15T16:56:12.134Z,1557939372.134 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-15T16:56:12.134Z,1557939372.134 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-15T16:56:12.135Z,1557939372.135 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-15T16:56:12.236Z,1557939372.236 [BuoyancyServo] Loaded
2019-05-15T16:56:12.237Z,1557939372.237 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-15T16:56:12.248Z,1557939372.248 [ElevatorServo] Loaded
2019-05-15T16:56:12.248Z,1557939372.248 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-15T16:56:12.259Z,1557939372.259 [MassServo] Loaded
2019-05-15T16:56:12.260Z,1557939372.260 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-15T16:56:12.271Z,1557939372.271 [RudderServo] Loaded
2019-05-15T16:56:12.271Z,1557939372.271 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-15T16:56:12.282Z,1557939372.282 [ThrusterServo] Loaded
2019-05-15T16:56:12.282Z,1557939372.282 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-15T16:56:12.282Z,1557939372.282 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-15T16:56:12.283Z,1557939372.283 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-15T16:56:12.296Z,1557939372.296 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-15T16:56:12.296Z,1557939372.296 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-15T16:56:12.542Z,1557939372.542 [CTD_NeilBrown] Loaded
2019-05-15T16:56:12.542Z,1557939372.542 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-15T16:56:12.543Z,1557939372.543 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 40A734E0
2019-05-15T16:56:12.543Z,1557939372.543 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1061
2019-05-15T16:56:12.558Z,1557939372.558 [PAR_Licor] Loaded
2019-05-15T16:56:12.558Z,1557939372.558 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-15T16:56:12.606Z,1557939372.606 [WetLabsBB2FL] Loaded
2019-05-15T16:56:12.606Z,1557939372.606 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-15T16:56:12.607Z,1557939372.607 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AA34E0
2019-05-15T16:56:12.608Z,1557939372.608 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1062
2019-05-15T16:56:12.608Z,1557939372.608 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-15T16:56:12.612Z,1557939372.612 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-15T16:56:12.613Z,1557939372.613 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-15T16:56:12.620Z,1557939372.620 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-15T16:56:12.621Z,1557939372.621 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD34E0
2019-05-15T16:56:12.621Z,1557939372.621 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1063
2019-05-15T16:56:12.626Z,1557939372.626 [Supervisor](INFO): Main Thread ID is 809
2019-05-15T16:56:12.626Z,1557939372.626 [Supervisor](DEBUG): Running supervisor.
2019-05-15T16:56:12.627Z,1557939372.627 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1064
2019-05-15T16:56:12.629Z,1557939372.629 [controlThread ThreadHandler](INFO): Handler Thread ID is 1065
2019-05-15T16:56:12.630Z,1557939372.630 [controlThread](DEBUG): Initializing ControlThread
2019-05-15T16:56:12.630Z,1557939372.630 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-15T16:56:12.632Z,1557939372.632 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-15T16:56:12.633Z,1557939372.633 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-15T16:56:12.633Z,1557939372.633 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-15T16:56:12.633Z,1557939372.633 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-15T16:56:12.634Z,1557939372.634 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-15T16:56:12.634Z,1557939372.634 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-15T16:56:12.634Z,1557939372.634 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-15T16:56:12.635Z,1557939372.635 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-15T16:56:12.635Z,1557939372.635 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-15T16:56:12.638Z,1557939372.638 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-15T16:56:12.639Z,1557939372.639 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-15T16:56:12.639Z,1557939372.639 [SBIT](INFO): Initialize SBIT Component.
2019-05-15T16:56:12.640Z,1557939372.640 [SBIT](IMPORTANT): git: 2019-05-01-6-g08bb213
2019-05-15T16:56:12.640Z,1557939372.640 [SBIT](INFO): git hash: 08bb2137fac00bb96d649b4144ef516361bafd54
2019-05-15T16:56:12.640Z,1557939372.640 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-15T16:56:12.641Z,1557939372.641 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2019-05-15T16:56:12.642Z,1557939372.642 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-05-15T16:56:12.643Z,1557939372.643 [IBIT](INFO): Initialize IBIT Component.
2019-05-15T16:56:12.644Z,1557939372.644 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-15T16:56:12.645Z,1557939372.645 [logger ThreadHandler](INFO): Handler Thread ID is 1066
2019-05-15T16:56:12.657Z,1557939372.657 [CBIT](DEBUG): Initialized mux pins.
2019-05-15T16:56:12.657Z,1557939372.657 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-15T16:56:12.665Z,1557939372.665 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1067
2019-05-15T16:56:12.666Z,1557939372.666 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-15T16:56:12.678Z,1557939372.678 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1068
2019-05-15T16:56:12.681Z,1557939372.681 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-15T16:56:12.681Z,1557939372.681 [CBIT](DEBUG): Initializing heartbeat.
2019-05-15T16:56:12.689Z,1557939372.689 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1069
2019-05-15T16:56:12.701Z,1557939372.701 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1070
2019-05-15T16:56:12.702Z,1557939372.702 [WetLabsBB2FL](INFO): Powering down
2019-05-15T16:56:12.729Z,1557939372.729 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1071
2019-05-15T16:56:12.740Z,1557939372.740 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-15T16:56:12.740Z,1557939372.740 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-15T16:56:12.740Z,1557939372.740 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-15T16:56:12.740Z,1557939372.740 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-15T16:56:12.740Z,1557939372.740 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-15T16:56:12.741Z,1557939372.741 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-15T16:56:12.741Z,1557939372.741 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-15T16:56:12.741Z,1557939372.741 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-15T16:56:12.741Z,1557939372.741 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-15T16:56:12.741Z,1557939372.741 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-15T16:56:12.742Z,1557939372.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-15T16:56:12.742Z,1557939372.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-15T16:56:12.742Z,1557939372.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-15T16:56:12.742Z,1557939372.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-15T16:56:12.742Z,1557939372.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-15T16:56:12.743Z,1557939372.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-15T16:56:12.753Z,1557939372.753 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-15T16:56:12.753Z,1557939372.753 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-15T16:56:12.789Z,1557939372.789 [CBIT](DEBUG): Backplane powered.
2019-05-15T16:56:12.794Z,1557939372.794 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-15T16:56:12.808Z,1557939372.808 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-15T16:56:12.843Z,1557939372.843 [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-15T16:56:12.843Z,1557939372.843 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-15T16:56:12.915Z,1557939372.915 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-15T16:56:12.916Z,1557939372.916 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-15T16:56:12.933Z,1557939372.933 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-15T16:56:12.966Z,1557939372.966 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-15T16:56:12.968Z,1557939372.968 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-15T16:56:12.998Z,1557939372.998 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-15T16:56:13.001Z,1557939373.001 [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-15T16:56:13.012Z,1557939373.012 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,BPC1,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-15T16:56:13.081Z,1557939373.081 [Radio_Surface](INFO): Powering up
2019-05-15T16:56:13.155Z,1557939373.155 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-15T16:56:13.165Z,1557939373.165 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-15T16:56:13.166Z,1557939373.166 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-15T16:56:13.173Z,1557939373.173 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-15T16:56:13.174Z,1557939373.174 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-15T16:56:13.181Z,1557939373.181 [MassServo](DEBUG): Initializing MassServo.
2019-05-15T16:56:13.182Z,1557939373.182 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-15T16:56:13.189Z,1557939373.189 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-15T16:56:13.190Z,1557939373.190 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-15T16:56:13.197Z,1557939373.197 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-15T16:56:14.197Z,1557939374.197 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-05-15T16:56:14.197Z,1557939374.197 [RudderServo](FAULT): Rudder failed to initialize
2019-05-15T16:56:14.197Z,1557939374.197 [RudderServo] Communications Fault, FailCount= 1
2019-05-15T16:56:14.197Z,1557939374.197 [RudderServo](ERROR): Communications Fault
2019-05-15T16:56:14.308Z,1557939374.308 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-15T16:56:14.482Z,1557939374.482 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-15T16:56:14.482Z,1557939374.482 [RudderServo](INFO): Powering down
2019-05-15T16:56:15.175Z,1557939375.175 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-15T16:56:15.294Z,1557939375.294 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-15T16:56:15.298Z,1557939375.298 [CBIT](INFO): Clearing failed state for component RudderServo
2019-05-15T16:56:15.298Z,1557939375.298 [RudderServo] No Fault, FailCount= 1
2019-05-15T16:56:39.870Z,1557939399.870 [NAL9602](INFO): Powering up NAL9602
2019-05-15T16:56:41.147Z,1557939401.147 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-15T16:56:41.152Z,1557939401.152 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-15T16:56:52.167Z,1557939412.167 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002017
CHAN A1 (24V): 0.002734
CHAN A2 (12V): -0.007880
CHAN A3 (5V): -0.002454
CHAN B0 (3.3V): 0.000602
CHAN B1 (3.15aV): 0.000175
CHAN B2 (3.15bV): -0.000481
CHAN B3 (GND): 0.001710
OPEN: -0.000756
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-15T16:57:00.710Z,1557939420.710 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T16:57:00.710Z,1557939420.710 [NAL9602] Communications Fault, FailCount= 1
2019-05-15T16:57:00.710Z,1557939420.710 [NAL9602](ERROR): Communications Fault
2019-05-15T16:57:00.834Z,1557939420.834 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T16:57:01.111Z,1557939421.111 [NAL9602](INFO): Powering down
2019-05-15T16:57:02.053Z,1557939422.053 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T16:57:02.053Z,1557939422.053 [NAL9602] No Fault, FailCount= 1
2019-05-15T16:57:31.502Z,1557939451.502 [NAL9602](INFO): Powering up NAL9602
2019-05-15T16:57:34.447Z,1557939454.447 [CommandLine](IMPORTANT): got command failComponent
2019-05-15T16:57:34.447Z,1557939454.447 [CommandLine](IMPORTANT): Failed components:
2019-05-15T16:57:34.447Z,1557939454.447 [CommandLine](IMPORTANT): No failed Components.
2019-05-15T16:57:34.760Z,1557939454.760 [SBIT](IMPORTANT): SBIT PASSED
2019-05-15T16:57:34.850Z,1557939454.850 [CommandLine](IMPORTANT): got command configSet list
2019-05-15T16:57:34.850Z,1557939454.850 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-15T16:57:34.851Z,1557939454.851 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-05-15T16:57:34.851Z,1557939454.851 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=0 count;

2019-05-15T16:57:34.851Z,1557939454.851 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-05-15T16:57:34.851Z,1557939454.851 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-05-15T16:57:34.851Z,1557939454.851 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-05-15T16:57:34.851Z,1557939454.851 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-05-15T16:57:35.168Z,1557939455.168 [MissionManager](IMPORTANT): Started mission Startup
2019-05-15T16:57:35.169Z,1557939455.169 [Startup] Running Loop=1
2019-05-15T16:57:35.169Z,1557939455.169 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-15T16:57:35.169Z,1557939455.169 [Startup:A.GoToSurface] Running Loop=1
2019-05-15T16:57:35.169Z,1557939455.169 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-15T16:57:35.170Z,1557939455.170 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-15T16:57:35.170Z,1557939455.170 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-15T16:57:35.170Z,1557939455.170 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-15T16:57:35.171Z,1557939455.171 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-15T16:57:35.171Z,1557939455.171 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-15T16:57:35.172Z,1557939455.172 [Startup:StartupSatComms] Running Loop=1
2019-05-15T16:57:35.173Z,1557939455.173 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-15T16:57:35.173Z,1557939455.173 [Startup:StartupSatComms:A] Running Loop=1
2019-05-15T16:57:35.570Z,1557939455.570 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-15T16:57:52.102Z,1557939472.102 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T16:57:52.102Z,1557939472.102 [NAL9602] Communications Fault, FailCount= 2
2019-05-15T16:57:52.103Z,1557939472.103 [NAL9602](ERROR): Communications Fault
2019-05-15T16:57:52.139Z,1557939472.139 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T16:57:52.508Z,1557939472.508 [NAL9602](INFO): Powering down
2019-05-15T16:57:53.419Z,1557939473.419 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T16:57:53.419Z,1557939473.419 [NAL9602] No Fault, FailCount= 2
2019-05-15T16:58:12.737Z,1557939492.737 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T16:58:12.737Z,1557939492.737 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-15T16:58:12.737Z,1557939492.737 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T16:58:12.748Z,1557939492.748 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T16:58:13.158Z,1557939493.158 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T16:58:13.158Z,1557939493.158 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-15T16:58:22.806Z,1557939502.806 [NAL9602](INFO): Powering up NAL9602
2019-05-15T16:58:35.358Z,1557939515.358 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-15T16:57:35.2Z
2019-05-15T16:58:35.358Z,1557939515.358 [Startup:StartupSatComms:A] Stopped
2019-05-15T16:58:35.358Z,1557939515.358 [Startup:StartupSatComms:B] Running Loop=1
2019-05-15T16:58:35.760Z,1557939515.760 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-15T16:58:43.414Z,1557939523.414 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T16:58:43.414Z,1557939523.414 [NAL9602] Communications Fault, FailCount= 3
2019-05-15T16:58:43.414Z,1557939523.414 [NAL9602](ERROR): Communications Fault
2019-05-15T16:58:43.444Z,1557939523.444 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T16:58:43.814Z,1557939523.814 [NAL9602](INFO): Powering down
2019-05-15T16:58:44.669Z,1557939524.669 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T16:58:44.669Z,1557939524.669 [NAL9602] No Fault, FailCount= 3
2019-05-15T16:59:14.118Z,1557939554.118 [NAL9602](INFO): Powering up NAL9602
2019-05-15T16:59:34.725Z,1557939574.725 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T16:59:34.725Z,1557939574.725 [NAL9602] Communications Fault, FailCount= 4
2019-05-15T16:59:34.725Z,1557939574.725 [NAL9602](ERROR): Communications Fault
2019-05-15T16:59:34.755Z,1557939574.755 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T16:59:35.126Z,1557939575.126 [NAL9602](INFO): Powering down
2019-05-15T16:59:35.550Z,1557939575.550 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-15T16:58:35.4Z
2019-05-15T16:59:35.550Z,1557939575.550 [Startup:StartupSatComms:B] Stopped
2019-05-15T16:59:35.551Z,1557939575.551 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-15T16:59:35.551Z,1557939575.551 [Startup:StartupSatComms] Stopped
2019-05-15T16:59:35.551Z,1557939575.551 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-15T16:59:35.551Z,1557939575.551 [Startup](INFO): Completed Startup
2019-05-15T16:59:35.552Z,1557939575.552 [MissionManager](INFO): Startup is completed.
2019-05-15T16:59:35.552Z,1557939575.552 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-15T16:59:35.552Z,1557939575.552 [Startup] Stopped
2019-05-15T16:59:35.552Z,1557939575.552 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-15T16:59:35.552Z,1557939575.552 [Startup:A.GoToSurface] Stopped
2019-05-15T16:59:35.552Z,1557939575.552 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-15T16:59:35.958Z,1557939575.958 [MissionManager](IMPORTANT): Started mission Default
2019-05-15T16:59:35.958Z,1557939575.958 [Default] Running Loop=1
2019-05-15T16:59:35.958Z,1557939575.958 [Default](DEBUG): Aggregate::initialize Default
2019-05-15T16:59:35.958Z,1557939575.958 [Default:B.GoToSurface] Running Loop=1
2019-05-15T16:59:35.958Z,1557939575.958 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-15T16:59:35.959Z,1557939575.959 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-15T16:59:35.959Z,1557939575.959 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-15T16:59:35.959Z,1557939575.959 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-15T16:59:35.960Z,1557939575.960 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-15T16:59:35.960Z,1557939575.960 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-15T16:59:35.960Z,1557939575.960 [Default:A.Wait] Running Loop=1
2019-05-15T16:59:35.960Z,1557939575.960 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-15T16:59:35.999Z,1557939575.999 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T16:59:35.999Z,1557939575.999 [NAL9602] No Fault, FailCount= 4
2019-05-15T16:59:49.286Z,1557939589.286 [Default:A.Wait](INFO): Done Waiting.
2019-05-15T16:59:49.286Z,1557939589.286 [Default:A.Wait] Stopped
2019-05-15T16:59:49.286Z,1557939589.286 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-15T16:59:49.691Z,1557939589.691 [Default:CheckIn] Running Loop=1
2019-05-15T16:59:49.691Z,1557939589.691 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-15T16:59:49.692Z,1557939589.692 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-15T16:59:50.146Z,1557939590.146 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-15T17:00:05.428Z,1557939605.428 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:00:13.987Z,1557939613.987 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:00:13.987Z,1557939613.987 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-15T17:00:13.987Z,1557939613.987 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:00:13.998Z,1557939613.998 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:00:14.344Z,1557939614.344 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:00:14.344Z,1557939614.344 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-15T17:00:26.032Z,1557939626.032 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:00:26.032Z,1557939626.032 [NAL9602] Communications Fault, FailCount= 5
2019-05-15T17:00:26.032Z,1557939626.032 [NAL9602](ERROR): Communications Fault
2019-05-15T17:00:26.067Z,1557939626.067 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:00:26.067Z,1557939626.067 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-05-15T17:00:26.430Z,1557939626.430 [NAL9602](INFO): Powering down
2019-05-15T17:00:26.521Z,1557939626.521 [CommandLine](FAULT): Scheduling is paused
2019-05-15T17:00:26.521Z,1557939626.521 [CBIT](INFO): Critical error at 20190515T170026
2019-05-15T17:00:26.522Z,1557939626.522 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-15T17:02:14.786Z,1557939734.786 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:02:14.786Z,1557939734.786 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-05-15T17:02:14.786Z,1557939734.786 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:02:14.796Z,1557939734.796 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:02:15.163Z,1557939735.163 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:02:15.163Z,1557939735.163 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-05-15T17:02:26.047Z,1557939746.047 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:02:26.047Z,1557939746.047 [NAL9602] No Fault, FailCount= 5
2019-05-15T17:02:26.422Z,1557939746.422 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:02:47.022Z,1557939767.022 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:02:47.022Z,1557939767.022 [NAL9602] Communications Fault, FailCount= 1
2019-05-15T17:02:47.022Z,1557939767.022 [NAL9602](ERROR): Communications Fault
2019-05-15T17:02:47.059Z,1557939767.059 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:02:47.426Z,1557939767.426 [NAL9602](INFO): Powering down
2019-05-15T17:02:48.267Z,1557939768.267 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:02:48.267Z,1557939768.267 [NAL9602] No Fault, FailCount= 1
2019-05-15T17:02:55.131Z,1557939775.131 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-05-15T17:02:55.135Z,1557939775.135 [BPC1](INFO): Received data from all battery sticks.
2019-05-15T17:03:17.723Z,1557939797.723 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:03:38.334Z,1557939818.334 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:03:38.334Z,1557939818.334 [NAL9602] Communications Fault, FailCount= 2
2019-05-15T17:03:38.334Z,1557939818.334 [NAL9602](ERROR): Communications Fault
2019-05-15T17:03:38.364Z,1557939818.364 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:03:38.738Z,1557939818.738 [NAL9602](INFO): Powering down
2019-05-15T17:03:39.574Z,1557939819.574 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:03:39.574Z,1557939819.574 [NAL9602] No Fault, FailCount= 2
2019-05-15T17:04:09.038Z,1557939849.038 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:04:15.925Z,1557939855.925 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:04:15.925Z,1557939855.925 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-05-15T17:04:15.925Z,1557939855.925 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:04:15.935Z,1557939855.935 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:04:16.343Z,1557939856.343 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:04:16.343Z,1557939856.343 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-05-15T17:04:29.642Z,1557939869.642 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:04:29.642Z,1557939869.642 [NAL9602] Communications Fault, FailCount= 3
2019-05-15T17:04:29.642Z,1557939869.642 [NAL9602](ERROR): Communications Fault
2019-05-15T17:04:29.672Z,1557939869.672 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:04:30.042Z,1557939870.042 [NAL9602](INFO): Powering down
2019-05-15T17:04:30.885Z,1557939870.885 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:04:30.885Z,1557939870.885 [NAL9602] No Fault, FailCount= 3
2019-05-15T17:04:49.854Z,1557939889.854 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-15T16:59:49.7Z
2019-05-15T17:04:49.854Z,1557939889.854 [Default:CheckIn:Read_GPS] Stopped
2019-05-15T17:04:49.855Z,1557939889.855 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-15T17:04:50.267Z,1557939890.267 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-15T17:05:00.342Z,1557939900.342 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:05:20.946Z,1557939920.946 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:05:20.946Z,1557939920.946 [NAL9602] Communications Fault, FailCount= 4
2019-05-15T17:05:20.946Z,1557939920.946 [NAL9602](ERROR): Communications Fault
2019-05-15T17:05:20.983Z,1557939920.983 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:05:21.354Z,1557939921.354 [NAL9602](INFO): Powering down
2019-05-15T17:05:22.194Z,1557939922.194 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:05:22.194Z,1557939922.194 [NAL9602] No Fault, FailCount= 4
2019-05-15T17:05:51.647Z,1557939951.647 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:06:12.251Z,1557939972.251 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:06:12.251Z,1557939972.251 [NAL9602] Communications Fault, FailCount= 5
2019-05-15T17:06:12.251Z,1557939972.251 [NAL9602](ERROR): Communications Fault
2019-05-15T17:06:12.280Z,1557939972.280 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:06:12.281Z,1557939972.281 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-05-15T17:06:12.666Z,1557939972.666 [NAL9602](INFO): Powering down
2019-05-15T17:06:12.701Z,1557939972.701 [CBIT](INFO): Critical error at 20190515T170612
2019-05-15T17:06:17.117Z,1557939977.117 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:06:17.117Z,1557939977.117 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-05-15T17:06:17.117Z,1557939977.117 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:06:17.145Z,1557939977.145 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:06:17.554Z,1557939977.554 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:06:17.554Z,1557939977.554 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-05-15T17:08:12.272Z,1557940092.272 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:08:12.272Z,1557940092.272 [NAL9602] No Fault, FailCount= 5
2019-05-15T17:08:12.646Z,1557940092.646 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:08:18.325Z,1557940098.325 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:08:18.325Z,1557940098.325 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-05-15T17:08:18.325Z,1557940098.325 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:08:18.335Z,1557940098.335 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:08:18.742Z,1557940098.742 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:08:18.742Z,1557940098.742 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-05-15T17:08:33.254Z,1557940113.254 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:08:33.254Z,1557940113.254 [NAL9602] Communications Fault, FailCount= 1
2019-05-15T17:08:33.254Z,1557940113.254 [NAL9602](ERROR): Communications Fault
2019-05-15T17:08:33.299Z,1557940113.299 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:08:33.658Z,1557940113.658 [NAL9602](INFO): Powering down
2019-05-15T17:08:34.495Z,1557940114.495 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:08:34.495Z,1557940114.495 [NAL9602] No Fault, FailCount= 1
2019-05-15T17:09:03.958Z,1557940143.958 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:09:24.558Z,1557940164.558 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:09:24.558Z,1557940164.558 [NAL9602] Communications Fault, FailCount= 2
2019-05-15T17:09:24.558Z,1557940164.558 [NAL9602](ERROR): Communications Fault
2019-05-15T17:09:24.610Z,1557940164.610 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:09:24.962Z,1557940164.962 [NAL9602](INFO): Powering down
2019-05-15T17:09:25.802Z,1557940165.802 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:09:25.802Z,1557940165.802 [NAL9602] No Fault, FailCount= 2
2019-05-15T17:09:55.259Z,1557940195.259 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:10:15.863Z,1557940215.863 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:10:15.863Z,1557940215.863 [NAL9602] Communications Fault, FailCount= 3
2019-05-15T17:10:15.863Z,1557940215.863 [NAL9602](ERROR): Communications Fault
2019-05-15T17:10:15.915Z,1557940215.915 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:10:16.270Z,1557940216.270 [NAL9602](INFO): Powering down
2019-05-15T17:10:17.155Z,1557940217.155 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:10:17.155Z,1557940217.155 [NAL9602] No Fault, FailCount= 3
2019-05-15T17:10:19.142Z,1557940219.142 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:10:19.142Z,1557940219.142 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-05-15T17:10:19.142Z,1557940219.142 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:10:19.153Z,1557940219.153 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:10:19.563Z,1557940219.563 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:10:19.563Z,1557940219.563 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-05-15T17:10:21.395Z,1557940221.395 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005092
2019-05-15T17:10:46.578Z,1557940246.578 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:11:07.186Z,1557940267.186 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:11:07.186Z,1557940267.186 [NAL9602] Communications Fault, FailCount= 4
2019-05-15T17:11:07.186Z,1557940267.186 [NAL9602](ERROR): Communications Fault
2019-05-15T17:11:07.216Z,1557940267.216 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:11:07.588Z,1557940267.588 [NAL9602](INFO): Powering down
2019-05-15T17:11:08.437Z,1557940268.437 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:11:08.437Z,1557940268.437 [NAL9602] No Fault, FailCount= 4
2019-05-15T17:11:37.886Z,1557940297.886 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:11:58.498Z,1557940318.498 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:11:58.498Z,1557940318.498 [NAL9602] Communications Fault, FailCount= 5
2019-05-15T17:11:58.498Z,1557940318.498 [NAL9602](ERROR): Communications Fault
2019-05-15T17:11:58.578Z,1557940318.578 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:11:58.578Z,1557940318.578 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-05-15T17:11:58.902Z,1557940318.902 [NAL9602](INFO): Powering down
2019-05-15T17:11:58.974Z,1557940318.974 [CBIT](INFO): Critical error at 20190515T171158
2019-05-15T17:12:19.940Z,1557940339.940 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:12:19.940Z,1557940339.940 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-05-15T17:12:19.940Z,1557940339.940 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:12:19.951Z,1557940339.951 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:12:20.361Z,1557940340.361 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:12:20.361Z,1557940340.361 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-05-15T17:13:58.525Z,1557940438.525 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:13:58.525Z,1557940438.525 [NAL9602] No Fault, FailCount= 5
2019-05-15T17:13:58.891Z,1557940438.891 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:14:19.495Z,1557940459.495 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:14:19.495Z,1557940459.495 [NAL9602] Communications Fault, FailCount= 1
2019-05-15T17:14:19.495Z,1557940459.495 [NAL9602](ERROR): Communications Fault
2019-05-15T17:14:19.524Z,1557940459.524 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:14:19.906Z,1557940459.906 [NAL9602](INFO): Powering down
2019-05-15T17:14:20.742Z,1557940460.742 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:14:20.742Z,1557940460.742 [NAL9602] No Fault, FailCount= 1
2019-05-15T17:14:21.139Z,1557940461.139 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:14:21.139Z,1557940461.139 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-05-15T17:14:21.139Z,1557940461.139 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:14:21.150Z,1557940461.150 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:14:21.585Z,1557940461.585 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:14:21.585Z,1557940461.585 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-05-15T17:14:50.199Z,1557940490.199 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:15:10.803Z,1557940510.803 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:15:10.803Z,1557940510.803 [NAL9602] Communications Fault, FailCount= 2
2019-05-15T17:15:10.803Z,1557940510.803 [NAL9602](ERROR): Communications Fault
2019-05-15T17:15:10.839Z,1557940510.839 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:15:11.210Z,1557940511.210 [NAL9602](INFO): Powering down
2019-05-15T17:15:12.044Z,1557940512.044 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:15:12.044Z,1557940512.044 [NAL9602] No Fault, FailCount= 2
2019-05-15T17:15:41.511Z,1557940541.511 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:16:02.115Z,1557940562.115 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:16:02.115Z,1557940562.115 [NAL9602] Communications Fault, FailCount= 3
2019-05-15T17:16:02.115Z,1557940562.115 [NAL9602](ERROR): Communications Fault
2019-05-15T17:16:02.167Z,1557940562.167 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:16:02.519Z,1557940562.519 [NAL9602](INFO): Powering down
2019-05-15T17:16:03.397Z,1557940563.397 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:16:03.398Z,1557940563.398 [NAL9602] No Fault, FailCount= 3
2019-05-15T17:16:10.778Z,1557940570.778 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20190508T221548/Courier0028.lzma
2019-05-15T17:16:11.583Z,1557940571.583 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0028.lzma.bak
2019-05-15T17:16:11.583Z,1557940571.583 [DataOverHttps](INFO): SBD MOMSN=11067573
2019-05-15T17:16:22.336Z,1557940582.336 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:16:22.336Z,1557940582.336 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-05-15T17:16:22.336Z,1557940582.336 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:16:22.379Z,1557940582.379 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:16:22.754Z,1557940582.754 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20190508T221548/Courier0031.lzma
2019-05-15T17:16:22.757Z,1557940582.757 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:16:22.757Z,1557940582.757 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-05-15T17:16:23.559Z,1557940583.559 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0031.lzma.bak
2019-05-15T17:16:23.559Z,1557940583.559 [DataOverHttps](INFO): SBD MOMSN=11067599
2019-05-15T17:16:32.818Z,1557940592.818 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:16:53.422Z,1557940613.422 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:16:53.422Z,1557940613.422 [NAL9602] Communications Fault, FailCount= 4
2019-05-15T17:16:53.423Z,1557940613.423 [NAL9602](ERROR): Communications Fault
2019-05-15T17:16:53.490Z,1557940613.490 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:16:53.823Z,1557940613.823 [NAL9602](INFO): Powering down
2019-05-15T17:16:54.678Z,1557940614.678 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:16:54.678Z,1557940614.678 [NAL9602] No Fault, FailCount= 4
2019-05-15T17:17:24.123Z,1557940644.123 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:17:44.730Z,1557940664.730 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:17:44.730Z,1557940664.730 [NAL9602] Communications Fault, FailCount= 5
2019-05-15T17:17:44.730Z,1557940664.730 [NAL9602](ERROR): Communications Fault
2019-05-15T17:17:44.815Z,1557940664.815 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:17:44.816Z,1557940664.816 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-05-15T17:17:45.138Z,1557940665.138 [NAL9602](INFO): Powering down
2019-05-15T17:17:45.200Z,1557940665.200 [CBIT](INFO): Critical error at 20190515T171744
2019-05-15T17:18:23.171Z,1557940703.171 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:18:23.171Z,1557940703.171 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-05-15T17:18:23.171Z,1557940703.171 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:18:23.238Z,1557940703.238 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:18:23.608Z,1557940703.608 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:18:23.608Z,1557940703.608 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-05-15T17:19:44.760Z,1557940784.760 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:19:44.760Z,1557940784.760 [NAL9602] No Fault, FailCount= 5
2019-05-15T17:19:45.138Z,1557940785.138 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:20:05.742Z,1557940805.742 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:20:05.742Z,1557940805.742 [NAL9602] Communications Fault, FailCount= 1
2019-05-15T17:20:05.742Z,1557940805.742 [NAL9602](ERROR): Communications Fault
2019-05-15T17:20:05.790Z,1557940805.790 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:20:06.146Z,1557940806.146 [NAL9602](INFO): Powering down
2019-05-15T17:20:06.985Z,1557940806.985 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:20:06.985Z,1557940806.985 [NAL9602] No Fault, FailCount= 1
2019-05-15T17:20:24.351Z,1557940824.351 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:20:24.351Z,1557940824.351 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-05-15T17:20:24.351Z,1557940824.351 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:20:24.361Z,1557940824.361 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:20:24.766Z,1557940824.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:20:24.767Z,1557940824.767 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-05-15T17:20:36.446Z,1557940836.446 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:20:57.047Z,1557940857.047 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:20:57.047Z,1557940857.047 [NAL9602] Communications Fault, FailCount= 2
2019-05-15T17:20:57.047Z,1557940857.047 [NAL9602](ERROR): Communications Fault
2019-05-15T17:20:57.083Z,1557940857.083 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:20:57.454Z,1557940857.454 [NAL9602](INFO): Powering down
2019-05-15T17:20:58.297Z,1557940858.297 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:20:58.297Z,1557940858.297 [NAL9602] No Fault, FailCount= 2
2019-05-15T17:21:27.754Z,1557940887.754 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:21:48.360Z,1557940908.360 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:21:48.360Z,1557940908.360 [NAL9602] Communications Fault, FailCount= 3
2019-05-15T17:21:48.360Z,1557940908.360 [NAL9602](ERROR): Communications Fault
2019-05-15T17:21:48.407Z,1557940908.407 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:21:48.762Z,1557940908.762 [NAL9602](INFO): Powering down
2019-05-15T17:21:49.630Z,1557940909.630 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:21:49.631Z,1557940909.631 [NAL9602] No Fault, FailCount= 3
2019-05-15T17:22:19.068Z,1557940939.068 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:22:25.550Z,1557940945.550 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:22:25.550Z,1557940945.550 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-05-15T17:22:25.550Z,1557940945.550 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:22:25.560Z,1557940945.560 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:22:25.967Z,1557940945.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:22:25.967Z,1557940945.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-05-15T17:22:39.666Z,1557940959.666 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:22:39.666Z,1557940959.666 [NAL9602] Communications Fault, FailCount= 4
2019-05-15T17:22:39.666Z,1557940959.666 [NAL9602](ERROR): Communications Fault
2019-05-15T17:22:39.730Z,1557940959.730 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:22:40.070Z,1557940960.070 [NAL9602](INFO): Powering down
2019-05-15T17:22:40.907Z,1557940960.907 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:22:40.907Z,1557940960.907 [NAL9602] No Fault, FailCount= 4
2019-05-15T17:23:10.375Z,1557940990.375 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:23:30.971Z,1557941010.971 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:23:30.971Z,1557941010.971 [NAL9602] Communications Fault, FailCount= 5
2019-05-15T17:23:30.971Z,1557941010.971 [NAL9602](ERROR): Communications Fault
2019-05-15T17:23:31.034Z,1557941011.034 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:23:31.034Z,1557941011.034 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-05-15T17:23:31.378Z,1557941011.378 [NAL9602](INFO): Powering down
2019-05-15T17:23:31.409Z,1557941011.409 [CBIT](INFO): Critical error at 20190515T172331
2019-05-15T17:24:26.746Z,1557941066.746 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:24:26.746Z,1557941066.746 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-05-15T17:24:26.746Z,1557941066.746 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:24:26.756Z,1557941066.756 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:24:27.162Z,1557941067.162 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:24:27.162Z,1557941067.162 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-05-15T17:24:42.554Z,1557941082.554 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20190508T221548/Courier0034.lzma
2019-05-15T17:24:43.359Z,1557941083.359 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0034.lzma.bak
2019-05-15T17:24:43.359Z,1557941083.359 [DataOverHttps](INFO): SBD MOMSN=11067636
2019-05-15T17:24:53.642Z,1557941093.642 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20190508T221548/Courier0037.lzma
2019-05-15T17:24:54.447Z,1557941094.447 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0037.lzma.bak
2019-05-15T17:24:54.447Z,1557941094.447 [DataOverHttps](INFO): SBD MOMSN=11067665
2019-05-15T17:25:04.790Z,1557941104.790 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20190508T221548/Courier0040.lzma
2019-05-15T17:25:05.595Z,1557941105.595 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0040.lzma.bak
2019-05-15T17:25:05.595Z,1557941105.595 [DataOverHttps](INFO): SBD MOMSN=11067743
2019-05-15T17:25:15.890Z,1557941115.890 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20190508T221548/Courier0043.lzma
2019-05-15T17:25:16.695Z,1557941116.695 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0043.lzma.bak
2019-05-15T17:25:16.695Z,1557941116.695 [DataOverHttps](INFO): SBD MOMSN=11067770
2019-05-15T17:25:31.023Z,1557941131.023 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:25:31.023Z,1557941131.023 [NAL9602] No Fault, FailCount= 5
2019-05-15T17:25:31.366Z,1557941131.366 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:25:51.970Z,1557941151.970 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:25:51.970Z,1557941151.970 [NAL9602] Communications Fault, FailCount= 1
2019-05-15T17:25:51.970Z,1557941151.970 [NAL9602](ERROR): Communications Fault
2019-05-15T17:25:52.038Z,1557941152.038 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:25:52.374Z,1557941152.374 [NAL9602](INFO): Powering down
2019-05-15T17:25:53.227Z,1557941153.227 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:25:53.227Z,1557941153.227 [NAL9602] No Fault, FailCount= 1
2019-05-15T17:26:22.674Z,1557941182.674 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:26:27.948Z,1557941187.948 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:26:27.948Z,1557941187.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2019-05-15T17:26:27.949Z,1557941187.949 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:26:27.960Z,1557941187.960 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:26:28.362Z,1557941188.362 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:26:28.363Z,1557941188.363 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2019-05-15T17:26:43.278Z,1557941203.278 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:26:43.278Z,1557941203.278 [NAL9602] Communications Fault, FailCount= 2
2019-05-15T17:26:43.278Z,1557941203.278 [NAL9602](ERROR): Communications Fault
2019-05-15T17:26:43.322Z,1557941203.322 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:26:43.682Z,1557941203.682 [NAL9602](INFO): Powering down
2019-05-15T17:26:44.526Z,1557941204.526 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:26:44.526Z,1557941204.526 [NAL9602] No Fault, FailCount= 2
2019-05-15T17:27:13.980Z,1557941233.980 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:27:34.592Z,1557941254.592 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:27:34.592Z,1557941254.592 [NAL9602] Communications Fault, FailCount= 3
2019-05-15T17:27:34.592Z,1557941254.592 [NAL9602](ERROR): Communications Fault
2019-05-15T17:27:34.621Z,1557941254.621 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:27:34.990Z,1557941254.990 [NAL9602](INFO): Powering down
2019-05-15T17:27:35.865Z,1557941255.865 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:27:35.865Z,1557941255.865 [NAL9602] No Fault, FailCount= 3
2019-05-15T17:28:05.292Z,1557941285.292 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:28:25.898Z,1557941305.898 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:28:25.898Z,1557941305.898 [NAL9602] Communications Fault, FailCount= 4
2019-05-15T17:28:25.898Z,1557941305.898 [NAL9602](ERROR): Communications Fault
2019-05-15T17:28:25.952Z,1557941305.952 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:28:26.302Z,1557941306.302 [NAL9602](INFO): Powering down
2019-05-15T17:28:27.170Z,1557941307.170 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-15T17:28:27.170Z,1557941307.170 [NAL9602] No Fault, FailCount= 4
2019-05-15T17:28:29.148Z,1557941309.148 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T17:28:29.148Z,1557941309.148 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2019-05-15T17:28:29.148Z,1557941309.148 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T17:28:29.158Z,1557941309.158 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T17:28:29.570Z,1557941309.570 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T17:28:29.570Z,1557941309.570 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2019-05-15T17:28:56.606Z,1557941336.606 [NAL9602](INFO): Powering up NAL9602
2019-05-15T17:29:17.211Z,1557941357.211 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-15T17:29:17.211Z,1557941357.211 [NAL9602] Communications Fault, FailCount= 5
2019-05-15T17:29:17.211Z,1557941357.211 [NAL9602](ERROR): Communications Fault
2019-05-15T17:29:17.270Z,1557941357.270 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-15T17:29:17.270Z,1557941357.270 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-05-15T17:29:17.615Z,1557941357.615 [NAL9602](INFO): Powering down
2019-05-15T17:29:17.658Z,1557941357.658 [CBIT](INFO): Critical error at 20190515T172917
2019-05-15T17:30:14.415Z,1557941414.415 [CommandLine](IMPORTANT): got command restart application
2019-05-15T17:30:15.421Z,1557941415.421 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T17:30:15.422Z,1557941415.422 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:15.505Z,1557941415.505 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-15T17:30:15.505Z,1557941415.505 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:15.506Z,1557941415.506 [CommandLine](INFO): Join timeout helper Thread ID is 1141
2019-05-15T17:30:15.506Z,1557941415.506 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-15T17:30:15.506Z,1557941415.506 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:15.507Z,1557941415.507 [NavChartDb](INFO): Join timeout helper Thread ID is 1142
2019-05-15T17:30:15.581Z,1557941415.581 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T17:30:15.581Z,1557941415.581 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:15.585Z,1557941415.585 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-15T17:30:15.585Z,1557941415.585 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:15.585Z,1557941415.585 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1143
2019-05-15T17:30:15.781Z,1557941415.781 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T17:30:15.782Z,1557941415.782 [WetLabsBB2FL](INFO): Powering down
2019-05-15T17:30:15.782Z,1557941415.782 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:15.785Z,1557941415.785 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-15T17:30:15.785Z,1557941415.785 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:15.786Z,1557941415.786 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1144
2019-05-15T17:30:15.904Z,1557941415.904 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T17:30:15.904Z,1557941415.904 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:15.913Z,1557941415.913 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-15T17:30:15.913Z,1557941415.913 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:15.913Z,1557941415.913 [Radio_Surface](INFO): Join timeout helper Thread ID is 1145
2019-05-15T17:30:16.197Z,1557941416.197 [Radio_Surface](INFO): Powering down
2019-05-15T17:30:16.198Z,1557941416.198 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T17:30:16.198Z,1557941416.198 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.200Z,1557941416.200 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-15T17:30:16.200Z,1557941416.200 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.200Z,1557941416.200 [DataOverHttps](INFO): Join timeout helper Thread ID is 1146
2019-05-15T17:30:16.345Z,1557941416.345 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T17:30:16.346Z,1557941416.346 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.350Z,1557941416.350 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-15T17:30:16.350Z,1557941416.350 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.350Z,1557941416.350 [logger](INFO): Join timeout helper Thread ID is 1147
2019-05-15T17:30:16.421Z,1557941416.421 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T17:30:16.421Z,1557941416.421 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.430Z,1557941416.430 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-15T17:30:16.430Z,1557941416.430 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.430Z,1557941416.430 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-15T17:30:16.430Z,1557941416.430 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.430Z,1557941416.430 [controlThread](INFO): Join timeout helper Thread ID is 1148
2019-05-15T17:30:16.593Z,1557941416.593 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T17:30:16.593Z,1557941416.593 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-15T17:30:16.594Z,1557941416.594 [NAL9602](INFO): Powering down
2019-05-15T17:30:16.595Z,1557941416.595 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-15T17:30:16.596Z,1557941416.596 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-15T17:30:16.596Z,1557941416.596 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-15T17:30:16.596Z,1557941416.596 [MissionManager](INFO): Uninitializing Mission Default
2019-05-15T17:30:16.596Z,1557941416.596 [Default] Stopped
2019-05-15T17:30:16.597Z,1557941416.597 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-15T17:30:16.597Z,1557941416.597 [Default:B.GoToSurface] Stopped
2019-05-15T17:30:16.597Z,1557941416.597 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-15T17:30:16.597Z,1557941416.597 [Default:CheckIn] Stopped
2019-05-15T17:30:16.597Z,1557941416.597 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-15T17:30:16.597Z,1557941416.597 [Default:CheckIn:Read_Iridium] Stopped
2019-05-15T17:30:16.599Z,1557941416.599 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-15T17:30:16.600Z,1557941416.600 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-15T17:30:16.600Z,1557941416.600 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-15T17:30:16.600Z,1557941416.600 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-15T17:30:16.600Z,1557941416.600 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-15T17:30:16.600Z,1557941416.600 [BuoyancyServo](INFO): Powering down
2019-05-15T17:30:16.613Z,1557941416.613 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-15T17:30:16.613Z,1557941416.613 [ElevatorServo](INFO): Powering down
2019-05-15T17:30:16.614Z,1557941416.614 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-15T17:30:16.614Z,1557941416.614 [MassServo](INFO): Powering down
2019-05-15T17:30:16.615Z,1557941416.615 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-15T17:30:16.615Z,1557941416.615 [RudderServo](INFO): Powering down
2019-05-15T17:30:16.616Z,1557941416.616 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-15T17:30:16.616Z,1557941416.616 [ThrusterServo](INFO): Powering down
2019-05-15T17:30:16.617Z,1557941416.617 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-15T17:30:16.617Z,1557941416.617 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-15T17:30:16.617Z,1557941416.617 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-15T17:30:16.617Z,1557941416.617 [CBIT](DEBUG): Powering off loads.
2019-05-15T17:30:16.629Z,1557941416.629 [CBIT](DEBUG): Disabling WDT.
2019-05-15T17:30:16.641Z,1557941416.641 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-15T17:30:16.642Z,1557941416.642 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.728Z,1557941416.728 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.734Z,1557941416.734 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.793Z,1557941416.793 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.795Z,1557941416.795 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.808Z,1557941416.808 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-15T17:30:16.863Z,1557941416.863 [logger ThreadHandler](INFO): Thread cancelled.