2020-01-10T02:37:53.569Z,1578623873.569 [Supervisor](DEBUG): Initializing supervisor.
2020-01-10T02:37:53.572Z,1578623873.572 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-10T02:37:53.573Z,1578623873.573 [SyncHandler](INFO): Protected caller Thread ID is 9460
2020-01-10T02:37:53.574Z,1578623873.574 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-10T02:37:53.575Z,1578623873.575 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-10T02:37:53.575Z,1578623873.575 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9461
2020-01-10T02:37:53.578Z,1578623873.578 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-10T02:37:53.590Z,1578623873.590 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-10T02:37:53.591Z,1578623873.591 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-10T02:37:53.591Z,1578623873.591 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9462
2020-01-10T02:37:53.592Z,1578623873.592 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-10T02:37:53.593Z,1578623873.593 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-10T02:37:53.593Z,1578623873.593 [logger ThreadHandler](INFO): Protected caller Thread ID is 9463
2020-01-10T02:37:53.595Z,1578623873.595 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-10T02:37:53.595Z,1578623873.595 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-10T02:37:53.597Z,1578623873.597 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-10T02:37:54.015Z,1578623874.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-10T02:37:54.016Z,1578623874.016 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-10T02:37:54.112Z,1578623874.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-10T02:37:54.112Z,1578623874.112 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-10T02:37:54.424Z,1578623874.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-10T02:37:54.425Z,1578623874.425 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-10T02:37:54.560Z,1578623874.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-10T02:37:54.560Z,1578623874.560 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-10T02:37:54.747Z,1578623874.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-10T02:37:54.747Z,1578623874.747 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-10T02:37:55.182Z,1578623875.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-10T02:37:55.182Z,1578623875.182 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-10T02:37:55.384Z,1578623875.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-10T02:37:55.384Z,1578623875.384 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-10T02:37:55.524Z,1578623875.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-10T02:37:55.525Z,1578623875.525 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-10T02:37:55.705Z,1578623875.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-10T02:37:55.705Z,1578623875.705 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-10T02:37:55.799Z,1578623875.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-10T02:37:55.799Z,1578623875.799 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-10T02:37:56.090Z,1578623876.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-10T02:37:56.090Z,1578623876.090 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-10T02:37:56.169Z,1578623876.169 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-10T02:37:56.269Z,1578623876.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-10T02:37:56.270Z,1578623876.270 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-10T02:37:56.894Z,1578623876.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-10T02:37:56.895Z,1578623876.895 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-10T02:37:57.296Z,1578623877.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-10T02:37:57.298Z,1578623877.298 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-10T02:37:57.299Z,1578623877.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-10T02:37:57.496Z,1578623877.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-10T02:37:57.593Z,1578623877.593 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-10T02:37:57.688Z,1578623877.688 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-10T02:37:57.989Z,1578623877.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-10T02:37:57.989Z,1578623877.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-10T02:37:58.224Z,1578623878.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-10T02:37:58.409Z,1578623878.409 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-10T02:37:58.637Z,1578623878.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-10T02:37:58.772Z,1578623878.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-10T02:37:58.981Z,1578623878.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-10T02:37:59.354Z,1578623879.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-10T02:37:59.480Z,1578623879.480 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-10T02:37:59.480Z,1578623879.480 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-10T02:37:59.503Z,1578623879.503 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-10T02:37:59.820Z,1578623879.820 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-10T02:37:59.825Z,1578623879.825 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-10T02:37:59.827Z,1578623879.827 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-10T02:37:59.832Z,1578623879.832 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-10T02:37:59.833Z,1578623879.833 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-10T02:37:59.838Z,1578623879.838 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-10T02:37:59.839Z,1578623879.839 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-10T02:37:59.844Z,1578623879.844 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-10T02:37:59.915Z,1578623879.915 [AHRS_M2] Loaded
2020-01-10T02:37:59.916Z,1578623879.916 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-10T02:37:59.995Z,1578623879.995 [DataOverHttps] Loaded
2020-01-10T02:37:59.995Z,1578623879.995 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-10T02:37:59.997Z,1578623879.997 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-01-10T02:37:59.997Z,1578623879.997 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9542
2020-01-10T02:38:00.021Z,1578623880.021 [DDM] Loaded
2020-01-10T02:38:00.021Z,1578623880.021 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-10T02:38:00.034Z,1578623880.034 [Depth_Keller] Loaded
2020-01-10T02:38:00.034Z,1578623880.034 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-10T02:38:00.039Z,1578623880.039 [DropWeight] Loaded
2020-01-10T02:38:00.039Z,1578623880.039 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-10T02:38:00.101Z,1578623880.101 [DUSBL_Hydroid] Loaded
2020-01-10T02:38:00.101Z,1578623880.101 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-10T02:38:00.147Z,1578623880.147 [Micromodem] Loaded
2020-01-10T02:38:00.148Z,1578623880.148 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-10T02:38:00.244Z,1578623880.244 [NAL9602] Loaded
2020-01-10T02:38:00.245Z,1578623880.245 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-10T02:38:00.281Z,1578623880.281 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-10T02:38:00.282Z,1578623880.282 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-10T02:38:00.283Z,1578623880.283 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-10T02:38:00.283Z,1578623880.283 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-10T02:38:00.284Z,1578623880.284 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-10T02:38:00.284Z,1578623880.284 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-10T02:38:00.285Z,1578623880.285 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-10T02:38:00.285Z,1578623880.285 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-10T02:38:00.285Z,1578623880.285 [Onboard] Loaded
2020-01-10T02:38:00.286Z,1578623880.286 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-10T02:38:00.291Z,1578623880.291 [PowerOnly] Loaded
2020-01-10T02:38:00.292Z,1578623880.292 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-10T02:38:00.298Z,1578623880.298 [Radio_Surface] Loaded
2020-01-10T02:38:00.298Z,1578623880.298 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-10T02:38:00.299Z,1578623880.299 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-01-10T02:38:00.300Z,1578623880.300 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9543
2020-01-10T02:38:00.343Z,1578623880.343 [RDI_Pathfinder] Loaded
2020-01-10T02:38:00.343Z,1578623880.343 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-10T02:38:01.853Z,1578623881.853 [BPC1] Loaded
2020-01-10T02:38:01.853Z,1578623881.853 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-10T02:38:01.853Z,1578623881.853 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-10T02:38:01.854Z,1578623881.854 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-10T02:38:01.867Z,1578623881.867 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-10T02:38:01.868Z,1578623881.868 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-10T02:38:01.971Z,1578623881.971 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-10T02:38:01.972Z,1578623881.972 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-10T02:38:01.992Z,1578623881.992 [NavChart] Loaded
2020-01-10T02:38:01.992Z,1578623881.992 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-10T02:38:01.996Z,1578623881.996 [UniversalFixResidualReporter] Loaded
2020-01-10T02:38:01.996Z,1578623881.996 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-10T02:38:01.997Z,1578623881.997 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-10T02:38:01.997Z,1578623881.997 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-10T02:38:02.103Z,1578623882.103 [BuoyancyServo] Loaded
2020-01-10T02:38:02.103Z,1578623882.103 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-10T02:38:02.118Z,1578623882.118 [ElevatorServo] Loaded
2020-01-10T02:38:02.118Z,1578623882.118 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-10T02:38:02.133Z,1578623882.133 [MassServo] Loaded
2020-01-10T02:38:02.133Z,1578623882.133 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-10T02:38:02.148Z,1578623882.148 [RudderServo] Loaded
2020-01-10T02:38:02.148Z,1578623882.148 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-10T02:38:02.162Z,1578623882.162 [ThrusterServo] Loaded
2020-01-10T02:38:02.163Z,1578623882.163 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-10T02:38:02.163Z,1578623882.163 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-10T02:38:02.164Z,1578623882.164 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-10T02:38:02.418Z,1578623882.418 [CTD_NeilBrown] Loaded
2020-01-10T02:38:02.418Z,1578623882.418 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-10T02:38:02.419Z,1578623882.419 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0
2020-01-10T02:38:02.419Z,1578623882.419 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 9544
2020-01-10T02:38:02.434Z,1578623882.434 [PAR_Licor] Loaded
2020-01-10T02:38:02.434Z,1578623882.434 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-10T02:38:02.477Z,1578623882.477 [WetLabsSeaOWL_UV_A] Loaded
2020-01-10T02:38:02.478Z,1578623882.478 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-10T02:38:02.479Z,1578623882.479 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0
2020-01-10T02:38:02.479Z,1578623882.479 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 9545
2020-01-10T02:38:02.480Z,1578623882.480 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-10T02:38:02.480Z,1578623882.480 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-10T02:38:02.786Z,1578623882.786 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-10T02:38:02.786Z,1578623882.786 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-10T02:38:02.826Z,1578623882.826 [DepthRateCalculator] Loaded
2020-01-10T02:38:02.826Z,1578623882.826 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-10T02:38:02.832Z,1578623882.832 [PitchRateCalculator] Loaded
2020-01-10T02:38:02.832Z,1578623882.832 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-10T02:38:02.844Z,1578623882.844 [SpeedCalculator] Loaded
2020-01-10T02:38:02.844Z,1578623882.844 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-10T02:38:02.865Z,1578623882.865 [TempGradientCalculator] Loaded
2020-01-10T02:38:02.865Z,1578623882.865 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-10T02:38:02.871Z,1578623882.871 [YawRateCalculator] Loaded
2020-01-10T02:38:02.871Z,1578623882.871 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-10T02:38:02.911Z,1578623882.911 [ElevatorOffsetCalculator] Loaded
2020-01-10T02:38:02.911Z,1578623882.911 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-10T02:38:02.912Z,1578623882.912 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-10T02:38:02.912Z,1578623882.912 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-10T02:38:03.046Z,1578623883.046 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-10T02:38:03.068Z,1578623883.068 [SBIT] Loaded
2020-01-10T02:38:03.068Z,1578623883.068 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-10T02:38:03.069Z,1578623883.069 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-10T02:38:03.081Z,1578623883.081 [IBIT] Loaded
2020-01-10T02:38:03.082Z,1578623883.082 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-10T02:38:03.085Z,1578623883.085 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-10T02:38:03.223Z,1578623883.223 [CBIT] Loaded
2020-01-10T02:38:03.223Z,1578623883.223 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-10T02:38:03.224Z,1578623883.224 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-10T02:38:03.224Z,1578623883.224 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-10T02:38:03.507Z,1578623883.507 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-10T02:38:03.508Z,1578623883.508 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-10T02:38:03.653Z,1578623883.653 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-10T02:38:03.653Z,1578623883.653 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-10T02:38:03.701Z,1578623883.701 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-10T02:38:03.783Z,1578623883.783 [VerticalControl] Loaded
2020-01-10T02:38:03.783Z,1578623883.783 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-10T02:38:03.784Z,1578623883.784 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-10T02:38:03.853Z,1578623883.853 [HorizontalControl] Loaded
2020-01-10T02:38:03.854Z,1578623883.854 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-10T02:38:03.854Z,1578623883.854 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-10T02:38:03.856Z,1578623883.856 [SpeedControl] Loaded
2020-01-10T02:38:03.856Z,1578623883.856 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-10T02:38:03.857Z,1578623883.857 [LoopControl](DEBUG): Construct LoopControl.
2020-01-10T02:38:03.857Z,1578623883.857 [LoopControl] Loaded
2020-01-10T02:38:03.858Z,1578623883.858 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-10T02:38:03.858Z,1578623883.858 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-10T02:38:03.859Z,1578623883.859 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-10T02:38:03.891Z,1578623883.891 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-10T02:38:03.895Z,1578623883.895 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-10T02:38:03.895Z,1578623883.895 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-10T02:38:03.902Z,1578623883.902 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-10T02:38:03.903Z,1578623883.903 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0
2020-01-10T02:38:03.903Z,1578623883.903 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9546
2020-01-10T02:38:03.908Z,1578623883.908 [Supervisor](INFO): Main Thread ID is 8534
2020-01-10T02:38:03.908Z,1578623883.908 [Supervisor](DEBUG): Running supervisor.
2020-01-10T02:38:03.909Z,1578623883.909 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9547
2020-01-10T02:38:03.911Z,1578623883.911 [controlThread ThreadHandler](INFO): Handler Thread ID is 9548
2020-01-10T02:38:03.911Z,1578623883.911 [controlThread](DEBUG): Initializing ControlThread
2020-01-10T02:38:03.921Z,1578623883.921 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-10T02:38:03.921Z,1578623883.921 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-10T02:38:03.925Z,1578623883.925 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-10T02:38:03.925Z,1578623883.925 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-10T02:38:03.926Z,1578623883.926 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-10T02:38:03.926Z,1578623883.926 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-10T02:38:03.926Z,1578623883.926 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-10T02:38:03.927Z,1578623883.927 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-10T02:38:03.927Z,1578623883.927 [SBIT](INFO): Initialize SBIT Component.
2020-01-10T02:38:03.928Z,1578623883.928 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-10T02:38:03.928Z,1578623883.928 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-10T02:38:03.928Z,1578623883.928 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-10T02:38:03.930Z,1578623883.930 [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-10T02:38:03.931Z,1578623883.931 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-10T02:38:03.931Z,1578623883.931 [IBIT](INFO): Initialize IBIT Component.
2020-01-10T02:38:03.932Z,1578623883.932 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-10T02:38:03.933Z,1578623883.933 [logger ThreadHandler](INFO): Handler Thread ID is 9549
2020-01-10T02:38:03.945Z,1578623883.945 [CBIT](DEBUG): Initialized mux pins.
2020-01-10T02:38:03.945Z,1578623883.945 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-10T02:38:03.953Z,1578623883.953 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9550
2020-01-10T02:38:03.954Z,1578623883.954 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-10T02:38:03.965Z,1578623883.965 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9551
2020-01-10T02:38:03.969Z,1578623883.969 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-10T02:38:03.969Z,1578623883.969 [CBIT](DEBUG): Initializing heartbeat.
2020-01-10T02:38:03.977Z,1578623883.977 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9552
2020-01-10T02:38:03.978Z,1578623883.978 [CTD_NeilBrown](INFO): Powering down
2020-01-10T02:38:04.005Z,1578623884.005 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 9553
2020-01-10T02:38:04.006Z,1578623884.006 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T02:38:04.033Z,1578623884.033 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9554
2020-01-10T02:38:04.036Z,1578623884.036 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-10T02:38:04.036Z,1578623884.036 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-10T02:38:04.036Z,1578623884.036 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-10T02:38:04.037Z,1578623884.037 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-10T02:38:04.037Z,1578623884.037 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-10T02:38:04.037Z,1578623884.037 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-10T02:38:04.037Z,1578623884.037 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-10T02:38:04.037Z,1578623884.037 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-10T02:38:04.038Z,1578623884.038 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-10T02:38:04.038Z,1578623884.038 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-10T02:38:04.038Z,1578623884.038 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-10T02:38:04.038Z,1578623884.038 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-10T02:38:04.038Z,1578623884.038 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-10T02:38:04.039Z,1578623884.039 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-10T02:38:04.039Z,1578623884.039 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-10T02:38:04.039Z,1578623884.039 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-10T02:38:04.041Z,1578623884.041 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-10T02:38:04.041Z,1578623884.041 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-10T02:38:04.077Z,1578623884.077 [CBIT](DEBUG): Backplane powered.
2020-01-10T02:38:04.077Z,1578623884.077 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-10T02:38:04.079Z,1578623884.079 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-10T02:38:04.079Z,1578623884.079 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-10T02:38:04.080Z,1578623884.080 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-10T02:38:04.081Z,1578623884.081 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-10T02:38:04.091Z,1578623884.091 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T02:38:04.110Z,1578623884.110 [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-10T02:38:04.110Z,1578623884.110 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-10T02:38:04.184Z,1578623884.184 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-10T02:38:04.186Z,1578623884.186 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-10T02:38:04.187Z,1578623884.187 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T02:38:04.219Z,1578623884.219 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-10T02:38:04.242Z,1578623884.242 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-10T02:38:04.247Z,1578623884.247 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-10T02:38:04.275Z,1578623884.275 [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-10T02:38:04.280Z,1578623884.280 [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-10T02:38:04.289Z,1578623884.289 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-10T02:38:04.331Z,1578623884.331 [DDM](INFO): Powering up
2020-01-10T02:38:04.331Z,1578623884.331 [DDM](DEBUG): Initializing DDM.
2020-01-10T02:38:04.369Z,1578623884.369 [Radio_Surface](INFO): Powering up
2020-01-10T02:38:04.439Z,1578623884.439 [DUSBL_Hydroid](INFO): Powering up
2020-01-10T02:38:04.439Z,1578623884.439 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-10T02:38:04.671Z,1578623884.671 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:38:04.677Z,1578623884.677 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-10T02:38:04.678Z,1578623884.678 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:38:04.685Z,1578623884.685 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-10T02:38:04.686Z,1578623884.686 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:38:04.693Z,1578623884.693 [MassServo](DEBUG): Initializing MassServo.
2020-01-10T02:38:04.694Z,1578623884.694 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:38:04.701Z,1578623884.701 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T02:38:04.702Z,1578623884.702 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:38:04.709Z,1578623884.709 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-10T02:38:04.771Z,1578623884.771 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-10T02:38:04.772Z,1578623884.772 [DropWeight] Hardware Fault, FailCount= 1
2020-01-10T02:38:04.772Z,1578623884.772 [DropWeight](ERROR): Hardware Fault
2020-01-10T02:38:04.789Z,1578623884.789 [Micromodem](INFO): Powering up
2020-01-10T02:38:04.790Z,1578623884.790 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-10T02:38:04.854Z,1578623884.854 [CommandLine](FAULT): Scheduling is paused
2020-01-10T02:38:04.855Z,1578623884.855 [CBIT](INFO): Critical error at 20200110T023804
2020-01-10T02:38:04.855Z,1578623884.855 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-10T02:38:04.857Z,1578623884.857 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-10T02:38:04.858Z,1578623884.858 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-10T02:38:05.505Z,1578623885.505 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-10T02:38:05.505Z,1578623885.505 [RudderServo](FAULT): Rudder failed to initialize
2020-01-10T02:38:05.505Z,1578623885.505 [RudderServo] Communications Fault, FailCount= 1
2020-01-10T02:38:05.505Z,1578623885.505 [RudderServo](ERROR): Communications Fault
2020-01-10T02:38:05.650Z,1578623885.650 [CBIT](INFO): Critical error at 20200110T023804
2020-01-10T02:38:05.653Z,1578623885.653 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-10T02:38:05.859Z,1578623885.859 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T02:38:05.860Z,1578623885.860 [RudderServo](INFO): Powering down
2020-01-10T02:38:06.525Z,1578623886.525 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:38:06.646Z,1578623886.646 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T02:38:06.650Z,1578623886.650 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-10T02:38:06.650Z,1578623886.650 [RudderServo] No Fault, FailCount= 1
2020-01-10T02:38:09.741Z,1578623889.741 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-10T02:38:10.147Z,1578623890.147 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T02:38:09.637972Z,RTC,RTC*5E

2020-01-10T02:38:10.148Z,1578623890.148 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-10T02:38:09.637972Z,RTC,RTC*5E

2020-01-10T02:38:10.547Z,1578623890.547 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-10T02:38:10.547Z,1578623890.547 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-10T02:38:10.951Z,1578623890.951 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-10T02:38:10.951Z,1578623890.951 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-10T02:38:11.359Z,1578623891.359 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-10T02:38:11.359Z,1578623891.359 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-10T02:38:11.763Z,1578623891.763 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-10T02:38:11.763Z,1578623891.763 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-10T02:38:12.167Z,1578623892.167 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-10T02:38:12.167Z,1578623892.167 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-10T02:38:12.567Z,1578623892.567 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-10T02:38:12.567Z,1578623892.567 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-10T02:38:12.971Z,1578623892.971 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-10T02:38:12.971Z,1578623892.971 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-10T02:38:13.351Z,1578623893.351 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-10T02:38:13.352Z,1578623893.352 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,10,02,38,14*48

2020-01-10T02:38:13.806Z,1578623893.806 [Micromodem](INFO): Nmea in: $CACLK,2020,1,10,2,38,14*4A

2020-01-10T02:38:14.156Z,1578623894.156 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-10T02:38:15Z*7B

2020-01-10T02:38:14.157Z,1578623894.157 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T02:38:15.026054Z,USER_CMD,RTC*19

2020-01-10T02:38:14.966Z,1578623894.966 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-01-10T02:38:17.837Z,1578623897.837 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-10T02:38:22.642Z,1578623902.642 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-10T02:38:30.311Z,1578623910.311 [NAL9602](INFO): Powering up NAL9602
2020-01-10T02:38:37.601Z,1578623917.601 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,2*6B

2020-01-10T02:38:37.602Z,1578623917.602 [Micromodem](INFO): Have incoming data
2020-01-10T02:38:37.623Z,1578623917.623 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A

2020-01-10T02:38:37.624Z,1578623917.624 [Micromodem](INFO): Frame:1
2020-01-10T02:38:37.624Z,1578623917.624 [Micromodem](INFO): Frame Count:2
2020-01-10T02:38:37.624Z,1578623917.624 [Micromodem](INFO): Data End:143
2020-01-10T02:38:37.624Z,1578623917.624 [Micromodem](INFO): Data At:16
2020-01-10T02:38:38.000Z,1578623918.000 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,2,4EEE1310BA042F91D0BD31A76379D458F2E8C122*52

2020-01-10T02:38:38.030Z,1578623918.030 [Micromodem](INFO): Frame:2
2020-01-10T02:38:38.030Z,1578623918.030 [Micromodem](INFO): Frame Count:2
2020-01-10T02:38:38.030Z,1578623918.030 [Micromodem](INFO): Data End:56
2020-01-10T02:38:38.030Z,1578623918.030 [Micromodem](INFO): Data At:16
2020-01-10T02:38:38.031Z,1578623918.031 [Micromodem](INFO): Deserialize. FrameCount:2
2020-01-10T02:38:38.031Z,1578623918.031 [Micromodem](INFO): Outer loop. Size:127
2020-01-10T02:38:38.031Z,1578623918.031 [Micromodem](INFO): Inner loop. C:133
2020-01-10T02:38:38.031Z,1578623918.031 [Micromodem](INFO): Inner loop. C:138
2020-01-10T02:38:38.031Z,1578623918.031 [Micromodem](INFO): Inner loop. C:26
2020-01-10T02:38:38.032Z,1578623918.032 [Micromodem](INFO): Inner loop. C:184
2020-01-10T02:38:38.032Z,1578623918.032 [Micromodem](INFO): Inner loop. C:120
2020-01-10T02:38:38.032Z,1578623918.032 [Micromodem](INFO): Inner loop. C:219
2020-01-10T02:38:38.032Z,1578623918.032 [Micromodem](INFO): Inner loop. C:112
2020-01-10T02:38:38.032Z,1578623918.032 [Micromodem](INFO): Inner loop. C:219
2020-01-10T02:38:38.032Z,1578623918.032 [Micromodem](INFO): Inner loop. C:232
2020-01-10T02:38:38.037Z,1578623918.037 [Micromodem](INFO): Inner loop. C:151
2020-01-10T02:38:38.037Z,1578623918.037 [Micromodem](INFO): Inner loop. C:4
2020-01-10T02:38:38.037Z,1578623918.037 [Micromodem](INFO): Inner loop. C:82
2020-01-10T02:38:38.037Z,1578623918.037 [Micromodem](INFO): Inner loop. C:95
2020-01-10T02:38:38.038Z,1578623918.038 [Micromodem](INFO): Inner loop. C:220
2020-01-10T02:38:38.038Z,1578623918.038 [Micromodem](INFO): Inner loop. C:149
2020-01-10T02:38:38.038Z,1578623918.038 [Micromodem](INFO): Inner loop. C:181
2020-01-10T02:38:38.038Z,1578623918.038 [Micromodem](INFO): Inner loop. C:197
2020-01-10T02:38:38.038Z,1578623918.038 [Micromodem](INFO): Inner loop. C:254
2020-01-10T02:38:38.038Z,1578623918.038 [Micromodem](INFO): Inner loop. C:159
2020-01-10T02:38:38.039Z,1578623918.039 [Micromodem](INFO): Inner loop. C:65
2020-01-10T02:38:38.039Z,1578623918.039 [Micromodem](INFO): Inner loop. C:71
2020-01-10T02:38:38.039Z,1578623918.039 [Micromodem](INFO): Inner loop. C:192
2020-01-10T02:38:38.039Z,1578623918.039 [Micromodem](INFO): Inner loop. C:155
2020-01-10T02:38:38.039Z,1578623918.039 [Micromodem](INFO): Inner loop. C:8
2020-01-10T02:38:38.039Z,1578623918.039 [Micromodem](INFO): Inner loop. C:62
2020-01-10T02:38:38.040Z,1578623918.040 [Micromodem](INFO): Inner loop. C:74
2020-01-10T02:38:38.040Z,1578623918.040 [Micromodem](INFO): Inner loop. C:140
2020-01-10T02:38:38.040Z,1578623918.040 [Micromodem](INFO): Inner loop. C:227
2020-01-10T02:38:38.040Z,1578623918.040 [Micromodem](INFO): Inner loop. C:15
2020-01-10T02:38:38.040Z,1578623918.040 [Micromodem](INFO): Inner loop. C:158
2020-01-10T02:38:38.040Z,1578623918.040 [Micromodem](INFO): Inner loop. C:162
2020-01-10T02:38:38.045Z,1578623918.045 [Micromodem](INFO): Inner loop. C:61
2020-01-10T02:38:38.045Z,1578623918.045 [Micromodem](INFO): Inner loop. C:183
2020-01-10T02:38:38.046Z,1578623918.046 [Micromodem](INFO): Inner loop. C:194
2020-01-10T02:38:38.046Z,1578623918.046 [Micromodem](INFO): Inner loop. C:236
2020-01-10T02:38:38.046Z,1578623918.046 [Micromodem](INFO): Inner loop. C:26
2020-01-10T02:38:38.046Z,1578623918.046 [Micromodem](INFO): Inner loop. C:63
2020-01-10T02:38:38.046Z,1578623918.046 [Micromodem](INFO): Inner loop. C:12
2020-01-10T02:38:38.047Z,1578623918.047 [Micromodem](INFO): Inner loop. C:132
2020-01-10T02:38:38.047Z,1578623918.047 [Micromodem](INFO): Inner loop. C:22
2020-01-10T02:38:38.047Z,1578623918.047 [Micromodem](INFO): Inner loop. C:178
2020-01-10T02:38:38.047Z,1578623918.047 [Micromodem](INFO): Inner loop. C:214
2020-01-10T02:38:38.047Z,1578623918.047 [Micromodem](INFO): Inner loop. C:186
2020-01-10T02:38:38.047Z,1578623918.047 [Micromodem](INFO): Inner loop. C:70
2020-01-10T02:38:38.048Z,1578623918.048 [Micromodem](INFO): Inner loop. C:156
2020-01-10T02:38:38.048Z,1578623918.048 [Micromodem](INFO): Inner loop. C:99
2020-01-10T02:38:38.048Z,1578623918.048 [Micromodem](INFO): Inner loop. C:2
2020-01-10T02:38:38.048Z,1578623918.048 [Micromodem](INFO): Inner loop. C:140
2020-01-10T02:38:38.048Z,1578623918.048 [Micromodem](INFO): Inner loop. C:149
2020-01-10T02:38:38.048Z,1578623918.048 [Micromodem](INFO): Inner loop. C:91
2020-01-10T02:38:38.053Z,1578623918.053 [Micromodem](INFO): Inner loop. C:30
2020-01-10T02:38:38.053Z,1578623918.053 [Micromodem](INFO): Inner loop. C:218
2020-01-10T02:38:38.053Z,1578623918.053 [Micromodem](INFO): Inner loop. C:208
2020-01-10T02:38:38.053Z,1578623918.053 [Micromodem](INFO): Inner loop. C:84
2020-01-10T02:38:38.054Z,1578623918.054 [Micromodem](INFO): Inner loop. C:17
2020-01-10T02:38:38.054Z,1578623918.054 [Micromodem](INFO): Inner loop. C:109
2020-01-10T02:38:38.054Z,1578623918.054 [Micromodem](INFO): Inner loop. C:11
2020-01-10T02:38:38.054Z,1578623918.054 [Micromodem](INFO): Inner loop. C:162
2020-01-10T02:38:38.054Z,1578623918.054 [Micromodem](INFO): Inner loop. C:197
2020-01-10T02:38:38.054Z,1578623918.054 [Micromodem](INFO): Inner loop. C:65
2020-01-10T02:38:38.055Z,1578623918.055 [Micromodem](INFO): Inner loop. C:173
2020-01-10T02:38:38.055Z,1578623918.055 [Micromodem](INFO): Inner loop. C:42
2020-01-10T02:38:38.055Z,1578623918.055 [Micromodem](INFO): Inner loop. C:116
2020-01-10T02:38:38.055Z,1578623918.055 [Micromodem](INFO): Inner loop. C:233
2020-01-10T02:38:38.055Z,1578623918.055 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.055Z,1578623918.055 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.056Z,1578623918.056 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.056Z,1578623918.056 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.056Z,1578623918.056 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.056Z,1578623918.056 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.056Z,1578623918.056 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.056Z,1578623918.056 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.085Z,1578623918.085 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.085Z,1578623918.085 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.085Z,1578623918.085 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.086Z,1578623918.086 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.086Z,1578623918.086 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.086Z,1578623918.086 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.086Z,1578623918.086 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.086Z,1578623918.086 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.086Z,1578623918.086 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.087Z,1578623918.087 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.087Z,1578623918.087 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.087Z,1578623918.087 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.087Z,1578623918.087 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.087Z,1578623918.087 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.087Z,1578623918.087 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.088Z,1578623918.088 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.088Z,1578623918.088 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.088Z,1578623918.088 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.088Z,1578623918.088 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.088Z,1578623918.088 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.088Z,1578623918.088 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.097Z,1578623918.097 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.097Z,1578623918.097 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.097Z,1578623918.097 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.097Z,1578623918.097 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.098Z,1578623918.098 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.098Z,1578623918.098 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.098Z,1578623918.098 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.098Z,1578623918.098 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.098Z,1578623918.098 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.098Z,1578623918.098 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.099Z,1578623918.099 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.099Z,1578623918.099 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.099Z,1578623918.099 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.099Z,1578623918.099 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.099Z,1578623918.099 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.100Z,1578623918.100 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.100Z,1578623918.100 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.100Z,1578623918.100 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.100Z,1578623918.100 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.100Z,1578623918.100 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.100Z,1578623918.100 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.109Z,1578623918.109 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.110Z,1578623918.110 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.111Z,1578623918.111 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.112Z,1578623918.112 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.121Z,1578623918.121 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.121Z,1578623918.121 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.121Z,1578623918.121 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.121Z,1578623918.121 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.122Z,1578623918.122 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.122Z,1578623918.122 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.122Z,1578623918.122 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.122Z,1578623918.122 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.122Z,1578623918.122 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.122Z,1578623918.122 [Micromodem](INFO): Outer loop. Size:40
2020-01-10T02:38:38.123Z,1578623918.123 [Micromodem](INFO): Inner loop. C:78
2020-01-10T02:38:38.123Z,1578623918.123 [Micromodem](INFO): Inner loop. C:238
2020-01-10T02:38:38.123Z,1578623918.123 [Micromodem](INFO): Inner loop. C:19
2020-01-10T02:38:38.123Z,1578623918.123 [Micromodem](INFO): Inner loop. C:16
2020-01-10T02:38:38.123Z,1578623918.123 [Micromodem](INFO): Inner loop. C:186
2020-01-10T02:38:38.124Z,1578623918.124 [Micromodem](INFO): Inner loop. C:4
2020-01-10T02:38:38.124Z,1578623918.124 [Micromodem](INFO): Inner loop. C:47
2020-01-10T02:38:38.124Z,1578623918.124 [Micromodem](INFO): Inner loop. C:145
2020-01-10T02:38:38.124Z,1578623918.124 [Micromodem](INFO): Inner loop. C:208
2020-01-10T02:38:38.124Z,1578623918.124 [Micromodem](INFO): Inner loop. C:189
2020-01-10T02:38:38.124Z,1578623918.124 [Micromodem](INFO): Inner loop. C:49
2020-01-10T02:38:38.125Z,1578623918.125 [Micromodem](INFO): Inner loop. C:167
2020-01-10T02:38:38.125Z,1578623918.125 [Micromodem](INFO): Inner loop. C:99
2020-01-10T02:38:38.125Z,1578623918.125 [Micromodem](INFO): Inner loop. C:121
2020-01-10T02:38:38.125Z,1578623918.125 [Micromodem](INFO): Inner loop. C:212
2020-01-10T02:38:38.125Z,1578623918.125 [Micromodem](INFO): Inner loop. C:88
2020-01-10T02:38:38.126Z,1578623918.126 [Micromodem](INFO): Inner loop. C:242
2020-01-10T02:38:38.126Z,1578623918.126 [Micromodem](INFO): Inner loop. C:232
2020-01-10T02:38:38.126Z,1578623918.126 [Micromodem](INFO): Inner loop. C:193
2020-01-10T02:38:38.126Z,1578623918.126 [Micromodem](INFO): Inner loop. C:34
2020-01-10T02:38:38.126Z,1578623918.126 [Micromodem](INFO): Inner loop. C:5
2020-01-10T02:38:38.126Z,1578623918.126 [Micromodem](INFO): Inner loop. C:32
2020-01-10T02:38:38.127Z,1578623918.127 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.127Z,1578623918.127 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.127Z,1578623918.127 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.127Z,1578623918.127 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.127Z,1578623918.127 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.128Z,1578623918.128 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.128Z,1578623918.128 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.128Z,1578623918.128 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.128Z,1578623918.128 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.128Z,1578623918.128 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.128Z,1578623918.128 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.149Z,1578623918.149 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.149Z,1578623918.149 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.149Z,1578623918.149 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.149Z,1578623918.149 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.149Z,1578623918.149 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.150Z,1578623918.150 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.150Z,1578623918.150 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:38:38.150Z,1578623918.150 [Micromodem](CRITICAL): Packet size is not N*16+4: 0xA7
2020-01-10T02:38:38.180Z,1578623918.180 [Micromodem](CRITICAL): Failed to parse uplink message:858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E90000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000004EEE1310BA042F91D0BD31A76379D458F2E8C1220520000000000000000000000000000000000000
2020-01-10T02:38:38.229Z,1578623918.229 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110023835.131254,06,1317,31,0098,0150,29,02,02,00,02,1,012,001,0,3,2,0,150,26.1,8.00,00,-5.00,-01,-0.02,20,10000,4000*7A

2020-01-10T02:38:38.305Z,1578623918.305 [CBIT](INFO): Critical error at 20200110T023838
2020-01-10T02:38:41.219Z,1578623921.219 [NAL9602](INFO): NAL9602 initialized
2020-01-10T02:38:43.299Z,1578623923.299 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-10T02:38:43.332Z,1578623923.332 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-10T02:38:54.081Z,1578623934.081 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011798
CHAN A1 (24V): -0.028903
CHAN A2 (12V): -0.007300
CHAN A3 (5V): -0.001986
CHAN B0 (3.3V): -0.000066
CHAN B1 (3.15aV): 0.000164
CHAN B2 (3.15bV): 0.000270
CHAN B3 (GND): 0.002228
OPEN: 0.005135
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-10T02:39:37.494Z,1578623977.494 [SBIT](IMPORTANT): SBIT PASSED
2020-01-10T02:39:37.532Z,1578623977.532 [CommandLine](IMPORTANT): got command configSet list
2020-01-10T02:39:37.532Z,1578623977.532 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-10T02:39:37.533Z,1578623977.533 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter;

2020-01-10T02:39:37.533Z,1578623977.533 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;

2020-01-10T02:39:37.533Z,1578623977.533 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;

2020-01-10T02:39:37.534Z,1578623977.534 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-10T02:39:37.534Z,1578623977.534 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-10T02:39:37.534Z,1578623977.534 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-10T02:39:37.534Z,1578623977.534 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-10T02:39:37.534Z,1578623977.534 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-10T02:39:37.534Z,1578623977.534 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-10T02:39:37.534Z,1578623977.534 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-10T02:39:37.534Z,1578623977.534 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-10T02:39:37.534Z,1578623977.534 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-10T02:39:37.535Z,1578623977.535 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-10T02:39:37.535Z,1578623977.535 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-10T02:39:37.535Z,1578623977.535 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-10T02:39:37.535Z,1578623977.535 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T02:39:37.535Z,1578623977.535 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T02:39:37.535Z,1578623977.535 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-10T02:39:37.535Z,1578623977.535 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-10T02:39:37.535Z,1578623977.535 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-10T02:39:37.535Z,1578623977.535 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-10T02:39:37.535Z,1578623977.535 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-10T02:39:37.536Z,1578623977.536 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-10T02:39:37.536Z,1578623977.536 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-10T02:39:37.536Z,1578623977.536 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-10T02:39:37.536Z,1578623977.536 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-10T02:39:37.907Z,1578623977.907 [MissionManager](IMPORTANT): Started mission Startup
2020-01-10T02:39:37.907Z,1578623977.907 [Startup] Running Loop=1
2020-01-10T02:39:37.908Z,1578623977.908 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-10T02:39:37.908Z,1578623977.908 [Startup:A.GoToSurface] Running Loop=1
2020-01-10T02:39:37.908Z,1578623977.908 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T02:39:37.908Z,1578623977.908 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T02:39:37.909Z,1578623977.909 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T02:39:37.909Z,1578623977.909 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T02:39:37.910Z,1578623977.910 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T02:39:37.910Z,1578623977.910 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T02:39:37.911Z,1578623977.911 [Startup:StartupSatComms] Running Loop=1
2020-01-10T02:39:37.912Z,1578623977.912 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-10T02:39:37.912Z,1578623977.912 [Startup:StartupSatComms:A] Running Loop=1
2020-01-10T02:39:38.291Z,1578623978.291 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-10T02:39:44.780Z,1578623984.780 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-10T02:40:33.215Z,1578624033.215 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005088
2020-01-10T02:40:38.091Z,1578624038.091 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-10T02:39:37.9Z
2020-01-10T02:40:38.091Z,1578624038.091 [Startup:StartupSatComms:A] Stopped
2020-01-10T02:40:38.091Z,1578624038.091 [Startup:StartupSatComms:B] Running Loop=1
2020-01-10T02:40:38.492Z,1578624038.492 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T02:40:39.504Z,1578624039.504 [Micromodem](INFO): Outgoing frame #1, 47 bytes: 54E2175E040000005D00000400140000000000000000151D80030C5669C08C34EE77A32BD7C276D9737A87F7B8E000
2020-01-10T02:40:39.504Z,1578624039.504 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-10T02:40:39.723Z,1578624039.723 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-10T02:40:40.085Z,1578624040.085 [Micromodem](INFO): Nmea in: $CADRQ,024040,1,12,0,64,1*76

2020-01-10T02:40:40.085Z,1578624040.085 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,54E2175E040000005D00000400140000000000000000151D80030C5669C08C34EE77A32BD7C276D9737A87F7B8E000*4A

2020-01-10T02:40:40.479Z,1578624040.479 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-10T02:40:40.883Z,1578624040.883 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-10T02:40:42.103Z,1578624042.103 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-10T02:40:42.499Z,1578624042.499 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,024041.150075,3,0,200,4000,10000,1,1,12,1,1,1,3,47*46

2020-01-10T02:40:43.307Z,1578624043.307 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:40:43.308Z,1578624043.308 [Micromodem](INFO): Sent 39 bytes from file Logs/20200110T023252/Courier0004.lzma.parts
2020-01-10T02:40:43.308Z,1578624043.308 [Micromodem](INFO): Packets left to send: 0
2020-01-10T02:40:43.722Z,1578624043.722 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110024043.148555,06,939,29,0118,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,21.4,7.00,17,-4.00,-01,-0.10,28,10000,4000*4F

2020-01-10T02:40:44.750Z,1578624044.750 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 81E3175E000002005D00000400ED0200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-10T02:40:44.752Z,1578624044.752 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367817
2020-01-10T02:40:44.754Z,1578624044.754 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 2195AD3813268A83E22AEFBE721F40A8F1582E2E57E7B50605F394DBEBDCBBAA947F77FE1977E8EEBC2A95A6E54B4203D79B46E47E6EACF0EFE215944DD2D99E
2020-01-10T02:40:44.755Z,1578624044.755 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T02:40:45.731Z,1578624045.731 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T02:40:46.139Z,1578624046.139 [Micromodem](INFO): Nmea in: $CADRQ,024045,1,12,0,64,1*73

2020-01-10T02:40:46.139Z,1578624046.139 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,81E3175E000002005D00000400ED0200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*44

2020-01-10T02:40:46.438Z,1578624046.438 [DataOverHttps](INFO): Sending 447 bytes from file Logs/20200110T023753/Courier0000.lzma
2020-01-10T02:40:46.552Z,1578624046.552 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:40:46.954Z,1578624046.954 [Micromodem](INFO): Nmea in: $CADRQ,024047,1,12,0,64,2*72

2020-01-10T02:40:46.954Z,1578624046.954 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367817*34

2020-01-10T02:40:47.351Z,1578624047.351 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:40:47.690Z,1578624047.690 [DataOverHttps](INFO): Moved sent file to Logs/20200110T023753/Courier0000.lzma.bak
2020-01-10T02:40:47.690Z,1578624047.690 [DataOverHttps](INFO): SBD MOMSN=12188917
2020-01-10T02:40:47.761Z,1578624047.761 [Micromodem](INFO): Nmea in: $CADRQ,024047,1,12,0,64,3*73

2020-01-10T02:40:47.761Z,1578624047.761 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,2195AD3813268A83E22AEFBE721F40A8F1582E2E57E7B50605F394DBEBDCBBAA947F77FE1977E8EEBC2A95A6E54B4203D79B46E47E6EACF0EFE215944DD2D99E*45

2020-01-10T02:40:48.155Z,1578624048.155 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:40:48.568Z,1578624048.568 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T02:40:52.207Z,1578624052.207 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T02:40:52.624Z,1578624052.624 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,024048.866424,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7A

2020-01-10T02:40:53.412Z,1578624053.412 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:40:53.815Z,1578624053.815 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T02:40:54.225Z,1578624054.225 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T02:40:54.225Z,1578624054.225 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T023753/Courier0000.lzma.parts
2020-01-10T02:40:54.225Z,1578624054.225 [Micromodem](INFO): Packets left to send: 2
2020-01-10T02:40:54.622Z,1578624054.622 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110024053.420735,06,578,27,0091,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,26.2,7.00,18,-4.00,-01,-0.04,23,10000,4000*41

2020-01-10T02:40:55.727Z,1578624055.727 [Startup:StartupSatComms:B] Stopped
2020-01-10T02:40:55.727Z,1578624055.727 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-10T02:40:55.727Z,1578624055.727 [Startup:StartupSatComms] Stopped
2020-01-10T02:40:55.727Z,1578624055.727 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-10T02:40:55.728Z,1578624055.728 [Startup](INFO): Completed Startup
2020-01-10T02:40:55.728Z,1578624055.728 [MissionManager](INFO): Startup is completed.
2020-01-10T02:40:55.728Z,1578624055.728 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-10T02:40:55.728Z,1578624055.728 [Startup] Stopped
2020-01-10T02:40:55.728Z,1578624055.728 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-10T02:40:55.728Z,1578624055.728 [Startup:A.GoToSurface] Stopped
2020-01-10T02:40:55.728Z,1578624055.728 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T02:40:56.031Z,1578624056.031 [MissionManager](IMPORTANT): Started mission Default
2020-01-10T02:40:56.031Z,1578624056.031 [Default] Running Loop=1
2020-01-10T02:40:56.031Z,1578624056.031 [Default](DEBUG): Aggregate::initialize Default
2020-01-10T02:40:56.031Z,1578624056.031 [Default:B.GoToSurface] Running Loop=1
2020-01-10T02:40:56.031Z,1578624056.031 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T02:40:56.032Z,1578624056.032 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T02:40:56.032Z,1578624056.032 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T02:40:56.032Z,1578624056.032 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T02:40:56.032Z,1578624056.032 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T02:40:56.037Z,1578624056.037 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T02:40:56.037Z,1578624056.037 [Default:A.Wait] Running Loop=1
2020-01-10T02:40:56.037Z,1578624056.037 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-10T02:41:03.933Z,1578624063.933 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-10T02:41:03.933Z,1578624063.933 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T02:41:03.975Z,1578624063.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T02:41:04.337Z,1578624064.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T02:41:04.337Z,1578624064.337 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-10T02:41:07.596Z,1578624067.596 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20200109T211216/Express0005.lzma
2020-01-10T02:41:09.059Z,1578624069.059 [Default:A.Wait](INFO): Done Waiting.
2020-01-10T02:41:09.059Z,1578624069.059 [Default:A.Wait] Stopped
2020-01-10T02:41:09.059Z,1578624069.059 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T02:41:09.098Z,1578624069.098 [DataOverHttps](INFO): Moved sent file to Logs/20200109T211216/Express0005.lzma.bak
2020-01-10T02:41:09.099Z,1578624069.099 [DataOverHttps](INFO): SBD MOMSN=12188924
2020-01-10T02:41:09.442Z,1578624069.442 [Default:CheckIn] Running Loop=1
2020-01-10T02:41:09.442Z,1578624069.442 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-10T02:41:09.442Z,1578624069.442 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-10T02:41:09.857Z,1578624069.857 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-10T02:43:44.549Z,1578624224.549 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-10T02:44:04.808Z,1578624244.808 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-10T02:44:04.808Z,1578624244.808 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T02:44:04.840Z,1578624244.840 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T02:44:05.255Z,1578624245.255 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T02:44:05.255Z,1578624245.255 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-10T02:45:27.254Z,1578624327.254 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-10T02:45:27.258Z,1578624327.258 [BPC1](INFO): Received data from all battery sticks.
2020-01-10T02:46:09.642Z,1578624369.642 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-10T02:41:09.4Z
2020-01-10T02:46:09.642Z,1578624369.642 [Default:CheckIn:Read_GPS] Stopped
2020-01-10T02:46:09.642Z,1578624369.642 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-10T02:46:10.011Z,1578624370.011 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T02:46:11.252Z,1578624371.252 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 81E3175E040001005D00000400600100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-10T02:46:11.252Z,1578624371.252 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F
2020-01-10T02:46:11.253Z,1578624371.253 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D510D236313D15206C6BAF0524E777EDCA
2020-01-10T02:46:11.253Z,1578624371.253 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T02:46:11.427Z,1578624371.427 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T02:46:11.775Z,1578624371.775 [Micromodem](INFO): Nmea in: $CADRQ,024612,1,12,0,64,1*77

2020-01-10T02:46:11.775Z,1578624371.775 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,81E3175E040001005D00000400600100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*43

2020-01-10T02:46:12.179Z,1578624372.179 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:46:12.611Z,1578624372.611 [Micromodem](INFO): Nmea in: $CADRQ,024612,1,12,0,64,2*74

2020-01-10T02:46:12.612Z,1578624372.612 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*3D

2020-01-10T02:46:12.991Z,1578624372.991 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:46:13.391Z,1578624373.391 [Micromodem](INFO): Nmea in: $CADRQ,024613,1,12,0,64,3*74

2020-01-10T02:46:13.391Z,1578624373.391 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D510D236313D15206C6BAF0524E777EDCA*48

2020-01-10T02:46:13.811Z,1578624373.811 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:46:14.198Z,1578624374.198 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T02:46:17.068Z,1578624377.068 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20200110T023753/Courier0004.lzma
2020-01-10T02:46:17.839Z,1578624377.839 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T02:46:18.250Z,1578624378.250 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,024614.496504,3,0,200,4000,10000,1,1,12,1,3,3,3,192*75

2020-01-10T02:46:19.076Z,1578624379.076 [DataOverHttps](INFO): Moved sent file to Logs/20200110T023753/Courier0004.lzma.bak
2020-01-10T02:46:19.076Z,1578624379.076 [DataOverHttps](INFO): SBD MOMSN=12188935
2020-01-10T02:46:19.463Z,1578624379.463 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:46:19.859Z,1578624379.859 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T02:46:20.259Z,1578624380.259 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T02:46:20.260Z,1578624380.260 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T023753/Courier0004.lzma.parts
2020-01-10T02:46:20.260Z,1578624380.260 [Micromodem](INFO): Packets left to send: 1
2020-01-10T02:46:20.688Z,1578624380.688 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110024619.047450,06,597,27,0096,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,25.4,5.00,11,-2.00,-01,-0.03,23,10000,4000*45

2020-01-10T02:46:21.705Z,1578624381.705 [Default:CheckIn:Read_Iridium] Stopped
2020-01-10T02:46:21.705Z,1578624381.705 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-10T02:46:21.705Z,1578624381.705 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-10T02:46:37.830Z,1578624397.830 [DataOverHttps](INFO): Sending 1373 bytes from file Logs/20200109T212834/Express0001.lzma
2020-01-10T02:46:39.834Z,1578624399.834 [DataOverHttps](INFO): Moved sent file to Logs/20200109T212834/Express0001.lzma.bak
2020-01-10T02:46:39.834Z,1578624399.834 [DataOverHttps](INFO): SBD MOMSN=12188937
2020-01-10T02:47:05.793Z,1578624425.793 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-10T02:47:05.793Z,1578624425.793 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T02:47:05.803Z,1578624425.803 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T02:47:06.231Z,1578624426.231 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T02:47:06.231Z,1578624426.231 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-10T02:48:43.127Z,1578624523.127 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-10T02:48:43.127Z,1578624523.127 [NAL9602] Data Fault, FailCount= 1
2020-01-10T02:48:43.127Z,1578624523.127 [NAL9602](ERROR): Data Fault
2020-01-10T02:48:43.180Z,1578624523.180 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-10T02:48:43.531Z,1578624523.531 [NAL9602](INFO): Powering down
2020-01-10T02:48:44.377Z,1578624524.377 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-10T02:48:44.377Z,1578624524.377 [NAL9602] No Fault, FailCount= 1
2020-01-10T02:49:13.835Z,1578624553.835 [NAL9602](INFO): Powering up NAL9602
2020-01-10T02:49:22.743Z,1578624562.743 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-10T02:49:24.761Z,1578624564.761 [NAL9602](INFO): NAL9602 initialized
2020-01-10T02:49:48.639Z,1578624588.639 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-10T02:49:48.639Z,1578624588.639 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,    690.98
2020-01-10T02:50:06.796Z,1578624606.796 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-01-10T02:50:06.796Z,1578624606.796 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T02:50:06.842Z,1578624606.842 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T02:50:07.244Z,1578624607.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T02:50:07.244Z,1578624607.244 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-01-10T02:50:56.143Z,1578624656.143 [CommandLine](IMPORTANT): got command restart application
2020-01-10T02:50:57.149Z,1578624657.149 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:50:57.149Z,1578624657.149 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:57.286Z,1578624657.286 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-10T02:50:57.286Z,1578624657.286 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:57.286Z,1578624657.286 [CommandLine](INFO): Join timeout helper Thread ID is 9586
2020-01-10T02:50:57.288Z,1578624657.288 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-10T02:50:57.288Z,1578624657.288 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:57.301Z,1578624657.301 [NavChartDb](INFO): Join timeout helper Thread ID is 9587
2020-01-10T02:50:57.381Z,1578624657.381 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:50:57.381Z,1578624657.381 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:57.397Z,1578624657.397 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-10T02:50:57.397Z,1578624657.397 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:57.397Z,1578624657.397 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 9588
2020-01-10T02:50:57.789Z,1578624657.789 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:50:57.790Z,1578624657.790 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T02:50:57.790Z,1578624657.790 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:57.793Z,1578624657.793 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-10T02:50:57.793Z,1578624657.793 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:57.793Z,1578624657.793 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9589
2020-01-10T02:50:57.905Z,1578624657.905 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:50:57.905Z,1578624657.905 [CTD_NeilBrown](INFO): Powering down
2020-01-10T02:50:57.918Z,1578624657.918 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:57.933Z,1578624657.933 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-10T02:50:57.933Z,1578624657.933 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:57.933Z,1578624657.933 [Radio_Surface](INFO): Join timeout helper Thread ID is 9590
2020-01-10T02:50:58.189Z,1578624658.189 [Radio_Surface](INFO): Powering down
2020-01-10T02:50:58.190Z,1578624658.190 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:50:58.190Z,1578624658.190 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:58.194Z,1578624658.194 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-10T02:50:58.194Z,1578624658.194 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:58.194Z,1578624658.194 [DataOverHttps](INFO): Join timeout helper Thread ID is 9591
2020-01-10T02:50:58.910Z,1578624658.910 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:50:58.910Z,1578624658.910 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:58.914Z,1578624658.914 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-10T02:50:58.914Z,1578624658.914 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:58.914Z,1578624658.914 [logger](INFO): Join timeout helper Thread ID is 9592
2020-01-10T02:50:58.941Z,1578624658.941 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:50:58.941Z,1578624658.941 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:58.961Z,1578624658.961 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-10T02:50:58.962Z,1578624658.962 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:58.962Z,1578624658.962 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-10T02:50:58.962Z,1578624658.962 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:58.962Z,1578624658.962 [controlThread](INFO): Join timeout helper Thread ID is 9593
2020-01-10T02:50:59.269Z,1578624659.269 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:50:59.269Z,1578624659.269 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-10T02:50:59.269Z,1578624659.269 [AHRS_M2](INFO): Powering down
2020-01-10T02:50:59.341Z,1578624659.341 [DDM](INFO): Powering down
2020-01-10T02:50:59.433Z,1578624659.433 [DUSBL_Hydroid](INFO): Powering down
2020-01-10T02:50:59.525Z,1578624659.525 [Micromodem](INFO): Powering down
2020-01-10T02:50:59.621Z,1578624659.621 [NAL9602](INFO): Powering down
2020-01-10T02:50:59.693Z,1578624659.693 [RDI_Pathfinder](INFO): Powering down
2020-01-10T02:50:59.694Z,1578624659.694 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-10T02:50:59.695Z,1578624659.695 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-10T02:50:59.696Z,1578624659.696 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-10T02:50:59.696Z,1578624659.696 [MissionManager](INFO): Uninitializing Mission Default
2020-01-10T02:50:59.696Z,1578624659.696 [Default] Stopped
2020-01-10T02:50:59.696Z,1578624659.696 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-10T02:50:59.696Z,1578624659.696 [Default:B.GoToSurface] Stopped
2020-01-10T02:50:59.697Z,1578624659.697 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T02:50:59.697Z,1578624659.697 [Default:CheckIn] Stopped
2020-01-10T02:50:59.697Z,1578624659.697 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-10T02:50:59.697Z,1578624659.697 [Default:CheckIn:C.Wait] Stopped
2020-01-10T02:50:59.697Z,1578624659.697 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T02:50:59.699Z,1578624659.699 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-10T02:50:59.700Z,1578624659.700 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-10T02:50:59.700Z,1578624659.700 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-10T02:50:59.700Z,1578624659.700 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-10T02:50:59.700Z,1578624659.700 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-10T02:50:59.701Z,1578624659.701 [BuoyancyServo](INFO): Powering down
2020-01-10T02:50:59.713Z,1578624659.713 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-10T02:50:59.713Z,1578624659.713 [ElevatorServo](INFO): Powering down
2020-01-10T02:50:59.714Z,1578624659.714 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-10T02:50:59.714Z,1578624659.714 [MassServo](INFO): Powering down
2020-01-10T02:50:59.715Z,1578624659.715 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T02:50:59.715Z,1578624659.715 [RudderServo](INFO): Powering down
2020-01-10T02:50:59.715Z,1578624659.715 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-10T02:50:59.716Z,1578624659.716 [ThrusterServo](INFO): Powering down
2020-01-10T02:50:59.716Z,1578624659.716 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-10T02:50:59.717Z,1578624659.717 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-10T02:50:59.717Z,1578624659.717 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-10T02:50:59.717Z,1578624659.717 [CBIT](DEBUG): Powering off loads.
2020-01-10T02:50:59.729Z,1578624659.729 [CBIT](DEBUG): Disabling WDT.
2020-01-10T02:50:59.741Z,1578624659.741 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-10T02:50:59.742Z,1578624659.742 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:59.801Z,1578624659.801 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:59.811Z,1578624659.811 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:59.859Z,1578624659.859 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:59.862Z,1578624659.862 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:59.922Z,1578624659.922 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:50:59.983Z,1578624659.983 [logger ThreadHandler](INFO): Thread cancelled.