2020-01-09T01:03:32.895Z,1578531812.895 [Supervisor](DEBUG): Initializing supervisor.
2020-01-09T01:03:32.898Z,1578531812.898 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-09T01:03:32.899Z,1578531812.899 [SyncHandler](INFO): Protected caller Thread ID is 807
2020-01-09T01:03:32.899Z,1578531812.899 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-09T01:03:32.900Z,1578531812.900 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-09T01:03:32.900Z,1578531812.900 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 808
2020-01-09T01:03:32.903Z,1578531812.903 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-09T01:03:32.914Z,1578531812.914 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-09T01:03:32.915Z,1578531812.915 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-09T01:03:32.915Z,1578531812.915 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 809
2020-01-09T01:03:32.916Z,1578531812.916 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-09T01:03:32.917Z,1578531812.917 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-09T01:03:32.918Z,1578531812.918 [logger ThreadHandler](INFO): Protected caller Thread ID is 810
2020-01-09T01:03:32.919Z,1578531812.919 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-09T01:03:32.920Z,1578531812.920 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-09T01:03:32.924Z,1578531812.924 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-09T01:03:33.352Z,1578531813.352 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-09T01:03:33.354Z,1578531813.354 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-09T01:03:33.451Z,1578531813.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-09T01:03:33.453Z,1578531813.453 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-09T01:03:33.779Z,1578531813.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-09T01:03:33.779Z,1578531813.779 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-09T01:03:33.917Z,1578531813.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-09T01:03:33.918Z,1578531813.918 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-09T01:03:34.106Z,1578531814.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-09T01:03:34.107Z,1578531814.107 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-09T01:03:34.550Z,1578531814.550 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-09T01:03:34.552Z,1578531814.552 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-09T01:03:34.758Z,1578531814.758 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-09T01:03:34.760Z,1578531814.760 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-09T01:03:34.903Z,1578531814.903 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-09T01:03:34.905Z,1578531814.905 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-09T01:03:35.093Z,1578531815.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-09T01:03:35.094Z,1578531815.094 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-09T01:03:35.189Z,1578531815.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-09T01:03:35.190Z,1578531815.190 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-09T01:03:35.488Z,1578531815.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-09T01:03:35.490Z,1578531815.490 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-09T01:03:35.571Z,1578531815.571 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-09T01:03:35.675Z,1578531815.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-09T01:03:35.676Z,1578531815.676 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-09T01:03:37.019Z,1578531817.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-09T01:03:37.019Z,1578531817.019 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-09T01:03:37.428Z,1578531817.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-09T01:03:37.430Z,1578531817.430 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-09T01:03:37.432Z,1578531817.432 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-09T01:03:37.637Z,1578531817.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-09T01:03:37.735Z,1578531817.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-09T01:03:37.833Z,1578531817.833 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-09T01:03:38.057Z,1578531818.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-09T01:03:38.057Z,1578531818.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-09T01:03:38.142Z,1578531818.142 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-09T01:03:38.234Z,1578531818.234 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-09T01:03:38.330Z,1578531818.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-09T01:03:38.412Z,1578531818.412 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-09T01:03:38.519Z,1578531818.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-09T01:03:38.700Z,1578531818.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-09T01:03:38.829Z,1578531818.829 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-09T01:03:38.857Z,1578531818.857 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-09T01:03:39.396Z,1578531819.396 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-09T01:03:39.401Z,1578531819.401 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-09T01:03:39.403Z,1578531819.403 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-09T01:03:39.408Z,1578531819.408 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-09T01:03:39.409Z,1578531819.409 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-09T01:03:39.414Z,1578531819.414 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-09T01:03:39.414Z,1578531819.414 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-09T01:03:39.419Z,1578531819.419 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-09T01:03:39.594Z,1578531819.594 [AHRS_M2] Loaded
2020-01-09T01:03:39.594Z,1578531819.594 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-09T01:03:39.741Z,1578531819.741 [DataOverHttps] Loaded
2020-01-09T01:03:39.742Z,1578531819.742 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-09T01:03:39.743Z,1578531819.743 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-09T01:03:39.743Z,1578531819.743 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 889
2020-01-09T01:03:39.766Z,1578531819.766 [DDM] Loaded
2020-01-09T01:03:39.767Z,1578531819.767 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-09T01:03:39.780Z,1578531819.780 [Depth_Keller] Loaded
2020-01-09T01:03:39.780Z,1578531819.780 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-09T01:03:39.785Z,1578531819.785 [DropWeight] Loaded
2020-01-09T01:03:39.785Z,1578531819.785 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-09T01:03:39.845Z,1578531819.845 [DUSBL_Hydroid] Loaded
2020-01-09T01:03:39.845Z,1578531819.845 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-09T01:03:39.890Z,1578531819.890 [Micromodem] Loaded
2020-01-09T01:03:39.891Z,1578531819.891 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-09T01:03:39.986Z,1578531819.986 [NAL9602] Loaded
2020-01-09T01:03:39.986Z,1578531819.986 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-09T01:03:40.023Z,1578531820.023 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-09T01:03:40.023Z,1578531820.023 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-09T01:03:40.024Z,1578531820.024 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-09T01:03:40.024Z,1578531820.024 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-09T01:03:40.025Z,1578531820.025 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-09T01:03:40.025Z,1578531820.025 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-09T01:03:40.026Z,1578531820.026 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-09T01:03:40.026Z,1578531820.026 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-09T01:03:40.027Z,1578531820.027 [Onboard] Loaded
2020-01-09T01:03:40.027Z,1578531820.027 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-09T01:03:40.033Z,1578531820.033 [PowerOnly] Loaded
2020-01-09T01:03:40.033Z,1578531820.033 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-09T01:03:40.039Z,1578531820.039 [Radio_Surface] Loaded
2020-01-09T01:03:40.039Z,1578531820.039 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-09T01:03:40.040Z,1578531820.040 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-09T01:03:40.041Z,1578531820.041 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 890
2020-01-09T01:03:40.083Z,1578531820.083 [RDI_Pathfinder] Loaded
2020-01-09T01:03:40.084Z,1578531820.084 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-09T01:03:41.555Z,1578531821.555 [BPC1] Loaded
2020-01-09T01:03:41.555Z,1578531821.555 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-09T01:03:41.556Z,1578531821.556 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-09T01:03:41.556Z,1578531821.556 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-09T01:03:41.580Z,1578531821.580 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-09T01:03:41.582Z,1578531821.582 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-09T01:03:41.701Z,1578531821.701 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-09T01:03:41.701Z,1578531821.701 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-09T01:03:41.721Z,1578531821.721 [NavChart] Loaded
2020-01-09T01:03:41.721Z,1578531821.721 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-09T01:03:41.725Z,1578531821.725 [UniversalFixResidualReporter] Loaded
2020-01-09T01:03:41.726Z,1578531821.726 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-09T01:03:41.726Z,1578531821.726 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-09T01:03:41.727Z,1578531821.727 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-09T01:03:41.855Z,1578531821.855 [BuoyancyServo] Loaded
2020-01-09T01:03:41.855Z,1578531821.855 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-09T01:03:41.870Z,1578531821.870 [ElevatorServo] Loaded
2020-01-09T01:03:41.870Z,1578531821.870 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-09T01:03:41.884Z,1578531821.884 [MassServo] Loaded
2020-01-09T01:03:41.885Z,1578531821.885 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-09T01:03:41.899Z,1578531821.899 [RudderServo] Loaded
2020-01-09T01:03:41.899Z,1578531821.899 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-09T01:03:41.914Z,1578531821.914 [ThrusterServo] Loaded
2020-01-09T01:03:41.914Z,1578531821.914 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-09T01:03:41.914Z,1578531821.914 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-09T01:03:41.916Z,1578531821.916 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-09T01:03:42.850Z,1578531822.850 [CTD_NeilBrown] Loaded
2020-01-09T01:03:42.851Z,1578531822.851 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-09T01:03:42.852Z,1578531822.852 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-09T01:03:42.852Z,1578531822.852 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 891
2020-01-09T01:03:42.866Z,1578531822.866 [PAR_Licor] Loaded
2020-01-09T01:03:42.866Z,1578531822.866 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-09T01:03:42.909Z,1578531822.909 [WetLabsSeaOWL_UV_A] Loaded
2020-01-09T01:03:42.909Z,1578531822.909 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-09T01:03:42.910Z,1578531822.910 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-09T01:03:42.911Z,1578531822.911 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 892
2020-01-09T01:03:42.911Z,1578531822.911 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-09T01:03:42.913Z,1578531822.913 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-09T01:03:43.266Z,1578531823.266 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-09T01:03:43.267Z,1578531823.267 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-09T01:03:43.321Z,1578531823.321 [DepthRateCalculator] Loaded
2020-01-09T01:03:43.321Z,1578531823.321 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-09T01:03:43.327Z,1578531823.327 [PitchRateCalculator] Loaded
2020-01-09T01:03:43.327Z,1578531823.327 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-09T01:03:43.339Z,1578531823.339 [SpeedCalculator] Loaded
2020-01-09T01:03:43.339Z,1578531823.339 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-09T01:03:43.359Z,1578531823.359 [TempGradientCalculator] Loaded
2020-01-09T01:03:43.359Z,1578531823.359 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-09T01:03:43.365Z,1578531823.365 [YawRateCalculator] Loaded
2020-01-09T01:03:43.365Z,1578531823.365 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-09T01:03:43.404Z,1578531823.404 [ElevatorOffsetCalculator] Loaded
2020-01-09T01:03:43.404Z,1578531823.404 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-09T01:03:43.405Z,1578531823.405 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-09T01:03:43.406Z,1578531823.406 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-09T01:03:43.563Z,1578531823.563 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-09T01:03:43.584Z,1578531823.584 [SBIT] Loaded
2020-01-09T01:03:43.585Z,1578531823.585 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-09T01:03:43.585Z,1578531823.585 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-09T01:03:43.598Z,1578531823.598 [IBIT] Loaded
2020-01-09T01:03:43.598Z,1578531823.598 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-09T01:03:43.601Z,1578531823.601 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-09T01:03:43.739Z,1578531823.739 [CBIT] Loaded
2020-01-09T01:03:43.739Z,1578531823.739 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-09T01:03:43.739Z,1578531823.739 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-09T01:03:43.741Z,1578531823.741 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-09T01:03:43.864Z,1578531823.864 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-09T01:03:43.865Z,1578531823.865 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-09T01:03:43.986Z,1578531823.986 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-09T01:03:43.987Z,1578531823.987 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-09T01:03:44.050Z,1578531824.050 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-09T01:03:44.131Z,1578531824.131 [VerticalControl] Loaded
2020-01-09T01:03:44.131Z,1578531824.131 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-09T01:03:44.132Z,1578531824.132 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-09T01:03:44.200Z,1578531824.200 [HorizontalControl] Loaded
2020-01-09T01:03:44.200Z,1578531824.200 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-09T01:03:44.201Z,1578531824.201 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-09T01:03:44.203Z,1578531824.203 [SpeedControl] Loaded
2020-01-09T01:03:44.203Z,1578531824.203 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-09T01:03:44.203Z,1578531824.203 [LoopControl](DEBUG): Construct LoopControl.
2020-01-09T01:03:44.204Z,1578531824.204 [LoopControl] Loaded
2020-01-09T01:03:44.204Z,1578531824.204 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-09T01:03:44.205Z,1578531824.205 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-09T01:03:44.205Z,1578531824.205 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-09T01:03:44.253Z,1578531824.253 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-09T01:03:44.257Z,1578531824.257 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-09T01:03:44.258Z,1578531824.258 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-09T01:03:44.264Z,1578531824.264 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-09T01:03:44.265Z,1578531824.265 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-09T01:03:44.266Z,1578531824.266 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 893
2020-01-09T01:03:44.270Z,1578531824.270 [Supervisor](INFO): Main Thread ID is 803
2020-01-09T01:03:44.270Z,1578531824.270 [Supervisor](DEBUG): Running supervisor.
2020-01-09T01:03:44.271Z,1578531824.271 [CommandLine ThreadHandler](INFO): Handler Thread ID is 894
2020-01-09T01:03:44.273Z,1578531824.273 [controlThread ThreadHandler](INFO): Handler Thread ID is 895
2020-01-09T01:03:44.273Z,1578531824.273 [controlThread](DEBUG): Initializing ControlThread
2020-01-09T01:03:44.280Z,1578531824.280 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-09T01:03:44.280Z,1578531824.280 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-09T01:03:44.285Z,1578531824.285 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-09T01:03:44.286Z,1578531824.286 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-09T01:03:44.286Z,1578531824.286 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-09T01:03:44.286Z,1578531824.286 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-09T01:03:44.287Z,1578531824.287 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-09T01:03:44.287Z,1578531824.287 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-09T01:03:44.288Z,1578531824.288 [SBIT](INFO): Initialize SBIT Component.
2020-01-09T01:03:44.288Z,1578531824.288 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-09T01:03:44.288Z,1578531824.288 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-09T01:03:44.289Z,1578531824.289 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-09T01:03:44.290Z,1578531824.290 [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-09T01:03:44.291Z,1578531824.291 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-09T01:03:44.292Z,1578531824.292 [IBIT](INFO): Initialize IBIT Component.
2020-01-09T01:03:44.293Z,1578531824.293 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-09T01:03:44.294Z,1578531824.294 [logger ThreadHandler](INFO): Handler Thread ID is 896
2020-01-09T01:03:44.305Z,1578531824.305 [CBIT](DEBUG): Initialized mux pins.
2020-01-09T01:03:44.305Z,1578531824.305 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-09T01:03:44.313Z,1578531824.313 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 897
2020-01-09T01:03:44.314Z,1578531824.314 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-09T01:03:44.325Z,1578531824.325 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 898
2020-01-09T01:03:44.329Z,1578531824.329 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-09T01:03:44.329Z,1578531824.329 [CBIT](DEBUG): Initializing heartbeat.
2020-01-09T01:03:44.337Z,1578531824.337 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 899
2020-01-09T01:03:44.338Z,1578531824.338 [CTD_NeilBrown](INFO): Powering down
2020-01-09T01:03:44.365Z,1578531824.365 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 900
2020-01-09T01:03:44.366Z,1578531824.366 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-09T01:03:44.385Z,1578531824.385 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 901
2020-01-09T01:03:44.392Z,1578531824.392 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-09T01:03:44.393Z,1578531824.393 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-09T01:03:44.393Z,1578531824.393 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-09T01:03:44.393Z,1578531824.393 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-09T01:03:44.394Z,1578531824.394 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-09T01:03:44.394Z,1578531824.394 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-09T01:03:44.394Z,1578531824.394 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-09T01:03:44.394Z,1578531824.394 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-09T01:03:44.394Z,1578531824.394 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-09T01:03:44.395Z,1578531824.395 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-09T01:03:44.395Z,1578531824.395 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-09T01:03:44.395Z,1578531824.395 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-09T01:03:44.395Z,1578531824.395 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-09T01:03:44.395Z,1578531824.395 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-09T01:03:44.396Z,1578531824.396 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-09T01:03:44.396Z,1578531824.396 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-09T01:03:44.401Z,1578531824.401 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-09T01:03:44.401Z,1578531824.401 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-09T01:03:44.437Z,1578531824.437 [CBIT](DEBUG): Backplane powered.
2020-01-09T01:03:44.437Z,1578531824.437 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-09T01:03:44.439Z,1578531824.439 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-09T01:03:44.440Z,1578531824.440 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-09T01:03:44.440Z,1578531824.440 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-09T01:03:44.441Z,1578531824.441 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-09T01:03:44.466Z,1578531824.466 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-09T01:03:44.488Z,1578531824.488 [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-09T01:03:44.505Z,1578531824.505 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-09T01:03:44.573Z,1578531824.573 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-09T01:03:44.574Z,1578531824.574 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-09T01:03:44.576Z,1578531824.576 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-09T01:03:44.620Z,1578531824.620 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-09T01:03:44.623Z,1578531824.623 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-09T01:03:44.632Z,1578531824.632 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-09T01:03:44.647Z,1578531824.647 [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-09T01:03:44.652Z,1578531824.652 [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-09T01:03:44.659Z,1578531824.659 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-09T01:03:44.723Z,1578531824.723 [DDM](INFO): Powering up
2020-01-09T01:03:44.723Z,1578531824.723 [DDM](DEBUG): Initializing DDM.
2020-01-09T01:03:44.729Z,1578531824.729 [Radio_Surface](INFO): Powering up
2020-01-09T01:03:44.789Z,1578531824.789 [DUSBL_Hydroid](INFO): Powering up
2020-01-09T01:03:44.789Z,1578531824.789 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-09T01:03:44.991Z,1578531824.991 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-09T01:03:44.997Z,1578531824.997 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-09T01:03:44.998Z,1578531824.998 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-09T01:03:45.005Z,1578531825.005 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-09T01:03:45.006Z,1578531825.006 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-09T01:03:45.013Z,1578531825.013 [MassServo](DEBUG): Initializing MassServo.
2020-01-09T01:03:45.014Z,1578531825.014 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-09T01:03:45.021Z,1578531825.021 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-09T01:03:45.022Z,1578531825.022 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-09T01:03:45.029Z,1578531825.029 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-09T01:03:45.091Z,1578531825.091 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-09T01:03:45.091Z,1578531825.091 [DropWeight] Hardware Fault, FailCount= 1
2020-01-09T01:03:45.091Z,1578531825.091 [DropWeight](ERROR): Hardware Fault
2020-01-09T01:03:45.093Z,1578531825.093 [Micromodem](INFO): Powering up
2020-01-09T01:03:45.093Z,1578531825.093 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-09T01:03:45.172Z,1578531825.172 [CommandLine](FAULT): Scheduling is paused
2020-01-09T01:03:45.173Z,1578531825.173 [CBIT](INFO): Critical error at 20200109T010345
2020-01-09T01:03:45.173Z,1578531825.173 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-09T01:03:45.175Z,1578531825.175 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-09T01:03:45.176Z,1578531825.176 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-09T01:03:45.849Z,1578531825.849 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-09T01:03:45.849Z,1578531825.849 [RudderServo](FAULT): Rudder failed to initialize
2020-01-09T01:03:45.849Z,1578531825.849 [RudderServo] Communications Fault, FailCount= 1
2020-01-09T01:03:45.849Z,1578531825.849 [RudderServo](ERROR): Communications Fault
2020-01-09T01:03:46.018Z,1578531826.018 [CBIT](INFO): Critical error at 20200109T010345
2020-01-09T01:03:46.021Z,1578531826.021 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-09T01:03:46.198Z,1578531826.198 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-09T01:03:46.198Z,1578531826.198 [RudderServo](INFO): Powering down
2020-01-09T01:03:46.871Z,1578531826.871 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-09T01:03:46.990Z,1578531826.990 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-09T01:03:46.994Z,1578531826.994 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-09T01:03:46.994Z,1578531826.994 [RudderServo] No Fault, FailCount= 1
2020-01-09T01:03:50.109Z,1578531830.109 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-09T01:03:50.512Z,1578531830.512 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T00:59:12.300357Z,RTC,RTC*55

2020-01-09T01:03:50.512Z,1578531830.512 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-09T00:59:12.300357Z,RTC,RTC*55

2020-01-09T01:03:50.919Z,1578531830.919 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-09T01:03:50.919Z,1578531830.919 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-09T01:03:51.319Z,1578531831.319 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-09T01:03:51.319Z,1578531831.319 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-09T01:03:51.727Z,1578531831.727 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-09T01:03:51.727Z,1578531831.727 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-09T01:03:52.131Z,1578531832.131 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-09T01:03:52.131Z,1578531832.131 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-09T01:03:52.535Z,1578531832.535 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-09T01:03:52.535Z,1578531832.535 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-09T01:03:52.935Z,1578531832.935 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-09T01:03:52.935Z,1578531832.935 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-09T01:03:53.793Z,1578531833.793 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-09T01:03:53.793Z,1578531833.793 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-09T01:03:53.951Z,1578531833.951 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-09T01:03:53.952Z,1578531833.952 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,09,01,03,54*4F

2020-01-09T01:03:54.303Z,1578531834.303 [Micromodem](INFO): Nmea in: $CACLK,2020,1,9,1,3,54*4D

2020-01-09T01:03:55.121Z,1578531835.121 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-09T01:03:55Z*7C

2020-01-09T01:03:55.134Z,1578531835.134 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T01:03:55.028596Z,USER_CMD,RTC*1B

2020-01-09T01:03:55.509Z,1578531835.509 [DDM](INFO): Dynamic Docking Module:
2020-01-09T01:03:58.377Z,1578531838.377 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-09T01:04:02.794Z,1578531842.794 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-09T01:04:10.067Z,1578531850.067 [NAL9602](INFO): Powering up NAL9602
2020-01-09T01:04:20.987Z,1578531860.987 [NAL9602](INFO): NAL9602 initialized
2020-01-09T01:04:23.044Z,1578531863.044 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T01:04:23.044Z,1578531863.044 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3278,V
2020-01-09T01:04:23.855Z,1578531863.855 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-09T01:04:23.863Z,1578531863.863 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-09T01:04:34.899Z,1578531874.899 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.013173
CHAN A1 (24V): -0.029426
CHAN A2 (12V): -0.007135
CHAN A3 (5V): -0.001865
CHAN B0 (3.3V): 0.000466
CHAN B1 (3.15aV): -0.000179
CHAN B2 (3.15bV): 0.000022
CHAN B3 (GND): 0.001933
OPEN: 0.005044
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-09T01:05:00.402Z,1578531900.402 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T01:05:00.402Z,1578531900.402 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   I,-32768,-32768,-32768,-32768,V
2020-01-09T01:05:17.908Z,1578531917.908 [SBIT](IMPORTANT): SBIT PASSED
2020-01-09T01:05:17.982Z,1578531917.982 [CommandLine](IMPORTANT): got command configSet list
2020-01-09T01:05:17.982Z,1578531917.982 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-09T01:05:17.983Z,1578531917.983 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter;

2020-01-09T01:05:17.983Z,1578531917.983 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;

2020-01-09T01:05:17.983Z,1578531917.983 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;

2020-01-09T01:05:17.983Z,1578531917.983 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-09T01:05:17.983Z,1578531917.983 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-09T01:05:17.984Z,1578531917.984 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-09T01:05:17.984Z,1578531917.984 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-09T01:05:17.984Z,1578531917.984 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-09T01:05:17.984Z,1578531917.984 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-09T01:05:17.984Z,1578531917.984 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-09T01:05:17.984Z,1578531917.984 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-09T01:05:17.984Z,1578531917.984 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-09T01:05:17.984Z,1578531917.984 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-09T01:05:17.984Z,1578531917.984 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-09T01:05:17.984Z,1578531917.984 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-09T01:05:17.985Z,1578531917.985 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-09T01:05:17.985Z,1578531917.985 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-09T01:05:17.985Z,1578531917.985 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-09T01:05:17.985Z,1578531917.985 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-09T01:05:17.985Z,1578531917.985 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-09T01:05:17.985Z,1578531917.985 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-09T01:05:17.985Z,1578531917.985 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-09T01:05:17.985Z,1578531917.985 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-09T01:05:17.986Z,1578531917.986 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-09T01:05:17.986Z,1578531917.986 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-09T01:05:17.986Z,1578531917.986 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-09T01:05:18.312Z,1578531918.312 [MissionManager](IMPORTANT): Started mission Startup
2020-01-09T01:05:18.312Z,1578531918.312 [Startup] Running Loop=1
2020-01-09T01:05:18.313Z,1578531918.313 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-09T01:05:18.317Z,1578531918.317 [Startup:A.GoToSurface] Running Loop=1
2020-01-09T01:05:18.317Z,1578531918.317 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-09T01:05:18.318Z,1578531918.318 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-09T01:05:18.318Z,1578531918.318 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-09T01:05:18.318Z,1578531918.318 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-09T01:05:18.319Z,1578531918.319 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-09T01:05:18.319Z,1578531918.319 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-09T01:05:18.320Z,1578531918.320 [Startup:StartupSatComms] Running Loop=1
2020-01-09T01:05:18.320Z,1578531918.320 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-09T01:05:18.321Z,1578531918.321 [Startup:StartupSatComms:A] Running Loop=1
2020-01-09T01:05:18.704Z,1578531918.704 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-09T01:05:32.814Z,1578531932.814 [NAL9602](INFO): SBD MO Status=2, MOMSN=10149, MT Status=2, MTMSN=0
2020-01-09T01:05:32.814Z,1578531932.814 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T01:06:14.554Z,1578531974.554 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004665
2020-01-09T01:06:18.570Z,1578531978.570 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-09T01:05:18.3Z
2020-01-09T01:06:18.571Z,1578531978.571 [Startup:StartupSatComms:A] Stopped
2020-01-09T01:06:18.571Z,1578531978.571 [Startup:StartupSatComms:B] Running Loop=1
2020-01-09T01:06:18.935Z,1578531978.935 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-09T01:06:20.425Z,1578531980.425 [Micromodem](INFO): Outgoing frame #1, 47 bytes: 6D79165E040000005D00000400140000000000000000151D80030C5669C08C34EE5D3581ED7A30C7D2FE4613D20000
2020-01-09T01:06:20.425Z,1578531980.425 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-09T01:06:20.440Z,1578531980.440 [NAL9602](INFO): SBD MO Status=0, MOMSN=10149, MT Status=0, MTMSN=0
2020-01-09T01:06:20.440Z,1578531980.440 [NAL9602](INFO): No messages in MT queue
2020-01-09T01:06:20.979Z,1578531980.979 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-09T01:06:21.379Z,1578531981.379 [Micromodem](INFO): Nmea in: $CADRQ,010620,1,12,0,64,1*71

2020-01-09T01:06:21.379Z,1578531981.379 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6D79165E040000005D00000400140000000000000000151D80030C5669C08C34EE5D3581ED7A30C7D2FE4613D20000*43

2020-01-09T01:06:21.786Z,1578531981.786 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-09T01:06:22.183Z,1578531982.183 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-09T01:06:23.411Z,1578531983.411 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-09T01:06:23.813Z,1578531983.813 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,010621.719413,3,0,200,4000,10000,1,1,12,1,1,1,3,47*46

2020-01-09T01:06:24.615Z,1578531984.615 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T01:06:24.616Z,1578531984.616 [Micromodem](INFO): Sent 39 bytes from file Logs/20200109T005301/Courier0004.lzma.parts
2020-01-09T01:06:24.616Z,1578531984.616 [Micromodem](INFO): Packets left to send: 0
2020-01-09T01:06:25.014Z,1578531985.014 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109010623.715782,06,228,23,0093,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,10.9,6.00,13,-3.00,-01,-0.03,36,10000,4000*45

2020-01-09T01:06:25.975Z,1578531985.975 [Micromodem](INFO): Outgoing frame #1, 64 bytes: E47B165E000001005D000004002D0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-09T01:06:25.975Z,1578531985.975 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367FE6
2020-01-09T01:06:25.976Z,1578531985.976 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 7350BAAC413336A5231F1CA13E85244E0C1097C5DBD8BEF5653D2154B3C246970529C1F74E174AF88403AA59A6FC08401395A23E6073ECDDECAB48D9939DF49C
2020-01-09T01:06:25.976Z,1578531985.976 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T01:06:26.955Z,1578531986.955 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T01:06:27.387Z,1578531987.387 [Micromodem](INFO): Nmea in: $CADRQ,010626,1,12,0,64,1*77

2020-01-09T01:06:27.387Z,1578531987.387 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E47B165E000001005D000004002D0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*49

2020-01-09T01:06:27.553Z,1578531987.553 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20200109T010332/Courier0000.lzma
2020-01-09T01:06:27.763Z,1578531987.763 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T01:06:28.171Z,1578531988.171 [Micromodem](INFO): Nmea in: $CADRQ,010627,1,12,0,64,2*75

2020-01-09T01:06:28.172Z,1578531988.172 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367FE6*3F

2020-01-09T01:06:28.571Z,1578531988.571 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T01:06:28.870Z,1578531988.870 [DataOverHttps](INFO): Moved sent file to Logs/20200109T010332/Courier0000.lzma.bak
2020-01-09T01:06:28.871Z,1578531988.871 [DataOverHttps](INFO): SBD MOMSN=12187800
2020-01-09T01:06:28.980Z,1578531988.980 [Micromodem](INFO): Nmea in: $CADRQ,010628,1,12,0,64,3*7B

2020-01-09T01:06:28.982Z,1578531988.982 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,7350BAAC413336A5231F1CA13E85244E0C1097C5DBD8BEF5653D2154B3C246970529C1F74E174AF88403AA59A6FC08401395A23E6073ECDDECAB48D9939DF49C*31

2020-01-09T01:06:29.060Z,1578531989.060 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T01:06:29.060Z,1578531989.060 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,

2020-01-09T01:06:29.379Z,1578531989.379 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T01:06:29.783Z,1578531989.783 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T01:06:33.419Z,1578531993.419 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T01:06:33.820Z,1578531993.820 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,010629.362665,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7C

2020-01-09T01:06:34.636Z,1578531994.636 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T01:06:35.035Z,1578531995.035 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T01:06:35.443Z,1578531995.443 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T01:06:35.443Z,1578531995.443 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T010332/Courier0000.lzma.parts
2020-01-09T01:06:35.444Z,1578531995.444 [Micromodem](INFO): Packets left to send: 1
2020-01-09T01:06:35.846Z,1578531995.846 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109010633.917199,06,662,28,0113,0150,89,02,02,00,04,1,012,001,0,4,1,0,150,25.4,6.00,13,-3.00,-01,-0.03,27,10000,4000*4A

2020-01-09T01:06:36.873Z,1578531996.873 [Startup:StartupSatComms:B] Stopped
2020-01-09T01:06:36.874Z,1578531996.874 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-09T01:06:36.874Z,1578531996.874 [Startup:StartupSatComms] Stopped
2020-01-09T01:06:36.874Z,1578531996.874 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-09T01:06:36.874Z,1578531996.874 [Startup](INFO): Completed Startup
2020-01-09T01:06:36.875Z,1578531996.875 [MissionManager](INFO): Startup is completed.
2020-01-09T01:06:36.875Z,1578531996.875 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-09T01:06:36.875Z,1578531996.875 [Startup] Stopped
2020-01-09T01:06:36.875Z,1578531996.875 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-09T01:06:36.875Z,1578531996.875 [Startup:A.GoToSurface] Stopped
2020-01-09T01:06:36.875Z,1578531996.875 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-09T01:06:37.023Z,1578531997.023 [MissionManager](IMPORTANT): Started mission Default
2020-01-09T01:06:37.023Z,1578531997.023 [Default] Running Loop=1
2020-01-09T01:06:37.023Z,1578531997.023 [Default](DEBUG): Aggregate::initialize Default
2020-01-09T01:06:37.023Z,1578531997.023 [Default:B.GoToSurface] Running Loop=1
2020-01-09T01:06:37.023Z,1578531997.023 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-09T01:06:37.024Z,1578531997.024 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-09T01:06:37.024Z,1578531997.024 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-09T01:06:37.024Z,1578531997.024 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-09T01:06:37.029Z,1578531997.029 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-09T01:06:37.029Z,1578531997.029 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-09T01:06:37.030Z,1578531997.030 [Default:A.Wait] Running Loop=1
2020-01-09T01:06:37.030Z,1578531997.030 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-09T01:06:44.788Z,1578532004.788 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-09T01:06:44.788Z,1578532004.788 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T01:06:44.802Z,1578532004.802 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T01:06:45.105Z,1578532005.105 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T01:06:45.105Z,1578532005.105 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-09T01:06:48.162Z,1578532008.162 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20200109T000230/Express0005.lzma
2020-01-09T01:06:50.175Z,1578532010.175 [DataOverHttps](INFO): Moved sent file to Logs/20200109T000230/Express0005.lzma.bak
2020-01-09T01:06:50.175Z,1578532010.175 [DataOverHttps](INFO): SBD MOMSN=12187805
2020-01-09T01:06:50.376Z,1578532010.376 [Default:A.Wait](INFO): Done Waiting.
2020-01-09T01:06:50.376Z,1578532010.376 [Default:A.Wait] Stopped
2020-01-09T01:06:50.376Z,1578532010.376 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T01:06:50.789Z,1578532010.789 [Default:CheckIn] Running Loop=1
2020-01-09T01:06:50.789Z,1578532010.789 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T01:06:50.789Z,1578532010.789 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T01:06:51.112Z,1578532011.112 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-09T01:07:59.027Z,1578532079.027 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T01:07:59.027Z,1578532079.027 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3768,-32768,V
2020-01-09T01:08:04.250Z,1578532084.250 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-09T01:08:57.582Z,1578532137.582 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-09T01:09:00.391Z,1578532140.391 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T01:09:00.392Z,1578532140.392 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010818165489,35.0, -0.1,   0.,  0
2020-01-09T01:09:40.397Z,1578532180.397 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-09T01:09:45.664Z,1578532185.664 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-09T01:09:45.664Z,1578532185.664 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T01:09:45.674Z,1578532185.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T01:09:46.117Z,1578532186.117 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T01:09:46.117Z,1578532186.117 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-09T01:10:44.197Z,1578532244.197 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010548.00,A,3648.16610,N,12147.27788,W,1.283,10.82,090120,,,A*47
2020-01-09T01:10:44.200Z,1578532244.200 [NAL9602](INFO): GPS fix at 20200109T010548: (36.802768, -121.787965)
2020-01-09T01:10:44.256Z,1578532244.256 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T01:10:44.256Z,1578532244.256 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T01:10:44.669Z,1578532244.669 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-09T01:10:45.925Z,1578532245.925 [Micromodem](INFO): Outgoing frame #1, 64 bytes: E47B165E040001005D00000400360300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567
2020-01-09T01:10:45.925Z,1578532245.925 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31
2020-01-09T01:10:45.926Z,1578532245.926 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3
2020-01-09T01:10:45.926Z,1578532245.926 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T01:10:46.486Z,1578532246.486 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T01:10:46.879Z,1578532246.879 [Micromodem](INFO): Nmea in: $CADRQ,011046,1,12,0,64,1*76

2020-01-09T01:10:46.879Z,1578532246.879 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E47B165E040001005D00000400360300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567*41

2020-01-09T01:10:47.294Z,1578532247.294 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T01:10:47.686Z,1578532247.686 [Micromodem](INFO): Nmea in: $CADRQ,011047,1,12,0,64,2*74

2020-01-09T01:10:47.687Z,1578532247.687 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31*3C

2020-01-09T01:10:48.090Z,1578532248.090 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T01:10:48.494Z,1578532248.494 [Micromodem](INFO): Nmea in: $CADRQ,011048,1,12,0,64,3*7A

2020-01-09T01:10:48.495Z,1578532248.495 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3*39

2020-01-09T01:10:48.942Z,1578532248.942 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T01:10:49.318Z,1578532249.318 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T01:10:52.950Z,1578532252.950 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T01:10:53.355Z,1578532253.355 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,011048.879089,3,0,200,4000,10000,1,1,12,1,3,3,3,192*79

2020-01-09T01:10:54.184Z,1578532254.184 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T01:10:54.573Z,1578532254.573 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T01:10:54.975Z,1578532254.975 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T01:10:54.975Z,1578532254.975 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T010332/Courier0004.lzma.parts
2020-01-09T01:10:54.975Z,1578532254.975 [Micromodem](INFO): Packets left to send: 1
2020-01-09T01:10:55.378Z,1578532255.378 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109011053.435339,06,678,28,0111,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,25.4,6.00,14,-3.00,-01,-0.03,22,10000,4000*4F

2020-01-09T01:10:55.433Z,1578532255.433 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20200109T010332/Courier0004.lzma
2020-01-09T01:10:57.064Z,1578532257.064 [Micromodem](INFO): Outgoing frame #1, 64 bytes: E47B165E04000000F62E8CD8314AF937CD3E036D68446B0032E0ED1F3A084B1D72EC21807D8A770EEEFB838C8BE7F25C95905379D0DA1B24C2CA60DEAFB478FB
2020-01-09T01:10:57.065Z,1578532257.065 [Micromodem](INFO): Outgoing frame #2, 39 bytes: 437DE02878E29B08404190246D1715F90331D53227BF32BEC1B1A97CF1CEE60A29D634F2586500
2020-01-09T01:10:57.065Z,1578532257.065 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-09T01:10:57.438Z,1578532257.438 [DataOverHttps](INFO): Moved sent file to Logs/20200109T010332/Courier0004.lzma.bak
2020-01-09T01:10:57.438Z,1578532257.438 [DataOverHttps](INFO): SBD MOMSN=12187814
2020-01-09T01:10:58.026Z,1578532258.026 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-09T01:10:58.407Z,1578532258.407 [Micromodem](INFO): Nmea in: $CADRQ,011057,1,12,0,64,1*76

2020-01-09T01:10:58.407Z,1578532258.407 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E47B165E04000000F62E8CD8314AF937CD3E036D68446B0032E0ED1F3A084B1D72EC21807D8A770EEEFB838C8BE7F25C95905379D0DA1B24C2CA60DEAFB478FB*3F

2020-01-09T01:10:58.810Z,1578532258.810 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T01:10:59.211Z,1578532259.211 [Micromodem](INFO): Nmea in: $CADRQ,011058,1,12,0,64,2*7A

2020-01-09T01:10:59.211Z,1578532259.211 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,437DE02878E29B08404190246D1715F90331D53227BF32BEC1B1A97CF1CEE60A29D634F2586500*46

2020-01-09T01:10:59.615Z,1578532259.615 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,39*43

2020-01-09T01:11:00.030Z,1578532260.030 [Micromodem](INFO): Nmea in: $CATXP,103*40

2020-01-09T01:11:02.454Z,1578532262.454 [Micromodem](INFO): Nmea in: $CATXF,103*56

2020-01-09T01:11:02.847Z,1578532262.847 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,011059.552988,3,0,200,4000,10000,1,1,12,1,2,2,3,103*7D

2020-01-09T01:11:03.655Z,1578532263.655 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T01:11:04.120Z,1578532264.120 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T01:11:04.125Z,1578532264.125 [Micromodem](INFO): Sent 95 bytes from file Logs/20200109T010332/Courier0004.lzma.parts
2020-01-09T01:11:04.126Z,1578532264.126 [Micromodem](INFO): Packets left to send: 0
2020-01-09T01:11:04.466Z,1578532264.466 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109011102.811615,06,593,27,0126,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,25.4,6.00,16,-3.00,-01,-0.05,27,10000,4000*4A

2020-01-09T01:11:05.540Z,1578532265.540 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T01:11:05.540Z,1578532265.540 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T01:11:05.540Z,1578532265.540 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T01:11:16.491Z,1578532276.491 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T01:11:17.306Z,1578532277.306 [DataOverHttps](INFO): Sending 293 bytes from file Logs/20200109T000230/Express0008.lzma
2020-01-09T01:11:19.310Z,1578532279.310 [DataOverHttps](INFO): Moved sent file to Logs/20200109T000230/Express0008.lzma.bak
2020-01-09T01:11:19.311Z,1578532279.311 [DataOverHttps](INFO): SBD MOMSN=12187817
2020-01-09T01:11:24.998Z,1578532284.998 [BPC1](ERROR): Battery stick #26 (s/n: 0141) reported OVER_TEMP_ALARM. Status code: 0x1717.
2020-01-09T01:11:24.999Z,1578532284.999 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2020-01-09T01:11:25.002Z,1578532285.002 [BPC1](INFO): Received data from all battery sticks.
2020-01-09T01:16:05.809Z,1578532565.809 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T01:16:05.809Z,1578532565.809 [Default:CheckIn:C.Wait] Stopped
2020-01-09T01:16:05.809Z,1578532565.809 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T01:16:05.809Z,1578532565.809 [Default:CheckIn:D] Running Loop=1
2020-01-09T01:16:06.197Z,1578532566.197 [Default:CheckIn:D] Stopped
2020-01-09T01:16:06.197Z,1578532566.197 [Default:CheckIn:E] Running Loop=1
2020-01-09T01:16:06.615Z,1578532566.615 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.486231 min 
2020-01-09T01:16:06.615Z,1578532566.615 [Default:CheckIn:E] Stopped
2020-01-09T01:16:06.615Z,1578532566.615 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T01:16:06.615Z,1578532566.615 [Default:CheckIn] Stopped
2020-01-09T01:16:06.616Z,1578532566.616 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T01:16:06.616Z,1578532566.616 [Default:CheckIn](INFO): Running loop #2
2020-01-09T01:16:06.616Z,1578532566.616 [Default:CheckIn] Running Loop=2
2020-01-09T01:16:06.616Z,1578532566.616 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T01:16:06.616Z,1578532566.616 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T01:16:08.597Z,1578532568.597 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011112.00,A,3648.17476,N,12147.28283,W,0.350,0.00,090120,,,A*7A
2020-01-09T01:16:08.599Z,1578532568.599 [NAL9602](INFO): GPS fix at 20200109T011112: (36.802913, -121.788047)
2020-01-09T01:16:08.649Z,1578532568.649 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T01:16:08.649Z,1578532568.649 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T01:16:10.449Z,1578532570.449 [Micromodem](INFO): Outgoing frame #1, 64 bytes: E47B165E070001005D00000400AD0100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-09T01:16:10.449Z,1578532570.449 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F
2020-01-09T01:16:10.450Z,1578532570.450 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D4675F7A182D6623EF174EB8C047E60418
2020-01-09T01:16:10.450Z,1578532570.450 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T01:16:10.638Z,1578532570.638 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T01:16:11.014Z,1578532571.014 [Micromodem](INFO): Nmea in: $CADRQ,011610,1,12,0,64,1*73

2020-01-09T01:16:11.014Z,1578532571.014 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E47B165E070001005D00000400AD0100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*39

2020-01-09T01:16:11.394Z,1578532571.394 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T01:16:11.805Z,1578532571.805 [Micromodem](INFO): Nmea in: $CADRQ,011611,1,12,0,64,2*71

2020-01-09T01:16:11.806Z,1578532571.806 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*3D

2020-01-09T01:16:12.199Z,1578532572.199 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T01:16:12.607Z,1578532572.607 [Micromodem](INFO): Nmea in: $CADRQ,011612,1,12,0,64,3*73

2020-01-09T01:16:12.607Z,1578532572.607 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D4675F7A182D6623EF174EB8C047E60418*46

2020-01-09T01:16:13.039Z,1578532573.039 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T01:16:13.414Z,1578532573.414 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T01:16:16.617Z,1578532576.617 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20200109T010332/Courier0007.lzma
2020-01-09T01:16:17.050Z,1578532577.050 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T01:16:17.456Z,1578532577.456 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,011612.991377,3,0,200,4000,10000,1,1,12,1,3,3,3,192*75

2020-01-09T01:16:18.622Z,1578532578.622 [DataOverHttps](INFO): Moved sent file to Logs/20200109T010332/Courier0007.lzma.bak
2020-01-09T01:16:18.622Z,1578532578.622 [DataOverHttps](INFO): SBD MOMSN=12187824
2020-01-09T01:16:32.416Z,1578532592.416 [NAL9602](INFO): SBD MO Status=2, MOMSN=10150, MT Status=2, MTMSN=0
2020-01-09T01:16:32.417Z,1578532592.417 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T01:16:34.450Z,1578532594.450 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T01:16:34.450Z,1578532594.450 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010818242889,35.0, -0.1,   0.,  0
2020-01-09T01:16:36.970Z,1578532596.970 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20200109T000230/Express0011.lzma
2020-01-09T01:16:38.977Z,1578532598.977 [DataOverHttps](INFO): Moved sent file to Logs/20200109T000230/Express0011.lzma.bak
2020-01-09T01:16:38.977Z,1578532598.977 [DataOverHttps](INFO): SBD MOMSN=12187827
2020-01-09T01:16:56.798Z,1578532616.798 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20200109T000230/Express0014.lzma
2020-01-09T01:16:58.802Z,1578532618.802 [DataOverHttps](INFO): Moved sent file to Logs/20200109T000230/Express0014.lzma.bak
2020-01-09T01:16:58.803Z,1578532618.803 [DataOverHttps](INFO): SBD MOMSN=12187833
2020-01-09T01:16:59.076Z,1578532619.076 [NAL9602](INFO): SBD MO Status=2, MOMSN=10150, MT Status=2, MTMSN=0
2020-01-09T01:16:59.077Z,1578532619.077 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T01:17:15.634Z,1578532635.634 [Micromodem](ERROR): Buffer fill timout failure.
2020-01-09T01:17:16.968Z,1578532636.968 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T01:17:16.968Z,1578532636.968 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T01:17:16.968Z,1578532636.968 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T01:17:17.944Z,1578532637.944 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20200109T000230/Express0017.lzma
2020-01-09T01:17:19.926Z,1578532639.926 [DataOverHttps](INFO): Moved sent file to Logs/20200109T000230/Express0017.lzma.bak
2020-01-09T01:17:19.927Z,1578532639.927 [DataOverHttps](INFO): SBD MOMSN=12187837
2020-01-09T01:17:39.240Z,1578532659.240 [NAL9602](INFO): SBD MO Status=0, MOMSN=10150, MT Status=0, MTMSN=0
2020-01-09T01:17:39.240Z,1578532659.240 [NAL9602](INFO): No messages in MT queue
2020-01-09T01:17:48.172Z,1578532668.172 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T01:17:48.172Z,1578532668.172 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,2001081825428.97
2020-01-09T01:18:09.947Z,1578532689.947 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T01:18:27.748Z,1578532707.748 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T01:18:27.748Z,1578532707.748 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3278,-32768,V
2020-01-09T01:18:41.855Z,1578532721.855 [CommandLine](IMPORTANT): got command quit
2020-01-09T01:18:42.857Z,1578532722.857 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T01:18:42.857Z,1578532722.857 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:42.897Z,1578532722.897 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-09T01:18:42.897Z,1578532722.897 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:42.898Z,1578532722.898 [CommandLine](INFO): Join timeout helper Thread ID is 937
2020-01-09T01:18:42.898Z,1578532722.898 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-09T01:18:42.898Z,1578532722.898 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:42.899Z,1578532722.899 [NavChartDb](INFO): Join timeout helper Thread ID is 938
2020-01-09T01:18:42.926Z,1578532722.926 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T01:18:42.926Z,1578532722.926 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:42.937Z,1578532722.937 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-09T01:18:42.937Z,1578532722.937 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:42.937Z,1578532722.937 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 939
2020-01-09T01:18:43.049Z,1578532723.049 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T01:18:43.049Z,1578532723.049 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-09T01:18:43.050Z,1578532723.050 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.069Z,1578532723.069 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-09T01:18:43.069Z,1578532723.069 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.070Z,1578532723.070 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 940
2020-01-09T01:18:43.253Z,1578532723.253 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T01:18:43.253Z,1578532723.253 [CTD_NeilBrown](INFO): Powering down
2020-01-09T01:18:43.265Z,1578532723.265 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.269Z,1578532723.269 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-09T01:18:43.269Z,1578532723.269 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.269Z,1578532723.269 [Radio_Surface](INFO): Join timeout helper Thread ID is 941
2020-01-09T01:18:43.297Z,1578532723.297 [Radio_Surface](INFO): Powering down
2020-01-09T01:18:43.298Z,1578532723.298 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T01:18:43.298Z,1578532723.298 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.310Z,1578532723.310 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-09T01:18:43.310Z,1578532723.310 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.310Z,1578532723.310 [DataOverHttps](INFO): Join timeout helper Thread ID is 942
2020-01-09T01:18:43.473Z,1578532723.473 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T01:18:43.473Z,1578532723.473 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.494Z,1578532723.494 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-09T01:18:43.494Z,1578532723.494 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.494Z,1578532723.494 [logger](INFO): Join timeout helper Thread ID is 943
2020-01-09T01:18:43.540Z,1578532723.540 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T01:18:43.540Z,1578532723.540 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.558Z,1578532723.558 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-09T01:18:43.558Z,1578532723.558 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.558Z,1578532723.558 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-09T01:18:43.558Z,1578532723.558 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:43.558Z,1578532723.558 [controlThread](INFO): Join timeout helper Thread ID is 944
2020-01-09T01:18:43.861Z,1578532723.861 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T01:18:43.861Z,1578532723.861 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-09T01:18:43.861Z,1578532723.861 [AHRS_M2](INFO): Powering down
2020-01-09T01:18:43.933Z,1578532723.933 [DDM](INFO): Powering down
2020-01-09T01:18:44.026Z,1578532724.026 [DUSBL_Hydroid](INFO): Powering down
2020-01-09T01:18:44.137Z,1578532724.137 [Micromodem](INFO): Powering down
2020-01-09T01:18:44.233Z,1578532724.233 [NAL9602](INFO): Powering down
2020-01-09T01:18:44.305Z,1578532724.305 [RDI_Pathfinder](INFO): Powering down
2020-01-09T01:18:44.306Z,1578532724.306 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-09T01:18:44.307Z,1578532724.307 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-09T01:18:44.308Z,1578532724.308 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-09T01:18:44.308Z,1578532724.308 [MissionManager](INFO): Uninitializing Mission Default
2020-01-09T01:18:44.308Z,1578532724.308 [Default] Stopped
2020-01-09T01:18:44.308Z,1578532724.308 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-09T01:18:44.308Z,1578532724.308 [Default:B.GoToSurface] Stopped
2020-01-09T01:18:44.309Z,1578532724.309 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-09T01:18:44.309Z,1578532724.309 [Default:CheckIn] Stopped
2020-01-09T01:18:44.309Z,1578532724.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T01:18:44.309Z,1578532724.309 [Default:CheckIn:C.Wait] Stopped
2020-01-09T01:18:44.309Z,1578532724.309 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T01:18:44.311Z,1578532724.311 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-09T01:18:44.312Z,1578532724.312 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-09T01:18:44.312Z,1578532724.312 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-09T01:18:44.312Z,1578532724.312 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-09T01:18:44.312Z,1578532724.312 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-09T01:18:44.312Z,1578532724.312 [BuoyancyServo](INFO): Powering down
2020-01-09T01:18:44.325Z,1578532724.325 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-09T01:18:44.325Z,1578532724.325 [ElevatorServo](INFO): Powering down
2020-01-09T01:18:44.326Z,1578532724.326 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-09T01:18:44.326Z,1578532724.326 [MassServo](INFO): Powering down
2020-01-09T01:18:44.327Z,1578532724.327 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-09T01:18:44.327Z,1578532724.327 [RudderServo](INFO): Powering down
2020-01-09T01:18:44.327Z,1578532724.327 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-09T01:18:44.328Z,1578532724.328 [ThrusterServo](INFO): Powering down
2020-01-09T01:18:44.328Z,1578532724.328 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-09T01:18:44.329Z,1578532724.329 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-09T01:18:44.329Z,1578532724.329 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-09T01:18:44.329Z,1578532724.329 [CBIT](DEBUG): Powering off loads.
2020-01-09T01:18:44.341Z,1578532724.341 [CBIT](DEBUG): Disabling WDT.
2020-01-09T01:18:44.353Z,1578532724.353 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-09T01:18:44.354Z,1578532724.354 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:44.400Z,1578532724.400 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:44.411Z,1578532724.411 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:44.454Z,1578532724.454 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:44.457Z,1578532724.457 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:44.516Z,1578532724.516 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T01:18:44.589Z,1578532724.589 [logger ThreadHandler](INFO): Thread cancelled.