2024-05-10T19:56:53.384Z,1715371013.384 [Supervisor](DEBUG): Initializing supervisor.
2024-05-10T19:56:53.389Z,1715371013.389 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-05-10T19:56:53.389Z,1715371013.389 [SyncHandler](INFO): Protected caller Thread ID is 830
2024-05-10T19:56:53.390Z,1715371013.390 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-05-10T19:56:53.391Z,1715371013.391 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-05-10T19:56:53.391Z,1715371013.391 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 831
2024-05-10T19:56:53.395Z,1715371013.395 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-05-10T19:56:53.414Z,1715371013.414 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-05-10T19:56:53.415Z,1715371013.415 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-05-10T19:56:53.415Z,1715371013.415 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 832
2024-05-10T19:56:53.420Z,1715371013.420 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-05-10T19:56:53.421Z,1715371013.421 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-05-10T19:56:53.421Z,1715371013.421 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 833
2024-05-10T19:56:53.423Z,1715371013.423 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-05-10T19:56:53.424Z,1715371013.424 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-05-10T19:56:53.425Z,1715371013.425 [logger ThreadHandler](INFO): Protected caller Thread ID is 834
2024-05-10T19:56:53.428Z,1715371013.428 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-05-10T19:56:53.429Z,1715371013.429 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-05-10T19:56:53.434Z,1715371013.434 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-05-10T19:56:53.545Z,1715371013.545 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-05-10T19:56:53.547Z,1715371013.547 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-05-10T19:56:53.806Z,1715371013.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-05-10T19:56:53.808Z,1715371013.808 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-05-10T19:56:54.114Z,1715371014.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-05-10T19:56:54.115Z,1715371014.115 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-05-10T19:56:54.237Z,1715371014.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-05-10T19:56:54.239Z,1715371014.239 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-05-10T19:56:55.062Z,1715371015.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-05-10T19:56:55.064Z,1715371015.064 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-05-10T19:56:55.622Z,1715371015.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-05-10T19:56:55.623Z,1715371015.623 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-05-10T19:56:56.216Z,1715371016.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-05-10T19:56:56.217Z,1715371016.217 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-05-10T19:56:56.960Z,1715371016.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-05-10T19:56:56.997Z,1715371016.997 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-05-10T19:56:57.567Z,1715371017.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-05-10T19:56:57.569Z,1715371017.569 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-05-10T19:56:57.983Z,1715371017.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-05-10T19:56:57.985Z,1715371017.985 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-05-10T19:56:58.237Z,1715371018.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-05-10T19:56:58.238Z,1715371018.238 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-05-10T19:56:58.398Z,1715371018.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-05-10T19:56:58.399Z,1715371018.399 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-05-10T19:56:58.493Z,1715371018.493 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-05-10T19:56:58.586Z,1715371018.586 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-05-10T19:56:58.800Z,1715371018.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-05-10T19:56:58.801Z,1715371018.801 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-05-10T19:56:59.059Z,1715371019.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-05-10T19:56:59.061Z,1715371019.061 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2024-05-10T19:56:59.065Z,1715371019.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2024-05-10T19:56:59.162Z,1715371019.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2024-05-10T19:56:59.446Z,1715371019.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-05-10T19:56:59.446Z,1715371019.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2024-05-10T19:56:59.559Z,1715371019.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2024-05-10T19:56:59.745Z,1715371019.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2024-05-10T19:56:59.858Z,1715371019.858 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2024-05-10T19:56:59.987Z,1715371019.987 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2024-05-10T19:57:00.116Z,1715371020.116 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2024-05-10T19:57:00.238Z,1715371020.238 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2024-05-10T19:57:00.421Z,1715371020.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2024-05-10T19:57:00.533Z,1715371020.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2024-05-10T19:57:00.674Z,1715371020.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2024-05-10T19:57:00.777Z,1715371020.777 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-05-10T19:57:00.789Z,1715371020.789 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-05-10T19:57:01.006Z,1715371021.006 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-05-10T19:57:01.007Z,1715371021.007 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-05-10T19:57:01.198Z,1715371021.198 [BuoyancyServo] Loaded
2024-05-10T19:57:01.198Z,1715371021.198 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-05-10T19:57:01.220Z,1715371021.220 [ElevatorServo] Loaded
2024-05-10T19:57:01.221Z,1715371021.221 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-05-10T19:57:01.242Z,1715371021.242 [MassServo] Loaded
2024-05-10T19:57:01.242Z,1715371021.242 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-05-10T19:57:01.263Z,1715371021.263 [RudderServo] Loaded
2024-05-10T19:57:01.264Z,1715371021.264 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-05-10T19:57:01.280Z,1715371021.280 [ThrusterHE] Loaded
2024-05-10T19:57:01.280Z,1715371021.280 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-05-10T19:57:01.280Z,1715371021.280 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-05-10T19:57:01.282Z,1715371021.282 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-05-10T19:57:01.454Z,1715371021.454 [SBIT](DEBUG): Construct Startup Built In Test.
2024-05-10T19:57:01.470Z,1715371021.470 [SBIT] Loaded
2024-05-10T19:57:01.470Z,1715371021.470 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-05-10T19:57:01.473Z,1715371021.473 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-05-10T19:57:01.495Z,1715371021.495 [IBIT] Loaded
2024-05-10T19:57:01.495Z,1715371021.495 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-05-10T19:57:01.501Z,1715371021.501 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-05-10T19:57:01.729Z,1715371021.729 [CBIT] Loaded
2024-05-10T19:57:01.729Z,1715371021.729 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-05-10T19:57:01.730Z,1715371021.730 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-05-10T19:57:01.730Z,1715371021.730 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-05-10T19:57:01.952Z,1715371021.952 [CTD_Seabird] Loaded
2024-05-10T19:57:01.952Z,1715371021.952 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-05-10T19:57:01.953Z,1715371021.953 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0
2024-05-10T19:57:01.954Z,1715371021.954 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 919
2024-05-10T19:57:01.987Z,1715371021.987 [ESPComponent] Loaded
2024-05-10T19:57:01.987Z,1715371021.987 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-05-10T19:57:02.008Z,1715371022.008 [PAR_Licor] Loaded
2024-05-10T19:57:02.008Z,1715371022.008 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-05-10T19:57:02.052Z,1715371022.052 [WetLabsBB2FL] Loaded
2024-05-10T19:57:02.052Z,1715371022.052 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-05-10T19:57:02.053Z,1715371022.053 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0
2024-05-10T19:57:02.053Z,1715371022.053 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 920
2024-05-10T19:57:02.054Z,1715371022.054 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-05-10T19:57:02.056Z,1715371022.056 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-05-10T19:57:02.581Z,1715371022.581 [DepthRateCalculator] Loaded
2024-05-10T19:57:02.582Z,1715371022.582 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-05-10T19:57:02.587Z,1715371022.587 [PitchRateCalculator] Loaded
2024-05-10T19:57:02.588Z,1715371022.588 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-05-10T19:57:02.598Z,1715371022.598 [SpeedCalculator] Loaded
2024-05-10T19:57:02.599Z,1715371022.599 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-05-10T19:57:02.606Z,1715371022.606 [YawRateCalculator] Loaded
2024-05-10T19:57:02.606Z,1715371022.606 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-05-10T19:57:02.630Z,1715371022.630 [ElevatorOffsetCalculator] Loaded
2024-05-10T19:57:02.630Z,1715371022.630 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-05-10T19:57:02.631Z,1715371022.631 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-05-10T19:57:02.631Z,1715371022.631 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-05-10T19:57:02.779Z,1715371022.779 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-05-10T19:57:02.780Z,1715371022.780 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-05-10T19:57:02.824Z,1715371022.824 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-05-10T19:57:02.824Z,1715371022.824 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-05-10T19:57:02.964Z,1715371022.964 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-05-10T19:57:02.965Z,1715371022.965 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-05-10T19:57:02.979Z,1715371022.979 [NavChart] Loaded
2024-05-10T19:57:02.979Z,1715371022.979 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-05-10T19:57:02.985Z,1715371022.985 [UniversalFixResidualReporter] Loaded
2024-05-10T19:57:02.986Z,1715371022.986 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-05-10T19:57:02.986Z,1715371022.986 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-05-10T19:57:02.987Z,1715371022.987 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-05-10T19:57:03.820Z,1715371023.820 [AHRS_M2] Loaded
2024-05-10T19:57:03.821Z,1715371023.821 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-05-10T19:57:04.071Z,1715371024.071 [BackseatComponent] Loaded
2024-05-10T19:57:04.071Z,1715371024.071 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-05-10T19:57:04.072Z,1715371024.072 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0
2024-05-10T19:57:04.073Z,1715371024.073 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2024-05-10T19:57:04.075Z,1715371024.075 [LcmUniversalReporter] Loaded
2024-05-10T19:57:04.076Z,1715371024.076 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-05-10T19:57:04.827Z,1715371024.827 [BPC1] Loaded
2024-05-10T19:57:04.827Z,1715371024.827 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-05-10T19:57:04.969Z,1715371024.969 [DataOverHttps] Loaded
2024-05-10T19:57:04.970Z,1715371024.970 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-05-10T19:57:04.971Z,1715371024.971 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0
2024-05-10T19:57:04.971Z,1715371024.971 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2024-05-10T19:57:04.992Z,1715371024.992 [Depth_Keller] Loaded
2024-05-10T19:57:04.993Z,1715371024.993 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-05-10T19:57:04.998Z,1715371024.998 [DropWeight] Loaded
2024-05-10T19:57:04.998Z,1715371024.998 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-05-10T19:57:05.038Z,1715371025.038 [DVL_micro] Loaded
2024-05-10T19:57:05.039Z,1715371025.039 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2024-05-10T19:57:05.099Z,1715371025.099 [NAL9602] Loaded
2024-05-10T19:57:05.100Z,1715371025.100 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-05-10T19:57:05.126Z,1715371025.126 [Onboard] Loaded
2024-05-10T19:57:05.126Z,1715371025.126 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-05-10T19:57:05.127Z,1715371025.127 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AB34E0
2024-05-10T19:57:05.128Z,1715371025.128 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2024-05-10T19:57:05.140Z,1715371025.140 [Power24vConverter] Loaded
2024-05-10T19:57:05.141Z,1715371025.141 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-05-10T19:57:05.154Z,1715371025.154 [Radio_Surface] Loaded
2024-05-10T19:57:05.154Z,1715371025.154 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-05-10T19:57:05.155Z,1715371025.155 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AE34E0
2024-05-10T19:57:05.156Z,1715371025.156 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2024-05-10T19:57:05.170Z,1715371025.170 [Sonardyne_Nano] Loaded
2024-05-10T19:57:05.170Z,1715371025.170 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-05-10T19:57:05.266Z,1715371025.266 [DAT] Loaded
2024-05-10T19:57:05.267Z,1715371025.267 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-05-10T19:57:05.268Z,1715371025.268 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B134E0
2024-05-10T19:57:05.268Z,1715371025.268 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925
2024-05-10T19:57:05.269Z,1715371025.269 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-05-10T19:57:05.269Z,1715371025.269 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-05-10T19:57:05.351Z,1715371025.351 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-05-10T19:57:05.353Z,1715371025.353 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-05-10T19:57:05.427Z,1715371025.427 [VerticalControl](DEBUG): Construct VerticalControl.
2024-05-10T19:57:05.481Z,1715371025.481 [VerticalControl] Loaded
2024-05-10T19:57:05.481Z,1715371025.481 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-05-10T19:57:05.484Z,1715371025.484 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-05-10T19:57:05.530Z,1715371025.530 [HorizontalControl] Loaded
2024-05-10T19:57:05.530Z,1715371025.530 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-05-10T19:57:05.533Z,1715371025.533 [SpeedControl](DEBUG): Construct SpeedControl.
2024-05-10T19:57:05.535Z,1715371025.535 [SpeedControl] Loaded
2024-05-10T19:57:05.535Z,1715371025.535 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-05-10T19:57:05.538Z,1715371025.538 [LoopControl](DEBUG): Construct LoopControl.
2024-05-10T19:57:05.538Z,1715371025.538 [LoopControl] Loaded
2024-05-10T19:57:05.539Z,1715371025.539 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-05-10T19:57:05.539Z,1715371025.539 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-05-10T19:57:05.540Z,1715371025.540 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-05-10T19:57:05.556Z,1715371025.556 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-05-10T19:57:05.557Z,1715371025.557 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-05-10T19:57:05.961Z,1715371025.961 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-05-10T19:57:05.968Z,1715371025.968 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-05-10T19:57:05.971Z,1715371025.971 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-05-10T19:57:05.983Z,1715371025.983 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-05-10T19:57:05.984Z,1715371025.984 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0
2024-05-10T19:57:05.984Z,1715371025.984 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926
2024-05-10T19:57:05.989Z,1715371025.989 [Supervisor](INFO): Main Thread ID is 823
2024-05-10T19:57:05.989Z,1715371025.989 [Supervisor](DEBUG): Running supervisor.
2024-05-10T19:57:05.990Z,1715371025.990 [CommandExec ThreadHandler](INFO): Handler Thread ID is 927
2024-05-10T19:57:05.990Z,1715371025.990 [CommandExec](INFO): Initializing the command executive.
2024-05-10T19:57:05.992Z,1715371025.992 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928
2024-05-10T19:57:05.994Z,1715371025.994 [controlThread ThreadHandler](INFO): Handler Thread ID is 929
2024-05-10T19:57:05.994Z,1715371025.994 [controlThread](DEBUG): Initializing ControlThread
2024-05-10T19:57:05.996Z,1715371025.996 [SBIT](INFO): Initialize SBIT Component.
2024-05-10T19:57:05.996Z,1715371025.996 [SBIT](IMPORTANT): git: 2024-05-10
2024-05-10T19:57:05.997Z,1715371025.997 [SBIT](INFO): git hash: a134c88d686ec779525c21b16fbeda2a3b596732
2024-05-10T19:57:05.997Z,1715371025.997 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-05-10T19:57:05.998Z,1715371025.998 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-05-10T19:57:05.999Z,1715371025.999 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2024-05-10T19:57:06.000Z,1715371026.000 [IBIT](INFO): Initialize IBIT Component.
2024-05-10T19:57:06.001Z,1715371026.001 [CBIT](DEBUG): Initialize CBIT Component.
2024-05-10T19:57:06.002Z,1715371026.002 [logger ThreadHandler](INFO): Handler Thread ID is 930
2024-05-10T19:57:06.012Z,1715371026.012 [CBIT](DEBUG): Initialized mux pins.
2024-05-10T19:57:06.012Z,1715371026.012 [CBIT](DEBUG): Initializing the watchdog timer.
2024-05-10T19:57:06.020Z,1715371026.020 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 931
2024-05-10T19:57:06.021Z,1715371026.021 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-05-10T19:57:06.026Z,1715371026.026 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 933
2024-05-10T19:57:06.028Z,1715371026.028 [WetLabsBB2FL](INFO): Powering up
2024-05-10T19:57:06.030Z,1715371026.030 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2024-05-10T19:57:06.036Z,1715371026.036 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-05-10T19:57:06.036Z,1715371026.036 [CBIT](DEBUG): Initializing heartbeat.
2024-05-10T19:57:06.037Z,1715371026.037 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936
2024-05-10T19:57:06.039Z,1715371026.039 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-05-10T19:57:06.052Z,1715371026.052 [Onboard ThreadHandler](INFO): Handler Thread ID is 937
2024-05-10T19:57:06.088Z,1715371026.088 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938
2024-05-10T19:57:06.089Z,1715371026.089 [DAT ThreadHandler](INFO): Handler Thread ID is 939
2024-05-10T19:57:06.090Z,1715371026.090 [DAT](INFO): Powering up
2024-05-10T19:57:06.090Z,1715371026.090 [DAT](DEBUG): Initializing DAT.
2024-05-10T19:57:06.094Z,1715371026.094 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941
2024-05-10T19:57:06.101Z,1715371026.101 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000
2024-05-10T19:57:06.102Z,1715371026.102 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000
2024-05-10T19:57:06.102Z,1715371026.102 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000
2024-05-10T19:57:06.102Z,1715371026.102 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000
2024-05-10T19:57:06.102Z,1715371026.102 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000
2024-05-10T19:57:06.102Z,1715371026.102 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000
2024-05-10T19:57:06.108Z,1715371026.108 [CBIT](DEBUG): Deactivating GF circuits.
2024-05-10T19:57:06.108Z,1715371026.108 [CBIT](DEBUG): Deactivating emergency mode.
2024-05-10T19:57:06.148Z,1715371026.148 [CBIT](DEBUG): Backplane powered.
2024-05-10T19:57:06.152Z,1715371026.152 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-05-10T19:57:06.153Z,1715371026.153 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-05-10T19:57:06.153Z,1715371026.153 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-05-10T19:57:06.153Z,1715371026.153 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-05-10T19:57:06.154Z,1715371026.154 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-05-10T19:57:06.155Z,1715371026.155 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-05-10T19:57:06.155Z,1715371026.155 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-05-10T19:57:06.162Z,1715371026.162 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-05-10T19:57:06.168Z,1715371026.168 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-05-10T19:57:06.169Z,1715371026.169 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-05-10T19:57:06.169Z,1715371026.169 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-05-10T19:57:06.170Z,1715371026.170 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-05-10T19:57:06.171Z,1715371026.171 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-05-10T19:57:06.171Z,1715371026.171 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-05-10T19:57:06.177Z,1715371026.177 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-05-10T19:57:06.258Z,1715371026.258 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-05-10T19:57:06.282Z,1715371026.282 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-10T19:57:06.307Z,1715371026.307 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-05-10T19:57:06.332Z,1715371026.332 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-05-10T19:57:06.332Z,1715371026.332 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-05-10T19:57:06.342Z,1715371026.342 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-05-10T19:57:06.492Z,1715371026.492 [Radio_Surface](INFO): Powering up
2024-05-10T19:57:06.619Z,1715371026.619 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-05-10T19:57:06.649Z,1715371026.649 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-05-10T19:57:06.650Z,1715371026.650 [Default:A.Wait](DEBUG): Construct Wait.
2024-05-10T19:57:06.673Z,1715371026.673 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-10T19:57:06.693Z,1715371026.693 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-05-10T19:57:06.695Z,1715371026.695 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-05-10T19:57:06.729Z,1715371026.729 [Default:E.Execute](DEBUG): Construct Execute.
2024-05-10T19:57:06.748Z,1715371026.748 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-05-10T19:57:06.753Z,1715371026.753 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-05-10T19:57:06.795Z,1715371026.795 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-05-10T19:57:07.061Z,1715371027.061 [Power24vConverter](INFO): Powering up.
2024-05-10T19:57:07.062Z,1715371027.062 [Sonardyne_Nano](INFO): Initializing.
2024-05-10T19:57:07.110Z,1715371027.110 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-05-10T19:57:07.116Z,1715371027.116 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-05-10T19:57:07.117Z,1715371027.117 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-05-10T19:57:07.124Z,1715371027.124 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-05-10T19:57:07.125Z,1715371027.125 [MassServo](DEBUG): Initializing EZServoServo.
2024-05-10T19:57:07.133Z,1715371027.133 [MassServo](DEBUG): Initializing MassServo.
2024-05-10T19:57:07.133Z,1715371027.133 [RudderServo](DEBUG): Initializing EZServoServo.
2024-05-10T19:57:07.140Z,1715371027.140 [RudderServo](DEBUG): Initializing RudderServo.
2024-05-10T19:57:07.141Z,1715371027.141 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-05-10T19:57:07.148Z,1715371027.148 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-05-10T19:57:08.138Z,1715371028.138 [WetLabsBB2FL](INFO): Powering down
2024-05-10T19:57:09.190Z,1715371029.190 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-05-10T19:57:10.441Z,1715371030.441 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2407
2024-05-10T19:57:12.661Z,1715371032.661 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-05-10T19:57:13.060Z,1715371033.060 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-05-10T19:57:18.184Z,1715371038.184 [DAT](INFO): DAT read:
2024-05-10T19:57:18.186Z,1715371038.186 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-05-10T19:57:19.698Z,1715371039.698 [DAT](INFO): DAT read: MF Frequency Band
2024-05-10T19:57:19.949Z,1715371039.949 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-05-10T19:57:19.950Z,1715371039.950 [DAT](INFO): DAT read: May 10 2024 19:57:13
2024-05-10T19:57:20.957Z,1715371040.957 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-05-10T19:57:20.958Z,1715371040.958 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-05-10T19:57:20.959Z,1715371040.959 [DAT](INFO): commRate: 1200
2024-05-10T19:57:23.024Z,1715371043.024 [DAT](INFO): entering command mode
2024-05-10T19:57:23.224Z,1715371043.224 [DAT](INFO): DAT read:
2024-05-10T19:57:23.225Z,1715371043.225 [DAT](INFO): DAT read: user:1>
2024-05-10T19:57:23.225Z,1715371043.225 [DAT](INFO): setting verbose to 3
2024-05-10T19:57:23.476Z,1715371043.476 [DAT](INFO): DAT read: user:1>
2024-05-10T19:57:23.478Z,1715371043.478 [DAT](INFO): DAT read: Verbose | 3
2024-05-10T19:57:23.478Z,1715371043.478 [DAT](INFO): set verbose to 3
2024-05-10T19:57:23.478Z,1715371043.478 [DAT](INFO): setting DatVerbose to 27440
2024-05-10T19:57:23.728Z,1715371043.728 [DAT](INFO): DAT read: user:2>
2024-05-10T19:57:23.729Z,1715371043.729 [DAT](INFO): DAT read: DatVerbose | 27440
2024-05-10T19:57:23.730Z,1715371043.730 [DAT](INFO): set DatVerbose to 27440
2024-05-10T19:57:23.730Z,1715371043.730 [DAT](INFO): setting transmit power to 8
2024-05-10T19:57:23.980Z,1715371043.980 [DAT](INFO): DAT read: user:3>
2024-05-10T19:57:23.981Z,1715371043.981 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-05-10T19:57:23.982Z,1715371043.982 [DAT](INFO): set transmit power to 8
2024-05-10T19:57:23.982Z,1715371043.982 [DAT](INFO): setting local address to 5
2024-05-10T19:57:24.233Z,1715371044.233 [DAT](INFO): DAT read: user:4>
2024-05-10T19:57:24.234Z,1715371044.234 [DAT](INFO): DAT read: LocalAddr | 5
2024-05-10T19:57:24.234Z,1715371044.234 [DAT](INFO): set local address to 5
2024-05-10T19:57:24.235Z,1715371044.235 [DAT](INFO): Setting time to: 19:57:24 And date to:5/10/2024
2024-05-10T19:57:24.485Z,1715371044.485 [DAT](INFO): DAT read: user:5>
2024-05-10T19:57:24.485Z,1715371044.485 [DAT](INFO): DAT read: Fri May 10, 2024 19:57:24
2024-05-10T19:57:24.486Z,1715371044.486 [DAT](INFO): Local DAT time set to Fri May 10, 2024 19:57:24
2024-05-10T19:57:35.461Z,1715371055.461 [NAL9602](INFO): Powering up NAL9602
2024-05-10T19:57:45.577Z,1715371065.577 [SBIT](IMPORTANT): Beginning Startup BIT
2024-05-10T19:57:45.583Z,1715371065.583 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-10T19:57:46.363Z,1715371066.363 [NAL9602](INFO): NAL9602 initialized
2024-05-10T19:57:56.521Z,1715371076.521 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.026563
CHAN A1 (24V): 0.060082
CHAN A2 (12V): -0.011879
CHAN A3 (5V): -0.026299
CHAN B0 (3.3V): -0.015881
CHAN B1 (3.15aV): -0.010767
CHAN B2 (3.15bV): -0.007112
CHAN B3 (GND): -0.015400
OPEN: 0.007705
Full Scale: +/- 1 mA
2024-05-10T19:58:03.332Z,1715371083.332 [NAL9602](INFO): SBD MO Status=0, MOMSN=11781, MT Status=0, MTMSN=0
2024-05-10T19:58:03.332Z,1715371083.332 [NAL9602](INFO): No messages in MT queue
2024-05-10T19:58:04.541Z,1715371084.541 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195801.00,A,3648.16151,N,12147.25563,W,0.350,0.00,100524,,,A*71
2024-05-10T19:58:04.556Z,1715371084.556 [NAL9602](INFO): GPS fix at 20240510T195801: (36.802692, -121.787594)
2024-05-10T19:58:36.870Z,1715371116.870 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-10T19:58:39.306Z,1715371119.306 [SBIT](IMPORTANT): SBIT PASSED
2024-05-10T19:58:39.307Z,1715371119.307 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-05-10T19:58:39.307Z,1715371119.307 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-05-10T19:58:39.307Z,1715371119.307 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=0.7 degree;
2024-05-10T19:58:39.308Z,1715371119.308 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=150 cubic_centimeter;
2024-05-10T19:58:39.308Z,1715371119.308 [SBIT](IMPORTANT): VerticalControl.massDefault=0.5 centimeter;
2024-05-10T19:58:39.707Z,1715371119.707 [MissionManager](IMPORTANT): Started mission Startup
2024-05-10T19:58:39.708Z,1715371119.708 [Startup] Running Loop=1
2024-05-10T19:58:39.708Z,1715371119.708 [Startup](DEBUG): Aggregate::initialize Startup
2024-05-10T19:58:39.708Z,1715371119.708 [Startup:A.GoToSurface] Running Loop=1
2024-05-10T19:58:39.708Z,1715371119.708 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-10T19:58:39.709Z,1715371119.709 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-10T19:58:39.709Z,1715371119.709 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-10T19:58:39.709Z,1715371119.709 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-10T19:58:39.710Z,1715371119.710 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-10T19:58:39.710Z,1715371119.710 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-05-10T19:58:39.710Z,1715371119.710 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-10T19:58:39.712Z,1715371119.712 [Startup:StartupSatComms] Running Loop=1
2024-05-10T19:58:39.712Z,1715371119.712 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-05-10T19:58:39.713Z,1715371119.713 [Startup:StartupSatComms:A] Running Loop=1
2024-05-10T19:58:40.115Z,1715371120.115 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-05-10T19:58:41.747Z,1715371121.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195838.00,A,3648.16454,N,12147.25760,W,0.078,0.00,100524,,,A*73
2024-05-10T19:58:41.750Z,1715371121.750 [NAL9602](INFO): GPS fix at 20240510T195838: (36.802742, -121.787627)
2024-05-10T19:58:41.760Z,1715371121.760 [Startup:StartupSatComms:A] Stopped
2024-05-10T19:58:41.760Z,1715371121.760 [Startup:StartupSatComms:B] Running Loop=1
2024-05-10T19:58:42.152Z,1715371122.152 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-10T19:58:55.168Z,1715371135.168 [NAL9602](INFO): SBD MO Status=1, MOMSN=11782, MT Status=0, MTMSN=0
2024-05-10T19:58:55.216Z,1715371135.216 [NAL9602](INFO): Sent 76 bytes from file Logs/20240510T193548/Courier0013.lzma
2024-05-10T19:58:55.216Z,1715371135.216 [NAL9602](INFO): Packets left to send: 0
2024-05-10T19:59:05.737Z,1715371145.737 [NAL9602](INFO): SBD MO Status=1, MOMSN=11783, MT Status=0, MTMSN=0
2024-05-10T19:59:05.786Z,1715371145.786 [NAL9602](INFO): Sent 18 bytes from file Logs/20240510T193548/Courier0016.lzma
2024-05-10T19:59:05.786Z,1715371145.786 [NAL9602](INFO): Packets left to send: 0
2024-05-10T19:59:15.843Z,1715371155.843 [NAL9602](INFO): SBD MO Status=1, MOMSN=11784, MT Status=0, MTMSN=0
2024-05-10T19:59:15.900Z,1715371155.900 [NAL9602](INFO): Sent 271 bytes from file Logs/20240510T195653/Courier0000.lzma
2024-05-10T19:59:15.900Z,1715371155.900 [NAL9602](INFO): Packets left to send: 0
2024-05-10T19:59:24.376Z,1715371164.376 [NAL9602](INFO): SBD MO Status=1, MOMSN=11785, MT Status=0, MTMSN=0
2024-05-10T19:59:24.428Z,1715371164.428 [NAL9602](INFO): Sent 332 bytes from file Logs/20240510T193548/Express0014.lzma
2024-05-10T19:59:24.428Z,1715371164.428 [NAL9602](INFO): Packets left to send: 1
2024-05-10T19:59:32.102Z,1715371172.102 [NAL9602](INFO): SBD MO Status=1, MOMSN=11786, MT Status=0, MTMSN=0
2024-05-10T19:59:32.156Z,1715371172.156 [NAL9602](INFO): Sent 196 bytes from file Logs/20240510T193548/Express0014.lzma
2024-05-10T19:59:32.156Z,1715371172.156 [NAL9602](INFO): Packets left to send: 0
2024-05-10T19:59:38.614Z,1715371178.614 [NAL9602](INFO): SBD MO Status=1, MOMSN=11787, MT Status=0, MTMSN=0
2024-05-10T19:59:38.668Z,1715371178.668 [NAL9602](INFO): Sent 18 bytes from file Logs/20240510T193548/Express0017.lzma
2024-05-10T19:59:38.668Z,1715371178.668 [NAL9602](INFO): Packets left to send: 0
2024-05-10T19:59:41.851Z,1715371181.851 [Startup:StartupSatComms:B](INFO): Timed out from 2024-05-10T19:58:41.8Z
2024-05-10T19:59:41.851Z,1715371181.851 [Startup:StartupSatComms:B] Stopped
2024-05-10T19:59:41.852Z,1715371181.852 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-05-10T19:59:41.852Z,1715371181.852 [Startup:StartupSatComms] Stopped
2024-05-10T19:59:41.852Z,1715371181.852 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-05-10T19:59:41.853Z,1715371181.853 [Startup](INFO): Completed Startup
2024-05-10T19:59:41.853Z,1715371181.853 [MissionManager](INFO): Startup is completed.
2024-05-10T19:59:41.853Z,1715371181.853 [MissionManager](INFO): Uninitializing Mission Startup
2024-05-10T19:59:41.853Z,1715371181.853 [Startup] Stopped
2024-05-10T19:59:41.853Z,1715371181.853 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-05-10T19:59:41.853Z,1715371181.853 [Startup:A.GoToSurface] Stopped
2024-05-10T19:59:41.853Z,1715371181.853 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-05-10T19:59:41.977Z,1715371181.977 [MissionManager](IMPORTANT): Started mission Default
2024-05-10T19:59:41.977Z,1715371181.977 [Default] Running Loop=1
2024-05-10T19:59:41.977Z,1715371181.977 [Default](DEBUG): Aggregate::initialize Default
2024-05-10T19:59:41.977Z,1715371181.977 [Default:B.GoToSurface] Running Loop=1
2024-05-10T19:59:41.978Z,1715371181.978 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-10T19:59:41.978Z,1715371181.978 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-10T19:59:41.978Z,1715371181.978 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-10T19:59:41.978Z,1715371181.978 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-10T19:59:41.979Z,1715371181.979 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-10T19:59:41.979Z,1715371181.979 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-05-10T19:59:41.979Z,1715371181.979 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-10T19:59:41.984Z,1715371181.984 [Default:A.Wait] Running Loop=1
2024-05-10T19:59:41.984Z,1715371181.984 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-05-10T19:59:49.346Z,1715371189.346 [NAL9602](INFO): SBD MO Status=1, MOMSN=11788, MT Status=0, MTMSN=0
2024-05-10T19:59:49.404Z,1715371189.404 [NAL9602](INFO): Sent 332 bytes from file Logs/20240510T195653/Express0001.lzma
2024-05-10T19:59:49.404Z,1715371189.404 [NAL9602](INFO): Packets left to send: 2
2024-05-10T19:59:55.264Z,1715371195.264 [Default:A.Wait](INFO): Done Waiting.
2024-05-10T19:59:55.264Z,1715371195.264 [Default:A.Wait] Stopped
2024-05-10T19:59:55.264Z,1715371195.264 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-05-10T19:59:55.661Z,1715371195.661 [Default:CheckIn] Running Loop=1
2024-05-10T19:59:55.661Z,1715371195.661 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-10T19:59:55.662Z,1715371195.662 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-10T19:59:56.065Z,1715371196.065 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-05-10T19:59:57.675Z,1715371197.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195954.00,A,3648.16259,N,12147.25593,W,0.039,0.00,100524,,,D*7D
2024-05-10T19:59:57.677Z,1715371197.677 [NAL9602](INFO): GPS fix at 20240510T195954: (36.802710, -121.787599)
2024-05-10T19:59:57.686Z,1715371197.686 [Default:CheckIn:Read_GPS] Stopped
2024-05-10T19:59:57.686Z,1715371197.686 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-10T19:59:58.108Z,1715371198.108 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-10T20:00:13.899Z,1715371213.899 [NAL9602](INFO): SBD MO Status=1, MOMSN=11789, MT Status=0, MTMSN=0
2024-05-10T20:00:13.948Z,1715371213.948 [NAL9602](INFO): Sent 76 bytes from file Logs/20240510T195653/Courier0004.lzma
2024-05-10T20:00:13.948Z,1715371213.948 [NAL9602](INFO): Packets left to send: 0
2024-05-10T20:00:27.683Z,1715371227.683 [NAL9602](INFO): SBD MO Status=1, MOMSN=11790, MT Status=0, MTMSN=0
2024-05-10T20:00:27.732Z,1715371227.732 [NAL9602](INFO): Sent 332 bytes from file Logs/20240510T195653/Express0001.lzma
2024-05-10T20:00:27.732Z,1715371227.732 [NAL9602](INFO): Packets left to send: 1
2024-05-10T20:00:39.886Z,1715371239.886 [NAL9602](INFO): SBD MO Status=1, MOMSN=11791, MT Status=0, MTMSN=0
2024-05-10T20:00:39.936Z,1715371239.936 [NAL9602](INFO): Sent 152 bytes from file Logs/20240510T195653/Express0001.lzma
2024-05-10T20:00:39.936Z,1715371239.936 [NAL9602](INFO): Packets left to send: 0
2024-05-10T20:00:48.438Z,1715371248.438 [NAL9602](INFO): SBD MO Status=1, MOMSN=11792, MT Status=0, MTMSN=0
2024-05-10T20:00:48.492Z,1715371248.492 [NAL9602](INFO): Sent 216 bytes from file Logs/20240510T195653/Express0005.lzma
2024-05-10T20:00:48.492Z,1715371248.492 [NAL9602](INFO): Packets left to send: 0
2024-05-10T20:00:59.330Z,1715371259.330 [NAL9602](INFO): SBD MO Status=0, MOMSN=11793, MT Status=0, MTMSN=0
2024-05-10T20:00:59.415Z,1715371259.415 [Default:CheckIn:Read_Iridium] Stopped
2024-05-10T20:00:59.415Z,1715371259.415 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-10T20:00:59.415Z,1715371259.415 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-10T20:01:04.124Z,1715371264.124 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-05-10T20:01:30.036Z,1715371290.036 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-10T20:01:49.209Z,1715371309.209 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004189
2024-05-10T20:03:10.473Z,1715371390.473 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-05-10T20:03:31.654Z,1715371411.654 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2024-05-10T20:03:31.657Z,1715371411.657 [BPC1](INFO): Received data from all battery sticks.
2024-05-10T20:05:59.920Z,1715371559.920 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-10T20:05:59.920Z,1715371559.920 [Default:CheckIn:C.Wait] Stopped
2024-05-10T20:05:59.920Z,1715371559.920 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-10T20:05:59.920Z,1715371559.920 [Default:CheckIn:D] Running Loop=1
2024-05-10T20:06:00.342Z,1715371560.342 [Default:CheckIn:D] Stopped
2024-05-10T20:06:00.342Z,1715371560.342 [Default:CheckIn:E] Running Loop=1
2024-05-10T20:06:00.750Z,1715371560.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.306070 min
2024-05-10T20:06:00.750Z,1715371560.750 [Default:CheckIn:E] Stopped
2024-05-10T20:06:00.750Z,1715371560.750 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-10T20:06:00.750Z,1715371560.750 [Default:CheckIn] Stopped
2024-05-10T20:06:00.750Z,1715371560.750 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-10T20:06:00.750Z,1715371560.750 [Default:CheckIn](INFO): Running loop #2
2024-05-10T20:06:00.750Z,1715371560.750 [Default:CheckIn] Running Loop=2
2024-05-10T20:06:00.751Z,1715371560.751 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-10T20:06:00.751Z,1715371560.751 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-10T20:06:02.742Z,1715371562.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200559.00,A,3648.16446,N,12147.25506,W,0.039,0.00,100524,,,D*77
2024-05-10T20:06:02.744Z,1715371562.744 [NAL9602](INFO): GPS fix at 20240510T200559: (36.802741, -121.787584)
2024-05-10T20:06:02.754Z,1715371562.754 [Default:CheckIn:Read_GPS] Stopped
2024-05-10T20:06:02.754Z,1715371562.754 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-10T20:06:15.288Z,1715371575.288 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240510T195653/Courier0007.lzma
2024-05-10T20:06:18.306Z,1715371578.306 [DataOverHttps](INFO): Moved sent file to Logs/20240510T195653/Courier0007.lzma.bak
2024-05-10T20:06:18.306Z,1715371578.306 [DataOverHttps](INFO): SBD MOMSN=21007998
2024-05-10T20:06:24.965Z,1715371584.965 [NAL9602](INFO): SBD MO Status=0, MOMSN=11794, MT Status=0, MTMSN=0
2024-05-10T20:06:24.965Z,1715371584.965 [NAL9602](INFO): No messages in MT queue
2024-05-10T20:06:46.347Z,1715371606.347 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20240510T195653/Express0008.lzma
2024-05-10T20:06:48.354Z,1715371608.354 [DataOverHttps](INFO): Moved sent file to Logs/20240510T195653/Express0008.lzma.bak
2024-05-10T20:06:48.354Z,1715371608.354 [DataOverHttps](INFO): SBD MOMSN=21008001
2024-05-10T20:06:49.608Z,1715371609.608 [Default:CheckIn:Read_Iridium] Stopped
2024-05-10T20:06:49.608Z,1715371609.608 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-10T20:06:49.608Z,1715371609.608 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-10T20:06:55.680Z,1715371615.680 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-10T20:11:50.186Z,1715371910.186 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-10T20:11:50.186Z,1715371910.186 [Default:CheckIn:C.Wait] Stopped
2024-05-10T20:11:50.187Z,1715371910.187 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-10T20:11:50.187Z,1715371910.187 [Default:CheckIn:D] Running Loop=1
2024-05-10T20:11:50.604Z,1715371910.604 [Default:CheckIn:D] Stopped
2024-05-10T20:11:50.604Z,1715371910.604 [Default:CheckIn:E] Running Loop=1
2024-05-10T20:11:51.011Z,1715371911.011 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.143778 min
2024-05-10T20:11:51.011Z,1715371911.011 [Default:CheckIn:E] Stopped
2024-05-10T20:11:51.012Z,1715371911.012 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-10T20:11:51.012Z,1715371911.012 [Default:CheckIn] Stopped
2024-05-10T20:11:51.012Z,1715371911.012 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-10T20:11:51.012Z,1715371911.012 [Default:CheckIn](INFO): Running loop #3
2024-05-10T20:11:51.012Z,1715371911.012 [Default:CheckIn] Running Loop=3
2024-05-10T20:11:51.012Z,1715371911.012 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-10T20:11:51.012Z,1715371911.012 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-10T20:11:53.017Z,1715371913.017 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201152.00,A,3648.16594,N,12147.25449,W,0.039,0.00,100524,,,D*7D
2024-05-10T20:11:53.019Z,1715371913.019 [NAL9602](INFO): GPS fix at 20240510T201152: (36.802766, -121.787575)
2024-05-10T20:11:53.044Z,1715371913.044 [Default:CheckIn:Read_GPS] Stopped
2024-05-10T20:11:53.044Z,1715371913.044 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-10T20:12:41.085Z,1715371961.085 [NAL9602](INFO): SBD MO Status=0, MOMSN=11795, MT Status=0, MTMSN=0
2024-05-10T20:12:41.086Z,1715371961.086 [NAL9602](INFO): No messages in MT queue
2024-05-10T20:13:04.531Z,1715371984.531 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20240510T195653/Courier0010.lzma
2024-05-10T20:13:07.542Z,1715371987.542 [DataOverHttps](INFO): Moved sent file to Logs/20240510T195653/Courier0010.lzma.bak
2024-05-10T20:13:07.542Z,1715371987.542 [DataOverHttps](INFO): SBD MOMSN=21008038
2024-05-10T20:13:11.794Z,1715371991.794 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-10T20:14:31.983Z,1715372071.983 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240510T195653/Express0011.lzma
2024-05-10T20:14:32.984Z,1715372072.984 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-05-10T20:16:59.100Z,1715372219.100 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240510T195653/Express0011.lzma
2024-05-10T20:17:01.108Z,1715372221.108 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.614456
2024-05-10T20:17:15.525Z,1715372235.525 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003334
2024-05-10T20:17:21.925Z,1715372241.925 [NAL9602](INFO): SBD MO Status=1, MOMSN=11796, MT Status=0, MTMSN=0
2024-05-10T20:17:21.992Z,1715372241.992 [NAL9602](INFO): Sent 132 bytes from file Logs/20240510T195653/Express0011.lzma
2024-05-10T20:17:21.992Z,1715372241.992 [NAL9602](INFO): Packets left to send: 0
2024-05-10T20:17:22.725Z,1715372242.725 [Default:CheckIn:Read_Iridium] Stopped
2024-05-10T20:17:22.725Z,1715372242.725 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-10T20:17:22.725Z,1715372242.725 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-10T20:17:48.109Z,1715372268.109 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-05-10T20:17:52.616Z,1715372272.616 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-10T20:18:19.236Z,1715372299.236 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:20:16.437Z,1715372416.437 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.915482
2024-05-10T20:21:26.744Z,1715372486.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:21:57.868Z,1715372517.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:22:23.320Z,1715372543.320 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-10T20:22:23.320Z,1715372543.320 [Default:CheckIn:C.Wait] Stopped
2024-05-10T20:22:23.321Z,1715372543.321 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-10T20:22:23.321Z,1715372543.321 [Default:CheckIn:D] Running Loop=1
2024-05-10T20:22:23.715Z,1715372543.715 [Default:CheckIn:D] Stopped
2024-05-10T20:22:23.715Z,1715372543.715 [Default:CheckIn:E] Running Loop=1
2024-05-10T20:22:24.135Z,1715372544.135 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.695628 min
2024-05-10T20:22:24.135Z,1715372544.135 [Default:CheckIn:E] Stopped
2024-05-10T20:22:24.140Z,1715372544.140 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-10T20:22:24.140Z,1715372544.140 [Default:CheckIn] Stopped
2024-05-10T20:22:24.140Z,1715372544.140 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-10T20:22:24.140Z,1715372544.140 [Default:CheckIn](INFO): Running loop #4
2024-05-10T20:22:24.140Z,1715372544.140 [Default:CheckIn] Running Loop=4
2024-05-10T20:22:24.140Z,1715372544.140 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-10T20:22:24.140Z,1715372544.140 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-10T20:22:26.138Z,1715372546.138 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202225.00,A,3648.16693,N,12147.25675,W,0.039,0.00,100524,,,D*74
2024-05-10T20:22:26.154Z,1715372546.154 [NAL9602](INFO): GPS fix at 20240510T202225: (36.802782, -121.787612)
2024-05-10T20:22:26.163Z,1715372546.163 [Default:CheckIn:Read_GPS] Stopped
2024-05-10T20:22:26.163Z,1715372546.163 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-10T20:22:58.926Z,1715372578.926 [NAL9602](INFO): SBD MO Status=1, MOMSN=11797, MT Status=0, MTMSN=0
2024-05-10T20:22:58.980Z,1715372578.980 [NAL9602](INFO): Sent 76 bytes from file Logs/20240510T195653/Courier0013.lzma
2024-05-10T20:22:58.980Z,1715372578.980 [NAL9602](INFO): Packets left to send: 0
2024-05-10T20:23:07.836Z,1715372587.836 [NAL9602](INFO): SBD MO Status=1, MOMSN=11798, MT Status=0, MTMSN=0
2024-05-10T20:23:07.892Z,1715372587.892 [NAL9602](INFO): Sent 131 bytes from file Logs/20240510T195653/Express0014.lzma
2024-05-10T20:23:07.892Z,1715372587.892 [NAL9602](INFO): Packets left to send: 0
2024-05-10T20:23:14.717Z,1715372594.717 [NAL9602](INFO): SBD MO Status=0, MOMSN=11799, MT Status=0, MTMSN=0
2024-05-10T20:23:14.799Z,1715372594.799 [Default:CheckIn:Read_Iridium] Stopped
2024-05-10T20:23:14.799Z,1715372594.799 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-10T20:23:14.799Z,1715372594.799 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-10T20:23:45.409Z,1715372625.409 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-10T20:24:35.192Z,1715372675.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:25:03.713Z,1715372703.713 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003369
2024-05-10T20:28:15.302Z,1715372895.302 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-10T20:28:15.302Z,1715372895.302 [Default:CheckIn:C.Wait] Stopped
2024-05-10T20:28:15.302Z,1715372895.302 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-10T20:28:15.302Z,1715372895.302 [Default:CheckIn:D] Running Loop=1
2024-05-10T20:28:15.688Z,1715372895.688 [Default:CheckIn:D] Stopped
2024-05-10T20:28:15.689Z,1715372895.689 [Default:CheckIn:E] Running Loop=1
2024-05-10T20:28:16.094Z,1715372896.094 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.561853 min
2024-05-10T20:28:16.094Z,1715372896.094 [Default:CheckIn:E] Stopped
2024-05-10T20:28:16.094Z,1715372896.094 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-10T20:28:16.094Z,1715372896.094 [Default:CheckIn] Stopped
2024-05-10T20:28:16.094Z,1715372896.094 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-10T20:28:16.094Z,1715372896.094 [Default:CheckIn](INFO): Running loop #5
2024-05-10T20:28:16.095Z,1715372896.095 [Default:CheckIn] Running Loop=5
2024-05-10T20:28:16.095Z,1715372896.095 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-10T20:28:16.095Z,1715372896.095 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-10T20:28:18.112Z,1715372898.112 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202817.00,A,3648.16591,N,12147.25450,W,0.019,0.00,100524,,,D*79
2024-05-10T20:28:18.114Z,1715372898.114 [NAL9602](INFO): GPS fix at 20240510T202817: (36.802765, -121.787575)
2024-05-10T20:28:18.135Z,1715372898.135 [Default:CheckIn:Read_GPS] Stopped
2024-05-10T20:28:18.140Z,1715372898.140 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-10T20:28:23.331Z,1715372903.331 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20240510T195653/Courier0016.lzma
2024-05-10T20:28:28.350Z,1715372908.350 [DataOverHttps](INFO): Moved sent file to Logs/20240510T195653/Courier0016.lzma.bak
2024-05-10T20:28:28.350Z,1715372908.350 [DataOverHttps](INFO): SBD MOMSN=21008328
2024-05-10T20:28:33.503Z,1715372913.503 [NAL9602](INFO): SBD MO Status=0, MOMSN=11800, MT Status=0, MTMSN=0
2024-05-10T20:28:33.503Z,1715372913.503 [NAL9602](INFO): No messages in MT queue
2024-05-10T20:29:00.247Z,1715372940.247 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20240510T195653/Express0017.lzma
2024-05-10T20:29:01.250Z,1715372941.250 [DataOverHttps](INFO): Moved sent file to Logs/20240510T195653/Express0017.lzma.bak
2024-05-10T20:29:01.250Z,1715372941.250 [DataOverHttps](INFO): SBD MOMSN=21008335
2024-05-10T20:29:02.602Z,1715372942.602 [Default:CheckIn:Read_Iridium] Stopped
2024-05-10T20:29:02.602Z,1715372942.602 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-10T20:29:02.602Z,1715372942.602 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-10T20:29:04.209Z,1715372944.209 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-10T20:30:03.504Z,1715373003.504 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:30:34.628Z,1715373034.628 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:31:05.768Z,1715373065.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:34:03.191Z,1715373243.191 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-10T20:34:03.191Z,1715373243.191 [Default:CheckIn:C.Wait] Stopped
2024-05-10T20:34:03.191Z,1715373243.191 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-10T20:34:03.191Z,1715373243.191 [Default:CheckIn:D] Running Loop=1
2024-05-10T20:34:03.582Z,1715373243.582 [Default:CheckIn:D] Stopped
2024-05-10T20:34:03.582Z,1715373243.582 [Default:CheckIn:E] Running Loop=1
2024-05-10T20:34:03.983Z,1715373243.983 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.360075 min
2024-05-10T20:34:03.983Z,1715373243.983 [Default:CheckIn:E] Stopped
2024-05-10T20:34:03.983Z,1715373243.983 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-10T20:34:03.983Z,1715373243.983 [Default:CheckIn] Stopped
2024-05-10T20:34:03.983Z,1715373243.983 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-10T20:34:03.983Z,1715373243.983 [Default:CheckIn](INFO): Running loop #6
2024-05-10T20:34:03.983Z,1715373243.983 [Default:CheckIn] Running Loop=6
2024-05-10T20:34:03.983Z,1715373243.983 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-10T20:34:03.984Z,1715373243.984 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-10T20:34:06.000Z,1715373246.000 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203405.00,A,3648.14313,N,12147.22556,W,0.058,19.51,100524,,,D*40
2024-05-10T20:34:06.002Z,1715373246.002 [NAL9602](INFO): GPS fix at 20240510T203405: (36.802385, -121.787093)
2024-05-10T20:34:06.011Z,1715373246.011 [Default:CheckIn:Read_GPS] Stopped
2024-05-10T20:34:06.011Z,1715373246.011 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-10T20:34:19.839Z,1715373259.839 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240510T195653/Courier0019.lzma
2024-05-10T20:34:25.862Z,1715373265.862 [DataOverHttps](INFO): Moved sent file to Logs/20240510T195653/Courier0019.lzma.bak
2024-05-10T20:34:25.862Z,1715373265.862 [DataOverHttps](INFO): SBD MOMSN=21008359
2024-05-10T20:34:29.835Z,1715373269.835 [NAL9602](INFO): SBD MO Status=0, MOMSN=11801, MT Status=0, MTMSN=0
2024-05-10T20:34:29.835Z,1715373269.835 [NAL9602](INFO): No messages in MT queue
2024-05-10T20:35:00.531Z,1715373300.531 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-10T20:35:10.747Z,1715373310.747 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240510T195653/Express0020.lzma
2024-05-10T20:35:13.758Z,1715373313.758 [DataOverHttps](INFO): Moved sent file to Logs/20240510T195653/Express0020.lzma.bak
2024-05-10T20:35:13.758Z,1715373313.758 [DataOverHttps](INFO): SBD MOMSN=21008364
2024-05-10T20:35:15.085Z,1715373315.085 [Default:CheckIn:Read_Iridium] Stopped
2024-05-10T20:35:15.086Z,1715373315.086 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-10T20:35:15.086Z,1715373315.086 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-10T20:38:30.981Z,1715373510.981 [CommandExec](IMPORTANT): got command burn on
2024-05-10T20:38:30.981Z,1715373510.981 [CommandExec](IMPORTANT): Activating dropweight wire
2024-05-10T20:38:45.366Z,1715373525.366 [CommandExec](IMPORTANT): got command burn off
2024-05-10T20:38:45.366Z,1715373525.366 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-05-10T20:38:57.981Z,1715373537.981 [CommandExec](IMPORTANT): got command ibit
2024-05-10T20:38:58.175Z,1715373538.175 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-05-10T20:38:58.175Z,1715373538.175 [IBIT](IMPORTANT): Beginning control surface checks.
2024-05-10T20:38:58.182Z,1715373538.182 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-10T20:38:59.735Z,1715373539.735 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203859.00,A,3648.14377,N,12147.22838,W,0.097,19.51,100524,,,D*41
2024-05-10T20:38:59.738Z,1715373539.738 [NAL9602](INFO): GPS fix at 20240510T203859: (36.802396, -121.787140)
2024-05-10T20:39:09.085Z,1715373549.085 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.031334
CHAN A1 (24V): 0.059265
CHAN A2 (12V): -0.010169
CHAN A3 (5V): -0.022532
CHAN B0 (3.3V): -0.014754
CHAN B1 (3.15aV): -0.009569
CHAN B2 (3.15bV): -0.006455
CHAN B3 (GND): -0.014867
OPEN: 0.007483
Full Scale: +/- 1 mA
2024-05-10T20:39:20.120Z,1715373560.120 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:39:30.438Z,1715373570.438 [NAL9602](INFO): SBD MO Status=0, MOMSN=11802, MT Status=0, MTMSN=0
2024-05-10T20:39:30.439Z,1715373570.439 [NAL9602](INFO): No messages in MT queue
2024-05-10T20:39:31.646Z,1715373571.646 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203931.00,A,3648.14380,N,12147.22871,W,0.000,19.51,100524,,,D*45
2024-05-10T20:39:31.649Z,1715373571.649 [NAL9602](INFO): GPS fix at 20240510T203931: (36.802397, -121.787145)
2024-05-10T20:39:34.874Z,1715373574.874 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203934.00,A,3648.14376,N,12147.22872,W,0.019,19.51,100524,,,D*42
2024-05-10T20:39:34.877Z,1715373574.877 [NAL9602](INFO): GPS fix at 20240510T203934: (36.802396, -121.787145)
2024-05-10T20:39:37.708Z,1715373577.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203937.00,A,3648.14380,N,12147.22873,W,0.000,19.51,100524,,,D*41
2024-05-10T20:39:37.711Z,1715373577.711 [NAL9602](INFO): GPS fix at 20240510T203937: (36.802397, -121.787145)
2024-05-10T20:39:40.950Z,1715373580.950 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203940.00,A,3648.14383,N,12147.22874,W,0.039,19.51,100524,,,D*4F
2024-05-10T20:39:40.952Z,1715373580.952 [NAL9602](INFO): GPS fix at 20240510T203940: (36.802397, -121.787146)
2024-05-10T20:39:43.770Z,1715373583.770 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203943.00,A,3648.14390,N,12147.22873,W,0.039,19.51,100524,,,D*49
2024-05-10T20:39:43.772Z,1715373583.772 [NAL9602](INFO): GPS fix at 20240510T203943: (36.802398, -121.787145)
2024-05-10T20:39:44.602Z,1715373584.602 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802399 Longitude: -121.787148
2024-05-10T20:39:44.986Z,1715373584.986 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 14.391000
2024-05-10T20:39:44.986Z,1715373584.986 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-05-10T20:39:44.986Z,1715373584.986 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-05-10T20:39:45.401Z,1715373585.401 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-05-10T20:39:45.401Z,1715373585.401 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-05-10T20:39:45.401Z,1715373585.401 [IBIT](IMPORTANT): Pressure:9.509832 PSI
2024-05-10T20:39:45.402Z,1715373585.402 [IBIT](IMPORTANT): Humidity:6.085948 %
2024-05-10T20:39:45.818Z,1715373585.818 [IBIT](IMPORTANT): Vehicle Pitch:-1.968640 degrees
2024-05-10T20:39:45.819Z,1715373585.819 [IBIT](IMPORTANT): Vehicle Roll:2.959360 degrees
2024-05-10T20:39:45.819Z,1715373585.819 [IBIT](IMPORTANT): Vehicle Heading:198.053268 degrees
2024-05-10T20:39:46.223Z,1715373586.223 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-05-10T20:39:46.224Z,1715373586.224 [IBIT](IMPORTANT): buoyancyNeutral: 150.000000 cc
2024-05-10T20:39:46.224Z,1715373586.224 [IBIT](IMPORTANT): massDefault: 0.500000 cm
2024-05-10T20:39:46.225Z,1715373586.225 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2024-05-10T20:39:46.225Z,1715373586.225 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-05-10T20:39:46.225Z,1715373586.225 [IBIT](IMPORTANT): IBIT PASSED
2024-05-10T20:39:51.253Z,1715373591.253 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:39:58.918Z,1715373598.918 [CommandExec](IMPORTANT): got command gfscan
2024-05-10T20:39:59.160Z,1715373599.160 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-10T20:40:03.568Z,1715373603.568 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2024-05-10T20:40:03.568Z,1715373603.568 [AHRS_M2] Data Fault, FailCount= 1
2024-05-10T20:40:03.568Z,1715373603.568 [AHRS_M2](ERROR): Data Fault
2024-05-10T20:40:03.594Z,1715373603.594 [CBIT](ERROR): Data Fault in component: AHRS_M2
2024-05-10T20:40:03.948Z,1715373603.948 [AHRS_M2](INFO): Powering down
2024-05-10T20:40:04.781Z,1715373604.781 [CBIT](INFO): Clearing failed state for component AHRS_M2
2024-05-10T20:40:04.781Z,1715373604.781 [AHRS_M2] No Fault, FailCount= 1
2024-05-10T20:40:05.161Z,1715373605.161 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-05-10T20:40:10.054Z,1715373610.054 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.031223
CHAN A1 (24V): 0.056414
CHAN A2 (12V): -0.013038
CHAN A3 (5V): -0.024401
CHAN B0 (3.3V): -0.014117
CHAN B1 (3.15aV): -0.009848
CHAN B2 (3.15bV): -0.006981
CHAN B3 (GND): -0.014905
OPEN: 0.007162
Full Scale: +/- 1 mA
2024-05-10T20:40:15.684Z,1715373615.684 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-10T20:40:15.684Z,1715373615.684 [Default:CheckIn:C.Wait] Stopped
2024-05-10T20:40:15.684Z,1715373615.684 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-10T20:40:15.685Z,1715373615.685 [Default:CheckIn:D] Running Loop=1
2024-05-10T20:40:16.089Z,1715373616.089 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-10T20:40:16.100Z,1715373616.100 [Default:CheckIn:D] Stopped
2024-05-10T20:40:16.100Z,1715373616.100 [Default:CheckIn:E] Running Loop=1
2024-05-10T20:40:16.498Z,1715373616.498 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.568709 min
2024-05-10T20:40:16.499Z,1715373616.499 [Default:CheckIn:E] Stopped
2024-05-10T20:40:16.499Z,1715373616.499 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-10T20:40:16.499Z,1715373616.499 [Default:CheckIn] Stopped
2024-05-10T20:40:16.499Z,1715373616.499 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-10T20:40:16.499Z,1715373616.499 [Default:CheckIn](INFO): Running loop #7
2024-05-10T20:40:16.499Z,1715373616.499 [Default:CheckIn] Running Loop=7
2024-05-10T20:40:16.499Z,1715373616.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-10T20:40:16.499Z,1715373616.499 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-10T20:40:18.510Z,1715373618.510 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204018.00,A,3648.14410,N,12147.22863,W,0.019,19.51,100524,,,D*45
2024-05-10T20:40:18.512Z,1715373618.512 [NAL9602](INFO): GPS fix at 20240510T204018: (36.802402, -121.787144)
2024-05-10T20:40:18.544Z,1715373618.544 [Default:CheckIn:Read_GPS] Stopped
2024-05-10T20:40:18.544Z,1715373618.544 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-10T20:40:31.440Z,1715373631.440 [NAL9602](INFO): SBD MO Status=0, MOMSN=11803, MT Status=0, MTMSN=0
2024-05-10T20:40:31.440Z,1715373631.440 [NAL9602](INFO): No messages in MT queue
2024-05-10T20:40:32.417Z,1715373632.417 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.076233
2024-05-10T20:40:35.475Z,1715373635.475 [DVL_micro](ERROR): No DVL communication! Re-initializing
2024-05-10T20:40:35.475Z,1715373635.475 [DVL_micro] Communications Fault, FailCount= 1
2024-05-10T20:40:35.475Z,1715373635.475 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:40:35.495Z,1715373635.495 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:40:35.976Z,1715373635.976 [DVL_micro](INFO): Powering down
2024-05-10T20:40:36.241Z,1715373636.241 [CommandExec](IMPORTANT): got command gfscan
2024-05-10T20:40:36.318Z,1715373636.318 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-10T20:40:36.717Z,1715373636.717 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:40:36.717Z,1715373636.717 [DVL_micro] No Fault, FailCount= 1
2024-05-10T20:40:47.214Z,1715373647.214 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.040796
CHAN A1 (24V): -0.153155
CHAN A2 (12V): 0.014562
CHAN A3 (5V): -0.010506
CHAN B0 (3.3V): -0.007482
CHAN B1 (3.15aV): -0.005045
CHAN B2 (3.15bV): -0.003680
CHAN B3 (GND): -0.011024
OPEN: 0.004219
Full Scale: +/- 1 mA
2024-05-10T20:40:54.463Z,1715373654.463 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:40:54.463Z,1715373654.463 [DVL_micro] Communications Fault, FailCount= 2
2024-05-10T20:40:54.463Z,1715373654.463 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:40:54.482Z,1715373654.482 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:40:54.965Z,1715373654.965 [DVL_micro](INFO): Powering down
2024-05-10T20:40:55.757Z,1715373655.757 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:40:55.757Z,1715373655.757 [DVL_micro] No Fault, FailCount= 2
2024-05-10T20:41:03.757Z,1715373663.757 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:41:04.280Z,1715373664.280 [NAL9602](ERROR): received:
2024-05-10T20:41:12.913Z,1715373672.913 [CommandExec](IMPORTANT): got command gfscan
2024-05-10T20:41:13.239Z,1715373673.239 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-10T20:41:13.623Z,1715373673.623 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:41:13.623Z,1715373673.623 [DVL_micro] Communications Fault, FailCount= 3
2024-05-10T20:41:13.623Z,1715373673.623 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:41:13.682Z,1715373673.682 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:41:13.683Z,1715373673.683 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-05-10T20:41:14.116Z,1715373674.116 [DVL_micro](INFO): Powering down
2024-05-10T20:41:14.133Z,1715373674.133 [CommandExec](FAULT): Scheduling is paused
2024-05-10T20:41:14.133Z,1715373674.133 [CBIT](INFO): Critical error at 20240510T204113
2024-05-10T20:41:14.133Z,1715373674.133 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-05-10T20:41:24.149Z,1715373684.149 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.030887
CHAN A1 (24V): -0.166156
CHAN A2 (12V): 0.012741
CHAN A3 (5V): -0.011165
CHAN B0 (3.3V): -0.007573
CHAN B1 (3.15aV): -0.005387
CHAN B2 (3.15bV): -0.003197
CHAN B3 (GND): -0.011147
OPEN: 0.004537
Full Scale: +/- 1 mA
2024-05-10T20:41:32.305Z,1715373692.305 [CommandExec](IMPORTANT): got command gfscan
2024-05-10T20:41:32.628Z,1715373692.628 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-10T20:41:35.441Z,1715373695.441 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:41:35.956Z,1715373695.956 [NAL9602](ERROR): received:
2024-05-10T20:41:44.187Z,1715373704.187 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.028148
CHAN A1 (24V): -0.164738
CHAN A2 (12V): 0.012838
CHAN A3 (5V): -0.010567
CHAN B0 (3.3V): -0.007650
CHAN B1 (3.15aV): -0.004895
CHAN B2 (3.15bV): -0.003627
CHAN B3 (GND): -0.011463
OPEN: 0.004256
Full Scale: +/- 1 mA
2024-05-10T20:42:00.973Z,1715373720.973 [CommandExec](IMPORTANT): got command gfscan
2024-05-10T20:42:01.148Z,1715373721.148 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-10T20:42:07.198Z,1715373727.198 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:42:07.716Z,1715373727.716 [NAL9602](ERROR): received:
2024-05-10T20:42:13.051Z,1715373733.051 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006224
CHAN A1 (24V): -0.157316
CHAN A2 (12V): -0.005037
CHAN A3 (5V): -0.002361
CHAN B0 (3.3V): -0.001164
CHAN B1 (3.15aV): -0.001103
CHAN B2 (3.15bV): -0.001011
CHAN B3 (GND): 0.000100
OPEN: 0.000769
Full Scale: +/- 1 mA
2024-05-10T20:42:38.869Z,1715373758.869 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:42:39.388Z,1715373759.388 [NAL9602](ERROR): received:
2024-05-10T20:42:42.649Z,1715373762.649 [CommandExec](IMPORTANT): got command gfscan
2024-05-10T20:42:42.715Z,1715373762.715 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-10T20:42:53.613Z,1715373773.613 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.029230
CHAN A1 (24V): -0.176557
CHAN A2 (12V): 0.014277
CHAN A3 (5V): -0.010276
CHAN B0 (3.3V): -0.007231
CHAN B1 (3.15aV): -0.005166
CHAN B2 (3.15bV): -0.003683
CHAN B3 (GND): -0.011097
OPEN: 0.004382
Full Scale: +/- 1 mA
2024-05-10T20:43:04.345Z,1715373784.345 [CommandExec](IMPORTANT): got command gfscan
2024-05-10T20:43:04.515Z,1715373784.515 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-10T20:43:10.559Z,1715373790.559 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:43:11.076Z,1715373791.076 [NAL9602](ERROR): received:
2024-05-10T20:43:15.664Z,1715373795.664 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.005933
CHAN A1 (24V): -0.161433
CHAN A2 (12V): -0.004858
CHAN A3 (5V): -0.002137
CHAN B0 (3.3V): -0.001528
CHAN B1 (3.15aV): -0.001181
CHAN B2 (3.15bV): -0.001311
CHAN B3 (GND): 0.000003
OPEN: 0.000721
Full Scale: +/- 1 mA
2024-05-10T20:43:42.256Z,1715373822.256 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:43:42.772Z,1715373822.772 [NAL9602](ERROR): received:
2024-05-10T20:44:13.949Z,1715373853.949 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:44:14.464Z,1715373854.464 [NAL9602](ERROR): received:
2024-05-10T20:44:14.488Z,1715373854.488 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:44:14.488Z,1715373854.488 [DVL_micro] No Fault, FailCount= 3
2024-05-10T20:44:30.376Z,1715373870.376 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003132
2024-05-10T20:44:31.883Z,1715373871.883 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:44:31.883Z,1715373871.883 [DVL_micro] Communications Fault, FailCount= 1
2024-05-10T20:44:31.883Z,1715373871.883 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:44:31.926Z,1715373871.926 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:44:32.376Z,1715373872.376 [DVL_micro](INFO): Powering down
2024-05-10T20:44:33.115Z,1715373873.115 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:44:33.116Z,1715373873.116 [DVL_micro] No Fault, FailCount= 1
2024-05-10T20:44:45.617Z,1715373885.617 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:44:46.132Z,1715373886.132 [NAL9602](ERROR): received:
2024-05-10T20:44:51.030Z,1715373891.030 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:44:51.030Z,1715373891.030 [DVL_micro] Communications Fault, FailCount= 2
2024-05-10T20:44:51.031Z,1715373891.031 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:44:51.049Z,1715373891.049 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:44:51.516Z,1715373891.516 [DVL_micro](INFO): Powering down
2024-05-10T20:44:52.256Z,1715373892.256 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:44:52.256Z,1715373892.256 [DVL_micro] No Fault, FailCount= 2
2024-05-10T20:45:10.013Z,1715373910.013 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:45:10.013Z,1715373910.013 [DVL_micro] Communications Fault, FailCount= 3
2024-05-10T20:45:10.013Z,1715373910.013 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:45:10.031Z,1715373910.031 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:45:10.032Z,1715373910.032 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-05-10T20:45:10.516Z,1715373910.516 [DVL_micro](INFO): Powering down
2024-05-10T20:45:10.539Z,1715373910.539 [CBIT](INFO): Critical error at 20240510T204510
2024-05-10T20:45:16.478Z,1715373916.478 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-10T20:46:29.403Z,1715373989.403 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20240510T195653/Courier0022.lzma
2024-05-10T20:46:33.418Z,1715373993.418 [DataOverHttps](INFO): Moved sent file to Logs/20240510T195653/Courier0022.lzma.bak
2024-05-10T20:46:33.418Z,1715373993.418 [DataOverHttps](INFO): SBD MOMSN=21008459
2024-05-10T20:47:10.101Z,1715374030.101 [DataOverHttps](INFO): Sending 942 bytes from file Logs/20240510T195653/Express0023.lzma
2024-05-10T20:47:26.176Z,1715374046.176 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-05-10T20:48:10.233Z,1715374090.233 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:48:10.233Z,1715374090.233 [DVL_micro] No Fault, FailCount= 3
2024-05-10T20:48:27.987Z,1715374107.987 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:48:27.987Z,1715374107.987 [DVL_micro] Communications Fault, FailCount= 1
2024-05-10T20:48:27.987Z,1715374107.987 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:48:28.006Z,1715374108.006 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:48:28.492Z,1715374108.492 [DVL_micro](INFO): Powering down
2024-05-10T20:48:29.239Z,1715374109.239 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:48:29.239Z,1715374109.239 [DVL_micro] No Fault, FailCount= 1
2024-05-10T20:48:46.979Z,1715374126.979 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:48:46.979Z,1715374126.979 [DVL_micro] Communications Fault, FailCount= 2
2024-05-10T20:48:46.979Z,1715374126.979 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:48:47.023Z,1715374127.023 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:48:47.472Z,1715374127.472 [DVL_micro](INFO): Powering down
2024-05-10T20:48:48.211Z,1715374128.211 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:48:48.211Z,1715374128.211 [DVL_micro] No Fault, FailCount= 2
2024-05-10T20:49:05.970Z,1715374145.970 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:49:05.970Z,1715374145.970 [DVL_micro] Communications Fault, FailCount= 3
2024-05-10T20:49:05.970Z,1715374145.970 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:49:05.989Z,1715374145.989 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:49:05.989Z,1715374145.989 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-05-10T20:49:06.469Z,1715374146.469 [DVL_micro](INFO): Powering down
2024-05-10T20:49:06.504Z,1715374146.504 [CBIT](INFO): Critical error at 20240510T204905
2024-05-10T20:49:54.793Z,1715374194.793 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.050629
2024-05-10T20:50:25.917Z,1715374225.917 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:50:25.964Z,1715374225.964 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:50:26.480Z,1715374226.480 [NAL9602](ERROR): received:
2024-05-10T20:50:57.040Z,1715374257.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:50:57.639Z,1715374257.639 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:50:58.160Z,1715374258.160 [NAL9602](ERROR): received:
2024-05-10T20:51:28.172Z,1715374288.172 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:51:29.324Z,1715374289.324 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:51:29.848Z,1715374289.848 [NAL9602](ERROR): received:
2024-05-10T20:51:59.308Z,1715374319.308 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:52:01.023Z,1715374321.023 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:52:01.544Z,1715374321.544 [NAL9602](ERROR): received:
2024-05-10T20:52:06.073Z,1715374326.073 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:52:06.073Z,1715374326.073 [DVL_micro] No Fault, FailCount= 3
2024-05-10T20:52:23.846Z,1715374343.846 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:52:23.846Z,1715374343.846 [DVL_micro] Communications Fault, FailCount= 1
2024-05-10T20:52:23.846Z,1715374343.846 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:52:23.865Z,1715374343.865 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:52:24.316Z,1715374344.316 [DVL_micro](INFO): Powering down
2024-05-10T20:52:25.059Z,1715374345.059 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:52:25.059Z,1715374345.059 [DVL_micro] No Fault, FailCount= 1
2024-05-10T20:52:30.432Z,1715374350.432 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:52:32.708Z,1715374352.708 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:52:33.232Z,1715374353.232 [NAL9602](ERROR): received:
2024-05-10T20:52:42.976Z,1715374362.976 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:52:42.976Z,1715374362.976 [DVL_micro] Communications Fault, FailCount= 2
2024-05-10T20:52:42.976Z,1715374362.976 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:52:42.995Z,1715374362.995 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:52:43.468Z,1715374363.468 [DVL_micro](INFO): Powering down
2024-05-10T20:52:44.220Z,1715374364.220 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:52:44.220Z,1715374364.220 [DVL_micro] No Fault, FailCount= 2
2024-05-10T20:53:01.564Z,1715374381.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:53:01.964Z,1715374381.964 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:53:01.964Z,1715374381.964 [DVL_micro] Communications Fault, FailCount= 3
2024-05-10T20:53:01.964Z,1715374381.964 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:53:02.014Z,1715374382.014 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:53:02.014Z,1715374382.014 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-05-10T20:53:02.456Z,1715374382.456 [DVL_micro](INFO): Powering down
2024-05-10T20:53:02.478Z,1715374382.478 [CBIT](INFO): Critical error at 20240510T205302
2024-05-10T20:53:04.389Z,1715374384.389 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:53:04.904Z,1715374384.904 [NAL9602](ERROR): received:
2024-05-10T20:53:32.688Z,1715374412.688 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:53:36.062Z,1715374416.062 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:53:36.580Z,1715374416.580 [NAL9602](ERROR): received:
2024-05-10T20:54:03.812Z,1715374443.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:54:07.746Z,1715374447.746 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:54:08.264Z,1715374448.264 [NAL9602](ERROR): received:
2024-05-10T20:54:34.937Z,1715374474.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:54:39.415Z,1715374479.415 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:54:39.940Z,1715374479.940 [NAL9602](ERROR): received:
2024-05-10T20:55:06.060Z,1715374506.060 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:55:11.094Z,1715374511.094 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:55:11.612Z,1715374511.612 [NAL9602](ERROR): received:
2024-05-10T20:55:37.184Z,1715374537.184 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:55:42.768Z,1715374542.768 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:55:43.284Z,1715374543.284 [NAL9602](ERROR): received:
2024-05-10T20:56:02.359Z,1715374562.359 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:56:02.360Z,1715374562.360 [DVL_micro] No Fault, FailCount= 3
2024-05-10T20:56:08.308Z,1715374568.308 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:56:14.460Z,1715374574.460 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:56:14.984Z,1715374574.984 [NAL9602](ERROR): received:
2024-05-10T20:56:20.300Z,1715374580.300 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:56:20.300Z,1715374580.300 [DVL_micro] Communications Fault, FailCount= 1
2024-05-10T20:56:20.300Z,1715374580.300 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:56:20.348Z,1715374580.348 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:56:20.792Z,1715374580.792 [DVL_micro](INFO): Powering down
2024-05-10T20:56:21.532Z,1715374581.532 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:56:21.532Z,1715374581.532 [DVL_micro] No Fault, FailCount= 1
2024-05-10T20:56:39.290Z,1715374599.290 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:56:39.290Z,1715374599.290 [DVL_micro] Communications Fault, FailCount= 2
2024-05-10T20:56:39.291Z,1715374599.291 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:56:39.309Z,1715374599.309 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:56:39.440Z,1715374599.440 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:56:39.784Z,1715374599.784 [DVL_micro](INFO): Powering down
2024-05-10T20:56:40.519Z,1715374600.519 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:56:40.519Z,1715374600.519 [DVL_micro] No Fault, FailCount= 2
2024-05-10T20:56:46.155Z,1715374606.155 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:56:46.680Z,1715374606.680 [NAL9602](ERROR): received:
2024-05-10T20:56:58.049Z,1715374618.049 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T20:56:58.049Z,1715374618.049 [DVL_micro] Communications Fault, FailCount= 3
2024-05-10T20:56:58.049Z,1715374618.049 [DVL_micro](ERROR): Communications Fault
2024-05-10T20:56:58.068Z,1715374618.068 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T20:56:58.068Z,1715374618.068 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-05-10T20:56:58.544Z,1715374618.544 [DVL_micro](INFO): Powering down
2024-05-10T20:56:58.577Z,1715374618.577 [CBIT](INFO): Critical error at 20240510T205658
2024-05-10T20:57:10.564Z,1715374630.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:57:17.844Z,1715374637.844 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:57:18.364Z,1715374638.364 [NAL9602](ERROR): received:
2024-05-10T20:57:41.689Z,1715374661.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:57:49.539Z,1715374669.539 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:57:50.056Z,1715374670.056 [NAL9602](ERROR): received:
2024-05-10T20:58:12.812Z,1715374692.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:58:21.237Z,1715374701.237 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:58:21.752Z,1715374701.752 [NAL9602](ERROR): received:
2024-05-10T20:58:43.940Z,1715374723.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:58:52.905Z,1715374732.905 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:58:53.420Z,1715374733.420 [NAL9602](ERROR): received:
2024-05-10T20:59:15.064Z,1715374755.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:59:24.590Z,1715374764.590 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:59:25.104Z,1715374765.104 [NAL9602](ERROR): received:
2024-05-10T20:59:46.188Z,1715374786.188 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T20:59:56.259Z,1715374796.259 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T20:59:56.784Z,1715374796.784 [NAL9602](ERROR): received:
2024-05-10T20:59:58.069Z,1715374798.069 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T20:59:58.069Z,1715374798.069 [DVL_micro] No Fault, FailCount= 3
2024-05-10T21:00:15.819Z,1715374815.819 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T21:00:15.819Z,1715374815.819 [DVL_micro] Communications Fault, FailCount= 1
2024-05-10T21:00:15.819Z,1715374815.819 [DVL_micro](ERROR): Communications Fault
2024-05-10T21:00:15.857Z,1715374815.857 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T21:00:16.320Z,1715374816.320 [DVL_micro](INFO): Powering down
2024-05-10T21:00:17.052Z,1715374817.052 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T21:00:17.052Z,1715374817.052 [DVL_micro] No Fault, FailCount= 1
2024-05-10T21:00:17.312Z,1715374817.312 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:00:27.944Z,1715374827.944 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:00:28.468Z,1715374828.468 [NAL9602](ERROR): received:
2024-05-10T21:00:35.000Z,1715374835.000 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T21:00:35.000Z,1715374835.000 [DVL_micro] Communications Fault, FailCount= 2
2024-05-10T21:00:35.000Z,1715374835.000 [DVL_micro](ERROR): Communications Fault
2024-05-10T21:00:35.035Z,1715374835.035 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T21:00:35.496Z,1715374835.496 [DVL_micro](INFO): Powering down
2024-05-10T21:00:36.231Z,1715374836.231 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T21:00:36.231Z,1715374836.231 [DVL_micro] No Fault, FailCount= 2
2024-05-10T21:00:48.436Z,1715374848.436 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:00:53.980Z,1715374853.980 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T21:00:53.980Z,1715374853.980 [DVL_micro] Communications Fault, FailCount= 3
2024-05-10T21:00:53.980Z,1715374853.980 [DVL_micro](ERROR): Communications Fault
2024-05-10T21:00:53.999Z,1715374853.999 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T21:00:53.999Z,1715374853.999 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-05-10T21:00:54.484Z,1715374854.484 [DVL_micro](INFO): Powering down
2024-05-10T21:00:54.514Z,1715374854.514 [CBIT](INFO): Critical error at 20240510T210053
2024-05-10T21:00:59.641Z,1715374859.641 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:01:00.164Z,1715374860.164 [NAL9602](ERROR): received:
2024-05-10T21:01:19.564Z,1715374879.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:01:31.319Z,1715374891.319 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:01:31.840Z,1715374891.840 [NAL9602](ERROR): received:
2024-05-10T21:01:50.688Z,1715374910.688 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:02:02.000Z,1715374923.000 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:02:03.524Z,1715374923.524 [NAL9602](ERROR): received:
2024-05-10T21:02:21.814Z,1715374941.814 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:02:34.679Z,1715374954.679 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:02:35.196Z,1715374955.196 [NAL9602](ERROR): received:
2024-05-10T21:02:52.936Z,1715374972.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:03:06.351Z,1715374986.351 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:03:06.876Z,1715374986.876 [NAL9602](ERROR): received:
2024-05-10T21:03:24.060Z,1715375004.060 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:03:38.054Z,1715375018.054 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:03:38.572Z,1715375018.572 [NAL9602](ERROR): received:
2024-05-10T21:03:53.989Z,1715375033.989 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T21:03:53.989Z,1715375033.989 [DVL_micro] No Fault, FailCount= 3
2024-05-10T21:03:55.184Z,1715375035.184 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:04:09.731Z,1715375049.731 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:04:10.248Z,1715375050.248 [NAL9602](ERROR): received:
2024-05-10T21:04:11.907Z,1715375051.907 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T21:04:11.907Z,1715375051.907 [DVL_micro] Communications Fault, FailCount= 1
2024-05-10T21:04:11.907Z,1715375051.907 [DVL_micro](ERROR): Communications Fault
2024-05-10T21:04:11.946Z,1715375051.946 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T21:04:12.404Z,1715375052.404 [DVL_micro](INFO): Powering down
2024-05-10T21:04:13.139Z,1715375053.139 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T21:04:13.139Z,1715375053.139 [DVL_micro] No Fault, FailCount= 1
2024-05-10T21:04:26.312Z,1715375066.312 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:04:30.897Z,1715375070.897 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T21:04:30.897Z,1715375070.897 [DVL_micro] Communications Fault, FailCount= 2
2024-05-10T21:04:30.897Z,1715375070.897 [DVL_micro](ERROR): Communications Fault
2024-05-10T21:04:30.916Z,1715375070.916 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T21:04:31.400Z,1715375071.400 [DVL_micro](INFO): Powering down
2024-05-10T21:04:32.139Z,1715375072.139 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T21:04:32.139Z,1715375072.139 [DVL_micro] No Fault, FailCount= 2
2024-05-10T21:04:41.404Z,1715375081.404 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:04:41.924Z,1715375081.924 [NAL9602](ERROR): received:
2024-05-10T21:04:50.052Z,1715375090.052 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T21:04:50.052Z,1715375090.052 [DVL_micro] Communications Fault, FailCount= 3
2024-05-10T21:04:50.052Z,1715375090.052 [DVL_micro](ERROR): Communications Fault
2024-05-10T21:04:50.070Z,1715375090.070 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T21:04:50.071Z,1715375090.071 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-05-10T21:04:50.548Z,1715375090.548 [DVL_micro](INFO): Powering down
2024-05-10T21:04:50.571Z,1715375090.571 [CBIT](INFO): Critical error at 20240510T210450
2024-05-10T21:04:57.436Z,1715375097.436 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:05:13.084Z,1715375113.084 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:05:13.608Z,1715375113.608 [NAL9602](ERROR): received:
2024-05-10T21:05:28.560Z,1715375128.560 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:05:44.767Z,1715375144.767 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:05:45.288Z,1715375145.288 [NAL9602](ERROR): received:
2024-05-10T21:05:59.684Z,1715375159.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:06:16.441Z,1715375176.441 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:06:16.960Z,1715375176.960 [NAL9602](ERROR): received:
2024-05-10T21:06:30.812Z,1715375190.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:06:48.127Z,1715375208.127 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:06:48.644Z,1715375208.644 [NAL9602](ERROR): received:
2024-05-10T21:07:01.944Z,1715375221.944 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:07:19.801Z,1715375239.801 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:07:20.316Z,1715375240.316 [NAL9602](ERROR): received:
2024-05-10T21:07:33.068Z,1715375253.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:07:50.323Z,1715375270.323 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T21:07:50.323Z,1715375270.323 [DVL_micro] No Fault, FailCount= 3
2024-05-10T21:07:51.480Z,1715375271.480 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-05-10T21:07:51.996Z,1715375271.996 [NAL9602](ERROR): received:
2024-05-10T21:08:04.192Z,1715375284.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:08:07.824Z,1715375287.824 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T21:08:07.824Z,1715375287.824 [DVL_micro] Communications Fault, FailCount= 1
2024-05-10T21:08:07.824Z,1715375287.824 [DVL_micro](ERROR): Communications Fault
2024-05-10T21:08:07.843Z,1715375287.843 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T21:08:08.320Z,1715375288.320 [DVL_micro](INFO): Powering down
2024-05-10T21:08:09.055Z,1715375289.055 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T21:08:09.055Z,1715375289.055 [DVL_micro] No Fault, FailCount= 1
2024-05-10T21:08:13.192Z,1715375293.192 [CommandExec](IMPORTANT): got command failComponent hardware NAL9602
2024-05-10T21:08:13.193Z,1715375293.193 [NAL9602] Hardware Fault, FailCount= 1
2024-05-10T21:08:13.193Z,1715375293.193 [NAL9602](ERROR): Hardware Fault
2024-05-10T21:08:13.193Z,1715375293.193 [CommandExec](IMPORTANT): NAL9602 failureMode is Hardware Fault
2024-05-10T21:08:13.482Z,1715375293.482 [NAL9602](INFO): Powering down
2024-05-10T21:08:13.528Z,1715375293.528 [CBIT](ERROR): Hardware Fault in component: NAL9602
2024-05-10T21:08:14.310Z,1715375294.310 [CBIT](INFO): Clearing failed state for component NAL9602
2024-05-10T21:08:14.310Z,1715375294.310 [NAL9602] No Fault, FailCount= 1
2024-05-10T21:08:26.816Z,1715375306.816 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T21:08:26.816Z,1715375306.816 [DVL_micro] Communications Fault, FailCount= 2
2024-05-10T21:08:26.816Z,1715375306.816 [DVL_micro](ERROR): Communications Fault
2024-05-10T21:08:26.877Z,1715375306.877 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T21:08:27.312Z,1715375307.312 [DVL_micro](INFO): Powering down
2024-05-10T21:08:28.047Z,1715375308.047 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-05-10T21:08:28.047Z,1715375308.047 [DVL_micro] No Fault, FailCount= 2
2024-05-10T21:08:35.316Z,1715375315.316 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:08:43.830Z,1715375323.830 [NAL9602](INFO): Powering up NAL9602
2024-05-10T21:08:45.072Z,1715375325.072 [CommandExec](IMPORTANT): got command failComponent
2024-05-10T21:08:45.073Z,1715375325.073 [CommandExec](IMPORTANT): Failed components:
2024-05-10T21:08:45.073Z,1715375325.073 [CommandExec](IMPORTANT): No failed Components.
2024-05-10T21:08:45.802Z,1715375325.802 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-05-10T21:08:45.802Z,1715375325.802 [DVL_micro] Communications Fault, FailCount= 3
2024-05-10T21:08:45.802Z,1715375325.802 [DVL_micro](ERROR): Communications Fault
2024-05-10T21:08:45.821Z,1715375325.821 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-05-10T21:08:45.821Z,1715375325.821 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-05-10T21:08:46.308Z,1715375326.308 [DVL_micro](INFO): Powering down
2024-05-10T21:08:46.337Z,1715375326.337 [CBIT](INFO): Critical error at 20240510T210845
2024-05-10T21:08:54.692Z,1715375334.692 [NAL9602](INFO): NAL9602 initialized
2024-05-10T21:09:06.440Z,1715375346.440 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:09:18.978Z,1715375358.978 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=11804, MT Status=1, MTMSN=3422
2024-05-10T21:09:19.032Z,1715375359.032 [NAL9602](INFO): Sent 332 bytes from file Logs/20240510T195653/Express0023.lzma
2024-05-10T21:09:19.032Z,1715375359.032 [NAL9602](INFO): Packets left to send: 2
2024-05-10T21:09:19.469Z,1715375359.469 [NAL9602](INFO): Received command: failc
2024-05-10T21:09:19.470Z,1715375359.470 [CommandExec](IMPORTANT): got command failComponent
2024-05-10T21:09:19.470Z,1715375359.470 [CommandExec](IMPORTANT): Failed components:
2024-05-10T21:09:19.470Z,1715375359.470 [CommandExec](IMPORTANT): DVL_micro: Communications Fault
2024-05-10T21:09:37.564Z,1715375377.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-05-10T21:09:37.671Z,1715375377.671 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=11805, MT Status=1, MTMSN=3423
2024-05-10T21:09:37.720Z,1715375377.720 [NAL9602](INFO): Sent 332 bytes from file Logs/20240510T195653/Express0023.lzma
2024-05-10T21:09:37.720Z,1715375377.720 [NAL9602](INFO): Packets left to send: 1
2024-05-10T21:09:38.136Z,1715375378.136 [NAL9602](INFO): Received command: restart sys
2024-05-10T21:09:38.229Z,1715375378.229 [CommandExec](IMPORTANT): got command restart system
2024-05-10T21:09:40.360Z,1715375380.360 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-10T21:09:40.360Z,1715375380.360 [CommandExec](INFO): Uninitializing the command executive.
2024-05-10T21:09:40.360Z,1715375380.360 [CommandExec](INFO): Uninitializing the command scheduler.
2024-05-10T21:09:40.361Z,1715375380.361 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-05-10T21:09:40.508Z,1715375380.508 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-05-10T21:09:40.508Z,1715375380.508 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-05-10T21:09:40.508Z,1715375380.508 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-10T21:09:40.509Z,1715375380.509 [NavChartDb](INFO): Join timeout helper Thread ID is 1048
2024-05-10T21:09:40.616Z,1715375380.616 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-10T21:09:40.616Z,1715375380.616 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-10T21:09:40.632Z,1715375380.632 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-05-10T21:09:40.632Z,1715375380.632 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-10T21:09:40.633Z,1715375380.633 [DAT](INFO): Join timeout helper Thread ID is 1049
2024-05-10T21:09:40.708Z,1715375380.708 [DAT](INFO): Powering down
2024-05-10T21:09:40.781Z,1715375380.781 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-10T21:09:40.781Z,1715375380.781 [DAT](INFO): Powering down
2024-05-10T21:09:40.782Z,1715375380.782 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-10T21:09:40.796Z,1715375380.796 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-05-10T21:09:40.796Z,1715375380.796 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-10T21:09:40.796Z,1715375380.796 [Radio_Surface](INFO): Join timeout helper Thread ID is 1050
2024-05-10T21:09:41.012Z,1715375381.012 [Radio_Surface](INFO): Powering down
2024-05-10T21:09:41.013Z,1715375381.013 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-10T21:09:41.013Z,1715375381.013 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-10T21:09:41.024Z,1715375381.024 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-05-10T21:09:41.024Z,1715375381.024 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-10T21:09:41.024Z,1715375381.024 [Onboard](INFO): Join timeout helper Thread ID is 1051
2024-05-10T21:09:44.660Z,1715375384.660 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-10T21:09:44.660Z,1715375384.660 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-10T21:09:44.676Z,1715375384.676 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-05-10T21:09:44.676Z,1715375384.676 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-10T21:09:44.676Z,17