2020-01-09T18:55:56.755Z,1578596156.755 [Supervisor](DEBUG): Initializing supervisor.
2020-01-09T18:55:56.758Z,1578596156.758 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-09T18:55:56.758Z,1578596156.758 [SyncHandler](INFO): Protected caller Thread ID is 5746
2020-01-09T18:55:56.759Z,1578596156.759 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-09T18:55:56.760Z,1578596156.760 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-09T18:55:56.760Z,1578596156.760 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5747
2020-01-09T18:55:56.763Z,1578596156.763 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-09T18:55:56.774Z,1578596156.774 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-09T18:55:56.775Z,1578596156.775 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-09T18:55:56.775Z,1578596156.775 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5748
2020-01-09T18:55:56.776Z,1578596156.776 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-09T18:55:56.777Z,1578596156.777 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-09T18:55:56.777Z,1578596156.777 [logger ThreadHandler](INFO): Protected caller Thread ID is 5749
2020-01-09T18:55:56.779Z,1578596156.779 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-09T18:55:56.780Z,1578596156.780 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-09T18:55:56.781Z,1578596156.781 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-09T18:55:57.199Z,1578596157.199 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-09T18:55:57.199Z,1578596157.199 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-09T18:55:57.294Z,1578596157.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-09T18:55:57.294Z,1578596157.294 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-09T18:55:57.676Z,1578596157.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-09T18:55:57.676Z,1578596157.676 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-09T18:55:57.810Z,1578596157.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-09T18:55:57.811Z,1578596157.811 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-09T18:55:57.993Z,1578596157.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-09T18:55:57.993Z,1578596157.993 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-09T18:55:58.424Z,1578596158.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-09T18:55:58.425Z,1578596158.425 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-09T18:55:58.625Z,1578596158.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-09T18:55:58.625Z,1578596158.625 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-09T18:55:58.764Z,1578596158.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-09T18:55:58.764Z,1578596158.764 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-09T18:55:58.944Z,1578596158.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-09T18:55:58.944Z,1578596158.944 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-09T18:55:59.037Z,1578596159.037 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-09T18:55:59.038Z,1578596159.038 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-09T18:55:59.326Z,1578596159.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-09T18:55:59.326Z,1578596159.326 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-09T18:55:59.404Z,1578596159.404 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-09T18:55:59.503Z,1578596159.503 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-09T18:55:59.504Z,1578596159.504 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-09T18:56:00.121Z,1578596160.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-09T18:56:00.122Z,1578596160.122 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-09T18:56:00.519Z,1578596160.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-09T18:56:00.521Z,1578596160.521 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-09T18:56:00.522Z,1578596160.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-09T18:56:00.718Z,1578596160.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-09T18:56:00.814Z,1578596160.814 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-09T18:56:00.908Z,1578596160.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-09T18:56:01.129Z,1578596161.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-09T18:56:01.130Z,1578596161.130 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-09T18:56:01.212Z,1578596161.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-09T18:56:01.302Z,1578596161.302 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-09T18:56:01.395Z,1578596161.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-09T18:56:01.475Z,1578596161.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-09T18:56:01.579Z,1578596161.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-09T18:56:01.767Z,1578596161.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-09T18:56:01.935Z,1578596161.935 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-09T18:56:01.935Z,1578596161.935 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-09T18:56:01.967Z,1578596161.967 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-09T18:56:02.675Z,1578596162.675 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-09T18:56:02.712Z,1578596162.712 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-09T18:56:02.714Z,1578596162.714 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-09T18:56:02.735Z,1578596162.735 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-09T18:56:02.736Z,1578596162.736 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-09T18:56:02.765Z,1578596162.765 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-09T18:56:02.766Z,1578596162.766 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-09T18:56:02.771Z,1578596162.771 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-09T18:56:02.931Z,1578596162.931 [AHRS_M2] Loaded
2020-01-09T18:56:02.931Z,1578596162.931 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-09T18:56:03.008Z,1578596163.008 [DataOverHttps] Loaded
2020-01-09T18:56:03.008Z,1578596163.008 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-09T18:56:03.009Z,1578596163.009 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-09T18:56:03.010Z,1578596163.010 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5829
2020-01-09T18:56:03.033Z,1578596163.033 [DDM] Loaded
2020-01-09T18:56:03.033Z,1578596163.033 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-09T18:56:03.046Z,1578596163.046 [Depth_Keller] Loaded
2020-01-09T18:56:03.046Z,1578596163.046 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-09T18:56:03.051Z,1578596163.051 [DropWeight] Loaded
2020-01-09T18:56:03.051Z,1578596163.051 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-09T18:56:03.112Z,1578596163.112 [DUSBL_Hydroid] Loaded
2020-01-09T18:56:03.112Z,1578596163.112 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-09T18:56:03.158Z,1578596163.158 [Micromodem] Loaded
2020-01-09T18:56:03.158Z,1578596163.158 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-09T18:56:03.254Z,1578596163.254 [NAL9602] Loaded
2020-01-09T18:56:03.254Z,1578596163.254 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-09T18:56:03.291Z,1578596163.291 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-09T18:56:03.292Z,1578596163.292 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-09T18:56:03.292Z,1578596163.292 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-09T18:56:03.293Z,1578596163.293 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-09T18:56:03.293Z,1578596163.293 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-09T18:56:03.294Z,1578596163.294 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-09T18:56:03.294Z,1578596163.294 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-09T18:56:03.295Z,1578596163.295 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-09T18:56:03.295Z,1578596163.295 [Onboard] Loaded
2020-01-09T18:56:03.295Z,1578596163.295 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-09T18:56:03.301Z,1578596163.301 [PowerOnly] Loaded
2020-01-09T18:56:03.301Z,1578596163.301 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-09T18:56:03.307Z,1578596163.307 [Radio_Surface] Loaded
2020-01-09T18:56:03.308Z,1578596163.308 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-09T18:56:03.309Z,1578596163.309 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-09T18:56:03.309Z,1578596163.309 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5830
2020-01-09T18:56:03.352Z,1578596163.352 [RDI_Pathfinder] Loaded
2020-01-09T18:56:03.353Z,1578596163.353 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-09T18:56:04.832Z,1578596164.832 [BPC1] Loaded
2020-01-09T18:56:04.832Z,1578596164.832 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-09T18:56:04.832Z,1578596164.832 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-09T18:56:04.833Z,1578596164.833 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-09T18:56:04.846Z,1578596164.846 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-09T18:56:04.847Z,1578596164.847 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-09T18:56:04.949Z,1578596164.949 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-09T18:56:04.949Z,1578596164.949 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-09T18:56:04.969Z,1578596164.969 [NavChart] Loaded
2020-01-09T18:56:04.969Z,1578596164.969 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-09T18:56:04.973Z,1578596164.973 [UniversalFixResidualReporter] Loaded
2020-01-09T18:56:04.973Z,1578596164.973 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-09T18:56:04.974Z,1578596164.974 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-09T18:56:04.974Z,1578596164.974 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-09T18:56:05.079Z,1578596165.079 [BuoyancyServo] Loaded
2020-01-09T18:56:05.080Z,1578596165.080 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-09T18:56:05.094Z,1578596165.094 [ElevatorServo] Loaded
2020-01-09T18:56:05.095Z,1578596165.095 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-09T18:56:05.109Z,1578596165.109 [MassServo] Loaded
2020-01-09T18:56:05.109Z,1578596165.109 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-09T18:56:05.124Z,1578596165.124 [RudderServo] Loaded
2020-01-09T18:56:05.124Z,1578596165.124 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-09T18:56:05.138Z,1578596165.138 [ThrusterServo] Loaded
2020-01-09T18:56:05.139Z,1578596165.139 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-09T18:56:05.139Z,1578596165.139 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-09T18:56:05.140Z,1578596165.140 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-09T18:56:05.392Z,1578596165.392 [CTD_NeilBrown] Loaded
2020-01-09T18:56:05.392Z,1578596165.392 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-09T18:56:05.393Z,1578596165.393 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-09T18:56:05.394Z,1578596165.394 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5831
2020-01-09T18:56:05.408Z,1578596165.408 [PAR_Licor] Loaded
2020-01-09T18:56:05.408Z,1578596165.408 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-09T18:56:05.450Z,1578596165.450 [WetLabsSeaOWL_UV_A] Loaded
2020-01-09T18:56:05.451Z,1578596165.451 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-09T18:56:05.452Z,1578596165.452 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-09T18:56:05.452Z,1578596165.452 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 5832
2020-01-09T18:56:05.453Z,1578596165.453 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-09T18:56:05.453Z,1578596165.453 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-09T18:56:05.756Z,1578596165.756 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-09T18:56:05.757Z,1578596165.757 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-09T18:56:05.796Z,1578596165.796 [DepthRateCalculator] Loaded
2020-01-09T18:56:05.797Z,1578596165.797 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-09T18:56:05.802Z,1578596165.802 [PitchRateCalculator] Loaded
2020-01-09T18:56:05.803Z,1578596165.803 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-09T18:56:05.815Z,1578596165.815 [SpeedCalculator] Loaded
2020-01-09T18:56:05.815Z,1578596165.815 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-09T18:56:05.836Z,1578596165.836 [TempGradientCalculator] Loaded
2020-01-09T18:56:05.836Z,1578596165.836 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-09T18:56:05.841Z,1578596165.841 [YawRateCalculator] Loaded
2020-01-09T18:56:05.842Z,1578596165.842 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-09T18:56:05.882Z,1578596165.882 [ElevatorOffsetCalculator] Loaded
2020-01-09T18:56:05.882Z,1578596165.882 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-09T18:56:05.883Z,1578596165.883 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-09T18:56:05.883Z,1578596165.883 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-09T18:56:06.015Z,1578596166.015 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-09T18:56:06.036Z,1578596166.036 [SBIT] Loaded
2020-01-09T18:56:06.037Z,1578596166.037 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-09T18:56:06.038Z,1578596166.038 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-09T18:56:06.050Z,1578596166.050 [IBIT] Loaded
2020-01-09T18:56:06.050Z,1578596166.050 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-09T18:56:06.053Z,1578596166.053 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-09T18:56:06.190Z,1578596166.190 [CBIT] Loaded
2020-01-09T18:56:06.190Z,1578596166.190 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-09T18:56:06.191Z,1578596166.191 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-09T18:56:06.191Z,1578596166.191 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-09T18:56:06.272Z,1578596166.272 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-09T18:56:06.272Z,1578596166.272 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-09T18:56:06.366Z,1578596166.366 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-09T18:56:06.367Z,1578596166.367 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-09T18:56:06.414Z,1578596166.414 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-09T18:56:06.496Z,1578596166.496 [VerticalControl] Loaded
2020-01-09T18:56:06.497Z,1578596166.497 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-09T18:56:06.497Z,1578596166.497 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-09T18:56:06.566Z,1578596166.566 [HorizontalControl] Loaded
2020-01-09T18:56:06.566Z,1578596166.566 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-09T18:56:06.567Z,1578596166.567 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-09T18:56:06.569Z,1578596166.569 [SpeedControl] Loaded
2020-01-09T18:56:06.569Z,1578596166.569 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-09T18:56:06.570Z,1578596166.570 [LoopControl](DEBUG): Construct LoopControl.
2020-01-09T18:56:06.570Z,1578596166.570 [LoopControl] Loaded
2020-01-09T18:56:06.570Z,1578596166.570 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-09T18:56:06.571Z,1578596166.571 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-09T18:56:06.571Z,1578596166.571 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-09T18:56:06.603Z,1578596166.603 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-09T18:56:06.606Z,1578596166.606 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-09T18:56:06.607Z,1578596166.607 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-09T18:56:06.614Z,1578596166.614 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-09T18:56:06.615Z,1578596166.615 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-09T18:56:06.615Z,1578596166.615 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5833
2020-01-09T18:56:06.619Z,1578596166.619 [Supervisor](INFO): Main Thread ID is 5745
2020-01-09T18:56:06.619Z,1578596166.619 [Supervisor](DEBUG): Running supervisor.
2020-01-09T18:56:06.620Z,1578596166.620 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5834
2020-01-09T18:56:06.623Z,1578596166.623 [controlThread ThreadHandler](INFO): Handler Thread ID is 5835
2020-01-09T18:56:06.623Z,1578596166.623 [controlThread](DEBUG): Initializing ControlThread
2020-01-09T18:56:06.628Z,1578596166.628 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-09T18:56:06.628Z,1578596166.628 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-09T18:56:06.633Z,1578596166.633 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-09T18:56:06.633Z,1578596166.633 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-09T18:56:06.634Z,1578596166.634 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-09T18:56:06.634Z,1578596166.634 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-09T18:56:06.634Z,1578596166.634 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-09T18:56:06.635Z,1578596166.635 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-09T18:56:06.635Z,1578596166.635 [SBIT](INFO): Initialize SBIT Component.
2020-01-09T18:56:06.636Z,1578596166.636 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-09T18:56:06.636Z,1578596166.636 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-09T18:56:06.636Z,1578596166.636 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-09T18:56:06.638Z,1578596166.638 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2020-01-09T18:56:06.639Z,1578596166.639 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-09T18:56:06.639Z,1578596166.639 [IBIT](INFO): Initialize IBIT Component.
2020-01-09T18:56:06.640Z,1578596166.640 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-09T18:56:06.641Z,1578596166.641 [logger ThreadHandler](INFO): Handler Thread ID is 5836
2020-01-09T18:56:06.653Z,1578596166.653 [CBIT](DEBUG): Initialized mux pins.
2020-01-09T18:56:06.653Z,1578596166.653 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-09T18:56:06.661Z,1578596166.661 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5837
2020-01-09T18:56:06.662Z,1578596166.662 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-09T18:56:06.673Z,1578596166.673 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5838
2020-01-09T18:56:06.677Z,1578596166.677 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-09T18:56:06.677Z,1578596166.677 [CBIT](DEBUG): Initializing heartbeat.
2020-01-09T18:56:06.685Z,1578596166.685 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5839
2020-01-09T18:56:06.686Z,1578596166.686 [CTD_NeilBrown](INFO): Powering down
2020-01-09T18:56:06.705Z,1578596166.705 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 5840
2020-01-09T18:56:06.706Z,1578596166.706 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-09T18:56:06.738Z,1578596166.738 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5841
2020-01-09T18:56:06.741Z,1578596166.741 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-09T18:56:06.741Z,1578596166.741 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-09T18:56:06.741Z,1578596166.741 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-09T18:56:06.741Z,1578596166.741 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-09T18:56:06.742Z,1578596166.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-09T18:56:06.742Z,1578596166.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-09T18:56:06.742Z,1578596166.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-09T18:56:06.742Z,1578596166.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-09T18:56:06.742Z,1578596166.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-09T18:56:06.742Z,1578596166.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-09T18:56:06.743Z,1578596166.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-09T18:56:06.743Z,1578596166.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-09T18:56:06.743Z,1578596166.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-09T18:56:06.743Z,1578596166.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-09T18:56:06.743Z,1578596166.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-09T18:56:06.744Z,1578596166.744 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-09T18:56:06.749Z,1578596166.749 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-09T18:56:06.750Z,1578596166.750 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-09T18:56:06.785Z,1578596166.785 [CBIT](DEBUG): Backplane powered.
2020-01-09T18:56:06.785Z,1578596166.785 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-09T18:56:06.787Z,1578596166.787 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-09T18:56:06.788Z,1578596166.788 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-09T18:56:06.788Z,1578596166.788 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-09T18:56:06.789Z,1578596166.789 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-09T18:56:06.799Z,1578596166.799 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-09T18:56:06.867Z,1578596166.867 [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>


2020-01-09T18:56:06.867Z,1578596166.867 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-09T18:56:06.936Z,1578596166.936 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-09T18:56:06.953Z,1578596166.953 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-09T18:56:06.955Z,1578596166.955 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-09T18:56:06.975Z,1578596166.975 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-09T18:56:06.998Z,1578596166.998 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-09T18:56:07.004Z,1578596167.004 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-09T18:56:07.028Z,1578596167.028 [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>


2020-01-09T18:56:07.045Z,1578596167.045 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-01-09T18:56:07.056Z,1578596167.056 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-09T18:56:07.077Z,1578596167.077 [Radio_Surface](INFO): Powering up
2020-01-09T18:56:07.087Z,1578596167.087 [DDM](INFO): Powering up
2020-01-09T18:56:07.087Z,1578596167.087 [DDM](DEBUG): Initializing DDM.
2020-01-09T18:56:07.119Z,1578596167.119 [DUSBL_Hydroid](INFO): Powering up
2020-01-09T18:56:07.119Z,1578596167.119 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-09T18:56:07.318Z,1578596167.318 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-09T18:56:07.325Z,1578596167.325 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-09T18:56:07.326Z,1578596167.326 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-09T18:56:07.333Z,1578596167.333 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-09T18:56:07.334Z,1578596167.334 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-09T18:56:07.341Z,1578596167.341 [MassServo](DEBUG): Initializing MassServo.
2020-01-09T18:56:07.342Z,1578596167.342 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-09T18:56:07.349Z,1578596167.349 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-09T18:56:07.350Z,1578596167.350 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-09T18:56:07.357Z,1578596167.357 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-09T18:56:07.562Z,1578596167.562 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-09T18:56:07.562Z,1578596167.562 [DropWeight] Hardware Fault, FailCount= 1
2020-01-09T18:56:07.562Z,1578596167.562 [DropWeight](ERROR): Hardware Fault
2020-01-09T18:56:07.564Z,1578596167.564 [Micromodem](INFO): Powering up
2020-01-09T18:56:07.564Z,1578596167.564 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-09T18:56:07.628Z,1578596167.628 [CommandLine](FAULT): Scheduling is paused
2020-01-09T18:56:07.628Z,1578596167.628 [CBIT](INFO): Critical error at 20200109T185607
2020-01-09T18:56:07.628Z,1578596167.628 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-09T18:56:07.647Z,1578596167.647 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-09T18:56:07.647Z,1578596167.647 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-09T18:56:08.257Z,1578596168.257 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-09T18:56:08.257Z,1578596168.257 [RudderServo](FAULT): Rudder failed to initialize
2020-01-09T18:56:08.257Z,1578596168.257 [RudderServo] Communications Fault, FailCount= 1
2020-01-09T18:56:08.257Z,1578596168.257 [RudderServo](ERROR): Communications Fault
2020-01-09T18:56:08.366Z,1578596168.366 [CBIT](INFO): Critical error at 20200109T185607
2020-01-09T18:56:08.369Z,1578596168.369 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-09T18:56:08.570Z,1578596168.570 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-09T18:56:08.570Z,1578596168.570 [RudderServo](INFO): Powering down
2020-01-09T18:56:09.233Z,1578596169.233 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-09T18:56:09.354Z,1578596169.354 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-09T18:56:09.358Z,1578596169.358 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-09T18:56:09.358Z,1578596169.358 [RudderServo] No Fault, FailCount= 1
2020-01-09T18:56:12.457Z,1578596172.457 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-09T18:56:12.860Z,1578596172.860 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T18:56:11.701920Z,RTC,RTC*5F

2020-01-09T18:56:12.860Z,1578596172.860 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-09T18:56:11.701920Z,RTC,RTC*5F

2020-01-09T18:56:13.267Z,1578596173.267 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-09T18:56:13.267Z,1578596173.267 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-09T18:56:13.667Z,1578596173.667 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-09T18:56:13.667Z,1578596173.667 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-09T18:56:14.075Z,1578596174.075 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-09T18:56:14.075Z,1578596174.075 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-09T18:56:14.479Z,1578596174.479 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-09T18:56:14.479Z,1578596174.479 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-09T18:56:14.883Z,1578596174.883 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-09T18:56:14.883Z,1578596174.883 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-09T18:56:15.283Z,1578596175.283 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-09T18:56:15.283Z,1578596175.283 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-09T18:56:15.707Z,1578596175.707 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-09T18:56:15.707Z,1578596175.707 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-09T18:56:16.071Z,1578596176.071 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-09T18:56:16.072Z,1578596176.072 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,09,18,56,17*40

2020-01-09T18:56:16.492Z,1578596176.492 [Micromodem](INFO): Nmea in: $CACLK,2020,1,9,18,56,17*42

2020-01-09T18:56:16.875Z,1578596176.875 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-09T18:56:18Z*7D

2020-01-09T18:56:16.877Z,1578596176.877 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T18:56:18.028517Z,USER_CMD,RTC*13

2020-01-09T18:56:17.715Z,1578596177.715 [DDM](INFO): Dynamic Docking Module:( "!C REMUS Capture Rev 1.1
2020-01-09T18:56:20.158Z,1578596180.158 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004509
2020-01-09T18:56:20.537Z,1578596180.537 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-09T18:56:24.946Z,1578596184.946 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-09T18:56:33.035Z,1578596193.035 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68

2020-01-09T18:56:33.036Z,1578596193.036 [Micromodem](INFO): Have incoming data
2020-01-09T18:56:33.039Z,1578596193.039 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-09T18:56:33.039Z,1578596193.039 [Micromodem](INFO): Deserialize. FrameCount:1
2020-01-09T18:56:33.039Z,1578596193.039 [Micromodem](INFO): Outer loop. Size:0
2020-01-09T18:56:33.040Z,1578596193.040 [Micromodem](CRITICAL): Packet size is not N*16+4: 0x0
2020-01-09T18:56:33.040Z,1578596193.040 [Micromodem](CRITICAL): Failed to parse uplink message:
2020-01-09T18:56:33.100Z,1578596193.100 [CBIT](INFO): Critical error at 20200109T185633
2020-01-09T18:56:33.434Z,1578596193.434 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109185632.275756,06,2615,34,0174,0150,59,02,02,00,03,1,012,001,0,3,1,0,150,8.5,8.00,00,-5.00,-01,-0.03,44,10000,4000*4E

2020-01-09T18:56:33.436Z,1578596193.436 [NAL9602](INFO): Powering up NAL9602
2020-01-09T18:56:44.339Z,1578596204.339 [NAL9602](INFO): NAL9602 initialized
2020-01-09T18:56:46.075Z,1578596206.075 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-09T18:56:46.088Z,1578596206.088 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-09T18:56:57.125Z,1578596217.125 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011321
CHAN A1 (24V): -0.029391
CHAN A2 (12V): -0.007557
CHAN A3 (5V): -0.002198
CHAN B0 (3.3V): 0.000014
CHAN B1 (3.15aV): -0.000064
CHAN B2 (3.15bV): -0.000093
CHAN B3 (GND): 0.002390
OPEN: 0.005206
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-09T18:56:59.123Z,1578596219.123 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:15.006620
2020-01-09T18:57:33.979Z,1578596253.979 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68

2020-01-09T18:57:33.979Z,1578596253.979 [Micromodem](INFO): Have incoming data
2020-01-09T18:57:33.984Z,1578596253.984 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-09T18:57:33.984Z,1578596253.984 [Micromodem](INFO): Deserialize. FrameCount:1
2020-01-09T18:57:33.985Z,1578596253.985 [Micromodem](INFO): Outer loop. Size:0
2020-01-09T18:57:33.985Z,1578596253.985 [Micromodem](CRITICAL): Packet size is not N*16+4: 0x0
2020-01-09T18:57:33.986Z,1578596253.986 [Micromodem](CRITICAL): Failed to parse uplink message:
2020-01-09T18:57:34.168Z,1578596254.168 [CBIT](INFO): Critical error at 20200109T185733
2020-01-09T18:57:34.387Z,1578596254.387 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109185733.326174,06,2564,34,0169,0150,07,01,01,00,03,1,012,001,0,3,1,0,150,26.3,8.00,00,-5.00,-01,-0.03,19,10000,4000*7F

2020-01-09T18:57:39.387Z,1578596259.387 [SBIT](FAULT): Rudder: EXPECTED:0.000000 ACTUAL:-0.015396
2020-01-09T18:57:39.387Z,1578596259.387 [SBIT](FAULT): Control surface position failure.
2020-01-09T18:57:39.818Z,1578596259.818 [SBIT](CRITICAL): SBIT FAILED
2020-01-09T18:57:39.857Z,1578596259.857 [CBIT](INFO): Critical error at 20200109T185739
2020-01-09T18:57:39.890Z,1578596259.890 [CommandLine](IMPORTANT): got command configSet list
2020-01-09T18:57:39.890Z,1578596259.890 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-09T18:57:39.891Z,1578596259.891 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter;

2020-01-09T18:57:39.891Z,1578596259.891 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;

2020-01-09T18:57:39.891Z,1578596259.891 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;

2020-01-09T18:57:39.891Z,1578596259.891 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-09T18:57:39.891Z,1578596259.891 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-09T18:57:39.891Z,1578596259.891 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-09T18:57:39.891Z,1578596259.891 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-09T18:57:39.891Z,1578596259.891 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-09T18:57:39.892Z,1578596259.892 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-09T18:57:39.892Z,1578596259.892 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-09T18:57:39.892Z,1578596259.892 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-09T18:57:39.892Z,1578596259.892 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-09T18:57:39.892Z,1578596259.892 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-09T18:57:39.892Z,1578596259.892 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-09T18:57:39.892Z,1578596259.892 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-09T18:57:39.892Z,1578596259.892 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-09T18:57:39.892Z,1578596259.892 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-09T18:57:39.893Z,1578596259.893 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-09T18:57:39.893Z,1578596259.893 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-09T18:57:39.893Z,1578596259.893 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-09T18:57:39.893Z,1578596259.893 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-09T18:57:39.893Z,1578596259.893 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-09T18:57:39.893Z,1578596259.893 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-09T18:57:39.893Z,1578596259.893 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-09T18:57:39.893Z,1578596259.893 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-09T18:57:39.894Z,1578596259.894 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-09T18:57:40.086Z,1578596260.086 [MissionManager](IMPORTANT): Started mission Startup
2020-01-09T18:57:40.087Z,1578596260.087 [Startup] Running Loop=1
2020-01-09T18:57:40.087Z,1578596260.087 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-09T18:57:40.087Z,1578596260.087 [Startup:A.GoToSurface] Running Loop=1
2020-01-09T18:57:40.087Z,1578596260.087 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-09T18:57:40.087Z,1578596260.087 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-09T18:57:40.088Z,1578596260.088 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-09T18:57:40.088Z,1578596260.088 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-09T18:57:40.093Z,1578596260.093 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-09T18:57:40.093Z,1578596260.093 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-09T18:57:40.095Z,1578596260.095 [Startup:StartupSatComms] Running Loop=1
2020-01-09T18:57:40.095Z,1578596260.095 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-09T18:57:40.095Z,1578596260.095 [Startup:StartupSatComms:A] Running Loop=1
2020-01-09T18:57:40.606Z,1578596260.606 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-09T18:57:41.333Z,1578596261.333 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T18:57:41.334Z,1578596261.334 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,
2020-01-09T18:57:47.324Z,1578596267.324 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T18:57:47.324Z,1578596267.324 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T18:58:21.677Z,1578596301.677 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T18:58:21.678Z,1578596301.678 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200109125.0, -0.1,   0.0,1448.9,  0
2020-01-09T18:58:40.281Z,1578596320.281 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-09T18:57:40.1Z
2020-01-09T18:58:40.281Z,1578596320.281 [Startup:StartupSatComms:A] Stopped
2020-01-09T18:58:40.281Z,1578596320.281 [Startup:StartupSatComms:B] Running Loop=1
2020-01-09T18:58:40.652Z,1578596320.652 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T18:58:40.652Z,1578596320.652 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T18:58:40.713Z,1578596320.713 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-09T18:58:41.890Z,1578596321.890 [Micromodem](INFO): Outgoing frame #1, 64 bytes: E47B165E0A0000005D00000400320000000000000000151D80030C5669C08C34EE7ACA27F34F64A5B41D6585CF6F52E76A21127CC1B7CA6917BD365F82BE52AB
2020-01-09T18:58:41.891Z,1578596321.891 [Micromodem](INFO): Outgoing frame #2, 5 bytes: 2A129D5F00
2020-01-09T18:58:41.891Z,1578596321.891 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-09T18:58:42.051Z,1578596322.051 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-09T18:58:42.407Z,1578596322.407 [Micromodem](INFO): Nmea in: $CADRQ,185843,1,12,0,64,1*77

2020-01-09T18:58:42.407Z,1578596322.407 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E47B165E0A0000005D00000400320000000000000000151D80030C5669C08C34EE7ACA27F34F64A5B41D6585CF6F52E76A21127CC1B7CA6917BD365F82BE52AB*4C

2020-01-09T18:58:42.807Z,1578596322.807 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T18:58:43.223Z,1578596323.223 [Micromodem](INFO): Nmea in: $CADRQ,185843,1,12,0,64,2*74

2020-01-09T18:58:43.223Z,1578596323.223 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,2A129D5F00*35

2020-01-09T18:58:43.615Z,1578596323.615 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,5*7C

2020-01-09T18:58:44.018Z,1578596324.018 [Micromodem](INFO): Nmea in: $CATXP,69*7D

2020-01-09T18:58:46.449Z,1578596326.449 [Micromodem](INFO): Nmea in: $CATXF,69*6B

2020-01-09T18:58:46.541Z,1578596326.541 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200109T010332/Courier0010.lzma
2020-01-09T18:58:47.662Z,1578596327.662 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,185844.513312,3,0,200,4000,10000,1,1,12,1,2,2,3,69*44

2020-01-09T18:58:48.065Z,1578596328.065 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T18:58:48.463Z,1578596328.463 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T18:58:48.464Z,1578596328.464 [Micromodem](INFO): Sent 61 bytes from file Logs/20200109T010332/Courier0010.lzma.parts
2020-01-09T18:58:48.464Z,1578596328.464 [Micromodem](INFO): Packets left to send: 0
2020-01-09T18:58:48.870Z,1578596328.870 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109185847.737028,06,668,28,0106,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,25.3,6.00,15,-3.00,-01,-0.07,24,10000,4000*4F

2020-01-09T18:58:49.144Z,1578596329.144 [DataOverHttps](FAULT): Server acknowledged file Logs/20200109T010332/Courier0010.lzma, notLogs/20200109T185556/Courier0000.lzma
2020-01-09T18:58:49.144Z,1578596329.144 [DataOverHttps](INFO): SBD MOMSN=12187951
2020-01-09T18:58:49.467Z,1578596329.467 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 3C77175E000001005D000004001C0200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-09T18:58:49.468Z,1578596329.468 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367224
2020-01-09T18:58:49.468Z,1578596329.468 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 8B55440F1E43126A7A9B00EEFF6877D12636C2A6FB38502FFA8AC09CF2ACFFB005B56A58A85CFA874F2C0118476D0AC76F15760FAD865C736BBABF94038E0997
2020-01-09T18:58:49.469Z,1578596329.469 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T18:58:50.483Z,1578596330.483 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T18:58:50.888Z,1578596330.888 [Micromodem](INFO): Nmea in: $CADRQ,185850,1,12,0,64,1*75

2020-01-09T18:58:50.889Z,1578596330.889 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3C77175E000001005D000004001C0200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*3B

2020-01-09T18:58:51.315Z,1578596331.315 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T18:58:51.695Z,1578596331.695 [Micromodem](INFO): Nmea in: $CADRQ,185852,1,12,0,64,2*74

2020-01-09T18:58:51.695Z,1578596331.695 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367224*3E

2020-01-09T18:58:52.103Z,1578596332.103 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T18:58:52.499Z,1578596332.499 [Micromodem](INFO): Nmea in: $CADRQ,185853,1,12,0,64,3*74

2020-01-09T18:58:52.499Z,1578596332.499 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,8B55440F1E43126A7A9B00EEFF6877D12636C2A6FB38502FFA8AC09CF2ACFFB005B56A58A85CFA874F2C0118476D0AC76F15760FAD865C736BBABF94038E0997*31

2020-01-09T18:58:52.903Z,1578596332.903 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T18:58:53.312Z,1578596333.312 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T18:58:56.947Z,1578596336.947 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T18:58:57.352Z,1578596337.352 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,185853.867428,3,0,200,4000,10000,1,1,12,1,3,3,3,192*77

2020-01-09T18:58:58.169Z,1578596338.169 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T18:58:58.559Z,1578596338.559 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T18:58:58.963Z,1578596338.963 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T18:58:58.964Z,1578596338.964 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T185556/Courier0000.lzma.parts
2020-01-09T18:58:58.964Z,1578596338.964 [Micromodem](INFO): Packets left to send: 1
2020-01-09T18:58:59.376Z,1578596339.376 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109185858.419784,06,633,28,0106,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,-0.4,6.00,17,-3.00,-01,-0.07,45,10000,4000*59

2020-01-09T18:58:59.876Z,1578596339.876 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 3C77175E00000000AAA6DBB2F8B5B45A9275DC14691CBED18B4E146FC42B5705588D06E31D714BFFDD187AD0480853FF5C9EB7F2132B70D85F0B75CCD58082A0
2020-01-09T18:58:59.876Z,1578596339.876 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 3DFA79968C301885EC99B9D5A5EFE90A139D2D054A01FB50978FE42490A8D372522F948156E0A769B6A74EFE63C990B1F993D1CF110938BBE7486680E50DC416
2020-01-09T18:58:59.877Z,1578596339.877 [Micromodem](INFO): Outgoing frame #3, 37 bytes: 482B7FA4F84B25886DE5B21BF67A1F38934BF84E255BDCF37230185F4CCCBD078BA51854E0
2020-01-09T18:58:59.877Z,1578596339.877 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T18:59:00.871Z,1578596340.871 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T18:59:01.278Z,1578596341.278 [Micromodem](INFO): Nmea in: $CADRQ,185901,1,12,0,64,1*70

2020-01-09T18:59:01.280Z,1578596341.280 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3C77175E00000000AAA6DBB2F8B5B45A9275DC14691CBED18B4E146FC42B5705588D06E31D714BFFDD187AD0480853FF5C9EB7F2132B70D85F0B75CCD58082A0*33

2020-01-09T18:59:01.679Z,1578596341.679 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T18:59:02.096Z,1578596342.096 [Micromodem](INFO): Nmea in: $CADRQ,185902,1,12,0,64,2*70

2020-01-09T18:59:02.096Z,1578596342.096 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3DFA79968C301885EC99B9D5A5EFE90A139D2D054A01FB50978FE42490A8D372522F948156E0A769B6A74EFE63C990B1F993D1CF110938BBE7486680E50DC416*44

2020-01-09T18:59:02.487Z,1578596342.487 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T18:59:02.887Z,1578596342.887 [Micromodem](INFO): Nmea in: $CADRQ,185903,1,12,0,64,3*70

2020-01-09T18:59:02.887Z,1578596342.887 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,482B7FA4F84B25886DE5B21BF67A1F38934BF84E255BDCF37230185F4CCCBD078BA51854E0*4F

2020-01-09T18:59:03.315Z,1578596343.315 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,37*4D

2020-01-09T18:59:03.698Z,1578596343.698 [Micromodem](INFO): Nmea in: $CATXP,165*40

2020-01-09T18:59:06.265Z,1578596346.265 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20200109T185556/Courier0000.lzma
2020-01-09T18:59:06.966Z,1578596346.966 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-09T18:59:06.966Z,1578596346.966 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T18:59:06.976Z,1578596346.976 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T18:59:07.339Z,1578596347.339 [Micromodem](INFO): Nmea in: $CATXF,165*56

2020-01-09T18:59:07.397Z,1578596347.397 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T18:59:07.397Z,1578596347.397 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-09T18:59:07.744Z,1578596347.744 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,185904.223603,3,0,200,4000,10000,1,1,12,1,3,3,3,165*7D

2020-01-09T18:59:07.746Z,1578596347.746 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T18:59:07.747Z,1578596347.747 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T18:59:08.270Z,1578596348.270 [DataOverHttps](INFO): Moved sent file to Logs/20200109T185556/Courier0000.lzma.bak
2020-01-09T18:59:08.270Z,1578596348.270 [DataOverHttps](INFO): SBD MOMSN=12187953
2020-01-09T18:59:08.551Z,1578596348.551 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T18:59:08.951Z,1578596348.951 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T18:59:09.359Z,1578596349.359 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T18:59:09.360Z,1578596349.360 [Micromodem](INFO): Sent 157 bytes from file Logs/20200109T185556/Courier0000.lzma.parts
2020-01-09T18:59:09.360Z,1578596349.360 [Micromodem](INFO): Packets left to send: 0
2020-01-09T18:59:09.778Z,1578596349.778 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109185908.749199,06,658,28,0111,0150,59,02,02,00,02,1,012,001,0,4,1,0,150,25.5,6.00,15,-3.00,-01,-0.06,23,10000,4000*43

2020-01-09T18:59:10.778Z,1578596350.778 [Startup:StartupSatComms:B] Stopped
2020-01-09T18:59:10.778Z,1578596350.778 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-09T18:59:10.778Z,1578596350.778 [Startup:StartupSatComms] Stopped
2020-01-09T18:59:10.778Z,1578596350.778 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-09T18:59:10.779Z,1578596350.779 [Startup](INFO): Completed Startup
2020-01-09T18:59:10.779Z,1578596350.779 [MissionManager](INFO): Startup is completed.
2020-01-09T18:59:10.779Z,1578596350.779 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-09T18:59:10.779Z,1578596350.779 [Startup] Stopped
2020-01-09T18:59:10.779Z,1578596350.779 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-09T18:59:10.779Z,1578596350.779 [Startup:A.GoToSurface] Stopped
2020-01-09T18:59:10.779Z,1578596350.779 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-09T18:59:10.907Z,1578596350.907 [MissionManager](IMPORTANT): Started mission Default
2020-01-09T18:59:10.907Z,1578596350.907 [Default] Running Loop=1
2020-01-09T18:59:10.907Z,1578596350.907 [Default](DEBUG): Aggregate::initialize Default
2020-01-09T18:59:10.907Z,1578596350.907 [Default:B.GoToSurface] Running Loop=1
2020-01-09T18:59:10.907Z,1578596350.907 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-09T18:59:10.907Z,1578596350.907 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-09T18:59:10.908Z,1578596350.908 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-09T18:59:10.908Z,1578596350.908 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-09T18:59:10.908Z,1578596350.908 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-09T18:59:10.913Z,1578596350.913 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-09T18:59:10.913Z,1578596350.913 [Default:A.Wait] Running Loop=1
2020-01-09T18:59:10.913Z,1578596350.913 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-09T18:59:24.171Z,1578596364.171 [Default:A.Wait](INFO): Done Waiting.
2020-01-09T18:59:24.171Z,1578596364.171 [Default:A.Wait] Stopped
2020-01-09T18:59:24.171Z,1578596364.171 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T18:59:24.593Z,1578596364.593 [Default:CheckIn] Running Loop=1
2020-01-09T18:59:24.593Z,1578596364.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T18:59:24.593Z,1578596364.593 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T18:59:24.975Z,1578596364.975 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-09T18:59:27.138Z,1578596367.138 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20200109T000230/Express0020.lzma
2020-01-09T18:59:29.142Z,1578596369.142 [DataOverHttps](INFO): Moved sent file to Logs/20200109T000230/Express0020.lzma.bak
2020-01-09T18:59:29.142Z,1578596369.142 [DataOverHttps](INFO): SBD MOMSN=12187965
2020-01-09T18:59:35.865Z,1578596375.865 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T18:59:35.865Z,1578596375.865 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T19:01:14.028Z,1578596474.028 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T19:01:14.029Z,1578596474.029 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T19:01:44.328Z,1578596504.328 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T19:01:44.343Z,1578596504.343 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T19:01:47.959Z,1578596507.959 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T19:02:08.213Z,1578596528.213 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-09T19:02:08.213Z,1578596528.213 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T19:02:08.223Z,1578596528.223 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T19:02:08.642Z,1578596528.642 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T19:02:08.642Z,1578596528.642 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-09T19:03:00.733Z,1578596580.733 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T19:03:00.733Z,1578596580.733 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3268,V
2020-01-09T19:03:35.046Z,1578596615.046 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-09T19:03:35.053Z,1578596615.053 [BPC1](INFO): Received data from all battery sticks.
2020-01-09T19:03:40.335Z,1578596620.335 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-09T19:03:40.335Z,1578596620.335 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0    +0.00,   0.00,439.99
2020-01-09T19:04:17.486Z,1578596657.486 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T19:04:17.486Z,1578596657.486 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-09T19:04:24.755Z,1578596664.755 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T18:59:24.6Z
2020-01-09T19:04:24.755Z,1578596664.755 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T19:04:24.755Z,1578596664.755 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T19:04:25.159Z,1578596665.159 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-09T19:04:26.467Z,1578596666.467 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 3C77175E040001005D00000400600100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-09T19:04:26.467Z,1578596666.467 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F
2020-01-09T19:04:26.468Z,1578596666.468 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D4C84B3E8844B440225002E089306D450B
2020-01-09T19:04:26.468Z,1578596666.468 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T19:04:26.631Z,1578596666.631 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T19:04:26.983Z,1578596666.983 [Micromodem](INFO): Nmea in: $CADRQ,190427,1,12,0,64,1*7D

2020-01-09T19:04:26.984Z,1578596666.984 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3C77175E040001005D00000400600100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*4C

2020-01-09T19:04:27.403Z,1578596667.403 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:04:27.792Z,1578596667.792 [Micromodem](INFO): Nmea in: $CADRQ,190428,1,12,0,64,2*71

2020-01-09T19:04:27.792Z,1578596667.792 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*3D

2020-01-09T19:04:28.199Z,1578596668.199 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:04:28.611Z,1578596668.611 [Micromodem](INFO): Nmea in: $CADRQ,190429,1,12,0,64,3*71

2020-01-09T19:04:28.611Z,1578596668.611 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D4C84B3E8844B440225002E089306D450B*44

2020-01-09T19:04:29.003Z,1578596669.003 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:04:29.406Z,1578596669.406 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T19:04:32.365Z,1578596672.365 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20200109T185556/Courier0004.lzma
2020-01-09T19:04:33.052Z,1578596673.052 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T19:04:33.452Z,1578596673.452 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,190429.979118,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7A

2020-01-09T19:04:34.321Z,1578596674.321 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T19:04:34.321Z,1578596674.321 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-

2020-01-09T19:04:34.370Z,1578596674.370 [DataOverHttps](INFO): Moved sent file to Logs/20200109T185556/Courier0004.lzma.bak
2020-01-09T19:04:34.371Z,1578596674.371 [DataOverHttps](INFO): SBD MOMSN=12187977
2020-01-09T19:04:34.663Z,1578596674.663 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T19:04:35.067Z,1578596675.067 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T19:04:35.467Z,1578596675.467 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T19:04:35.468Z,1578596675.468 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T185556/Courier0004.lzma.parts
2020-01-09T19:04:35.468Z,1578596675.468 [Micromodem](INFO): Packets left to send: 1
2020-01-09T19:04:35.874Z,1578596675.874 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109190434.535594,06,650,28,0094,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,13.7,8.00,18,-5.00,-01,-0.05,34,10000,4000*47

2020-01-09T19:04:37.315Z,1578596677.315 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T19:04:37.315Z,1578596677.315 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T19:04:37.315Z,1578596677.315 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T19:05:00.020Z,1578596700.020 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-09T19:05:09.310Z,1578596709.310 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-09T19:05:09.310Z,1578596709.310 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T19:05:09.320Z,1578596709.320 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T19:05:09.767Z,1578596709.767 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T19:05:09.767Z,1578596709.767 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-09T19:05:21.208Z,1578596721.208 [DataOverHttps](INFO): Sending 1462 bytes from file Logs/20200109T004706/Express0001.lzma
2020-01-09T19:05:23.210Z,1578596723.210 [DataOverHttps](INFO): Moved sent file to Logs/20200109T004706/Express0001.lzma.bak
2020-01-09T19:05:23.210Z,1578596723.210 [DataOverHttps](INFO): SBD MOMSN=12187979
2020-01-09T19:06:46.647Z,1578596806.647 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-09T19:06:46.648Z,1578596806.648 [NAL9602] Data Fault, FailCount= 1
2020-01-09T19:06:46.648Z,1578596806.648 [NAL9602](ERROR): Data Fault
2020-01-09T19:06:46.719Z,1578596806.719 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T19:06:47.047Z,1578596807.047 [NAL9602](INFO): Powering down
2020-01-09T19:06:47.911Z,1578596807.911 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T19:06:47.911Z,1578596807.911 [NAL9602] No Fault, FailCount= 1
2020-01-09T19:07:17.347Z,1578596837.347 [NAL9602](INFO): Powering up NAL9602
2020-01-09T19:07:28.263Z,1578596848.263 [NAL9602](INFO): NAL9602 initialized
2020-01-09T19:08:10.323Z,1578596890.323 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T19:08:10.323Z,1578596890.323 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-09T19:08:10.338Z,1578596890.338 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-01-09T19:08:10.338Z,1578596890.338 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T19:08:10.364Z,1578596890.364 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T19:08:10.731Z,1578596890.731 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T19:08:10.731Z,1578596890.731 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-01-09T19:09:37.596Z,1578596977.596 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T19:09:37.596Z,1578596977.596 [Default:CheckIn:C.Wait] Stopped
2020-01-09T19:09:37.596Z,1578596977.596 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T19:09:37.596Z,1578596977.596 [Default:CheckIn:D] Running Loop=1
2020-01-09T19:09:37.983Z,1578596977.983 [Default:CheckIn:D] Stopped
2020-01-09T19:09:37.983Z,1578596977.983 [Default:CheckIn:E] Running Loop=1
2020-01-09T19:09:38.387Z,1578596978.387 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.451265 min 
2020-01-09T19:09:38.387Z,1578596978.387 [Default:CheckIn:E] Stopped
2020-01-09T19:09:38.387Z,1578596978.387 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T19:09:38.387Z,1578596978.387 [Default:CheckIn] Stopped
2020-01-09T19:09:38.388Z,1578596978.388 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T19:09:38.388Z,1578596978.388 [Default:CheckIn](INFO): Running loop #2
2020-01-09T19:09:38.388Z,1578596978.388 [Default:CheckIn] Running Loop=2
2020-01-09T19:09:38.388Z,1578596978.388 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T19:09:38.388Z,1578596978.388 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T19:10:47.545Z,1578597047.545 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-09T19:10:47.545Z,1578597047.545 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-09T19:10:47.545Z,1578597047.545 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-09T19:10:47.545Z,1578597047.545 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-09T19:10:47.568Z,1578597047.568 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-09T19:10:47.913Z,1578597047.913 [RDI_Pathfinder](INFO): Powering down
2020-01-09T19:10:48.703Z,1578597048.703 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-09T19:10:48.703Z,1578597048.703 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-09T19:11:02.012Z,1578597062.012 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-09T19:11:07.689Z,1578597067.689 [CBIT](INFO): Clearing failed state for component DropWeight
2020-01-09T19:11:07.689Z,1578597067.689 [DropWeight] No Fault, FailCount= 1
2020-01-09T19:11:11.321Z,1578597071.321 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T19:11:11.321Z,1578597071.321 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3276,V
2020-01-09T19:11:11.327Z,1578597071.327 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-01-09T19:11:11.327Z,1578597071.327 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T19:11:11.337Z,1578597071.337 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T19:11:11.711Z,1578597071.711 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T19:11:11.711Z,1578597071.711 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-01-09T19:11:58.941Z,1578597118.941 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190703.00,A,3648.15735,N,12147.28549,W,1.438,327.41,090120,,,A*78
2020-01-09T19:11:58.944Z,1578597118.944 [NAL9602](INFO): GPS fix at 20200109T190703: (36.802622, -121.788091)
2020-01-09T19:11:58.993Z,1578597118.993 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T19:11:58.993Z,1578597118.993 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T19:12:00.231Z,1578597120.231 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 3C77175E070001005D00000400180300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567
2020-01-09T19:12:00.232Z,1578597120.232 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31
2020-01-09T19:12:00.232Z,1578597120.232 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3
2020-01-09T19:12:00.250Z,1578597120.250 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T19:12:00.446Z,1578597120.446 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T19:12:00.819Z,1578597120.819 [Micromodem](INFO): Nmea in: $CADRQ,191201,1,12,0,64,1*7E

2020-01-09T19:12:00.819Z,1578597120.819 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3C77175E070001005D00000400180300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567*3B

2020-01-09T19:12:01.223Z,1578597121.223 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:12:01.631Z,1578597121.631 [Micromodem](INFO): Nmea in: $CADRQ,191202,1,12,0,64,2*7E

2020-01-09T19:12:01.631Z,1578597121.631 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31*3C

2020-01-09T19:12:02.031Z,1578597122.031 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:12:02.435Z,1578597122.435 [Micromodem](INFO): Nmea in: $CADRQ,191202,1,12,0,64,3*7F

2020-01-09T19:12:02.435Z,1578597122.435 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3*39

2020-01-09T19:12:02.839Z,1578597122.839 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:12:03.251Z,1578597123.251 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T19:12:06.883Z,1578597126.883 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T19:12:07.284Z,1578597127.284 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,191203.797751,3,0,200,4000,10000,1,1,12,1,3,3,3,192*70

2020-01-09T19:12:08.495Z,1578597128.495 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T19:12:08.899Z,1578597128.899 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T19:12:09.301Z,1578597129.301 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T19:12:09.302Z,1578597129.302 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T185556/Courier0007.lzma.parts
2020-01-09T19:12:09.302Z,1578597129.302 [Micromodem](INFO): Packets left to send: 1
2020-01-09T19:12:09.714Z,1578597129.714 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109191208.351801,06,242,23,0095,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,24.5,6.00,18,-3.00,-01,-0.03,28,10000,4000*4F

2020-01-09T19:12:09.969Z,1578597129.969 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20200109T185556/Courier0007.lzma
2020-01-09T19:12:11.449Z,1578597131.449 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 3C77175E07000000F62E8CD8314AF937CD3E02E67547F74555A227C669D794C67A517C3F77AFF2F88E02301EBBD22D5BC5EFDA0EAD998FDBBF9F2C403044923B
2020-01-09T19:12:11.449Z,1578597131.449 [Micromodem](INFO): Outgoing frame #2, 29 bytes: 25F78405ADBC7E3B8D9BC94AB67DA581D736D0B7D4B2706AB3DD3E0C00
2020-01-09T19:12:11.450Z,1578597131.450 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-09T19:12:11.974Z,1578597131.974 [DataOverHttps](INFO): Moved sent file to Logs/20200109T185556/Courier0007.lzma.bak
2020-01-09T19:12:11.974Z,1578597131.974 [DataOverHttps](INFO): SBD MOMSN=12188024
2020-01-09T19:12:12.383Z,1578597132.383 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-09T19:12:12.791Z,1578597132.791 [Micromodem](INFO): Nmea in: $CADRQ,191212,1,12,0,64,1*7C

2020-01-09T19:12:12.791Z,1578597132.791 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3C77175E07000000F62E8CD8314AF937CD3E02E67547F74555A227C669D794C67A517C3F77AFF2F88E02301EBBD22D5BC5EFDA0EAD998FDBBF9F2C403044923B*3E

2020-01-09T19:12:13.191Z,1578597133.191 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:12:13.599Z,1578597133.599 [Micromodem](INFO): Nmea in: $CADRQ,191214,1,12,0,64,2*79

2020-01-09T19:12:13.599Z,1578597133.599 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,25F78405ADBC7E3B8D9BC94AB67DA581D736D0B7D4B2706AB3DD3E0C00*32

2020-01-09T19:12:13.998Z,1578597133.998 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,29*42

2020-01-09T19:12:14.403Z,1578597134.403 [Micromodem](INFO): Nmea in: $CATXP,93*78

2020-01-09T19:12:16.826Z,1578597136.826 [Micromodem](INFO): Nmea in: $CATXF,93*6E

2020-01-09T19:12:17.231Z,1578597137.231 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,191214.915728,3,0,200,4000,10000,1,1,12,1,2,2,3,93*4C

2020-01-09T19:12:18.039Z,1578597138.039 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T19:12:18.447Z,1578597138.447 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T19:12:18.447Z,1578597138.447 [Micromodem](INFO): Sent 85 bytes from file Logs/20200109T185556/Courier0007.lzma.parts
2020-01-09T19:12:18.448Z,1578597138.448 [Micromodem](INFO): Packets left to send: 0
2020-01-09T19:12:18.850Z,1578597138.850 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109191218.167108,06,621,27,0113,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,23.8,8.00,19,-5.00,-01,-0.05,24,10000,4000*40

2020-01-09T19:12:20.243Z,1578597140.243 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T19:12:20.243Z,1578597140.243 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T19:12:20.243Z,1578597140.243 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T19:12:31.235Z,1578597151.235 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T19:12:32.716Z,1578597152.716 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20200109T004706/Express0005.lzma
2020-01-09T19:12:34.726Z,1578597154.726 [DataOverHttps](INFO): Moved sent file to Logs/20200109T004706/Express0005.lzma.bak
2020-01-09T19:12:34.727Z,1578597154.727 [DataOverHttps](INFO): SBD MOMSN=12188027
2020-01-09T19:16:39.463Z,1578597399.463 [CommandLine](IMPORTANT): got command restart application
2020-01-09T19:16:40.469Z,1578597400.469 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T19:16:40.469Z,1578597400.469 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:40.537Z,1578597400.537 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-09T19:16:40.537Z,1578597400.537 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:40.538Z,1578597400.538 [CommandLine](INFO): Join timeout helper Thread ID is 5890
2020-01-09T19:16:40.549Z,1578597400.549 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-09T19:16:40.549Z,1578597400.549 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:40.550Z,1578597400.550 [NavChartDb](INFO): Join timeout helper Thread ID is 5891
2020-01-09T19:16:40.845Z,1578597400.845 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T19:16:40.845Z,1578597400.845 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:40.865Z,1578597400.865 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-09T19:16:40.865Z,1578597400.865 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:40.865Z,1578597400.865 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 5892
2020-01-09T19:16:41.061Z,1578597401.061 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T19:16:41.061Z,1578597401.061 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-09T19:16:41.062Z,1578597401.062 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:41.069Z,1578597401.069 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-09T19:16:41.069Z,1578597401.069 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:41.069Z,1578597401.069 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5893
2020-01-09T19:16:41.137Z,1578597401.137 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T19:16:41.137Z,1578597401.137 [CTD_NeilBrown](INFO): Powering down
2020-01-09T19:16:41.149Z,1578597401.149 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:41.149Z,1578597401.149 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-09T19:16:41.150Z,1578597401.150 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:41.150Z,1578597401.150 [Radio_Surface](INFO): Join timeout helper Thread ID is 5894
2020-01-09T19:16:41.473Z,1578597401.473 [Radio_Surface](INFO): Powering down
2020-01-09T19:16:41.474Z,1578597401.474 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T19:16:41.474Z,1578597401.474 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:41.478Z,1578597401.478 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-09T19:16:41.478Z,1578597401.478 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:41.478Z,1578597401.478 [DataOverHttps](INFO): Join timeout helper Thread ID is 5895
2020-01-09T19:16:42.173Z,1578597402.173 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T19:16:42.176Z,1578597402.176 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:42.190Z,1578597402.190 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-09T19:16:42.190Z,1578597402.190 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:42.190Z,1578597402.190 [logger](INFO): Join timeout helper Thread ID is 5896
2020-01-09T19:16:42.191Z,1578597402.191 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T19:16:42.191Z,1578597402.191 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:42.192Z,1578597402.192 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-09T19:16:42.192Z,1578597402.192 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:42.192Z,1578597402.192 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-09T19:16:42.192Z,1578597402.192 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:42.193Z,1578597402.193 [controlThread](INFO): Join timeout helper Thread ID is 5897
2020-01-09T19:16:42.501Z,1578597402.501 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T19:16:42.501Z,1578597402.501 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-09T19:16:42.502Z,1578597402.502 [AHRS_M2](INFO): Powering down
2020-01-09T19:16:42.573Z,1578597402.573 [DDM](INFO): Powering down
2020-01-09T19:16:42.666Z,1578597402.666 [DUSBL_Hydroid](INFO): Powering down
2020-01-09T19:16:42.757Z,1578597402.757 [Micromodem](INFO): Powering down
2020-01-09T19:16:42.853Z,1578597402.853 [NAL9602](INFO): Powering down
2020-01-09T19:16:42.925Z,1578597402.925 [RDI_Pathfinder](INFO): Powering down
2020-01-09T19:16:42.926Z,1578597402.926 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-09T19:16:42.927Z,1578597402.927 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-09T19:16:42.928Z,1578597402.928 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-09T19:16:42.928Z,1578597402.928 [MissionManager](INFO): Uninitializing Mission Default
2020-01-09T19:16:42.928Z,1578597402.928 [Default] Stopped
2020-01-09T19:16:42.928Z,1578597402.928 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-09T19:16:42.929Z,1578597402.929 [Default:B.GoToSurface] Stopped
2020-01-09T19:16:42.929Z,1578597402.929 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-09T19:16:42.929Z,1578597402.929 [Default:CheckIn] Stopped
2020-01-09T19:16:42.929Z,1578597402.929 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T19:16:42.929Z,1578597402.929 [Default:CheckIn:C.Wait] Stopped
2020-01-09T19:16:42.929Z,1578597402.929 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T19:16:42.931Z,1578597402.931 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-09T19:16:42.932Z,1578597402.932 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-09T19:16:42.932Z,1578597402.932 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-09T19:16:42.932Z,1578597402.932 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-09T19:16:42.932Z,1578597402.932 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-09T19:16:42.933Z,1578597402.933 [BuoyancyServo](INFO): Powering down
2020-01-09T19:16:42.945Z,1578597402.945 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-09T19:16:42.945Z,1578597402.945 [ElevatorServo](INFO): Powering down
2020-01-09T19:16:42.946Z,1578597402.946 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-09T19:16:42.946Z,1578597402.946 [MassServo](INFO): Powering down
2020-01-09T19:16:42.947Z,1578597402.947 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-09T19:16:42.947Z,1578597402.947 [RudderServo](INFO): Powering down
2020-01-09T19:16:42.947Z,1578597402.947 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-09T19:16:42.948Z,1578597402.948 [ThrusterServo](INFO): Powering down
2020-01-09T19:16:42.948Z,1578597402.948 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-09T19:16:42.949Z,1578597402.949 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-09T19:16:42.949Z,1578597402.949 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-09T19:16:42.949Z,1578597402.949 [CBIT](DEBUG): Powering off loads.
2020-01-09T19:16:42.961Z,1578597402.961 [CBIT](DEBUG): Disabling WDT.
2020-01-09T19:16:42.973Z,1578597402.973 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-09T19:16:42.974Z,1578597402.974 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:43.032Z,1578597403.032 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:43.043Z,1578597403.043 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:43.090Z,1578597403.090 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:43.093Z,1578597403.093 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:43.152Z,1578597403.152 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T19:16:43.226Z,1578597403.226 [logger ThreadHandler](INFO): Thread cancelled.