2019-10-04T19:38:16.735Z,1570217896.735 [Supervisor](DEBUG): Initializing supervisor.
2019-10-04T19:38:16.737Z,1570217896.737 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-04T19:38:16.738Z,1570217896.738 [SyncHandler](INFO): Protected caller Thread ID is 807
2019-10-04T19:38:16.738Z,1570217896.738 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-04T19:38:16.739Z,1570217896.739 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-04T19:38:16.740Z,1570217896.740 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 808
2019-10-04T19:38:16.742Z,1570217896.742 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-04T19:38:16.753Z,1570217896.753 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-04T19:38:16.754Z,1570217896.754 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-04T19:38:16.755Z,1570217896.755 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 809
2019-10-04T19:38:16.755Z,1570217896.755 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-04T19:38:16.756Z,1570217896.756 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-04T19:38:16.757Z,1570217896.757 [logger ThreadHandler](INFO): Protected caller Thread ID is 810
2019-10-04T19:38:16.759Z,1570217896.759 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-04T19:38:16.759Z,1570217896.759 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-04T19:38:16.763Z,1570217896.763 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-04T19:38:17.187Z,1570217897.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-04T19:38:17.188Z,1570217897.188 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-04T19:38:17.286Z,1570217897.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-04T19:38:17.288Z,1570217897.288 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-04T19:38:17.607Z,1570217897.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-04T19:38:17.609Z,1570217897.609 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-04T19:38:17.750Z,1570217897.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-04T19:38:17.752Z,1570217897.752 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-04T19:38:17.946Z,1570217897.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-04T19:38:17.948Z,1570217897.948 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-04T19:38:18.397Z,1570217898.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-04T19:38:18.398Z,1570217898.398 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-04T19:38:18.605Z,1570217898.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-04T19:38:18.607Z,1570217898.607 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-04T19:38:18.751Z,1570217898.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-04T19:38:18.753Z,1570217898.753 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-04T19:38:18.952Z,1570217898.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-04T19:38:18.952Z,1570217898.952 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-04T19:38:19.048Z,1570217899.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-04T19:38:19.049Z,1570217899.049 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-04T19:38:19.368Z,1570217899.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-04T19:38:19.369Z,1570217899.369 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-04T19:38:19.451Z,1570217899.451 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-04T19:38:19.553Z,1570217899.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-04T19:38:19.554Z,1570217899.554 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-04T19:38:20.818Z,1570217900.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-04T19:38:20.818Z,1570217900.818 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-04T19:38:21.218Z,1570217901.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-04T19:38:21.220Z,1570217901.220 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-10-04T19:38:21.223Z,1570217901.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-10-04T19:38:21.434Z,1570217901.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-10-04T19:38:21.536Z,1570217901.536 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-10-04T19:38:21.635Z,1570217901.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-10-04T19:38:21.862Z,1570217901.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-04T19:38:21.862Z,1570217901.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-10-04T19:38:21.947Z,1570217901.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-10-04T19:38:22.041Z,1570217902.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-10-04T19:38:22.154Z,1570217902.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-10-04T19:38:22.265Z,1570217902.265 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-10-04T19:38:22.374Z,1570217902.374 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-10-04T19:38:22.559Z,1570217902.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-10-04T19:38:22.691Z,1570217902.691 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-10-04T19:38:22.696Z,1570217902.696 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-04T19:38:23.427Z,1570217903.427 [AHRS_M2] Loaded
2019-10-04T19:38:23.428Z,1570217903.428 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-04T19:38:23.571Z,1570217903.571 [DataOverHttps] Loaded
2019-10-04T19:38:23.571Z,1570217903.571 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-04T19:38:23.572Z,1570217903.572 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407664E0
2019-10-04T19:38:23.572Z,1570217903.572 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 890
2019-10-04T19:38:23.586Z,1570217903.586 [Depth_Keller] Loaded
2019-10-04T19:38:23.586Z,1570217903.586 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-04T19:38:23.590Z,1570217903.590 [DropWeight] Loaded
2019-10-04T19:38:23.591Z,1570217903.591 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-04T19:38:23.641Z,1570217903.641 [Micromodem] Loaded
2019-10-04T19:38:23.641Z,1570217903.641 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-10-04T19:38:23.738Z,1570217903.738 [NAL9602] Loaded
2019-10-04T19:38:23.738Z,1570217903.738 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-04T19:38:23.754Z,1570217903.754 [Onboard] Loaded
2019-10-04T19:38:23.754Z,1570217903.754 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-04T19:38:23.760Z,1570217903.760 [PowerOnly] Loaded
2019-10-04T19:38:23.760Z,1570217903.760 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-10-04T19:38:23.766Z,1570217903.766 [Radio_Surface] Loaded
2019-10-04T19:38:23.767Z,1570217903.767 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-04T19:38:23.768Z,1570217903.768 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407964E0
2019-10-04T19:38:23.768Z,1570217903.768 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 891
2019-10-04T19:38:23.812Z,1570217903.812 [RDI_Pathfinder] Loaded
2019-10-04T19:38:23.812Z,1570217903.812 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-10-04T19:38:25.282Z,1570217905.282 [BPC1] Loaded
2019-10-04T19:38:25.282Z,1570217905.282 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-04T19:38:25.283Z,1570217905.283 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-04T19:38:25.284Z,1570217905.284 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-04T19:38:25.307Z,1570217905.307 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-04T19:38:25.308Z,1570217905.308 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-04T19:38:25.430Z,1570217905.430 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-04T19:38:25.430Z,1570217905.430 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-04T19:38:25.450Z,1570217905.450 [NavChart] Loaded
2019-10-04T19:38:25.450Z,1570217905.450 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-04T19:38:25.454Z,1570217905.454 [UniversalFixResidualReporter] Loaded
2019-10-04T19:38:25.454Z,1570217905.454 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-04T19:38:25.455Z,1570217905.455 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-04T19:38:25.455Z,1570217905.455 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-04T19:38:25.587Z,1570217905.587 [BuoyancyServo] Loaded
2019-10-04T19:38:25.587Z,1570217905.587 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-04T19:38:25.602Z,1570217905.602 [ElevatorServo] Loaded
2019-10-04T19:38:25.603Z,1570217905.603 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-04T19:38:25.617Z,1570217905.617 [MassServo] Loaded
2019-10-04T19:38:25.618Z,1570217905.618 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-04T19:38:25.632Z,1570217905.632 [RudderServo] Loaded
2019-10-04T19:38:25.633Z,1570217905.633 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-04T19:38:25.647Z,1570217905.647 [ThrusterServo] Loaded
2019-10-04T19:38:25.648Z,1570217905.648 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-04T19:38:25.648Z,1570217905.648 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-04T19:38:25.649Z,1570217905.649 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-04T19:38:26.192Z,1570217906.192 [Aanderaa_O2] Loaded
2019-10-04T19:38:26.192Z,1570217906.192 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-10-04T19:38:26.274Z,1570217906.274 [CTD_NeilBrown] Loaded
2019-10-04T19:38:26.274Z,1570217906.274 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-10-04T19:38:26.275Z,1570217906.275 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0
2019-10-04T19:38:26.276Z,1570217906.276 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 892
2019-10-04T19:38:26.290Z,1570217906.290 [PAR_Licor] Loaded
2019-10-04T19:38:26.290Z,1570217906.290 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-04T19:38:26.334Z,1570217906.334 [WetLabsSeaOWL_UV_A] Loaded
2019-10-04T19:38:26.334Z,1570217906.334 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-10-04T19:38:26.335Z,1570217906.335 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409114E0
2019-10-04T19:38:26.335Z,1570217906.335 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 893
2019-10-04T19:38:26.336Z,1570217906.336 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-04T19:38:26.337Z,1570217906.337 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-04T19:38:26.677Z,1570217906.677 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-04T19:38:26.678Z,1570217906.678 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-04T19:38:26.733Z,1570217906.733 [DepthRateCalculator] Loaded
2019-10-04T19:38:26.734Z,1570217906.734 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-04T19:38:26.739Z,1570217906.739 [PitchRateCalculator] Loaded
2019-10-04T19:38:26.740Z,1570217906.740 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-04T19:38:26.752Z,1570217906.752 [SpeedCalculator] Loaded
2019-10-04T19:38:26.752Z,1570217906.752 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-04T19:38:26.773Z,1570217906.773 [TempGradientCalculator] Loaded
2019-10-04T19:38:26.773Z,1570217906.773 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-04T19:38:26.778Z,1570217906.778 [YawRateCalculator] Loaded
2019-10-04T19:38:26.779Z,1570217906.779 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-04T19:38:26.819Z,1570217906.819 [ElevatorOffsetCalculator] Loaded
2019-10-04T19:38:26.819Z,1570217906.819 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-04T19:38:26.819Z,1570217906.819 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-04T19:38:26.820Z,1570217906.820 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-04T19:38:26.980Z,1570217906.980 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-04T19:38:27.002Z,1570217907.002 [SBIT] Loaded
2019-10-04T19:38:27.002Z,1570217907.002 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-04T19:38:27.003Z,1570217907.003 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-04T19:38:27.014Z,1570217907.014 [IBIT] Loaded
2019-10-04T19:38:27.015Z,1570217907.015 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-04T19:38:27.018Z,1570217907.018 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-04T19:38:27.154Z,1570217907.154 [CBIT] Loaded
2019-10-04T19:38:27.155Z,1570217907.155 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-04T19:38:27.155Z,1570217907.155 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-04T19:38:27.156Z,1570217907.156 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-04T19:38:27.280Z,1570217907.280 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-04T19:38:27.281Z,1570217907.281 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-04T19:38:27.407Z,1570217907.407 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-04T19:38:27.407Z,1570217907.407 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-04T19:38:27.494Z,1570217907.494 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-04T19:38:27.577Z,1570217907.577 [VerticalControl] Loaded
2019-10-04T19:38:27.577Z,1570217907.577 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-04T19:38:27.578Z,1570217907.578 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-04T19:38:27.635Z,1570217907.635 [HorizontalControl] Loaded
2019-10-04T19:38:27.635Z,1570217907.635 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-04T19:38:27.636Z,1570217907.636 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-04T19:38:27.638Z,1570217907.638 [SpeedControl] Loaded
2019-10-04T19:38:27.638Z,1570217907.638 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-04T19:38:27.639Z,1570217907.639 [LoopControl](DEBUG): Construct LoopControl.
2019-10-04T19:38:27.639Z,1570217907.639 [LoopControl] Loaded
2019-10-04T19:38:27.639Z,1570217907.639 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-04T19:38:27.640Z,1570217907.640 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-04T19:38:27.640Z,1570217907.640 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-04T19:38:27.688Z,1570217907.688 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-04T19:38:27.691Z,1570217907.691 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-04T19:38:27.692Z,1570217907.692 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-04T19:38:27.699Z,1570217907.699 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-04T19:38:27.700Z,1570217907.700 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AED4E0
2019-10-04T19:38:27.700Z,1570217907.700 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 894
2019-10-04T19:38:27.705Z,1570217907.705 [Supervisor](INFO): Main Thread ID is 803
2019-10-04T19:38:27.705Z,1570217907.705 [Supervisor](DEBUG): Running supervisor.
2019-10-04T19:38:27.706Z,1570217907.706 [CommandLine ThreadHandler](INFO): Handler Thread ID is 895
2019-10-04T19:38:27.708Z,1570217907.708 [controlThread ThreadHandler](INFO): Handler Thread ID is 896
2019-10-04T19:38:27.709Z,1570217907.709 [controlThread](DEBUG): Initializing ControlThread
2019-10-04T19:38:27.716Z,1570217907.716 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-04T19:38:27.717Z,1570217907.717 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-04T19:38:27.721Z,1570217907.721 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-04T19:38:27.721Z,1570217907.721 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-04T19:38:27.722Z,1570217907.722 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-04T19:38:27.722Z,1570217907.722 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-04T19:38:27.723Z,1570217907.723 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-04T19:38:27.723Z,1570217907.723 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-04T19:38:27.723Z,1570217907.723 [SBIT](INFO): Initialize SBIT Component.
2019-10-04T19:38:27.724Z,1570217907.724 [SBIT](IMPORTANT): git: 2019-08-24A
2019-10-04T19:38:27.724Z,1570217907.724 [SBIT](INFO): git hash: 1cd70827f152bddb570e5fcd117087690803f3ad
2019-10-04T19:38:27.724Z,1570217907.724 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-04T19:38:27.726Z,1570217907.726 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2019-10-04T19:38:27.727Z,1570217907.727 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-10-04T19:38:27.727Z,1570217907.727 [IBIT](INFO): Initialize IBIT Component.
2019-10-04T19:38:27.728Z,1570217907.728 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-04T19:38:27.729Z,1570217907.729 [logger ThreadHandler](INFO): Handler Thread ID is 897
2019-10-04T19:38:27.741Z,1570217907.741 [CBIT](DEBUG): Initialized mux pins.
2019-10-04T19:38:27.741Z,1570217907.741 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-10-04T19:38:27.741Z,1570217907.741 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-04T19:38:27.749Z,1570217907.749 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 898
2019-10-04T19:38:27.750Z,1570217907.750 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-04T19:38:27.761Z,1570217907.761 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 899
2019-10-04T19:38:27.765Z,1570217907.765 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-10-04T19:38:27.765Z,1570217907.765 [CBIT](DEBUG): Initializing heartbeat.
2019-10-04T19:38:27.773Z,1570217907.773 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 900
2019-10-04T19:38:27.774Z,1570217907.774 [CTD_NeilBrown](INFO): Powering down
2019-10-04T19:38:27.801Z,1570217907.801 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 901
2019-10-04T19:38:27.802Z,1570217907.802 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-10-04T19:38:27.829Z,1570217907.829 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 902
2019-10-04T19:38:27.837Z,1570217907.837 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-04T19:38:27.837Z,1570217907.837 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-04T19:38:27.839Z,1570217907.839 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-04T19:38:27.839Z,1570217907.839 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-04T19:38:27.839Z,1570217907.839 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-04T19:38:27.840Z,1570217907.840 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-04T19:38:27.840Z,1570217907.840 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-04T19:38:27.840Z,1570217907.840 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-04T19:38:27.840Z,1570217907.840 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-04T19:38:27.840Z,1570217907.840 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-04T19:38:27.841Z,1570217907.841 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-04T19:38:27.841Z,1570217907.841 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-04T19:38:27.841Z,1570217907.841 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-04T19:38:27.841Z,1570217907.841 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-04T19:38:27.842Z,1570217907.842 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-04T19:38:27.842Z,1570217907.842 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-04T19:38:27.842Z,1570217907.842 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-04T19:38:27.842Z,1570217907.842 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-04T19:38:27.873Z,1570217907.873 [CBIT](DEBUG): Backplane powered.
2019-10-04T19:38:27.873Z,1570217907.873 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-04T19:38:27.875Z,1570217907.875 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-04T19:38:27.876Z,1570217907.876 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-04T19:38:27.876Z,1570217907.876 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-04T19:38:27.877Z,1570217907.877 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-04T19:38:27.902Z,1570217907.902 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-04T19:38:27.942Z,1570217907.942 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-10-04T19:38:27.942Z,1570217907.942 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-04T19:38:28.013Z,1570217908.013 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-04T19:38:28.014Z,1570217908.014 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-04T19:38:28.016Z,1570217908.016 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-04T19:38:28.051Z,1570217908.051 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-04T19:38:28.076Z,1570217908.076 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-04T19:38:28.101Z,1570217908.101 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-04T19:38:28.121Z,1570217908.121 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-10-04T19:38:28.126Z,1570217908.126 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-04T19:38:28.141Z,1570217908.141 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-04T19:38:28.165Z,1570217908.165 [Radio_Surface](INFO): Powering up
2019-10-04T19:38:28.495Z,1570217908.495 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-04T19:38:28.497Z,1570217908.497 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-04T19:38:28.498Z,1570217908.498 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-04T19:38:28.505Z,1570217908.505 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-04T19:38:28.506Z,1570217908.506 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-04T19:38:28.513Z,1570217908.513 [MassServo](DEBUG): Initializing MassServo.
2019-10-04T19:38:28.514Z,1570217908.514 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-04T19:38:28.521Z,1570217908.521 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-04T19:38:28.522Z,1570217908.522 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-04T19:38:28.529Z,1570217908.529 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-04T19:38:28.531Z,1570217908.531 [CommandLine](FAULT): Scheduling is paused
2019-10-04T19:38:28.532Z,1570217908.532 [CBIT](INFO): Critical error at 20191004T193827
2019-10-04T19:38:28.532Z,1570217908.532 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-10-04T19:38:28.612Z,1570217908.612 [Micromodem](INFO): Powering up
2019-10-04T19:38:28.612Z,1570217908.612 [Micromodem](DEBUG): Initializing Micromodem.
2019-10-04T19:38:33.431Z,1570217913.431 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-10-04T19:38:34.225Z,1570217914.225 [Radio_Surface](INFO): Powering down
2019-10-04T19:38:37.455Z,1570217917.455 [Micromodem](INFO): Nmea in: $CATMG,2019-10-04T19:38:27.684545Z,RTC,RTC*57

2019-10-04T19:38:37.455Z,1570217917.455 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2019-10-04T19:38:27.684545Z,RTC,RTC*57

2019-10-04T19:38:38.674Z,1570217918.674 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:38:38.674Z,1570217918.674 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-10-04T19:38:38.674Z,1570217918.674 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:38:38.785Z,1570217918.785 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:38:39.189Z,1570217919.189 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:38:39.993Z,1570217919.993 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:38:39.993Z,1570217919.993 [Aanderaa_O2] No Fault, FailCount= 1
2019-10-04T19:38:41.904Z,1570217921.904 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-10-04T19:38:44.711Z,1570217924.711 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-10-04T19:38:45.115Z,1570217925.115 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-10-04T19:38:45.115Z,1570217925.115 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-10-04T19:38:45.527Z,1570217925.527 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-10-04T19:38:45.528Z,1570217925.528 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-10-04T19:38:45.923Z,1570217925.923 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-10-04T19:38:45.923Z,1570217925.923 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2019-10-04T19:38:47.135Z,1570217927.135 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2019-10-04T19:38:47.135Z,1570217927.135 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2019-10-04T19:38:47.539Z,1570217927.539 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2019-10-04T19:38:47.539Z,1570217927.539 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2019-10-04T19:38:47.942Z,1570217927.942 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2019-10-04T19:38:47.943Z,1570217927.943 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2019-10-04T19:38:48.347Z,1570217928.347 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2019-10-04T19:38:48.347Z,1570217928.347 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2019-10-04T19:38:48.750Z,1570217928.750 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2019-10-04T19:38:48.751Z,1570217928.751 [Micromodem](INFO): Nmea out: $CCCLK,2019,10,04,19,38,49*45

2019-10-04T19:38:49.155Z,1570217929.155 [Micromodem](INFO): Nmea in: $CACLK,2019,10,4,19,38,49*77

2019-10-04T19:38:49.156Z,1570217929.156 [Micromodem](INFO): Nmea in: $CATMS,0,2019-10-04T19:38:50Z*7F

2019-10-04T19:38:49.158Z,1570217929.158 [Micromodem](INFO): Nmea in: $CATMG,2019-10-04T19:38:50.028355Z,USER_CMD,RTC*11

2019-10-04T19:38:50.379Z,1570217930.379 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:38:50.379Z,1570217930.379 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-10-04T19:38:50.380Z,1570217930.380 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:38:50.490Z,1570217930.490 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:38:50.917Z,1570217930.917 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:38:51.713Z,1570217931.713 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:38:51.713Z,1570217931.713 [Aanderaa_O2] No Fault, FailCount= 2
2019-10-04T19:38:53.999Z,1570217933.999 [NAL9602](INFO): Powering up NAL9602
2019-10-04T19:38:56.134Z,1570217936.134 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-04T19:38:56.138Z,1570217936.138 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-04T19:39:02.105Z,1570217942.105 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:39:02.105Z,1570217942.105 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-10-04T19:39:02.105Z,1570217942.105 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:39:02.182Z,1570217942.182 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:39:02.183Z,1570217942.183 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-10-04T19:39:02.626Z,1570217942.626 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:39:04.907Z,1570217944.907 [NAL9602](INFO): NAL9602 initialized
2019-10-04T19:39:05.734Z,1570217945.734 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:39:07.054Z,1570217947.054 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.013361
CHAN A1 (24V): -0.014856
CHAN A2 (12V): -0.001550
CHAN A3 (5V): -0.000565
CHAN B0 (3.3V): 0.000868
CHAN B1 (3.15aV): 0.000267
CHAN B2 (3.15bV): 0.000283
CHAN B3 (GND): 0.000409
OPEN: 0.007265
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-04T19:39:09.491Z,1570217949.491 [SBIT](ERROR): Could not read massPosReader_.
2019-10-04T19:39:49.975Z,1570217989.975 [SBIT](CRITICAL): SBIT FAILED
2019-10-04T19:39:50.005Z,1570217990.005 [CBIT](INFO): Critical error at 20191004T193949
2019-10-04T19:39:50.065Z,1570217990.065 [CommandLine](IMPORTANT): got command configSet list
2019-10-04T19:39:50.066Z,1570217990.066 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-04T19:39:50.069Z,1570217990.069 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-10-04T19:39:50.319Z,1570217990.319 [MissionManager](IMPORTANT): Started mission Startup
2019-10-04T19:39:50.319Z,1570217990.319 [Startup] Running Loop=1
2019-10-04T19:39:50.319Z,1570217990.319 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-04T19:39:50.320Z,1570217990.320 [Startup:A.GoToSurface] Running Loop=1
2019-10-04T19:39:50.320Z,1570217990.320 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-04T19:39:50.320Z,1570217990.320 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-04T19:39:50.321Z,1570217990.321 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-04T19:39:50.321Z,1570217990.321 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-04T19:39:50.322Z,1570217990.322 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-04T19:39:50.322Z,1570217990.322 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-04T19:39:50.721Z,1570217990.721 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm (1 active estimators).
2019-10-04T19:39:50.985Z,1570217990.985 [Radio_Surface](INFO): Powering up
2019-10-04T19:40:34.848Z,1570218034.848 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: 2
2019-10-04T19:40:34.849Z,1570218034.849 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-10-04T19:41:27.798Z,1570218087.798 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-04T19:41:27.798Z,1570218087.798 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-04T19:41:27.861Z,1570218087.861 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-04T19:41:28.241Z,1570218088.241 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-04T19:41:28.241Z,1570218088.241 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-04T19:41:32.305Z,1570218092.305 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:41:32.305Z,1570218092.305 [Aanderaa_O2] No Fault, FailCount= 3
2019-10-04T19:41:42.733Z,1570218102.733 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:41:42.733Z,1570218102.733 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-10-04T19:41:42.734Z,1570218102.734 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:41:42.825Z,1570218102.825 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:41:43.245Z,1570218103.245 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:41:44.032Z,1570218104.032 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:41:44.033Z,1570218104.033 [Aanderaa_O2] No Fault, FailCount= 1
2019-10-04T19:41:54.432Z,1570218114.432 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:41:54.432Z,1570218114.432 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-10-04T19:41:54.432Z,1570218114.432 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:41:54.537Z,1570218114.537 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:41:54.961Z,1570218114.961 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:41:55.752Z,1570218115.752 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:41:55.753Z,1570218115.753 [Aanderaa_O2] No Fault, FailCount= 2
2019-10-04T19:42:06.155Z,1570218126.155 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:42:06.155Z,1570218126.155 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-10-04T19:42:06.155Z,1570218126.155 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:42:06.225Z,1570218126.225 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:42:06.225Z,1570218126.225 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-10-04T19:42:06.693Z,1570218126.693 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:43:01.518Z,1570218181.518 [Startup:StartupSatComms] Running Loop=1
2019-10-04T19:43:01.518Z,1570218181.518 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-04T19:43:01.518Z,1570218181.518 [Startup:StartupSatComms:A] Running Loop=1
2019-10-04T19:43:01.914Z,1570218181.914 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-04T19:43:04.309Z,1570218184.309 [ThrusterServo](ERROR): Thruster uart error: serial timeout
2019-10-04T19:43:04.309Z,1570218184.309 [ThrusterServo] Communications Fault, FailCount= 1
2019-10-04T19:43:04.309Z,1570218184.309 [ThrusterServo](ERROR): Communications Fault
2019-10-04T19:43:04.313Z,1570218184.313 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2019-10-04T19:43:04.435Z,1570218184.435 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-04T19:43:04.435Z,1570218184.435 [ThrusterServo](INFO): Powering down
2019-10-04T19:43:05.336Z,1570218185.336 [CBIT](INFO): Clearing failed state for component ThrusterServo
2019-10-04T19:43:05.336Z,1570218185.336 [ThrusterServo] No Fault, FailCount= 1
2019-10-04T19:43:05.693Z,1570218185.693 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-04T19:43:05.694Z,1570218185.694 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-04T19:44:01.618Z,1570218241.618 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-04T19:43:01.5Z
2019-10-04T19:44:01.618Z,1570218241.618 [Startup:StartupSatComms:A] Stopped
2019-10-04T19:44:01.618Z,1570218241.618 [Startup:StartupSatComms:B] Running Loop=1
2019-10-04T19:44:02.033Z,1570218242.033 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-04T19:44:05.075Z,1570218245.075 [Micromodem](INFO): Using rate 5 for 26 bytes.
2019-10-04T19:44:05.076Z,1570218245.076 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:05.076Z,1570218245.076 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:05.367Z,1570218245.367 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:05.711Z,1570218245.711 [Micromodem](INFO): Nmea in: $CADRQ,194406,1,9,0,64,1*40

2019-10-04T19:44:05.711Z,1570218245.711 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:07.476Z,1570218247.476 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:07.476Z,1570218247.476 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:07.477Z,1570218247.477 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:07.643Z,1570218247.643 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:08.007Z,1570218248.007 [Micromodem](INFO): Nmea in: $CADRQ,194408,1,9,0,64,1*4E

2019-10-04T19:44:08.007Z,1570218248.007 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:09.854Z,1570218249.854 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:09.855Z,1570218249.855 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:09.855Z,1570218249.855 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:09.861Z,1570218249.861 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-04T19:44:10.003Z,1570218250.003 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:10.343Z,1570218250.343 [Micromodem](INFO): Nmea in: $CADRQ,194410,1,9,0,64,1*47

2019-10-04T19:44:10.343Z,1570218250.343 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:12.132Z,1570218252.132 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:12.132Z,1570218252.132 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:12.132Z,1570218252.132 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:12.177Z,1570218252.177 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:44:12.295Z,1570218252.295 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:12.654Z,1570218252.654 [Micromodem](INFO): Nmea in: $CADRQ,194413,1,9,0,64,1*44

2019-10-04T19:44:12.655Z,1570218252.655 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:14.478Z,1570218254.478 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:14.478Z,1570218254.478 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:14.479Z,1570218254.479 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:14.660Z,1570218254.660 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:14.995Z,1570218254.995 [Micromodem](INFO): Nmea in: $CADRQ,194415,1,9,0,64,1*42

2019-10-04T19:44:14.995Z,1570218254.995 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:16.790Z,1570218256.790 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:16.791Z,1570218256.791 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:16.791Z,1570218256.791 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:16.953Z,1570218256.953 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:17.299Z,1570218257.299 [Micromodem](INFO): Nmea in: $CADRQ,194417,1,9,0,64,1*40

2019-10-04T19:44:17.299Z,1570218257.299 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:19.123Z,1570218259.123 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:19.124Z,1570218259.124 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:19.124Z,1570218259.124 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:19.141Z,1570218259.141 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:44:19.263Z,1570218259.263 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:19.607Z,1570218259.607 [Micromodem](INFO): Nmea in: $CADRQ,194420,1,9,0,64,1*44

2019-10-04T19:44:19.607Z,1570218259.607 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:21.414Z,1570218261.414 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:21.415Z,1570218261.415 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:21.415Z,1570218261.415 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:21.611Z,1570218261.611 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-04T19:44:21.691Z,1570218261.691 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:22.055Z,1570218262.055 [Micromodem](INFO): Nmea in: $CADRQ,194422,1,9,0,64,1*46

2019-10-04T19:44:22.056Z,1570218262.056 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:23.872Z,1570218263.872 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:23.873Z,1570218263.873 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:23.873Z,1570218263.873 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:24.051Z,1570218264.051 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:24.399Z,1570218264.399 [Micromodem](INFO): Nmea in: $CADRQ,194424,1,9,0,64,1*40

2019-10-04T19:44:24.400Z,1570218264.400 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:26.198Z,1570218266.198 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:26.198Z,1570218266.198 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:26.199Z,1570218266.199 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:26.217Z,1570218266.217 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:44:26.404Z,1570218266.404 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:26.727Z,1570218266.727 [Micromodem](INFO): Nmea in: $CADRQ,194427,1,9,0,64,1*43

2019-10-04T19:44:26.727Z,1570218266.727 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:28.515Z,1570218268.515 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:28.516Z,1570218268.516 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:28.516Z,1570218268.516 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:28.600Z,1570218268.600 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-04T19:44:28.601Z,1570218268.601 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-04T19:44:28.614Z,1570218268.614 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-04T19:44:28.679Z,1570218268.679 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:28.761Z,1570218268.761 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-04T19:44:28.761Z,1570218268.761 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-04T19:44:29.031Z,1570218269.031 [Micromodem](INFO): Nmea in: $CADRQ,194429,1,9,0,64,1*4D

2019-10-04T19:44:29.031Z,1570218269.031 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:30.819Z,1570218270.819 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:30.819Z,1570218270.819 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:30.820Z,1570218270.820 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:30.994Z,1570218270.994 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:31.335Z,1570218271.335 [Micromodem](INFO): Nmea in: $CADRQ,194431,1,9,0,64,1*44

2019-10-04T19:44:31.336Z,1570218271.336 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:33.132Z,1570218273.132 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:33.138Z,1570218273.138 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:33.138Z,1570218273.138 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:33.157Z,1570218273.157 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:44:33.287Z,1570218273.287 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:33.669Z,1570218273.669 [Micromodem](INFO): Nmea in: $CADRQ,194434,1,9,0,64,1*41

2019-10-04T19:44:33.669Z,1570218273.669 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:36.257Z,1570218276.257 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:36.258Z,1570218276.258 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:36.258Z,1570218276.258 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:36.348Z,1570218276.348 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:44:36.348Z,1570218276.348 [Aanderaa_O2] No Fault, FailCount= 3
2019-10-04T19:44:36.423Z,1570218276.423 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:36.771Z,1570218276.771 [Micromodem](INFO): Nmea in: $CADRQ,194437,1,9,0,64,1*42

2019-10-04T19:44:36.771Z,1570218276.771 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:38.540Z,1570218278.540 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:38.540Z,1570218278.540 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:38.554Z,1570218278.554 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:38.799Z,1570218278.799 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:39.151Z,1570218279.151 [Micromodem](INFO): Nmea in: $CADRQ,194439,1,9,0,64,1*4C

2019-10-04T19:44:39.151Z,1570218279.151 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:41.145Z,1570218281.145 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:41.146Z,1570218281.146 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:41.146Z,1570218281.146 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:41.165Z,1570218281.165 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:44:41.300Z,1570218281.300 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:41.673Z,1570218281.673 [Micromodem](INFO): Nmea in: $CADRQ,194442,1,9,0,64,1*40

2019-10-04T19:44:41.674Z,1570218281.674 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:43.440Z,1570218283.440 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:43.440Z,1570218283.440 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:43.441Z,1570218283.441 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:43.602Z,1570218283.602 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:43.943Z,1570218283.943 [Micromodem](INFO): Nmea in: $CADRQ,194444,1,9,0,64,1*46

2019-10-04T19:44:43.943Z,1570218283.943 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:45.778Z,1570218285.778 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:45.779Z,1570218285.779 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:45.779Z,1570218285.779 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:45.959Z,1570218285.959 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:46.283Z,1570218286.283 [Micromodem](INFO): Nmea in: $CADRQ,194446,1,9,0,64,1*44

2019-10-04T19:44:46.283Z,1570218286.283 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:48.081Z,1570218288.081 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:48.081Z,1570218288.081 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:48.082Z,1570218288.082 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:48.101Z,1570218288.101 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:44:48.211Z,1570218288.211 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:44:48.211Z,1570218288.211 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-10-04T19:44:48.211Z,1570218288.211 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:44:48.246Z,1570218288.246 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:44:48.295Z,1570218288.295 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:48.477Z,1570218288.477 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:44:48.671Z,1570218288.671 [Micromodem](INFO): Nmea in: $CADRQ,194449,1,9,0,64,1*4B

2019-10-04T19:44:48.671Z,1570218288.671 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:50.482Z,1570218290.482 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:50.483Z,1570218290.483 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:50.483Z,1570218290.483 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:50.675Z,1570218290.675 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:50.745Z,1570218290.745 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:44:50.745Z,1570218290.745 [Aanderaa_O2] No Fault, FailCount= 1
2019-10-04T19:44:51.003Z,1570218291.003 [Micromodem](INFO): Nmea in: $CADRQ,194451,1,9,0,64,1*42

2019-10-04T19:44:51.003Z,1570218291.003 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:52.795Z,1570218292.795 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:52.795Z,1570218292.795 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:52.796Z,1570218292.796 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:52.947Z,1570218292.947 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:53.291Z,1570218293.291 [Micromodem](INFO): Nmea in: $CADRQ,194453,1,9,0,64,1*40

2019-10-04T19:44:53.292Z,1570218293.292 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:55.118Z,1570218295.118 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:55.119Z,1570218295.119 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:55.119Z,1570218295.119 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:55.137Z,1570218295.137 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:44:55.343Z,1570218295.343 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:55.679Z,1570218295.679 [Micromodem](INFO): Nmea in: $CADRQ,194456,1,9,0,64,1*45

2019-10-04T19:44:55.679Z,1570218295.679 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:57.444Z,1570218297.444 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:57.445Z,1570218297.445 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:57.446Z,1570218297.446 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:57.623Z,1570218297.623 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:44:57.967Z,1570218297.967 [Micromodem](INFO): Nmea in: $CADRQ,194458,1,9,0,64,1*4B

2019-10-04T19:44:57.967Z,1570218297.967 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:44:59.795Z,1570218299.795 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:44:59.796Z,1570218299.796 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:44:59.796Z,1570218299.796 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:44:59.987Z,1570218299.987 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:45:00.315Z,1570218300.315 [Micromodem](INFO): Nmea in: $CADRQ,194500,1,9,0,64,1*47

2019-10-04T19:45:00.315Z,1570218300.315 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:45:02.128Z,1570218302.128 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:45:02.128Z,1570218302.128 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:45:02.129Z,1570218302.129 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:45:02.175Z,1570218302.175 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:02.214Z,1570218302.214 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-04T19:44:01.6Z
2019-10-04T19:45:02.214Z,1570218302.214 [Startup:StartupSatComms:B] Stopped
2019-10-04T19:45:02.214Z,1570218302.214 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-10-04T19:45:02.214Z,1570218302.214 [Startup:StartupSatComms] Stopped
2019-10-04T19:45:02.214Z,1570218302.214 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-04T19:45:02.215Z,1570218302.215 [Startup](INFO): Completed Startup
2019-10-04T19:45:02.215Z,1570218302.215 [MissionManager](INFO): Startup is completed.
2019-10-04T19:45:02.216Z,1570218302.216 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-04T19:45:02.216Z,1570218302.216 [Startup] Stopped
2019-10-04T19:45:02.216Z,1570218302.216 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-04T19:45:02.216Z,1570218302.216 [Startup:A.GoToSurface] Stopped
2019-10-04T19:45:02.216Z,1570218302.216 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-04T19:45:02.315Z,1570218302.315 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:45:02.316Z,1570218302.316 [Micromodem](INFO): Nmea in: $CADRQ,194503,1,9,0,64,1*44

2019-10-04T19:45:02.317Z,1570218302.317 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:45:02.363Z,1570218302.363 [MissionManager](IMPORTANT): Started mission Default
2019-10-04T19:45:02.363Z,1570218302.363 [Default] Running Loop=1
2019-10-04T19:45:02.364Z,1570218302.364 [Default](DEBUG): Aggregate::initialize Default
2019-10-04T19:45:02.364Z,1570218302.364 [Default:B.GoToSurface] Running Loop=1
2019-10-04T19:45:02.364Z,1570218302.364 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-04T19:45:02.364Z,1570218302.364 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-04T19:45:02.364Z,1570218302.364 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-04T19:45:02.369Z,1570218302.369 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-04T19:45:02.369Z,1570218302.369 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-04T19:45:02.370Z,1570218302.370 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-04T19:45:02.370Z,1570218302.370 [Default:A.Wait] Running Loop=1
2019-10-04T19:45:02.370Z,1570218302.370 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-04T19:45:03.059Z,1570218303.059 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:45:03.060Z,1570218303.060 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-10-04T19:45:03.060Z,1570218303.060 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:45:03.080Z,1570218303.080 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:45:03.581Z,1570218303.581 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:45:04.316Z,1570218304.316 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:45:04.316Z,1570218304.316 [Aanderaa_O2] No Fault, FailCount= 2
2019-10-04T19:45:05.081Z,1570218305.081 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:07.917Z,1570218307.917 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:08.982Z,1570218308.982 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004531
2019-10-04T19:45:10.737Z,1570218310.737 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:13.969Z,1570218313.969 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:14.801Z,1570218314.801 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:45:14.801Z,1570218314.801 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-10-04T19:45:14.801Z,1570218314.801 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:45:14.824Z,1570218314.824 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:45:14.824Z,1570218314.824 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-10-04T19:45:15.298Z,1570218315.298 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:45:15.600Z,1570218315.600 [Default:A.Wait](INFO): Done Waiting.
2019-10-04T19:45:15.600Z,1570218315.600 [Default:A.Wait] Stopped
2019-10-04T19:45:15.600Z,1570218315.600 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-04T19:45:16.004Z,1570218316.004 [Default:CheckIn] Running Loop=1
2019-10-04T19:45:16.004Z,1570218316.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-04T19:45:16.005Z,1570218316.005 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-04T19:45:16.459Z,1570218316.459 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-10-04T19:45:16.793Z,1570218316.793 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:20.025Z,1570218320.025 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:22.853Z,1570218322.853 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:26.085Z,1570218326.085 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:28.913Z,1570218328.913 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:31.745Z,1570218331.745 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:32.539Z,1570218332.539 [Micromodem](INFO): Nmea in: $CAERR,194533,DATA_TIMEOUT,1*47

2019-10-04T19:45:34.977Z,1570218334.977 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:37.805Z,1570218337.805 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:41.033Z,1570218341.033 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:43.861Z,1570218343.861 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:47.093Z,1570218347.093 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:49.921Z,1570218349.921 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:52.749Z,1570218352.749 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:55.985Z,1570218355.985 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:45:58.813Z,1570218358.813 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:02.041Z,1570218362.041 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:04.869Z,1570218364.869 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:08.105Z,1570218368.105 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:10.929Z,1570218370.929 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:13.761Z,1570218373.761 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:16.993Z,1570218376.993 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:19.821Z,1570218379.821 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:23.057Z,1570218383.057 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:25.885Z,1570218385.885 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:29.121Z,1570218389.121 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:31.941Z,1570218391.941 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:34.765Z,1570218394.765 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:37.997Z,1570218397.997 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:40.825Z,1570218400.825 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:44.057Z,1570218404.057 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:46.889Z,1570218406.889 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:50.117Z,1570218410.117 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:52.952Z,1570218412.952 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:56.182Z,1570218416.182 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:46:56.205Z,1570218416.205 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-10-04T19:46:56.208Z,1570218416.208 [BPC1](INFO): Received data from all battery sticks.
2019-10-04T19:46:59.009Z,1570218419.009 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:47:01.833Z,1570218421.833 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:47:03.862Z,1570218423.862 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-10-04T19:47:05.065Z,1570218425.065 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:47:07.893Z,1570218427.893 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:47:11.125Z,1570218431.125 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:47:13.961Z,1570218433.961 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:47:17.185Z,1570218437.185 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:47:20.017Z,1570218440.017 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:47:23.249Z,1570218443.249 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:47:23.654Z,1570218443.654 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194156.00,A,3648.16222,N,12147.28680,W,0.233,337.29,041019,,,A*78
2019-10-04T19:47:23.677Z,1570218443.677 [NAL9602](INFO): GPS fix at 20191004T194156: (36.802704, -121.788113)
2019-10-04T19:47:23.723Z,1570218443.723 [Default:CheckIn:Read_GPS] Stopped
2019-10-04T19:47:23.723Z,1570218443.723 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-04T19:47:24.100Z,1570218444.100 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-04T19:47:29.560Z,1570218449.560 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:47:29.561Z,1570218449.561 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:47:29.561Z,1570218449.561 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:47:30.483Z,1570218450.483 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:47:30.981Z,1570218450.981 [Micromodem](INFO): Nmea in: $CADRQ,194730,1,9,0,64,1*46

2019-10-04T19:47:30.982Z,1570218450.982 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:47:36.950Z,1570218456.950 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-10-04T19:47:36.950Z,1570218456.950 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 3D32965DC30100005D0000040000000000000000000000000000
2019-10-04T19:47:36.951Z,1570218456.951 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-10-04T19:47:37.279Z,1570218457.279 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-10-04T19:47:37.665Z,1570218457.665 [Micromodem](INFO): Nmea in: $CADRQ,194737,1,9,0,64,1*41

2019-10-04T19:47:37.666Z,1570218457.666 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-10-04T19:47:38.848Z,1570218458.848 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20191003T173909/Courier0451.lzma
2019-10-04T19:47:39.659Z,1570218459.659 [DataOverHttps](INFO): Moved sent file to Logs/20191003T173909/Courier0451.lzma.bak
2019-10-04T19:47:39.660Z,1570218459.660 [DataOverHttps](INFO): SBD MOMSN=11850713
2019-10-04T19:47:39.974Z,1570218459.974 [Micromodem](INFO): Outgoing frame #1, 64 bytes: A89F975D000000005D00000400810000000000000000151D80030C5669BF31555EAE1409A708CE248A69041B345E92F8B20B7420A5EA58D066A72551B64B29E8
2019-10-04T19:47:39.974Z,1570218459.974 [Micromodem](INFO): Outgoing frame #2, 64 bytes: E1AA92AB1A87E50FF240ACC03A4DE0E691B5CDBAA0AFA111CA9A2C6022E6EEC7F77E2CCF5DE8DD6822153CBC1C68549AC2881F9F1374EE431C5470D8B4BA0647
2019-10-04T19:47:39.975Z,1570218459.975 [Micromodem](INFO): Outgoing frame #3, 1 bytes: 00
2019-10-04T19:47:39.975Z,1570218459.975 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53

2019-10-04T19:47:40.185Z,1570218460.185 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51

2019-10-04T19:47:40.551Z,1570218460.551 [Micromodem](INFO): Nmea in: $CADRQ,194740,1,9,0,64,1*41

2019-10-04T19:47:40.551Z,1570218460.551 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,A89F975D000000005D00000400810000000000000000151D80030C5669BF31555EAE1409A708CE248A69041B345E92F8B20B7420A5EA58D066A72551B64B29E8*79

2019-10-04T19:47:40.950Z,1570218460.950 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-04T19:47:41.355Z,1570218461.355 [Micromodem](INFO): Nmea in: $CADRQ,194741,1,9,0,64,2*43

2019-10-04T19:47:41.355Z,1570218461.355 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,E1AA92AB1A87E50FF240ACC03A4DE0E691B5CDBAA0AFA111CA9A2C6022E6EEC7F77E2CCF5DE8DD6822153CBC1C68549AC2881F9F1374EE431C5470D8B4BA0647*04

2019-10-04T19:47:41.762Z,1570218461.762 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-04T19:47:42.163Z,1570218462.163 [Micromodem](INFO): Nmea in: $CADRQ,194742,1,9,0,64,3*41

2019-10-04T19:47:42.163Z,1570218462.163 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,00*71

2019-10-04T19:47:42.566Z,1570218462.566 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,1*42

2019-10-04T19:47:42.976Z,1570218462.976 [Micromodem](INFO): Nmea in: $CATXP,129*48

2019-10-04T19:47:45.142Z,1570218465.142 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:47:45.142Z,1570218465.142 [Aanderaa_O2] No Fault, FailCount= 3
2019-10-04T19:47:46.603Z,1570218466.603 [Micromodem](INFO): Nmea in: $CATXF,129*5E

2019-10-04T19:47:47.008Z,1570218467.008 [Micromodem](INFO): Nmea in: $CAXST,6,20191004,194743.154020,3,0,200,4000,10000,1,1,9,1,3,3,3,129*41

2019-10-04T19:47:55.232Z,1570218475.232 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20191004T193816/Courier0000.lzma
2019-10-04T19:47:55.512Z,1570218475.512 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:47:55.512Z,1570218475.512 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-10-04T19:47:55.512Z,1570218475.512 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:47:55.530Z,1570218475.530 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:47:56.038Z,1570218476.038 [DataOverHttps](INFO): Moved sent file to Logs/20191004T193816/Courier0000.lzma.bak
2019-10-04T19:47:56.038Z,1570218476.038 [DataOverHttps](INFO): SBD MOMSN=11850715
2019-10-04T19:47:56.057Z,1570218476.057 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:47:56.794Z,1570218476.794 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:47:56.794Z,1570218476.794 [Aanderaa_O2] No Fault, FailCount= 1
2019-10-04T19:48:00.746Z,1570218480.746 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-04T19:48:07.225Z,1570218487.225 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:48:07.225Z,1570218487.225 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-10-04T19:48:07.225Z,1570218487.225 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:48:07.294Z,1570218487.294 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:48:07.765Z,1570218487.765 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:48:08.466Z,1570218488.466 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:48:08.466Z,1570218488.466 [Aanderaa_O2] No Fault, FailCount= 2
2019-10-04T19:48:10.413Z,1570218490.413 [DataOverHttps](INFO): Sending 300 bytes from file Logs/20191004T193816/Courier0004.lzma
2019-10-04T19:48:11.551Z,1570218491.551 [DataOverHttps](INFO): Moved sent file to Logs/20191004T193816/Courier0004.lzma.bak
2019-10-04T19:48:11.551Z,1570218491.551 [DataOverHttps](INFO): SBD MOMSN=11850720
2019-10-04T19:48:18.952Z,1570218498.952 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:48:18.952Z,1570218498.952 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-10-04T19:48:18.957Z,1570218498.957 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:48:18.982Z,1570218498.982 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:48:18.983Z,1570218498.983 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-10-04T19:48:19.465Z,1570218499.465 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:48:27.328Z,1570218507.328 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20191003T173909/Express0452.lzma
2019-10-04T19:48:28.135Z,1570218508.135 [DataOverHttps](INFO): Moved sent file to Logs/20191003T173909/Express0452.lzma.bak
2019-10-04T19:48:28.135Z,1570218508.135 [DataOverHttps](INFO): SBD MOMSN=11850723
2019-10-04T19:48:44.074Z,1570218524.074 [DataOverHttps](INFO): Sending 778 bytes from file Logs/20191004T193816/Express0001.lzma
2019-10-04T19:48:44.878Z,1570218524.878 [DataOverHttps](INFO): Moved sent file to Logs/20191004T193816/Express0001.lzma.bak
2019-10-04T19:48:44.878Z,1570218524.878 [DataOverHttps](INFO): SBD MOMSN=11850725
2019-10-04T19:48:45.186Z,1570218525.186 [Micromodem](ERROR): Buffer fill timout failure.
2019-10-04T19:48:49.230Z,1570218529.230 [Default:CheckIn:Read_Iridium] Stopped
2019-10-04T19:48:49.230Z,1570218529.230 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-04T19:48:49.230Z,1570218529.230 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-04T19:49:34.417Z,1570218574.417 [DataOverHttps](INFO): Sending 373 bytes from file Logs/20191004T193816/Express0005.lzma
2019-10-04T19:49:35.222Z,1570218575.222 [DataOverHttps](INFO): Moved sent file to Logs/20191004T193816/Express0005.lzma.bak
2019-10-04T19:49:35.222Z,1570218575.222 [DataOverHttps](INFO): SBD MOMSN=11850747
2019-10-04T19:50:49.298Z,1570218649.298 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:50:49.298Z,1570218649.298 [Aanderaa_O2] No Fault, FailCount= 3
2019-10-04T19:50:59.804Z,1570218659.804 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:50:59.805Z,1570218659.805 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-10-04T19:50:59.805Z,1570218659.805 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:50:59.822Z,1570218659.822 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:51:00.301Z,1570218660.301 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:51:01.022Z,1570218661.022 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:51:01.022Z,1570218661.022 [Aanderaa_O2] No Fault, FailCount= 1
2019-10-04T19:51:11.502Z,1570218671.502 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:51:11.502Z,1570218671.502 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-10-04T19:51:11.502Z,1570218671.502 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:51:11.520Z,1570218671.520 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:51:12.037Z,1570218672.037 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:51:12.751Z,1570218672.751 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:51:12.751Z,1570218672.751 [Aanderaa_O2] No Fault, FailCount= 2
2019-10-04T19:51:23.215Z,1570218683.215 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:51:23.215Z,1570218683.215 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-10-04T19:51:23.215Z,1570218683.215 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:51:23.238Z,1570218683.238 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:51:23.239Z,1570218683.239 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-10-04T19:51:23.753Z,1570218683.753 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:53:49.480Z,1570218829.480 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-04T19:53:49.480Z,1570218829.480 [Default:CheckIn:C.Wait] Stopped
2019-10-04T19:53:49.480Z,1570218829.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-04T19:53:49.480Z,1570218829.480 [Default:CheckIn:D] Running Loop=1
2019-10-04T19:53:49.898Z,1570218829.898 [Default:CheckIn:D] Stopped
2019-10-04T19:53:49.898Z,1570218829.898 [Default:CheckIn:E] Running Loop=1
2019-10-04T19:53:50.295Z,1570218830.295 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.792241 min 
2019-10-04T19:53:50.295Z,1570218830.295 [Default:CheckIn:E] Stopped
2019-10-04T19:53:50.295Z,1570218830.295 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-04T19:53:50.295Z,1570218830.295 [Default:CheckIn] Stopped
2019-10-04T19:53:50.295Z,1570218830.295 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-04T19:53:50.295Z,1570218830.295 [Default:CheckIn](INFO): Running loop #2
2019-10-04T19:53:50.295Z,1570218830.295 [Default:CheckIn] Running Loop=2
2019-10-04T19:53:50.296Z,1570218830.296 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-04T19:53:50.296Z,1570218830.296 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-04T19:53:51.889Z,1570218831.889 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:53:52.281Z,1570218832.281 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194826.00,A,3648.16441,N,12147.28608,W,0.253,358.71,041019,,,A*77
2019-10-04T19:53:52.283Z,1570218832.283 [NAL9602](INFO): GPS fix at 20191004T194826: (36.802740, -121.788101)
2019-10-04T19:53:52.313Z,1570218832.313 [Default:CheckIn:Read_GPS] Stopped
2019-10-04T19:53:52.313Z,1570218832.313 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-04T19:53:56.544Z,1570218836.544 [Micromodem](INFO): Outgoing frame #1, 64 bytes: A89F975D070000005D00000400370000000000000000151D80030C5669C08C35ED111A8BE64944990D0E30CCDB6CE6B6F5A4B628388ECB8D4DFECBDCD46B8B86
2019-10-04T19:53:56.544Z,1570218836.544 [Micromodem](INFO): Outgoing frame #2, 7 bytes: F7833749460000
2019-10-04T19:53:56.545Z,1570218836.545 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52

2019-10-04T19:53:56.701Z,1570218836.701 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:53:56.701Z,1570218836.701 [Aanderaa_O2] No Fault, FailCount= 3
2019-10-04T19:53:56.752Z,1570218836.752 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,195357,AUV,2.0.32731*1
 expected 17
2019-10-04T19:53:56.752Z,1570218836.752 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52

2019-10-04T19:53:57.527Z,1570218837.527 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50

2019-10-04T19:53:57.931Z,1570218837.931 [Micromodem](INFO): Nmea in: $CADRQ,195357,1,9,0,64,1*42

2019-10-04T19:53:57.931Z,1570218837.931 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,A89F975D070000005D00000400370000000000000000151D80030C5669C08C35ED111A8BE64944990D0E30CCDB6CE6B6F5A4B628388ECB8D4DFECBDCD46B8B86*00

2019-10-04T19:53:58.335Z,1570218838.335 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50

2019-10-04T19:53:58.335Z,1570218838.335 [Micromodem](ERROR): TXD response from modem unexpected: $CACYC,0,1,9,1,0,2*50

2019-10-04T19:53:58.739Z,1570218838.739 [Micromodem](INFO): Nmea in: $CADRQ,195357,1,9,0,64,1*42

2019-10-04T19:53:58.739Z,1570218838.739 [Micromodem](ERROR): TXD response from modem unexpected: $CADRQ,195357,1,9,0,64,1*42

2019-10-04T19:53:59.150Z,1570218839.150 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-04T19:53:59.547Z,1570218839.547 [Micromodem](INFO): Nmea in: $CADRQ,195358,1,9,0,64,2*4E

2019-10-04T19:53:59.547Z,1570218839.547 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,F7833749460000*00

2019-10-04T19:53:59.950Z,1570218839.950 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,7*44

2019-10-04T19:54:00.354Z,1570218840.354 [Micromodem](INFO): Nmea in: $CATXP,71*74

2019-10-04T19:54:01.764Z,1570218841.764 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191004T193816/Courier0007.lzma
2019-10-04T19:54:02.570Z,1570218842.570 [DataOverHttps](INFO): Moved sent file to Logs/20191004T193816/Courier0007.lzma.bak
2019-10-04T19:54:02.570Z,1570218842.570 [DataOverHttps](INFO): SBD MOMSN=11850755
2019-10-04T19:54:02.782Z,1570218842.782 [Micromodem](INFO): Nmea in: $CATXF,71*62

2019-10-04T19:54:03.183Z,1570218843.183 [Micromodem](INFO): Nmea in: $CAXST,6,20191004,195400.533982,3,0,200,4000,10000,1,1,9,1,2,2,3,71*7C

2019-10-04T19:54:06.855Z,1570218846.855 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:54:06.855Z,1570218846.855 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-10-04T19:54:06.855Z,1570218846.855 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:54:06.894Z,1570218846.894 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:54:07.357Z,1570218847.357 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:54:08.089Z,1570218848.089 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:54:08.089Z,1570218848.089 [Aanderaa_O2] No Fault, FailCount= 1
2019-10-04T19:54:18.561Z,1570218858.561 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:54:18.561Z,1570218858.561 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-10-04T19:54:18.561Z,1570218858.561 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:54:18.584Z,1570218858.584 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:54:18.613Z,1570218858.613 [DataOverHttps](INFO): Sending 263 bytes from file Logs/20191004T193816/Express0008.lzma
2019-10-04T19:54:19.097Z,1570218859.097 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:54:19.418Z,1570218859.418 [DataOverHttps](INFO): Moved sent file to Logs/20191004T193816/Express0008.lzma.bak
2019-10-04T19:54:19.419Z,1570218859.419 [DataOverHttps](INFO): SBD MOMSN=11850758
2019-10-04T19:54:19.838Z,1570218859.838 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:54:19.838Z,1570218859.838 [Aanderaa_O2] No Fault, FailCount= 2
2019-10-04T19:54:22.983Z,1570218862.983 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-10-04T19:54:23.061Z,1570218863.061 [NAL9602](FAULT): received: 
+CSQ:0

OK
2019-10-04T19:54:23.061Z,1570218863.061 [NAL9602] Data Fault, FailCount= 1
2019-10-04T19:54:23.061Z,1570218863.061 [NAL9602](ERROR): Data Fault
2019-10-04T19:54:23.145Z,1570218863.145 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-04T19:54:23.383Z,1570218863.383 [NAL9602](INFO): Powering down
2019-10-04T19:54:24.232Z,1570218864.232 [Default:CheckIn:Read_Iridium] Stopped
2019-10-04T19:54:24.232Z,1570218864.232 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-04T19:54:24.232Z,1570218864.232 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-04T19:54:24.253Z,1570218864.253 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-04T19:54:24.253Z,1570218864.253 [NAL9602] No Fault, FailCount= 1
2019-10-04T19:54:30.267Z,1570218870.267 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:54:30.267Z,1570218870.267 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-10-04T19:54:30.267Z,1570218870.267 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:54:30.363Z,1570218870.363 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:54:30.363Z,1570218870.363 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-10-04T19:54:30.857Z,1570218870.857 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:54:53.683Z,1570218893.683 [NAL9602](INFO): Powering up NAL9602
2019-10-04T19:55:04.595Z,1570218904.595 [NAL9602](INFO): NAL9602 initialized
2019-10-04T19:55:35.706Z,1570218935.706 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-04T19:57:00.598Z,1570219020.598 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:57:00.598Z,1570219020.598 [Aanderaa_O2] No Fault, FailCount= 3
2019-10-04T19:57:11.071Z,1570219031.071 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:57:11.071Z,1570219031.071 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-10-04T19:57:11.072Z,1570219031.072 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:57:11.089Z,1570219031.089 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:57:11.577Z,1570219031.577 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:57:12.291Z,1570219032.291 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:57:12.291Z,1570219032.291 [Aanderaa_O2] No Fault, FailCount= 1
2019-10-04T19:57:22.788Z,1570219042.788 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:57:22.788Z,1570219042.788 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-10-04T19:57:22.788Z,1570219042.788 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:57:22.806Z,1570219042.806 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:57:23.301Z,1570219043.301 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:57:24.026Z,1570219044.026 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T19:57:24.026Z,1570219044.026 [Aanderaa_O2] No Fault, FailCount= 2
2019-10-04T19:57:34.511Z,1570219054.511 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T19:57:34.511Z,1570219054.511 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-10-04T19:57:34.511Z,1570219054.511 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T19:57:34.557Z,1570219054.557 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T19:57:34.558Z,1570219054.558 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-10-04T19:57:35.025Z,1570219055.025 [Aanderaa_O2](INFO): Powering down
2019-10-04T19:59:24.825Z,1570219164.825 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-04T19:59:24.825Z,1570219164.825 [Default:CheckIn:C.Wait] Stopped
2019-10-04T19:59:24.825Z,1570219164.825 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-04T19:59:24.825Z,1570219164.825 [Default:CheckIn:D] Running Loop=1
2019-10-04T19:59:25.208Z,1570219165.208 [Default:CheckIn:D] Stopped
2019-10-04T19:59:25.208Z,1570219165.208 [Default:CheckIn:E] Running Loop=1
2019-10-04T19:59:25.636Z,1570219165.636 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.380743 min 
2019-10-04T19:59:25.637Z,1570219165.637 [Default:CheckIn:E] Stopped
2019-10-04T19:59:25.637Z,1570219165.637 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-04T19:59:25.638Z,1570219165.638 [Default:CheckIn] Stopped
2019-10-04T19:59:25.638Z,1570219165.638 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-04T19:59:25.638Z,1570219165.638 [Default:CheckIn](INFO): Running loop #3
2019-10-04T19:59:25.639Z,1570219165.639 [Default:CheckIn] Running Loop=3
2019-10-04T19:59:25.639Z,1570219165.639 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-04T19:59:25.639Z,1570219165.639 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-04T19:59:27.205Z,1570219167.205 [NAL9602](DEBUG): Fix Requested
2019-10-04T19:59:27.600Z,1570219167.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195400.00,A,3648.16109,N,12147.27737,W,0.369,0.00,041019,,,A*75
2019-10-04T19:59:27.602Z,1570219167.602 [NAL9602](INFO): GPS fix at 20191004T195400: (36.802685, -121.787956)
2019-10-04T19:59:27.667Z,1570219167.667 [Default:CheckIn:Read_GPS] Stopped
2019-10-04T19:59:27.667Z,1570219167.667 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-04T19:59:28.854Z,1570219168.854 [Micromodem](ERROR): Buffer fill timout failure.
2019-10-04T19:59:33.144Z,1570219173.144 [Micromodem](INFO): Outgoing frame #1, 64 bytes: A89F975D0A0000005D00000400370000000000000000151D80030C5669C08C35ED1F8EADBA0D03EDF17C32584B693FC4D59952225BFA8238D1268D0E6C56BA63
2019-10-04T19:59:33.145Z,1570219173.145 [Micromodem](INFO): Outgoing frame #2, 5 bytes: 11E7FCA000
2019-10-04T19:59:33.145Z,1570219173.145 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52

2019-10-04T19:59:33.363Z,1570219173.363 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50

2019-10-04T19:59:33.364Z,1570219173.364 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-04T19:59:33.724Z,1570219173.724 [Micromodem](INFO): Nmea in: $CADRQ,195934,1,9,0,64,1*4D

2019-10-04T19:59:33.725Z,1570219173.725 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,A89F975D0A0000005D00000400370000000000000000151D80030C5669C08C35ED1F8EADBA0D03EDF17C32584B693FC4D59952225BFA8238D1268D0E6C56BA63*03

2019-10-04T19:59:34.130Z,1570219174.130 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-04T19:59:34.523Z,1570219174.523 [Micromodem](INFO): Nmea in: $CADRQ,195934,1,9,0,64,2*4E

2019-10-04T19:59:34.523Z,1570219174.523 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,11E7FCA000*77

2019-10-04T19:59:34.926Z,1570219174.926 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,5*46

2019-10-04T19:59:35.330Z,1570219175.330 [Micromodem](INFO): Nmea in: $CATXP,69*7D

2019-10-04T19:59:37.762Z,1570219177.762 [Micromodem](INFO): Nmea in: $CATXF,69*6B

2019-10-04T19:59:37.844Z,1570219177.844 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191004T193816/Courier0010.lzma
2019-10-04T19:59:38.653Z,1570219178.653 [DataOverHttps](INFO): Moved sent file to Logs/20191004T193816/Courier0010.lzma.bak
2019-10-04T19:59:38.653Z,1570219178.653 [DataOverHttps](INFO): SBD MOMSN=11850769
2019-10-04T19:59:38.967Z,1570219178.967 [Micromodem](INFO): Nmea in: $CAXST,6,20191004,195935.511814,3,0,200,4000,10000,1,1,9,1,2,2,3,69*70

2019-10-04T19:59:40.183Z,1570219180.183 [Micromodem](INFO): Nmea in: $CAACK,9,1,1,1*43

2019-10-04T19:59:40.593Z,1570219180.593 [Micromodem](INFO): Nmea in: $CAACK,9,1,2,1*40

2019-10-04T19:59:40.595Z,1570219180.595 [Micromodem](INFO): Sent 61 bytes from file Logs/20191004T193816/Courier0010.lzma.parts
2019-10-04T19:59:40.595Z,1570219180.595 [Micromodem](INFO): Packets left to send: 0
2019-10-04T19:59:40.990Z,1570219180.990 [Micromodem](INFO): Nmea in: $CACST,6,0,20191004195940.027825,06,5887,37,0129,0150,07,00,00,00,00,1,009,001,0,4,1,0,150,-13.5,3.00,06,0.00,-01,0.01,79,10000,4000*53

2019-10-04T19:59:53.072Z,1570219193.072 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  95.42,  93.93,  92.43,   0.00
2019-10-04T20:00:01.212Z,1570219201.212 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20191004T193816/Express0011.lzma
2019-10-04T20:00:02.018Z,1570219202.018 [DataOverHttps](INFO): Moved sent file to Logs/20191004T193816/Express0011.lzma.bak
2019-10-04T20:00:02.019Z,1570219202.019 [DataOverHttps](INFO): SBD MOMSN=11850772
2019-10-04T20:00:04.854Z,1570219204.854 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-04T20:00:04.884Z,1570219204.884 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-10-04T20:00:04.930Z,1570219204.930 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T20:00:04.930Z,1570219204.930 [Aanderaa_O2] No Fault, FailCount= 3
2019-10-04T20:00:12.562Z,1570219212.562 [Default:CheckIn:Read_Iridium] Stopped
2019-10-04T20:00:12.562Z,1570219212.562 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-04T20:00:12.562Z,1570219212.562 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-04T20:00:19.071Z,1570219219.071 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T20:00:19.071Z,1570219219.071 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-10-04T20:00:19.071Z,1570219219.071 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T20:00:19.121Z,1570219219.121 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T20:00:19.589Z,1570219219.589 [Aanderaa_O2](INFO): Powering down
2019-10-04T20:00:20.302Z,1570219220.302 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T20:00:20.302Z,1570219220.302 [Aanderaa_O2] No Fault, FailCount= 1
2019-10-04T20:00:30.800Z,1570219230.800 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T20:00:30.800Z,1570219230.800 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-10-04T20:00:30.800Z,1570219230.800 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T20:00:30.818Z,1570219230.818 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T20:00:31.309Z,1570219231.309 [Aanderaa_O2](INFO): Powering down
2019-10-04T20:00:32.054Z,1570219232.054 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T20:00:32.054Z,1570219232.054 [Aanderaa_O2] No Fault, FailCount= 2
2019-10-04T20:00:42.495Z,1570219242.495 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-04T20:00:42.496Z,1570219242.496 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-10-04T20:00:42.496Z,1570219242.496 [Aanderaa_O2](ERROR): Communications Fault
2019-10-04T20:00:42.518Z,1570219242.518 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-04T20:00:42.518Z,1570219242.518 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-10-04T20:00:43.029Z,1570219243.029 [Aanderaa_O2](INFO): Powering down
2019-10-04T20:02:21.951Z,1570219341.951 [CommandLine](IMPORTANT): got command failComponent
2019-10-04T20:02:21.951Z,1570219341.951 [CommandLine](IMPORTANT): Failed components:
2019-10-04T20:02:21.952Z,1570219341.952 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2019-10-04T20:02:39.075Z,1570219359.075 [CommandLine](IMPORTANT): got command configSet Aanderaa_O2.loadAtStartup 0.000000 bool persist
2019-10-04T20:02:39.075Z,1570219359.075 [CommandLine](IMPORTANT): configSet Aanderaa_O2.loadAtStartup requires a restart to take effect.
2019-10-04T20:02:45.152Z,1570219365.152 [CommandLine](IMPORTANT): got command configSet list
2019-10-04T20:02:45.152Z,1570219365.152 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-04T20:02:45.153Z,1570219365.153 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-10-04T20:03:10.755Z,1570219390.755 [CommandLine](IMPORTANT): got command configSet CTD_NeilBrown.loadAtStartup 0.000000 bool persist
2019-10-04T20:03:10.755Z,1570219390.755 [CommandLine](IMPORTANT): configSet CTD_NeilBrown.loadAtStartup requires a restart to take effect.
2019-10-04T20:03:12.824Z,1570219392.824 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-04T20:03:12.824Z,1570219392.824 [Aanderaa_O2] No Fault, FailCount= 3
2019-10-04T20:03:16.513Z,1570219396.513 [CommandLine](IMPORTANT): got command restart application
2019-10-04T20:03:17.521Z,1570219397.521 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-04T20:03:17.521Z,1570219397.521 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:17.705Z,1570219397.705 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-10-04T20:03:17.705Z,1570219397.705 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:17.706Z,1570219397.706 [CommandLine](INFO): Join timeout helper Thread ID is 1015
2019-10-04T20:03:17.706Z,1570219397.706 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-04T20:03:17.706Z,1570219397.706 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:17.707Z,1570219397.707 [NavChartDb](INFO): Join timeout helper Thread ID is 1016
2019-10-04T20:03:17.801Z,1570219397.801 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-04T20:03:17.801Z,1570219397.801 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:17.809Z,1570219397.809 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-10-04T20:03:17.809Z,1570219397.809 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:17.809Z,1570219397.809 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1017
2019-10-04T20:03:17.817Z,1570219397.817 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-04T20:03:17.817Z,1570219397.817 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-10-04T20:03:17.818Z,1570219397.818 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:17.821Z,1570219397.821 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-10-04T20:03:17.821Z,1570219397.821 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:17.821Z,1570219397.821 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1018
2019-10-04T20:03:17.993Z,1570219397.993 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-04T20:03:17.993Z,1570219397.993 [CTD_NeilBrown](INFO): Powering down
2019-10-04T20:03:18.005Z,1570219398.005 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:18.010Z,1570219398.010 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-04T20:03:18.010Z,1570219398.010 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:18.010Z,1570219398.010 [Radio_Surface](INFO): Join timeout helper Thread ID is 1019
2019-10-04T20:03:18.202Z,1570219398.202 [Radio_Surface](INFO): Powering down
2019-10-04T20:03:18.202Z,1570219398.202 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-04T20:03:18.203Z,1570219398.203 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:18.222Z,1570219398.222 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-04T20:03:18.222Z,1570219398.222 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:18.222Z,1570219398.222 [DataOverHttps](INFO): Join timeout helper Thread ID is 1020
2019-10-04T20:03:20.656Z,1570219400.656 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-04T20:03:20.660Z,1570219400.660 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:20.662Z,1570219400.662 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-04T20:03:20.662Z,1570219400.662 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:20.662Z,1570219400.662 [logger](INFO): Join timeout helper Thread ID is 1021
2019-10-04T20:03:20.713Z,1570219400.713 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-04T20:03:20.713Z,1570219400.713 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:20.721Z,1570219400.721 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-04T20:03:20.722Z,1570219400.722 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:20.722Z,1570219400.722 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-04T20:03:20.722Z,1570219400.722 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:20.722Z,1570219400.722 [controlThread](INFO): Join timeout helper Thread ID is 1022
2019-10-04T20:03:20.833Z,1570219400.833 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-04T20:03:20.833Z,1570219400.833 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-04T20:03:20.834Z,1570219400.834 [AHRS_M2](INFO): Powering down
2019-10-04T20:03:20.905Z,1570219400.905 [Micromodem](INFO): Powering down
2019-10-04T20:03:21.001Z,1570219401.001 [NAL9602](INFO): Powering down
2019-10-04T20:03:21.073Z,1570219401.073 [RDI_Pathfinder](INFO): Powering down
2019-10-04T20:03:21.193Z,1570219401.193 [Aanderaa_O2](INFO): Powering down
2019-10-04T20:03:21.194Z,1570219401.194 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-04T20:03:21.195Z,1570219401.195 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-04T20:03:21.196Z,1570219401.196 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-04T20:03:21.196Z,1570219401.196 [MissionManager](INFO): Uninitializing Mission Default
2019-10-04T20:03:21.196Z,1570219401.196 [Default] Stopped
2019-10-04T20:03:21.196Z,1570219401.196 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-04T20:03:21.196Z,1570219401.196 [Default:B.GoToSurface] Stopped
2019-10-04T20:03:21.196Z,1570219401.196 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-04T20:03:21.197Z,1570219401.197 [Default:CheckIn] Stopped
2019-10-04T20:03:21.197Z,1570219401.197 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-04T20:03:21.197Z,1570219401.197 [Default:CheckIn:C.Wait] Stopped
2019-10-04T20:03:21.197Z,1570219401.197 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-04T20:03:21.199Z,1570219401.199 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-04T20:03:21.199Z,1570219401.199 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-04T20:03:21.200Z,1570219401.200 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-04T20:03:21.200Z,1570219401.200 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-04T20:03:21.200Z,1570219401.200 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-04T20:03:21.200Z,1570219401.200 [BuoyancyServo](INFO): Powering down
2019-10-04T20:03:21.213Z,1570219401.213 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-04T20:03:21.213Z,1570219401.213 [ElevatorServo](INFO): Powering down
2019-10-04T20:03:21.214Z,1570219401.214 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-04T20:03:21.214Z,1570219401.214 [MassServo](INFO): Powering down
2019-10-04T20:03:21.215Z,1570219401.215 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-04T20:03:21.215Z,1570219401.215 [RudderServo](INFO): Powering down
2019-10-04T20:03:21.215Z,1570219401.215 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-04T20:03:21.216Z,1570219401.216 [ThrusterServo](INFO): Powering down
2019-10-04T20:03:21.216Z,1570219401.216 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-04T20:03:21.217Z,1570219401.217 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-04T20:03:21.217Z,1570219401.217 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-04T20:03:21.217Z,1570219401.217 [CBIT](DEBUG): Powering off loads.
2019-10-04T20:03:21.229Z,1570219401.229 [CBIT](DEBUG): Disabling WDT.
2019-10-04T20:03:21.241Z,1570219401.241 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-04T20:03:21.242Z,1570219401.242 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:21.300Z,1570219401.300 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:21.308Z,1570219401.308 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:21.349Z,1570219401.349 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:21.352Z,1570219401.352 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:21.410Z,1570219401.410 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-04T20:03:21.482Z,1570219401.482 [logger ThreadHandler](INFO): Thread cancelled.