2024-06-15T13:13:19.099Z,1718457199.099 [Supervisor](DEBUG): Initializing supervisor.
2024-06-15T13:13:19.103Z,1718457199.103 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-06-15T13:13:19.104Z,1718457199.104 [SyncHandler](INFO): Protected caller Thread ID is 835
2024-06-15T13:13:19.104Z,1718457199.104 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-06-15T13:13:19.105Z,1718457199.105 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-06-15T13:13:19.106Z,1718457199.106 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2024-06-15T13:13:19.109Z,1718457199.109 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-06-15T13:13:19.127Z,1718457199.127 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-06-15T13:13:19.128Z,1718457199.128 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-06-15T13:13:19.128Z,1718457199.128 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2024-06-15T13:13:19.132Z,1718457199.132 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-06-15T13:13:19.133Z,1718457199.133 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-06-15T13:13:19.134Z,1718457199.134 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2024-06-15T13:13:19.136Z,1718457199.136 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-06-15T13:13:19.137Z,1718457199.137 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-06-15T13:13:19.137Z,1718457199.137 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2024-06-15T13:13:19.140Z,1718457199.140 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-06-15T13:13:19.141Z,1718457199.141 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-06-15T13:13:19.145Z,1718457199.145 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-06-15T13:13:19.494Z,1718457199.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-06-15T13:13:19.496Z,1718457199.496 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-06-15T13:13:20.286Z,1718457200.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-06-15T13:13:20.287Z,1718457200.287 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-06-15T13:13:20.408Z,1718457200.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-06-15T13:13:20.410Z,1718457200.410 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-06-15T13:13:20.980Z,1718457200.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-06-15T13:13:20.982Z,1718457200.982 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-06-15T13:13:21.090Z,1718457201.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-06-15T13:13:21.092Z,1718457201.092 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-06-15T13:13:21.185Z,1718457201.185 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-06-15T13:13:21.617Z,1718457201.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-06-15T13:13:21.617Z,1718457201.617 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-06-15T13:13:21.871Z,1718457201.871 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-06-15T13:13:21.873Z,1718457201.873 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-06-15T13:13:22.022Z,1718457202.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-06-15T13:13:22.024Z,1718457202.024 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-06-15T13:13:22.282Z,1718457202.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-06-15T13:13:22.283Z,1718457202.283 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-06-15T13:13:22.895Z,1718457202.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-06-15T13:13:22.895Z,1718457202.895 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-06-15T13:13:23.717Z,1718457203.717 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-06-15T13:13:23.717Z,1718457203.717 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-06-15T13:13:23.811Z,1718457203.811 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-06-15T13:13:24.020Z,1718457204.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-06-15T13:13:24.022Z,1718457204.022 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-06-15T13:13:24.268Z,1718457204.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-06-15T13:13:24.270Z,1718457204.270 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-06-15T13:13:24.586Z,1718457204.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-06-15T13:13:24.588Z,1718457204.588 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2024-06-15T13:13:24.593Z,1718457204.593 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2024-06-15T13:13:24.712Z,1718457204.712 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2024-06-15T13:13:24.877Z,1718457204.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2024-06-15T13:13:24.974Z,1718457204.974 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2024-06-15T13:13:25.071Z,1718457205.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2024-06-15T13:13:25.191Z,1718457205.191 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg
2024-06-15T13:13:25.287Z,1718457205.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2024-06-15T13:13:25.405Z,1718457205.405 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2024-06-15T13:13:25.680Z,1718457205.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-06-15T13:13:25.681Z,1718457205.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2024-06-15T13:13:25.828Z,1718457205.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2024-06-15T13:13:25.933Z,1718457205.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2024-06-15T13:13:26.040Z,1718457206.040 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2024-06-15T13:13:26.157Z,1718457206.157 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2024-06-15T13:13:26.162Z,1718457206.162 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-06-15T13:13:26.304Z,1718457206.304 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-06-15T13:13:26.304Z,1718457206.304 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-06-15T13:13:26.320Z,1718457206.320 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-06-15T13:13:26.320Z,1718457206.320 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-06-15T13:13:26.390Z,1718457206.390 [DepthRateCalculator] Loaded
2024-06-15T13:13:26.391Z,1718457206.391 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-06-15T13:13:26.396Z,1718457206.396 [PitchRateCalculator] Loaded
2024-06-15T13:13:26.396Z,1718457206.396 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-06-15T13:13:26.407Z,1718457206.407 [SpeedCalculator] Loaded
2024-06-15T13:13:26.407Z,1718457206.407 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-06-15T13:13:26.412Z,1718457206.412 [YawRateCalculator] Loaded
2024-06-15T13:13:26.412Z,1718457206.412 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-06-15T13:13:26.433Z,1718457206.433 [ElevatorOffsetCalculator] Loaded
2024-06-15T13:13:26.433Z,1718457206.433 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-06-15T13:13:26.434Z,1718457206.434 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-06-15T13:13:26.434Z,1718457206.434 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-06-15T13:13:26.503Z,1718457206.503 [VerticalControl](DEBUG): Construct VerticalControl.
2024-06-15T13:13:26.559Z,1718457206.559 [VerticalControl] Loaded
2024-06-15T13:13:26.559Z,1718457206.559 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-06-15T13:13:26.562Z,1718457206.562 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-06-15T13:13:26.608Z,1718457206.608 [HorizontalControl] Loaded
2024-06-15T13:13:26.608Z,1718457206.608 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-06-15T13:13:26.611Z,1718457206.611 [SpeedControl](DEBUG): Construct SpeedControl.
2024-06-15T13:13:26.614Z,1718457206.614 [SpeedControl] Loaded
2024-06-15T13:13:26.614Z,1718457206.614 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-06-15T13:13:26.616Z,1718457206.616 [LoopControl](DEBUG): Construct LoopControl.
2024-06-15T13:13:26.617Z,1718457206.617 [LoopControl] Loaded
2024-06-15T13:13:26.617Z,1718457206.617 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-06-15T13:13:26.618Z,1718457206.618 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-06-15T13:13:26.618Z,1718457206.618 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-06-15T13:13:26.754Z,1718457206.754 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-06-15T13:13:26.754Z,1718457206.754 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-06-15T13:13:26.768Z,1718457206.768 [NavChart] Loaded
2024-06-15T13:13:26.769Z,1718457206.769 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-06-15T13:13:26.774Z,1718457206.774 [UniversalFixResidualReporter] Loaded
2024-06-15T13:13:26.774Z,1718457206.774 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-06-15T13:13:26.775Z,1718457206.775 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-06-15T13:13:26.777Z,1718457206.777 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-06-15T13:13:26.985Z,1718457206.985 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-06-15T13:13:26.985Z,1718457206.985 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-06-15T13:13:27.768Z,1718457207.768 [AHRS_M2] Loaded
2024-06-15T13:13:27.768Z,1718457207.768 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-06-15T13:13:28.216Z,1718457208.216 [BackseatComponent] Loaded
2024-06-15T13:13:28.216Z,1718457208.216 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-06-15T13:13:28.217Z,1718457208.217 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409214E0
2024-06-15T13:13:28.225Z,1718457208.225 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924
2024-06-15T13:13:28.228Z,1718457208.228 [LcmUniversalReporter] Loaded
2024-06-15T13:13:28.228Z,1718457208.228 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-06-15T13:13:29.424Z,1718457209.424 [BPC1] Loaded
2024-06-15T13:13:29.424Z,1718457209.424 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-06-15T13:13:29.575Z,1718457209.575 [DataOverHttps] Loaded
2024-06-15T13:13:29.575Z,1718457209.575 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-06-15T13:13:29.576Z,1718457209.576 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409514E0
2024-06-15T13:13:29.576Z,1718457209.576 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926
2024-06-15T13:13:29.599Z,1718457209.599 [Depth_Keller] Loaded
2024-06-15T13:13:29.599Z,1718457209.599 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-06-15T13:13:29.604Z,1718457209.604 [DropWeight] Loaded
2024-06-15T13:13:29.605Z,1718457209.605 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-06-15T13:13:29.656Z,1718457209.656 [DVL_micro] Loaded
2024-06-15T13:13:29.657Z,1718457209.657 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2024-06-15T13:13:29.720Z,1718457209.720 [NAL9602] Loaded
2024-06-15T13:13:29.721Z,1718457209.721 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-06-15T13:13:29.750Z,1718457209.750 [Onboard] Loaded
2024-06-15T13:13:29.750Z,1718457209.750 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-06-15T13:13:29.751Z,1718457209.751 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409814E0
2024-06-15T13:13:29.752Z,1718457209.752 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927
2024-06-15T13:13:29.766Z,1718457209.766 [Power24vConverter] Loaded
2024-06-15T13:13:29.766Z,1718457209.766 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-06-15T13:13:29.781Z,1718457209.781 [Radio_Surface] Loaded
2024-06-15T13:13:29.782Z,1718457209.782 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-06-15T13:13:29.782Z,1718457209.782 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409B14E0
2024-06-15T13:13:29.783Z,1718457209.783 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928
2024-06-15T13:13:29.813Z,1718457209.813 [Sonardyne_Nano] Loaded
2024-06-15T13:13:29.813Z,1718457209.813 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-06-15T13:13:29.909Z,1718457209.909 [DAT] Loaded
2024-06-15T13:13:29.909Z,1718457209.909 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-06-15T13:13:29.910Z,1718457209.910 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409E14E0
2024-06-15T13:13:29.911Z,1718457209.911 [DAT ThreadHandler](INFO): Protected caller Thread ID is 929
2024-06-15T13:13:29.911Z,1718457209.911 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-06-15T13:13:29.912Z,1718457209.912 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-06-15T13:13:30.072Z,1718457210.072 [BuoyancyServo] Loaded
2024-06-15T13:13:30.073Z,1718457210.073 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-06-15T13:13:30.096Z,1718457210.096 [ElevatorServo] Loaded
2024-06-15T13:13:30.096Z,1718457210.096 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-06-15T13:13:30.118Z,1718457210.118 [MassServo] Loaded
2024-06-15T13:13:30.118Z,1718457210.118 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-06-15T13:13:30.138Z,1718457210.138 [RudderServo] Loaded
2024-06-15T13:13:30.139Z,1718457210.139 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-06-15T13:13:30.155Z,1718457210.155 [ThrusterHE] Loaded
2024-06-15T13:13:30.155Z,1718457210.155 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-06-15T13:13:30.155Z,1718457210.155 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-06-15T13:13:30.156Z,1718457210.156 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-06-15T13:13:30.238Z,1718457210.238 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-06-15T13:13:30.239Z,1718457210.239 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-06-15T13:13:30.281Z,1718457210.281 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-06-15T13:13:30.282Z,1718457210.282 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-06-15T13:13:30.674Z,1718457210.674 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-06-15T13:13:30.675Z,1718457210.675 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-06-15T13:13:30.899Z,1718457210.899 [CTD_Seabird] Loaded
2024-06-15T13:13:30.899Z,1718457210.899 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-06-15T13:13:30.900Z,1718457210.900 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B984E0
2024-06-15T13:13:30.901Z,1718457210.901 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 930
2024-06-15T13:13:30.937Z,1718457210.937 [ESPComponent] Loaded
2024-06-15T13:13:30.937Z,1718457210.937 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-06-15T13:13:30.959Z,1718457210.959 [PAR_Licor] Loaded
2024-06-15T13:13:30.959Z,1718457210.959 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-06-15T13:13:31.004Z,1718457211.004 [WetLabsBB2FL] Loaded
2024-06-15T13:13:31.004Z,1718457211.004 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-06-15T13:13:31.005Z,1718457211.005 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BC84E0
2024-06-15T13:13:31.006Z,1718457211.006 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 931
2024-06-15T13:13:31.006Z,1718457211.006 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-06-15T13:13:31.007Z,1718457211.007 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-06-15T13:13:31.176Z,1718457211.176 [SBIT](DEBUG): Construct Startup Built In Test.
2024-06-15T13:13:31.185Z,1718457211.185 [SBIT] Loaded
2024-06-15T13:13:31.185Z,1718457211.185 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-06-15T13:13:31.188Z,1718457211.188 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-06-15T13:13:31.201Z,1718457211.201 [IBIT] Loaded
2024-06-15T13:13:31.201Z,1718457211.201 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-06-15T13:13:31.207Z,1718457211.207 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-06-15T13:13:31.313Z,1718457211.313 [CBIT] Loaded
2024-06-15T13:13:31.314Z,1718457211.314 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-06-15T13:13:31.314Z,1718457211.314 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-06-15T13:13:31.320Z,1718457211.320 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-06-15T13:13:31.323Z,1718457211.323 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-06-15T13:13:31.335Z,1718457211.335 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-06-15T13:13:31.336Z,1718457211.336 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0
2024-06-15T13:13:31.336Z,1718457211.336 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 932
2024-06-15T13:13:31.341Z,1718457211.341 [Supervisor](INFO): Main Thread ID is 828
2024-06-15T13:13:31.341Z,1718457211.341 [Supervisor](DEBUG): Running supervisor.
2024-06-15T13:13:31.342Z,1718457211.342 [CommandExec ThreadHandler](INFO): Handler Thread ID is 933
2024-06-15T13:13:31.342Z,1718457211.342 [CommandExec](INFO): Initializing the command executive.
2024-06-15T13:13:31.344Z,1718457211.344 [CommandLine ThreadHandler](INFO): Handler Thread ID is 934
2024-06-15T13:13:31.346Z,1718457211.346 [controlThread ThreadHandler](INFO): Handler Thread ID is 935
2024-06-15T13:13:31.346Z,1718457211.346 [controlThread](DEBUG): Initializing ControlThread
2024-06-15T13:13:31.347Z,1718457211.347 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-06-15T13:13:31.347Z,1718457211.347 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-06-15T13:13:31.348Z,1718457211.348 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-06-15T13:13:31.348Z,1718457211.348 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-06-15T13:13:31.348Z,1718457211.348 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-06-15T13:13:31.349Z,1718457211.349 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-06-15T13:13:31.351Z,1718457211.351 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-06-15T13:13:31.351Z,1718457211.351 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-06-15T13:13:31.352Z,1718457211.352 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-06-15T13:13:31.353Z,1718457211.353 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-06-15T13:13:31.353Z,1718457211.353 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-06-15T13:13:31.362Z,1718457211.362 [SBIT](INFO): Initialize SBIT Component.
2024-06-15T13:13:31.362Z,1718457211.362 [SBIT](IMPORTANT): git: 2024-06-04
2024-06-15T13:13:31.362Z,1718457211.362 [SBIT](INFO): git hash: 665aaa51948338ae26aa7901209e8336b196d4d1
2024-06-15T13:13:31.363Z,1718457211.363 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-06-15T13:13:31.364Z,1718457211.364 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-06-15T13:13:31.365Z,1718457211.365 [SBIT](INFO): Beginning SBIT in 134.000000 seconds.
2024-06-15T13:13:31.365Z,1718457211.365 [IBIT](INFO): Initialize IBIT Component.
2024-06-15T13:13:31.366Z,1718457211.366 [CBIT](DEBUG): Initialize CBIT Component.
2024-06-15T13:13:31.367Z,1718457211.367 [logger ThreadHandler](INFO): Handler Thread ID is 936
2024-06-15T13:13:31.377Z,1718457211.377 [CBIT](DEBUG): Initialized mux pins.
2024-06-15T13:13:31.378Z,1718457211.378 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2024-06-15T13:13:31.378Z,1718457211.378 [CBIT](DEBUG): Initializing the watchdog timer.
2024-06-15T13:13:31.386Z,1718457211.386 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 937
2024-06-15T13:13:31.398Z,1718457211.398 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938
2024-06-15T13:13:31.399Z,1718457211.399 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-06-15T13:13:31.401Z,1718457211.401 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2024-06-15T13:13:31.402Z,1718457211.402 [CBIT](DEBUG): Initializing heartbeat.
2024-06-15T13:13:31.410Z,1718457211.410 [Onboard ThreadHandler](INFO): Handler Thread ID is 939
2024-06-15T13:13:31.428Z,1718457211.428 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940
2024-06-15T13:13:31.450Z,1718457211.450 [DAT ThreadHandler](INFO): Handler Thread ID is 941
2024-06-15T13:13:31.451Z,1718457211.451 [DAT](INFO): Powering up
2024-06-15T13:13:31.451Z,1718457211.451 [DAT](DEBUG): Initializing DAT.
2024-06-15T13:13:31.455Z,1718457211.455 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 943
2024-06-15T13:13:31.455Z,1718457211.455 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-06-15T13:13:31.459Z,1718457211.459 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 945
2024-06-15T13:13:31.462Z,1718457211.462 [WetLabsBB2FL](INFO): Powering up
2024-06-15T13:13:31.463Z,1718457211.463 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 947
2024-06-15T13:13:31.471Z,1718457211.471 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000
2024-06-15T13:13:31.471Z,1718457211.471 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000
2024-06-15T13:13:31.472Z,1718457211.472 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000
2024-06-15T13:13:31.472Z,1718457211.472 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000
2024-06-15T13:13:31.472Z,1718457211.472 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000
2024-06-15T13:13:31.472Z,1718457211.472 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000
2024-06-15T13:13:31.473Z,1718457211.473 [CBIT](DEBUG): Deactivating GF circuits.
2024-06-15T13:13:31.474Z,1718457211.474 [CBIT](DEBUG): Deactivating emergency mode.
2024-06-15T13:13:31.513Z,1718457211.513 [CBIT](DEBUG): Backplane powered.
2024-06-15T13:13:31.514Z,1718457211.514 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-06-15T13:13:31.515Z,1718457211.515 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-06-15T13:13:31.515Z,1718457211.515 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-06-15T13:13:31.530Z,1718457211.530 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-06-15T13:13:31.564Z,1718457211.564 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-06-15T13:13:31.568Z,1718457211.568 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-15T13:13:31.616Z,1718457211.616 [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-06-15T13:13:31.617Z,1718457211.617 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-06-15T13:13:31.617Z,1718457211.617 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-06-15T13:13:31.626Z,1718457211.626 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-06-15T13:13:31.833Z,1718457211.833 [Radio_Surface](INFO): Powering up
2024-06-15T13:13:31.925Z,1718457211.925 [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-06-15T13:13:31.946Z,1718457211.946 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-06-15T13:13:31.947Z,1718457211.947 [Default:A.Wait](DEBUG): Construct Wait.
2024-06-15T13:13:31.950Z,1718457211.950 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-15T13:13:31.991Z,1718457211.991 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-06-15T13:13:31.994Z,1718457211.994 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-06-15T13:13:32.008Z,1718457212.008 [Default:E.Execute](DEBUG): Construct Execute.
2024-06-15T13:13:32.031Z,1718457212.031 [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-06-15T13:13:32.036Z,1718457212.036 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-06-15T13:13:32.059Z,1718457212.059 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-06-15T13:13:32.207Z,1718457212.207 [Power24vConverter](INFO): Powering up.
2024-06-15T13:13:32.208Z,1718457212.208 [Sonardyne_Nano](INFO): Initializing.
2024-06-15T13:13:32.263Z,1718457212.263 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:13:32.270Z,1718457212.270 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-06-15T13:13:32.271Z,1718457212.271 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:13:32.278Z,1718457212.278 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-15T13:13:32.279Z,1718457212.279 [MassServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:13:32.286Z,1718457212.286 [MassServo](DEBUG): Initializing MassServo.
2024-06-15T13:13:32.287Z,1718457212.287 [RudderServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:13:32.294Z,1718457212.294 [RudderServo](DEBUG): Initializing RudderServo.
2024-06-15T13:13:32.295Z,1718457212.295 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-06-15T13:13:32.302Z,1718457212.302 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-06-15T13:13:32.303Z,1718457212.303 [CommandExec](FAULT): Scheduling is paused
2024-06-15T13:13:32.304Z,1718457212.304 [CBIT](INFO): Critical error at 20240615T131331
2024-06-15T13:13:32.304Z,1718457212.304 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-06-15T13:13:34.074Z,1718457214.074 [WetLabsBB2FL](INFO): Powering down
2024-06-15T13:13:34.470Z,1718457214.470 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-06-15T13:13:35.706Z,1718457215.706 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207
2024-06-15T13:13:37.799Z,1718457217.799 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-06-15T13:13:43.550Z,1718457223.550 [DAT](INFO): DAT read:
2024-06-15T13:13:43.551Z,1718457223.551 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-06-15T13:13:45.314Z,1718457225.314 [DAT](INFO): DAT read: MF Frequency Band
2024-06-15T13:13:45.316Z,1718457225.316 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-06-15T13:13:45.316Z,1718457225.316 [DAT](INFO): DAT read: Jun 15 2024 13:13:34
2024-06-15T13:13:46.323Z,1718457226.323 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-06-15T13:13:46.324Z,1718457226.324 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-06-15T13:13:46.324Z,1718457226.324 [DAT](INFO): commRate: 800
2024-06-15T13:13:48.389Z,1718457228.389 [DAT](INFO): entering command mode
2024-06-15T13:13:48.590Z,1718457228.590 [DAT](INFO): DAT read:
2024-06-15T13:13:48.590Z,1718457228.590 [DAT](INFO): DAT read: user:1>
2024-06-15T13:13:48.591Z,1718457228.591 [DAT](INFO): setting verbose to 3
2024-06-15T13:13:48.842Z,1718457228.842 [DAT](INFO): DAT read: user:1>
2024-06-15T13:13:48.843Z,1718457228.843 [DAT](INFO): DAT read: Verbose | 3
2024-06-15T13:13:48.843Z,1718457228.843 [DAT](INFO): set verbose to 3
2024-06-15T13:13:48.844Z,1718457228.844 [DAT](INFO): setting DatVerbose to 27440
2024-06-15T13:13:49.094Z,1718457229.094 [DAT](INFO): DAT read: user:2>
2024-06-15T13:13:49.095Z,1718457229.095 [DAT](INFO): DAT read: DatVerbose | 27440
2024-06-15T13:13:49.095Z,1718457229.095 [DAT](INFO): set DatVerbose to 27440
2024-06-15T13:13:49.096Z,1718457229.096 [DAT](INFO): setting transmit power to 8
2024-06-15T13:13:49.346Z,1718457229.346 [DAT](INFO): DAT read: user:3>
2024-06-15T13:13:49.348Z,1718457229.348 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-06-15T13:13:49.350Z,1718457229.350 [DAT](INFO): set transmit power to 8
2024-06-15T13:13:49.352Z,1718457229.352 [DAT](INFO): setting local address to 11
2024-06-15T13:13:49.598Z,1718457229.598 [DAT](INFO): DAT read: user:4>
2024-06-15T13:13:49.599Z,1718457229.599 [DAT](INFO): DAT read: LocalAddr | 11
2024-06-15T13:13:49.599Z,1718457229.599 [DAT](INFO): set local address to 11
2024-06-15T13:13:49.601Z,1718457229.601 [DAT](INFO): Setting time to: 13:13:49 And date to:6/15/2024
2024-06-15T13:13:49.850Z,1718457229.850 [DAT](INFO): DAT read: user:5>
2024-06-15T13:13:49.851Z,1718457229.851 [DAT](INFO): DAT read: Sat Jun 15, 2024 13:13:49
2024-06-15T13:13:49.851Z,1718457229.851 [DAT](INFO): Local DAT time set to Sat Jun 15, 2024 13:13:49
2024-06-15T13:13:59.928Z,1718457239.928 [NAL9602](INFO): Powering up NAL9602
2024-06-15T13:14:10.836Z,1718457250.836 [NAL9602](INFO): NAL9602 initialized
2024-06-15T13:14:37.490Z,1718457277.490 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:14:51.631Z,1718457291.631 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:15:05.366Z,1718457305.366 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:15:19.506Z,1718457319.506 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:15:33.646Z,1718457333.646 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:15:45.809Z,1718457345.809 [SBIT](IMPORTANT): Beginning Startup BIT
2024-06-15T13:15:45.832Z,1718457345.832 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-15T13:15:47.383Z,1718457347.383 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:15:56.711Z,1718457356.711 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006847
CHAN A1 (24V): 0.001991
CHAN A2 (12V): -0.006441
CHAN A3 (5V): -0.010660
CHAN B0 (3.3V): -0.002706
CHAN B1 (3.15aV): -0.004210
CHAN B2 (3.15bV): 0.001140
CHAN B3 (GND): -0.005042
OPEN: 0.001965
Full Scale: +/- 1 mA
2024-06-15T13:16:04.025Z,1718457364.025 [ElevatorServo](FAULT): Overload Error
2024-06-15T13:16:04.025Z,1718457364.025 [ElevatorServo] Hardware Fault, FailCount= 1
2024-06-15T13:16:04.025Z,1718457364.025 [ElevatorServo](ERROR): Hardware Fault
2024-06-15T13:16:04.110Z,1718457364.110 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-06-15T13:16:04.367Z,1718457364.367 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-15T13:16:04.368Z,1718457364.368 [ElevatorServo](INFO): Powering down
2024-06-15T13:16:05.183Z,1718457365.183 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:16:05.298Z,1718457365.298 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-15T13:16:05.359Z,1718457365.359 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-06-15T13:16:05.359Z,1718457365.359 [ElevatorServo] No Fault, FailCount= 1
2024-06-15T13:16:10.469Z,1718457370.469 [ElevatorServo](FAULT): Overload Error
2024-06-15T13:16:10.470Z,1718457370.470 [ElevatorServo] Hardware Fault, FailCount= 2
2024-06-15T13:16:10.470Z,1718457370.470 [ElevatorServo](ERROR): Hardware Fault
2024-06-15T13:16:10.530Z,1718457370.530 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-06-15T13:16:10.836Z,1718457370.836 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-15T13:16:10.836Z,1718457370.836 [ElevatorServo](INFO): Powering down
2024-06-15T13:16:11.662Z,1718457371.662 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:16:11.778Z,1718457371.778 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-15T13:16:11.811Z,1718457371.811 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-06-15T13:16:11.811Z,1718457371.811 [ElevatorServo] No Fault, FailCount= 2
2024-06-15T13:16:16.965Z,1718457376.965 [ElevatorServo](FAULT): Overload Error
2024-06-15T13:16:16.966Z,1718457376.966 [ElevatorServo] Hardware Fault, FailCount= 3
2024-06-15T13:16:16.966Z,1718457376.966 [ElevatorServo](ERROR): Hardware Fault
2024-06-15T13:16:17.002Z,1718457377.002 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-06-15T13:16:17.002Z,1718457377.002 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2024-06-15T13:16:17.325Z,1718457377.325 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-15T13:16:17.326Z,1718457377.326 [ElevatorServo](INFO): Powering down
2024-06-15T13:16:17.329Z,1718457377.329 [CBIT](INFO): Critical error at 20240615T131617
2024-06-15T13:16:18.111Z,1718457378.111 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:16:18.226Z,1718457378.226 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-15T13:16:25.785Z,1718457385.785 [SBIT](ERROR): Could not read elevatorAngleReader_.
2024-06-15T13:16:25.785Z,1718457385.785 [SBIT](FAULT): Control surface position failure.
2024-06-15T13:16:31.448Z,1718457391.448 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-06-15T13:16:31.448Z,1718457391.448 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-15T13:16:31.514Z,1718457391.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-15T13:16:31.890Z,1718457391.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-15T13:16:31.890Z,1718457391.890 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-06-15T13:16:35.725Z,1718457395.725 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008904
2024-06-15T13:16:39.522Z,1718457399.522 [SBIT](ERROR): Could not read elevatorAngleReader_.
2024-06-15T13:16:39.522Z,1718457399.522 [SBIT](FAULT): Control surface position failure.
2024-06-15T13:16:39.924Z,1718457399.924 [SBIT](CRITICAL): SBIT FAILED
2024-06-15T13:16:39.924Z,1718457399.924 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2024-06-15T13:16:39.926Z,1718457399.926 [CBIT](INFO): Critical error at 20240615T131639
2024-06-15T13:16:40.326Z,1718457400.326 [MissionManager](IMPORTANT): Started mission Startup
2024-06-15T13:16:40.327Z,1718457400.327 [Startup] Running Loop=1
2024-06-15T13:16:40.327Z,1718457400.327 [Startup](DEBUG): Aggregate::initialize Startup
2024-06-15T13:16:40.327Z,1718457400.327 [Startup:A.GoToSurface] Running Loop=1
2024-06-15T13:16:40.327Z,1718457400.327 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-15T13:16:40.327Z,1718457400.327 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-15T13:16:40.328Z,1718457400.328 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-15T13:16:40.330Z,1718457400.330 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-15T13:16:40.332Z,1718457400.332 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-15T13:16:40.334Z,1718457400.334 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-15T13:16:40.334Z,1718457400.334 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-15T13:16:40.336Z,1718457400.336 [Startup:StartupSatComms] Running Loop=1
2024-06-15T13:16:40.336Z,1718457400.336 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-06-15T13:16:40.336Z,1718457400.336 [Startup:StartupSatComms:A] Running Loop=1
2024-06-15T13:16:40.739Z,1718457400.739 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-06-15T13:17:01.776Z,1718457421.776 [NAL9602](INFO): SBD MO Status=0, MOMSN=21531, MT Status=0, MTMSN=0
2024-06-15T13:17:01.776Z,1718457421.776 [NAL9602](INFO): No messages in MT queue
2024-06-15T13:17:02.988Z,1718457422.988 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131659.00,A,5528.30279,N,00825.37051,E,5.346,30.13,150624,,,A*5C
2024-06-15T13:17:02.991Z,1718457422.991 [NAL9602](INFO): GPS fix at 20240615T131659: (55.471713, 8.422842)
2024-06-15T13:17:03.003Z,1718457423.003 [Startup:StartupSatComms:A] Stopped
2024-06-15T13:17:03.003Z,1718457423.003 [Startup:StartupSatComms:B] Running Loop=1
2024-06-15T13:17:03.435Z,1718457423.435 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-15T13:17:15.711Z,1718457435.711 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240615T122057/Courier0022.lzma
2024-06-15T13:17:17.718Z,1718457437.718 [DataOverHttps](INFO): Moved sent file to Logs/20240615T122057/Courier0022.lzma.bak
2024-06-15T13:17:17.718Z,1718457437.718 [DataOverHttps](INFO): SBD MOMSN=21235289
2024-06-15T13:17:35.330Z,1718457455.330 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-15T13:17:42.069Z,1718457462.069 [DataOverHttps](INFO): Sending 391 bytes from file Logs/20240615T131319/Courier0000.lzma
2024-06-15T13:17:43.070Z,1718457463.070 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0000.lzma.bak
2024-06-15T13:17:43.070Z,1718457463.070 [DataOverHttps](INFO): SBD MOMSN=21235292
2024-06-15T13:18:03.175Z,1718457483.175 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240615T122057/Express0023.lzma
2024-06-15T13:18:03.215Z,1718457483.215 [Startup:StartupSatComms:B](INFO): Timed out from 2024-06-15T13:17:03.0Z
2024-06-15T13:18:03.215Z,1718457483.215 [Startup:StartupSatComms:B] Stopped
2024-06-15T13:18:03.216Z,1718457483.216 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-06-15T13:18:03.216Z,1718457483.216 [Startup:StartupSatComms] Stopped
2024-06-15T13:18:03.216Z,1718457483.216 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-06-15T13:18:03.217Z,1718457483.217 [Startup](INFO): Completed Startup
2024-06-15T13:18:03.217Z,1718457483.217 [MissionManager](INFO): Startup is completed.
2024-06-15T13:18:03.217Z,1718457483.217 [MissionManager](INFO): Uninitializing Mission Startup
2024-06-15T13:18:03.217Z,1718457483.217 [Startup] Stopped
2024-06-15T13:18:03.217Z,1718457483.217 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-06-15T13:18:03.217Z,1718457483.217 [Startup:A.GoToSurface] Stopped
2024-06-15T13:18:03.218Z,1718457483.218 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-15T13:18:03.632Z,1718457483.632 [MissionManager](IMPORTANT): Started mission Default
2024-06-15T13:18:03.632Z,1718457483.632 [Default] Running Loop=1
2024-06-15T13:18:03.632Z,1718457483.632 [Default](DEBUG): Aggregate::initialize Default
2024-06-15T13:18:03.633Z,1718457483.633 [Default:B.GoToSurface] Running Loop=1
2024-06-15T13:18:03.633Z,1718457483.633 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-15T13:18:03.633Z,1718457483.633 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-15T13:18:03.633Z,1718457483.633 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-15T13:18:03.633Z,1718457483.633 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-15T13:18:03.634Z,1718457483.634 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-15T13:18:03.634Z,1718457483.634 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-15T13:18:03.634Z,1718457483.634 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-15T13:18:03.635Z,1718457483.635 [Default:A.Wait] Running Loop=1
2024-06-15T13:18:03.635Z,1718457483.635 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-06-15T13:18:04.178Z,1718457484.178 [DataOverHttps](INFO): Moved sent file to Logs/20240615T122057/Express0023.lzma.bak
2024-06-15T13:18:04.178Z,1718457484.178 [DataOverHttps](INFO): SBD MOMSN=21235346
2024-06-15T13:18:17.043Z,1718457497.043 [Default:A.Wait](INFO): Done Waiting.
2024-06-15T13:18:17.043Z,1718457497.043 [Default:A.Wait] Stopped
2024-06-15T13:18:17.043Z,1718457497.043 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T13:18:17.406Z,1718457497.406 [Default:CheckIn] Running Loop=1
2024-06-15T13:18:17.406Z,1718457497.406 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T13:18:17.406Z,1718457497.406 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T13:18:17.834Z,1718457497.834 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-06-15T13:18:19.371Z,1718457499.371 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131815.00,A,5528.40292,N,00825.32088,E,5.482,342.69,150624,,,A*6D
2024-06-15T13:18:19.374Z,1718457499.374 [NAL9602](INFO): GPS fix at 20240615T131815: (55.473382, 8.422015)
2024-06-15T13:18:19.403Z,1718457499.403 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T13:18:19.403Z,1718457499.403 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T13:18:19.811Z,1718457499.811 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-15T13:18:31.827Z,1718457511.827 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240615T131319/Courier0004.lzma
2024-06-15T13:18:32.829Z,1718457512.829 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0004.lzma.bak
2024-06-15T13:18:32.830Z,1718457512.830 [DataOverHttps](INFO): SBD MOMSN=21235348
2024-06-15T13:18:36.751Z,1718457516.751 [NAL9602](INFO): SBD MO Status=0, MOMSN=21532, MT Status=0, MTMSN=0
2024-06-15T13:18:36.751Z,1718457516.751 [NAL9602](INFO): No messages in MT queue
2024-06-15T13:18:59.946Z,1718457539.946 [DataOverHttps](INFO): Sending 864 bytes from file Logs/20240615T131319/Express0001.lzma
2024-06-15T13:19:00.946Z,1718457540.946 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0001.lzma.bak
2024-06-15T13:19:00.946Z,1718457540.946 [DataOverHttps](INFO): SBD MOMSN=21235352
2024-06-15T13:19:07.441Z,1718457547.441 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-15T13:19:23.527Z,1718457563.527 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20240615T131319/Express0005.lzma
2024-06-15T13:19:24.530Z,1718457564.530 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0005.lzma.bak
2024-06-15T13:19:24.530Z,1718457564.530 [DataOverHttps](INFO): SBD MOMSN=21235371
2024-06-15T13:19:29.283Z,1718457569.283 [Default:CheckIn:Read_Iridium] Stopped
2024-06-15T13:19:29.288Z,1718457569.288 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-15T13:19:29.288Z,1718457569.288 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-15T13:20:45.209Z,1718457645.209 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:20:50.458Z,1718457650.458 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:21:05.406Z,1718457665.406 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:21:20.759Z,1718457680.759 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:21:35.707Z,1718457695.707 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:21:50.656Z,1718457710.656 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:22:05.602Z,1718457725.602 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:22:20.148Z,1718457740.148 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:22:34.692Z,1718457754.692 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:22:49.638Z,1718457769.638 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:23:04.587Z,1718457784.587 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:23:19.939Z,1718457799.939 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:23:33.676Z,1718457813.676 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:23:47.814Z,1718457827.814 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:24:01.551Z,1718457841.551 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:24:15.692Z,1718457855.692 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:24:29.427Z,1718457869.427 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:24:29.863Z,1718457869.863 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-15T13:24:29.863Z,1718457869.863 [Default:CheckIn:C.Wait] Stopped
2024-06-15T13:24:29.863Z,1718457869.863 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T13:24:29.863Z,1718457869.863 [Default:CheckIn:D] Running Loop=1
2024-06-15T13:24:30.267Z,1718457870.267 [Default:CheckIn:D] Stopped
2024-06-15T13:24:30.267Z,1718457870.267 [Default:CheckIn:E] Running Loop=1
2024-06-15T13:24:30.657Z,1718457870.657 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.443914 min
2024-06-15T13:24:30.657Z,1718457870.657 [Default:CheckIn:E] Stopped
2024-06-15T13:24:30.657Z,1718457870.657 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-15T13:24:30.657Z,1718457870.657 [Default:CheckIn] Stopped
2024-06-15T13:24:30.657Z,1718457870.657 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-15T13:24:30.657Z,1718457870.657 [Default:CheckIn](INFO): Running loop #2
2024-06-15T13:24:30.657Z,1718457870.657 [Default:CheckIn] Running Loop=2
2024-06-15T13:24:30.657Z,1718457870.657 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T13:24:30.657Z,1718457870.657 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T13:24:32.663Z,1718457872.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132428.00,A,5528.51780,N,00825.46761,E,0.078,42.39,150624,,,D*5E
2024-06-15T13:24:32.666Z,1718457872.666 [NAL9602](INFO): GPS fix at 20240615T132428: (55.475297, 8.424460)
2024-06-15T13:24:32.691Z,1718457872.691 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T13:24:32.691Z,1718457872.691 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T13:24:36.967Z,1718457876.967 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240615T131319/Courier0007.lzma
2024-06-15T13:24:37.974Z,1718457877.974 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0007.lzma.bak
2024-06-15T13:24:37.974Z,1718457877.974 [DataOverHttps](INFO): SBD MOMSN=21235382
2024-06-15T13:24:43.227Z,1718457883.227 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:24:56.962Z,1718457896.963 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:25:02.496Z,1718457902.496 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20240615T131319/Express0008.lzma
2024-06-15T13:25:03.499Z,1718457903.499 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0008.lzma.bak
2024-06-15T13:25:03.499Z,1718457903.499 [DataOverHttps](INFO): SBD MOMSN=21235391
2024-06-15T13:25:08.303Z,1718457908.303 [Default:CheckIn:Read_Iridium] Stopped
2024-06-15T13:25:08.308Z,1718457908.308 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-15T13:25:08.308Z,1718457908.308 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-15T13:25:09.491Z,1718457909.491 [NAL9602](INFO): SBD MO Status=2, MOMSN=21533, MT Status=2, MTMSN=0
2024-06-15T13:25:09.491Z,1718457909.491 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-15T13:25:10.698Z,1718457910.698 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:25:24.436Z,1718457924.436 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:25:38.176Z,1718457938.176 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:25:38.226Z,1718457938.226 [NAL9602](INFO): SBD MO Status=2, MOMSN=21533, MT Status=2, MTMSN=0
2024-06-15T13:25:38.227Z,1718457938.227 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-15T13:25:56.363Z,1718457956.363 [NAL9602](INFO): SBD MO Status=0, MOMSN=21533, MT Status=0, MTMSN=0
2024-06-15T13:25:56.363Z,1718457956.363 [NAL9602](INFO): No messages in MT queue
2024-06-15T13:26:27.078Z,1718457987.078 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-15T13:30:08.873Z,1718458208.873 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-15T13:30:08.873Z,1718458208.873 [Default:CheckIn:C.Wait] Stopped
2024-06-15T13:30:08.874Z,1718458208.874 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T13:30:08.874Z,1718458208.874 [Default:CheckIn:D] Running Loop=1
2024-06-15T13:30:09.272Z,1718458209.272 [Default:CheckIn:D] Stopped
2024-06-15T13:30:09.272Z,1718458209.272 [Default:CheckIn:E] Running Loop=1
2024-06-15T13:30:09.673Z,1718458209.673 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.093986 min
2024-06-15T13:30:09.673Z,1718458209.673 [Default:CheckIn:E] Stopped
2024-06-15T13:30:09.673Z,1718458209.673 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-15T13:30:09.673Z,1718458209.673 [Default:CheckIn] Stopped
2024-06-15T13:30:09.673Z,1718458209.673 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-15T13:30:09.673Z,1718458209.673 [Default:CheckIn](INFO): Running loop #3
2024-06-15T13:30:09.673Z,1718458209.673 [Default:CheckIn] Running Loop=3
2024-06-15T13:30:09.673Z,1718458209.673 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T13:30:09.673Z,1718458209.673 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T13:30:11.687Z,1718458211.687 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133010.00,A,5528.51889,N,00825.46625,E,0.019,42.39,150624,,,D*50
2024-06-15T13:30:11.689Z,1718458211.689 [NAL9602](INFO): GPS fix at 20240615T133010: (55.475315, 8.424437)
2024-06-15T13:30:11.713Z,1718458211.713 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T13:30:11.713Z,1718458211.713 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T13:30:15.626Z,1718458215.626 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240615T131319/Courier0010.lzma
2024-06-15T13:30:16.637Z,1718458216.637 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0010.lzma.bak
2024-06-15T13:30:16.638Z,1718458216.638 [DataOverHttps](INFO): SBD MOMSN=21235442
2024-06-15T13:30:32.351Z,1718458232.351 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:30:37.198Z,1718458237.198 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:30:41.337Z,1718458241.337 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240615T131319/Express0011.lzma
2024-06-15T13:30:43.342Z,1718458243.342 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0011.lzma.bak
2024-06-15T13:30:43.342Z,1718458243.342 [DataOverHttps](INFO): SBD MOMSN=21235448
2024-06-15T13:30:48.181Z,1718458248.181 [Default:CheckIn:Read_Iridium] Stopped
2024-06-15T13:30:48.181Z,1718458248.181 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-15T13:30:48.181Z,1718458248.181 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-15T13:30:52.552Z,1718458252.552 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:31:07.498Z,1718458267.498 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:31:17.225Z,1718458277.225 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-06-15T13:31:17.225Z,1718458277.225 [ElevatorServo] No Fault, FailCount= 3
2024-06-15T13:31:21.708Z,1718458281.708 [ElevatorServo](FAULT): Overload Error
2024-06-15T13:31:21.708Z,1718458281.708 [ElevatorServo] Hardware Fault, FailCount= 1
2024-06-15T13:31:21.708Z,1718458281.708 [ElevatorServo](ERROR): Hardware Fault
2024-06-15T13:31:21.745Z,1718458281.745 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-06-15T13:31:22.106Z,1718458282.106 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-15T13:31:22.107Z,1718458282.107 [ElevatorServo](INFO): Powering down
2024-06-15T13:31:22.447Z,1718458282.447 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:31:22.900Z,1718458282.900 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:31:23.017Z,1718458283.017 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-15T13:31:23.022Z,1718458283.022 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-06-15T13:31:23.022Z,1718458283.022 [ElevatorServo] No Fault, FailCount= 1
2024-06-15T13:31:28.263Z,1718458288.263 [ElevatorServo](FAULT): Overload Error
2024-06-15T13:31:28.263Z,1718458288.263 [ElevatorServo] Hardware Fault, FailCount= 2
2024-06-15T13:31:28.263Z,1718458288.263 [ElevatorServo](ERROR): Hardware Fault
2024-06-15T13:31:28.297Z,1718458288.297 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-06-15T13:31:28.539Z,1718458288.539 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-15T13:31:28.539Z,1718458288.539 [ElevatorServo](INFO): Powering down
2024-06-15T13:31:29.333Z,1718458289.333 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:31:29.455Z,1718458289.455 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-15T13:31:29.461Z,1718458289.461 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-06-15T13:31:29.461Z,1718458289.461 [ElevatorServo] No Fault, FailCount= 2
2024-06-15T13:31:34.612Z,1718458294.612 [ElevatorServo](FAULT): Overload Error
2024-06-15T13:31:34.612Z,1718458294.612 [ElevatorServo] Hardware Fault, FailCount= 3
2024-06-15T13:31:34.612Z,1718458294.612 [ElevatorServo](ERROR): Hardware Fault
2024-06-15T13:31:34.640Z,1718458294.640 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-06-15T13:31:34.641Z,1718458294.641 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2024-06-15T13:31:34.997Z,1718458294.997 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-15T13:31:34.997Z,1718458294.997 [ElevatorServo](INFO): Powering down
2024-06-15T13:31:35.000Z,1718458295.000 [CBIT](INFO): Critical error at 20240615T133134
2024-06-15T13:31:35.796Z,1718458295.796 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:31:35.917Z,1718458295.917 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-15T13:31:37.394Z,1718458297.394 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:31:39.419Z,1718458299.419 [NAL9602](INFO): SBD MO Status=2, MOMSN=21534, MT Status=2, MTMSN=0
2024-06-15T13:31:39.419Z,1718458299.419 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-15T13:31:51.938Z,1718458311.938 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:32:06.887Z,1718458326.887 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:32:21.834Z,1718458341.834 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:32:37.207Z,1718458357.207 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:32:52.134Z,1718458372.134 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:33:07.090Z,1718458387.090 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:33:20.818Z,1718458400.818 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:33:34.958Z,1718458414.958 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:33:48.694Z,1718458428.694 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:34:02.835Z,1718458442.835 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:34:16.578Z,1718458456.578 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:34:26.270Z,1718458466.270 [NAL9602](INFO): SBD MO Status=0, MOMSN=21534, MT Status=0, MTMSN=0
2024-06-15T13:34:26.270Z,1718458466.270 [NAL9602](INFO): No messages in MT queue
2024-06-15T13:34:30.307Z,1718458470.307 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:34:44.042Z,1718458484.042 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:34:56.988Z,1718458496.988 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-15T13:34:58.184Z,1718458498.184 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:35:12.332Z,1718458512.332 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:35:26.061Z,1718458526.061 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:35:48.694Z,1718458548.694 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-15T13:35:48.694Z,1718458548.694 [Default:CheckIn:C.Wait] Stopped
2024-06-15T13:35:48.694Z,1718458548.694 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T13:35:48.695Z,1718458548.695 [Default:CheckIn:D] Running Loop=1
2024-06-15T13:35:49.107Z,1718458549.107 [Default:CheckIn:D] Stopped
2024-06-15T13:35:49.107Z,1718458549.107 [Default:CheckIn:E] Running Loop=1
2024-06-15T13:35:49.515Z,1718458549.515 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.757910 min
2024-06-15T13:35:49.515Z,1718458549.515 [Default:CheckIn:E] Stopped
2024-06-15T13:35:49.515Z,1718458549.515 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-15T13:35:49.515Z,1718458549.515 [Default:CheckIn] Stopped
2024-06-15T13:35:49.515Z,1718458549.515 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-15T13:35:49.515Z,1718458549.515 [Default:CheckIn](INFO): Running loop #4
2024-06-15T13:35:49.515Z,1718458549.515 [Default:CheckIn] Running Loop=4
2024-06-15T13:35:49.516Z,1718458549.516 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T13:35:49.516Z,1718458549.516 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T13:35:51.515Z,1718458551.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133550.00,A,5528.51886,N,00825.47184,E,0.097,78.25,150624,,,D*51
2024-06-15T13:35:51.517Z,1718458551.517 [NAL9602](INFO): GPS fix at 20240615T133550: (55.475314, 8.424531)
2024-06-15T13:35:51.562Z,1718458551.562 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T13:35:51.562Z,1718458551.562 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T13:35:55.460Z,1718458555.460 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20240615T131319/Courier0013.lzma
2024-06-15T13:35:57.470Z,1718458557.470 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0013.lzma.bak
2024-06-15T13:35:57.470Z,1718458557.470 [DataOverHttps](INFO): SBD MOMSN=21235462
2024-06-15T13:36:22.228Z,1718458582.228 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20240615T131319/Express0014.lzma
2024-06-15T13:36:23.230Z,1718458583.230 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0014.lzma.bak
2024-06-15T13:36:23.230Z,1718458583.230 [DataOverHttps](INFO): SBD MOMSN=21235466
2024-06-15T13:36:28.371Z,1718458588.371 [Default:CheckIn:Read_Iridium] Stopped
2024-06-15T13:36:28.371Z,1718458588.371 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-15T13:36:28.371Z,1718458588.371 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-15T13:36:43.779Z,1718458603.779 [NAL9602](INFO): SBD MO Status=2, MOMSN=21535, MT Status=2, MTMSN=0
2024-06-15T13:36:43.779Z,1718458603.779 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-15T13:39:18.035Z,1718458758.035 [NAL9602](INFO): SBD MO Status=2, MOMSN=21535, MT Status=2, MTMSN=0
2024-06-15T13:39:18.035Z,1718458758.035 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-15T13:39:39.865Z,1718458779.865 [NAL9602](INFO): SBD MO Status=2, MOMSN=21535, MT Status=2, MTMSN=0
2024-06-15T13:39:39.865Z,1718458779.865 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-15T13:40:04.100Z,1718458804.100 [NAL9602](INFO): SBD MO Status=0, MOMSN=21535, MT Status=0, MTMSN=0
2024-06-15T13:40:04.100Z,1718458804.100 [NAL9602](INFO): No messages in MT queue
2024-06-15T13:40:20.246Z,1718458820.246 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:40:25.903Z,1718458825.903 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:40:34.797Z,1718458834.797 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-15T13:40:40.851Z,1718458840.851 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:40:55.799Z,1718458855.799 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:41:10.746Z,1718458870.746 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:41:25.290Z,1718458885.290 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:41:28.938Z,1718458888.938 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-15T13:41:28.938Z,1718458888.938 [Default:CheckIn:C.Wait] Stopped
2024-06-15T13:41:28.938Z,1718458888.938 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T13:41:28.939Z,1718458888.939 [Default:CheckIn:D] Running Loop=1
2024-06-15T13:41:29.399Z,1718458889.399 [Default:CheckIn:D] Stopped
2024-06-15T13:41:29.399Z,1718458889.399 [Default:CheckIn:E] Running Loop=1
2024-06-15T13:41:29.765Z,1718458889.765 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.429435 min
2024-06-15T13:41:29.765Z,1718458889.765 [Default:CheckIn:E] Stopped
2024-06-15T13:41:29.765Z,1718458889.765 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-15T13:41:29.765Z,1718458889.765 [Default:CheckIn] Stopped
2024-06-15T13:41:29.765Z,1718458889.765 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-15T13:41:29.765Z,1718458889.765 [Default:CheckIn](INFO): Running loop #5
2024-06-15T13:41:29.765Z,1718458889.765 [Default:CheckIn] Running Loop=5
2024-06-15T13:41:29.765Z,1718458889.765 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T13:41:29.766Z,1718458889.766 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T13:41:31.763Z,1718458891.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,134130.00,A,5528.51929,N,00825.48549,E,0.175,163.95,150624,,,D*67
2024-06-15T13:41:31.765Z,1718458891.765 [NAL9602](INFO): GPS fix at 20240615T134130: (55.475321, 8.424758)
2024-06-15T13:41:31.835Z,1718458891.835 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T13:41:31.835Z,1718458891.835 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T13:41:34.898Z,1718458894.898 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240615T131319/Courier0016.lzma
2024-06-15T13:41:35.906Z,1718458895.906 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0016.lzma.bak
2024-06-15T13:41:35.906Z,1718458895.906 [DataOverHttps](INFO): SBD MOMSN=21235563
2024-06-15T13:41:40.315Z,1718458900.315 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:41:55.262Z,1718458915.262 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:41:59.869Z,1718458919.869 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240615T131319/Express0017.lzma
2024-06-15T13:42:00.870Z,1718458920.870 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0017.lzma.bak
2024-06-15T13:42:00.870Z,1718458920.870 [DataOverHttps](INFO): SBD MOMSN=21235566
2024-06-15T13:42:05.801Z,1718458925.801 [Default:CheckIn:Read_Iridium] Stopped
2024-06-15T13:42:05.801Z,1718458925.801 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-15T13:42:05.801Z,1718458925.801 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-15T13:42:10.212Z,1718458930.212 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:42:25.562Z,1718458945.562 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:42:40.511Z,1718458960.511 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:42:55.054Z,1718458975.054 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:43:09.195Z,1718458989.195 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:43:22.930Z,1718459002.930 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:43:37.071Z,1718459017.071 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:43:50.806Z,1718459030.806 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:44:04.946Z,1718459044.946 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:44:18.683Z,1718459058.683 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-15T13:44:32.463Z,1718459072.463 [BPC1](INFO): Calculating totals. Valid battery stick count: 25. Valid reserve battery stick count: 3.
2024-06-15T13:44:32.474Z,1718459072.474 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-06-15T13:46:09.803Z,1718459169.803 [DVL_micro](ERROR): Failed to parse:
:SA,-01.86,+10.49,011.6
2024-06-15T13:46:34.881Z,1718459194.881 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-06-15T13:46:34.881Z,1718459194.881 [ElevatorServo] No Fault, FailCount= 3
2024-06-15T13:46:39.352Z,1718459199.352 [ElevatorServo](FAULT): Overload Error
2024-06-15T13:46:39.352Z,1718459199.352 [ElevatorServo] Hardware Fault, FailCount= 1
2024-06-15T13:46:39.352Z,1718459199.352 [ElevatorServo](ERROR): Hardware Fault
2024-06-15T13:46:39.382Z,1718459199.382 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-06-15T13:46:39.701Z,1718459199.701 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-15T13:46:39.701Z,1718459199.701 [ElevatorServo](INFO): Powering down
2024-06-15T13:46:40.505Z,1718459200.505 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:46:40.621Z,1718459200.621 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-15T13:46:40.626Z,1718459200.626 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-06-15T13:46:40.626Z,1718459200.626 [ElevatorServo] No Fault, FailCount= 1
2024-06-15T13:46:40.893Z,1718459200.893 [NAL9602](INFO): SBD MO Status=2, MOMSN=21536, MT Status=2, MTMSN=0
2024-06-15T13:46:40.893Z,1718459200.893 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-15T13:46:41.302Z,1718459201.302 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-06-15T13:46:45.792Z,1718459205.792 [ElevatorServo](FAULT): Overload Error
2024-06-15T13:46:45.792Z,1718459205.792 [ElevatorServo] Hardware Fault, FailCount= 2
2024-06-15T13:46:45.792Z,1718459205.792 [ElevatorServo](ERROR): Hardware Fault
2024-06-15T13:46:45.820Z,1718459205.820 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-06-15T13:46:46.180Z,1718459206.180 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-15T13:46:46.180Z,1718459206.180 [ElevatorServo](INFO): Powering down
2024-06-15T13:46:47.054Z,1718459207.054 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-15T13:46:47.173Z,1718459207.173 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-15T13:46:47.177Z,1718459207.177 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-06-15T13:46:47.177Z,1718459207.177 [ElevatorServo] No Fault, FailCount= 2
2024-06-15T13:47:06.393Z,1718459226.393 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-15T13:47:06.393Z,1718459226.393 [Default:CheckIn:C.Wait] Stopped
2024-06-15T13:47:06.393Z,1718459226.393 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T13:47:06.393Z,1718459226.393 [Default:CheckIn:D] Running Loop=1
2024-06-15T13:47:06.763Z,1718459226.763 [Default:CheckIn:D] Stopped
2024-06-15T13:47:06.763Z,1718459226.763 [Default:CheckIn:E] Running Loop=1
2024-06-15T13:47:07.165Z,1718459227.165 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.052179 min
2024-06-15T13:47:07.165Z,1718459227.165 [Default:CheckIn:E] Stopped
2024-06-15T13:47:07.165Z,1718459227.165 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-15T13:47:07.165Z,1718459227.165 [Default:CheckIn] Stopped
2024-06-15T13:47:07.165Z,1718459227.165 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-15T13:47:07.165Z,1718459227.165 [Default:CheckIn](INFO): Running loop #6
2024-06-15T13:47:07.166Z,1718459227.166 [Default:CheckIn] Running Loop=6
2024-06-15T13:47:07.166Z,1718459227.166 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T13:47:07.166Z,1718459227.166 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T13:47:09.191Z,1718459229.191 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,134708.00,A,5528.51713,N,00825.47886,E,0.039,163.95,150624,,,D*65
2024-06-15T13:47:09.193Z,1718459229.193 [NAL9602](INFO): GPS fix at 20240615T134708: (55.475285, 8.424648)
2024-06-15T13:47:09.219Z,1718459229.219 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T13:47:09.219Z,1718459229.219 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T13:47:14.427Z,1718459234.427 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20240615T131319/Courier0019.lzma
2024-06-15T13:47:15.434Z,1718459235.434 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0019.lzma.bak
2024-06-15T13:47:15.434Z,1718459235.434 [DataOverHttps](INFO): SBD MOMSN=21235576
2024-06-15T13:47:39.880Z,1718459259.880 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20240615T131319/Express0020.lzma
2024-06-15T13:47:41.720Z,1718459261.720 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-15T13:47:41.886Z,1718459261.886 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0020.lzma.bak
2024-06-15T13:47:41.886Z,1718459261.886 [DataOverHttps](INFO): SBD MOMSN=21235583
2024-06-15T13:47:46.996Z,1718459266.996 [Default:CheckIn:Read_Iridium] Stopped
2024-06-15T13:47:46.997Z,1718459266.997 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-15T13:47:46.997Z,1718459266.997 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-15T13:52:39.623Z,1718459559.623 [DVL_micro](ERROR): Failed to parse::SA,+00.24,+08.70,359.6
2024-06-15T13:52:47.683Z,1718459567.683 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-15T13:52:47.684Z,1718459567.684 [Default:CheckIn:C.Wait] Stopped
2024-06-15T13:52:47.684Z,1718459567.684 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T13:52:47.684Z,1718459567.684 [Default:CheckIn:D] Running Loop=1
2024-06-15T13:52:48.088Z,1718459568.088 [Default:CheckIn:D] Stopped
2024-06-15T13:52:48.088Z,1718459568.088 [Default:CheckIn:E] Running Loop=1
2024-06-15T13:52:48.498Z,1718459568.498 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.740930 min
2024-06-15T13:52:48.498Z,1718459568.498 [Default:CheckIn:E] Stopped
2024-06-15T13:52:48.498Z,1718459568.498 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-15T13:52:48.498Z,1718459568.498 [Default:CheckIn] Stopped
2024-06-15T13:52:48.498Z,1718459568.498 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-15T13:52:48.498Z,1718459568.498 [Default:CheckIn](INFO): Running loop #7
2024-06-15T13:52:48.499Z,1718459568.499 [Default:CheckIn] Running Loop=7
2024-06-15T13:52:48.499Z,1718459568.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T13:52:48.499Z,1718459568.499 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T13:52:50.543Z,1718459570.543 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135249.00,A,5528.54450,N,00825.49906,E,12.168,330.03,150624,,,D*5F
2024-06-15T13:52:50.575Z,1718459570.575 [NAL9602](INFO): GPS fix at 20240615T135249: (55.475742, 8.424984)
2024-06-15T13:52:50.626Z,1718459570.626 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T13:52:50.626Z,1718459570.626 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T13:53:06.848Z,1718459586.848 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240615T131319/Courier0022.lzma
2024-06-15T13:53:07.854Z,1718459587.854 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0022.lzma.bak
2024-06-15T13:53:07.854Z,1718459587.854 [DataOverHttps](INFO): SBD MOMSN=21235682
2024-06-15T13:53:14.311Z,1718459594.311 [NAL9602](INFO): SBD MO Status=2, MOMSN=21536, MT Status=2, MTMSN=0
2024-06-15T13:53:14.332Z,1718459594.332 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-15T13:53:25.851Z,1718459605.851 [NAL9602](INFO): SBD MO Status=0, MOMSN=21536, MT Status=0, MTMSN=0
2024-06-15T13:53:25.851Z,1718459605.851 [NAL9602](INFO): No messages in MT queue
2024-06-15T13:53:43.912Z,1718459623.912 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240615T131319/Express0023.lzma
2024-06-15T13:53:44.914Z,1718459624.914 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0023.lzma.bak
2024-06-15T13:53:44.914Z,1718459624.914 [DataOverHttps](INFO): SBD MOMSN=21235686
2024-06-15T13:53:56.377Z,1718459636.377 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-15T13:54:03.793Z,1718459643.793 [Default:CheckIn:Read_Iridium] Stopped
2024-06-15T13:54:03.793Z,1718459643.793 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-15T13:54:03.793Z,1718459643.793 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-15T13:56:30.691Z,1718459790.691 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=66.961960
2024-06-15T13:57:17.436Z,1718459837.436 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.007172
2024-06-15T13:59:04.276Z,1718459944.276 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-15T13:59:04.276Z,1718459944.276 [Default:CheckIn:C.Wait] Stopped
2024-06-15T13:59:04.276Z,1718459944.276 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T13:59:04.276Z,1718459944.276 [Default:CheckIn:D] Running Loop=1
2024-06-15T13:59:04.646Z,1718459944.646 [Default:CheckIn:D] Stopped
2024-06-15T13:59:04.646Z,1718459944.646 [Default:CheckIn:E] Running Loop=1
2024-06-15T13:59:05.092Z,1718459945.092 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.016886 min
2024-06-15T13:59:05.092Z,1718459945.092 [Default:CheckIn:E] Stopped
2024-06-15T13:59:05.092Z,1718459945.092 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-15T13:59:05.092Z,1718459945.092 [Default:CheckIn] Stopped
2024-06-15T13:59:05.092Z,1718459945.092 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-15T13:59:05.092Z,1718459945.092 [Default:CheckIn](INFO): Running loop #8
2024-06-15T13:59:05.093Z,1718459945.093 [Default:CheckIn] Running Loop=8
2024-06-15T13:59:05.093Z,1718459945.093 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T13:59:05.093Z,1718459945.093 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T13:59:07.139Z,1718459947.139 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135905.00,A,5530.15651,N,00823.27667,E,21.130,322.98,150624,,,D*59
2024-06-15T13:59:07.162Z,1718459947.162 [NAL9602](INFO): GPS fix at 20240615T135905: (55.502609, 8.387944)
2024-06-15T13:59:07.197Z,1718459947.197 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T13:59:07.197Z,1718459947.197 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T13:59:53.335Z,1718459993.335 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240615T131319/Courier0025.lzma
2024-06-15T13:59:54.339Z,1718459994.339 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0025.lzma.bak
2024-06-15T13:59:54.339Z,1718459994.339 [DataOverHttps](INFO): SBD MOMSN=21235742
2024-06-15T14:00:58.840Z,1718460058.840 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240615T131319/Express0026.lzma
2024-06-15T14:00:59.847Z,1718460059.847 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0026.lzma.bak
2024-06-15T14:00:59.847Z,1718460059.847 [DataOverHttps](INFO): SBD MOMSN=21235745
2024-06-15T14:01:12.032Z,1718460072.032 [Default:CheckIn:Read_Iridium] Stopped
2024-06-15T14:01:12.032Z,1718460072.032 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-15T14:01:12.032Z,1718460072.032 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-15T14:01:35.227Z,1718460095.227 [NAL9602](INFO): SBD MO Status=2, MOMSN=21537, MT Status=2, MTMSN=0
2024-06-15T14:01:35.227Z,1718460095.227 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-15T14:01:47.579Z,1718460107.579 [NAL9602](INFO): SBD MO Status=0, MOMSN=21537, MT Status=0, MTMSN=0
2024-06-15T14:01:47.579Z,1718460107.579 [NAL9602](INFO): No messages in MT queue
2024-06-15T14:02:18.463Z,1718460138.463 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-15T14:06:12.557Z,1718460372.557 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-15T14:06:12.557Z,1718460372.557 [Default:CheckIn:C.Wait] Stopped
2024-06-15T14:06:12.558Z,1718460372.558 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T14:06:12.558Z,1718460372.558 [Default:CheckIn:D] Running Loop=1
2024-06-15T14:06:12.884Z,1718460372.884 [Default:CheckIn:D] Stopped
2024-06-15T14:06:12.884Z,1718460372.884 [Default:CheckIn:E] Running Loop=1
2024-06-15T14:06:13.255Z,1718460373.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.154199 min
2024-06-15T14:06:13.255Z,1718460373.255 [Default:CheckIn:E] Stopped
2024-06-15T14:06:13.255Z,1718460373.255 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-15T14:06:13.255Z,1718460373.255 [Default:CheckIn] Stopped
2024-06-15T14:06:13.256Z,1718460373.256 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-15T14:06:13.256Z,1718460373.256 [Default:CheckIn](INFO): Running loop #9
2024-06-15T14:06:13.256Z,1718460373.256 [Default:CheckIn] Running Loop=9
2024-06-15T14:06:13.256Z,1718460373.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T14:06:13.256Z,1718460373.256 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T14:06:15.312Z,1718460375.312 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,140614.00,A,5532.15660,N,00821.45641,E,14.793,296.72,150624,,,A*50
2024-06-15T14:06:15.323Z,1718460375.323 [NAL9602](INFO): GPS fix at 20240615T140614: (55.535943, 8.357607)
2024-06-15T14:06:15.399Z,1718460375.399 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T14:06:15.399Z,1718460375.399 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T14:06:30.288Z,1718460390.288 [NAL9602](INFO): SBD MO Status=2, MOMSN=21538, MT Status=2, MTMSN=0
2024-06-15T14:06:30.288Z,1718460390.288 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-15T14:06:47.095Z,1718460407.095 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240615T131319/Courier0028.lzma
2024-06-15T14:06:48.057Z,1718460408.057 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0028.lzma.bak
2024-06-15T14:06:48.057Z,1718460408.057 [DataOverHttps](INFO): SBD MOMSN=21235778
2024-06-15T14:08:08.515Z,1718460488.515 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240615T131319/Express0029.lzma
2024-06-15T14:08:09.518Z,1718460489.518 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0029.lzma.bak
2024-06-15T14:08:09.518Z,1718460489.518 [DataOverHttps](INFO): SBD MOMSN=21235787
2024-06-15T14:08:20.866Z,1718460500.866 [Default:CheckIn:Read_Iridium] Stopped
2024-06-15T14:08:20.866Z,1718460500.866 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-15T14:08:20.866Z,1718460500.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-15T14:09:11.101Z,1718460551.101 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=61.584499
2024-06-15T14:09:32.963Z,1718460572.963 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003000
2024-06-15T14:11:26.194Z,1718460686.194 [NAL9602](INFO): SBD MO Status=0, MOMSN=21538, MT Status=0, MTMSN=0
2024-06-15T14:11:26.194Z,1718460686.194 [NAL9602](INFO): No messages in MT queue
2024-06-15T14:11:50.646Z,1718460710.646 [DVL_micro](ERROR): Failed to parse:
:SA,-03.14,+08.88,351.1
2024-06-15T14:11:56.732Z,1718460716.732 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-15T14:13:21.262Z,1718460801.262 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-15T14:13:21.262Z,1718460801.262 [Default:CheckIn:C.Wait] Stopped
2024-06-15T14:13:21.262Z,1718460801.262 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T14:13:21.262Z,1718460801.262 [Default:CheckIn:D] Running Loop=1
2024-06-15T14:13:21.658Z,1718460801.658 [Default:CheckIn:D] Stopped
2024-06-15T14:13:21.683Z,1718460801.683 [Default:CheckIn:E] Running Loop=1
2024-06-15T14:13:21.969Z,1718460801.969 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.300431 min
2024-06-15T14:13:21.969Z,1718460801.969 [Default:CheckIn:E] Stopped
2024-06-15T14:13:21.969Z,1718460801.969 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-15T14:13:21.969Z,1718460801.969 [Default:CheckIn] Stopped
2024-06-15T14:13:21.969Z,1718460801.969 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-15T14:13:21.969Z,1718460801.969 [Default:CheckIn](INFO): Running loop #10
2024-06-15T14:13:21.969Z,1718460801.969 [Default:CheckIn] Running Loop=10
2024-06-15T14:13:21.970Z,1718460801.970 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T14:13:21.970Z,1718460801.970 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T14:13:24.038Z,1718460804.038 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141323.00,A,5532.24795,N,00821.44636,E,0.039,109.02,150624,,,A*68
2024-06-15T14:13:24.068Z,1718460804.068 [NAL9602](INFO): GPS fix at 20240615T141323: (55.537466, 8.357439)
2024-06-15T14:13:24.180Z,1718460804.180 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T14:13:24.180Z,1718460804.180 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T14:13:44.390Z,1718460824.390 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240615T131319/Courier0031.lzma
2024-06-15T14:13:45.552Z,1718460825.552 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Courier0031.lzma.bak
2024-06-15T14:13:45.552Z,1718460825.552 [DataOverHttps](INFO): SBD MOMSN=21235842
2024-06-15T14:14:20.036Z,1718460860.036 [NAL9602](INFO): SBD MO Status=0, MOMSN=21539, MT Status=0, MTMSN=0
2024-06-15T14:14:20.036Z,1718460860.036 [NAL9602](INFO): No messages in MT queue
2024-06-15T14:14:50.524Z,1718460890.524 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-15T14:14:55.375Z,1718460895.375 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240615T131319/Express0032.lzma
2024-06-15T14:14:56.385Z,1718460896.385 [DataOverHttps](INFO): Moved sent file to Logs/20240615T131319/Express0032.lzma.bak
2024-06-15T14:14:56.385Z,1718460896.385 [DataOverHttps](INFO): SBD MOMSN=21235846
2024-06-15T14:15:14.855Z,1718460914.855 [Default:CheckIn:Read_Iridium] Stopped
2024-06-15T14:15:14.855Z,1718460914.855 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-15T14:15:14.855Z,1718460914.855 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-15T14:16:00.473Z,1718460960.473 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=64.088371
2024-06-15T14:16:44.720Z,1718461004.720 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004227
2024-06-15T14:20:15.629Z,1718461215.629 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-15T14:20:15.629Z,1718461215.629 [Default:CheckIn:C.Wait] Stopped
2024-06-15T14:20:15.629Z,1718461215.629 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-15T14:20:15.629Z,1718461215.629 [Default:CheckIn:D] Running Loop=1
2024-06-15T14:20:16.288Z,1718461216.288 [Default:CheckIn:D] Stopped
2024-06-15T14:20:16.288Z,1718461216.288 [Default:CheckIn:E] Running Loop=1
2024-06-15T14:20:16.553Z,1718461216.553 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.210921 min
2024-06-15T14:20:16.553Z,1718461216.553 [Default:CheckIn:E] Stopped
2024-06-15T14:20:16.553Z,1718461216.553 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-15T14:20:16.553Z,1718461216.553 [Default:CheckIn] Stopped
2024-06-15T14:20:16.553Z,1718461216.553 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-15T14:20:16.553Z,1718461216.553 [Default:CheckIn](INFO): Running loop #11
2024-06-15T14:20:16.553Z,1718461216.553 [Default:CheckIn] Running Loop=11
2024-06-15T14:20:16.553Z,1718461216.553 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-15T14:20:16.554Z,1718461216.554 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-15T14:20:18.378Z,1718461218.378 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142017.00,A,5532.24638,N,00821.44626,E,0.078,109.02,150624,,,A*6D
2024-06-15T14:20:18.380Z,1718461218.380 [NAL9602](INFO): GPS fix at 20240615T142017: (55.537440, 8.357438)
2024-06-15T14:20:18.505Z,1718461218.505 [Default:CheckIn:Read_GPS] Stopped
2024-06-15T14:20:18.505Z,1718461218.505 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-15T14:20:27.418Z,1718461227.418 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240615T131319/Courier0034.lzma
2024-06-15T14:20:27.424Z,1718461227.424 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-06-15T14:20:40.707Z,1718461240.707 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=62.461948
2024-06-15T14:20:54.373Z,1718461254.373 [NAL9602](INFO): SBD MO Status=0, MOMSN=21540, MT Status=0, MTMSN=0
2024-06-15T14:20:54.373Z,1718461254.373 [NAL9602](INFO): No messages in MT queue
2024-06-15T14:21:02.663Z,1718461262.663 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003038
2024-06-15T14:21:15.667Z,1718461275.667 [NAL9602](INFO): SBD MO Status=1, MOMSN=21541, MT Status=0, MTMSN=0
2024-06-15T14:21:15.723Z,1718461275.723 [NAL9602](INFO): Sent 72 bytes from file Logs/20240615T131319/Courier0034.lzma