2019-09-23T14:41:27.551Z,1569249687.551 [Supervisor](DEBUG): Initializing supervisor.
2019-09-23T14:41:27.554Z,1569249687.554 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-09-23T14:41:27.555Z,1569249687.555 [SyncHandler](INFO): Protected caller Thread ID is 5476
2019-09-23T14:41:27.555Z,1569249687.555 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-09-23T14:41:27.556Z,1569249687.556 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-09-23T14:41:27.557Z,1569249687.557 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5477
2019-09-23T14:41:27.560Z,1569249687.560 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-09-23T14:41:27.571Z,1569249687.571 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-09-23T14:41:27.572Z,1569249687.572 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-09-23T14:41:27.572Z,1569249687.572 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5478
2019-09-23T14:41:27.573Z,1569249687.573 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-09-23T14:41:27.574Z,1569249687.574 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-09-23T14:41:27.574Z,1569249687.574 [logger ThreadHandler](INFO): Protected caller Thread ID is 5479
2019-09-23T14:41:27.576Z,1569249687.576 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-09-23T14:41:27.577Z,1569249687.577 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-09-23T14:41:27.578Z,1569249687.578 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-09-23T14:41:27.672Z,1569249687.672 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-09-23T14:41:27.672Z,1569249687.672 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-09-23T14:41:28.229Z,1569249688.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-09-23T14:41:28.230Z,1569249688.230 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-09-23T14:41:28.326Z,1569249688.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-09-23T14:41:28.326Z,1569249688.326 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-09-23T14:41:28.425Z,1569249688.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-09-23T14:41:28.426Z,1569249688.426 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-09-23T14:41:28.504Z,1569249688.504 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-09-23T14:41:28.640Z,1569249688.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-09-23T14:41:28.641Z,1569249688.641 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-09-23T14:41:28.931Z,1569249688.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-09-23T14:41:28.932Z,1569249688.932 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-09-23T14:41:29.389Z,1569249689.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-09-23T14:41:29.390Z,1569249689.390 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-09-23T14:41:29.533Z,1569249689.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-09-23T14:41:29.533Z,1569249689.533 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-09-23T14:41:29.727Z,1569249689.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-09-23T14:41:29.728Z,1569249689.728 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-09-23T14:41:30.180Z,1569249690.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-09-23T14:41:30.181Z,1569249690.181 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-09-23T14:41:30.390Z,1569249690.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-09-23T14:41:30.391Z,1569249690.391 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-09-23T14:41:30.590Z,1569249690.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-09-23T14:41:30.591Z,1569249690.591 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-09-23T14:41:30.981Z,1569249690.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-09-23T14:41:30.982Z,1569249690.982 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-09-23T14:41:31.310Z,1569249691.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-09-23T14:41:31.312Z,1569249691.312 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-09-23T14:41:31.313Z,1569249691.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-09-23T14:41:31.394Z,1569249691.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-09-23T14:41:31.547Z,1569249691.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-09-23T14:41:31.668Z,1569249691.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-09-23T14:41:31.769Z,1569249691.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-09-23T14:41:31.928Z,1569249691.928 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-09-23T14:41:32.326Z,1569249692.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-09-23T14:41:32.763Z,1569249692.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-09-23T14:41:32.763Z,1569249692.763 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-09-23T14:41:32.854Z,1569249692.854 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-09-23T14:41:32.950Z,1569249692.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-09-23T14:41:33.084Z,1569249693.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-09-23T14:41:33.182Z,1569249693.182 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2019-09-23T14:41:33.183Z,1569249693.183 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-09-23T14:41:33.193Z,1569249693.193 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-09-23T14:41:33.267Z,1569249693.267 [VerticalControl](DEBUG): Construct VerticalControl.
2019-09-23T14:41:33.382Z,1569249693.382 [VerticalControl] Loaded
2019-09-23T14:41:33.383Z,1569249693.383 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-09-23T14:41:33.383Z,1569249693.383 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-09-23T14:41:33.455Z,1569249693.455 [HorizontalControl] Loaded
2019-09-23T14:41:33.455Z,1569249693.455 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-09-23T14:41:33.456Z,1569249693.456 [SpeedControl](DEBUG): Construct SpeedControl.
2019-09-23T14:41:33.461Z,1569249693.461 [SpeedControl] Loaded
2019-09-23T14:41:33.462Z,1569249693.462 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-09-23T14:41:33.462Z,1569249693.462 [LoopControl](DEBUG): Construct LoopControl.
2019-09-23T14:41:33.463Z,1569249693.463 [LoopControl] Loaded
2019-09-23T14:41:33.463Z,1569249693.463 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-09-23T14:41:33.464Z,1569249693.464 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-09-23T14:41:33.464Z,1569249693.464 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-09-23T14:41:33.508Z,1569249693.508 [DepthRateCalculator] Loaded
2019-09-23T14:41:33.508Z,1569249693.508 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-09-23T14:41:33.514Z,1569249693.514 [PitchRateCalculator] Loaded
2019-09-23T14:41:33.514Z,1569249693.514 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-09-23T14:41:33.530Z,1569249693.530 [SpeedCalculator] Loaded
2019-09-23T14:41:33.530Z,1569249693.530 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-09-23T14:41:33.552Z,1569249693.552 [TempGradientCalculator] Loaded
2019-09-23T14:41:33.552Z,1569249693.552 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-09-23T14:41:33.557Z,1569249693.557 [YawRateCalculator] Loaded
2019-09-23T14:41:33.557Z,1569249693.557 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-09-23T14:41:33.588Z,1569249693.588 [ElevatorOffsetCalculator] Loaded
2019-09-23T14:41:33.588Z,1569249693.588 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-09-23T14:41:33.588Z,1569249693.588 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-09-23T14:41:33.589Z,1569249693.589 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-09-23T14:41:33.623Z,1569249693.623 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-09-23T14:41:33.624Z,1569249693.624 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-09-23T14:41:33.698Z,1569249693.698 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-09-23T14:41:33.699Z,1569249693.699 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-09-23T14:41:34.017Z,1569249694.017 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-09-23T14:41:34.017Z,1569249694.017 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-09-23T14:41:34.125Z,1569249694.125 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-09-23T14:41:34.125Z,1569249694.125 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-09-23T14:41:34.429Z,1569249694.429 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-09-23T14:41:34.434Z,1569249694.434 [AHRS_M2](INFO): created writer for : platform_orientation
2019-09-23T14:41:34.436Z,1569249694.436 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-09-23T14:41:34.442Z,1569249694.442 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-09-23T14:41:34.442Z,1569249694.442 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-09-23T14:41:34.447Z,1569249694.447 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-09-23T14:41:34.448Z,1569249694.448 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-09-23T14:41:34.453Z,1569249694.453 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-09-23T14:41:34.528Z,1569249694.528 [AHRS_M2] Loaded
2019-09-23T14:41:34.529Z,1569249694.529 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-09-23T14:41:34.602Z,1569249694.602 [DataOverHttps] Loaded
2019-09-23T14:41:34.602Z,1569249694.602 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-09-23T14:41:34.603Z,1569249694.603 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408CA4E0
2019-09-23T14:41:34.604Z,1569249694.604 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5558
2019-09-23T14:41:34.617Z,1569249694.617 [Depth_Keller] Loaded
2019-09-23T14:41:34.618Z,1569249694.618 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-09-23T14:41:34.623Z,1569249694.623 [DropWeight] Loaded
2019-09-23T14:41:34.623Z,1569249694.623 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-09-23T14:41:34.726Z,1569249694.726 [NAL9602] Loaded
2019-09-23T14:41:34.726Z,1569249694.726 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-09-23T14:41:34.742Z,1569249694.742 [Onboard] Loaded
2019-09-23T14:41:34.743Z,1569249694.743 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-09-23T14:41:34.746Z,1569249694.746 [Radio_Surface] Loaded
2019-09-23T14:41:34.746Z,1569249694.746 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-09-23T14:41:34.747Z,1569249694.747 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408FA4E0
2019-09-23T14:41:34.748Z,1569249694.748 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5559
2019-09-23T14:41:34.795Z,1569249694.795 [RDI_Pathfinder] Loaded
2019-09-23T14:41:34.795Z,1569249694.795 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-09-23T14:41:34.927Z,1569249694.927 [DAT] Loaded
2019-09-23T14:41:34.927Z,1569249694.927 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-09-23T14:41:36.536Z,1569249696.536 [BPC1] Loaded
2019-09-23T14:41:36.536Z,1569249696.536 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-09-23T14:41:36.537Z,1569249696.537 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-09-23T14:41:36.537Z,1569249696.537 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-09-23T14:41:36.645Z,1569249696.645 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-09-23T14:41:36.645Z,1569249696.645 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-09-23T14:41:36.667Z,1569249696.667 [NavChart] Loaded
2019-09-23T14:41:36.667Z,1569249696.667 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-09-23T14:41:36.671Z,1569249696.671 [UniversalFixResidualReporter] Loaded
2019-09-23T14:41:36.671Z,1569249696.671 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-09-23T14:41:36.672Z,1569249696.672 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-09-23T14:41:36.672Z,1569249696.672 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-09-23T14:41:36.932Z,1569249696.932 [SBIT](DEBUG): Construct Startup Built In Test.
2019-09-23T14:41:36.951Z,1569249696.951 [SBIT] Loaded
2019-09-23T14:41:36.951Z,1569249696.951 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-09-23T14:41:36.952Z,1569249696.952 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-09-23T14:41:36.977Z,1569249696.977 [IBIT] Loaded
2019-09-23T14:41:36.978Z,1569249696.978 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-09-23T14:41:36.981Z,1569249696.981 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-09-23T14:41:37.200Z,1569249697.200 [CBIT] Loaded
2019-09-23T14:41:37.200Z,1569249697.200 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-09-23T14:41:37.200Z,1569249697.200 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-09-23T14:41:37.201Z,1569249697.201 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-09-23T14:41:37.312Z,1569249697.312 [BuoyancyServo] Loaded
2019-09-23T14:41:37.312Z,1569249697.312 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-09-23T14:41:37.324Z,1569249697.324 [ElevatorServo] Loaded
2019-09-23T14:41:37.324Z,1569249697.324 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-09-23T14:41:37.335Z,1569249697.335 [MassServo] Loaded
2019-09-23T14:41:37.336Z,1569249697.336 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-09-23T14:41:37.347Z,1569249697.347 [RudderServo] Loaded
2019-09-23T14:41:37.347Z,1569249697.347 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-09-23T14:41:37.358Z,1569249697.358 [ThrusterServo] Loaded
2019-09-23T14:41:37.358Z,1569249697.358 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-09-23T14:41:37.359Z,1569249697.359 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-09-23T14:41:37.359Z,1569249697.359 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-09-23T14:41:37.373Z,1569249697.373 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-09-23T14:41:37.373Z,1569249697.373 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-09-23T14:41:37.559Z,1569249697.559 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-09-23T14:41:37.564Z,1569249697.564 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-09-23T14:41:37.565Z,1569249697.565 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-09-23T14:41:37.570Z,1569249697.570 [CTD_Seabird](INFO): created writer for : depth
2019-09-23T14:41:37.570Z,1569249697.570 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-09-23T14:41:37.576Z,1569249697.576 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-09-23T14:41:37.576Z,1569249697.576 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-09-23T14:41:37.582Z,1569249697.582 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-09-23T14:41:37.582Z,1569249697.582 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-09-23T14:41:37.588Z,1569249697.588 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-09-23T14:41:37.589Z,1569249697.589 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-09-23T14:41:37.594Z,1569249697.594 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-09-23T14:41:37.595Z,1569249697.595 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-09-23T14:41:37.600Z,1569249697.600 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-09-23T14:41:37.629Z,1569249697.629 [CTD_Seabird] Loaded
2019-09-23T14:41:37.629Z,1569249697.629 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-09-23T14:41:37.630Z,1569249697.630 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2019-09-23T14:41:37.631Z,1569249697.631 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5560
2019-09-23T14:41:37.638Z,1569249697.638 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2019-09-23T14:41:37.638Z,1569249697.638 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2019-09-23T14:41:37.642Z,1569249697.642 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2019-09-23T14:41:37.643Z,1569249697.643 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2019-09-23T14:41:37.647Z,1569249697.647 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2019-09-23T14:41:37.648Z,1569249697.648 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2019-09-23T14:41:37.653Z,1569249697.653 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2019-09-23T14:41:37.653Z,1569249697.653 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2019-09-23T14:41:37.657Z,1569249697.657 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2019-09-23T14:41:37.657Z,1569249697.657 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2019-09-23T14:41:37.662Z,1569249697.662 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2019-09-23T14:41:37.662Z,1569249697.662 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2019-09-23T14:41:37.667Z,1569249697.667 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2019-09-23T14:41:37.667Z,1569249697.667 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2019-09-23T14:41:37.672Z,1569249697.672 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2019-09-23T14:41:37.676Z,1569249697.676 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2019-09-23T14:41:37.677Z,1569249697.677 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-09-23T14:41:37.677Z,1569249697.677 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-09-23T14:41:37.681Z,1569249697.681 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-09-23T14:41:37.682Z,1569249697.682 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-09-23T14:41:37.686Z,1569249697.686 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-09-23T14:41:37.686Z,1569249697.686 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-09-23T14:41:37.691Z,1569249697.691 [WetLabsBB2FL] Loaded
2019-09-23T14:41:37.691Z,1569249697.691 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-09-23T14:41:37.692Z,1569249697.692 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2019-09-23T14:41:37.693Z,1569249697.693 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5561
2019-09-23T14:41:37.707Z,1569249697.707 [WetLabsUBAT..SerialDriver](INFO): Created logger
2019-09-23T14:41:37.708Z,1569249697.708 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2019-09-23T14:41:37.708Z,1569249697.708 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2019-09-23T14:41:37.710Z,1569249697.710 [WetLabsUBAT](INFO): in readConfig
2019-09-23T14:41:37.713Z,1569249697.713 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -nopump -ldir /mnt/mmc/LRAUV/Logs/latest
2019-09-23T14:41:37.714Z,1569249697.714 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2019-09-23T14:41:37.714Z,1569249697.714 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2019-09-23T14:41:37.718Z,1569249697.718 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2019-09-23T14:41:37.718Z,1569249697.718 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2019-09-23T14:41:37.723Z,1569249697.723 [WetLabsUBAT] Loaded
2019-09-23T14:41:37.723Z,1569249697.723 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2019-09-23T14:41:37.724Z,1569249697.724 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2019-09-23T14:41:37.724Z,1569249697.724 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 5562
2019-09-23T14:41:37.725Z,1569249697.725 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-09-23T14:41:37.728Z,1569249697.728 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-09-23T14:41:37.729Z,1569249697.729 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-09-23T14:41:37.736Z,1569249697.736 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-09-23T14:41:37.737Z,1569249697.737 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B0F4E0
2019-09-23T14:41:37.737Z,1569249697.737 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5563
2019-09-23T14:41:37.742Z,1569249697.742 [Supervisor](INFO): Main Thread ID is 5350
2019-09-23T14:41:37.742Z,1569249697.742 [Supervisor](DEBUG): Running supervisor.
2019-09-23T14:41:37.743Z,1569249697.743 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5564
2019-09-23T14:41:37.746Z,1569249697.746 [controlThread ThreadHandler](INFO): Handler Thread ID is 5565
2019-09-23T14:41:37.746Z,1569249697.746 [controlThread](DEBUG): Initializing ControlThread
2019-09-23T14:41:37.747Z,1569249697.747 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-09-23T14:41:37.749Z,1569249697.749 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-09-23T14:41:37.749Z,1569249697.749 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-09-23T14:41:37.750Z,1569249697.750 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-09-23T14:41:37.750Z,1569249697.750 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-09-23T14:41:37.750Z,1569249697.750 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-09-23T14:41:37.751Z,1569249697.751 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-09-23T14:41:37.751Z,1569249697.751 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-09-23T14:41:37.752Z,1569249697.752 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-09-23T14:41:37.752Z,1569249697.752 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-09-23T14:41:37.758Z,1569249697.758 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-09-23T14:41:37.758Z,1569249697.758 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-09-23T14:41:37.759Z,1569249697.759 [SBIT](INFO): Initialize SBIT Component.
2019-09-23T14:41:37.759Z,1569249697.759 [SBIT](IMPORTANT): git: 2019-09-10-15-gf9f7781
2019-09-23T14:41:37.759Z,1569249697.759 [SBIT](INFO): git hash: f9f7781fb6d86741f33ee9b3258f94ce1a6d26dc
2019-09-23T14:41:37.760Z,1569249697.760 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-09-23T14:41:37.760Z,1569249697.760 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-09-23T14:41:37.761Z,1569249697.761 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-09-23T14:41:37.762Z,1569249697.762 [IBIT](INFO): Initialize IBIT Component.
2019-09-23T14:41:37.762Z,1569249697.762 [CBIT](DEBUG): Initialize CBIT Component.
2019-09-23T14:41:37.763Z,1569249697.763 [logger ThreadHandler](INFO): Handler Thread ID is 5566
2019-09-23T14:41:37.775Z,1569249697.775 [CBIT](DEBUG): Initialized mux pins.
2019-09-23T14:41:37.775Z,1569249697.775 [CBIT](DEBUG): Initializing the watchdog timer.
2019-09-23T14:41:37.784Z,1569249697.784 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5567
2019-09-23T14:41:37.785Z,1569249697.785 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-09-23T14:41:37.795Z,1569249697.795 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5568
2019-09-23T14:41:37.799Z,1569249697.799 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-09-23T14:41:37.799Z,1569249697.799 [CBIT](DEBUG): Initializing heartbeat.
2019-09-23T14:41:37.808Z,1569249697.808 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5569
2019-09-23T14:41:37.808Z,1569249697.808 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-09-23T14:41:37.812Z,1569249697.812 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-09-23T14:41:37.813Z,1569249697.813 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5571
2019-09-23T14:41:37.814Z,1569249697.814 [WetLabsBB2FL](INFO): Powering down
2019-09-23T14:41:37.844Z,1569249697.844 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 5572
2019-09-23T14:41:37.855Z,1569249697.855 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5573
2019-09-23T14:41:37.858Z,1569249697.858 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-09-23T14:41:37.858Z,1569249697.858 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-09-23T14:41:37.859Z,1569249697.859 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-09-23T14:41:37.859Z,1569249697.859 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-09-23T14:41:37.859Z,1569249697.859 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-09-23T14:41:37.859Z,1569249697.859 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-09-23T14:41:37.859Z,1569249697.859 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-09-23T14:41:37.860Z,1569249697.860 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-09-23T14:41:37.860Z,1569249697.860 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-09-23T14:41:37.860Z,1569249697.860 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-09-23T14:41:37.860Z,1569249697.860 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-09-23T14:41:37.860Z,1569249697.860 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-09-23T14:41:37.860Z,1569249697.860 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-09-23T14:41:37.861Z,1569249697.861 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-09-23T14:41:37.861Z,1569249697.861 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-09-23T14:41:37.861Z,1569249697.861 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-09-23T14:41:37.871Z,1569249697.871 [CBIT](DEBUG): Deactivating GF circuits.
2019-09-23T14:41:37.871Z,1569249697.871 [CBIT](DEBUG): Deactivating emergency mode.
2019-09-23T14:41:37.907Z,1569249697.907 [CBIT](DEBUG): Backplane powered.
2019-09-23T14:41:37.909Z,1569249697.909 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-09-23T14:41:37.920Z,1569249697.920 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-23T14:41:37.947Z,1569249697.947 [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-09-23T14:41:37.948Z,1569249697.948 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-09-23T14:41:38.015Z,1569249698.015 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-09-23T14:41:38.016Z,1569249698.016 [Default:A.Wait](DEBUG): Construct Wait.
2019-09-23T14:41:38.017Z,1569249698.017 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-23T14:41:38.061Z,1569249698.061 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-09-23T14:41:38.064Z,1569249698.064 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-09-23T14:41:38.089Z,1569249698.089 [Default:E.Execute](DEBUG): Construct Execute.
2019-09-23T14:41:38.092Z,1569249698.092 [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-09-23T14:41:38.103Z,1569249698.103 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,Depth_Keller,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-09-23T14:41:38.116Z,1569249698.116 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-23T14:41:38.198Z,1569249698.198 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-09-23T14:41:38.198Z,1569249698.198 [DAT](INFO): Powering up
2019-09-23T14:41:38.198Z,1569249698.198 [DAT](DEBUG): Initializing DAT.
2019-09-23T14:41:38.199Z,1569249698.199 [Radio_Surface](INFO): Powering up
2019-09-23T14:41:38.243Z,1569249698.243 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-09-23T14:41:38.318Z,1569249698.318 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-09-23T14:41:38.324Z,1569249698.324 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-09-23T14:41:38.325Z,1569249698.325 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-09-23T14:41:38.335Z,1569249698.335 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-09-23T14:41:38.337Z,1569249698.337 [MassServo](DEBUG): Initializing EZServoServo.
2019-09-23T14:41:38.347Z,1569249698.347 [MassServo](DEBUG): Initializing MassServo.
2019-09-23T14:41:38.348Z,1569249698.348 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-23T14:41:38.362Z,1569249698.362 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-23T14:41:38.364Z,1569249698.364 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-09-23T14:41:38.371Z,1569249698.371 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-09-23T14:41:38.374Z,1569249698.374 [CommandLine](FAULT): Scheduling is paused
2019-09-23T14:41:38.375Z,1569249698.375 [CBIT](INFO): Critical error at 20190923T144137
2019-09-23T14:41:38.375Z,1569249698.375 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-09-23T14:41:38.544Z,1569249698.544 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2019-09-23T14:41:38.544Z,1569249698.544 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-09-23T14:41:38.545Z,1569249698.545 [DropWeight] Hardware Fault, FailCount= 1
2019-09-23T14:41:38.545Z,1569249698.545 [DropWeight](ERROR): Hardware Fault
2019-09-23T14:41:38.578Z,1569249698.578 [CBIT](INFO): Critical error at 20190923T144138
2019-09-23T14:41:38.581Z,1569249698.581 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-09-23T14:41:38.582Z,1569249698.582 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-09-23T14:41:39.283Z,1569249699.283 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-09-23T14:41:39.283Z,1569249699.283 [RudderServo](FAULT): Rudder failed to initialize
2019-09-23T14:41:39.283Z,1569249699.283 [RudderServo] Communications Fault, FailCount= 1
2019-09-23T14:41:39.283Z,1569249699.283 [RudderServo](ERROR): Communications Fault
2019-09-23T14:41:39.392Z,1569249699.392 [CBIT](INFO): Critical error at 20190923T144138
2019-09-23T14:41:39.395Z,1569249699.395 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-09-23T14:41:39.560Z,1569249699.560 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-23T14:41:39.560Z,1569249699.560 [RudderServo](INFO): Powering down
2019-09-23T14:41:40.239Z,1569249700.239 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-23T14:41:40.372Z,1569249700.372 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-23T14:41:40.376Z,1569249700.376 [CBIT](INFO): Clearing failed state for component RudderServo
2019-09-23T14:41:40.376Z,1569249700.376 [RudderServo] No Fault, FailCount= 1
2019-09-23T14:41:44.875Z,1569249704.875 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2019-09-23T14:41:51.569Z,1569249711.569 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-09-23T14:41:52.371Z,1569249712.371 [DAT](INFO): setting local address to 4
2019-09-23T14:41:52.832Z,1569249712.832 [DAT](INFO): set local address to 4
2019-09-23T14:42:04.876Z,1569249724.876 [NAL9602](INFO): Powering up NAL9602
2019-09-23T14:42:15.784Z,1569249735.784 [NAL9602](INFO): NAL9602 initialized
2019-09-23T14:42:16.603Z,1569249736.603 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:42:37.248Z,1569249757.248 [SBIT](IMPORTANT): Beginning Startup BIT
2019-09-23T14:42:37.252Z,1569249757.252 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-23T14:42:48.320Z,1569249768.320 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011978
CHAN A1 (24V): -0.000323
CHAN A2 (12V): -0.007645
CHAN A3 (5V): -0.001424
CHAN B0 (3.3V): -0.000255
CHAN B1 (3.15aV): 0.000216
CHAN B2 (3.15bV): 0.000186
CHAN B3 (GND): 0.001410
OPEN: 0.004902
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-23T14:43:25.714Z,1569249805.714 [NAL9602](INFO): SBD MO Status=2, MOMSN=1416, MT Status=2, MTMSN=0
2019-09-23T14:43:25.714Z,1569249805.714 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T14:43:30.617Z,1569249810.617 [SBIT](IMPORTANT): SBIT PASSED
2019-09-23T14:43:30.656Z,1569249810.656 [CommandLine](IMPORTANT): got command configSet list
2019-09-23T14:43:30.656Z,1569249810.656 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-09-23T14:43:30.657Z,1569249810.657 [CommandLine](IMPORTANT): CBIT.gf24Offset=143 microampere;

2019-09-23T14:43:30.657Z,1569249810.657 [CommandLine](IMPORTANT): Express none RDI_Pathfinder.height_above_sea_floor;

2019-09-23T14:43:30.658Z,1569249810.658 [CommandLine](IMPORTANT): Express linearApproximation Tracking.range_to_contact 10.000000 meter;

2019-09-23T14:43:30.658Z,1569249810.658 [CommandLine](IMPORTANT): Express linearApproximation WetLabsUBAT.biolum_potential 10000000.000000 none;

2019-09-23T14:43:30.658Z,1569249810.658 [CommandLine](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2019-09-23T14:43:30.658Z,1569249810.658 [CommandLine](IMPORTANT): Express none height_above_sea_floor;

2019-09-23T14:43:30.658Z,1569249810.658 [CommandLine](IMPORTANT): Normal linearApproximation WetLabsUBAT.biolum_potential 1000000.000000 none;

2019-09-23T14:43:30.658Z,1569249810.658 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=80 cubic_centimeter;

2019-09-23T14:43:30.658Z,1569249810.658 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter;

2019-09-23T14:43:31.014Z,1569249811.014 [MissionManager](IMPORTANT): Started mission Startup
2019-09-23T14:43:31.014Z,1569249811.014 [Startup] Running Loop=1
2019-09-23T14:43:31.014Z,1569249811.014 [Startup](DEBUG): Aggregate::initialize Startup
2019-09-23T14:43:31.015Z,1569249811.015 [Startup:A.GoToSurface] Running Loop=1
2019-09-23T14:43:31.015Z,1569249811.015 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-23T14:43:31.016Z,1569249811.016 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-23T14:43:31.016Z,1569249811.016 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-23T14:43:31.016Z,1569249811.016 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-23T14:43:31.017Z,1569249811.017 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-23T14:43:31.017Z,1569249811.017 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-23T14:43:31.018Z,1569249811.018 [Startup:StartupSatComms] Running Loop=1
2019-09-23T14:43:31.019Z,1569249811.019 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-09-23T14:43:31.019Z,1569249811.019 [Startup:StartupSatComms:A] Running Loop=1
2019-09-23T14:43:31.398Z,1569249811.398 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-09-23T14:44:31.194Z,1569249871.194 [Startup:StartupSatComms:A](INFO): Timed out from 2019-09-23T14:43:31.0Z
2019-09-23T14:44:31.194Z,1569249871.194 [Startup:StartupSatComms:A] Stopped
2019-09-23T14:44:31.194Z,1569249871.194 [Startup:StartupSatComms:B] Running Loop=1
2019-09-23T14:44:31.613Z,1569249871.613 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-09-23T14:44:38.059Z,1569249878.059 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-09-23T14:44:38.059Z,1569249878.059 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-09-23T14:44:38.085Z,1569249878.085 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-09-23T14:44:38.551Z,1569249878.551 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-09-23T14:44:38.551Z,1569249878.551 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-09-23T14:44:43.686Z,1569249883.686 [NAL9602](INFO): SBD MO Status=2, MOMSN=1416, MT Status=2, MTMSN=0
2019-09-23T14:44:43.687Z,1569249883.687 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T14:45:05.507Z,1569249905.507 [NAL9602](INFO): SBD MO Status=2, MOMSN=1416, MT Status=2, MTMSN=0
2019-09-23T14:45:05.507Z,1569249905.507 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T14:45:31.393Z,1569249931.393 [Startup:StartupSatComms:B](INFO): Timed out from 2019-09-23T14:44:31.2Z
2019-09-23T14:45:31.393Z,1569249931.393 [Startup:StartupSatComms:B] Stopped
2019-09-23T14:45:31.393Z,1569249931.393 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-09-23T14:45:31.393Z,1569249931.393 [Startup:StartupSatComms] Stopped
2019-09-23T14:45:31.393Z,1569249931.393 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-09-23T14:45:31.394Z,1569249931.394 [Startup](INFO): Completed Startup
2019-09-23T14:45:31.394Z,1569249931.394 [MissionManager](INFO): Startup is completed.
2019-09-23T14:45:31.394Z,1569249931.394 [MissionManager](INFO): Uninitializing Mission Startup
2019-09-23T14:45:31.394Z,1569249931.394 [Startup] Stopped
2019-09-23T14:45:31.394Z,1569249931.394 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-09-23T14:45:31.394Z,1569249931.394 [Startup:A.GoToSurface] Stopped
2019-09-23T14:45:31.394Z,1569249931.394 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-23T14:45:31.810Z,1569249931.810 [MissionManager](IMPORTANT): Started mission Default
2019-09-23T14:45:31.810Z,1569249931.810 [Default] Running Loop=1
2019-09-23T14:45:31.810Z,1569249931.810 [Default](DEBUG): Aggregate::initialize Default
2019-09-23T14:45:31.810Z,1569249931.810 [Default:B.GoToSurface] Running Loop=1
2019-09-23T14:45:31.811Z,1569249931.811 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-23T14:45:31.811Z,1569249931.811 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-23T14:45:31.811Z,1569249931.811 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-23T14:45:31.812Z,1569249931.812 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-23T14:45:31.812Z,1569249931.812 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-23T14:45:31.812Z,1569249931.812 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-23T14:45:31.813Z,1569249931.813 [Default:A.Wait] Running Loop=1
2019-09-23T14:45:31.813Z,1569249931.813 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-09-23T14:45:45.148Z,1569249945.148 [Default:A.Wait](INFO): Done Waiting.
2019-09-23T14:45:45.149Z,1569249945.149 [Default:A.Wait] Stopped
2019-09-23T14:45:45.149Z,1569249945.149 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-09-23T14:45:45.580Z,1569249945.580 [Default:CheckIn] Running Loop=1
2019-09-23T14:45:45.581Z,1569249945.581 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-23T14:45:45.581Z,1569249945.581 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-23T14:45:45.935Z,1569249945.935 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-09-23T14:47:19.226Z,1569250039.226 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-23T14:47:20.047Z,1569250040.047 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:22.484Z,1569250042.484 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:25.705Z,1569250045.705 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:28.531Z,1569250048.531 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:31.763Z,1569250051.763 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:34.591Z,1569250054.591 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:37.823Z,1569250057.823 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:39.062Z,1569250059.062 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-09-23T14:47:39.062Z,1569250059.062 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-09-23T14:47:39.072Z,1569250059.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-09-23T14:47:39.472Z,1569250059.472 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-09-23T14:47:39.472Z,1569250059.472 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-09-23T14:47:40.655Z,1569250060.655 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:43.495Z,1569250063.495 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:46.711Z,1569250066.711 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:49.547Z,1569250069.547 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:52.771Z,1569250072.771 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:55.599Z,1569250075.599 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:58.831Z,1569250078.831 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:47:59.684Z,1569250079.684 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-09-23T14:47:59.686Z,1569250079.686 [BPC1](INFO): Received data from all battery sticks.
2019-09-23T14:48:01.659Z,1569250081.659 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:04.499Z,1569250084.499 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:07.719Z,1569250087.719 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:10.547Z,1569250090.547 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:13.779Z,1569250093.779 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:16.611Z,1569250096.611 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:19.855Z,1569250099.855 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:22.667Z,1569250102.667 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:25.507Z,1569250105.507 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:28.727Z,1569250108.727 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:31.559Z,1569250111.559 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:34.791Z,1569250114.791 [NAL9602](DEBUG): Fix Requested
2019-09-23T14:48:35.178Z,1569250115.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144654.00,A,3648.16195,N,12147.27957,W,0.292,0.00,230919,,,A*7F
2019-09-23T14:48:35.182Z,1569250115.182 [NAL9602](INFO): GPS fix at 20190923T144654: (36.802699, -121.787993)
2019-09-23T14:48:35.222Z,1569250115.222 [Default:CheckIn:Read_GPS] Stopped
2019-09-23T14:48:35.222Z,1569250115.222 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-23T14:48:35.648Z,1569250115.648 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-09-23T14:50:09.957Z,1569250209.957 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T14:51:18.851Z,1569250278.851 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T14:51:31.123Z,1569250291.123 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T14:52:55.590Z,1569250375.590 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T14:53:13.849Z,1569250393.849 [NAL9602](INFO): SBD MO Status=2, MOMSN=1416, MT Status=2, MTMSN=0
2019-09-23T14:53:13.850Z,1569250393.850 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T14:53:29.913Z,1569250409.913 [NAL9602](INFO): SBD MO Status=2, MOMSN=1416, MT Status=2, MTMSN=0
2019-09-23T14:53:29.913Z,1569250409.913 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T14:53:37.831Z,1569250417.831 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T14:53:55.969Z,1569250435.969 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T14:54:14.190Z,1569250454.190 [NAL9602](INFO): SBD MO Status=2, MOMSN=1416, MT Status=2, MTMSN=0
2019-09-23T14:54:14.190Z,1569250454.190 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T14:56:39.945Z,1569250599.945 [CBIT](INFO): Clearing failed state for component DropWeight
2019-09-23T14:56:39.945Z,1569250599.945 [DropWeight] No Fault, FailCount= 1
2019-09-23T14:57:51.759Z,1569250671.759 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T14:58:19.915Z,1569250699.915 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T14:59:14.798Z,1569250754.798 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:02:17.808Z,1569250937.808 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2019-09-23T15:07:40.610Z,1569251260.610 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-09-23T15:07:40.610Z,1569251260.610 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19092315070348,35.0, -0.1,   0.0,10
2019-09-23T15:11:06.789Z,1569251466.789 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,1753.87
2019-09-23T15:11:56.776Z,1569251516.776 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-09-23T15:12:33.830Z,1569251553.830 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-09-23T15:12:33.831Z,1569251553.831 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3278,V
2019-09-23T15:12:46.777Z,1569251566.777 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2019-09-23T15:13:38.814Z,1569251618.814 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T15:13:53.776Z,1569251633.776 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,1920.85
2019-09-23T15:16:29.934Z,1569251789.934 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:17:04.743Z,1569251824.743 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:21:21.876Z,1569252081.876 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-09-23T15:21:21.877Z,1569252081.877 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-09-23T15:22:38.603Z,1569252158.603 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-09-23T15:22:39.937Z,1569252159.937 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:22:49.606Z,1569252169.606 [NAL9602](INFO): SBD MO Status=2, MOMSN=1416, MT Status=2, MTMSN=0
2019-09-23T15:22:49.606Z,1569252169.606 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:24:17.774Z,1569252257.774 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-09-23T15:24:45.732Z,1569252285.732 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:26:06.748Z,1569252366.748 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T15:26:48.835Z,1569252408.835 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:27:03.739Z,1569252423.739 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T15:28:15.772Z,1569252495.772 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T15:29:38.788Z,1569252578.788 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:30:44.130Z,1569252644.130 [NAL9602](INFO): SBD MO Status=2, MOMSN=1416, MT Status=2, MTMSN=0
2019-09-23T15:30:44.130Z,1569252644.130 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:31:02.869Z,1569252662.869 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-09-23T15:31:02.869Z,1569252662.869 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768V
2019-09-23T15:31:06.496Z,1569252666.496 [NAL9602](INFO): SBD MO Status=2, MOMSN=1416, MT Status=2, MTMSN=0
2019-09-23T15:31:06.496Z,1569252666.496 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:31:25.758Z,1569252685.758 [NAL9602](INFO): SBD MO Status=1, MOMSN=1416, MT Status=0, MTMSN=0
2019-09-23T15:31:25.811Z,1569252685.811 [NAL9602](INFO): Sent 104 bytes from file Logs/20190922T103144/Courier0841.lzma
2019-09-23T15:31:25.812Z,1569252685.812 [NAL9602](INFO): Packets left to send: 0
2019-09-23T15:31:42.554Z,1569252702.554 [NAL9602](INFO): SBD MO Status=2, MOMSN=1417, MT Status=2, MTMSN=0
2019-09-23T15:31:42.554Z,1569252702.554 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:32:06.688Z,1569252726.688 [NAL9602](INFO): SBD MO Status=2, MOMSN=1417, MT Status=2, MTMSN=0
2019-09-23T15:32:06.688Z,1569252726.688 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:32:32.766Z,1569252752.766 [NAL9602](INFO): SBD MO Status=2, MOMSN=1417, MT Status=2, MTMSN=0
2019-09-23T15:32:32.766Z,1569252752.766 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:34:08.506Z,1569252848.506 [NAL9602](INFO): SBD MO Status=2, MOMSN=1417, MT Status=2, MTMSN=0
2019-09-23T15:34:08.506Z,1569252848.506 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:35:27.771Z,1569252927.771 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:40:05.521Z,1569253205.521 [NAL9602](INFO): SBD MO Status=1, MOMSN=1417, MT Status=0, MTMSN=0
2019-09-23T15:40:05.584Z,1569253205.584 [NAL9602](INFO): Sent 177 bytes from file Logs/20190923T143748/Courier0000.lzma
2019-09-23T15:40:05.584Z,1569253205.584 [NAL9602](INFO): Packets left to send: 0
2019-09-23T15:40:11.083Z,1569253211.083 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T15:40:20.365Z,1569253220.365 [NAL9602](INFO): SBD MO Status=2, MOMSN=1418, MT Status=2, MTMSN=0
2019-09-23T15:40:20.366Z,1569253220.366 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:41:06.937Z,1569253266.937 [NAL9602](INFO): SBD MO Status=2, MOMSN=1418, MT Status=2, MTMSN=0
2019-09-23T15:41:06.938Z,1569253266.938 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:41:32.261Z,1569253292.260 [NAL9602](INFO): SBD MO Status=2, MOMSN=1418, MT Status=2, MTMSN=0
2019-09-23T15:41:32.261Z,1569253292.261 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:41:59.609Z,1569253319.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=1418, MT Status=2, MTMSN=0
2019-09-23T15:41:59.610Z,1569253319.610 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:42:46.133Z,1569253366.133 [NAL9602](INFO): SBD MO Status=1, MOMSN=1418, MT Status=0, MTMSN=0
2019-09-23T15:42:46.187Z,1569253366.187 [NAL9602](INFO): Sent 25 bytes from file Logs/20190923T143748/Courier0004.lzma
2019-09-23T15:42:46.187Z,1569253366.187 [NAL9602](INFO): Packets left to send: 0
2019-09-23T15:42:56.838Z,1569253376.838 [NAL9602](INFO): SBD MO Status=2, MOMSN=1419, MT Status=2, MTMSN=0
2019-09-23T15:42:56.838Z,1569253376.838 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:44:55.767Z,1569253495.767 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2019-09-23T15:46:08.811Z,1569253568.811 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:47:38.991Z,1569253658.991 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-09-23T15:47:38.991Z,1569253658.991 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-09-23T15:48:04.756Z,1569253684.756 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T15:49:50.820Z,1569253790.820 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:50:04.113Z,1569253804.113 [NAL9602](INFO): SBD MO Status=1, MOMSN=1419, MT Status=0, MTMSN=0
2019-09-23T15:50:04.171Z,1569253804.171 [NAL9602](INFO): Sent 176 bytes from file Logs/20190923T144127/Courier0000.lzma
2019-09-23T15:50:04.171Z,1569253804.171 [NAL9602](INFO): Packets left to send: 0
2019-09-23T15:50:25.347Z,1569253825.347 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T15:50:25.347Z,1569253825.347 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:51:43.877Z,1569253903.877 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T15:51:43.878Z,1569253903.878 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:52:10.133Z,1569253930.133 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T15:52:10.134Z,1569253930.134 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:52:48.925Z,1569253968.925 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T15:52:48.926Z,1569253968.926 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:53:24.588Z,1569254004.588 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-09-23T15:53:34.670Z,1569254014.670 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T15:53:34.670Z,1569254014.670 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:53:53.721Z,1569254033.721 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T15:55:33.239Z,1569254133.239 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-09-23T15:55:33.241Z,1569254133.241 [BPC1](INFO): Received data from all battery sticks.
2019-09-23T15:57:23.456Z,1569254243.456 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-09-23T15:57:23.456Z,1569254243.456 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,     ,        +0.00,        +0.00,   0.00,4531.12
2019-09-23T15:59:09.747Z,1569254349.747 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T15:59:09.747Z,1569254349.747 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T15:59:24.820Z,1569254364.820 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-09-23T15:59:24.820Z,1569254364.820 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19092315584948,35.0, -0.1,   0.0,144

2019-09-23T16:00:50.630Z,1569254450.630 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T16:00:50.630Z,1569254450.630 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:01:12.885Z,1569254472.885 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T16:01:12.886Z,1569254472.886 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:01:46.648Z,1569254506.648 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-09-23T16:01:46.648Z,1569254506.648 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-9
2019-09-23T16:01:55.526Z,1569254515.526 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T16:01:55.526Z,1569254515.526 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:02:23.396Z,1569254543.396 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T16:02:23.397Z,1569254543.397 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:03:13.845Z,1569254593.845 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T16:03:13.846Z,1569254593.846 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:03:24.450Z,1569254604.450 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-09-23T16:03:24.450Z,1569254604.450 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0    +0.00,        +0.00,   0.00,4891.94
2019-09-23T16:03:48.649Z,1569254628.649 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T16:04:28.544Z,1569254668.544 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-09-23T16:05:08.978Z,1569254708.978 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T16:06:32.732Z,1569254792.732 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T16:08:07.746Z,1569254887.746 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-09-23T16:08:24.289Z,1569254904.289 [NAL9602](INFO): SBD MO Status=2, MOMSN=1420, MT Status=2, MTMSN=0
2019-09-23T16:08:24.289Z,1569254904.289 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:08:57.416Z,1569254937.416 [NAL9602](INFO): SBD MO Status=1, MOMSN=1420, MT Status=0, MTMSN=0
2019-09-23T16:08:57.472Z,1569254937.472 [NAL9602](INFO): Sent 301 bytes from file Logs/20190923T144127/Courier0004.lzma
2019-09-23T16:08:57.473Z,1569254937.473 [NAL9602](INFO): Packets left to send: 0
2019-09-23T16:09:15.742Z,1569254955.742 [NAL9602](INFO): SBD MO Status=2, MOMSN=1421, MT Status=2, MTMSN=0
2019-09-23T16:09:15.742Z,1569254955.742 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:09:55.630Z,1569254995.630 [NAL9602](INFO): SBD MO Status=2, MOMSN=1421, MT Status=2, MTMSN=0
2019-09-23T16:09:55.630Z,1569254995.630 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:10:04.711Z,1569255004.711 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T16:11:57.962Z,1569255117.962 [NAL9602](INFO): SBD MO Status=1, MOMSN=1421, MT Status=0, MTMSN=0
2019-09-23T16:11:58.019Z,1569255118.019 [NAL9602](INFO): Sent 197 bytes from file Logs/20190922T103144/Express0839.lzma
2019-09-23T16:11:58.020Z,1569255118.020 [NAL9602](INFO): Packets left to send: 0
2019-09-23T16:12:12.349Z,1569255132.349 [NAL9602](INFO): SBD MO Status=2, MOMSN=1422, MT Status=2, MTMSN=0
2019-09-23T16:12:12.350Z,1569255132.350 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:13:03.461Z,1569255183.461 [NAL9602](INFO): SBD MO Status=1, MOMSN=1422, MT Status=0, MTMSN=0
2019-09-23T16:13:03.531Z,1569255183.531 [NAL9602](INFO): Sent 332 bytes from file Logs/20190922T103144/Express0842.lzma
2019-09-23T16:13:03.532Z,1569255183.532 [NAL9602](INFO): Packets left to send: 1
2019-09-23T16:13:21.838Z,1569255201.838 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:13:21.838Z,1569255201.838 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:15:48.768Z,1569255348.768 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T16:17:22.030Z,1569255442.030 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:17:22.030Z,1569255442.030 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:18:00.942Z,1569255480.942 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:18:00.942Z,1569255480.942 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:18:23.653Z,1569255503.653 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:18:23.654Z,1569255503.654 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:18:58.126Z,1569255538.126 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:18:58.126Z,1569255538.126 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:19:35.274Z,1569255575.274 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:19:35.274Z,1569255575.274 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:19:44.701Z,1569255584.701 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T16:20:26.290Z,1569255626.290 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:20:26.290Z,1569255626.290 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:20:47.699Z,1569255647.699 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T16:22:03.794Z,1569255723.794 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:22:03.794Z,1569255723.794 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:22:51.068Z,1569255771.068 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T16:24:18.914Z,1569255858.914 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:24:18.914Z,1569255858.914 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:28:31.462Z,1569256111.462 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:28:31.462Z,1569256111.462 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:29:39.378Z,1569256179.378 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:29:39.378Z,1569256179.378 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:30:18.272Z,1569256218.272 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:30:18.272Z,1569256218.272 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:30:32.777Z,1569256232.777 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-09-23T16:30:32.777Z,1569256232.777 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-09-23T16:30:49.866Z,1569256249.866 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:30:49.866Z,1569256249.866 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:35:50.484Z,1569256550.484 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:35:50.485Z,1569256550.485 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:36:01.032Z,1569256561.032 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T16:36:29.401Z,1569256589.401 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:36:29.401Z,1569256589.401 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:37:10.918Z,1569256630.918 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:37:10.918Z,1569256630.918 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:37:48.733Z,1569256668.733 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:37:48.734Z,1569256668.734 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:38:13.389Z,1569256693.389 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:38:13.390Z,1569256693.390 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:38:35.302Z,1569256715.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:38:35.302Z,1569256715.302 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:45:52.525Z,1569257152.525 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:45:52.526Z,1569257152.526 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:46:59.582Z,1569257219.582 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:46:59.582Z,1569257219.582 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:47:28.736Z,1569257248.736 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T16:48:38.667Z,1569257318.667 [Default:CheckIn:Read_Iridium](INFO): Timed out from 2019-09-23T14:48:35.2Z
2019-09-23T16:48:38.667Z,1569257318.667 [Default:CheckIn:Read_Iridium:A_Timeout] Running Loop=1
2019-09-23T16:48:38.667Z,1569257318.667 [Default:CheckIn:Read_Iridium:A_Timeout](DEBUG): Aggregate::initialize Default:CheckIn:Read_Iridium:A_Timeout
2019-09-23T16:48:38.667Z,1569257318.667 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute] Running Loop=1
2019-09-23T16:48:38.667Z,1569257318.667 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](INFO): Executing command Burn on
2019-09-23T16:48:38.668Z,1569257318.668 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute] Stopped
2019-09-23T16:48:38.668Z,1569257318.668 [Default:CheckIn:Read_Iridium:A_Timeout:B] Running Loop=1
2019-09-23T16:48:38.740Z,1569257318.740 [CommandLine](IMPORTANT): got command burn on
2019-09-23T16:48:38.740Z,1569257318.740 [CommandLine](IMPORTANT): Activating dropweight wire
2019-09-23T16:48:38.804Z,1569257318.804 [Default:CheckIn:Read_Iridium:A_Timeout:B](CRITICAL): Dropped weight due to communications timeout.
2019-09-23T16:48:38.804Z,1569257318.804 [Default:CheckIn:Read_Iridium:A_Timeout:B] Stopped
2019-09-23T16:48:38.804Z,1569257318.804 [Default:CheckIn:Read_Iridium:A_Timeout](INFO): Completed Default:CheckIn:Read_Iridium:A_Timeout
2019-09-23T16:48:38.804Z,1569257318.804 [Default:CheckIn:Read_Iridium] Stopped
2019-09-23T16:48:38.805Z,1569257318.805 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-23T16:48:38.805Z,1569257318.805 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-23T16:48:38.815Z,1569257318.815 [CBIT](INFO): Critical error at 20190923T164838
2019-09-23T16:49:09.804Z,1569257349.804 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-23T16:53:39.304Z,1569257619.304 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-23T16:53:39.304Z,1569257619.304 [Default:CheckIn:C.Wait] Stopped
2019-09-23T16:53:39.304Z,1569257619.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-23T16:53:39.304Z,1569257619.304 [Default:CheckIn:D] Running Loop=1
2019-09-23T16:53:39.699Z,1569257619.699 [Default:CheckIn:D] Stopped
2019-09-23T16:53:39.699Z,1569257619.699 [Default:CheckIn:E] Running Loop=1
2019-09-23T16:53:40.117Z,1569257620.117 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.131486 min 
2019-09-23T16:53:40.118Z,1569257620.118 [Default:CheckIn:E] Stopped
2019-09-23T16:53:40.118Z,1569257620.118 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-23T16:53:40.118Z,1569257620.118 [Default:CheckIn] Stopped
2019-09-23T16:53:40.118Z,1569257620.118 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-23T16:53:40.118Z,1569257620.118 [Default:CheckIn](INFO): Running loop #2
2019-09-23T16:53:40.118Z,1569257620.118 [Default:CheckIn] Running Loop=2
2019-09-23T16:53:40.118Z,1569257620.118 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-23T16:53:40.118Z,1569257620.118 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-23T16:53:41.703Z,1569257621.703 [NAL9602](DEBUG): Fix Requested
2019-09-23T16:53:42.103Z,1569257622.103 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165202.00,A,3648.16643,N,12147.28581,W,0.039,0.00,230919,,,A*7C
2019-09-23T16:53:42.105Z,1569257622.105 [NAL9602](INFO): GPS fix at 20190923T165202: (36.802774, -121.788097)
2019-09-23T16:53:42.154Z,1569257622.154 [Default:CheckIn:Read_GPS] Stopped
2019-09-23T16:53:42.154Z,1569257622.154 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-23T16:54:55.680Z,1569257695.680 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T16:55:06.683Z,1569257706.683 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-09-23T16:56:16.996Z,1569257776.996 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-09-23T16:56:16.997Z,1569257776.997 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,190923165 0
2019-09-23T16:56:59.682Z,1569257819.682 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:56:59.682Z,1569257819.682 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:57:18.532Z,1569257838.532 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T16:57:18.532Z,1569257838.532 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T16:57:29.884Z,1569257849.884 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,8134.36
2019-09-23T16:58:23.414Z,1569257903.414 [RDI_Pathfinder](ERROR): Failed to parse:.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T17:02:54.428Z,1569258174.428 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-23T17:03:09.824Z,1569258189.824 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-09-23T17:03:09.826Z,1569258189.826 [BPC1](INFO): Received data from all battery sticks.
2019-09-23T17:04:04.672Z,1569258244.672 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T17:04:33.694Z,1569258273.694 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T17:05:11.252Z,1569258311.252 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T17:05:11.253Z,1569258311.253 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:12:15.713Z,1569258735.713 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-09-23T17:13:43.656Z,1569258823.656 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-09-23T17:18:13.709Z,1569259093.709 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-09-23T17:18:13.710Z,1569259093.710 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19092317173848,35.0, -0.1,448.9,  0
2019-09-23T17:20:16.656Z,1569259216.656 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-09-23T17:23:10.750Z,1569259390.750 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-09-23T17:23:10.751Z,1569259390.751 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,32768,-32768,V
2019-09-23T17:26:32.696Z,1569259592.696 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T17:27:29.749Z,1569259649.749 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-09-23T17:27:29.750Z,1569259649.750 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,     ,        +0.00,   0.00,9933.63
2019-09-23T17:27:47.796Z,1569259667.796 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-09-23T17:27:47.796Z,1569259667.796 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00
2019-09-23T17:29:18.746Z,1569259758.746 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-09-23T17:30:06.495Z,1569259806.495 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2019-09-23T17:30:06.495Z,1569259806.495 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-09-23T17:30:06.496Z,1569259806.496 [RDI_Pathfinder](ERROR): Communications Fault
2019-09-23T17:30:06.496Z,1569259806.496 [RDI_Pathfinder](ERROR): Failed to parse:
2019-09-23T17:30:06.522Z,1569259806.522 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-09-23T17:30:06.867Z,1569259806.867 [RDI_Pathfinder](INFO): Powering down
2019-09-23T17:30:07.642Z,1569259807.642 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-09-23T17:30:07.642Z,1569259807.642 [RDI_Pathfinder] No Fault, FailCount= 1
2019-09-23T17:30:22.931Z,1569259822.931 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-09-23T17:30:31.165Z,1569259831.165 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T17:32:37.994Z,1569259957.994 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,134.00
2019-09-23T17:34:00.226Z,1569260040.226 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T17:34:00.226Z,1569260040.226 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:34:25.922Z,1569260065.922 [NAL9602](INFO): SBD MO Status=2, MOMSN=1423, MT Status=2, MTMSN=0
2019-09-23T17:34:25.922Z,1569260065.922 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:34:55.252Z,1569260095.252 [NAL9602](INFO): SBD MO Status=1, MOMSN=1423, MT Status=0, MTMSN=0
2019-09-23T17:34:55.304Z,1569260095.304 [NAL9602](INFO): Sent 187 bytes from file Logs/20190923T144127/Courier0007.lzma
2019-09-23T17:34:55.304Z,1569260095.304 [NAL9602](INFO): Packets left to send: 0
2019-09-23T17:35:13.535Z,1569260113.535 [NAL9602](INFO): SBD MO Status=2, MOMSN=1424, MT Status=2, MTMSN=0
2019-09-23T17:35:13.535Z,1569260113.535 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:37:32.958Z,1569260252.958 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T17:37:52.256Z,1569260272.256 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2019-09-23T17:37:52.256Z,1569260272.256 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00
2019-09-23T17:43:10.454Z,1569260590.454 [NAL9602](INFO): SBD MO Status=2, MOMSN=1424, MT Status=2, MTMSN=0
2019-09-23T17:43:10.454Z,1569260590.454 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:43:44.982Z,1569260624.982 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T17:43:50.037Z,1569260630.037 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T17:44:07.634Z,1569260647.634 [NAL9602](INFO): SBD MO Status=2, MOMSN=1424, MT Status=2, MTMSN=0
2019-09-23T17:44:07.634Z,1569260647.634 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:44:41.993Z,1569260681.993 [NAL9602](INFO): SBD MO Status=1, MOMSN=1424, MT Status=0, MTMSN=0
2019-09-23T17:44:42.067Z,1569260682.067 [NAL9602](INFO): Sent 215 bytes from file Logs/20190922T103144/Express0842.lzma
2019-09-23T17:44:42.068Z,1569260682.068 [NAL9602](INFO): Packets left to send: 0
2019-09-23T17:45:19.006Z,1569260719.006 [NAL9602](INFO): SBD MO Status=2, MOMSN=1425, MT Status=2, MTMSN=0
2019-09-23T17:45:19.006Z,1569260719.006 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:46:47.114Z,1569260807.114 [NAL9602](INFO): SBD MO Status=2, MOMSN=1425, MT Status=2, MTMSN=0
2019-09-23T17:46:47.114Z,1569260807.114 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:47:18.619Z,1569260838.619 [NAL9602](INFO): SBD MO Status=2, MOMSN=1425, MT Status=2, MTMSN=0
2019-09-23T17:47:18.619Z,1569260838.619 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:53:39.517Z,1569261219.517 [NAL9602](INFO): SBD MO Status=1, MOMSN=1425, MT Status=0, MTMSN=0
2019-09-23T17:53:39.580Z,1569261219.580 [NAL9602](INFO): Sent 332 bytes from file Logs/20190923T143748/Express0001.lzma
2019-09-23T17:53:39.580Z,1569261219.580 [NAL9602](INFO): Packets left to send: 3
2019-09-23T17:53:59.546Z,1569261239.546 [NAL9602](INFO): SBD MO Status=1, MOMSN=1426, MT Status=0, MTMSN=0
2019-09-23T17:53:59.596Z,1569261239.596 [NAL9602](INFO): Sent 332 bytes from file Logs/20190923T143748/Express0001.lzma
2019-09-23T17:53:59.596Z,1569261239.596 [NAL9602](INFO): Packets left to send: 2
2019-09-23T17:54:14.734Z,1569261254.734 [NAL9602](INFO): SBD MO Status=2, MOMSN=1427, MT Status=2, MTMSN=0
2019-09-23T17:54:14.734Z,1569261254.734 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:54:37.386Z,1569261277.386 [NAL9602](INFO): SBD MO Status=2, MOMSN=1427, MT Status=2, MTMSN=0
2019-09-23T17:54:37.386Z,1569261277.386 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:55:09.026Z,1569261309.026 [NAL9602](INFO): SBD MO Status=2, MOMSN=1427, MT Status=2, MTMSN=0
2019-09-23T17:55:09.026Z,1569261309.026 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:55:38.845Z,1569261338.845 [NAL9602](INFO): SBD MO Status=2, MOMSN=1427, MT Status=2, MTMSN=0
2019-09-23T17:55:38.846Z,1569261338.846 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:56:17.306Z,1569261377.306 [NAL9602](INFO): SBD MO Status=2, MOMSN=1427, MT Status=2, MTMSN=0
2019-09-23T17:56:17.306Z,1569261377.306 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T17:58:12.946Z,1569261492.946 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T18:00:15.093Z,1569261615.093 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-09-23T18:00:15.093Z,1569261615.093 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,32768,V
2019-09-23T18:02:30.190Z,1569261750.190 [NAL9602](INFO): SBD MO Status=1, MOMSN=1427, MT Status=0, MTMSN=0
2019-09-23T18:02:30.235Z,1569261750.235 [NAL9602](INFO): Sent 332 bytes from file Logs/20190923T143748/Express0001.lzma
2019-09-23T18:02:30.236Z,1569261750.236 [NAL9602](INFO): Packets left to send: 1
2019-09-23T18:02:49.092Z,1569261769.092 [NAL9602](INFO): SBD MO Status=1, MOMSN=1428, MT Status=0, MTMSN=0
2019-09-23T18:02:49.144Z,1569261769.144 [NAL9602](INFO): Sent 4 bytes from file Logs/20190923T143748/Express0001.lzma
2019-09-23T18:02:49.144Z,1569261769.144 [NAL9602](INFO): Packets left to send: 0
2019-09-23T18:03:03.704Z,1569261783.704 [NAL9602](INFO): SBD MO Status=1, MOMSN=1429, MT Status=0, MTMSN=0
2019-09-23T18:03:03.759Z,1569261783.759 [NAL9602](INFO): Sent 78 bytes from file Logs/20190923T143748/Express0005.lzma
2019-09-23T18:03:03.759Z,1569261783.759 [NAL9602](INFO): Packets left to send: 0
2019-09-23T18:03:11.049Z,1569261791.049 [RDI_Pathfinder](ERROR): Failed to parse:
:R,   0.00,   0.00,   0.00,   0.00
2019-09-23T18:03:28.781Z,1569261808.781 [NAL9602](INFO): SBD MO Status=2, MOMSN=1430, MT Status=2, MTMSN=0
2019-09-23T18:03:28.782Z,1569261808.782 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:03:54.534Z,1569261834.534 [NAL9602](INFO): SBD MO Status=2, MOMSN=1430, MT Status=2, MTMSN=0
2019-09-23T18:03:54.534Z,1569261834.534 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:04:32.921Z,1569261872.921 [NAL9602](INFO): SBD MO Status=2, MOMSN=1430, MT Status=2, MTMSN=0
2019-09-23T18:04:32.921Z,1569261872.921 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:04:42.330Z,1569261882.330 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T18:04:56.207Z,1569261896.207 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,2070.83
2019-09-23T18:05:08.781Z,1569261908.781 [NAL9602](INFO): SBD MO Status=2, MOMSN=1430, MT Status=2, MTMSN=0
2019-09-23T18:05:08.781Z,1569261908.781 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:05:38.866Z,1569261938.866 [NAL9602](INFO): SBD MO Status=2, MOMSN=1430, MT Status=2, MTMSN=0
2019-09-23T18:05:38.866Z,1569261938.866 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:05:53.921Z,1569261953.921 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T18:06:04.869Z,1569261964.869 [NAL9602](INFO): SBD MO Status=2, MOMSN=1430, MT Status=2, MTMSN=0
2019-09-23T18:06:04.870Z,1569261964.870 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:06:29.255Z,1569261989.255 [NAL9602](INFO): SBD MO Status=2, MOMSN=1430, MT Status=2, MTMSN=0
2019-09-23T18:06:29.256Z,1569261989.256 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:06:56.596Z,1569262016.596 [NAL9602](INFO): SBD MO Status=2, MOMSN=1430, MT Status=2, MTMSN=0
2019-09-23T18:06:56.596Z,1569262016.596 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:07:15.845Z,1569262035.845 [NAL9602](INFO): SBD MO Status=2, MOMSN=1430, MT Status=2, MTMSN=0
2019-09-23T18:07:15.846Z,1569262035.846 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:07:52.325Z,1569262072.325 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,2247.78
2019-09-23T18:10:20.146Z,1569262220.146 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-09-23T18:10:45.162Z,1569262245.162 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-09-23T18:10:45.173Z,1569262245.173 [BPC1](INFO): Received data from all battery sticks.
2019-09-23T18:12:08.538Z,1569262328.538 [NAL9602](INFO): SBD MO Status=1, MOMSN=1430, MT Status=0, MTMSN=0
2019-09-23T18:12:08.600Z,1569262328.600 [NAL9602](INFO): Sent 332 bytes from file Logs/20190923T144127/Express0001.lzma
2019-09-23T18:12:08.600Z,1569262328.600 [NAL9602](INFO): Packets left to send: 2
2019-09-23T18:12:31.661Z,1569262351.661 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:12:31.662Z,1569262351.662 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:12:57.565Z,1569262377.565 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:12:57.566Z,1569262377.566 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:13:41.629Z,1569262421.629 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:13:41.630Z,1569262421.630 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:14:24.946Z,1569262464.946 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:14:24.946Z,1569262464.946 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:14:36.986Z,1569262476.986 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-09-23T18:14:51.909Z,1569262491.909 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:14:51.910Z,1569262491.910 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:15:18.125Z,1569262518.125 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:15:18.126Z,1569262518.126 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:15:46.742Z,1569262546.742 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:15:46.742Z,1569262546.742 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:16:26.421Z,1569262586.421 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:16:26.422Z,1569262586.422 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:16:50.217Z,1569262610.217 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-09-23T18:16:50.217Z,1569262610.217 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-09-23T18:16:54.246Z,1569262614.246 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:16:54.246Z,1569262614.246 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:18:26.950Z,1569262706.950 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-09-23T18:19:28.815Z,1569262768.815 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2019-09-23T18:19:28.815Z,1569262768.815 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-09-23T18:19:28.815Z,1569262768.815 [RDI_Pathfinder](ERROR): Communications Fault
2019-09-23T18:19:28.816Z,1569262768.816 [RDI_Pathfinder](ERROR): Failed to parse:
2019-09-23T18:19:28.838Z,1569262768.838 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-09-23T18:19:29.175Z,1569262769.175 [RDI_Pathfinder](INFO): Powering down
2019-09-23T18:19:34.471Z,1569262774.471 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-09-23T18:19:34.471Z,1569262774.471 [RDI_Pathfinder] No Fault, FailCount= 1
2019-09-23T18:19:48.154Z,1569262788.154 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-09-23T18:21:25.649Z,1569262885.649 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:21:25.650Z,1569262885.650 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:21:51.419Z,1569262911.419 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:21:51.420Z,1569262911.420 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:22:28.475Z,1569262948.475 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:22:28.476Z,1569262948.476 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:23:09.406Z,1569262989.406 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:23:09.406Z,1569262989.406 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:23:47.482Z,1569263027.482 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:23:47.482Z,1569263027.482 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:24:12.424Z,1569263052.424 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:24:12.424Z,1569263052.424 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:25:03.284Z,1569263103.284 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:25:03.285Z,1569263103.285 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:25:26.541Z,1569263126.541 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:25:26.542Z,1569263126.542 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:25:55.618Z,1569263155.618 [NAL9602](INFO): SBD MO Status=2, MOMSN=1431, MT Status=2, MTMSN=0
2019-09-23T18:25:55.618Z,1569263155.618 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:26:49.309Z,1569263209.309 [NAL9602](INFO): SBD MO Status=1, MOMSN=1431, MT Status=0, MTMSN=0
2019-09-23T18:26:49.363Z,1569263209.363 [NAL9602](INFO): Sent 332 bytes from file Logs/20190923T144127/Express0001.lzma
2019-09-23T18:26:49.364Z,1569263209.364 [NAL9602](INFO): Packets left to send: 1
2019-09-23T18:27:06.857Z,1569263226.857 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:27:06.857Z,1569263226.857 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:30:03.988Z,1569263403.988 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:30:03.988Z,1569263403.988 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:30:23.362Z,1569263423.362 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:30:23.362Z,1569263423.362 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:31:23.677Z,1569263483.677 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:31:23.678Z,1569263483.678 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:31:26.531Z,1569263486.531 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-09-23T18:31:26.531Z,1569263486.531 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19092318305093,35.0, -0.,1448.9,  0
2019-09-23T18:31:42.396Z,1569263502.396 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-09-23T18:31:42.396Z,1569263502.396 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-09-23T18:32:08.254Z,1569263528.254 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:32:08.254Z,1569263528.254 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:32:21.157Z,1569263541.157 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-09-23T18:32:21.158Z,1569263541.158 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,1909231831459-0.1,   0.0,1448.9,  0
2019-09-23T18:32:28.006Z,1569263548.006 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:32:28.006Z,1569263548.006 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:32:37.434Z,1569263557.434 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T18:33:04.626Z,1569263584.626 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:33:04.626Z,1569263584.626 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:34:00.674Z,1569263640.674 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:34:00.674Z,1569263640.674 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:34:23.506Z,1569263663.506 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:34:23.506Z,1569263663.506 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:34:49.201Z,1569263689.201 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:34:49.202Z,1569263689.202 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:35:36.146Z,1569263736.146 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-09-23T18:35:36.147Z,1569263736.147 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3268,-32768,V
2019-09-23T18:35:42.956Z,1569263742.956 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:35:42.956Z,1569263742.956 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:36:24.073Z,1569263784.073 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2019-09-23T18:38:09.948Z,1569263889.948 [CommandLine](IMPORTANT): got command do
2019-09-23T18:38:09.948Z,1569263889.948 [CommandLine](FAULT): Incomplete syntax. Try: help do
2019-09-23T18:38:12.764Z,1569263892.764 [CommandLine](IMPORTANT): got command do
2019-09-23T18:38:12.764Z,1569263892.764 [CommandLine](FAULT): Incomplete syntax. Try: help do
2019-09-23T18:39:01.702Z,1569263941.702 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=2, MTMSN=0
2019-09-23T18:39:01.702Z,1569263941.702 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:39:16.243Z,1569263956.243 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-09-23T18:40:03.866Z,1569264003.866 [NAL9602](INFO): SBD MO Status=2, MOMSN=1432, MT Status=0, MTMSN=0
2019-09-23T18:40:03.867Z,1569264003.867 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T18:40:17.284Z,1569264017.284 [CommandLine](IMPORTANT): got command failComponent
2019-09-23T18:40:17.284Z,1569264017.284 [CommandLine](IMPORTANT): Failed components:
2019-09-23T18:40:17.284Z,1569264017.284 [CommandLine](IMPORTANT): No failed Components.
2019-09-23T18:40:32.176Z,1569264032.176 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-09-23T18:40:32.176Z,1569264032.176 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19092318395693,35.0, -0.1,   .9,  0
2019-09-23T18:40:45.928Z,1569264045.928 [CommandLine](IMPORTANT): got command restart system
2019-09-23T18:40:48.003Z,1569264048.003 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T18:40:48.004Z,1569264048.004 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.127Z,1569264048.127 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-09-23T18:40:48.127Z,1569264048.127 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.128Z,1569264048.128 [CommandLine](INFO): Join timeout helper Thread ID is 5597
2019-09-23T18:40:48.128Z,1569264048.128 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-09-23T18:40:48.129Z,1569264048.129 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.129Z,1569264048.129 [NavChartDb](INFO): Join timeout helper Thread ID is 5598
2019-09-23T18:40:48.219Z,1569264048.219 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T18:40:48.219Z,1569264048.219 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.227Z,1569264048.227 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2019-09-23T18:40:48.227Z,1569264048.227 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.227Z,1569264048.227 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 5599
2019-09-23T18:40:48.271Z,1569264048.271 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T18:40:48.271Z,1569264048.271 [WetLabsUBAT](INFO): uninitialize
2019-09-23T18:40:48.271Z,1569264048.271 [WetLabsUBAT](INFO): Powering down
2019-09-23T18:40:48.288Z,1569264048.288 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.289Z,1569264048.289 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-09-23T18:40:48.289Z,1569264048.289 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.289Z,1569264048.289 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5600
2019-09-23T18:40:48.623Z,1569264048.623 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T18:40:48.624Z,1569264048.624 [WetLabsBB2FL](INFO): Powering down
2019-09-23T18:40:48.625Z,1569264048.625 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.643Z,1569264048.643 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-09-23T18:40:48.643Z,1569264048.643 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.643Z,1569264048.643 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5601
2019-09-23T18:40:48.788Z,1569264048.788 [CTD_Seabird](INFO): Powering down
2019-09-23T18:40:48.803Z,1569264048.803 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T18:40:48.803Z,1569264048.803 [CTD_Seabird](INFO): Powering down
2019-09-23T18:40:48.815Z,1569264048.815 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.828Z,1569264048.828 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-09-23T18:40:48.828Z,1569264048.828 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:48.829Z,1569264048.829 [Radio_Surface](INFO): Join timeout helper Thread ID is 5602
2019-09-23T18:40:49.043Z,1569264049.043 [Radio_Surface](INFO): Powering down
2019-09-23T18:40:49.044Z,1569264049.044 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T18:40:49.045Z,1569264049.045 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:49.052Z,1569264049.052 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-09-23T18:40:49.052Z,1569264049.052 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:49.052Z,1569264049.052 [DataOverHttps](INFO): Join timeout helper Thread ID is 5603
2019-09-23T18:40:49.889Z,1569264049.889 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T18:40:49.890Z,1569264049.890 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:49.896Z,1569264049.896 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-09-23T18:40:49.896Z,1569264049.896 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:49.896Z,1569264049.896 [logger](INFO): Join timeout helper Thread ID is 5604
2019-09-23T18:40:49.940Z,1569264049.940 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T18:40:49.940Z,1569264049.940 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:49.952Z,1569264049.952 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-09-23T18:40:49.952Z,1569264049.952 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:49.952Z,1569264049.952 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-09-23T18:40:49.952Z,1569264049.952 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:49.953Z,1569264049.953 [controlThread](INFO): Join timeout helper Thread ID is 5605
2019-09-23T18:40:49.959Z,1569264049.959 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T18:40:49.960Z,1569264049.960 [controlThread](DEBUG): Uninitializing ControlThread
2019-09-23T18:40:49.960Z,1569264049.960 [AHRS_M2](INFO): Powering down
2019-09-23T18:40:50.032Z,1569264050.032 [NAL9602](INFO): Powering down
2019-09-23T18:40:50.103Z,1569264050.103 [RDI_Pathfinder](INFO): Powering down
2019-09-23T18:40:50.104Z,1569264050.104 [DAT](INFO): Powering down
2019-09-23T18:40:50.224Z,1569264050.224 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-09-23T18:40:50.225Z,1569264050.225 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-09-23T18:40:50.225Z,1569264050.225 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-09-23T18:40:50.226Z,1569264050.226 [MissionManager](INFO): Uninitializing Mission Default
2019-09-23T18:40:50.226Z,1569264050.226 [Default] Stopped
2019-09-23T18:40:50.226Z,1569264050.226 [Default](DEBUG): Aggregate::uninitialize Default
2019-09-23T18:40:50.226Z,1569264050.226 [Default:B.GoToSurface] Stopped
2019-09-23T18:40:50.226Z,1569264050.226 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-23T18:40:50.226Z,1569264050.226 [Default:CheckIn] Stopped
2019-09-23T18:40:50.226Z,1569264050.226 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-23T18:40:50.226Z,1569264050.226 [Default:CheckIn:Read_Iridium] Stopped
2019-09-23T18:40:50.229Z,1569264050.229 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-09-23T18:40:50.229Z,1569264050.229 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-09-23T18:40:50.229Z,1569264050.229 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-09-23T18:40:50.230Z,1569264050.230 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-09-23T18:40:50.230Z,1569264050.230 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-09-23T18:40:50.230Z,1569264050.230 [BuoyancyServo](INFO): Powering down
2019-09-23T18:40:50.243Z,1569264050.243 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-09-23T18:40:50.243Z,1569264050.243 [ElevatorServo](INFO): Powering down
2019-09-23T18:40:50.244Z,1569264050.244 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-09-23T18:40:50.244Z,1569264050.244 [MassServo](INFO): Powering down
2019-09-23T18:40:50.245Z,1569264050.245 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-23T18:40:50.245Z,1569264050.245 [RudderServo](INFO): Powering down
2019-09-23T18:40:50.246Z,1569264050.246 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-09-23T18:40:50.246Z,1569264050.246 [ThrusterServo](INFO): Powering down
2019-09-23T18:40:50.247Z,1569264050.247 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-09-23T18:40:50.247Z,1569264050.247 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-09-23T18:40:50.248Z,1569264050.248 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-09-23T18:40:50.248Z,1569264050.248 [CBIT](DEBUG): Powering off loads.
2019-09-23T18:40:50.259Z,1569264050.259 [CBIT](DEBUG): Disabling WDT.
2019-09-23T18:40:50.271Z,1569264050.271 [CBIT](DEBUG): Opening all GF detection circuits.
2019-09-23T18:40:50.272Z,1569264050.272 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:50.364Z,1569264050.364 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:50.370Z,1569264050.370 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:50.425Z,1569264050.425 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:50.426Z,1569264050.426 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open
2019-09-23T18:40:50.426Z,1569264050.426 [WetLabsUBAT](INFO): WetLabs destructor: done
2019-09-23T18:40:50.429Z,1569264050.429 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:50.432Z,1569264050.432 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:50.442Z,1569264050.442 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-23T18:40:50.518Z,1569264050.518 [logger ThreadHandler](INFO): Thread cancelled.