2024-09-16T18:05:51.330Z,1726509951.330 [Supervisor](DEBUG): Initializing supervisor.
2024-09-16T18:05:51.335Z,1726509951.335 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-09-16T18:05:51.335Z,1726509951.335 [SyncHandler](INFO): Protected caller Thread ID is 836
2024-09-16T18:05:51.335Z,1726509951.335 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-09-16T18:05:51.336Z,1726509951.336 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-09-16T18:05:51.337Z,1726509951.337 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2024-09-16T18:05:51.341Z,1726509951.341 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-09-16T18:05:51.359Z,1726509951.359 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-09-16T18:05:51.360Z,1726509951.360 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-09-16T18:05:51.360Z,1726509951.360 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2024-09-16T18:05:51.364Z,1726509951.364 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-09-16T18:05:51.365Z,1726509951.365 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-09-16T18:05:51.366Z,1726509951.366 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2024-09-16T18:05:51.368Z,1726509951.368 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-09-16T18:05:51.369Z,1726509951.369 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-09-16T18:05:51.369Z,1726509951.369 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2024-09-16T18:05:51.373Z,1726509951.373 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-09-16T18:05:51.373Z,1726509951.373 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-09-16T18:05:51.377Z,1726509951.377 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-09-16T18:05:51.740Z,1726509951.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-09-16T18:05:51.741Z,1726509951.741 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-09-16T18:05:51.833Z,1726509951.833 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-09-16T18:05:51.954Z,1726509951.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-09-16T18:05:51.955Z,1726509951.955 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-09-16T18:05:52.098Z,1726509952.098 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-09-16T18:05:52.099Z,1726509952.099 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-09-16T18:05:52.471Z,1726509952.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-09-16T18:05:52.472Z,1726509952.472 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-09-16T18:05:52.582Z,1726509952.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-09-16T18:05:52.582Z,1726509952.582 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-09-16T18:05:53.040Z,1726509953.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-09-16T18:05:53.041Z,1726509953.041 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-09-16T18:05:53.273Z,1726509953.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-09-16T18:05:53.274Z,1726509953.274 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-09-16T18:05:53.366Z,1726509953.366 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-09-16T18:05:53.625Z,1726509953.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-09-16T18:05:53.626Z,1726509953.626 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-09-16T18:05:53.867Z,1726509953.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-09-16T18:05:53.867Z,1726509953.867 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-09-16T18:05:54.427Z,1726509954.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-09-16T18:05:54.428Z,1726509954.428 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-09-16T18:05:54.656Z,1726509954.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-09-16T18:05:54.658Z,1726509954.658 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-09-16T18:05:54.973Z,1726509954.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-09-16T18:05:54.975Z,1726509954.975 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-09-16T18:05:55.883Z,1726509955.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-09-16T18:05:55.884Z,1726509955.884 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-09-16T18:05:56.494Z,1726509956.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-09-16T18:05:56.496Z,1726509956.496 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2024-09-16T18:05:56.499Z,1726509956.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2024-09-16T18:05:56.612Z,1726509956.612 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2024-09-16T18:05:56.743Z,1726509956.743 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2024-09-16T18:05:56.839Z,1726509956.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2024-09-16T18:05:56.961Z,1726509956.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2024-09-16T18:05:57.060Z,1726509957.060 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2024-09-16T18:05:57.331Z,1726509957.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-09-16T18:05:57.332Z,1726509957.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2024-09-16T18:05:57.457Z,1726509957.457 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2024-09-16T18:05:57.569Z,1726509957.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2024-09-16T18:05:57.686Z,1726509957.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2024-09-16T18:05:57.844Z,1726509957.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2024-09-16T18:05:57.942Z,1726509957.942 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-09-16T18:05:57.958Z,1726509957.958 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-09-16T18:05:58.102Z,1726509958.102 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-09-16T18:05:58.103Z,1726509958.103 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-09-16T18:05:58.173Z,1726509958.173 [VerticalControl](DEBUG): Construct VerticalControl.
2024-09-16T18:05:58.234Z,1726509958.234 [VerticalControl] Loaded
2024-09-16T18:05:58.234Z,1726509958.234 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-09-16T18:05:58.237Z,1726509958.237 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-09-16T18:05:58.285Z,1726509958.285 [HorizontalControl] Loaded
2024-09-16T18:05:58.285Z,1726509958.285 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-09-16T18:05:58.287Z,1726509958.287 [SpeedControl](DEBUG): Construct SpeedControl.
2024-09-16T18:05:58.290Z,1726509958.290 [SpeedControl] Loaded
2024-09-16T18:05:58.291Z,1726509958.291 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-09-16T18:05:58.293Z,1726509958.293 [LoopControl](DEBUG): Construct LoopControl.
2024-09-16T18:05:58.294Z,1726509958.294 [LoopControl] Loaded
2024-09-16T18:05:58.294Z,1726509958.294 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-09-16T18:05:58.294Z,1726509958.294 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-09-16T18:05:58.296Z,1726509958.296 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-09-16T18:05:58.395Z,1726509958.395 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-09-16T18:05:58.396Z,1726509958.396 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-09-16T18:05:59.231Z,1726509959.231 [AHRS_M2] Loaded
2024-09-16T18:05:59.231Z,1726509959.231 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-09-16T18:05:59.484Z,1726509959.484 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4
2024-09-16T18:05:59.485Z,1726509959.485 [BackseatComponent] Loaded
2024-09-16T18:05:59.485Z,1726509959.485 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-09-16T18:05:59.486Z,1726509959.486 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2024-09-16T18:05:59.486Z,1726509959.486 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922
2024-09-16T18:05:59.489Z,1726509959.489 [LcmUniversalReporter] Loaded
2024-09-16T18:05:59.489Z,1726509959.489 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-09-16T18:06:00.300Z,1726509960.300 [BPC1] Loaded
2024-09-16T18:06:00.300Z,1726509960.300 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-09-16T18:06:00.392Z,1726509960.392 [DAT] Loaded
2024-09-16T18:06:00.392Z,1726509960.392 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-09-16T18:06:00.393Z,1726509960.393 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408E64E0
2024-09-16T18:06:00.393Z,1726509960.393 [DAT ThreadHandler](INFO): Protected caller Thread ID is 923
2024-09-16T18:06:00.541Z,1726509960.541 [DataOverHttps] Loaded
2024-09-16T18:06:00.541Z,1726509960.541 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-09-16T18:06:00.542Z,1726509960.542 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409164E0
2024-09-16T18:06:00.543Z,1726509960.543 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2024-09-16T18:06:00.566Z,1726509960.566 [Depth_Keller] Loaded
2024-09-16T18:06:00.566Z,1726509960.566 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-09-16T18:06:00.571Z,1726509960.571 [DropWeight] Loaded
2024-09-16T18:06:00.571Z,1726509960.571 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-09-16T18:06:00.635Z,1726509960.635 [NAL9602] Loaded
2024-09-16T18:06:00.636Z,1726509960.636 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-09-16T18:06:00.665Z,1726509960.665 [Onboard] Loaded
2024-09-16T18:06:00.665Z,1726509960.665 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-09-16T18:06:00.666Z,1726509960.666 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409464E0
2024-09-16T18:06:00.667Z,1726509960.667 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2024-09-16T18:06:00.681Z,1726509960.681 [Power24vConverter] Loaded
2024-09-16T18:06:00.681Z,1726509960.681 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-09-16T18:06:00.696Z,1726509960.696 [Radio_Surface] Loaded
2024-09-16T18:06:00.696Z,1726509960.696 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-09-16T18:06:00.697Z,1726509960.697 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409764E0
2024-09-16T18:06:00.697Z,1726509960.697 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2024-09-16T18:06:00.713Z,1726509960.713 [Sonardyne_Nano] Loaded
2024-09-16T18:06:00.713Z,1726509960.713 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-09-16T18:06:00.737Z,1726509960.737 [Waterlinked] Loaded
2024-09-16T18:06:00.737Z,1726509960.737 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-09-16T18:06:00.737Z,1726509960.737 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-09-16T18:06:00.738Z,1726509960.738 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-09-16T18:06:00.756Z,1726509960.756 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-09-16T18:06:00.758Z,1726509960.758 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-09-16T18:06:00.831Z,1726509960.831 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-09-16T18:06:00.831Z,1726509960.831 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-09-16T18:06:01.003Z,1726509961.003 [SBIT](DEBUG): Construct Startup Built In Test.
2024-09-16T18:06:01.012Z,1726509961.012 [SBIT] Loaded
2024-09-16T18:06:01.012Z,1726509961.012 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-09-16T18:06:01.015Z,1726509961.015 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-09-16T18:06:01.027Z,1726509961.027 [IBIT] Loaded
2024-09-16T18:06:01.028Z,1726509961.028 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-09-16T18:06:01.033Z,1726509961.033 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-09-16T18:06:01.138Z,1726509961.138 [CBIT] Loaded
2024-09-16T18:06:01.138Z,1726509961.138 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-09-16T18:06:01.138Z,1726509961.138 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-09-16T18:06:01.139Z,1726509961.139 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-09-16T18:06:01.550Z,1726509961.550 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-09-16T18:06:01.551Z,1726509961.551 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-09-16T18:06:01.622Z,1726509961.622 [DepthRateCalculator] Loaded
2024-09-16T18:06:01.622Z,1726509961.622 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-09-16T18:06:01.627Z,1726509961.627 [PitchRateCalculator] Loaded
2024-09-16T18:06:01.627Z,1726509961.627 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-09-16T18:06:01.638Z,1726509961.638 [SpeedCalculator] Loaded
2024-09-16T18:06:01.638Z,1726509961.638 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-09-16T18:06:01.642Z,1726509961.642 [YawRateCalculator] Loaded
2024-09-16T18:06:01.643Z,1726509961.643 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-09-16T18:06:01.662Z,1726509961.662 [ElevatorOffsetCalculator] Loaded
2024-09-16T18:06:01.663Z,1726509961.663 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-09-16T18:06:01.663Z,1726509961.663 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-09-16T18:06:01.664Z,1726509961.664 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-09-16T18:06:01.879Z,1726509961.879 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-09-16T18:06:01.880Z,1726509961.880 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-09-16T18:06:02.017Z,1726509962.017 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-09-16T18:06:02.018Z,1726509962.018 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-09-16T18:06:02.061Z,1726509962.061 [DeadReckonUsingSpeedCalculator] Loaded
2024-09-16T18:06:02.061Z,1726509962.061 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-09-16T18:06:02.104Z,1726509962.104 [DeadReckonWithRespectToSeafloor] Loaded
2024-09-16T18:06:02.104Z,1726509962.104 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2024-09-16T18:06:02.118Z,1726509962.118 [NavChart] Loaded
2024-09-16T18:06:02.118Z,1726509962.118 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-09-16T18:06:02.124Z,1726509962.124 [UniversalFixResidualReporter] Loaded
2024-09-16T18:06:02.124Z,1726509962.124 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-09-16T18:06:02.124Z,1726509962.124 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-09-16T18:06:02.125Z,1726509962.125 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-09-16T18:06:02.291Z,1726509962.291 [BuoyancyServo] Loaded
2024-09-16T18:06:02.291Z,1726509962.291 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-09-16T18:06:02.314Z,1726509962.314 [ElevatorServo] Loaded
2024-09-16T18:06:02.314Z,1726509962.314 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-09-16T18:06:02.336Z,1726509962.336 [MassServo] Loaded
2024-09-16T18:06:02.336Z,1726509962.336 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-09-16T18:06:02.358Z,1726509962.358 [RudderServo] Loaded
2024-09-16T18:06:02.358Z,1726509962.358 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-09-16T18:06:02.374Z,1726509962.374 [ThrusterHE] Loaded
2024-09-16T18:06:02.375Z,1726509962.375 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-09-16T18:06:02.375Z,1726509962.375 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-09-16T18:06:02.376Z,1726509962.376 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-09-16T18:06:02.602Z,1726509962.602 [CTD_Seabird] Loaded
2024-09-16T18:06:02.602Z,1726509962.602 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-09-16T18:06:02.603Z,1726509962.603 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BF14E0
2024-09-16T18:06:02.603Z,1726509962.603 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2024-09-16T18:06:02.640Z,1726509962.640 [ESPComponent] Loaded
2024-09-16T18:06:02.640Z,1726509962.640 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-09-16T18:06:02.662Z,1726509962.662 [PAR_Licor] Loaded
2024-09-16T18:06:02.662Z,1726509962.662 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-09-16T18:06:02.707Z,1726509962.707 [WetLabsBB2FL] Loaded
2024-09-16T18:06:02.708Z,1726509962.708 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-09-16T18:06:02.709Z,1726509962.709 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C214E0
2024-09-16T18:06:02.709Z,1726509962.709 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2024-09-16T18:06:02.710Z,1726509962.710 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-09-16T18:06:02.716Z,1726509962.716 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-09-16T18:06:02.719Z,1726509962.719 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-09-16T18:06:02.730Z,1726509962.730 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-09-16T18:06:02.731Z,1726509962.731 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C514E0
2024-09-16T18:06:02.732Z,1726509962.732 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2024-09-16T18:06:02.736Z,1726509962.736 [Supervisor](INFO): Main Thread ID is 827
2024-09-16T18:06:02.736Z,1726509962.736 [Supervisor](DEBUG): Running supervisor.
2024-09-16T18:06:02.737Z,1726509962.737 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2024-09-16T18:06:02.738Z,1726509962.738 [CommandExec](INFO): Initializing the command executive.
2024-09-16T18:06:02.739Z,1726509962.739 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2024-09-16T18:06:02.741Z,1726509962.741 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2024-09-16T18:06:02.741Z,1726509962.741 [controlThread](DEBUG): Initializing ControlThread
2024-09-16T18:06:02.742Z,1726509962.742 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-09-16T18:06:02.744Z,1726509962.744 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-09-16T18:06:02.745Z,1726509962.745 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-09-16T18:06:02.745Z,1726509962.745 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-09-16T18:06:02.750Z,1726509962.750 [SBIT](INFO): Initialize SBIT Component.
2024-09-16T18:06:02.751Z,1726509962.751 [SBIT](IMPORTANT): git: 2024-09-16_B
2024-09-16T18:06:02.751Z,1726509962.751 [SBIT](INFO): git hash: 99f9462fcf59f51f8462e4e844b71b4239fb11a8
2024-09-16T18:06:02.751Z,1726509962.751 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-09-16T18:06:02.752Z,1726509962.752 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023
2024-09-16T18:06:02.754Z,1726509962.754 [SBIT](INFO): Beginning SBIT in 81.000000 seconds.
2024-09-16T18:06:02.754Z,1726509962.754 [IBIT](INFO): Initialize IBIT Component.
2024-09-16T18:06:02.755Z,1726509962.755 [CBIT](DEBUG): Initialize CBIT Component.
2024-09-16T18:06:02.756Z,1726509962.756 [logger ThreadHandler](INFO): Handler Thread ID is 933
2024-09-16T18:06:02.768Z,1726509962.768 [CBIT](DEBUG): Initialized mux pins.
2024-09-16T18:06:02.768Z,1726509962.768 [CBIT](DEBUG): Initializing the watchdog timer.
2024-09-16T18:06:02.776Z,1726509962.776 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2024-09-16T18:06:02.788Z,1726509962.788 [DAT ThreadHandler](INFO): Handler Thread ID is 935
2024-09-16T18:06:02.789Z,1726509962.789 [DAT](INFO): Powering up
2024-09-16T18:06:02.789Z,1726509962.789 [DAT](DEBUG): Initializing DAT.
2024-09-16T18:06:02.792Z,1726509962.792 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-09-16T18:06:02.792Z,1726509962.792 [CBIT](DEBUG): Initializing heartbeat.
2024-09-16T18:06:02.794Z,1726509962.794 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937
2024-09-16T18:06:02.796Z,1726509962.796 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-09-16T18:06:02.805Z,1726509962.805 [Onboard ThreadHandler](INFO): Handler Thread ID is 938
2024-09-16T18:06:02.822Z,1726509962.822 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939
2024-09-16T18:06:02.841Z,1726509962.841 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940
2024-09-16T18:06:02.841Z,1726509962.841 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-09-16T18:06:02.845Z,1726509962.845 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 942
2024-09-16T18:06:02.848Z,1726509962.848 [WetLabsBB2FL](INFO): Powering up
2024-09-16T18:06:02.850Z,1726509962.850 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944
2024-09-16T18:06:02.857Z,1726509962.857 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2024-09-16T18:06:02.857Z,1726509962.857 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2024-09-16T18:06:02.857Z,1726509962.857 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2024-09-16T18:06:02.857Z,1726509962.857 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2024-09-16T18:06:02.857Z,1726509962.857 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-09-16T18:06:02.857Z,1726509962.857 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-09-16T18:06:02.857Z,1726509962.857 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-09-16T18:06:02.858Z,1726509962.858 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-09-16T18:06:02.858Z,1726509962.858 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2024-09-16T18:06:02.858Z,1726509962.858 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2024-09-16T18:06:02.858Z,1726509962.858 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2024-09-16T18:06:02.858Z,1726509962.858 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2024-09-16T18:06:02.864Z,1726509962.864 [CBIT](DEBUG): Deactivating GF circuits.
2024-09-16T18:06:02.864Z,1726509962.864 [CBIT](DEBUG): Deactivating emergency mode.
2024-09-16T18:06:02.904Z,1726509962.904 [CBIT](DEBUG): Backplane powered.
2024-09-16T18:06:02.904Z,1726509962.904 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-09-16T18:06:02.905Z,1726509962.905 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-09-16T18:06:02.905Z,1726509962.905 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-09-16T18:06:02.905Z,1726509962.905 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-09-16T18:06:02.906Z,1726509962.906 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-09-16T18:06:02.907Z,1726509962.907 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-16T18:06:02.908Z,1726509962.908 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-09-16T18:06:02.908Z,1726509962.908 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-09-16T18:06:02.912Z,1726509962.912 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-09-16T18:06:02.913Z,1726509962.913 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-09-16T18:06:02.913Z,1726509962.913 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-09-16T18:06:02.924Z,1726509962.924 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-09-16T18:06:02.970Z,1726509962.970 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-09-16T18:06:02.991Z,1726509962.991 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T18:06:03.011Z,1726509963.011 [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-09-16T18:06:03.036Z,1726509963.036 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-09-16T18:06:03.036Z,1726509963.036 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-09-16T18:06:03.043Z,1726509963.043 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-09-16T18:06:03.240Z,1726509963.240 [Radio_Surface](INFO): Powering up
2024-09-16T18:06:03.303Z,1726509963.303 [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-09-16T18:06:03.317Z,1726509963.317 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-09-16T18:06:03.318Z,1726509963.318 [Default:A.Wait](DEBUG): Construct Wait.
2024-09-16T18:06:03.321Z,1726509963.321 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T18:06:03.374Z,1726509963.374 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-09-16T18:06:03.376Z,1726509963.376 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-09-16T18:06:03.406Z,1726509963.406 [Default:E.Execute](DEBUG): Construct Execute.
2024-09-16T18:06:03.409Z,1726509963.409 [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-09-16T18:06:03.414Z,1726509963.414 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-09-16T18:06:03.437Z,1726509963.437 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-09-16T18:06:03.589Z,1726509963.589 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2024-09-16T18:06:03.590Z,1726509963.590 [Power24vConverter](INFO): Powering up.
2024-09-16T18:06:03.591Z,1726509963.591 [Sonardyne_Nano](INFO): Initializing.
2024-09-16T18:06:03.629Z,1726509963.629 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-09-16T18:06:03.651Z,1726509963.651 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:06:03.656Z,1726509963.656 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-09-16T18:06:03.657Z,1726509963.657 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:06:03.664Z,1726509963.664 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-09-16T18:06:03.665Z,1726509963.665 [MassServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:06:03.673Z,1726509963.673 [MassServo](DEBUG): Initializing MassServo.
2024-09-16T18:06:03.675Z,1726509963.675 [RudderServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:06:03.684Z,1726509963.684 [RudderServo](DEBUG): Initializing RudderServo.
2024-09-16T18:06:03.685Z,1726509963.685 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-09-16T18:06:03.692Z,1726509963.692 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-09-16T18:06:04.956Z,1726509964.956 [WetLabsBB2FL](INFO): Powering down
2024-09-16T18:06:05.850Z,1726509965.850 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-09-16T18:06:07.084Z,1726509967.084 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2024-09-16T18:06:09.369Z,1726509969.369 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-09-16T18:06:15.641Z,1726509975.641 [DAT](INFO): DAT read:
2024-09-16T18:06:15.642Z,1726509975.642 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-09-16T18:06:17.405Z,1726509977.405 [DAT](INFO): DAT read: MF Frequency Band
2024-09-16T18:06:17.406Z,1726509977.406 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-09-16T18:06:17.407Z,1726509977.407 [DAT](INFO): DAT read: Sep 16 2024 18:06:10
2024-09-16T18:06:18.413Z,1726509978.413 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-09-16T18:06:18.414Z,1726509978.414 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-09-16T18:06:18.414Z,1726509978.414 [DAT](INFO): commRate: 800
2024-09-16T18:06:20.480Z,1726509980.480 [DAT](INFO): entering command mode
2024-09-16T18:06:20.681Z,1726509980.681 [DAT](INFO): DAT read:
2024-09-16T18:06:20.681Z,1726509980.681 [DAT](INFO): DAT read: user:1>
2024-09-16T18:06:20.681Z,1726509980.681 [DAT](INFO): setting verbose to 3
2024-09-16T18:06:20.933Z,1726509980.933 [DAT](INFO): DAT read: user:1>
2024-09-16T18:06:20.934Z,1726509980.934 [DAT](INFO): DAT read: Verbose | 3
2024-09-16T18:06:20.934Z,1726509980.934 [DAT](INFO): set verbose to 3
2024-09-16T18:06:20.934Z,1726509980.934 [DAT](INFO): setting DatVerbose to 27440
2024-09-16T18:06:21.184Z,1726509981.184 [DAT](INFO): DAT read: user:2>
2024-09-16T18:06:21.185Z,1726509981.185 [DAT](INFO): DAT read: DatVerbose | 27440
2024-09-16T18:06:21.186Z,1726509981.186 [DAT](INFO): set DatVerbose to 27440
2024-09-16T18:06:21.186Z,1726509981.186 [DAT](INFO): setting transmit power to 8
2024-09-16T18:06:21.436Z,1726509981.436 [DAT](INFO): DAT read: user:3>
2024-09-16T18:06:21.437Z,1726509981.437 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-09-16T18:06:21.438Z,1726509981.438 [DAT](INFO): set transmit power to 8
2024-09-16T18:06:21.438Z,1726509981.438 [DAT](INFO): setting local address to 1
2024-09-16T18:06:21.688Z,1726509981.688 [DAT](INFO): DAT read: user:4>
2024-09-16T18:06:21.689Z,1726509981.689 [DAT](INFO): DAT read: LocalAddr | 1
2024-09-16T18:06:21.690Z,1726509981.690 [DAT](INFO): set local address to 1
2024-09-16T18:06:21.691Z,1726509981.691 [DAT](INFO): Setting time to: 18:6:21 And date to:9/16/2024
2024-09-16T18:06:21.941Z,1726509981.941 [DAT](INFO): DAT read: user:5>
2024-09-16T18:06:21.941Z,1726509981.941 [DAT](INFO): DAT read: Mon Sep 16, 2024 18:06:21
2024-09-16T18:06:21.942Z,1726509981.942 [DAT](INFO): Local DAT time set to Mon Sep 16, 2024 18:06:21
2024-09-16T18:06:30.910Z,1726509990.910 [NAL9602](INFO): Powering up NAL9602
2024-09-16T18:06:41.842Z,1726510001.842 [NAL9602](INFO): NAL9602 initialized
2024-09-16T18:06:43.103Z,1726510003.103 [Waterlinked](ERROR): only read 2 of 5 data items. Device response::BI, -3, +4, +,A
2024-09-16T18:07:10.091Z,1726510030.091 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:07:15.384Z,1726510035.384 [NAL9602](INFO): SBD MO Status=2, MOMSN=16699, MT Status=2, MTMSN=0
2024-09-16T18:07:15.385Z,1726510035.385 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-16T18:07:24.238Z,1726510044.238 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:07:24.390Z,1726510044.390 [SBIT](IMPORTANT): Beginning Startup BIT
2024-09-16T18:07:24.394Z,1726510044.394 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T18:07:30.715Z,1726510050.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=16699, MT Status=2, MTMSN=0
2024-09-16T18:07:30.715Z,1726510050.715 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-16T18:07:35.260Z,1726510055.260 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002892
CHAN A1 (24V): 0.004703
CHAN A2 (12V): 0.002308
CHAN A3 (5V): -0.001151
CHAN B0 (3.3V): 0.000638
CHAN B1 (3.15aV): 0.000540
CHAN B2 (3.15bV): 0.000290
CHAN B3 (GND): 0.000097
OPEN: 0.006813
Full Scale: +/- 1 mA
2024-09-16T18:07:37.236Z,1726510057.236 [Waterlinked](INFO): Powering down
2024-09-16T18:07:38.016Z,1726510058.016 [NAL9602](INFO): SBD MO Status=0, MOMSN=16699, MT Status=0, MTMSN=0
2024-09-16T18:07:38.016Z,1726510058.016 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:07:38.368Z,1726510058.368 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:07:39.187Z,1726510059.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180737.00,A,3648.14005,N,12147.22941,W,0.039,0.00,160924,,,A*70
2024-09-16T18:07:39.190Z,1726510059.190 [NAL9602](INFO): GPS fix at 20240916T180737: (36.802334, -121.787157)
2024-09-16T18:07:52.514Z,1726510072.514 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:08:06.655Z,1726510086.655 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:08:11.525Z,1726510091.525 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:08:18.420Z,1726510098.420 [SBIT](IMPORTANT): SBIT PASSED
2024-09-16T18:08:18.420Z,1726510098.420 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-09-16T18:08:18.421Z,1726510098.421 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2024-09-16T18:08:18.421Z,1726510098.421 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 0.600000 count_per_second;
2024-09-16T18:08:18.421Z,1726510098.421 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 0.600000 count_per_second;
2024-09-16T18:08:18.421Z,1726510098.421 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_caseHumidity 10.000000 percent;
2024-09-16T18:08:18.421Z,1726510098.421 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_diatoms 30.000000 count_per_milliliter;
2024-09-16T18:08:18.422Z,1726510098.422 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_dinoflagellates 30.000000 count_per_milliliter;
2024-09-16T18:08:18.422Z,1726510098.422 [SBIT](IMPORTANT): Express linearApproximation _.predict_plankton_data_pct_dinos_sqerr 0.100000 none;
2024-09-16T18:08:18.422Z,1726510098.422 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2024-09-16T18:08:18.422Z,1726510098.422 [SBIT](IMPORTANT): Normal all _.predict_plankton_data_pct_dinos_sqerr;
2024-09-16T18:08:18.422Z,1726510098.422 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=239.155981 cubic_centimeter;
2024-09-16T18:08:18.422Z,1726510098.422 [SBIT](IMPORTANT): VerticalControl.massDefault=15.358415 millimeter;
2024-09-16T18:08:18.795Z,1726510098.795 [MissionManager](IMPORTANT): Started mission Startup
2024-09-16T18:08:18.795Z,1726510098.795 [Startup] Running Loop=1
2024-09-16T18:08:18.795Z,1726510098.795 [Startup](DEBUG): Aggregate::initialize Startup
2024-09-16T18:08:18.795Z,1726510098.795 [Startup:A.GoToSurface] Running Loop=1
2024-09-16T18:08:18.796Z,1726510098.796 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T18:08:18.796Z,1726510098.796 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T18:08:18.797Z,1726510098.797 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T18:08:18.797Z,1726510098.797 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T18:08:18.797Z,1726510098.797 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T18:08:18.798Z,1726510098.798 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T18:08:18.798Z,1726510098.798 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T18:08:18.800Z,1726510098.800 [Startup:StartupSatComms] Running Loop=1
2024-09-16T18:08:18.800Z,1726510098.800 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-09-16T18:08:18.800Z,1726510098.800 [Startup:StartupSatComms:A] Running Loop=1
2024-09-16T18:08:19.208Z,1726510099.208 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-09-16T18:08:20.391Z,1726510100.391 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:08:20.815Z,1726510100.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180819.00,A,3648.14128,N,12147.22996,W,0.019,0.00,160924,,,A*75
2024-09-16T18:08:20.818Z,1726510100.818 [NAL9602](INFO): GPS fix at 20240916T180819: (36.802355, -121.787166)
2024-09-16T18:08:20.834Z,1726510100.834 [Startup:StartupSatComms:A] Stopped
2024-09-16T18:08:20.834Z,1726510100.834 [Startup:StartupSatComms:B] Running Loop=1
2024-09-16T18:08:21.253Z,1726510101.253 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-16T18:08:33.722Z,1726510113.722 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:08:34.147Z,1726510114.147 [NAL9602](INFO): SBD MO Status=0, MOMSN=16700, MT Status=0, MTMSN=0
2024-09-16T18:08:34.147Z,1726510114.147 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:08:47.054Z,1726510127.054 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:09:03.240Z,1726510143.240 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T18:09:03.240Z,1726510143.240 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:09:03.251Z,1726510143.251 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:09:03.673Z,1726510143.673 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:09:03.673Z,1726510143.673 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T18:09:04.898Z,1726510144.898 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:09:21.017Z,1726510161.017 [Startup:StartupSatComms:B](INFO): Timed out from 2024-09-16T18:08:20.8Z
2024-09-16T18:09:21.017Z,1726510161.017 [Startup:StartupSatComms:B] Stopped
2024-09-16T18:09:21.017Z,1726510161.017 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-09-16T18:09:21.017Z,1726510161.017 [Startup:StartupSatComms] Stopped
2024-09-16T18:09:21.017Z,1726510161.017 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-09-16T18:09:21.018Z,1726510161.018 [Startup](INFO): Completed Startup
2024-09-16T18:09:21.018Z,1726510161.018 [MissionManager](INFO): Startup is completed.
2024-09-16T18:09:21.018Z,1726510161.018 [MissionManager](INFO): Uninitializing Mission Startup
2024-09-16T18:09:21.019Z,1726510161.019 [Startup] Stopped
2024-09-16T18:09:21.019Z,1726510161.019 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-09-16T18:09:21.019Z,1726510161.019 [Startup:A.GoToSurface] Stopped
2024-09-16T18:09:21.019Z,1726510161.019 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-16T18:09:21.431Z,1726510161.431 [MissionManager](IMPORTANT): Started mission Default
2024-09-16T18:09:21.431Z,1726510161.431 [Default] Running Loop=1
2024-09-16T18:09:21.431Z,1726510161.431 [Default](DEBUG): Aggregate::initialize Default
2024-09-16T18:09:21.431Z,1726510161.431 [Default:B.GoToSurface] Running Loop=1
2024-09-16T18:09:21.431Z,1726510161.431 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T18:09:21.431Z,1726510161.431 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T18:09:21.436Z,1726510161.436 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T18:09:21.436Z,1726510161.436 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T18:09:21.437Z,1726510161.437 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T18:09:21.437Z,1726510161.437 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T18:09:21.437Z,1726510161.437 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T18:09:21.437Z,1726510161.437 [Default:A.Wait] Running Loop=1
2024-09-16T18:09:21.437Z,1726510161.437 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:09:34.763Z,1726510174.763 [Default:A.Wait](INFO): Done Waiting.
2024-09-16T18:09:34.763Z,1726510174.763 [Default:A.Wait] Stopped
2024-09-16T18:09:34.763Z,1726510174.763 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:09:35.155Z,1726510175.155 [Default:CheckIn] Running Loop=1
2024-09-16T18:09:35.155Z,1726510175.155 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:09:35.155Z,1726510175.155 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:09:35.552Z,1726510175.552 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-09-16T18:09:37.157Z,1726510177.157 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180935.00,A,3648.14082,N,12147.23040,W,0.000,0.00,160924,,,D*75
2024-09-16T18:09:37.159Z,1726510177.159 [NAL9602](INFO): GPS fix at 20240916T180935: (36.802347, -121.787173)
2024-09-16T18:09:37.174Z,1726510177.174 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:09:37.174Z,1726510177.174 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:09:37.601Z,1726510177.601 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-16T18:09:46.859Z,1726510186.859 [NAL9602](INFO): SBD MO Status=0, MOMSN=16701, MT Status=0, MTMSN=0
2024-09-16T18:09:46.859Z,1726510186.859 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:09:57.094Z,1726510197.094 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004527
2024-09-16T18:10:04.573Z,1726510204.573 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240916T172703/Courier0022.lzma
2024-09-16T18:10:05.574Z,1726510205.574 [DataOverHttps](INFO): Moved sent file to Logs/20240916T172703/Courier0022.lzma.bak
2024-09-16T18:10:05.574Z,1726510205.574 [DataOverHttps](INFO): SBD MOMSN=23190775
2024-09-16T18:10:17.561Z,1726510217.561 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:10:22.480Z,1726510222.480 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20240916T180551/Courier0000.lzma
2024-09-16T18:10:23.483Z,1726510223.483 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0000.lzma.bak
2024-09-16T18:10:23.483Z,1726510223.483 [DataOverHttps](INFO): SBD MOMSN=23190786
2024-09-16T18:10:39.391Z,1726510239.391 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0004.lzma
2024-09-16T18:10:40.394Z,1726510240.394 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0004.lzma.bak
2024-09-16T18:10:40.395Z,1726510240.395 [DataOverHttps](INFO): SBD MOMSN=23190793
2024-09-16T18:11:03.569Z,1726510263.569 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240916T172703/Express0023.lzma
2024-09-16T18:11:04.570Z,1726510264.570 [DataOverHttps](INFO): Moved sent file to Logs/20240916T172703/Express0023.lzma.bak
2024-09-16T18:11:04.570Z,1726510264.570 [DataOverHttps](INFO): SBD MOMSN=23190842
2024-09-16T18:11:21.065Z,1726510281.065 [DataOverHttps](INFO): Sending 1079 bytes from file Logs/20240916T180551/Express0001.lzma
2024-09-16T18:11:22.066Z,1726510282.066 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0001.lzma.bak
2024-09-16T18:11:22.066Z,1726510282.066 [DataOverHttps](INFO): SBD MOMSN=23190846
2024-09-16T18:11:22.278Z,1726510282.278 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:11:38.466Z,1726510298.466 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:11:39.987Z,1726510299.987 [DataOverHttps](INFO): Sending 108 bytes from file Logs/20240916T180551/Express0005.lzma
2024-09-16T18:11:40.990Z,1726510300.990 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0005.lzma.bak
2024-09-16T18:11:40.990Z,1726510300.990 [DataOverHttps](INFO): SBD MOMSN=23190870
2024-09-16T18:11:42.123Z,1726510302.123 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:11:42.123Z,1726510302.123 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:11:42.123Z,1726510302.123 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:11:53.018Z,1726510313.018 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:12:04.349Z,1726510324.349 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T18:12:04.349Z,1726510324.349 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:12:04.390Z,1726510324.390 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:12:04.758Z,1726510324.758 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:12:04.758Z,1726510324.758 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T18:12:08.372Z,1726510328.372 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:12:23.336Z,1726510343.336 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:12:38.302Z,1726510358.302 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:12:53.667Z,1726510373.667 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:13:09.019Z,1726510389.019 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:13:23.966Z,1726510403.966 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:13:38.511Z,1726510418.511 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-09-16T18:14:31.447Z,1726510471.447 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2024-09-16T18:14:31.447Z,1726510471.447 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2024-09-16T18:14:31.450Z,1726510471.450 [BPC1](INFO): Received data from all battery sticks.
2024-09-16T18:15:05.463Z,1726510505.463 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T18:15:05.463Z,1726510505.463 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:15:05.475Z,1726510505.475 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:15:05.896Z,1726510505.896 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:15:05.896Z,1726510505.896 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T18:16:42.819Z,1726510602.819 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:16:42.819Z,1726510602.819 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:16:42.819Z,1726510602.819 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:16:42.819Z,1726510602.819 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:16:43.232Z,1726510603.232 [Default:CheckIn:D] Stopped
2024-09-16T18:16:43.232Z,1726510603.232 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:16:43.647Z,1726510603.647 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.363344 min
2024-09-16T18:16:43.647Z,1726510603.647 [Default:CheckIn:E] Stopped
2024-09-16T18:16:43.647Z,1726510603.647 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:16:43.647Z,1726510603.647 [Default:CheckIn] Stopped
2024-09-16T18:16:43.647Z,1726510603.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:16:43.647Z,1726510603.647 [Default:CheckIn](INFO): Running loop #2
2024-09-16T18:16:43.647Z,1726510603.647 [Default:CheckIn] Running Loop=2
2024-09-16T18:16:43.652Z,1726510603.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:16:43.652Z,1726510603.652 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:16:45.635Z,1726510605.635 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181644.00,A,3648.13905,N,12147.22918,W,0.039,0.00,160924,,,D*73
2024-09-16T18:16:45.638Z,1726510605.638 [NAL9602](INFO): GPS fix at 20240916T181644: (36.802318, -121.787153)
2024-09-16T18:16:45.653Z,1726510605.653 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:16:45.653Z,1726510605.653 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:16:56.125Z,1726510616.125 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240916T180551/Courier0007.lzma
2024-09-16T18:16:57.126Z,1726510617.126 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0007.lzma.bak
2024-09-16T18:16:57.126Z,1726510617.126 [DataOverHttps](INFO): SBD MOMSN=23190935
2024-09-16T18:16:59.023Z,1726510619.023 [NAL9602](INFO): SBD MO Status=0, MOMSN=16702, MT Status=0, MTMSN=0
2024-09-16T18:16:59.023Z,1726510619.023 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:17:13.095Z,1726510633.095 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20240916T180551/Express0008.lzma
2024-09-16T18:17:14.098Z,1726510634.098 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0008.lzma.bak
2024-09-16T18:17:14.098Z,1726510634.098 [DataOverHttps](INFO): SBD MOMSN=23190938
2024-09-16T18:17:15.194Z,1726510635.194 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:17:15.194Z,1726510635.194 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:17:15.194Z,1726510635.194 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:17:29.720Z,1726510649.720 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:18:06.501Z,1726510686.501 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T18:18:06.501Z,1726510686.501 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:18:06.519Z,1726510686.519 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:18:06.928Z,1726510686.928 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:18:06.928Z,1726510686.928 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T18:21:07.628Z,1726510867.628 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T18:21:07.628Z,1726510867.628 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:21:07.647Z,1726510867.647 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:21:08.041Z,1726510868.041 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:21:08.041Z,1726510868.041 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T18:22:15.894Z,1726510935.894 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:22:15.894Z,1726510935.894 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:22:15.894Z,1726510935.894 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:22:15.894Z,1726510935.894 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:22:16.302Z,1726510936.302 [Default:CheckIn:D] Stopped
2024-09-16T18:22:16.302Z,1726510936.302 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:22:16.719Z,1726510936.719 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.914512 min
2024-09-16T18:22:16.719Z,1726510936.719 [Default:CheckIn:E] Stopped
2024-09-16T18:22:16.719Z,1726510936.719 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:22:16.719Z,1726510936.719 [Default:CheckIn] Stopped
2024-09-16T18:22:16.719Z,1726510936.719 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:22:16.719Z,1726510936.719 [Default:CheckIn](INFO): Running loop #3
2024-09-16T18:22:16.719Z,1726510936.719 [Default:CheckIn] Running Loop=3
2024-09-16T18:22:16.719Z,1726510936.719 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:22:16.719Z,1726510936.719 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:22:18.711Z,1726510938.711 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182217.00,A,3648.13856,N,12147.23079,W,0.019,0.00,160924,,,D*78
2024-09-16T18:22:18.713Z,1726510938.713 [NAL9602](INFO): GPS fix at 20240916T182217: (36.802309, -121.787180)
2024-09-16T18:22:18.729Z,1726510938.729 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:22:18.729Z,1726510938.729 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:22:19.533Z,1726510939.533 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:22:19.533Z,1726510939.533 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:22:19.533Z,1726510939.533 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:22:37.693Z,1726510957.693 [NAL9602](INFO): SBD MO Status=0, MOMSN=16703, MT Status=0, MTMSN=0
2024-09-16T18:22:37.693Z,1726510957.693 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:23:08.439Z,1726510988.439 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:24:08.653Z,1726511048.653 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T18:24:08.653Z,1726511048.653 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:24:08.664Z,1726511048.664 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:24:09.072Z,1726511049.072 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:24:09.072Z,1726511049.072 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T18:27:09.645Z,1726511229.645 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T18:27:09.645Z,1726511229.645 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:27:09.657Z,1726511229.657 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:27:10.066Z,1726511230.066 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:27:10.066Z,1726511230.066 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T18:27:20.145Z,1726511240.145 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:27:20.146Z,1726511240.146 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:27:20.146Z,1726511240.146 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:27:20.146Z,1726511240.146 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:27:20.550Z,1726511240.550 [Default:CheckIn:D] Stopped
2024-09-16T18:27:20.551Z,1726511240.551 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:27:20.957Z,1726511240.957 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.985325 min
2024-09-16T18:27:20.957Z,1726511240.957 [Default:CheckIn:E] Stopped
2024-09-16T18:27:20.957Z,1726511240.957 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:27:20.957Z,1726511240.957 [Default:CheckIn] Stopped
2024-09-16T18:27:20.957Z,1726511240.957 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:27:20.957Z,1726511240.957 [Default:CheckIn](INFO): Running loop #4
2024-09-16T18:27:20.957Z,1726511240.957 [Default:CheckIn] Running Loop=4
2024-09-16T18:27:20.957Z,1726511240.957 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:27:20.957Z,1726511240.957 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:27:22.971Z,1726511242.971 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182722.00,A,3648.13754,N,12147.22909,W,0.019,0.00,160924,,,D*79
2024-09-16T18:27:22.974Z,1726511242.974 [NAL9602](INFO): GPS fix at 20240916T182722: (36.802292, -121.787151)
2024-09-16T18:27:22.990Z,1726511242.990 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:27:22.990Z,1726511242.990 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:27:30.771Z,1726511250.771 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T180551/Courier0010.lzma
2024-09-16T18:27:31.774Z,1726511251.774 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0010.lzma.bak
2024-09-16T18:27:31.774Z,1726511251.774 [DataOverHttps](INFO): SBD MOMSN=23190957
2024-09-16T18:27:37.515Z,1726511257.515 [NAL9602](INFO): SBD MO Status=0, MOMSN=16704, MT Status=0, MTMSN=0
2024-09-16T18:27:37.515Z,1726511257.515 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:27:47.611Z,1726511267.611 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0013.lzma
2024-09-16T18:27:48.614Z,1726511268.614 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0013.lzma.bak
2024-09-16T18:27:48.614Z,1726511268.614 [DataOverHttps](INFO): SBD MOMSN=23190963
2024-09-16T18:28:04.559Z,1726511284.559 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240916T180551/Express0011.lzma
2024-09-16T18:28:05.561Z,1726511285.561 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0011.lzma.bak
2024-09-16T18:28:05.562Z,1726511285.562 [DataOverHttps](INFO): SBD MOMSN=23190970
2024-09-16T18:28:08.217Z,1726511288.217 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:28:21.535Z,1726511301.535 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240916T180551/Express0014.lzma
2024-09-16T18:28:22.538Z,1726511302.538 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0014.lzma.bak
2024-09-16T18:28:22.538Z,1726511302.538 [DataOverHttps](INFO): SBD MOMSN=23190974
2024-09-16T18:28:23.581Z,1726511303.581 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:28:23.581Z,1726511303.581 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:28:23.581Z,1726511303.581 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:30:10.645Z,1726511410.645 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T18:30:10.645Z,1726511410.645 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:30:10.656Z,1726511410.656 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:30:11.075Z,1726511411.075 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:30:11.075Z,1726511411.075 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T18:33:11.629Z,1726511591.629 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T18:33:11.629Z,1726511591.629 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:33:11.645Z,1726511591.645 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:33:12.052Z,1726511592.052 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:33:12.052Z,1726511592.052 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T18:33:24.206Z,1726511604.206 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:33:24.206Z,1726511604.206 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:33:24.206Z,1726511604.206 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:33:24.207Z,1726511604.207 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:33:24.563Z,1726511604.563 [Default:CheckIn:D] Stopped
2024-09-16T18:33:24.563Z,1726511604.563 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:33:24.967Z,1726511604.967 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.052207 min
2024-09-16T18:33:24.968Z,1726511604.968 [Default:CheckIn:E] Stopped
2024-09-16T18:33:24.968Z,1726511604.968 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:33:24.968Z,1726511604.968 [Default:CheckIn] Stopped
2024-09-16T18:33:24.968Z,1726511604.968 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:33:24.968Z,1726511604.968 [Default:CheckIn](INFO): Running loop #5
2024-09-16T18:33:24.968Z,1726511604.968 [Default:CheckIn] Running Loop=5
2024-09-16T18:33:24.968Z,1726511604.968 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:33:24.968Z,1726511604.968 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:33:26.970Z,1726511606.970 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183326.00,A,3648.13825,N,12147.23003,W,0.019,0.00,160924,,,D*73
2024-09-16T18:33:26.983Z,1726511606.983 [NAL9602](INFO): GPS fix at 20240916T183326: (36.802304, -121.787167)
2024-09-16T18:33:27.004Z,1726511607.004 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:33:27.004Z,1726511607.004 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:33:34.379Z,1726511614.379 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0016.lzma
2024-09-16T18:33:35.382Z,1726511615.382 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0016.lzma.bak
2024-09-16T18:33:35.382Z,1726511615.382 [DataOverHttps](INFO): SBD MOMSN=23190985
2024-09-16T18:33:39.499Z,1726511619.499 [NAL9602](INFO): SBD MO Status=0, MOMSN=16705, MT Status=0, MTMSN=0
2024-09-16T18:33:39.499Z,1726511619.499 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:33:51.215Z,1726511631.215 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20240916T180551/Express0017.lzma
2024-09-16T18:33:52.218Z,1726511632.218 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0017.lzma.bak
2024-09-16T18:33:52.218Z,1726511632.218 [DataOverHttps](INFO): SBD MOMSN=23190991
2024-09-16T18:33:53.263Z,1726511633.263 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:33:53.263Z,1726511633.263 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:33:53.263Z,1726511633.263 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:34:10.199Z,1726511650.199 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:36:12.625Z,1726511772.625 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T18:36:12.625Z,1726511772.625 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:36:12.637Z,1726511772.637 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:36:13.043Z,1726511773.043 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:36:13.043Z,1726511773.043 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T18:38:53.832Z,1726511933.832 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:38:53.832Z,1726511933.832 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:38:53.832Z,1726511933.832 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:38:53.832Z,1726511933.832 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:38:54.247Z,1726511934.247 [Default:CheckIn:D] Stopped
2024-09-16T18:38:54.247Z,1726511934.247 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:38:54.655Z,1726511934.655 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.546928 min
2024-09-16T18:38:54.655Z,1726511934.655 [Default:CheckIn:E] Stopped
2024-09-16T18:38:54.655Z,1726511934.655 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:38:54.655Z,1726511934.655 [Default:CheckIn] Stopped
2024-09-16T18:38:54.655Z,1726511934.655 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:38:54.655Z,1726511934.655 [Default:CheckIn](INFO): Running loop #6
2024-09-16T18:38:54.661Z,1726511934.661 [Default:CheckIn] Running Loop=6
2024-09-16T18:38:54.661Z,1726511934.661 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:38:54.661Z,1726511934.661 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:38:56.639Z,1726511936.639 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183855.00,A,3648.13819,N,12147.23088,W,0.019,0.00,160924,,,D*70
2024-09-16T18:38:56.642Z,1726511936.642 [NAL9602](INFO): GPS fix at 20240916T183855: (36.802303, -121.787181)
2024-09-16T18:38:56.677Z,1726511936.677 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:38:56.677Z,1726511936.677 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:39:04.039Z,1726511944.039 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0019.lzma
2024-09-16T18:39:05.042Z,1726511945.042 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0019.lzma.bak
2024-09-16T18:39:05.042Z,1726511945.042 [DataOverHttps](INFO): SBD MOMSN=23191099
2024-09-16T18:39:13.613Z,1726511953.613 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T18:39:13.613Z,1726511953.613 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:39:13.624Z,1726511953.624 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:39:14.011Z,1726511954.011 [NAL9602](INFO): SBD MO Status=0, MOMSN=16706, MT Status=0, MTMSN=0
2024-09-16T18:39:14.011Z,1726511954.011 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:39:14.032Z,1726511954.032 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:39:14.032Z,1726511954.032 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T18:39:20.887Z,1726511960.887 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20240916T180551/Express0020.lzma
2024-09-16T18:39:21.890Z,1726511961.890 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0020.lzma.bak
2024-09-16T18:39:21.890Z,1726511961.890 [DataOverHttps](INFO): SBD MOMSN=23191102
2024-09-16T18:39:22.926Z,1726511962.926 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:39:22.926Z,1726511962.926 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:39:22.926Z,1726511962.926 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:39:44.707Z,1726511984.707 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:42:14.604Z,1726512134.604 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T18:42:14.604Z,1726512134.604 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:42:14.615Z,1726512134.615 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:42:15.036Z,1726512135.036 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:42:15.036Z,1726512135.036 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T18:44:23.486Z,1726512263.486 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:44:23.486Z,1726512263.486 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:44:23.486Z,1726512263.486 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:44:23.486Z,1726512263.486 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:44:23.895Z,1726512263.895 [Default:CheckIn:D] Stopped
2024-09-16T18:44:23.895Z,1726512263.895 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:44:24.302Z,1726512264.302 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.041060 min
2024-09-16T18:44:24.302Z,1726512264.302 [Default:CheckIn:E] Stopped
2024-09-16T18:44:24.302Z,1726512264.302 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:44:24.302Z,1726512264.302 [Default:CheckIn] Stopped
2024-09-16T18:44:24.302Z,1726512264.302 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:44:24.302Z,1726512264.302 [Default:CheckIn](INFO): Running loop #7
2024-09-16T18:44:24.302Z,1726512264.302 [Default:CheckIn] Running Loop=7
2024-09-16T18:44:24.302Z,1726512264.302 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:44:24.302Z,1726512264.302 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:44:26.298Z,1726512266.298 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184425.00,A,3648.13832,N,12147.22920,W,0.019,0.00,160924,,,D*7F
2024-09-16T18:44:26.300Z,1726512266.300 [NAL9602](INFO): GPS fix at 20240916T184425: (36.802305, -121.787153)
2024-09-16T18:44:26.335Z,1726512266.335 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:44:26.335Z,1726512266.335 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:44:33.635Z,1726512273.635 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0022.lzma
2024-09-16T18:44:34.638Z,1726512274.638 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0022.lzma.bak
2024-09-16T18:44:34.638Z,1726512274.638 [DataOverHttps](INFO): SBD MOMSN=23191207
2024-09-16T18:44:36.402Z,1726512276.402 [NAL9602](INFO): SBD MO Status=0, MOMSN=16707, MT Status=0, MTMSN=0
2024-09-16T18:44:36.402Z,1726512276.402 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:44:52.932Z,1726512292.932 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240916T180551/Express0023.lzma
2024-09-16T18:44:53.934Z,1726512293.934 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0023.lzma.bak
2024-09-16T18:44:53.934Z,1726512293.934 [DataOverHttps](INFO): SBD MOMSN=23191211
2024-09-16T18:44:55.038Z,1726512295.038 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:44:55.038Z,1726512295.038 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:44:55.038Z,1726512295.038 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:45:07.141Z,1726512307.141 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:45:15.633Z,1726512315.633 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T18:45:15.633Z,1726512315.633 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:45:15.644Z,1726512315.644 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:45:16.048Z,1726512316.048 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:45:16.048Z,1726512316.048 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T18:48:16.636Z,1726512496.636 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T18:48:16.636Z,1726512496.636 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:48:16.647Z,1726512496.647 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:48:17.054Z,1726512497.054 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:48:17.054Z,1726512497.054 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T18:49:55.632Z,1726512595.632 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:49:55.632Z,1726512595.632 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:49:55.632Z,1726512595.632 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:49:55.632Z,1726512595.632 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:49:56.050Z,1726512596.050 [Default:CheckIn:D] Stopped
2024-09-16T18:49:56.050Z,1726512596.050 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:49:56.434Z,1726512596.434 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.576990 min
2024-09-16T18:49:56.434Z,1726512596.434 [Default:CheckIn:E] Stopped
2024-09-16T18:49:56.434Z,1726512596.434 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:49:56.434Z,1726512596.434 [Default:CheckIn] Stopped
2024-09-16T18:49:56.434Z,1726512596.434 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:49:56.434Z,1726512596.434 [Default:CheckIn](INFO): Running loop #8
2024-09-16T18:49:56.434Z,1726512596.434 [Default:CheckIn] Running Loop=8
2024-09-16T18:49:56.434Z,1726512596.434 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:49:56.435Z,1726512596.435 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:49:58.463Z,1726512598.463 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184957.00,A,3648.13953,N,12147.22991,W,0.039,0.00,160924,,,D*79
2024-09-16T18:49:58.469Z,1726512598.469 [NAL9602](INFO): GPS fix at 20240916T184957: (36.802326, -121.787165)
2024-09-16T18:49:58.518Z,1726512598.518 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:49:58.518Z,1726512598.518 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:50:07.459Z,1726512607.459 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0025.lzma
2024-09-16T18:50:08.462Z,1726512608.462 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0025.lzma.bak
2024-09-16T18:50:08.462Z,1726512608.462 [DataOverHttps](INFO): SBD MOMSN=23191232
2024-09-16T18:50:13.542Z,1726512613.542 [NAL9602](INFO): SBD MO Status=0, MOMSN=16708, MT Status=0, MTMSN=0
2024-09-16T18:50:13.543Z,1726512613.543 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:50:24.747Z,1726512624.747 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240916T180551/Express0026.lzma
2024-09-16T18:50:25.750Z,1726512625.750 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0026.lzma.bak
2024-09-16T18:50:25.750Z,1726512625.750 [DataOverHttps](INFO): SBD MOMSN=23191251
2024-09-16T18:50:26.890Z,1726512626.890 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:50:26.890Z,1726512626.890 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:50:26.890Z,1726512626.890 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:50:44.244Z,1726512644.244 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:51:17.792Z,1726512677.792 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T18:51:17.792Z,1726512677.792 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:51:17.827Z,1726512677.827 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:51:18.207Z,1726512678.207 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:51:18.207Z,1726512678.207 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T18:54:18.822Z,1726512858.822 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T18:54:18.822Z,1726512858.822 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:54:18.841Z,1726512858.841 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:54:19.250Z,1726512859.250 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:54:19.250Z,1726512859.250 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T18:55:27.609Z,1726512927.609 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:55:27.610Z,1726512927.610 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:55:27.610Z,1726512927.610 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:55:27.610Z,1726512927.610 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:55:28.022Z,1726512928.022 [Default:CheckIn:D] Stopped
2024-09-16T18:55:28.022Z,1726512928.022 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:55:28.439Z,1726512928.439 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.109851 min
2024-09-16T18:55:28.439Z,1726512928.439 [Default:CheckIn:E] Stopped
2024-09-16T18:55:28.439Z,1726512928.439 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:55:28.439Z,1726512928.439 [Default:CheckIn] Stopped
2024-09-16T18:55:28.439Z,1726512928.439 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:55:28.439Z,1726512928.439 [Default:CheckIn](INFO): Running loop #9
2024-09-16T18:55:28.440Z,1726512928.440 [Default:CheckIn] Running Loop=9
2024-09-16T18:55:28.446Z,1726512928.446 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:55:28.446Z,1726512928.446 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:55:30.423Z,1726512930.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185529.00,A,3648.14008,N,12147.23060,W,0.019,0.00,160924,,,D*79
2024-09-16T18:55:30.426Z,1726512930.426 [NAL9602](INFO): GPS fix at 20240916T185529: (36.802335, -121.787177)
2024-09-16T18:55:30.461Z,1726512930.461 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:55:30.461Z,1726512930.461 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:55:40.011Z,1726512940.011 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T180551/Courier0028.lzma
2024-09-16T18:55:41.014Z,1726512941.014 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0028.lzma.bak
2024-09-16T18:55:41.014Z,1726512941.014 [DataOverHttps](INFO): SBD MOMSN=23191273
2024-09-16T18:55:42.159Z,1726512942.159 [NAL9602](INFO): SBD MO Status=0, MOMSN=16709, MT Status=0, MTMSN=0
2024-09-16T18:55:42.159Z,1726512942.159 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:56:04.163Z,1726512964.163 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20240916T180551/Express0029.lzma
2024-09-16T18:56:05.166Z,1726512965.166 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0029.lzma.bak
2024-09-16T18:56:05.166Z,1726512965.166 [DataOverHttps](INFO): SBD MOMSN=23191278
2024-09-16T18:56:06.467Z,1726512966.467 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:56:06.467Z,1726512966.467 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:56:06.467Z,1726512966.467 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:56:08.867Z,1726512968.867 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2024-09-16T18:56:12.909Z,1726512972.909 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:57:19.736Z,1726513039.736 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T18:57:19.736Z,1726513039.736 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T18:57:19.805Z,1726513039.805 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T18:57:20.194Z,1726513040.194 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T18:57:20.194Z,1726513040.194 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:00:20.530Z,1726513220.530 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T19:00:20.530Z,1726513220.530 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:00:20.542Z,1726513220.542 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:00:20.951Z,1726513220.951 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:00:20.951Z,1726513220.951 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T19:01:07.070Z,1726513267.070 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:01:07.071Z,1726513267.071 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:01:07.071Z,1726513267.071 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:01:07.071Z,1726513267.071 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:01:07.512Z,1726513267.512 [Default:CheckIn:D] Stopped
2024-09-16T19:01:07.512Z,1726513267.512 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:01:08.058Z,1726513268.058 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.768022 min
2024-09-16T19:01:08.058Z,1726513268.058 [Default:CheckIn:E] Stopped
2024-09-16T19:01:08.058Z,1726513268.058 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:01:08.058Z,1726513268.058 [Default:CheckIn] Stopped
2024-09-16T19:01:08.058Z,1726513268.058 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:01:08.058Z,1726513268.058 [Default:CheckIn](INFO): Running loop #10
2024-09-16T19:01:08.058Z,1726513268.058 [Default:CheckIn] Running Loop=10
2024-09-16T19:01:08.059Z,1726513268.059 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:01:08.059Z,1726513268.059 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:01:09.891Z,1726513269.891 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190109.00,A,3648.14998,N,12147.22311,W,0.039,33.42,160924,,,D*4B
2024-09-16T19:01:09.925Z,1726513269.925 [NAL9602](INFO): GPS fix at 20240916T190109: (36.802500, -121.787052)
2024-09-16T19:01:10.002Z,1726513270.002 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:01:10.002Z,1726513270.002 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:01:18.799Z,1726513278.799 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T180551/Courier0031.lzma
2024-09-16T19:01:19.802Z,1726513279.802 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0031.lzma.bak
2024-09-16T19:01:19.802Z,1726513279.802 [DataOverHttps](INFO): SBD MOMSN=23191336
2024-09-16T19:01:25.254Z,1726513285.254 [NAL9602](INFO): SBD MO Status=0, MOMSN=16710, MT Status=0, MTMSN=0
2024-09-16T19:01:25.254Z,1726513285.254 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:01:41.475Z,1726513301.475 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240916T180551/Express0032.lzma
2024-09-16T19:01:42.478Z,1726513302.478 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0032.lzma.bak
2024-09-16T19:01:42.478Z,1726513302.478 [DataOverHttps](INFO): SBD MOMSN=23191339
2024-09-16T19:01:43.907Z,1726513303.907 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:01:43.907Z,1726513303.907 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:01:43.907Z,1726513303.907 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:01:56.031Z,1726513316.031 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:03:21.370Z,1726513401.370 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:03:21.370Z,1726513401.370 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:03:21.397Z,1726513401.397 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:03:21.790Z,1726513401.790 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:03:21.790Z,1726513401.790 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:05:56.018Z,1726513556.018 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T190554
2024-09-16T19:06:04.504Z,1726513564.504 [DataOverHttps](INFO): Received command: ibit
2024-09-16T19:06:04.537Z,1726513564.537 [CommandExec](IMPORTANT): got command ibit
2024-09-16T19:06:04.861Z,1726513564.861 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-09-16T19:06:04.861Z,1726513564.861 [IBIT](IMPORTANT): Beginning control surface checks.
2024-09-16T19:06:04.873Z,1726513564.873 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T19:06:06.375Z,1726513566.375 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190605.00,A,3648.14998,N,12147.22364,W,0.058,33.42,160924,,,D*45
2024-09-16T19:06:06.377Z,1726513566.377 [NAL9602](INFO): GPS fix at 20240916T190605: (36.802500, -121.787061)
2024-09-16T19:06:15.767Z,1726513575.767 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.003318
CHAN A1 (24V): 0.006203
CHAN A2 (12V): 0.003241
CHAN A3 (5V): -0.001160
CHAN B0 (3.3V): 0.000659
CHAN B1 (3.15aV): 0.000428
CHAN B2 (3.15bV): 0.000024
CHAN B3 (GND): -0.000648
OPEN: 0.006468
Full Scale: +/- 1 mA
2024-09-16T19:06:22.567Z,1726513582.567 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:06:22.567Z,1726513582.567 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:06:22.661Z,1726513582.661 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:06:23.084Z,1726513583.084 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:06:23.085Z,1726513583.085 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:06:31.041Z,1726513591.041 [NAL9602](INFO): SBD MO Status=0, MOMSN=16711, MT Status=0, MTMSN=0
2024-09-16T19:06:31.042Z,1726513591.042 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:06:32.251Z,1726513592.251 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190631.00,A,3648.15001,N,12147.22370,W,0.019,33.42,160924,,,D*4A
2024-09-16T19:06:32.253Z,1726513592.253 [NAL9602](INFO): GPS fix at 20240916T190631: (36.802500, -121.787062)
2024-09-16T19:06:35.071Z,1726513595.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190634.00,A,3648.15005,N,12147.22371,W,0.039,33.42,160924,,,D*48
2024-09-16T19:06:35.073Z,1726513595.073 [NAL9602](INFO): GPS fix at 20240916T190634: (36.802501, -121.787062)
2024-09-16T19:06:38.302Z,1726513598.302 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190637.00,A,3648.15009,N,12147.22371,W,0.019,33.42,160924,,,D*45
2024-09-16T19:06:38.304Z,1726513598.304 [NAL9602](INFO): GPS fix at 20240916T190637: (36.802501, -121.787062)
2024-09-16T19:06:41.130Z,1726513601.130 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190640.00,A,3648.15011,N,12147.22368,W,0.058,33.42,160924,,,D*41
2024-09-16T19:06:41.132Z,1726513601.132 [NAL9602](INFO): GPS fix at 20240916T190640: (36.802502, -121.787061)
2024-09-16T19:06:44.362Z,1726513604.362 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190643.00,A,3648.15006,N,12147.22374,W,0.058,33.42,160924,,,D*49
2024-09-16T19:06:44.364Z,1726513604.364 [NAL9602](INFO): GPS fix at 20240916T190643: (36.802501, -121.787062)
2024-09-16T19:06:47.207Z,1726513607.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190646.00,A,3648.14999,N,12147.22380,W,0.019,33.42,160924,,,D*4C
2024-09-16T19:06:47.210Z,1726513607.210 [NAL9602](INFO): GPS fix at 20240916T190646: (36.802500, -121.787063)
2024-09-16T19:06:50.034Z,1726513610.034 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190649.00,A,3648.14990,N,12147.22388,W,0.039,33.42,160924,,,D*40
2024-09-16T19:06:50.036Z,1726513610.036 [NAL9602](INFO): GPS fix at 20240916T190649: (36.802498, -121.787065)
2024-09-16T19:06:50.871Z,1726513610.871 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802498 Longitude: -121.787064
2024-09-16T19:06:51.284Z,1726513611.284 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2024-09-16T19:06:51.284Z,1726513611.284 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-09-16T19:06:51.285Z,1726513611.285 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-09-16T19:06:51.705Z,1726513611.705 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-09-16T19:06:51.705Z,1726513611.705 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-09-16T19:06:51.705Z,1726513611.705 [IBIT](IMPORTANT): Pressure:9.896235 PSI
2024-09-16T19:06:51.706Z,1726513611.706 [IBIT](IMPORTANT): Humidity:22.707850 %
2024-09-16T19:06:52.124Z,1726513612.124 [IBIT](IMPORTANT): Vehicle Pitch:-0.513357 degrees
2024-09-16T19:06:52.125Z,1726513612.125 [IBIT](IMPORTANT): Vehicle Roll:1.476975 degrees
2024-09-16T19:06:52.125Z,1726513612.125 [IBIT](IMPORTANT): Vehicle Heading:212.321808 degrees
2024-09-16T19:06:52.497Z,1726513612.497 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-09-16T19:06:52.498Z,1726513612.498 [IBIT](IMPORTANT): buoyancyNeutral: 239.155975 cc
2024-09-16T19:06:52.498Z,1726513612.498 [IBIT](IMPORTANT): massDefault: 1.535841 cm
2024-09-16T19:06:52.498Z,1726513612.498 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2024-09-16T19:06:52.498Z,1726513612.498 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-09-16T19:06:52.498Z,1726513612.498 [IBIT](IMPORTANT): IBIT PASSED
2024-09-16T19:06:52.875Z,1726513612.875 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:06:52.875Z,1726513612.875 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:06:52.875Z,1726513612.875 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:06:52.875Z,1726513612.875 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:06:53.287Z,1726513613.287 [Default:CheckIn:D] Stopped
2024-09-16T19:06:53.287Z,1726513613.287 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:06:53.697Z,1726513613.697 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.530929 min
2024-09-16T19:06:53.697Z,1726513613.697 [Default:CheckIn:E] Stopped
2024-09-16T19:06:53.697Z,1726513613.697 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:06:53.697Z,1726513613.697 [Default:CheckIn] Stopped
2024-09-16T19:06:53.697Z,1726513613.697 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:06:53.697Z,1726513613.697 [Default:CheckIn](INFO): Running loop #11
2024-09-16T19:06:53.697Z,1726513613.697 [Default:CheckIn] Running Loop=11
2024-09-16T19:06:53.698Z,1726513613.698 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:06:53.698Z,1726513613.698 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:06:55.691Z,1726513615.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190654.00,A,3648.14976,N,12147.22391,W,0.039,33.42,160924,,,D*4C
2024-09-16T19:06:55.694Z,1726513615.694 [NAL9602](INFO): GPS fix at 20240916T190654: (36.802496, -121.787065)
2024-09-16T19:06:55.717Z,1726513615.717 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:06:55.718Z,1726513615.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:07:06.243Z,1726513626.243 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0034.lzma
2024-09-16T19:07:07.246Z,1726513627.246 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0034.lzma.bak
2024-09-16T19:07:07.246Z,1726513627.246 [DataOverHttps](INFO): SBD MOMSN=23191370
2024-09-16T19:07:28.357Z,1726513648.357 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:07:30.465Z,1726513650.465 [DataOverHttps](INFO): Sending 820 bytes from file Logs/20240916T180551/Express0035.lzma
2024-09-16T19:07:31.466Z,1726513651.466 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0035.lzma.bak
2024-09-16T19:07:31.466Z,1726513651.466 [DataOverHttps](INFO): SBD MOMSN=23191373
2024-09-16T19:07:32.843Z,1726513652.843 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:07:32.843Z,1726513652.843 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:07:32.843Z,1726513652.843 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:09:23.623Z,1726513763.623 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:09:23.623Z,1726513763.623 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:09:23.643Z,1726513763.643 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:09:24.039Z,1726513764.039 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:09:24.039Z,1726513764.039 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:12:24.427Z,1726513944.427 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T19:12:24.427Z,1726513944.427 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:12:24.447Z,1726513944.447 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:12:24.894Z,1726513944.894 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:12:24.894Z,1726513944.894 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T19:12:33.326Z,1726513953.326 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:12:33.326Z,1726513953.326 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:12:33.326Z,1726513953.326 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:12:33.326Z,1726513953.326 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:12:33.828Z,1726513953.828 [Default:CheckIn:D] Stopped
2024-09-16T19:12:33.829Z,1726513953.829 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:12:34.167Z,1726513954.167 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.206624 min
2024-09-16T19:12:34.167Z,1726513954.167 [Default:CheckIn:E] Stopped
2024-09-16T19:12:34.167Z,1726513954.167 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:12:34.167Z,1726513954.167 [Default:CheckIn] Stopped
2024-09-16T19:12:34.167Z,1726513954.167 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:12:34.167Z,1726513954.167 [Default:CheckIn](INFO): Running loop #12
2024-09-16T19:12:34.167Z,1726513954.167 [Default:CheckIn] Running Loop=12
2024-09-16T19:12:34.200Z,1726513954.200 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:12:34.200Z,1726513954.200 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:12:36.134Z,1726513956.134 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191235.00,A,3648.15117,N,12147.22333,W,0.019,33.42,160924,,,D*4A
2024-09-16T19:12:36.136Z,1726513956.136 [NAL9602](INFO): GPS fix at 20240916T191235: (36.802520, -121.787055)
2024-09-16T19:12:36.151Z,1726513956.151 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:12:36.151Z,1726513956.151 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:12:43.772Z,1726513963.772 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T180551/Courier0039.lzma
2024-09-16T19:12:44.770Z,1726513964.770 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0039.lzma.bak
2024-09-16T19:12:44.770Z,1726513964.770 [DataOverHttps](INFO): SBD MOMSN=23191428
2024-09-16T19:13:00.882Z,1726513980.882 [NAL9602](INFO): SBD MO Status=0, MOMSN=16712, MT Status=0, MTMSN=0
2024-09-16T19:13:00.883Z,1726513980.883 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:13:08.703Z,1726513988.703 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20240916T180551/Express0041.lzma
2024-09-16T19:13:09.710Z,1726513989.710 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0041.lzma.bak
2024-09-16T19:13:09.710Z,1726513989.710 [DataOverHttps](INFO): SBD MOMSN=23191431
2024-09-16T19:13:11.043Z,1726513991.043 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:13:11.043Z,1726513991.043 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:13:11.043Z,1726513991.043 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:13:31.349Z,1726514011.349 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:15:25.321Z,1726514125.321 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:15:25.321Z,1726514125.321 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:15:25.349Z,1726514125.349 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:15:25.735Z,1726514125.735 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:15:25.735Z,1726514125.735 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:17:27.386Z,1726514247.386 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:17:34.234Z,1726514254.234 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:17:49.182Z,1726514269.182 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:18:04.132Z,1726514284.132 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:18:11.822Z,1726514291.822 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:18:11.822Z,1726514291.822 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:18:11.822Z,1726514291.822 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:18:11.822Z,1726514291.822 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:18:12.245Z,1726514292.245 [Default:CheckIn:D] Stopped
2024-09-16T19:18:12.245Z,1726514292.245 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:18:12.644Z,1726514292.644 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.846899 min
2024-09-16T19:18:12.644Z,1726514292.644 [Default:CheckIn:E] Stopped
2024-09-16T19:18:12.644Z,1726514292.644 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:18:12.644Z,1726514292.644 [Default:CheckIn] Stopped
2024-09-16T19:18:12.644Z,1726514292.644 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:18:12.645Z,1726514292.645 [Default:CheckIn](INFO): Running loop #13
2024-09-16T19:18:12.645Z,1726514292.645 [Default:CheckIn] Running Loop=13
2024-09-16T19:18:12.645Z,1726514292.645 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:18:12.645Z,1726514292.645 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:18:14.647Z,1726514294.647 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191813.00,A,3648.14991,N,12147.22412,W,0.019,33.42,160924,,,D*47
2024-09-16T19:18:14.649Z,1726514294.649 [NAL9602](INFO): GPS fix at 20240916T191813: (36.802498, -121.787069)
2024-09-16T19:18:14.689Z,1726514294.689 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:18:14.689Z,1726514294.689 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:18:19.486Z,1726514299.486 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:18:21.612Z,1726514301.612 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0043.lzma
2024-09-16T19:18:22.614Z,1726514302.614 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0043.lzma.bak
2024-09-16T19:18:22.614Z,1726514302.614 [DataOverHttps](INFO): SBD MOMSN=23191455
2024-09-16T19:18:26.405Z,1726514306.405 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:18:26.405Z,1726514306.405 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:18:26.432Z,1726514306.432 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:18:26.832Z,1726514306.832 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:18:26.832Z,1726514306.832 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:18:27.574Z,1726514307.574 [NAL9602](INFO): SBD MO Status=0, MOMSN=16713, MT Status=0, MTMSN=0
2024-09-16T19:18:27.574Z,1726514307.574 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:18:35.242Z,1726514315.242 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:18:38.567Z,1726514318.567 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240916T180551/Express0044.lzma
2024-09-16T19:18:39.570Z,1726514319.570 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0044.lzma.bak
2024-09-16T19:18:39.570Z,1726514319.570 [DataOverHttps](INFO): SBD MOMSN=23191458
2024-09-16T19:18:40.918Z,1726514320.918 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:18:40.918Z,1726514320.918 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:18:40.918Z,1726514320.918 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:18:50.192Z,1726514330.192 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:18:58.291Z,1726514338.291 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:19:05.558Z,1726514345.558 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:19:20.914Z,1726514360.914 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:19:35.862Z,1726514375.862 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:21:27.494Z,1726514487.494 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T19:21:27.494Z,1726514487.494 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:21:27.518Z,1726514487.518 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:21:28.117Z,1726514488.117 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:21:28.117Z,1726514488.117 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T19:22:19.997Z,1726514539.997 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T19:22:33.427Z,1726514553.427 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2024-09-16T19:22:33.427Z,1726514553.427 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2024-09-16T19:22:33.478Z,1726514553.478 [BPC1](INFO): Received data from all battery sticks.
2024-09-16T19:23:41.686Z,1726514621.686 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:23:41.686Z,1726514621.686 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:23:41.686Z,1726514621.686 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:23:41.686Z,1726514621.686 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:23:42.090Z,1726514622.090 [Default:CheckIn:D] Stopped
2024-09-16T19:23:42.090Z,1726514622.090 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:23:42.494Z,1726514622.494 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.344320 min
2024-09-16T19:23:42.494Z,1726514622.494 [Default:CheckIn:E] Stopped
2024-09-16T19:23:42.494Z,1726514622.494 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:23:42.495Z,1726514622.495 [Default:CheckIn] Stopped
2024-09-16T19:23:42.495Z,1726514622.495 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:23:42.495Z,1726514622.495 [Default:CheckIn](INFO): Running loop #14
2024-09-16T19:23:42.495Z,1726514622.495 [Default:CheckIn] Running Loop=14
2024-09-16T19:23:42.495Z,1726514622.495 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:23:42.495Z,1726514622.495 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:23:44.499Z,1726514624.499 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192343.00,A,3648.15089,N,12147.22359,W,0.019,33.42,160924,,,D*43
2024-09-16T19:23:44.501Z,1726514624.501 [NAL9602](INFO): GPS fix at 20240916T192343: (36.802515, -121.787060)
2024-09-16T19:23:44.545Z,1726514624.545 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:23:44.545Z,1726514624.545 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:23:51.727Z,1726514631.727 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240916T180551/Courier0046.lzma
2024-09-16T19:23:52.730Z,1726514632.730 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0046.lzma.bak
2024-09-16T19:23:52.730Z,1726514632.730 [DataOverHttps](INFO): SBD MOMSN=23191477
2024-09-16T19:23:56.618Z,1726514636.618 [NAL9602](INFO): SBD MO Status=0, MOMSN=16714, MT Status=0, MTMSN=0
2024-09-16T19:23:56.619Z,1726514636.619 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:24:08.715Z,1726514648.715 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20240916T180551/Express0047.lzma
2024-09-16T19:24:09.721Z,1726514649.721 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0047.lzma.bak
2024-09-16T19:24:09.721Z,1726514649.721 [DataOverHttps](INFO): SBD MOMSN=23191481
2024-09-16T19:24:10.783Z,1726514650.783 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:24:10.783Z,1726514650.783 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:24:10.783Z,1726514650.783 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:24:27.325Z,1726514667.325 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:24:28.546Z,1726514668.546 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:24:28.546Z,1726514668.546 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:24:28.557Z,1726514668.557 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:24:28.967Z,1726514668.967 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:24:28.968Z,1726514668.968 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:27:29.573Z,1726514849.573 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T19:27:29.573Z,1726514849.573 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:27:29.584Z,1726514849.584 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:27:30.004Z,1726514850.004 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:27:30.004Z,1726514850.004 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T19:29:11.430Z,1726514951.430 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:29:11.430Z,1726514951.430 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:29:11.430Z,1726514951.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:29:11.430Z,1726514951.430 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:29:11.839Z,1726514951.839 [Default:CheckIn:D] Stopped
2024-09-16T19:29:11.839Z,1726514951.839 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:29:12.255Z,1726514952.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.840137 min
2024-09-16T19:29:12.255Z,1726514952.255 [Default:CheckIn:E] Stopped
2024-09-16T19:29:12.255Z,1726514952.255 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:29:12.255Z,1726514952.255 [Default:CheckIn] Stopped
2024-09-16T19:29:12.255Z,1726514952.255 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:29:12.255Z,1726514952.255 [Default:CheckIn](INFO): Running loop #15
2024-09-16T19:29:12.256Z,1726514952.256 [Default:CheckIn] Running Loop=15
2024-09-16T19:29:12.256Z,1726514952.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:29:12.256Z,1726514952.256 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:29:14.247Z,1726514954.247 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192913.00,A,3648.14953,N,12147.22586,W,0.039,175.92,160924,,,D*7B
2024-09-16T19:29:14.249Z,1726514954.249 [NAL9602](INFO): GPS fix at 20240916T192913: (36.802492, -121.787098)
2024-09-16T19:29:14.285Z,1726514954.285 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:29:14.285Z,1726514954.285 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:29:22.063Z,1726514962.063 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0049.lzma
2024-09-16T19:29:23.066Z,1726514963.066 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0049.lzma.bak
2024-09-16T19:29:23.066Z,1726514963.066 [DataOverHttps](INFO): SBD MOMSN=23191506
2024-09-16T19:29:32.027Z,1726514972.027 [NAL9602](INFO): SBD MO Status=0, MOMSN=16715, MT Status=0, MTMSN=0
2024-09-16T19:29:32.027Z,1726514972.027 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:29:38.907Z,1726514978.907 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240916T180551/Express0050.lzma
2024-09-16T19:29:39.910Z,1726514979.910 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0050.lzma.bak
2024-09-16T19:29:39.910Z,1726514979.910 [DataOverHttps](INFO): SBD MOMSN=23191509
2024-09-16T19:29:40.945Z,1726514980.945 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:29:40.945Z,1726514980.945 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:29:40.945Z,1726514980.945 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:30:02.725Z,1726515002.725 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:30:30.611Z,1726515030.611 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:30:30.611Z,1726515030.611 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:30:30.622Z,1726515030.622 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:30:31.034Z,1726515031.034 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:30:31.034Z,1726515031.034 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:33:31.625Z,1726515211.625 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T19:33:31.626Z,1726515211.626 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:33:31.637Z,1726515211.637 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:33:32.059Z,1726515212.059 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:33:32.059Z,1726515212.059 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T19:34:41.514Z,1726515281.514 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:34:41.514Z,1726515281.514 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:34:41.514Z,1726515281.514 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:34:41.514Z,1726515281.514 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:34:41.925Z,1726515281.925 [Default:CheckIn:D] Stopped
2024-09-16T19:34:41.925Z,1726515281.925 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:34:42.319Z,1726515282.319 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.341569 min
2024-09-16T19:34:42.319Z,1726515282.319 [Default:CheckIn:E] Stopped
2024-09-16T19:34:42.319Z,1726515282.319 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:34:42.319Z,1726515282.319 [Default:CheckIn] Stopped
2024-09-16T19:34:42.319Z,1726515282.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:34:42.319Z,1726515282.319 [Default:CheckIn](INFO): Running loop #16
2024-09-16T19:34:42.319Z,1726515282.319 [Default:CheckIn] Running Loop=16
2024-09-16T19:34:42.319Z,1726515282.319 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:34:42.319Z,1726515282.319 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:34:44.335Z,1726515284.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193443.00,A,3648.14893,N,12147.22155,W,0.039,130.95,160924,,,D*73
2024-09-16T19:34:44.337Z,1726515284.337 [NAL9602](INFO): GPS fix at 20240916T193443: (36.802482, -121.787026)
2024-09-16T19:34:44.353Z,1726515284.353 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:34:44.354Z,1726515284.354 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:34:54.155Z,1726515294.155 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0052.lzma
2024-09-16T19:34:55.158Z,1726515295.158 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0052.lzma.bak
2024-09-16T19:34:55.158Z,1726515295.158 [DataOverHttps](INFO): SBD MOMSN=23191530
2024-09-16T19:34:58.915Z,1726515298.915 [NAL9602](INFO): SBD MO Status=0, MOMSN=16716, MT Status=0, MTMSN=0
2024-09-16T19:34:58.915Z,1726515298.915 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:35:11.823Z,1726515311.823 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240916T180551/Express0053.lzma
2024-09-16T19:35:12.826Z,1726515312.826 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0053.lzma.bak
2024-09-16T19:35:12.826Z,1726515312.826 [DataOverHttps](INFO): SBD MOMSN=23191533
2024-09-16T19:35:13.890Z,1726515313.890 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:35:13.890Z,1726515313.890 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:35:13.890Z,1726515313.890 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:35:29.609Z,1726515329.609 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:36:32.645Z,1726515392.645 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:36:32.645Z,1726515392.645 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:36:32.676Z,1726515392.676 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:36:33.058Z,1726515393.058 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:36:33.059Z,1726515393.059 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:38:24.121Z,1726515504.121 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T193823
2024-09-16T19:38:31.539Z,1726515511.539 [DataOverHttps](INFO): Received command: strobe off
2024-09-16T19:38:31.561Z,1726515511.561 [CommandExec](IMPORTANT): got command strobe off
2024-09-16T19:38:31.561Z,1726515511.561 [CommandExec](IMPORTANT): Deactivating strobe
2024-09-16T19:39:33.598Z,1726515573.598 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T193932
2024-09-16T19:39:33.694Z,1726515573.694 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T19:39:33.694Z,1726515573.694 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:39:33.707Z,1726515573.707 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:39:34.114Z,1726515574.114 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:39:34.114Z,1726515574.114 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T19:39:40.976Z,1726515580.976 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;run
2024-09-16T19:39:40.998Z,1726515580.998 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2024-09-16T19:39:40.998Z,1726515580.998 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2024-09-16T19:39:40.998Z,1726515580.998 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2024-09-16T19:39:41.001Z,1726515581.001 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2024-09-16T19:39:41.658Z,1726515581.658 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2024-09-16T19:39:41.663Z,1726515581.663 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2024-09-16T19:39:41.666Z,1726515581.666 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2024-09-16T19:39:41.669Z,1726515581.669 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2024-09-16T19:39:41.672Z,1726515581.672 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2024-09-16T19:39:41.675Z,1726515581.675 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2024-09-16T19:39:41.678Z,1726515581.678 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2024-09-16T19:39:41.680Z,1726515581.680 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2024-09-16T19:39:41.683Z,1726515581.683 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2024-09-16T19:39:41.686Z,1726515581.686 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2024-09-16T19:39:41.689Z,1726515581.689 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2024-09-16T19:39:41.692Z,1726515581.692 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2024-09-16T19:39:41.694Z,1726515581.694 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2024-09-16T19:39:41.697Z,1726515581.697 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2024-09-16T19:39:41.698Z,1726515581.698 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2024-09-16T19:39:41.698Z,1726515581.698 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2024-09-16T19:39:41.699Z,1726515581.699 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2024-09-16T19:39:42.077Z,1726515582.077 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1000
7
30
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2024-09-16T19:39:42.083Z,1726515582.083 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2024-09-16T19:39:42.086Z,1726515582.086 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2024-09-16T19:39:42.108Z,1726515582.108 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2024-09-16T19:39:42.111Z,1726515582.111 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2024-09-16T19:39:42.118Z,1726515582.118 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2024-09-16T19:39:42.122Z,1726515582.122 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2024-09-16T19:39:42.165Z,1726515582.165 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2024-09-16T19:39:42.176Z,1726515582.176 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2024-09-16T19:39:42.191Z,1726515582.191 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T19:39:42.249Z,1726515582.249 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2024-09-16T19:39:42.249Z,1726515582.249 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2024-09-16T19:39:42.250Z,1726515582.250 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2024-09-16T19:39:42.374Z,1726515582.374 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2024-09-16T19:39:42.383Z,1726515582.383 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2024-09-16T19:39:42.397Z,1726515582.397 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2024-09-16T19:39:42.402Z,1726515582.402 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2024-09-16T19:39:42.406Z,1726515582.406 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2024-09-16T19:39:42.407Z,1726515582.407 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2024-09-16T19:39:42.432Z,1726515582.432 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2024-09-16T19:39:42.449Z,1726515582.449 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2024-09-16T19:39:42.469Z,1726515582.469 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2024-09-16T19:39:42.469Z,1726515582.469 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2024-09-16T19:39:42.470Z,1726515582.470 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2024-09-16T19:39:42.516Z,1726515582.516 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2024-09-16T19:39:42.524Z,1726515582.524 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2024-09-16T19:39:42.527Z,1726515582.527 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2024-09-16T19:39:42.534Z,1726515582.534 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2024-09-16T19:39:42.534Z,1726515582.534 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2024-09-16T19:39:42.535Z,1726515582.535 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2024-09-16T19:39:42.833Z,1726515582.833 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2024-09-16T19:39:42.842Z,1726515582.842 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2024-09-16T19:39:42.848Z,1726515582.848 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2024-09-16T19:39:42.851Z,1726515582.851 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2024-09-16T19:39:42.862Z,1726515582.862 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2024-09-16T19:39:42.869Z,1726515582.869 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2024-09-16T19:39:42.889Z,1726515582.889 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2024-09-16T19:39:42.891Z,1726515582.891 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2024-09-16T19:39:42.898Z,1726515582.898 [transit:MassHold.Pitch](DEBUG): Construct.
2024-09-16T19:39:42.914Z,1726515582.914 [transit:Transit:A.Pitch](DEBUG): Construct.
2024-09-16T19:39:42.928Z,1726515582.928 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2024-09-16T19:39:42.931Z,1726515582.931 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2024-09-16T19:39:43.002Z,1726515583.002 [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 transit {
"""
Vehicle transits to desired waypoint and can be commanded to use/hold
mass or buoyancy.
"""
arguments {
MissionTimeout = 60 minute
"""
Maximum duration of mission
"""
NeedCommsTime = 30 minute
"""
How often to surface for commumications
"""
Latitude = NaN degree
"""
Latitude of waypoint to seek. If set to NaN, uses latitude at mission
initialization.
"""
Longitude = NaN degree
"""
Longitude of waypoint to seek. If set to NaN, uses longitude at mission
initialization.
"""
Northings = 0 meter
"""
Northward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Eastings = 0 meter
"""
Eastward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Depth = 10 meter
"""
Depth of flat and level flight during the mission.
"""
Speed = 1 meter_per_second
"""
Speed of vehicle (relative to water) during the mission.
"""
MaxDepth = 20 meter
"""
Maximum depth for the entire mission.
"""
MinOffshore = 1 kilometer
"""
Minimum distance offshore for the entire mission.
"""
MinAltitude = 7 meter
"""
Minimum height above the sea floor for the entire mission.
"""
MassHold = true
"""
Set to True in order to hold mass at default position, False to allow
mass to run on its own.
"""
BuoyancyHold = true
"""
Set to True in order to hold buoyancy at neutral position, False to
allow buoyancy to run on its own.
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
assign in sequence NeedComms:WaitForPitchUp = 1 second
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Buoyancy id="BuoyancyHold" {
run while ( BuoyancyHold )
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch id="MassHold" {
run while ( MassHold )
set massPosition = Control:VerticalControl.massDefault
}
aggregate Transit {
run in sequence
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
set northingsDelta = Northings
set eastingsDelta = Eastings
}
call id="PhoneHome" refId="NeedComms"
}
}
2024-09-16T19:39:43.003Z,1726515583.003 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2024-09-16T19:39:49.189Z,1726515589.189 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute
2024-09-16T19:39:49.189Z,1726515589.189 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute
2024-09-16T19:39:49.190Z,1726515589.190 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2024-09-16T19:39:49.191Z,1726515589.191 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2024-09-16T19:39:49.191Z,1726515589.191 [CommandExec](IMPORTANT): got command run
2024-09-16T19:39:49.194Z,1726515589.194 [CommandExec](IMPORTANT): Running
2024-09-16T19:39:49.442Z,1726515589.442 [Default] Stopped
2024-09-16T19:39:49.442Z,1726515589.442 [Default](DEBUG): Aggregate::uninitialize Default
2024-09-16T19:39:49.442Z,1726515589.442 [Default:B.GoToSurface] Stopped
2024-09-16T19:39:49.442Z,1726515589.442 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-16T19:39:49.442Z,1726515589.442 [Default:CheckIn] Stopped
2024-09-16T19:39:49.442Z,1726515589.442 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:39:49.443Z,1726515589.443 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:39:49.443Z,1726515589.443 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:39:49.443Z,1726515589.443 [MissionManager](IMPORTANT): Started mission transit
2024-09-16T19:39:49.443Z,1726515589.443 [transit] Running Loop=1
2024-09-16T19:39:49.443Z,1726515589.443 [transit](DEBUG): Aggregate::initialize transit
2024-09-16T19:39:49.443Z,1726515589.443 [transit:StandardEnvelopes] Running Loop=1
2024-09-16T19:39:49.443Z,1726515589.443 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2024-09-16T19:39:49.443Z,1726515589.443 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-09-16T19:39:49.443Z,1726515589.443 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2024-09-16T19:39:49.444Z,1726515589.444 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-09-16T19:39:49.444Z,1726515589.444 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2024-09-16T19:39:49.444Z,1726515589.444 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-09-16T19:39:49.444Z,1726515589.444 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2024-09-16T19:39:49.444Z,1726515589.444 [transit:BackseatDriver] Running Loop=1
2024-09-16T19:39:49.445Z,1726515589.445 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2024-09-16T19:39:49.445Z,1726515589.445 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2024-09-16T19:39:49.445Z,1726515589.445 [transit:PowerOnly] Running Loop=1
2024-09-16T19:39:49.445Z,1726515589.445 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2024-09-16T19:39:49.445Z,1726515589.445 [transit:PowerOnly:A] Running Loop=1
2024-09-16T19:39:49.445Z,1726515589.445 [transit:PowerOnly:B] Running Loop=1
2024-09-16T19:39:49.445Z,1726515589.445 [transit:PowerOnly:C] Running Loop=1
2024-09-16T19:39:49.445Z,1726515589.445 [transit:PowerOnly:D] Running Loop=1
2024-09-16T19:39:49.445Z,1726515589.445 [transit:PowerOnly:E.Wait] Running Loop=1
2024-09-16T19:39:49.445Z,1726515589.445 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:39:49.445Z,1726515589.445 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-09-16T19:39:49.446Z,1726515589.446 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2024-09-16T19:39:49.446Z,1726515589.446 [transit:MassHold.Pitch] Running Loop=1
2024-09-16T19:39:49.446Z,1726515589.446 [transit:MassHold.Pitch](DEBUG): Initialize.
2024-09-16T19:39:49.446Z,1726515589.446 [transit:B] Running Loop=1
2024-09-16T19:39:49.447Z,1726515589.447 [transit:MassHold.Pitch] Running Loop=1
2024-09-16T19:39:49.447Z,1726515589.447 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-09-16T19:39:49.447Z,1726515589.447 [transit:PowerOnly] Running Loop=1
2024-09-16T19:39:49.448Z,1726515589.448 [transit:PowerOnly:D] Stopped
2024-09-16T19:39:49.448Z,1726515589.448 [transit:PowerOnly:C] Stopped
2024-09-16T19:39:49.448Z,1726515589.448 [transit:PowerOnly:B] Stopped
2024-09-16T19:39:49.448Z,1726515589.448 [transit:PowerOnly:A] Stopped
2024-09-16T19:39:49.448Z,1726515589.448 [transit:BackseatDriver] Running Loop=1
2024-09-16T19:39:49.448Z,1726515589.448 [transit:StandardEnvelopes] Running Loop=1
2024-09-16T19:39:49.449Z,1726515589.449 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-09-16T19:39:49.449Z,1726515589.449 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-09-16T19:39:49.450Z,1726515589.450 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-09-16T19:39:49.450Z,1726515589.450 [transit:B] Stopped
2024-09-16T19:39:49.450Z,1726515589.450 [transit:C] Running Loop=1
2024-09-16T19:39:49.856Z,1726515589.856 [transit:C] Stopped
2024-09-16T19:39:49.857Z,1726515589.857 [transit:E] Running Loop=1
2024-09-16T19:39:50.267Z,1726515590.267 [transit:E] Stopped
2024-09-16T19:39:50.267Z,1726515590.267 [transit:F] Running Loop=1
2024-09-16T19:39:50.659Z,1726515590.659 [transit:F] Stopped
2024-09-16T19:39:50.660Z,1726515590.660 [transit:G] Running Loop=1
2024-09-16T19:39:51.061Z,1726515591.061 [transit:G] Stopped
2024-09-16T19:39:51.061Z,1726515591.061 [transit:Transit] Running Loop=1
2024-09-16T19:39:51.061Z,1726515591.061 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2024-09-16T19:39:51.061Z,1726515591.061 [transit:Transit:A.Pitch] Running Loop=1
2024-09-16T19:39:51.061Z,1726515591.061 [transit:Transit:A.Pitch](DEBUG): Initialize.
2024-09-16T19:39:51.061Z,1726515591.061 [transit:Transit:B.SetSpeed] Running Loop=1
2024-09-16T19:39:51.061Z,1726515591.061 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2024-09-16T19:39:51.062Z,1726515591.062 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2024-09-16T19:39:51.062Z,1726515591.062 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2024-09-16T19:39:51.063Z,1726515591.063 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2024-09-16T19:39:51.468Z,1726515591.468 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2024-09-16T19:39:51.469Z,1726515591.469 [transit:Transit:B.SetSpeed] Running Loop=1
2024-09-16T19:39:51.469Z,1726515591.469 [transit:Transit:A.Pitch] Running Loop=1
2024-09-16T19:39:51.478Z,1726515591.478 [HorizontalControl](DEBUG): kpHeading = 0.400000
2024-09-16T19:39:51.478Z,1726515591.478 [HorizontalControl](DEBUG): kiHeading = 0.001000
2024-09-16T19:39:51.478Z,1726515591.478 [HorizontalControl](DEBUG): kdHeading = 0.050000
2024-09-16T19:40:43.477Z,1726515643.477 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T194042
2024-09-16T19:40:54.804Z,1726515654.804 [DataOverHttps](INFO): Received command: stop
2024-09-16T19:40:54.885Z,1726515654.885 [CommandExec](IMPORTANT): got command stop
2024-09-16T19:40:54.885Z,1726515654.885 [CommandExec](IMPORTANT): Scheduling is paused
2024-09-16T19:40:54.885Z,1726515654.885 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2024-09-16T19:40:54.994Z,1726515654.994 [MissionManager](INFO): MissionManager is completed.
2024-09-16T19:40:54.994Z,1726515654.994 [MissionManager](INFO): Uninitializing Mission transit
2024-09-16T19:40:54.994Z,1726515654.994 [transit] Stopped
2024-09-16T19:40:54.995Z,1726515654.995 [transit](DEBUG): Aggregate::uninitialize transit
2024-09-16T19:40:54.995Z,1726515654.995 [transit:StandardEnvelopes] Stopped
2024-09-16T19:40:54.995Z,1726515654.995 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2024-09-16T19:40:54.995Z,1726515654.995 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2024-09-16T19:40:54.995Z,1726515654.995 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2024-09-16T19:40:54.995Z,1726515654.995 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2024-09-16T19:40:54.995Z,1726515654.995 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2024-09-16T19:40:54.995Z,1726515654.995 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2024-09-16T19:40:54.995Z,1726515654.995 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2024-09-16T19:40:54.995Z,1726515654.995 [transit:BackseatDriver] Stopped
2024-09-16T19:40:54.995Z,1726515654.995 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2024-09-16T19:40:54.995Z,1726515654.995 [transit:BackseatDriver:A.BackseatDriver] Stopped
2024-09-16T19:40:55.008Z,1726515655.008 [transit:PowerOnly] Stopped
2024-09-16T19:40:55.008Z,1726515655.008 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2024-09-16T19:40:55.012Z,1726515655.012 [transit:PowerOnly:E.Wait] Stopped
2024-09-16T19:40:55.012Z,1726515655.012 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:40:55.012Z,1726515655.012 [transit:BuoyancyHold.Buoyancy] Stopped
2024-09-16T19:40:55.012Z,1726515655.012 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2024-09-16T19:40:55.012Z,1726515655.012 [transit:MassHold.Pitch] Stopped
2024-09-16T19:40:55.012Z,1726515655.012 [transit:Transit] Stopped
2024-09-16T19:40:55.012Z,1726515655.012 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2024-09-16T19:40:55.012Z,1726515655.012 [transit:Transit:A.Pitch] Stopped
2024-09-16T19:40:55.012Z,1726515655.012 [transit:Transit:B.SetSpeed] Stopped
2024-09-16T19:40:55.012Z,1726515655.012 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2024-09-16T19:40:55.012Z,1726515655.012 [transit:Transit:Wpt1.Waypoint] Stopped
2024-09-16T19:40:55.013Z,1726515655.013 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2024-09-16T19:40:55.376Z,1726515655.376 [MissionManager](IMPORTANT): Started mission Default
2024-09-16T19:40:55.376Z,1726515655.376 [Default] Running Loop=1
2024-09-16T19:40:55.376Z,1726515655.376 [Default](DEBUG): Aggregate::initialize Default
2024-09-16T19:40:55.376Z,1726515655.376 [Default:B.GoToSurface] Running Loop=1
2024-09-16T19:40:55.376Z,1726515655.376 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T19:40:55.377Z,1726515655.377 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T19:40:55.377Z,1726515655.377 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T19:40:55.377Z,1726515655.377 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T19:40:55.378Z,1726515655.378 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T19:40:55.378Z,1726515655.378 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T19:40:55.378Z,1726515655.378 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T19:40:55.379Z,1726515655.379 [Default:A.Wait] Running Loop=1
2024-09-16T19:40:55.379Z,1726515655.379 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:41:08.703Z,1726515668.703 [Default:A.Wait](INFO): Done Waiting.
2024-09-16T19:41:08.703Z,1726515668.703 [Default:A.Wait] Stopped
2024-09-16T19:41:08.703Z,1726515668.703 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:41:09.113Z,1726515669.113 [Default:CheckIn] Running Loop=1
2024-09-16T19:41:09.113Z,1726515669.113 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:41:09.114Z,1726515669.114 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:41:11.115Z,1726515671.115 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194110.00,A,3648.41758,N,12147.11621,W,4.957,2.86,160924,,,D*7C
2024-09-16T19:41:11.117Z,1726515671.117 [NAL9602](INFO): GPS fix at 20240916T194110: (36.806960, -121.785270)
2024-09-16T19:41:11.152Z,1726515671.152 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:41:11.152Z,1726515671.152 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:41:18.407Z,1726515678.407 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0055.lzma
2024-09-16T19:41:19.410Z,1726515679.410 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0055.lzma.bak
2024-09-16T19:41:19.410Z,1726515679.410 [DataOverHttps](INFO): SBD MOMSN=23191590
2024-09-16T19:41:34.138Z,1726515694.138 [NAL9602](INFO): SBD MO Status=0, MOMSN=16717, MT Status=0, MTMSN=0
2024-09-16T19:41:34.139Z,1726515694.139 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:41:35.481Z,1726515695.481 [DataOverHttps](INFO): Sending 500 bytes from file Logs/20240916T180551/Express0056.lzma
2024-09-16T19:41:36.482Z,1726515696.482 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0056.lzma.bak
2024-09-16T19:41:36.482Z,1726515696.482 [DataOverHttps](INFO): SBD MOMSN=23191596
2024-09-16T19:41:37.787Z,1726515697.787 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:41:37.788Z,1726515697.788 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:41:37.788Z,1726515697.788 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:42:04.849Z,1726515724.849 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:42:34.764Z,1726515754.764 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:42:34.764Z,1726515754.764 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:42:34.775Z,1726515754.775 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:42:35.186Z,1726515755.186 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:42:35.186Z,1726515755.186 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:45:35.769Z,1726515935.769 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T19:45:35.769Z,1726515935.769 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:45:35.808Z,1726515935.808 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:45:36.196Z,1726515936.196 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:45:36.196Z,1726515936.196 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T19:46:38.389Z,1726515998.389 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:46:38.389Z,1726515998.389 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:46:38.389Z,1726515998.389 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:46:38.389Z,1726515998.389 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:46:38.801Z,1726515998.801 [Default:CheckIn:D] Stopped
2024-09-16T19:46:38.801Z,1726515998.801 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:46:39.198Z,1726515999.198 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.723751 min
2024-09-16T19:46:39.198Z,1726515999.198 [Default:CheckIn:E] Stopped
2024-09-16T19:46:39.199Z,1726515999.199 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:46:39.199Z,1726515999.199 [Default:CheckIn] Stopped
2024-09-16T19:46:39.199Z,1726515999.199 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:46:39.199Z,1726515999.199 [Default:CheckIn](INFO): Running loop #2
2024-09-16T19:46:39.199Z,1726515999.199 [Default:CheckIn] Running Loop=2
2024-09-16T19:46:39.199Z,1726515999.199 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:46:39.199Z,1726515999.199 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:46:41.199Z,1726516001.199 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194640.00,A,3648.37861,N,12147.97500,W,16.853,287.29,160924,,,D*48
2024-09-16T19:46:41.202Z,1726516001.202 [NAL9602](INFO): GPS fix at 20240916T194640: (36.806310, -121.799583)
2024-09-16T19:46:41.217Z,1726516001.217 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:46:41.217Z,1726516001.217 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:46:48.203Z,1726516008.203 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T180551/Courier0061.lzma
2024-09-16T19:46:49.206Z,1726516009.206 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0061.lzma.bak
2024-09-16T19:46:49.206Z,1726516009.206 [DataOverHttps](INFO): SBD MOMSN=23191650
2024-09-16T19:46:50.086Z,1726516010.086 [NAL9602](INFO): SBD MO Status=0, MOMSN=16718, MT Status=0, MTMSN=0
2024-09-16T19:46:50.086Z,1726516010.086 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:47:07.963Z,1726516027.963 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240916T180551/Express0062.lzma
2024-09-16T19:47:08.966Z,1726516028.966 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0062.lzma.bak
2024-09-16T19:47:08.966Z,1726516028.966 [DataOverHttps](INFO): SBD MOMSN=23191653
2024-09-16T19:47:10.357Z,1726516030.357 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:47:10.358Z,1726516030.358 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:47:10.358Z,1726516030.358 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:47:20.841Z,1726516040.841 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:48:36.810Z,1726516116.810 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:48:36.810Z,1726516116.810 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:48:36.821Z,1726516116.821 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:48:37.242Z,1726516117.242 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:48:37.242Z,1726516117.242 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:51:37.861Z,1726516297.861 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T19:51:37.861Z,1726516297.861 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:51:37.872Z,1726516297.872 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:51:38.279Z,1726516298.279 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:51:38.279Z,1726516298.279 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T19:52:10.989Z,1726516330.989 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:52:10.989Z,1726516330.989 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:52:10.989Z,1726516330.989 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:52:10.989Z,1726516330.989 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:52:11.399Z,1726516331.399 [Default:CheckIn:D] Stopped
2024-09-16T19:52:11.399Z,1726516331.399 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:52:11.806Z,1726516331.806 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.267050 min
2024-09-16T19:52:11.806Z,1726516331.806 [Default:CheckIn:E] Stopped
2024-09-16T19:52:11.807Z,1726516331.807 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:52:11.807Z,1726516331.807 [Default:CheckIn] Stopped
2024-09-16T19:52:11.807Z,1726516331.807 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:52:11.807Z,1726516331.807 [Default:CheckIn](INFO): Running loop #3
2024-09-16T19:52:11.807Z,1726516331.807 [Default:CheckIn] Running Loop=3
2024-09-16T19:52:11.807Z,1726516331.807 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:52:11.807Z,1726516331.807 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:52:13.806Z,1726516333.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195213.00,A,3648.58777,N,12149.10406,W,0.680,249.59,160924,,,D*7E
2024-09-16T19:52:13.819Z,1726516333.819 [NAL9602](INFO): GPS fix at 20240916T195213: (36.809796, -121.818401)
2024-09-16T19:52:13.841Z,1726516333.841 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:52:13.841Z,1726516333.841 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:52:15.844Z,1726516335.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-16T19:52:27.138Z,1726516347.138 [NAL9602](INFO): SBD MO Status=0, MOMSN=16719, MT Status=0, MTMSN=0
2024-09-16T19:52:27.139Z,1726516347.139 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:52:46.968Z,1726516366.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-16T19:52:51.317Z,1726516371.317 [DAT](INFO): DAT read: user:6>Rx Time:19:52:50.1174
2024-09-16T19:52:51.318Z,1726516371.318 [DAT](INFO): Rx dataTimestamp_ set to:1726516371.317164
2024-09-16T19:52:52.078Z,1726516372.078 [DAT](INFO): DAT read: 19:52:50.1174 LVL= 15536, 11537, 10738, 15283, AGC= 65, IDX= 222, 0.23, 0.328,-0.868,-1.738,-0.971, PHS= 1.265, 0.089,-0.779, RAW= 65.0, -6.5, CAL= 70.0, -10.4, ROT= 80.0, 10.4
2024-09-16T19:52:52.079Z,1726516372.079 [DAT](INFO): got valid direction response:
19:52:50.1174 LVL= 15536, 11537, 10738, 15283, AGC= 65, IDX= 222, 0.23, 0.328,-0.868,-1.738,-0.971, PHS= 1.265, 0.089,-0.779, RAW= 65.0, -6.5, CAL= 70.0, -10.4, ROT= 80.0, 10.4
2024-09-16T19:52:52.080Z,1726516372.080 [DAT](INFO): DAT read: $Error in header
2024-09-16T19:52:52.081Z,1726516372.081 [DAT](INFO): Received a bad header
2024-09-16T19:52:52.092Z,1726516372.092 [DAT](INFO): #Rx 1: Read direction message, but no range.
2024-09-16T19:52:52.093Z,1726516372.093 [DAT](INFO): direction in FSK: [0.170795,0.968629,-0.180519]
2024-09-16T19:52:57.846Z,1726516377.846 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:53:18.092Z,1726516398.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-16T19:53:49.216Z,1726516429.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-16T19:53:59.256Z,1726516439.256 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.099197
2024-09-16T19:54:30.380Z,1726516470.380 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-16T19:54:38.800Z,1726516478.800 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003164
2024-09-16T19:54:38.869Z,1726516478.869 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-16T19:54:38.869Z,1726516478.869 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:54:38.905Z,1726516478.905 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:54:39.272Z,1726516479.272 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:54:39.273Z,1726516479.273 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-16T19:54:46.195Z,1726516486.195 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180551/Courier0064.lzma
2024-09-16T19:54:47.198Z,1726516487.198 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Courier0064.lzma.bak
2024-09-16T19:54:47.198Z,1726516487.198 [DataOverHttps](INFO): SBD MOMSN=23191702
2024-09-16T19:55:05.971Z,1726516505.971 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20240916T180551/Express0065.lzma
2024-09-16T19:55:06.974Z,1726516506.974 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180551/Express0065.lzma.bak
2024-09-16T19:55:06.974Z,1726516506.974 [DataOverHttps](INFO): SBD MOMSN=23191708
2024-09-16T19:55:08.020Z,1726516508.020 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:55:08.020Z,1726516508.020 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:55:08.020Z,1726516508.020 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:57:39.914Z,1726516659.914 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-16T19:57:39.914Z,1726516659.914 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-16T19:57:39.925Z,1726516659.925 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-16T19:57:40.330Z,1726516660.330 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-16T19:57:40.330Z,1726516660.330 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-16T19:57:48.110Z,1726516668.110 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T195747
2024-09-16T19:57:55.611Z,1726516675.611 [DataOverHttps](INFO): Received command: configSet DATMMP.loadAtStartup 1 bool persist;configSet DATMMP.simulateHardware 0 bool persist;configSet DAT.loadAtStartup 0 bool persist
2024-09-16T19:57:56.372Z,1726516676.372 [CommandExec](IMPORTANT): got command configSet DATMMP.loadAtStartup 1 bool persist
2024-09-16T19:57:56.372Z,1726516676.372 [CommandExec](IMPORTANT): configSet DATMMP.loadAtStartup requires a restart to take effect.
2024-09-16T19:57:56.376Z,1726516676.376 [CommandExec](IMPORTANT): got command configSet DATMMP.simulateHardware 0 bool persist
2024-09-16T19:57:56.379Z,1726516676.379 [CommandExec](IMPORTANT): got command configSet DAT.loadAtStartup 0 bool persist
2024-09-16T19:57:56.379Z,1726516676.379 [CommandExec](IMPORTANT): configSet DAT.loadAtStartup requires a restart to take effect.
2024-09-16T19:59:00.533Z,1726516740.533 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T195859
2024-09-16T19:59:08.027Z,1726516748.027 [DataOverHttps](INFO): Received command: restart app
2024-09-16T19:59:08.093Z,1726516748.093 [CommandExec](IMPORTANT): got command restart application
2024-09-16T19:59:09.096Z,1726516749.096 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:09.096Z,1726516749.096 [CommandExec](INFO): Uninitializing the command executive.
2024-09-16T19:59:09.096Z,1726516749.096 [CommandExec](INFO): Uninitializing the command scheduler.
2024-09-16T19:59:09.097Z,1726516749.097 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:09.180Z,1726516749.180 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-09-16T19:59:09.180Z,1726516749.180 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-09-16T19:59:09.180Z,1726516749.180 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:09.181Z,1726516749.181 [NavChartDb](INFO): Join timeout helper Thread ID is 2353
2024-09-16T19:59:09.428Z,1726516749.428 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:09.429Z,1726516749.429 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:09.432Z,1726516749.432 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-09-16T19:59:09.432Z,1726516749.432 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:09.432Z,1726516749.432 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2354
2024-09-16T19:59:09.792Z,1726516749.792 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:09.792Z,1726516749.792 [WetLabsBB2FL](INFO): Powering down
2024-09-16T19:59:09.793Z,1726516749.793 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:09.802Z,1726516749.802 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-09-16T19:59:09.802Z,1726516749.802 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:09.803Z,1726516749.803 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2355
2024-09-16T19:59:10.384Z,1726516750.384 [CTD_Seabird](INFO): Powering down
2024-09-16T19:59:10.396Z,1726516750.396 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:10.396Z,1726516750.396 [CTD_Seabird](INFO): Powering down
2024-09-16T19:59:10.408Z,1726516750.408 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:10.412Z,1726516750.412 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-09-16T19:59:10.412Z,1726516750.412 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:10.412Z,1726516750.412 [Radio_Surface](INFO): Join timeout helper Thread ID is 2356
2024-09-16T19:59:10.640Z,1726516750.640 [Radio_Surface](INFO): Powering down
2024-09-16T19:59:10.641Z,1726516750.641 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:10.641Z,1726516750.641 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:10.644Z,1726516750.644 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-09-16T19:59:10.644Z,1726516750.644 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:10.644Z,1726516750.644 [Onboard](INFO): Join timeout helper Thread ID is 2357
2024-09-16T19:59:11.036Z,1726516751.036 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-09-16T19:59:13.668Z,1726516753.668 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:13.669Z,1726516753.669 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:13.677Z,1726516753.677 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-09-16T19:59:13.677Z,1726516753.677 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:13.677Z,1726516753.677 [DataOverHttps](INFO): Join timeout helper Thread ID is 2358
2024-09-16T19:59:14.048Z,1726516754.048 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:14.048Z,1726516754.048 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.057Z,1726516754.057 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-09-16T19:59:14.057Z,1726516754.057 [DAT ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.057Z,1726516754.057 [DAT](INFO): Join timeout helper Thread ID is 2359
2024-09-16T19:59:14.196Z,1726516754.196 [DAT](INFO): Powering down
2024-09-16T19:59:14.268Z,1726516754.268 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:14.268Z,1726516754.268 [DAT](INFO): Powering down
2024-09-16T19:59:14.269Z,1726516754.269 [DAT ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.287Z,1726516754.287 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-09-16T19:59:14.287Z,1726516754.287 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.288Z,1726516754.288 [BackseatComponent](INFO): Join timeout helper Thread ID is 2360
2024-09-16T19:59:14.376Z,1726516754.376 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:14.376Z,1726516754.376 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.381Z,1726516754.381 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-09-16T19:59:14.381Z,1726516754.381 [logger ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.381Z,1726516754.381 [logger](INFO): Join timeout helper Thread ID is 2361
2024-09-16T19:59:14.384Z,1726516754.384 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:14.384Z,1726516754.384 [logger ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.400Z,1726516754.400 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-09-16T19:59:14.401Z,1726516754.401 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.401Z,1726516754.401 [CommandLine](INFO): Join timeout helper Thread ID is 2362
2024-09-16T19:59:14.460Z,1726516754.460 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:14.460Z,1726516754.460 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.482Z,1726516754.482 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-09-16T19:59:14.482Z,1726516754.482 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.482Z,1726516754.482 [CommandExec](INFO): Join timeout helper Thread ID is 2363
2024-09-16T19:59:14.483Z,1726516754.483 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-09-16T19:59:14.483Z,1726516754.483 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.484Z,1726516754.484 [controlThread](INFO): Join timeout helper Thread ID is 2364
2024-09-16T19:59:14.492Z,1726516754.492 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T19:59:14.492Z,1726516754.492 [controlThread](DEBUG): Uninitializing ControlThread
2024-09-16T19:59:14.493Z,1726516754.493 [AHRS_M2](INFO): Powering down
2024-09-16T19:59:14.565Z,1726516754.565 [NAL9602](INFO): Powering down
2024-09-16T19:59:14.566Z,1726516754.566 [Sonardyne_Nano](INFO): Powering down
2024-09-16T19:59:14.684Z,1726516754.684 [Waterlinked](INFO): Powering down
2024-09-16T19:59:14.687Z,1726516754.687 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-09-16T19:59:14.688Z,1726516754.688 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-09-16T19:59:14.689Z,1726516754.689 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-09-16T19:59:14.689Z,1726516754.689 [MissionManager](INFO): Uninitializing Mission Default
2024-09-16T19:59:14.689Z,1726516754.689 [Default] Stopped
2024-09-16T19:59:14.689Z,1726516754.689 [Default](DEBUG): Aggregate::uninitialize Default
2024-09-16T19:59:14.689Z,1726516754.689 [Default:B.GoToSurface] Stopped
2024-09-16T19:59:14.689Z,1726516754.689 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-16T19:59:14.689Z,1726516754.689 [Default:CheckIn] Stopped
2024-09-16T19:59:14.690Z,1726516754.690 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:59:14.690Z,1726516754.690 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:59:14.690Z,1726516754.690 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:59:14.693Z,1726516754.693 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-09-16T19:59:14.693Z,1726516754.693 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-09-16T19:59:14.693Z,1726516754.693 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-09-16T19:59:14.693Z,1726516754.693 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-09-16T19:59:14.694Z,1726516754.694 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-09-16T19:59:14.694Z,1726516754.694 [BuoyancyServo](INFO): Powering down
2024-09-16T19:59:14.708Z,1726516754.708 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-09-16T19:59:14.708Z,1726516754.708 [ElevatorServo](INFO): Powering down
2024-09-16T19:59:14.709Z,1726516754.709 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-09-16T19:59:14.709Z,1726516754.709 [MassServo](INFO): Powering down
2024-09-16T19:59:14.710Z,1726516754.710 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-09-16T19:59:14.710Z,1726516754.710 [RudderServo](INFO): Powering down
2024-09-16T19:59:14.711Z,1726516754.711 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-09-16T19:59:14.711Z,1726516754.711 [ThrusterHE](INFO): Powering down
2024-09-16T19:59:14.712Z,1726516754.712 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-09-16T19:59:14.712Z,1726516754.712 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-09-16T19:59:14.712Z,1726516754.712 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-09-16T19:59:14.713Z,1726516754.713 [CBIT](DEBUG): Powering off loads.
2024-09-16T19:59:14.724Z,1726516754.724 [CBIT](DEBUG): Disabling WDT.
2024-09-16T19:59:14.736Z,1726516754.736 [CBIT](DEBUG): Opening all GF detection circuits.
2024-09-16T19:59:14.737Z,1726516754.737 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.766Z,1726516754.766 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.769Z,1726516754.769 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.775Z,1726516754.775 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.781Z,1726516754.781 [DAT ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.822Z,1726516754.822 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.913Z,1726516754.913 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.919Z,1726516754.919 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.927Z,1726516754.927 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-16T19:59:14.993Z,1726516754.993 [logger ThreadHandler](INFO): Thread cancelled.