2018-11-06T00:45:37.504Z,1541465137.504 [Supervisor](DEBUG): Initializing supervisor.
2018-11-06T00:45:37.507Z,1541465137.507 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-06T00:45:37.508Z,1541465137.508 [SyncHandler](INFO): Protected caller Thread ID is 912
2018-11-06T00:45:37.508Z,1541465137.508 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-06T00:45:37.509Z,1541465137.509 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-06T00:45:37.510Z,1541465137.510 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 913
2018-11-06T00:45:37.513Z,1541465137.513 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-06T00:45:37.525Z,1541465137.525 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-06T00:45:37.526Z,1541465137.526 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-06T00:45:37.526Z,1541465137.526 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 914
2018-11-06T00:45:37.527Z,1541465137.527 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-06T00:45:37.528Z,1541465137.528 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-06T00:45:37.529Z,1541465137.529 [logger ThreadHandler](INFO): Protected caller Thread ID is 915
2018-11-06T00:45:37.531Z,1541465137.531 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-06T00:45:37.531Z,1541465137.531 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-06T00:45:37.532Z,1541465137.532 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-06T00:45:37.997Z,1541465137.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-06T00:45:37.998Z,1541465137.998 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-06T00:45:38.477Z,1541465138.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-06T00:45:38.478Z,1541465138.478 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-06T00:45:38.684Z,1541465138.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-06T00:45:38.685Z,1541465138.685 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-06T00:45:38.845Z,1541465138.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-06T00:45:38.846Z,1541465138.846 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-06T00:45:39.371Z,1541465139.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-06T00:45:39.371Z,1541465139.371 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-06T00:45:39.470Z,1541465139.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-06T00:45:39.471Z,1541465139.471 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-06T00:45:39.619Z,1541465139.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-06T00:45:39.620Z,1541465139.620 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-06T00:45:39.723Z,1541465139.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-06T00:45:39.724Z,1541465139.724 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-06T00:45:39.830Z,1541465139.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-06T00:45:39.831Z,1541465139.831 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-06T00:45:40.196Z,1541465140.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-06T00:45:40.197Z,1541465140.197 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-06T00:45:40.414Z,1541465140.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-06T00:45:40.415Z,1541465140.415 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-06T00:45:40.641Z,1541465140.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-06T00:45:40.642Z,1541465140.642 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-06T00:45:40.725Z,1541465140.725 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-06T00:45:41.107Z,1541465141.107 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-06T00:45:41.107Z,1541465141.107 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-06T00:45:41.448Z,1541465141.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-06T00:45:41.450Z,1541465141.450 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2018-11-06T00:45:41.451Z,1541465141.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2018-11-06T00:45:41.538Z,1541465141.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2018-11-06T00:45:41.697Z,1541465141.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2018-11-06T00:45:41.799Z,1541465141.799 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2018-11-06T00:45:41.983Z,1541465141.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2018-11-06T00:45:42.067Z,1541465142.067 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2018-11-06T00:45:42.301Z,1541465142.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-06T00:45:42.301Z,1541465142.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2018-11-06T00:45:42.421Z,1541465142.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2018-11-06T00:45:42.526Z,1541465142.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2018-11-06T00:45:42.624Z,1541465142.624 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2018-11-06T00:45:42.731Z,1541465142.731 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2018-11-06T00:45:42.848Z,1541465142.848 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2018-11-06T00:45:42.848Z,1541465142.848 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-06T00:45:42.854Z,1541465142.854 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-06T00:45:42.899Z,1541465142.899 [DepthRateCalculator] Loaded
2018-11-06T00:45:42.899Z,1541465142.899 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-06T00:45:42.905Z,1541465142.905 [PitchRateCalculator] Loaded
2018-11-06T00:45:42.905Z,1541465142.905 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-06T00:45:42.922Z,1541465142.922 [SpeedCalculator] Loaded
2018-11-06T00:45:42.922Z,1541465142.922 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-06T00:45:42.944Z,1541465142.944 [TempGradientCalculator] Loaded
2018-11-06T00:45:42.944Z,1541465142.944 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-06T00:45:42.950Z,1541465142.950 [YawRateCalculator] Loaded
2018-11-06T00:45:42.950Z,1541465142.950 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-06T00:45:42.992Z,1541465142.992 [ElevatorOffsetCalculator] Loaded
2018-11-06T00:45:42.992Z,1541465142.992 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-06T00:45:42.993Z,1541465142.993 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-06T00:45:42.993Z,1541465142.993 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-06T00:45:43.243Z,1541465143.243 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-06T00:45:43.244Z,1541465143.244 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-06T00:45:43.546Z,1541465143.546 [DataOverHttps] Loaded
2018-11-06T00:45:43.546Z,1541465143.546 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-06T00:45:43.573Z,1541465143.573 [Depth_Keller] Loaded
2018-11-06T00:45:43.574Z,1541465143.574 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-06T00:45:43.578Z,1541465143.578 [DropWeight] Loaded
2018-11-06T00:45:43.579Z,1541465143.579 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-06T00:45:43.679Z,1541465143.679 [NAL9602] Loaded
2018-11-06T00:45:43.679Z,1541465143.679 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-06T00:45:43.696Z,1541465143.696 [Onboard] Loaded
2018-11-06T00:45:43.696Z,1541465143.696 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-06T00:45:43.707Z,1541465143.707 [Radio_Surface] Loaded
2018-11-06T00:45:43.707Z,1541465143.707 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-06T00:45:43.708Z,1541465143.708 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2018-11-06T00:45:43.708Z,1541465143.708 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 994
2018-11-06T00:45:43.715Z,1541465143.715 [SCPI] Loaded
2018-11-06T00:45:43.715Z,1541465143.715 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-11-06T00:45:45.992Z,1541465145.992 [BPC1] Loaded
2018-11-06T00:45:45.993Z,1541465145.993 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-06T00:45:45.993Z,1541465145.993 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-06T00:45:45.994Z,1541465145.994 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-06T00:45:46.060Z,1541465146.060 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-06T00:45:46.061Z,1541465146.061 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-06T00:45:46.159Z,1541465146.159 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-11-06T00:45:46.160Z,1541465146.160 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-11-06T00:45:46.215Z,1541465146.215 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-06T00:45:46.215Z,1541465146.215 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-06T00:45:46.232Z,1541465146.232 [NavChart] Loaded
2018-11-06T00:45:46.232Z,1541465146.232 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-06T00:45:46.237Z,1541465146.237 [UniversalFixResidualReporter] Loaded
2018-11-06T00:45:46.237Z,1541465146.237 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-06T00:45:46.237Z,1541465146.237 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-06T00:45:46.238Z,1541465146.238 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-06T00:45:46.467Z,1541465146.467 [CTD_NeilBrown] Loaded
2018-11-06T00:45:46.468Z,1541465146.468 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-11-06T00:45:46.469Z,1541465146.469 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-11-06T00:45:46.469Z,1541465146.469 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 995
2018-11-06T00:45:46.520Z,1541465146.520 [WetLabsBB2FL] Loaded
2018-11-06T00:45:46.521Z,1541465146.521 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-06T00:45:46.522Z,1541465146.522 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2018-11-06T00:45:46.522Z,1541465146.522 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 996
2018-11-06T00:45:46.523Z,1541465146.523 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-06T00:45:46.523Z,1541465146.523 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-06T00:45:46.594Z,1541465146.594 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-06T00:45:46.696Z,1541465146.696 [VerticalControl] Loaded
2018-11-06T00:45:46.697Z,1541465146.697 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-06T00:45:46.697Z,1541465146.697 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-06T00:45:46.769Z,1541465146.769 [HorizontalControl] Loaded
2018-11-06T00:45:46.769Z,1541465146.769 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-06T00:45:46.770Z,1541465146.770 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-06T00:45:46.775Z,1541465146.775 [SpeedControl] Loaded
2018-11-06T00:45:46.776Z,1541465146.776 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-06T00:45:46.776Z,1541465146.776 [LoopControl](DEBUG): Construct LoopControl.
2018-11-06T00:45:46.777Z,1541465146.777 [LoopControl] Loaded
2018-11-06T00:45:46.777Z,1541465146.777 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-06T00:45:46.778Z,1541465146.778 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-06T00:45:46.778Z,1541465146.778 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-06T00:45:46.791Z,1541465146.791 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-06T00:45:46.792Z,1541465146.792 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-06T00:45:46.927Z,1541465146.927 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-06T00:45:46.938Z,1541465146.938 [SBIT] Loaded
2018-11-06T00:45:46.939Z,1541465146.939 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-06T00:45:46.940Z,1541465146.940 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-06T00:45:46.951Z,1541465146.951 [IBIT] Loaded
2018-11-06T00:45:46.952Z,1541465146.952 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-06T00:45:46.955Z,1541465146.955 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-06T00:45:47.109Z,1541465147.109 [CBIT] Loaded
2018-11-06T00:45:47.109Z,1541465147.109 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-06T00:45:47.110Z,1541465147.110 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-06T00:45:47.110Z,1541465147.110 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-06T00:45:47.215Z,1541465147.215 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-06T00:45:47.216Z,1541465147.216 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-06T00:45:47.242Z,1541465147.242 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-06T00:45:47.242Z,1541465147.242 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-06T00:45:47.343Z,1541465147.343 [BuoyancyServo] Loaded
2018-11-06T00:45:47.343Z,1541465147.343 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-06T00:45:47.355Z,1541465147.355 [ElevatorServo] Loaded
2018-11-06T00:45:47.355Z,1541465147.355 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-06T00:45:47.366Z,1541465147.366 [MassServo] Loaded
2018-11-06T00:45:47.367Z,1541465147.367 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-06T00:45:47.378Z,1541465147.378 [RudderServo] Loaded
2018-11-06T00:45:47.379Z,1541465147.379 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-06T00:45:47.390Z,1541465147.390 [ThrusterServo] Loaded
2018-11-06T00:45:47.390Z,1541465147.390 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-06T00:45:47.390Z,1541465147.390 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-06T00:45:47.394Z,1541465147.394 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-06T00:45:47.395Z,1541465147.395 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-06T00:45:47.402Z,1541465147.402 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-06T00:45:47.403Z,1541465147.403 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2018-11-06T00:45:47.403Z,1541465147.403 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 997
2018-11-06T00:45:47.408Z,1541465147.408 [Supervisor](INFO): Main Thread ID is 795
2018-11-06T00:45:47.408Z,1541465147.408 [Supervisor](DEBUG): Running supervisor.
2018-11-06T00:45:47.409Z,1541465147.409 [CommandLine ThreadHandler](INFO): Handler Thread ID is 998
2018-11-06T00:45:47.411Z,1541465147.411 [controlThread ThreadHandler](INFO): Handler Thread ID is 999
2018-11-06T00:45:47.412Z,1541465147.412 [controlThread](DEBUG): Initializing ControlThread
2018-11-06T00:45:47.412Z,1541465147.412 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T00:45:47.413Z,1541465147.413 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-06T00:45:47.413Z,1541465147.413 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-06T00:45:47.413Z,1541465147.413 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-06T00:45:47.414Z,1541465147.414 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-06T00:45:47.414Z,1541465147.414 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-06T00:45:47.420Z,1541465147.420 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T00:45:47.421Z,1541465147.421 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:45:47.422Z,1541465147.422 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:45:47.422Z,1541465147.422 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T00:45:47.422Z,1541465147.422 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:45:47.423Z,1541465147.423 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:45:47.423Z,1541465147.423 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-06T00:45:47.423Z,1541465147.423 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-06T00:45:47.424Z,1541465147.424 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-06T00:45:47.426Z,1541465147.426 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-06T00:45:47.426Z,1541465147.426 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-06T00:45:47.427Z,1541465147.427 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-06T00:45:47.427Z,1541465147.427 [SBIT](INFO): Initialize SBIT Component.
2018-11-06T00:45:47.428Z,1541465147.428 [SBIT](IMPORTANT): git: 2018-11-02
2018-11-06T00:45:47.428Z,1541465147.428 [SBIT](INFO): git hash: 766718804c5d96cf896f07eddb44b232c8585615
2018-11-06T00:45:47.428Z,1541465147.428 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-06T00:45:47.428Z,1541465147.428 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-06T00:45:47.429Z,1541465147.429 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-11-06T00:45:47.430Z,1541465147.430 [IBIT](INFO): Initialize IBIT Component.
2018-11-06T00:45:47.431Z,1541465147.431 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-06T00:45:47.431Z,1541465147.431 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-06T00:45:47.432Z,1541465147.432 [logger ThreadHandler](INFO): Handler Thread ID is 1000
2018-11-06T00:45:47.450Z,1541465147.450 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1001
2018-11-06T00:45:47.456Z,1541465147.456 [Radio_Surface](INFO): Powering up
2018-11-06T00:45:47.462Z,1541465147.462 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1002
2018-11-06T00:45:47.474Z,1541465147.474 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1003
2018-11-06T00:45:47.475Z,1541465147.475 [WetLabsBB2FL](INFO): Powering down
2018-11-06T00:45:47.501Z,1541465147.501 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1004
2018-11-06T00:45:47.512Z,1541465147.512 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-06T00:45:47.513Z,1541465147.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-06T00:45:47.513Z,1541465147.513 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-06T00:45:47.513Z,1541465147.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-06T00:45:47.513Z,1541465147.513 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-06T00:45:47.513Z,1541465147.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-06T00:45:47.514Z,1541465147.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-06T00:45:47.514Z,1541465147.514 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-06T00:45:47.514Z,1541465147.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-06T00:45:47.514Z,1541465147.514 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-06T00:45:47.515Z,1541465147.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-06T00:45:47.515Z,1541465147.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-06T00:45:47.515Z,1541465147.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-06T00:45:47.515Z,1541465147.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-06T00:45:47.515Z,1541465147.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-06T00:45:47.516Z,1541465147.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-06T00:45:47.540Z,1541465147.540 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-06T00:45:47.550Z,1541465147.550 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T00:45:47.578Z,1541465147.578 [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>


2018-11-06T00:45:47.578Z,1541465147.578 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-06T00:45:47.650Z,1541465147.650 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-06T00:45:47.651Z,1541465147.651 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-06T00:45:47.653Z,1541465147.653 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T00:45:47.705Z,1541465147.705 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-06T00:45:47.708Z,1541465147.708 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-06T00:45:47.733Z,1541465147.733 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-06T00:45:47.736Z,1541465147.736 [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>


2018-11-06T00:45:47.742Z,1541465147.742 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-06T00:45:47.765Z,1541465147.765 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-06T00:45:48.226Z,1541465148.226 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-06T00:45:48.234Z,1541465148.234 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-06T00:45:48.248Z,1541465148.248 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-06T00:45:48.254Z,1541465148.254 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-06T00:45:48.280Z,1541465148.280 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-06T00:45:48.286Z,1541465148.286 [MassServo](DEBUG): Initializing MassServo.
2018-11-06T00:45:48.292Z,1541465148.292 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-06T00:45:48.298Z,1541465148.298 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-06T00:45:48.304Z,1541465148.304 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-06T00:45:48.310Z,1541465148.310 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-06T00:45:48.475Z,1541465148.475 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-06T00:45:48.475Z,1541465148.475 [DropWeight] Hardware Fault, FailCount= 1
2018-11-06T00:45:48.475Z,1541465148.475 [DropWeight](ERROR): Hardware Fault
2018-11-06T00:45:48.532Z,1541465148.532 [CommandLine](FAULT): Scheduling is paused
2018-11-06T00:45:48.533Z,1541465148.533 [CBIT](INFO): Critical error at 20181106T004548
2018-11-06T00:45:48.533Z,1541465148.533 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-06T00:45:48.535Z,1541465148.535 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-06T00:45:48.536Z,1541465148.536 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-06T00:45:49.194Z,1541465149.194 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-06T00:45:49.194Z,1541465149.194 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-06T00:45:49.194Z,1541465149.194 [BuoyancyServo] Communications Fault, FailCount= 1
2018-11-06T00:45:49.194Z,1541465149.194 [BuoyancyServo](ERROR): Communications Fault
2018-11-06T00:45:49.400Z,1541465149.400 [CBIT](INFO): Critical error at 20181106T004548
2018-11-06T00:45:49.402Z,1541465149.402 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-06T00:45:51.591Z,1541465151.591 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-06T00:45:51.591Z,1541465151.591 [BuoyancyServo] No Fault, FailCount= 1
2018-11-06T00:45:51.844Z,1541465151.844 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-06T00:45:51.963Z,1541465151.963 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-06T00:46:13.863Z,1541465173.863 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:46:15.922Z,1541465175.922 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-06T00:46:15.927Z,1541465175.927 [CBIT](IMPORTANT): Beginning ground fault scan
2018-11-06T00:46:18.531Z,1541465178.531 [CommandLine](IMPORTANT): got command failComponent
2018-11-06T00:46:18.532Z,1541465178.532 [CommandLine](IMPORTANT): Failed components:
2018-11-06T00:46:18.532Z,1541465178.532 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-11-06T00:46:27.003Z,1541465187.003 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008675
CHAN A1 (24V): 0.002980
CHAN A2 (12V): -0.005831
CHAN A3 (5V): -0.002627
CHAN B0 (3.3V): -0.001334
CHAN B1 (3.15aV): -0.001535
CHAN B2 (3.15bV): -0.001187
CHAN B3 (GND): -0.000245
OPEN: -0.001409
Full Scale Calc: 4.765 mA, -1.589 mA
2018-11-06T00:46:34.603Z,1541465194.603 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:46:34.603Z,1541465194.603 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T00:46:34.603Z,1541465194.603 [NAL9602](ERROR): Communications Fault
2018-11-06T00:46:34.779Z,1541465194.779 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:46:35.003Z,1541465195.003 [NAL9602](INFO): Powering down
2018-11-06T00:46:35.927Z,1541465195.927 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:46:35.927Z,1541465195.927 [NAL9602] No Fault, FailCount= 1
2018-11-06T00:47:05.140Z,1541465225.140 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:47:09.998Z,1541465229.998 [SBIT](IMPORTANT): SBIT PASSED
2018-11-06T00:47:10.038Z,1541465230.038 [CommandLine](IMPORTANT): got command configSet list
2018-11-06T00:47:10.038Z,1541465230.038 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-06T00:47:10.039Z,1541465230.039 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2018-11-06T00:47:10.039Z,1541465230.039 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-11-06T00:47:10.039Z,1541465230.039 [CommandLine](IMPORTANT): Depth_Keller.offset=2.34 decibar;

2018-11-06T00:47:10.039Z,1541465230.039 [CommandLine](IMPORTANT): Depth_Keller.scale=38.77 micropascal;

2018-11-06T00:47:10.040Z,1541465230.040 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=0 bool;

2018-11-06T00:47:10.376Z,1541465230.376 [MissionManager](IMPORTANT): Started mission Startup
2018-11-06T00:47:10.376Z,1541465230.376 [Startup] Running Loop=1
2018-11-06T00:47:10.376Z,1541465230.376 [Startup](DEBUG): Aggregate::initialize Startup
2018-11-06T00:47:10.376Z,1541465230.376 [Startup:A.GoToSurface] Running Loop=1
2018-11-06T00:47:10.376Z,1541465230.376 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-06T00:47:10.377Z,1541465230.377 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-06T00:47:10.378Z,1541465230.378 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-06T00:47:10.378Z,1541465230.378 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-06T00:47:10.378Z,1541465230.378 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-06T00:47:10.379Z,1541465230.379 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-06T00:47:10.413Z,1541465230.413 [Startup:StartupSatComms] Running Loop=1
2018-11-06T00:47:10.413Z,1541465230.413 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-11-06T00:47:10.413Z,1541465230.413 [Startup:StartupSatComms:A] Running Loop=1
2018-11-06T00:47:10.772Z,1541465230.772 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-11-06T00:47:26.347Z,1541465246.347 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:47:26.347Z,1541465246.347 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T00:47:26.347Z,1541465246.347 [NAL9602](ERROR): Communications Fault
2018-11-06T00:47:26.422Z,1541465246.422 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:47:26.739Z,1541465246.739 [NAL9602](INFO): Powering down
2018-11-06T00:47:27.583Z,1541465247.583 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:47:27.583Z,1541465247.583 [NAL9602] No Fault, FailCount= 2
2018-11-06T00:47:47.574Z,1541465267.574 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:47:47.574Z,1541465267.574 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-11-06T00:47:47.574Z,1541465267.574 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T00:47:47.575Z,1541465267.575 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:47:47.575Z,1541465267.575 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-11-06T00:47:47.576Z,1541465267.576 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T00:47:47.588Z,1541465267.588 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T00:47:47.588Z,1541465267.588 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T00:47:47.985Z,1541465267.985 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T00:47:47.985Z,1541465267.985 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-11-06T00:47:47.985Z,1541465267.985 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T00:47:47.985Z,1541465267.985 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-11-06T00:47:48.369Z,1541465268.369 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T00:47:48.385Z,1541465268.385 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:47:48.386Z,1541465268.386 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:47:48.386Z,1541465268.386 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T00:47:48.387Z,1541465268.387 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:47:48.387Z,1541465268.387 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:47:57.140Z,1541465277.140 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:48:10.784Z,1541465290.784 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-06T00:47:10.4Z
2018-11-06T00:48:10.784Z,1541465290.784 [Startup:StartupSatComms:A] Stopped
2018-11-06T00:48:10.784Z,1541465290.784 [Startup:StartupSatComms:B] Running Loop=1
2018-11-06T00:48:11.168Z,1541465291.168 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-06T00:48:19.055Z,1541465299.055 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:48:19.055Z,1541465299.055 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T00:48:19.055Z,1541465299.055 [NAL9602](ERROR): Communications Fault
2018-11-06T00:48:19.140Z,1541465299.140 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:48:19.156Z,1541465299.156 [NAL9602](INFO): Powering down
2018-11-06T00:48:19.952Z,1541465299.952 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004560
2018-11-06T00:48:20.011Z,1541465300.011 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:48:20.011Z,1541465300.011 [NAL9602] No Fault, FailCount= 3
2018-11-06T00:48:24.015Z,1541465304.015 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181106T000906/Courier0004.lzma
2018-11-06T00:48:24.812Z,1541465304.812 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000906/Courier0004.lzma.bak
2018-11-06T00:48:24.812Z,1541465304.812 [DataOverHttps](INFO): SBD MOMSN=8792757
2018-11-06T00:48:33.508Z,1541465313.508 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T001301/Courier0000.lzma
2018-11-06T00:48:34.308Z,1541465314.308 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001301/Courier0000.lzma.bak
2018-11-06T00:48:34.308Z,1541465314.308 [DataOverHttps](INFO): SBD MOMSN=8792759
2018-11-06T00:48:43.040Z,1541465323.040 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181106T001400/Courier0000.lzma
2018-11-06T00:48:43.840Z,1541465323.840 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001400/Courier0000.lzma.bak
2018-11-06T00:48:43.840Z,1541465323.840 [DataOverHttps](INFO): SBD MOMSN=8792762
2018-11-06T00:48:49.402Z,1541465329.402 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:48:52.579Z,1541465332.579 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20181106T001431/Courier0000.lzma
2018-11-06T00:48:53.376Z,1541465333.376 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001431/Courier0000.lzma.bak
2018-11-06T00:48:53.379Z,1541465333.379 [DataOverHttps](INFO): SBD MOMSN=8792767
2018-11-06T00:49:02.130Z,1541465342.130 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T001527/Courier0000.lzma
2018-11-06T00:49:02.928Z,1541465342.928 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001527/Courier0000.lzma.bak
2018-11-06T00:49:02.928Z,1541465342.928 [DataOverHttps](INFO): SBD MOMSN=8792775
2018-11-06T00:49:11.656Z,1541465351.656 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-06T00:48:10.8Z
2018-11-06T00:49:11.656Z,1541465351.656 [Startup:StartupSatComms:B] Stopped
2018-11-06T00:49:11.657Z,1541465351.657 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-11-06T00:49:11.657Z,1541465351.657 [Startup:StartupSatComms] Stopped
2018-11-06T00:49:11.657Z,1541465351.657 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-11-06T00:49:11.695Z,1541465351.695 [Startup](INFO): Completed Startup
2018-11-06T00:49:11.695Z,1541465351.695 [MissionManager](INFO): Startup is completed.
2018-11-06T00:49:11.695Z,1541465351.695 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-06T00:49:11.696Z,1541465351.696 [Startup] Stopped
2018-11-06T00:49:11.696Z,1541465351.696 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-11-06T00:49:11.696Z,1541465351.696 [Startup:A.GoToSurface] Stopped
2018-11-06T00:49:11.696Z,1541465351.696 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-06T00:49:11.746Z,1541465351.746 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T001621/Courier0000.lzma
2018-11-06T00:49:11.784Z,1541465351.784 [MissionManager](IMPORTANT): Started mission Default
2018-11-06T00:49:11.784Z,1541465351.784 [Default] Running Loop=1
2018-11-06T00:49:11.784Z,1541465351.784 [Default](DEBUG): Aggregate::initialize Default
2018-11-06T00:49:11.784Z,1541465351.784 [Default:B.GoToSurface] Running Loop=1
2018-11-06T00:49:11.784Z,1541465351.784 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-06T00:49:11.785Z,1541465351.785 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-06T00:49:11.785Z,1541465351.785 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-06T00:49:11.790Z,1541465351.790 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-06T00:49:11.790Z,1541465351.790 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-06T00:49:11.790Z,1541465351.790 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-06T00:49:11.791Z,1541465351.791 [Default:A.Wait] Running Loop=1
2018-11-06T00:49:11.791Z,1541465351.791 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-11-06T00:49:12.524Z,1541465352.524 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001621/Courier0000.lzma.bak
2018-11-06T00:49:12.525Z,1541465352.525 [DataOverHttps](INFO): SBD MOMSN=8792780
2018-11-06T00:49:12.553Z,1541465352.553 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:49:12.553Z,1541465352.553 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T00:49:12.553Z,1541465352.553 [NAL9602](ERROR): Communications Fault
2018-11-06T00:49:12.605Z,1541465352.605 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:49:12.930Z,1541465352.930 [NAL9602](INFO): Powering down
2018-11-06T00:49:16.512Z,1541465356.512 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:49:16.513Z,1541465356.513 [NAL9602] No Fault, FailCount= 4
2018-11-06T00:49:24.953Z,1541465364.953 [Default:A.Wait](INFO): Done Waiting.
2018-11-06T00:49:24.954Z,1541465364.954 [Default:A.Wait] Stopped
2018-11-06T00:49:24.954Z,1541465364.954 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T00:49:25.356Z,1541465365.356 [Default:CheckIn] Running Loop=1
2018-11-06T00:49:25.357Z,1541465365.357 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T00:49:25.357Z,1541465365.357 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T00:49:25.756Z,1541465365.756 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-11-06T00:49:43.327Z,1541465383.327 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:49:48.555Z,1541465388.555 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:49:48.555Z,1541465388.555 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-11-06T00:49:48.556Z,1541465388.556 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T00:49:48.558Z,1541465388.558 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:49:48.558Z,1541465388.558 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-11-06T00:49:48.558Z,1541465388.558 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T00:49:48.620Z,1541465388.620 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T00:49:48.620Z,1541465388.620 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T00:49:51.660Z,1541465391.660 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T00:49:51.660Z,1541465391.660 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-11-06T00:49:51.661Z,1541465391.661 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T00:49:51.661Z,1541465391.661 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-11-06T00:49:51.759Z,1541465391.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T00:49:51.760Z,1541465391.760 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:49:51.760Z,1541465391.760 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:49:51.760Z,1541465391.760 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T00:49:51.761Z,1541465391.761 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:49:51.761Z,1541465391.761 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:50:04.475Z,1541465404.475 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:50:04.475Z,1541465404.475 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T00:50:04.475Z,1541465404.475 [NAL9602](ERROR): Communications Fault
2018-11-06T00:50:04.585Z,1541465404.585 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:50:04.585Z,1541465404.585 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T00:50:04.875Z,1541465404.875 [NAL9602](INFO): Powering down
2018-11-06T00:50:04.943Z,1541465404.943 [CBIT](INFO): Critical error at 20181106T005004
2018-11-06T00:51:51.986Z,1541465511.986 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:51:51.986Z,1541465511.986 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-11-06T00:51:51.986Z,1541465511.986 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T00:51:51.988Z,1541465511.988 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:51:51.988Z,1541465511.988 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-11-06T00:51:51.988Z,1541465511.988 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T00:51:52.000Z,1541465512.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T00:51:52.001Z,1541465512.001 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T00:51:52.398Z,1541465512.398 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T00:51:52.398Z,1541465512.398 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-11-06T00:51:52.399Z,1541465512.399 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T00:51:52.399Z,1541465512.399 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-11-06T00:51:52.784Z,1541465512.784 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T00:51:52.784Z,1541465512.784 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:51:52.785Z,1541465512.785 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:51:52.785Z,1541465512.785 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T00:51:52.785Z,1541465512.785 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:51:52.786Z,1541465512.786 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:52:04.802Z,1541465524.802 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:52:04.803Z,1541465524.803 [NAL9602] No Fault, FailCount= 5
2018-11-06T00:52:05.155Z,1541465525.155 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:52:25.961Z,1541465545.961 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:52:25.961Z,1541465545.961 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T00:52:25.961Z,1541465545.961 [NAL9602](ERROR): Communications Fault
2018-11-06T00:52:26.034Z,1541465546.034 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:52:26.363Z,1541465546.363 [NAL9602](INFO): Powering down
2018-11-06T00:52:27.218Z,1541465547.218 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:52:27.218Z,1541465547.218 [NAL9602] No Fault, FailCount= 1
2018-11-06T00:52:56.695Z,1541465576.695 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:53:17.495Z,1541465597.495 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:53:17.495Z,1541465597.495 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T00:53:17.495Z,1541465597.495 [NAL9602](ERROR): Communications Fault
2018-11-06T00:53:17.555Z,1541465597.555 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:53:17.895Z,1541465597.895 [NAL9602](INFO): Powering down
2018-11-06T00:53:18.793Z,1541465598.793 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:53:18.793Z,1541465598.793 [NAL9602] No Fault, FailCount= 2
2018-11-06T00:53:35.868Z,1541465615.868 [BPC1](FAULT): Battery stick #23 (s/n: 02A0) reported OVER_TEMP_ALARM. Status code: 0x1717.
2018-11-06T00:53:35.869Z,1541465615.869 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2018-11-06T00:53:48.247Z,1541465628.247 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:53:53.063Z,1541465633.063 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:53:53.063Z,1541465633.063 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-11-06T00:53:53.063Z,1541465633.063 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T00:53:53.065Z,1541465633.065 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:53:53.065Z,1541465633.065 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-11-06T00:53:53.065Z,1541465633.065 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T00:53:53.090Z,1541465633.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T00:53:53.090Z,1541465633.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T00:53:53.503Z,1541465633.503 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T00:53:53.503Z,1541465633.503 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-11-06T00:53:53.504Z,1541465633.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T00:53:53.504Z,1541465633.504 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-11-06T00:53:53.865Z,1541465633.865 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T00:53:53.866Z,1541465633.866 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:53:53.866Z,1541465633.866 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:53:53.867Z,1541465633.867 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T00:53:53.867Z,1541465633.867 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:53:53.867Z,1541465633.867 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:54:09.375Z,1541465649.375 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:54:09.375Z,1541465649.375 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T00:54:09.375Z,1541465649.375 [NAL9602](ERROR): Communications Fault
2018-11-06T00:54:09.432Z,1541465649.432 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:54:09.775Z,1541465649.775 [NAL9602](INFO): Powering down
2018-11-06T00:54:10.618Z,1541465650.618 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:54:10.618Z,1541465650.618 [NAL9602] No Fault, FailCount= 3
2018-11-06T00:54:25.404Z,1541465665.404 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-06T00:49:25.4Z
2018-11-06T00:54:25.404Z,1541465665.404 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T00:54:25.404Z,1541465665.404 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-06T00:54:25.802Z,1541465665.802 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-06T00:54:29.722Z,1541465669.722 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T001704/Courier0000.lzma
2018-11-06T00:54:30.524Z,1541465670.524 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001704/Courier0000.lzma.bak
2018-11-06T00:54:30.524Z,1541465670.524 [DataOverHttps](INFO): SBD MOMSN=8792785
2018-11-06T00:54:39.226Z,1541465679.226 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T001810/Courier0000.lzma
2018-11-06T00:54:40.024Z,1541465680.024 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001810/Courier0000.lzma.bak
2018-11-06T00:54:40.024Z,1541465680.024 [DataOverHttps](INFO): SBD MOMSN=8792788
2018-11-06T00:54:40.030Z,1541465680.030 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:54:48.727Z,1541465688.727 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181106T001901/Courier0000.lzma
2018-11-06T00:54:49.524Z,1541465689.524 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001901/Courier0000.lzma.bak
2018-11-06T00:54:49.524Z,1541465689.524 [DataOverHttps](INFO): SBD MOMSN=8792793
2018-11-06T00:54:58.729Z,1541465698.729 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T001954/Courier0000.lzma
2018-11-06T00:54:59.528Z,1541465699.528 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001954/Courier0000.lzma.bak
2018-11-06T00:54:59.528Z,1541465699.528 [DataOverHttps](INFO): SBD MOMSN=8792798
2018-11-06T00:55:03.879Z,1541465703.879 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:55:03.879Z,1541465703.879 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T00:55:03.879Z,1541465703.879 [NAL9602](ERROR): Communications Fault
2018-11-06T00:55:03.935Z,1541465703.935 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:55:04.283Z,1541465704.283 [NAL9602](INFO): Powering down
2018-11-06T00:55:05.118Z,1541465705.118 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:55:05.118Z,1541465705.118 [NAL9602] No Fault, FailCount= 4
2018-11-06T00:55:08.229Z,1541465708.229 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T002021/Courier0000.lzma
2018-11-06T00:55:09.001Z,1541465709.001 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002021/Courier0000.lzma.bak
2018-11-06T00:55:09.001Z,1541465709.001 [DataOverHttps](INFO): SBD MOMSN=8792803
2018-11-06T00:55:17.695Z,1541465717.695 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T002120/Courier0000.lzma
2018-11-06T00:55:18.488Z,1541465718.488 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002120/Courier0000.lzma.bak
2018-11-06T00:55:18.488Z,1541465718.488 [DataOverHttps](INFO): SBD MOMSN=8792806
2018-11-06T00:55:27.189Z,1541465727.189 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T002212/Courier0000.lzma
2018-11-06T00:55:27.988Z,1541465727.988 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002212/Courier0000.lzma.bak
2018-11-06T00:55:27.988Z,1541465727.988 [DataOverHttps](INFO): SBD MOMSN=8792811
2018-11-06T00:55:36.621Z,1541465736.621 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:55:36.696Z,1541465736.696 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T002359/Courier0000.lzma
2018-11-06T00:55:37.496Z,1541465737.496 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002359/Courier0000.lzma.bak
2018-11-06T00:55:37.496Z,1541465737.496 [DataOverHttps](INFO): SBD MOMSN=8792816
2018-11-06T00:55:46.179Z,1541465746.179 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T002646/Courier0000.lzma
2018-11-06T00:55:46.976Z,1541465746.976 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002646/Courier0000.lzma.bak
2018-11-06T00:55:46.976Z,1541465746.976 [DataOverHttps](INFO): SBD MOMSN=8792821
2018-11-06T00:55:55.632Z,1541465755.632 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:55:55.632Z,1541465755.632 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2018-11-06T00:55:55.632Z,1541465755.632 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T00:55:55.634Z,1541465755.634 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:55:55.634Z,1541465755.634 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-11-06T00:55:55.634Z,1541465755.634 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T00:55:55.690Z,1541465755.690 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T00:55:55.690Z,1541465755.690 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T00:55:55.715Z,1541465755.715 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T002759/Courier0000.lzma
2018-11-06T00:55:55.773Z,1541465755.773 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T00:55:55.773Z,1541465755.773 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2018-11-06T00:55:55.773Z,1541465755.773 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T00:55:55.773Z,1541465755.773 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-11-06T00:55:56.142Z,1541465756.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T00:55:56.142Z,1541465756.142 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:55:56.142Z,1541465756.142 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:55:56.143Z,1541465756.143 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T00:55:56.143Z,1541465756.143 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:55:56.144Z,1541465756.144 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:55:56.505Z,1541465756.505 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002759/Courier0000.lzma.bak
2018-11-06T00:55:56.507Z,1541465756.507 [DataOverHttps](INFO): SBD MOMSN=8792826
2018-11-06T00:56:00.390Z,1541465760.390 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:56:00.390Z,1541465760.390 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T00:56:00.390Z,1541465760.390 [NAL9602](ERROR): Communications Fault
2018-11-06T00:56:00.445Z,1541465760.445 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:56:00.445Z,1541465760.445 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T00:56:00.507Z,1541465760.507 [NAL9602](INFO): Powering down
2018-11-06T00:56:00.541Z,1541465760.541 [CBIT](INFO): Critical error at 20181106T005600
2018-11-06T00:56:05.189Z,1541465765.189 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T002845/Courier0000.lzma
2018-11-06T00:56:05.988Z,1541465765.988 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002845/Courier0000.lzma.bak
2018-11-06T00:56:05.988Z,1541465765.988 [DataOverHttps](INFO): SBD MOMSN=8792831
2018-11-06T00:56:14.746Z,1541465774.746 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181106T002931/Courier0000.lzma
2018-11-06T00:56:15.544Z,1541465775.544 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002931/Courier0000.lzma.bak
2018-11-06T00:56:15.544Z,1541465775.544 [DataOverHttps](INFO): SBD MOMSN=8792836
2018-11-06T00:56:24.295Z,1541465784.295 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T003004/Courier0000.lzma
2018-11-06T00:56:25.096Z,1541465785.096 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003004/Courier0000.lzma.bak
2018-11-06T00:56:25.096Z,1541465785.096 [DataOverHttps](INFO): SBD MOMSN=8792841
2018-11-06T00:56:33.793Z,1541465793.793 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181106T003133/Courier0000.lzma
2018-11-06T00:56:34.560Z,1541465794.560 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003133/Courier0000.lzma.bak
2018-11-06T00:56:34.576Z,1541465794.576 [DataOverHttps](INFO): SBD MOMSN=8792844
2018-11-06T00:56:43.247Z,1541465803.247 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T003226/Courier0000.lzma
2018-11-06T00:56:44.040Z,1541465804.040 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003226/Courier0000.lzma.bak
2018-11-06T00:56:44.040Z,1541465804.040 [DataOverHttps](INFO): SBD MOMSN=8792849
2018-11-06T00:56:52.703Z,1541465812.703 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20181106T003656/Courier0000.lzma
2018-11-06T00:56:53.500Z,1541465813.500 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003656/Courier0000.lzma.bak
2018-11-06T00:56:53.500Z,1541465813.500 [DataOverHttps](INFO): SBD MOMSN=8792854
2018-11-06T00:57:02.205Z,1541465822.205 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181106T003835/Courier0000.lzma
2018-11-06T00:57:03.004Z,1541465823.004 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003835/Courier0000.lzma.bak
2018-11-06T00:57:03.004Z,1541465823.004 [DataOverHttps](INFO): SBD MOMSN=8792862
2018-11-06T00:57:11.696Z,1541465831.696 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20181106T004049/Courier0000.lzma
2018-11-06T00:57:12.464Z,1541465832.464 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004049/Courier0000.lzma.bak
2018-11-06T00:57:12.464Z,1541465832.464 [DataOverHttps](INFO): SBD MOMSN=8792867
2018-11-06T00:57:21.142Z,1541465841.142 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T004213/Courier0000.lzma
2018-11-06T00:57:21.940Z,1541465841.940 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004213/Courier0000.lzma.bak
2018-11-06T00:57:21.941Z,1541465841.941 [DataOverHttps](INFO): SBD MOMSN=8792875
2018-11-06T00:57:30.638Z,1541465850.638 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20181106T004410/Courier0000.lzma
2018-11-06T00:57:31.408Z,1541465851.408 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004410/Courier0000.lzma.bak
2018-11-06T00:57:31.408Z,1541465851.408 [DataOverHttps](INFO): SBD MOMSN=8792880
2018-11-06T00:57:40.149Z,1541465860.149 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181106T004537/Courier0000.lzma
2018-11-06T00:57:40.948Z,1541465860.948 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004537/Courier0000.lzma.bak
2018-11-06T00:57:40.948Z,1541465860.948 [DataOverHttps](INFO): SBD MOMSN=8792888
2018-11-06T00:57:49.954Z,1541465869.954 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20181106T004537/Courier0004.lzma
2018-11-06T00:57:50.752Z,1541465870.752 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004537/Courier0004.lzma.bak
2018-11-06T00:57:50.752Z,1541465870.752 [DataOverHttps](INFO): SBD MOMSN=8792893
2018-11-06T00:57:56.329Z,1541465876.329 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:57:56.329Z,1541465876.329 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2018-11-06T00:57:56.329Z,1541465876.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T00:57:56.330Z,1541465876.330 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:57:56.331Z,1541465876.331 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-11-06T00:57:56.331Z,1541465876.331 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T00:57:56.344Z,1541465876.344 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T00:57:56.344Z,1541465876.344 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T00:57:59.408Z,1541465879.408 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T00:57:59.408Z,1541465879.408 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2018-11-06T00:57:59.409Z,1541465879.409 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T00:57:59.409Z,1541465879.409 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-11-06T00:57:59.451Z,1541465879.451 [DataOverHttps](INFO): Sending 2345 bytes from file Logs/20181106T000056/Express0005.lzma
2018-11-06T00:57:59.501Z,1541465879.501 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T00:57:59.506Z,1541465879.506 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:57:59.506Z,1541465879.506 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:57:59.507Z,1541465879.507 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T00:57:59.507Z,1541465879.507 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T00:57:59.508Z,1541465879.508 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T00:58:00.220Z,1541465880.220 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000056/Express0005.lzma.bak
2018-11-06T00:58:00.220Z,1541465880.220 [DataOverHttps](INFO): SBD MOMSN=8792896
2018-11-06T00:58:00.782Z,1541465880.782 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:58:00.782Z,1541465880.782 [NAL9602] No Fault, FailCount= 5
2018-11-06T00:58:01.027Z,1541465881.027 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:58:08.897Z,1541465888.897 [DataOverHttps](INFO): Sending 830 bytes from file Logs/20181106T000701/Express0001.lzma
2018-11-06T00:58:09.692Z,1541465889.692 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000701/Express0001.lzma.bak
2018-11-06T00:58:09.692Z,1541465889.692 [DataOverHttps](INFO): SBD MOMSN=8793206
2018-11-06T00:58:18.402Z,1541465898.402 [DataOverHttps](INFO): Sending 958 bytes from file Logs/20181106T000906/Express0001.lzma
2018-11-06T00:58:19.170Z,1541465899.170 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000906/Express0001.lzma.bak
2018-11-06T00:58:19.172Z,1541465899.172 [DataOverHttps](INFO): SBD MOMSN=8793230
2018-11-06T00:58:23.915Z,1541465903.915 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:58:23.915Z,1541465903.915 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T00:58:23.916Z,1541465903.916 [NAL9602](ERROR): Communications Fault
2018-11-06T00:58:23.948Z,1541465903.948 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:58:24.303Z,1541465904.303 [NAL9602](INFO): Powering down
2018-11-06T00:58:27.843Z,1541465907.843 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:58:27.843Z,1541465907.843 [NAL9602] No Fault, FailCount= 1
2018-11-06T00:58:27.854Z,1541465907.854 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20181106T000906/Express0005.lzma
2018-11-06T00:58:28.652Z,1541465908.652 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000906/Express0005.lzma.bak
2018-11-06T00:58:28.652Z,1541465908.652 [DataOverHttps](INFO): SBD MOMSN=8793258
2018-11-06T00:58:37.350Z,1541465917.350 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T001301/Express0001.lzma
2018-11-06T00:58:38.148Z,1541465918.148 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001301/Express0001.lzma.bak
2018-11-06T00:58:38.148Z,1541465918.148 [DataOverHttps](INFO): SBD MOMSN=8793262
2018-11-06T00:58:46.868Z,1541465926.868 [DataOverHttps](INFO): Sending 496 bytes from file Logs/20181106T001400/Express0001.lzma
2018-11-06T00:58:47.652Z,1541465927.652 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001400/Express0001.lzma.bak
2018-11-06T00:58:47.652Z,1541465927.652 [DataOverHttps](INFO): SBD MOMSN=8793264
2018-11-06T00:58:56.299Z,1541465936.299 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:58:56.369Z,1541465936.369 [DataOverHttps](INFO): Sending 491 bytes from file Logs/20181106T001431/Express0001.lzma
2018-11-06T00:58:57.168Z,1541465937.168 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001431/Express0001.lzma.bak
2018-11-06T00:58:57.168Z,1541465937.168 [DataOverHttps](INFO): SBD MOMSN=8793275
2018-11-06T00:59:05.862Z,1541465945.862 [DataOverHttps](INFO): Sending 508 bytes from file Logs/20181106T001527/Express0001.lzma
2018-11-06T00:59:06.660Z,1541465946.660 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001527/Express0001.lzma.bak
2018-11-06T00:59:06.660Z,1541465946.660 [DataOverHttps](INFO): SBD MOMSN=8793287
2018-11-06T00:59:15.460Z,1541465955.460 [DataOverHttps](INFO): Sending 520 bytes from file Logs/20181106T001621/Express0001.lzma
2018-11-06T00:59:16.260Z,1541465956.260 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001621/Express0001.lzma.bak
2018-11-06T00:59:16.260Z,1541465956.260 [DataOverHttps](INFO): SBD MOMSN=8793301
2018-11-06T00:59:20.584Z,1541465960.584 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T00:59:20.585Z,1541465960.585 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T00:59:20.585Z,1541465960.585 [NAL9602](ERROR): Communications Fault
2018-11-06T00:59:20.618Z,1541465960.618 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T00:59:20.695Z,1541465960.695 [NAL9602](INFO): Powering down
2018-11-06T00:59:21.467Z,1541465961.467 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T00:59:21.467Z,1541465961.467 [NAL9602] No Fault, FailCount= 2
2018-11-06T00:59:25.364Z,1541465965.364 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T001704/Express0001.lzma
2018-11-06T00:59:26.164Z,1541465966.164 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001704/Express0001.lzma.bak
2018-11-06T00:59:26.164Z,1541465966.164 [DataOverHttps](INFO): SBD MOMSN=8793315
2018-11-06T00:59:34.856Z,1541465974.856 [DataOverHttps](INFO): Sending 601 bytes from file Logs/20181106T001810/Express0001.lzma
2018-11-06T00:59:35.628Z,1541465975.628 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001810/Express0001.lzma.bak
2018-11-06T00:59:35.628Z,1541465975.628 [DataOverHttps](INFO): SBD MOMSN=8793317
2018-11-06T00:59:44.287Z,1541465984.287 [DataOverHttps](INFO): Sending 511 bytes from file Logs/20181106T001901/Express0001.lzma
2018-11-06T00:59:45.084Z,1541465985.084 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001901/Express0001.lzma.bak
2018-11-06T00:59:45.084Z,1541465985.084 [DataOverHttps](INFO): SBD MOMSN=8793335
2018-11-06T00:59:53.695Z,1541465993.695 [NAL9602](INFO): Powering up NAL9602
2018-11-06T00:59:53.756Z,1541465993.756 [DataOverHttps](INFO): Sending 405 bytes from file Logs/20181106T001954/Express0001.lzma
2018-11-06T00:59:54.552Z,1541465994.552 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001954/Express0001.lzma.bak
2018-11-06T00:59:54.552Z,1541465994.552 [DataOverHttps](INFO): SBD MOMSN=8793349
2018-11-06T00:59:59.834Z,1541465999.834 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:59:59.834Z,1541465999.834 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2018-11-06T00:59:59.834Z,1541465999.834 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T00:59:59.835Z,1541465999.835 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T00:59:59.835Z,1541465999.835 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-11-06T00:59:59.836Z,1541465999.836 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T00:59:59.848Z,1541465999.848 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T00:59:59.849Z,1541465999.849 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T01:00:00.243Z,1541466000.243 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T01:00:00.243Z,1541466000.243 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2018-11-06T01:00:00.244Z,1541466000.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T01:00:00.244Z,1541466000.244 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-11-06T01:00:03.416Z,1541466003.416 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T01:00:03.417Z,1541466003.417 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T01:00:03.417Z,1541466003.417 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T01:00:03.418Z,1541466003.418 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T01:00:03.418Z,1541466003.418 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T01:00:03.418Z,1541466003.418 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T01:00:03.440Z,1541466003.440 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T002021/Express0001.lzma
2018-11-06T01:00:04.240Z,1541466004.240 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002021/Express0001.lzma.bak
2018-11-06T01:00:04.240Z,1541466004.240 [DataOverHttps](INFO): SBD MOMSN=8793358
2018-11-06T01:00:13.015Z,1541466013.015 [DataOverHttps](INFO): Sending 601 bytes from file Logs/20181106T002120/Express0001.lzma
2018-11-06T01:00:13.812Z,1541466013.812 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002120/Express0001.lzma.bak
2018-11-06T01:00:13.812Z,1541466013.812 [DataOverHttps](INFO): SBD MOMSN=8793360
2018-11-06T01:00:14.343Z,1541466014.343 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T01:00:14.343Z,1541466014.343 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T01:00:14.343Z,1541466014.343 [NAL9602](ERROR): Communications Fault
2018-11-06T01:00:14.411Z,1541466014.411 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T01:00:14.615Z,1541466014.615 [NAL9602](INFO): Powering down
2018-11-06T01:00:17.859Z,1541466017.859 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T01:00:17.859Z,1541466017.859 [NAL9602] No Fault, FailCount= 3
2018-11-06T01:00:22.522Z,1541466022.522 [DataOverHttps](INFO): Sending 474 bytes from file Logs/20181106T002212/Express0001.lzma
2018-11-06T01:00:23.320Z,1541466023.320 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002212/Express0001.lzma.bak
2018-11-06T01:00:23.323Z,1541466023.323 [DataOverHttps](INFO): SBD MOMSN=8793378
2018-11-06T01:00:32.024Z,1541466032.024 [DataOverHttps](INFO): Sending 835 bytes from file Logs/20181106T002359/Express0001.lzma
2018-11-06T01:00:32.820Z,1541466032.820 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002359/Express0001.lzma.bak
2018-11-06T01:00:32.820Z,1541466032.820 [DataOverHttps](INFO): SBD MOMSN=8793391
2018-11-06T01:00:41.525Z,1541466041.525 [DataOverHttps](INFO): Sending 611 bytes from file Logs/20181106T002646/Express0001.lzma
2018-11-06T01:00:42.288Z,1541466042.288 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002646/Express0001.lzma.bak
2018-11-06T01:00:42.288Z,1541466042.288 [DataOverHttps](INFO): SBD MOMSN=8793415
2018-11-06T01:00:46.169Z,1541466046.169 [NAL9602](INFO): Powering up NAL9602
2018-11-06T01:00:50.966Z,1541466050.966 [CBIT](INFO): Clearing failed state for component DropWeight
2018-11-06T01:00:50.966Z,1541466050.966 [DropWeight] No Fault, FailCount= 1
2018-11-06T01:00:50.979Z,1541466050.979 [DataOverHttps](INFO): Sending 520 bytes from file Logs/20181106T002759/Express0001.lzma
2018-11-06T01:00:51.776Z,1541466051.776 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002759/Express0001.lzma.bak
2018-11-06T01:00:51.776Z,1541466051.776 [DataOverHttps](INFO): SBD MOMSN=8793427
2018-11-06T01:01:00.525Z,1541466060.525 [DataOverHttps](INFO): Sending 505 bytes from file Logs/20181106T002845/Express0001.lzma
2018-11-06T01:01:01.296Z,1541466061.296 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002845/Express0001.lzma.bak
2018-11-06T01:01:01.296Z,1541466061.296 [DataOverHttps](INFO): SBD MOMSN=8793441
2018-11-06T01:01:06.851Z,1541466066.851 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T01:01:06.852Z,1541466066.852 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T01:01:06.852Z,1541466066.852 [NAL9602](ERROR): Communications Fault
2018-11-06T01:01:06.931Z,1541466066.931 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T01:01:09.930Z,1541466069.930 [NAL9602](INFO): Powering down
2018-11-06T01:01:10.013Z,1541466070.013 [DataOverHttps](INFO): Sending 406 bytes from file Logs/20181106T002931/Express0001.lzma
2018-11-06T01:01:10.454Z,1541466070.454 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T01:01:10.454Z,1541466070.454 [NAL9602] No Fault, FailCount= 4
2018-11-06T01:01:10.812Z,1541466070.812 [DataOverHttps](INFO): Moved sent file to Logs/20181106T002931/Express0001.lzma.bak
2018-11-06T01:01:10.812Z,1541466070.812 [DataOverHttps](INFO): SBD MOMSN=8793454
2018-11-06T01:01:19.912Z,1541466079.912 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T003004/Express0001.lzma
2018-11-06T01:01:20.680Z,1541466080.680 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003004/Express0001.lzma.bak
2018-11-06T01:01:20.680Z,1541466080.680 [DataOverHttps](INFO): SBD MOMSN=8793463
2018-11-06T01:01:29.367Z,1541466089.367 [DataOverHttps](INFO): Sending 600 bytes from file Logs/20181106T003133/Express0001.lzma
2018-11-06T01:01:30.164Z,1541466090.164 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003133/Express0001.lzma.bak
2018-11-06T01:01:30.164Z,1541466090.164 [DataOverHttps](INFO): SBD MOMSN=8793465
2018-11-06T01:01:38.824Z,1541466098.824 [DataOverHttps](INFO): Sending 611 bytes from file Logs/20181106T003226/Express0001.lzma
2018-11-06T01:01:39.620Z,1541466099.620 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003226/Express0001.lzma.bak
2018-11-06T01:01:39.620Z,1541466099.620 [DataOverHttps](INFO): SBD MOMSN=8793483
2018-11-06T01:01:40.244Z,1541466100.244 [NAL9602](INFO): Powering up NAL9602
2018-11-06T01:01:48.321Z,1541466108.321 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T003537/Express0001.lzma
2018-11-06T01:01:49.092Z,1541466109.092 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003537/Express0001.lzma.bak
2018-11-06T01:01:49.092Z,1541466109.092 [DataOverHttps](INFO): SBD MOMSN=8793495
2018-11-06T01:01:57.838Z,1541466117.838 [DataOverHttps](INFO): Sending 547 bytes from file Logs/20181106T003656/Express0001.lzma
2018-11-06T01:01:58.604Z,1541466118.604 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003656/Express0001.lzma.bak
2018-11-06T01:01:58.604Z,1541466118.604 [DataOverHttps](INFO): SBD MOMSN=8793498
2018-11-06T01:02:03.360Z,1541466123.360 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T01:02:03.360Z,1541466123.360 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T01:02:03.360Z,1541466123.360 [NAL9602](ERROR): Communications Fault
2018-11-06T01:02:03.392Z,1541466123.392 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T01:02:03.392Z,1541466123.392 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T01:02:03.751Z,1541466123.751 [NAL9602](INFO): Powering down
2018-11-06T01:02:03.786Z,1541466123.786 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T01:02:03.786Z,1541466123.786 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2018-11-06T01:02:03.786Z,1541466123.786 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T01:02:03.788Z,1541466123.788 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T01:02:03.788Z,1541466123.788 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-11-06T01:02:03.788Z,1541466123.788 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T01:02:03.799Z,1541466123.799 [CBIT](INFO): Critical error at 20181106T010203
2018-11-06T01:02:03.801Z,1541466123.801 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T01:02:03.802Z,1541466123.802 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T01:02:04.188Z,1541466124.188 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T01:02:04.189Z,1541466124.189 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2018-11-06T01:02:04.189Z,1541466124.189 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T01:02:04.189Z,1541466124.189 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-11-06T01:02:07.251Z,1541466127.251 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T01:02:07.252Z,1541466127.252 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T01:02:07.252Z,1541466127.252 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T01:02:07.252Z,1541466127.252 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T01:02:07.253Z,1541466127.253 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T01:02:07.253Z,1541466127.253 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T01:02:07.334Z,1541466127.334 [DataOverHttps](INFO): Sending 670 bytes from file Logs/20181106T003835/Express0001.lzma
2018-11-06T01:02:08.132Z,1541466128.132 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003835/Express0001.lzma.bak
2018-11-06T01:02:08.133Z,1541466128.133 [DataOverHttps](INFO): SBD MOMSN=8793512
2018-11-06T01:02:16.808Z,1541466136.808 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T003934/Express0001.lzma
2018-11-06T01:02:17.608Z,1541466137.608 [DataOverHttps](INFO): Moved sent file to Logs/20181106T003934/Express0001.lzma.bak
2018-11-06T01:02:17.608Z,1541466137.608 [DataOverHttps](INFO): SBD MOMSN=8793527
2018-11-06T01:02:26.320Z,1541466146.320 [DataOverHttps](INFO): Sending 648 bytes from file Logs/20181106T004049/Express0001.lzma
2018-11-06T01:02:27.108Z,1541466147.108 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004049/Express0001.lzma.bak
2018-11-06T01:02:27.108Z,1541466147.108 [DataOverHttps](INFO): SBD MOMSN=8793530
2018-11-06T01:02:35.810Z,1541466155.810 [DataOverHttps](INFO): Sending 467 bytes from file Logs/20181106T004213/Express0001.lzma
2018-11-06T01:02:36.608Z,1541466156.608 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004213/Express0001.lzma.bak
2018-11-06T01:02:36.608Z,1541466156.608 [DataOverHttps](INFO): SBD MOMSN=8793553
2018-11-06T01:02:45.352Z,1541466165.352 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T004257/Express0001.lzma
2018-11-06T01:02:46.152Z,1541466166.152 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004257/Express0001.lzma.bak
2018-11-06T01:02:46.155Z,1541466166.155 [DataOverHttps](INFO): SBD MOMSN=8793564
2018-11-06T01:02:54.858Z,1541466174.858 [DataOverHttps](INFO): Sending 588 bytes from file Logs/20181106T004410/Express0001.lzma
2018-11-06T01:02:55.656Z,1541466175.656 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004410/Express0001.lzma.bak
2018-11-06T01:02:55.656Z,1541466175.656 [DataOverHttps](INFO): SBD MOMSN=8793567
2018-11-06T01:03:04.359Z,1541466184.359 [DataOverHttps](INFO): Sending 943 bytes from file Logs/20181106T004537/Express0001.lzma
2018-11-06T01:03:05.156Z,1541466185.156 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004537/Express0001.lzma.bak
2018-11-06T01:03:05.156Z,1541466185.156 [DataOverHttps](INFO): SBD MOMSN=8793582
2018-11-06T01:03:13.854Z,1541466193.854 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20181106T004537/Express0005.lzma
2018-11-06T01:03:14.652Z,1541466194.652 [DataOverHttps](INFO): Moved sent file to Logs/20181106T004537/Express0005.lzma.bak
2018-11-06T01:03:14.652Z,1541466194.652 [DataOverHttps](INFO): SBD MOMSN=8793607
2018-11-06T01:03:15.219Z,1541466195.219 [Default:CheckIn:Read_Iridium] Stopped
2018-11-06T01:03:15.220Z,1541466195.220 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-06T01:03:15.220Z,1541466195.220 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-06T01:04:03.418Z,1541466243.418 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T01:04:03.419Z,1541466243.419 [NAL9602] No Fault, FailCount= 5
2018-11-06T01:04:03.773Z,1541466243.773 [NAL9602](INFO): Powering up NAL9602
2018-11-06T01:04:07.404Z,1541466247.404 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T01:04:07.404Z,1541466247.404 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2018-11-06T01:04:07.405Z,1541466247.405 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T01:04:07.406Z,1541466247.406 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T01:04:07.406Z,1541466247.406 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-11-06T01:04:07.406Z,1541466247.406 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T01:04:07.419Z,1541466247.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T01:04:07.419Z,1541466247.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T01:04:07.813Z,1541466247.813 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T01:04:07.813Z,1541466247.813 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2018-11-06T01:04:07.813Z,1541466247.813 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T01:04:07.814Z,1541466247.814 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-11-06T01:04:08.205Z,1541466248.205 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T01:04:08.206Z,1541466248.206 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T01:04:08.207Z,1541466248.207 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T01:04:08.207Z,1541466248.207 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T01:04:08.207Z,1541466248.207 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T01:04:08.208Z,1541466248.208 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T01:04:24.976Z,1541466264.976 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T01:04:24.976Z,1541466264.976 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T01:04:24.976Z,1541466264.976 [NAL9602](ERROR): Communications Fault
2018-11-06T01:04:25.042Z,1541466265.042 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T01:04:25.375Z,1541466265.375 [NAL9602](INFO): Powering down
2018-11-06T01:04:28.926Z,1541466268.926 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T01:04:28.926Z,1541466268.926 [NAL9602] No Fault, FailCount= 1
2018-11-06T01:04:55.739Z,1541466295.739 [NAL9602](INFO): Powering up NAL9602
2018-11-06T01:05:16.888Z,1541466316.888 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T01:05:16.888Z,1541466316.888 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T01:05:16.888Z,1541466316.888 [NAL9602](ERROR): Communications Fault
2018-11-06T01:05:16.949Z,1541466316.949 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T01:05:17.283Z,1541466317.283 [NAL9602](INFO): Powering down
2018-11-06T01:05:18.125Z,1541466318.125 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T01:05:18.125Z,1541466318.125 [NAL9602] No Fault, FailCount= 2
2018-11-06T01:05:47.619Z,1541466347.619 [NAL9602](INFO): Powering up NAL9602
2018-11-06T01:06:08.439Z,1541466368.439 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T01:06:08.439Z,1541466368.439 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2018-11-06T01:06:08.439Z,1541466368.439 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T01:06:08.441Z,1541466368.441 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T01:06:08.441Z,1541466368.441 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-11-06T01:06:08.441Z,1541466368.441 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T01:06:08.494Z,1541466368.494 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T01:06:08.495Z,1541466368.495 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T01:06:08.819Z,1541466368.819 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T01:06:08.819Z,1541466368.819 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T01:06:08.819Z,1541466368.819 [NAL9602](ERROR): Communications Fault
2018-11-06T01:06:08.857Z,1541466368.857 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T01:06:08.857Z,1541466368.857 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T01:06:08.886Z,1541466368.886 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2018-11-06T01:06:08.886Z,1541466368.886 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T01:06:08.886Z,1541466368.886 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-11-06T01:06:09.215Z,1541466369.215 [NAL9602](INFO): Powering down
2018-11-06T01:06:09.241Z,1541466369.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T01:06:09.250Z,1541466369.250 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T01:06:09.250Z,1541466369.250 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T01:06:09.251Z,1541466369.251 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T01:06:09.251Z,1541466369.251 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T01:06:09.252Z,1541466369.252 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T01:06:10.057Z,1541466370.057 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T01:06:10.057Z,1541466370.057 [NAL9602] No Fault, FailCount= 3
2018-11-06T01:06:39.563Z,1541466399.563 [NAL9602](INFO): Powering up NAL9602
2018-11-06T01:07:00.724Z,1541466420.724 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T01:07:00.724Z,1541466420.724 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T01:07:00.724Z,1541466420.724 [NAL9602](ERROR): Communications Fault
2018-11-06T01:07:00.796Z,1541466420.796 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T01:07:01.117Z,1541466421.117 [NAL9602](INFO): Powering down
2018-11-06T01:07:01.948Z,1541466421.948 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T01:07:01.948Z,1541466421.948 [NAL9602] No Fault, FailCount= 4
2018-11-06T01:07:31.449Z,1541466451.449 [NAL9602](INFO): Powering up NAL9602
2018-11-06T01:07:52.249Z,1541466472.249 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T01:07:52.249Z,1541466472.249 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T01:07:52.249Z,1541466472.249 [NAL9602](ERROR): Communications Fault
2018-11-06T01:07:52.319Z,1541466472.319 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T01:07:52.322Z,1541466472.322 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T01:07:52.651Z,1541466472.651 [NAL9602](INFO): Powering down
2018-11-06T01:07:52.711Z,1541466472.711 [CBIT](INFO): Critical error at 20181106T010752
2018-11-06T01:08:09.439Z,1541466489.439 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T01:08:09.439Z,1541466489.439 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2018-11-06T01:08:09.439Z,1541466489.439 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-06T01:08:09.441Z,1541466489.441 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T01:08:09.441Z,1541466489.441 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-11-06T01:08:09.441Z,1541466489.441 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T01:08:09.454Z,1541466489.454 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-06T01:08:09.455Z,1541466489.455 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T01:08:09.855Z,1541466489.855 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-06T01:08:09.855Z,1541466489.855 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2018-11-06T01:08:09.855Z,1541466489.855 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T01:08:09.855Z,1541466489.855 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-11-06T01:08:10.221Z,1541466490.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-06T01:08:10.222Z,1541466490.222 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T01:08:10.222Z,1541466490.222 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T01:08:10.223Z,1541466490.223 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T01:08:10.223Z,1541466490.223 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T01:08:10.223Z,1541466490.223 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T01:08:15.832Z,1541466495.832 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-06T01:08:15.832Z,1541466495.832 [Default:CheckIn:C.Wait] Stopped
2018-11-06T01:08:15.832Z,1541466495.832 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T01:08:15.832Z,1541466495.832 [Default:CheckIn:D] Running Loop=1
2018-11-06T01:08:16.224Z,1541466496.224 [Default:CheckIn:D] Stopped
2018-11-06T01:08:16.224Z,1541466496.224 [Default:CheckIn:E] Running Loop=1
2018-11-06T01:08:16.636Z,1541466496.636 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.073960 min 
2018-11-06T01:08:16.654Z,1541466496.654 [Default:CheckIn:E] Stopped
2018-11-06T01:08:16.654Z,1541466496.654 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-06T01:08:16.654Z,1541466496.654 [Default:CheckIn] Stopped
2018-11-06T01:08:16.654Z,1541466496.654 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T01:08:16.654Z,1541466496.654 [Default:CheckIn](INFO): Running loop #2
2018-11-06T01:08:16.654Z,1541466496.654 [Default:CheckIn] Running Loop=2
2018-11-06T01:08:16.655Z,1541466496.655 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T01:08:16.655Z,1541466496.655 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T01:08:46.270Z,1541466526.270 [CommandLine](IMPORTANT): got command restart application
2018-11-06T01:08:47.276Z,1541466527.276 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T01:08:47.277Z,1541466527.277 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:47.398Z,1541466527.398 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-06T01:08:47.398Z,1541466527.398 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:47.398Z,1541466527.398 [CommandLine](INFO): Join timeout helper Thread ID is 1028
2018-11-06T01:08:47.406Z,1541466527.406 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-06T01:08:47.406Z,1541466527.406 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:47.406Z,1541466527.406 [NavChartDb](INFO): Join timeout helper Thread ID is 1029
2018-11-06T01:08:47.538Z,1541466527.538 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T01:08:47.538Z,1541466527.538 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:47.554Z,1541466527.554 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-11-06T01:08:47.554Z,1541466527.554 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:47.554Z,1541466527.554 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1030
2018-11-06T01:08:48.050Z,1541466528.050 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T01:08:48.050Z,1541466528.050 [WetLabsBB2FL](INFO): Powering down
2018-11-06T01:08:48.051Z,1541466528.051 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.062Z,1541466528.062 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-11-06T01:08:48.062Z,1541466528.062 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.062Z,1541466528.062 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1031
2018-11-06T01:08:48.250Z,1541466528.250 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T01:08:48.250Z,1541466528.250 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.266Z,1541466528.266 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-06T01:08:48.266Z,1541466528.266 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.266Z,1541466528.266 [Radio_Surface](INFO): Join timeout helper Thread ID is 1032
2018-11-06T01:08:48.490Z,1541466528.490 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T01:08:48.490Z,1541466528.490 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.507Z,1541466528.507 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-06T01:08:48.507Z,1541466528.507 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.507Z,1541466528.507 [logger](INFO): Join timeout helper Thread ID is 1033
2018-11-06T01:08:48.542Z,1541466528.542 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T01:08:48.542Z,1541466528.542 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.548Z,1541466528.548 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-06T01:08:48.549Z,1541466528.549 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.549Z,1541466528.549 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-06T01:08:48.549Z,1541466528.549 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.549Z,1541466528.549 [controlThread](INFO): Join timeout helper Thread ID is 1034
2018-11-06T01:08:48.571Z,1541466528.571 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T01:08:48.571Z,1541466528.571 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-06T01:08:48.572Z,1541466528.572 [NAL9602](INFO): Powering down
2018-11-06T01:08:48.573Z,1541466528.573 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T01:08:48.574Z,1541466528.574 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-06T01:08:48.575Z,1541466528.575 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-06T01:08:48.575Z,1541466528.575 [MissionManager](INFO): Uninitializing Mission Default
2018-11-06T01:08:48.575Z,1541466528.575 [Default] Stopped
2018-11-06T01:08:48.576Z,1541466528.576 [Default](DEBUG): Aggregate::uninitialize Default
2018-11-06T01:08:48.576Z,1541466528.576 [Default:B.GoToSurface] Stopped
2018-11-06T01:08:48.576Z,1541466528.576 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-06T01:08:48.576Z,1541466528.576 [Default:CheckIn] Stopped
2018-11-06T01:08:48.576Z,1541466528.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T01:08:48.576Z,1541466528.576 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T01:08:48.579Z,1541466528.579 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-06T01:08:48.580Z,1541466528.580 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-06T01:08:48.580Z,1541466528.580 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-06T01:08:48.580Z,1541466528.580 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-06T01:08:48.580Z,1541466528.580 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-06T01:08:48.581Z,1541466528.581 [BuoyancyServo](INFO): Powering down
2018-11-06T01:08:48.594Z,1541466528.594 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-06T01:08:48.594Z,1541466528.594 [ElevatorServo](INFO): Powering down
2018-11-06T01:08:48.595Z,1541466528.595 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-06T01:08:48.595Z,1541466528.595 [MassServo](INFO): Powering down
2018-11-06T01:08:48.596Z,1541466528.596 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-06T01:08:48.596Z,1541466528.596 [RudderServo](INFO): Powering down
2018-11-06T01:08:48.597Z,1541466528.597 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-06T01:08:48.597Z,1541466528.597 [ThrusterServo](INFO): Powering down
2018-11-06T01:08:48.598Z,1541466528.598 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-06T01:08:48.598Z,1541466528.598 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-06T01:08:48.598Z,1541466528.598 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-06T01:08:48.599Z,1541466528.599 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.674Z,1541466528.674 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.711Z,1541466528.711 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.713Z,1541466528.713 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.759Z,1541466528.759 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T01:08:48.813Z,1541466528.813 [logger ThreadHandler](INFO): Thread cancelled.