2019-05-24T15:40:30.064Z,1558712430.064 [Supervisor](DEBUG): Initializing supervisor.
2019-05-24T15:40:30.067Z,1558712430.067 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-24T15:40:30.068Z,1558712430.068 [SyncHandler](INFO): Protected caller Thread ID is 1279
2019-05-24T15:40:30.068Z,1558712430.068 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-24T15:40:30.069Z,1558712430.069 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-24T15:40:30.069Z,1558712430.069 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1280
2019-05-24T15:40:30.072Z,1558712430.072 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-24T15:40:30.085Z,1558712430.085 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-24T15:40:30.086Z,1558712430.086 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-24T15:40:30.086Z,1558712430.086 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1281
2019-05-24T15:40:30.087Z,1558712430.087 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-24T15:40:30.088Z,1558712430.088 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-24T15:40:30.088Z,1558712430.088 [logger ThreadHandler](INFO): Protected caller Thread ID is 1282
2019-05-24T15:40:30.090Z,1558712430.090 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-24T15:40:30.090Z,1558712430.090 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-24T15:40:30.092Z,1558712430.092 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-24T15:40:30.189Z,1558712430.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-24T15:40:30.189Z,1558712430.189 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-24T15:40:30.729Z,1558712430.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-24T15:40:30.729Z,1558712430.729 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-24T15:40:30.829Z,1558712430.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-24T15:40:30.830Z,1558712430.830 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-24T15:40:30.932Z,1558712430.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-24T15:40:30.933Z,1558712430.933 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-24T15:40:31.016Z,1558712431.016 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-24T15:40:31.159Z,1558712431.159 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-24T15:40:31.160Z,1558712431.160 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-24T15:40:31.455Z,1558712431.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-24T15:40:31.456Z,1558712431.456 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-24T15:40:31.913Z,1558712431.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-24T15:40:31.914Z,1558712431.914 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-24T15:40:32.060Z,1558712432.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-24T15:40:32.061Z,1558712432.061 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-24T15:40:32.262Z,1558712432.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-24T15:40:32.263Z,1558712432.263 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-24T15:40:32.714Z,1558712432.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-24T15:40:32.715Z,1558712432.715 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-24T15:40:32.933Z,1558712432.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-24T15:40:32.934Z,1558712432.934 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-24T15:40:33.137Z,1558712433.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-24T15:40:33.138Z,1558712433.138 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-24T15:40:33.522Z,1558712433.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-24T15:40:33.523Z,1558712433.523 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-24T15:40:33.857Z,1558712433.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-24T15:40:33.859Z,1558712433.859 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-05-24T15:40:33.860Z,1558712433.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-05-24T15:40:33.944Z,1558712433.944 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-05-24T15:40:34.166Z,1558712434.166 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-05-24T15:40:34.350Z,1558712434.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-05-24T15:40:34.546Z,1558712434.546 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-05-24T15:40:34.709Z,1558712434.709 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-05-24T15:40:34.968Z,1558712434.968 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-05-24T15:40:35.195Z,1558712435.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-24T15:40:35.195Z,1558712435.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-05-24T15:40:35.290Z,1558712435.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-05-24T15:40:35.390Z,1558712435.390 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-05-24T15:40:35.520Z,1558712435.520 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-05-24T15:40:35.623Z,1558712435.623 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2019-05-24T15:40:35.624Z,1558712435.624 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-24T15:40:35.631Z,1558712435.631 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-24T15:40:35.715Z,1558712435.715 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-24T15:40:35.826Z,1558712435.826 [VerticalControl] Loaded
2019-05-24T15:40:35.827Z,1558712435.827 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-24T15:40:35.827Z,1558712435.827 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-24T15:40:35.894Z,1558712435.894 [HorizontalControl] Loaded
2019-05-24T15:40:35.894Z,1558712435.894 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-24T15:40:35.895Z,1558712435.895 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-24T15:40:35.900Z,1558712435.900 [SpeedControl] Loaded
2019-05-24T15:40:35.900Z,1558712435.900 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-24T15:40:35.901Z,1558712435.901 [LoopControl](DEBUG): Construct LoopControl.
2019-05-24T15:40:35.902Z,1558712435.902 [LoopControl] Loaded
2019-05-24T15:40:35.902Z,1558712435.902 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-24T15:40:35.902Z,1558712435.902 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-24T15:40:35.903Z,1558712435.903 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-24T15:40:35.956Z,1558712435.956 [DepthRateCalculator] Loaded
2019-05-24T15:40:35.956Z,1558712435.956 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-24T15:40:35.962Z,1558712435.962 [PitchRateCalculator] Loaded
2019-05-24T15:40:35.962Z,1558712435.962 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-24T15:40:35.977Z,1558712435.977 [SpeedCalculator] Loaded
2019-05-24T15:40:35.977Z,1558712435.977 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-24T15:40:35.997Z,1558712435.997 [TempGradientCalculator] Loaded
2019-05-24T15:40:35.998Z,1558712435.998 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-24T15:40:36.002Z,1558712436.002 [YawRateCalculator] Loaded
2019-05-24T15:40:36.003Z,1558712436.003 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-24T15:40:36.031Z,1558712436.031 [ElevatorOffsetCalculator] Loaded
2019-05-24T15:40:36.031Z,1558712436.031 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-24T15:40:36.032Z,1558712436.032 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-24T15:40:36.032Z,1558712436.032 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-24T15:40:36.072Z,1558712436.072 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-24T15:40:36.073Z,1558712436.073 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-24T15:40:36.179Z,1558712436.179 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-24T15:40:36.179Z,1558712436.179 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-24T15:40:36.504Z,1558712436.504 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-24T15:40:36.505Z,1558712436.505 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-24T15:40:36.624Z,1558712436.624 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-24T15:40:36.625Z,1558712436.625 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-24T15:40:37.066Z,1558712437.066 [DataOverHttps] Loaded
2019-05-24T15:40:37.066Z,1558712437.066 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-24T15:40:37.071Z,1558712437.071 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2019-05-24T15:40:37.071Z,1558712437.071 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1361
2019-05-24T15:40:37.085Z,1558712437.085 [Depth_Keller] Loaded
2019-05-24T15:40:37.085Z,1558712437.085 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-24T15:40:37.136Z,1558712437.136 [Micromodem] Loaded
2019-05-24T15:40:37.136Z,1558712437.136 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-05-24T15:40:37.231Z,1558712437.231 [NAL9602] Loaded
2019-05-24T15:40:37.231Z,1558712437.231 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-24T15:40:37.247Z,1558712437.247 [Onboard] Loaded
2019-05-24T15:40:37.247Z,1558712437.247 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-24T15:40:37.250Z,1558712437.250 [Radio_Surface] Loaded
2019-05-24T15:40:37.251Z,1558712437.251 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-24T15:40:37.252Z,1558712437.252 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2019-05-24T15:40:37.252Z,1558712437.252 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1362
2019-05-24T15:40:38.690Z,1558712438.690 [BPC1] Loaded
2019-05-24T15:40:38.690Z,1558712438.690 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-24T15:40:38.690Z,1558712438.690 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-24T15:40:38.691Z,1558712438.691 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-24T15:40:38.807Z,1558712438.807 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-24T15:40:38.808Z,1558712438.808 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-24T15:40:38.832Z,1558712438.832 [NavChart] Loaded
2019-05-24T15:40:38.832Z,1558712438.832 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-24T15:40:38.836Z,1558712438.836 [UniversalFixResidualReporter] Loaded
2019-05-24T15:40:38.836Z,1558712438.836 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-24T15:40:38.837Z,1558712438.837 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-24T15:40:38.837Z,1558712438.837 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-24T15:40:38.990Z,1558712438.990 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-24T15:40:39.000Z,1558712439.000 [SBIT] Loaded
2019-05-24T15:40:39.001Z,1558712439.001 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-24T15:40:39.001Z,1558712439.001 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-24T15:40:39.013Z,1558712439.013 [IBIT] Loaded
2019-05-24T15:40:39.013Z,1558712439.013 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-24T15:40:39.016Z,1558712439.016 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-24T15:40:39.150Z,1558712439.150 [CBIT] Loaded
2019-05-24T15:40:39.151Z,1558712439.151 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-24T15:40:39.151Z,1558712439.151 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-24T15:40:39.152Z,1558712439.152 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-24T15:40:39.275Z,1558712439.275 [BuoyancyServo] Loaded
2019-05-24T15:40:39.276Z,1558712439.276 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-24T15:40:39.287Z,1558712439.287 [ElevatorServo] Loaded
2019-05-24T15:40:39.287Z,1558712439.287 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-24T15:40:39.298Z,1558712439.298 [MassServo] Loaded
2019-05-24T15:40:39.298Z,1558712439.298 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-24T15:40:39.309Z,1558712439.309 [RudderServo] Loaded
2019-05-24T15:40:39.309Z,1558712439.309 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-24T15:40:39.320Z,1558712439.320 [ThrusterServo] Loaded
2019-05-24T15:40:39.320Z,1558712439.320 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-24T15:40:39.321Z,1558712439.321 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-24T15:40:39.321Z,1558712439.321 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-24T15:40:39.368Z,1558712439.368 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-24T15:40:39.369Z,1558712439.369 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-24T15:40:40.267Z,1558712440.267 [CTD_NeilBrown] Loaded
2019-05-24T15:40:40.267Z,1558712440.267 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-24T15:40:40.269Z,1558712440.269 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 40A7C4E0
2019-05-24T15:40:40.269Z,1558712440.269 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1363
2019-05-24T15:40:40.283Z,1558712440.283 [PAR_Licor] Loaded
2019-05-24T15:40:40.283Z,1558712440.283 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-24T15:40:40.329Z,1558712440.329 [WetLabsBB2FL] Loaded
2019-05-24T15:40:40.330Z,1558712440.330 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-24T15:40:40.331Z,1558712440.331 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAC4E0
2019-05-24T15:40:40.331Z,1558712440.331 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1364
2019-05-24T15:40:40.332Z,1558712440.332 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-24T15:40:40.335Z,1558712440.335 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-24T15:40:40.336Z,1558712440.336 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-24T15:40:40.343Z,1558712440.343 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-24T15:40:40.344Z,1558712440.344 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADC4E0
2019-05-24T15:40:40.344Z,1558712440.344 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1365
2019-05-24T15:40:40.349Z,1558712440.349 [Supervisor](INFO): Main Thread ID is 1278
2019-05-24T15:40:40.349Z,1558712440.349 [Supervisor](DEBUG): Running supervisor.
2019-05-24T15:40:40.350Z,1558712440.350 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1366
2019-05-24T15:40:40.354Z,1558712440.354 [controlThread ThreadHandler](INFO): Handler Thread ID is 1367
2019-05-24T15:40:40.354Z,1558712440.354 [controlThread](DEBUG): Initializing ControlThread
2019-05-24T15:40:40.355Z,1558712440.355 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-24T15:40:40.357Z,1558712440.357 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-24T15:40:40.358Z,1558712440.358 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-24T15:40:40.358Z,1558712440.358 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-24T15:40:40.358Z,1558712440.358 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-24T15:40:40.359Z,1558712440.359 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-24T15:40:40.359Z,1558712440.359 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-24T15:40:40.359Z,1558712440.359 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-24T15:40:40.360Z,1558712440.360 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-24T15:40:40.360Z,1558712440.360 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-24T15:40:40.366Z,1558712440.366 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-24T15:40:40.367Z,1558712440.367 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-24T15:40:40.367Z,1558712440.367 [SBIT](INFO): Initialize SBIT Component.
2019-05-24T15:40:40.367Z,1558712440.367 [SBIT](IMPORTANT): git: 2019-05-01-9-g5be6d91
2019-05-24T15:40:40.368Z,1558712440.368 [SBIT](INFO): git hash: 5be6d91bfd1d3ef858080230e47d423d1f938b96
2019-05-24T15:40:40.368Z,1558712440.368 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-24T15:40:40.368Z,1558712440.368 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-24T15:40:40.369Z,1558712440.369 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-05-24T15:40:40.370Z,1558712440.370 [IBIT](INFO): Initialize IBIT Component.
2019-05-24T15:40:40.370Z,1558712440.370 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-24T15:40:40.371Z,1558712440.371 [logger ThreadHandler](INFO): Handler Thread ID is 1368
2019-05-24T15:40:40.385Z,1558712440.385 [CBIT](DEBUG): Initialized mux pins.
2019-05-24T15:40:40.385Z,1558712440.385 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-24T15:40:40.389Z,1558712440.389 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1369
2019-05-24T15:40:40.390Z,1558712440.390 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-24T15:40:40.397Z,1558712440.397 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1370
2019-05-24T15:40:40.405Z,1558712440.405 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1371
2019-05-24T15:40:40.409Z,1558712440.409 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-24T15:40:40.409Z,1558712440.409 [CBIT](DEBUG): Initializing heartbeat.
2019-05-24T15:40:40.417Z,1558712440.417 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1372
2019-05-24T15:40:40.418Z,1558712440.418 [WetLabsBB2FL](INFO): Powering down
2019-05-24T15:40:40.445Z,1558712440.445 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1373
2019-05-24T15:40:40.451Z,1558712440.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-24T15:40:40.451Z,1558712440.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-24T15:40:40.451Z,1558712440.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-24T15:40:40.451Z,1558712440.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-24T15:40:40.452Z,1558712440.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-24T15:40:40.452Z,1558712440.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-24T15:40:40.452Z,1558712440.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-24T15:40:40.452Z,1558712440.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-24T15:40:40.452Z,1558712440.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-24T15:40:40.453Z,1558712440.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-24T15:40:40.453Z,1558712440.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-24T15:40:40.453Z,1558712440.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-24T15:40:40.453Z,1558712440.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-24T15:40:40.454Z,1558712440.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-24T15:40:40.454Z,1558712440.454 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-24T15:40:40.454Z,1558712440.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-24T15:40:40.481Z,1558712440.481 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-24T15:40:40.481Z,1558712440.481 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-24T15:40:40.517Z,1558712440.517 [CBIT](DEBUG): Backplane powered.
2019-05-24T15:40:40.522Z,1558712440.522 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-24T15:40:40.546Z,1558712440.546 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-24T15:40:40.573Z,1558712440.573 [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-24T15:40:40.574Z,1558712440.574 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-24T15:40:40.660Z,1558712440.660 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-24T15:40:40.661Z,1558712440.661 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-24T15:40:40.663Z,1558712440.663 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-24T15:40:40.709Z,1558712440.709 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-24T15:40:40.712Z,1558712440.712 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-24T15:40:40.738Z,1558712440.738 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-24T15:40:40.741Z,1558712440.741 [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-24T15:40:40.756Z,1558712440.756 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,Micromodem,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-24T15:40:40.801Z,1558712440.801 [Radio_Surface](INFO): Powering up
2019-05-24T15:40:40.817Z,1558712440.817 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-05-24T15:40:40.887Z,1558712440.887 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-24T15:40:40.912Z,1558712440.912 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-24T15:40:40.937Z,1558712440.937 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-24T15:40:40.938Z,1558712440.938 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-24T15:40:40.957Z,1558712440.957 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-24T15:40:40.958Z,1558712440.958 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-24T15:40:40.965Z,1558712440.965 [MassServo](DEBUG): Initializing MassServo.
2019-05-24T15:40:40.966Z,1558712440.966 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-24T15:40:40.985Z,1558712440.985 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-24T15:40:40.986Z,1558712440.986 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-24T15:40:40.995Z,1558712440.995 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-24T15:40:41.467Z,1558712441.467 [Micromodem](INFO): Powering up
2019-05-24T15:40:41.468Z,1558712441.468 [Micromodem](DEBUG): Initializing Micromodem.
2019-05-24T15:40:41.853Z,1558712441.853 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-05-24T15:40:41.853Z,1558712441.853 [RudderServo](FAULT): Rudder failed to initialize
2019-05-24T15:40:41.853Z,1558712441.853 [RudderServo] Communications Fault, FailCount= 1
2019-05-24T15:40:41.853Z,1558712441.853 [RudderServo](ERROR): Communications Fault
2019-05-24T15:40:41.964Z,1558712441.964 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-24T15:40:42.166Z,1558712442.166 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-24T15:40:42.166Z,1558712442.166 [RudderServo](INFO): Powering down
2019-05-24T15:40:42.822Z,1558712442.822 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-24T15:40:42.942Z,1558712442.942 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-24T15:40:42.946Z,1558712442.946 [CBIT](INFO): Clearing failed state for component RudderServo
2019-05-24T15:40:42.946Z,1558712442.946 [RudderServo] No Fault, FailCount= 1
2019-05-24T15:40:46.014Z,1558712446.014 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-05-24T15:40:49.671Z,1558712449.671 [Micromodem](INFO): Nmea in: $CAERR,222003,NMEA,13,Missing dollar sign*0C

2019-05-24T15:40:49.671Z,1558712449.671 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222003,NMEA,13,Missing dollar sign*0C

2019-05-24T15:40:52.973Z,1558712452.973 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.111309
2019-05-24T15:40:57.346Z,1558712457.346 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-05-24T15:40:57.750Z,1558712457.750 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222007,AUV,0.93.0.34*0B

2019-05-24T15:40:59.366Z,1558712459.366 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-05-24T15:40:59.367Z,1558712459.367 [Micromodem](INFO): Nmea out: $CCCFG,SRC,7*37

2019-05-24T15:40:59.771Z,1558712459.771 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222016,INIT,0.93.0.34*53

2019-05-24T15:41:00.174Z,1558712460.174 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222017,AUV,0.93.0.34*0A

2019-05-24T15:41:07.447Z,1558712467.447 [NAL9602](INFO): Powering up NAL9602
2019-05-24T15:41:09.122Z,1558712469.122 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-24T15:41:09.156Z,1558712469.156 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-24T15:41:09.466Z,1558712469.466 [Micromodem](INFO): Nmea in: $CACFG,SRC,7*35

2019-05-24T15:41:09.466Z,1558712469.466 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:41:09.793Z,1558712469.793 [CommandLine](IMPORTANT): got command get depth
2019-05-24T15:41:09.794Z,1558712469.794 [CommandLine](IMPORTANT): depth 0.825429 m
2019-05-24T15:41:09.898Z,1558712469.898 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222027,AUV,0.93.0.34*09

2019-05-24T15:41:19.775Z,1558712479.775 [Micromodem](INFO): Nmea in: $CAERR,222027,NMEA,17,Argument value out of range*01

2019-05-24T15:41:19.775Z,1558712479.775 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222027,NMEA,17,Argument value out of range*01

2019-05-24T15:41:19.915Z,1558712479.915 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001547
CHAN A1 (24V): 0.003477
CHAN A2 (12V): -0.007435
CHAN A3 (5V): -0.001656
CHAN B0 (3.3V): 0.000628
CHAN B1 (3.15aV): 0.000049
CHAN B2 (3.15bV): -0.000048
CHAN B3 (GND): 0.002397
OPEN: -0.000177
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-24T15:41:20.594Z,1558712480.594 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:41:20.989Z,1558712480.989 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222037,AUV,0.93.0.34*08

2019-05-24T15:41:28.370Z,1558712488.370 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-24T15:41:28.370Z,1558712488.370 [NAL9602] Communications Fault, FailCount= 1
2019-05-24T15:41:28.370Z,1558712488.370 [NAL9602](ERROR): Communications Fault
2019-05-24T15:41:28.537Z,1558712488.537 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-24T15:41:28.774Z,1558712488.774 [NAL9602](INFO): Powering down
2019-05-24T15:41:29.583Z,1558712489.583 [Micromodem](INFO): Nmea in: $CAERR,222038,NMEA,17,Argument value out of range*0F

2019-05-24T15:41:29.583Z,1558712489.583 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222038,NMEA,17,Argument value out of range*0F

2019-05-24T15:41:29.917Z,1558712489.917 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-24T15:41:29.917Z,1558712489.917 [NAL9602] No Fault, FailCount= 1
2019-05-24T15:41:31.602Z,1558712491.602 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:41:32.007Z,1558712492.007 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222047,AUV,0.93.0.34*0F

2019-05-24T15:41:39.679Z,1558712499.679 [Micromodem](INFO): Nmea in: $CAERR,222049,NMEA,17,Argument value out of range*09

2019-05-24T15:41:39.680Z,1558712499.680 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222049,NMEA,17,Argument value out of range*09

2019-05-24T15:41:42.918Z,1558712502.918 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:41:43.323Z,1558712503.323 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222057,AUV,0.93.0.34*0E

2019-05-24T15:41:49.787Z,1558712509.787 [Micromodem](INFO): Nmea in: $CAERR,222100,NMEA,17,Argument value out of range*05

2019-05-24T15:41:49.787Z,1558712509.787 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222100,NMEA,17,Argument value out of range*05

2019-05-24T15:41:54.299Z,1558712514.299 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:41:54.703Z,1558712514.703 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222107,AUV,0.93.0.34*0A

2019-05-24T15:41:59.147Z,1558712519.147 [NAL9602](INFO): Powering up NAL9602
2019-05-24T15:41:59.551Z,1558712519.551 [Micromodem](INFO): Nmea in: $CAERR,222112,NMEA,17,Argument value out of range*06

2019-05-24T15:41:59.552Z,1558712519.552 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222112,NMEA,17,Argument value out of range*06

2019-05-24T15:42:02.807Z,1558712522.807 [SBIT](IMPORTANT): SBIT PASSED
2019-05-24T15:42:02.825Z,1558712522.825 [CommandLine](IMPORTANT): got command configSet list
2019-05-24T15:42:02.826Z,1558712522.826 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-24T15:42:02.826Z,1558712522.826 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-05-24T15:42:02.827Z,1558712522.827 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=0 count;

2019-05-24T15:42:02.827Z,1558712522.827 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-05-24T15:42:02.827Z,1558712522.827 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-05-24T15:42:02.827Z,1558712522.827 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-05-24T15:42:02.827Z,1558712522.827 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-05-24T15:42:03.222Z,1558712523.222 [MissionManager](IMPORTANT): Started mission Startup
2019-05-24T15:42:03.222Z,1558712523.222 [Startup] Running Loop=1
2019-05-24T15:42:03.222Z,1558712523.222 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-24T15:42:03.222Z,1558712523.222 [Startup:A.GoToSurface] Running Loop=1
2019-05-24T15:42:03.222Z,1558712523.222 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-24T15:42:03.223Z,1558712523.223 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-24T15:42:03.223Z,1558712523.223 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-24T15:42:03.224Z,1558712523.224 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-24T15:42:03.224Z,1558712523.224 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-24T15:42:03.224Z,1558712523.224 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-24T15:42:03.226Z,1558712523.226 [Startup:StartupSatComms] Running Loop=1
2019-05-24T15:42:03.226Z,1558712523.226 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-24T15:42:03.226Z,1558712523.226 [Startup:StartupSatComms:A] Running Loop=1
2019-05-24T15:42:03.622Z,1558712523.622 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-24T15:42:05.610Z,1558712525.610 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:42:06.011Z,1558712526.011 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222117,AUV,0.93.0.34*0B

2019-05-24T15:42:09.651Z,1558712529.651 [Micromodem](INFO): Nmea in: $CAERR,222123,NMEA,17,Argument value out of range*04

2019-05-24T15:42:09.652Z,1558712529.652 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222123,NMEA,17,Argument value out of range*04

2019-05-24T15:42:16.922Z,1558712536.922 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:42:17.327Z,1558712537.327 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222127,AUV,0.93.0.34*08

2019-05-24T15:42:19.747Z,1558712539.747 [Micromodem](INFO): Nmea in: $CAERR,222134,NMEA,17,Argument value out of range*02

2019-05-24T15:42:19.748Z,1558712539.748 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222134,NMEA,17,Argument value out of range*02

2019-05-24T15:42:19.749Z,1558712539.749 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-24T15:42:19.749Z,1558712539.749 [NAL9602] Communications Fault, FailCount= 2
2019-05-24T15:42:19.749Z,1558712539.749 [NAL9602](ERROR): Communications Fault
2019-05-24T15:42:19.779Z,1558712539.779 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-24T15:42:20.162Z,1558712540.162 [NAL9602](INFO): Powering down
2019-05-24T15:42:20.988Z,1558712540.988 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-24T15:42:20.988Z,1558712540.988 [NAL9602] No Fault, FailCount= 2
2019-05-24T15:42:28.234Z,1558712548.234 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:42:28.639Z,1558712548.639 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222137,AUV,0.93.0.34*09

2019-05-24T15:42:29.851Z,1558712549.851 [Micromodem](INFO): Nmea in: $CAERR,222146,NMEA,17,Argument value out of range*07

2019-05-24T15:42:29.851Z,1558712549.851 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222146,NMEA,17,Argument value out of range*07

2019-05-24T15:42:39.547Z,1558712559.547 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222147,AUV,0.93.0.34*0E

2019-05-24T15:42:39.548Z,1558712559.548 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:42:39.951Z,1558712559.951 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222157,AUV,0.93.0.34*0F

2019-05-24T15:42:40.381Z,1558712560.381 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-24T15:42:40.381Z,1558712560.381 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-24T15:42:40.381Z,1558712560.381 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-24T15:42:40.391Z,1558712560.391 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-24T15:42:40.795Z,1558712560.795 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-24T15:42:40.795Z,1558712560.795 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-24T15:42:41.166Z,1558712561.166 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,222157,AUV,0.93.0.34*0F
, available: $CAERR,222157,NMEA,17,Argument value out of range*07
E@xZ
2019-05-24T15:42:41.166Z,1558712561.166 [Micromodem] Communications Fault, FailCount= 1
2019-05-24T15:42:41.166Z,1558712561.166 [Micromodem](ERROR): Communications Fault
2019-05-24T15:42:41.269Z,1558712561.269 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-05-24T15:42:41.566Z,1558712561.566 [Micromodem](INFO): Powering down
2019-05-24T15:42:42.424Z,1558712562.424 [CBIT](INFO): Clearing failed state for component Micromodem
2019-05-24T15:42:42.424Z,1558712562.424 [Micromodem] No Fault, FailCount= 1
2019-05-24T15:42:45.202Z,1558712565.202 [Micromodem](INFO): Powering up
2019-05-24T15:42:45.202Z,1558712565.202 [Micromodem](DEBUG): Initializing Micromodem.
2019-05-24T15:42:50.455Z,1558712570.455 [NAL9602](INFO): Powering up NAL9602
2019-05-24T15:42:50.858Z,1558712570.858 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:42:51.263Z,1558712571.263 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222207,AUV,0.93.0.34*09

2019-05-24T15:42:59.743Z,1558712579.743 [Micromodem](INFO): Nmea in: $CAERR,222208,NMEA,17,Argument value out of range*0E

2019-05-24T15:42:59.744Z,1558712579.744 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222208,NMEA,17,Argument value out of range*0E

2019-05-24T15:43:02.170Z,1558712582.170 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:43:02.575Z,1558712582.575 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222217,AUV,0.93.0.34*08

2019-05-24T15:43:03.421Z,1558712583.421 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-24T15:42:03.2Z
2019-05-24T15:43:03.421Z,1558712583.421 [Startup:StartupSatComms:A] Stopped
2019-05-24T15:43:03.422Z,1558712583.422 [Startup:StartupSatComms:B] Running Loop=1
2019-05-24T15:43:03.827Z,1558712583.827 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-24T15:43:06.044Z,1558712586.044 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20190508T221548/Courier0115.lzma
2019-05-24T15:43:06.855Z,1558712586.855 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0115.lzma.bak
2019-05-24T15:43:06.855Z,1558712586.855 [DataOverHttps](INFO): SBD MOMSN=11179128
2019-05-24T15:43:09.847Z,1558712589.847 [Micromodem](INFO): Nmea in: $CAERR,222219,NMEA,17,Argument value out of range*0E

2019-05-24T15:43:09.847Z,1558712589.847 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222219,NMEA,17,Argument value out of range*0E

2019-05-24T15:43:11.062Z,1558712591.062 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-24T15:43:11.062Z,1558712591.062 [NAL9602] Communications Fault, FailCount= 3
2019-05-24T15:43:11.063Z,1558712591.063 [NAL9602](ERROR): Communications Fault
2019-05-24T15:43:11.110Z,1558712591.110 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-24T15:43:11.464Z,1558712591.464 [NAL9602](INFO): Powering down
2019-05-24T15:43:12.318Z,1558712592.318 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-24T15:43:12.318Z,1558712592.318 [NAL9602] No Fault, FailCount= 3
2019-05-24T15:43:13.483Z,1558712593.483 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:43:13.883Z,1558712593.883 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222227,AUV,0.93.0.34*0B

2019-05-24T15:43:18.034Z,1558712598.034 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20190508T221548/Courier0118.lzma
2019-05-24T15:43:18.858Z,1558712598.858 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0118.lzma.bak
2019-05-24T15:43:18.859Z,1558712598.859 [DataOverHttps](INFO): SBD MOMSN=11179154
2019-05-24T15:43:19.955Z,1558712599.955 [Micromodem](INFO): Nmea in: $CAERR,222231,NMEA,17,Argument value out of range*04

2019-05-24T15:43:19.955Z,1558712599.955 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222231,NMEA,17,Argument value out of range*04

2019-05-24T15:43:24.794Z,1558712604.794 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:43:25.199Z,1558712605.199 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222237,AUV,0.93.0.34*0A

2019-05-24T15:43:29.643Z,1558712609.643 [Micromodem](INFO): Nmea in: $CAERR,222242,NMEA,17,Argument value out of range*00

2019-05-24T15:43:29.643Z,1558712609.643 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222242,NMEA,17,Argument value out of range*00

2019-05-24T15:43:29.874Z,1558712609.874 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20190508T221548/Courier0121.lzma
2019-05-24T15:43:30.679Z,1558712610.679 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0121.lzma.bak
2019-05-24T15:43:30.679Z,1558712610.679 [DataOverHttps](INFO): SBD MOMSN=11179180
2019-05-24T15:43:36.106Z,1558712616.106 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:43:36.511Z,1558712616.511 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222247,AUV,0.93.0.34*0D

2019-05-24T15:43:39.743Z,1558712619.743 [Micromodem](INFO): Nmea in: $CAERR,222253,NMEA,17,Argument value out of range*00

2019-05-24T15:43:39.743Z,1558712619.743 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222253,NMEA,17,Argument value out of range*00

2019-05-24T15:43:41.654Z,1558712621.654 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20190508T221548/Courier0124.lzma
2019-05-24T15:43:41.759Z,1558712621.759 [NAL9602](INFO): Powering up NAL9602
2019-05-24T15:43:42.458Z,1558712622.458 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0124.lzma.bak
2019-05-24T15:43:42.459Z,1558712622.459 [DataOverHttps](INFO): SBD MOMSN=11179205
2019-05-24T15:43:47.426Z,1558712627.426 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:43:47.823Z,1558712627.823 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222257,AUV,0.93.0.34*0C

2019-05-24T15:43:49.843Z,1558712629.843 [Micromodem](INFO): Nmea in: $CAERR,222305,NMEA,17,Argument value out of range*02

2019-05-24T15:43:49.843Z,1558712629.843 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222305,NMEA,17,Argument value out of range*02

2019-05-24T15:43:53.342Z,1558712633.342 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20190508T221548/Courier0127.lzma
2019-05-24T15:43:54.146Z,1558712634.146 [DataOverHttps](INFO): Moved sent file to Logs/20190508T221548/Courier0127.lzma.bak
2019-05-24T15:43:54.147Z,1558712634.147 [DataOverHttps](INFO): SBD MOMSN=11179231
2019-05-24T15:43:58.738Z,1558712638.738 [Micromodem](ERROR): Nmea resend: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-24T15:43:59.143Z,1558712639.143 [Micromodem](ERROR): CFG response from modem unexpected: $CAREV,222307,AUV,0.93.0.34*08

2019-05-24T15:43:59.515Z,1558712639.515 [CommandLine](IMPORTANT): got command quit
2019-05-24T15:43:59.943Z,1558712639.943 [Micromodem](INFO): Nmea in: $CAERR,222316,NMEA,17,Argument value out of range*00

2019-05-24T15:43:59.943Z,1558712639.943 [Micromodem](ERROR): CFG response from modem unexpected: $CAERR,222316,NMEA,17,Argument value out of range*00

2019-05-24T15:44:00.521Z,1558712640.521 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-05-24T15:44:00.521Z,1558712640.521 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T15:44:00.521Z,1558712640.521 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:00.522Z,1558712640.522 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-24T15:44:00.522Z,1558712640.522 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:00.523Z,1558712640.523 [CommandLine](INFO): Join timeout helper Thread ID is 1395
2019-05-24T15:44:00.533Z,1558712640.533 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-24T15:44:00.533Z,1558712640.533 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:00.533Z,1558712640.533 [NavChartDb](INFO): Join timeout helper Thread ID is 1396
2019-05-24T15:44:00.749Z,1558712640.749 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T15:44:00.749Z,1558712640.749 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:00.753Z,1558712640.753 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-24T15:44:00.753Z,1558712640.753 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:00.753Z,1558712640.753 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1397
2019-05-24T15:44:01.077Z,1558712641.077 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T15:44:01.077Z,1558712641.077 [WetLabsBB2FL](INFO): Powering down
2019-05-24T15:44:01.078Z,1558712641.078 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:01.085Z,1558712641.085 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-24T15:44:01.085Z,1558712641.085 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:01.085Z,1558712641.085 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1398
2019-05-24T15:44:01.269Z,1558712641.269 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T15:44:01.269Z,1558712641.269 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:01.281Z,1558712641.281 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-24T15:44:01.281Z,1558712641.281 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:01.281Z,1558712641.281 [Radio_Surface](INFO): Join timeout helper Thread ID is 1399
2019-05-24T15:44:01.681Z,1558712641.681 [Radio_Surface](INFO): Powering down
2019-05-24T15:44:01.682Z,1558712641.682 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T15:44:01.682Z,1558712641.682 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:01.702Z,1558712641.702 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-24T15:44:01.702Z,1558712641.702 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:01.702Z,1558712641.702 [DataOverHttps](INFO): Join timeout helper Thread ID is 1400
2019-05-24T15:44:02.366Z,1558712642.366 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-24T15:44:02.367Z,1558712642.367 [NAL9602] Communications Fault, FailCount= 4
2019-05-24T15:44:02.367Z,1558712642.367 [NAL9602](ERROR): Communications Fault
2019-05-24T15:44:02.543Z,1558712642.543 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-24T15:44:02.770Z,1558712642.770 [NAL9602](INFO): Powering down
2019-05-24T15:44:03.625Z,1558712643.625 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-24T15:43:03.4Z
2019-05-24T15:44:03.626Z,1558712643.626 [Startup:StartupSatComms:B] Stopped
2019-05-24T15:44:03.626Z,1558712643.626 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-24T15:44:03.626Z,1558712643.626 [Startup:StartupSatComms] Stopped
2019-05-24T15:44:03.626Z,1558712643.626 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-24T15:44:03.627Z,1558712643.627 [Startup](INFO): Completed Startup
2019-05-24T15:44:03.627Z,1558712643.627 [MissionManager](INFO): Startup is completed.
2019-05-24T15:44:03.627Z,1558712643.627 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-24T15:44:03.627Z,1558712643.627 [Startup] Stopped
2019-05-24T15:44:03.627Z,1558712643.627 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-24T15:44:03.627Z,1558712643.627 [Startup:A.GoToSurface] Stopped
2019-05-24T15:44:03.627Z,1558712643.627 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-24T15:44:03.646Z,1558712643.646 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-24T15:44:03.646Z,1558712643.646 [NAL9602] No Fault, FailCount= 4
2019-05-24T15:44:04.037Z,1558712644.037 [MissionManager](IMPORTANT): Started mission Default
2019-05-24T15:44:04.037Z,1558712644.037 [Default] Running Loop=1
2019-05-24T15:44:04.037Z,1558712644.037 [Default](DEBUG): Aggregate::initialize Default
2019-05-24T15:44:04.037Z,1558712644.037 [Default:B.GoToSurface] Running Loop=1
2019-05-24T15:44:04.037Z,1558712644.037 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-24T15:44:04.037Z,1558712644.037 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-24T15:44:04.038Z,1558712644.038 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-24T15:44:04.038Z,1558712644.038 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-24T15:44:04.038Z,1558712644.038 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-24T15:44:04.039Z,1558712644.039 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-24T15:44:04.039Z,1558712644.039 [Default:A.Wait] Running Loop=1
2019-05-24T15:44:04.039Z,1558712644.039 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-24T15:44:04.929Z,1558712644.929 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T15:44:04.933Z,1558712644.933 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:04.941Z,1558712644.941 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-24T15:44:04.942Z,1558712644.942 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:04.942Z,1558712644.942 [logger](INFO): Join timeout helper Thread ID is 1401
2019-05-24T15:44:05.009Z,1558712645.009 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T15:44:05.009Z,1558712645.009 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:05.021Z,1558712645.021 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-24T15:44:05.022Z,1558712645.022 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:05.022Z,1558712645.022 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-24T15:44:05.022Z,1558712645.022 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:05.022Z,1558712645.022 [controlThread](INFO): Join timeout helper Thread ID is 1402
2019-05-24T15:44:05.185Z,1558712645.185 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T15:44:05.185Z,1558712645.185 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-24T15:44:05.186Z,1558712645.186 [Micromodem](INFO): Powering down
2019-05-24T15:44:05.281Z,1558712645.281 [NAL9602](INFO): Powering down
2019-05-24T15:44:05.283Z,1558712645.283 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-24T15:44:05.284Z,1558712645.284 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-24T15:44:05.285Z,1558712645.285 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-24T15:44:05.285Z,1558712645.285 [MissionManager](INFO): Uninitializing Mission Default
2019-05-24T15:44:05.285Z,1558712645.285 [Default] Stopped
2019-05-24T15:44:05.286Z,1558712645.286 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-24T15:44:05.286Z,1558712645.286 [Default:A.Wait] Stopped
2019-05-24T15:44:05.286Z,1558712645.286 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-24T15:44:05.286Z,1558712645.286 [Default:B.GoToSurface] Stopped
2019-05-24T15:44:05.286Z,1558712645.286 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-24T15:44:05.288Z,1558712645.288 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-24T15:44:05.288Z,1558712645.288 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-24T15:44:05.289Z,1558712645.289 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-24T15:44:05.289Z,1558712645.289 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-24T15:44:05.289Z,1558712645.289 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-24T15:44:05.289Z,1558712645.289 [BuoyancyServo](INFO): Powering down
2019-05-24T15:44:05.301Z,1558712645.301 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-24T15:44:05.301Z,1558712645.301 [ElevatorServo](INFO): Powering down
2019-05-24T15:44:05.302Z,1558712645.302 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-24T15:44:05.302Z,1558712645.302 [MassServo](INFO): Powering down
2019-05-24T15:44:05.303Z,1558712645.303 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-24T15:44:05.303Z,1558712645.303 [RudderServo](INFO): Powering down
2019-05-24T15:44:05.303Z,1558712645.303 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-24T15:44:05.304Z,1558712645.304 [ThrusterServo](INFO): Powering down
2019-05-24T15:44:05.304Z,1558712645.304 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-24T15:44:05.305Z,1558712645.305 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-24T15:44:05.305Z,1558712645.305 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-24T15:44:05.305Z,1558712645.305 [CBIT](DEBUG): Powering off loads.
2019-05-24T15:44:05.317Z,1558712645.317 [CBIT](DEBUG): Disabling WDT.
2019-05-24T15:44:05.329Z,1558712645.329 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-24T15:44:05.329Z,1558712645.329 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:05.410Z,1558712645.410 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:05.417Z,1558712645.417 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:05.467Z,1558712645.467 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:05.470Z,1558712645.470 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:05.478Z,1558712645.478 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-24T15:44:05.536Z,1558712645.536 [logger ThreadHandler](INFO): Thread cancelled.