2024-02-06T23:45:54.823Z,1707263154.823 [Supervisor](DEBUG): Initializing supervisor. 2024-02-06T23:45:54.828Z,1707263154.828 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-02-06T23:45:54.829Z,1707263154.829 [SyncHandler](INFO): Protected caller Thread ID is 992 2024-02-06T23:45:54.829Z,1707263154.829 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-02-06T23:45:54.830Z,1707263154.830 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-02-06T23:45:54.831Z,1707263154.831 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 993 2024-02-06T23:45:54.835Z,1707263154.835 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-02-06T23:45:54.854Z,1707263154.854 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-02-06T23:45:54.855Z,1707263154.855 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-02-06T23:45:54.855Z,1707263154.855 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 994 2024-02-06T23:45:54.859Z,1707263154.859 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-02-06T23:45:54.860Z,1707263154.860 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-02-06T23:45:54.861Z,1707263154.861 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 995 2024-02-06T23:45:54.863Z,1707263154.863 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-02-06T23:45:54.864Z,1707263154.864 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-02-06T23:45:54.864Z,1707263154.864 [logger ThreadHandler](INFO): Protected caller Thread ID is 996 2024-02-06T23:45:54.868Z,1707263154.868 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-02-06T23:45:54.869Z,1707263154.869 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-02-06T23:45:54.870Z,1707263154.870 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-02-06T23:45:55.078Z,1707263155.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-02-06T23:45:55.079Z,1707263155.079 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-02-06T23:45:55.186Z,1707263155.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-02-06T23:45:55.186Z,1707263155.186 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-02-06T23:45:55.281Z,1707263155.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-02-06T23:45:55.282Z,1707263155.282 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-02-06T23:45:55.755Z,1707263155.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-02-06T23:45:55.755Z,1707263155.755 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-02-06T23:45:56.113Z,1707263156.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-02-06T23:45:56.113Z,1707263156.113 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-02-06T23:45:56.443Z,1707263156.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-02-06T23:45:56.444Z,1707263156.444 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-02-06T23:45:56.635Z,1707263156.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-02-06T23:45:56.635Z,1707263156.635 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-02-06T23:45:56.714Z,1707263156.714 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-02-06T23:45:56.795Z,1707263156.795 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-02-06T23:45:57.205Z,1707263157.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-02-06T23:45:57.206Z,1707263157.206 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-02-06T23:45:57.485Z,1707263157.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-02-06T23:45:57.485Z,1707263157.485 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-02-06T23:45:57.993Z,1707263157.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-02-06T23:45:57.993Z,1707263157.993 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-02-06T23:45:58.214Z,1707263158.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-02-06T23:45:58.215Z,1707263158.215 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-02-06T23:45:58.455Z,1707263158.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-02-06T23:45:58.455Z,1707263158.455 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-02-06T23:45:58.595Z,1707263158.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-02-06T23:45:58.596Z,1707263158.596 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-02-06T23:45:59.866Z,1707263159.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-02-06T23:45:59.868Z,1707263159.868 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2024-02-06T23:45:59.869Z,1707263159.869 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2024-02-06T23:46:00.069Z,1707263160.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2024-02-06T23:46:00.325Z,1707263160.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2024-02-06T23:46:00.600Z,1707263160.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-02-06T23:46:00.601Z,1707263160.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2024-02-06T23:46:00.722Z,1707263160.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2024-02-06T23:46:00.822Z,1707263160.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2024-02-06T23:46:00.914Z,1707263160.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2024-02-06T23:46:01.021Z,1707263161.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2024-02-06T23:46:01.114Z,1707263161.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2024-02-06T23:46:01.199Z,1707263161.199 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2024-02-06T23:46:01.298Z,1707263161.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/workSite.cfg 2024-02-06T23:46:01.380Z,1707263161.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2024-02-06T23:46:01.542Z,1707263161.542 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2024-02-06T23:46:01.543Z,1707263161.543 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-02-06T23:46:01.547Z,1707263161.547 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-02-06T23:46:01.599Z,1707263161.599 [DepthRateCalculator] Loaded 2024-02-06T23:46:01.599Z,1707263161.599 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-02-06T23:46:01.605Z,1707263161.605 [PitchRateCalculator] Loaded 2024-02-06T23:46:01.605Z,1707263161.605 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-02-06T23:46:01.616Z,1707263161.616 [SpeedCalculator] Loaded 2024-02-06T23:46:01.616Z,1707263161.616 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-02-06T23:46:01.621Z,1707263161.621 [YawRateCalculator] Loaded 2024-02-06T23:46:01.622Z,1707263161.622 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-02-06T23:46:01.644Z,1707263161.644 [ElevatorOffsetCalculator] Loaded 2024-02-06T23:46:01.644Z,1707263161.644 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-02-06T23:46:01.645Z,1707263161.645 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-02-06T23:46:01.645Z,1707263161.645 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-02-06T23:46:01.671Z,1707263161.671 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-02-06T23:46:01.672Z,1707263161.672 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-02-06T23:46:01.682Z,1707263161.682 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-02-06T23:46:01.682Z,1707263161.682 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-02-06T23:46:02.133Z,1707263162.133 [AHRS_M2] Loaded 2024-02-06T23:46:02.134Z,1707263162.134 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-02-06T23:46:02.169Z,1707263162.169 [BackseatComponent] Loaded 2024-02-06T23:46:02.169Z,1707263162.169 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-02-06T23:46:02.171Z,1707263162.171 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084F4E0 2024-02-06T23:46:02.171Z,1707263162.171 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1081 2024-02-06T23:46:02.174Z,1707263162.174 [LcmUniversalReporter] Loaded 2024-02-06T23:46:02.174Z,1707263162.174 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-02-06T23:46:02.981Z,1707263162.981 [BPC1] Loaded 2024-02-06T23:46:02.981Z,1707263162.981 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-02-06T23:46:03.056Z,1707263163.056 [DataOverHttps] Loaded 2024-02-06T23:46:03.056Z,1707263163.056 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-02-06T23:46:03.057Z,1707263163.057 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087F4E0 2024-02-06T23:46:03.058Z,1707263163.058 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1082 2024-02-06T23:46:03.078Z,1707263163.078 [Depth_Keller] Loaded 2024-02-06T23:46:03.078Z,1707263163.078 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-02-06T23:46:03.083Z,1707263163.083 [DropWeight] Loaded 2024-02-06T23:46:03.084Z,1707263163.084 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-02-06T23:46:03.145Z,1707263163.145 [NAL9602] Loaded 2024-02-06T23:46:03.145Z,1707263163.145 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-02-06T23:46:03.175Z,1707263163.175 [Onboard] Loaded 2024-02-06T23:46:03.176Z,1707263163.176 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-02-06T23:46:03.177Z,1707263163.177 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AF4E0 2024-02-06T23:46:03.177Z,1707263163.177 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1083 2024-02-06T23:46:03.183Z,1707263163.183 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2024-02-06T23:46:03.194Z,1707263163.194 [PowerOnly] Loaded 2024-02-06T23:46:03.195Z,1707263163.195 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-02-06T23:46:03.206Z,1707263163.206 [Power24vConverter] Loaded 2024-02-06T23:46:03.207Z,1707263163.207 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-02-06T23:46:03.221Z,1707263163.221 [Radio_Surface] Loaded 2024-02-06T23:46:03.221Z,1707263163.221 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-02-06T23:46:03.222Z,1707263163.222 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DF4E0 2024-02-06T23:46:03.222Z,1707263163.222 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1084 2024-02-06T23:46:03.314Z,1707263163.314 [DAT] Loaded 2024-02-06T23:46:03.314Z,1707263163.314 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-02-06T23:46:03.315Z,1707263163.315 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0 2024-02-06T23:46:03.316Z,1707263163.316 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1085 2024-02-06T23:46:03.316Z,1707263163.316 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-02-06T23:46:03.317Z,1707263163.317 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-02-06T23:46:03.429Z,1707263163.429 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-02-06T23:46:03.429Z,1707263163.429 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-02-06T23:46:03.471Z,1707263163.471 [DeadReckonUsingSpeedCalculator] Loaded 2024-02-06T23:46:03.471Z,1707263163.471 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-02-06T23:46:03.485Z,1707263163.485 [NavChart] Loaded 2024-02-06T23:46:03.486Z,1707263163.486 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-02-06T23:46:03.491Z,1707263163.491 [UniversalFixResidualReporter] Loaded 2024-02-06T23:46:03.492Z,1707263163.492 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-02-06T23:46:03.492Z,1707263163.492 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-02-06T23:46:03.493Z,1707263163.493 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-02-06T23:46:03.633Z,1707263163.633 [SBIT](DEBUG): Construct Startup Built In Test. 2024-02-06T23:46:03.648Z,1707263163.648 [SBIT] Loaded 2024-02-06T23:46:03.648Z,1707263163.648 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-02-06T23:46:03.651Z,1707263163.651 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-02-06T23:46:03.664Z,1707263163.664 [IBIT] Loaded 2024-02-06T23:46:03.664Z,1707263163.664 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-02-06T23:46:03.670Z,1707263163.670 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-02-06T23:46:03.777Z,1707263163.777 [CBIT] Loaded 2024-02-06T23:46:03.777Z,1707263163.777 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-02-06T23:46:03.778Z,1707263163.778 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-02-06T23:46:03.778Z,1707263163.778 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-02-06T23:46:03.950Z,1707263163.950 [CTD_Seabird] Loaded 2024-02-06T23:46:03.951Z,1707263163.951 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-02-06T23:46:03.952Z,1707263163.952 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0 2024-02-06T23:46:03.952Z,1707263163.952 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1086 2024-02-06T23:46:03.973Z,1707263163.973 [PAR_Licor] Loaded 2024-02-06T23:46:03.973Z,1707263163.973 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-02-06T23:46:04.016Z,1707263164.016 [WetLabsBB2FL] Loaded 2024-02-06T23:46:04.016Z,1707263164.016 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-02-06T23:46:04.017Z,1707263164.017 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0 2024-02-06T23:46:04.018Z,1707263164.018 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1087 2024-02-06T23:46:04.018Z,1707263164.018 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-02-06T23:46:04.019Z,1707263164.019 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-02-06T23:46:04.153Z,1707263164.153 [BuoyancyServo] Loaded 2024-02-06T23:46:04.153Z,1707263164.153 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-02-06T23:46:04.175Z,1707263164.175 [ElevatorServo] Loaded 2024-02-06T23:46:04.175Z,1707263164.175 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-02-06T23:46:04.196Z,1707263164.196 [MassServo] Loaded 2024-02-06T23:46:04.197Z,1707263164.197 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-02-06T23:46:04.217Z,1707263164.217 [RudderServo] Loaded 2024-02-06T23:46:04.217Z,1707263164.217 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-02-06T23:46:04.234Z,1707263164.234 [ThrusterHE] Loaded 2024-02-06T23:46:04.234Z,1707263164.234 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-02-06T23:46:04.234Z,1707263164.234 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-02-06T23:46:04.235Z,1707263164.235 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-02-06T23:46:04.286Z,1707263164.286 [VerticalControl](DEBUG): Construct VerticalControl. 2024-02-06T23:46:04.338Z,1707263164.338 [VerticalControl] Loaded 2024-02-06T23:46:04.338Z,1707263164.338 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-02-06T23:46:04.341Z,1707263164.341 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-02-06T23:46:04.380Z,1707263164.380 [HorizontalControl] Loaded 2024-02-06T23:46:04.380Z,1707263164.380 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-02-06T23:46:04.383Z,1707263164.383 [SpeedControl](DEBUG): Construct SpeedControl. 2024-02-06T23:46:04.385Z,1707263164.385 [SpeedControl] Loaded 2024-02-06T23:46:04.385Z,1707263164.385 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-02-06T23:46:04.388Z,1707263164.388 [LoopControl](DEBUG): Construct LoopControl. 2024-02-06T23:46:04.388Z,1707263164.388 [LoopControl] Loaded 2024-02-06T23:46:04.389Z,1707263164.389 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-02-06T23:46:04.389Z,1707263164.389 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-02-06T23:46:04.390Z,1707263164.390 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-02-06T23:46:04.503Z,1707263164.503 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-02-06T23:46:04.503Z,1707263164.503 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-02-06T23:46:04.650Z,1707263164.650 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-02-06T23:46:04.651Z,1707263164.651 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-02-06T23:46:04.989Z,1707263164.989 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-02-06T23:46:04.990Z,1707263164.990 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-02-06T23:46:05.232Z,1707263165.232 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-02-06T23:46:05.278Z,1707263165.278 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-02-06T23:46:05.281Z,1707263165.281 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-02-06T23:46:05.310Z,1707263165.310 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-02-06T23:46:05.313Z,1707263165.313 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C114E0 2024-02-06T23:46:05.313Z,1707263165.313 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1088 2024-02-06T23:46:05.398Z,1707263165.398 [Supervisor](INFO): Main Thread ID is 829 2024-02-06T23:46:05.398Z,1707263165.398 [Supervisor](DEBUG): Running supervisor. 2024-02-06T23:46:05.399Z,1707263165.399 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1089 2024-02-06T23:46:05.399Z,1707263165.399 [CommandExec](INFO): Initializing the command executive. 2024-02-06T23:46:05.401Z,1707263165.401 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1090 2024-02-06T23:46:05.404Z,1707263165.404 [controlThread ThreadHandler](INFO): Handler Thread ID is 1091 2024-02-06T23:46:05.404Z,1707263165.404 [controlThread](DEBUG): Initializing ControlThread 2024-02-06T23:46:05.405Z,1707263165.405 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-02-06T23:46:05.405Z,1707263165.405 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-02-06T23:46:05.406Z,1707263165.406 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-02-06T23:46:05.406Z,1707263165.406 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-02-06T23:46:05.406Z,1707263165.406 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-02-06T23:46:05.414Z,1707263165.414 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-06T23:46:05.415Z,1707263165.415 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-02-06T23:46:05.415Z,1707263165.415 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-02-06T23:46:05.415Z,1707263165.415 [SBIT](INFO): Initialize SBIT Component. 2024-02-06T23:46:05.416Z,1707263165.416 [SBIT](IMPORTANT): git: 2024-02-06 2024-02-06T23:46:05.416Z,1707263165.416 [SBIT](INFO): git hash: 45759fb189c1cf26f5343b3c07f985a86883e7e8 2024-02-06T23:46:05.416Z,1707263165.416 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-02-06T23:46:05.418Z,1707263165.418 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-02-06T23:46:05.419Z,1707263165.419 [SBIT](INFO): Beginning SBIT in 35.000000 seconds. 2024-02-06T23:46:05.419Z,1707263165.419 [IBIT](INFO): Initialize IBIT Component. 2024-02-06T23:46:05.420Z,1707263165.420 [CBIT](DEBUG): Initialize CBIT Component. 2024-02-06T23:46:05.421Z,1707263165.421 [logger ThreadHandler](INFO): Handler Thread ID is 1092 2024-02-06T23:46:05.432Z,1707263165.432 [CBIT](DEBUG): Initialized mux pins. 2024-02-06T23:46:05.432Z,1707263165.432 [CBIT](DEBUG): Initializing the watchdog timer. 2024-02-06T23:46:05.440Z,1707263165.440 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1093 2024-02-06T23:46:05.452Z,1707263165.452 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1094 2024-02-06T23:46:05.454Z,1707263165.454 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-02-06T23:46:05.456Z,1707263165.456 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-02-06T23:46:05.456Z,1707263165.456 [CBIT](DEBUG): Initializing heartbeat. 2024-02-06T23:46:05.464Z,1707263165.464 [Onboard ThreadHandler](INFO): Handler Thread ID is 1095 2024-02-06T23:46:05.482Z,1707263165.482 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1096 2024-02-06T23:46:05.500Z,1707263165.500 [DAT ThreadHandler](INFO): Handler Thread ID is 1097 2024-02-06T23:46:05.501Z,1707263165.501 [DAT](INFO): Powering up 2024-02-06T23:46:05.501Z,1707263165.501 [DAT](DEBUG): Initializing DAT. 2024-02-06T23:46:05.506Z,1707263165.506 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1099 2024-02-06T23:46:05.506Z,1707263165.506 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-02-06T23:46:05.513Z,1707263165.513 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1101 2024-02-06T23:46:05.517Z,1707263165.517 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1103 2024-02-06T23:46:05.519Z,1707263165.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-02-06T23:46:05.519Z,1707263165.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-02-06T23:46:05.519Z,1707263165.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-02-06T23:46:05.519Z,1707263165.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-02-06T23:46:05.520Z,1707263165.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-02-06T23:46:05.520Z,1707263165.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-02-06T23:46:05.520Z,1707263165.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-02-06T23:46:05.520Z,1707263165.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-02-06T23:46:05.524Z,1707263165.524 [WetLabsBB2FL](INFO): Powering up 2024-02-06T23:46:05.528Z,1707263165.528 [CBIT](DEBUG): Deactivating GF circuits. 2024-02-06T23:46:05.528Z,1707263165.528 [CBIT](DEBUG): Deactivating emergency mode. 2024-02-06T23:46:05.568Z,1707263165.568 [CBIT](DEBUG): Backplane powered. 2024-02-06T23:46:05.573Z,1707263165.573 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-02-06T23:46:05.575Z,1707263165.575 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-02-06T23:46:05.576Z,1707263165.576 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-02-06T23:46:05.576Z,1707263165.576 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-02-06T23:46:05.577Z,1707263165.577 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-02-06T23:46:05.578Z,1707263165.578 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-02-06T23:46:05.578Z,1707263165.578 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-02-06T23:46:05.579Z,1707263165.579 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-02-06T23:46:05.630Z,1707263165.630 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-02-06T23:46:05.647Z,1707263165.647 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-06T23:46:05.670Z,1707263165.670 [MissionManager](DEBUG): 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-02-06T23:46:05.670Z,1707263165.670 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2024-02-06T23:46:05.721Z,1707263165.721 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-02-06T23:46:05.722Z,1707263165.722 [Default:A.Wait](DEBUG): Construct Wait. 2024-02-06T23:46:05.740Z,1707263165.740 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-06T23:46:05.775Z,1707263165.775 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-02-06T23:46:05.778Z,1707263165.778 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-02-06T23:46:05.796Z,1707263165.796 [Default:E.Execute](DEBUG): Construct Execute. 2024-02-06T23:46:05.799Z,1707263165.799 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-02-06T23:46:05.804Z,1707263165.804 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-02-06T23:46:05.810Z,1707263165.810 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-02-06T23:46:05.868Z,1707263165.868 [PowerOnly](INFO): Powering up loadControl 2024-02-06T23:46:05.884Z,1707263165.884 [Power24vConverter](INFO): Powering up. 2024-02-06T23:46:05.896Z,1707263165.896 [Radio_Surface](INFO): Powering up 2024-02-06T23:46:05.922Z,1707263165.922 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-02-06T23:46:05.928Z,1707263165.928 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-02-06T23:46:05.930Z,1707263165.930 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-02-06T23:46:05.936Z,1707263165.936 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-02-06T23:46:05.937Z,1707263165.937 [MassServo](DEBUG): Initializing EZServoServo. 2024-02-06T23:46:05.944Z,1707263165.944 [MassServo](DEBUG): Initializing MassServo. 2024-02-06T23:46:05.945Z,1707263165.945 [RudderServo](DEBUG): Initializing EZServoServo. 2024-02-06T23:46:05.952Z,1707263165.952 [RudderServo](DEBUG): Initializing RudderServo. 2024-02-06T23:46:05.953Z,1707263165.953 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-02-06T23:46:05.960Z,1707263165.960 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-02-06T23:46:08.128Z,1707263168.128 [WetLabsBB2FL](INFO): Powering down 2024-02-06T23:46:11.701Z,1707263171.701 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-02-06T23:46:12.537Z,1707263172.537 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-02-06T23:46:17.848Z,1707263177.848 [DAT](INFO): DAT read: 2024-02-06T23:46:17.850Z,1707263177.850 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-02-06T23:46:19.613Z,1707263179.613 [DAT](INFO): DAT read: MF Frequency Band 2024-02-06T23:46:19.614Z,1707263179.614 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-02-06T23:46:19.614Z,1707263179.614 [DAT](INFO): DAT read: Feb 6 2024 23:46:12 2024-02-06T23:46:20.621Z,1707263180.621 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-02-06T23:46:20.622Z,1707263180.622 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-02-06T23:46:20.622Z,1707263180.622 [DAT](INFO): commRate: 800 2024-02-06T23:46:22.688Z,1707263182.688 [DAT](INFO): entering command mode 2024-02-06T23:46:22.888Z,1707263182.888 [DAT](INFO): DAT read: 2024-02-06T23:46:22.889Z,1707263182.889 [DAT](INFO): DAT read: user:1> 2024-02-06T23:46:22.889Z,1707263182.889 [DAT](INFO): setting verbose to 3 2024-02-06T23:46:23.140Z,1707263183.140 [DAT](INFO): DAT read: user:1> 2024-02-06T23:46:23.141Z,1707263183.141 [DAT](INFO): DAT read: Verbose | 3 2024-02-06T23:46:23.142Z,1707263183.142 [DAT](INFO): set verbose to 3 2024-02-06T23:46:23.142Z,1707263183.142 [DAT](INFO): setting DatVerbose to 27440 2024-02-06T23:46:23.392Z,1707263183.392 [DAT](INFO): DAT read: user:2> 2024-02-06T23:46:23.393Z,1707263183.393 [DAT](INFO): DAT read: DatVerbose | 27440 2024-02-06T23:46:23.394Z,1707263183.394 [DAT](INFO): set DatVerbose to 27440 2024-02-06T23:46:23.394Z,1707263183.394 [DAT](INFO): setting transmit power to 8 2024-02-06T23:46:23.645Z,1707263183.645 [DAT](INFO): DAT read: user:3> 2024-02-06T23:46:23.646Z,1707263183.646 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-02-06T23:46:23.646Z,1707263183.646 [DAT](INFO): set transmit power to 8 2024-02-06T23:46:23.646Z,1707263183.646 [DAT](INFO): setting local address to 9 2024-02-06T23:46:23.896Z,1707263183.896 [DAT](INFO): DAT read: user:4> 2024-02-06T23:46:23.897Z,1707263183.897 [DAT](INFO): DAT read: LocalAddr | 9 2024-02-06T23:46:23.898Z,1707263183.898 [DAT](INFO): set local address to 9 2024-02-06T23:46:23.899Z,1707263183.899 [DAT](INFO): Setting time to: 23:46:23 And date to:2/6/2024 2024-02-06T23:46:24.148Z,1707263184.148 [DAT](INFO): DAT read: user:5> 2024-02-06T23:46:24.149Z,1707263184.149 [DAT](INFO): DAT read: Tue Feb 6, 2024 23:46:23 2024-02-06T23:46:24.150Z,1707263184.150 [DAT](INFO): Local DAT time set to Tue Feb 6, 2024 23:46:23 2024-02-06T23:46:33.293Z,1707263193.293 [NAL9602](INFO): Powering up NAL9602 2024-02-06T23:46:40.985Z,1707263200.985 [SBIT](IMPORTANT): Beginning Startup BIT 2024-02-06T23:46:40.989Z,1707263200.989 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-06T23:46:44.203Z,1707263204.203 [NAL9602](INFO): NAL9602 initialized 2024-02-06T23:46:51.940Z,1707263211.940 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002217 CHAN A1 (24V): 0.001179 CHAN A2 (12V): -0.005632 CHAN A3 (5V): -0.002713 CHAN B0 (3.3V): -0.001125 CHAN B1 (3.15aV): -0.001206 CHAN B2 (3.15bV): -0.001412 CHAN B3 (GND): -0.000777 OPEN: 0.004703 Full Scale: +/- 1 mA 2024-02-06T23:47:12.487Z,1707263232.487 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-02-06T23:47:18.999Z,1707263238.999 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-02-06T23:47:25.057Z,1707263245.057 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-02-06T23:47:35.135Z,1707263255.135 [SBIT](IMPORTANT): SBIT PASSED 2024-02-06T23:47:35.136Z,1707263255.136 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-02-06T23:47:35.137Z,1707263255.137 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool; 2024-02-06T23:47:35.137Z,1707263255.137 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=315.338855 cubic_centimeter; 2024-02-06T23:47:35.137Z,1707263255.137 [SBIT](IMPORTANT): VerticalControl.massDefault=3.983660 millimeter; 2024-02-06T23:47:35.534Z,1707263255.534 [MissionManager](IMPORTANT): Started mission Startup 2024-02-06T23:47:35.535Z,1707263255.535 [Startup] Running Loop=1 2024-02-06T23:47:35.535Z,1707263255.535 [Startup](DEBUG): Aggregate::initialize Startup 2024-02-06T23:47:35.535Z,1707263255.535 [Startup:A.GoToSurface] Running Loop=1 2024-02-06T23:47:35.535Z,1707263255.535 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-06T23:47:35.536Z,1707263255.536 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-06T23:47:35.536Z,1707263255.536 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-06T23:47:35.537Z,1707263255.537 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-06T23:47:35.537Z,1707263255.537 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-06T23:47:35.537Z,1707263255.537 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-06T23:47:35.539Z,1707263255.539 [Startup:StartupSatComms] Running Loop=1 2024-02-06T23:47:35.540Z,1707263255.540 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-02-06T23:47:35.540Z,1707263255.540 [Startup:StartupSatComms:A] Running Loop=1 2024-02-06T23:47:35.947Z,1707263255.947 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-02-06T23:48:33.386Z,1707263313.386 [DataOverHttps](IMPORTANT): SBD MTMSN=20240206T234832 2024-02-06T23:48:33.391Z,1707263313.391 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005851 2024-02-06T23:48:35.724Z,1707263315.724 [Startup:StartupSatComms:A](INFO): Timed out from 2024-02-06T23:47:35.5Z 2024-02-06T23:48:35.724Z,1707263315.724 [Startup:StartupSatComms:A] Stopped 2024-02-06T23:48:35.724Z,1707263315.724 [Startup:StartupSatComms:B] Running Loop=1 2024-02-06T23:48:36.119Z,1707263316.119 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-06T23:48:40.652Z,1707263320.652 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240206T233457/Courier0010.lzma 2024-02-06T23:48:40.655Z,1707263320.655 [DataOverHttps](INFO): Received command: strobe off 2024-02-06T23:48:40.685Z,1707263320.685 [CommandExec](IMPORTANT): got command strobe off 2024-02-06T23:48:40.685Z,1707263320.685 [CommandExec](IMPORTANT): Deactivating strobe 2024-02-06T23:48:41.891Z,1707263321.891 [DataOverHttps](INFO): Moved sent file to Logs/20240206T233457/Courier0010.lzma.bak 2024-02-06T23:48:41.892Z,1707263321.892 [DataOverHttps](INFO): SBD MOMSN=19267871 2024-02-06T23:48:57.428Z,1707263337.428 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240206T234554/Courier0000.lzma 2024-02-06T23:48:58.430Z,1707263338.430 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Courier0000.lzma.bak 2024-02-06T23:48:58.430Z,1707263338.430 [DataOverHttps](INFO): SBD MOMSN=19267874 2024-02-06T23:49:05.662Z,1707263345.662 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-02-06T23:49:05.662Z,1707263345.662 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-06T23:49:05.663Z,1707263345.663 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-02-06T23:49:05.663Z,1707263345.663 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-06T23:49:05.705Z,1707263345.705 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-06T23:49:05.705Z,1707263345.705 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-06T23:49:06.065Z,1707263346.065 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-06T23:49:06.065Z,1707263346.065 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-02-06T23:49:06.066Z,1707263346.066 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-06T23:49:06.066Z,1707263346.066 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-02-06T23:49:06.468Z,1707263346.468 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-06T23:49:14.068Z,1707263354.068 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240206T234554/Courier0004.lzma 2024-02-06T23:49:15.070Z,1707263355.070 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Courier0004.lzma.bak 2024-02-06T23:49:15.070Z,1707263355.070 [DataOverHttps](INFO): SBD MOMSN=19267877 2024-02-06T23:49:27.147Z,1707263367.147 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-02-06T23:49:30.684Z,1707263370.684 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20240206T233457/Express0011.lzma 2024-02-06T23:49:31.686Z,1707263371.686 [DataOverHttps](INFO): Moved sent file to Logs/20240206T233457/Express0011.lzma.bak 2024-02-06T23:49:31.686Z,1707263371.686 [DataOverHttps](INFO): SBD MOMSN=19267879 2024-02-06T23:49:35.994Z,1707263375.994 [Startup:StartupSatComms:B](INFO): Timed out from 2024-02-06T23:48:35.7Z 2024-02-06T23:49:35.994Z,1707263375.994 [Startup:StartupSatComms:B] Stopped 2024-02-06T23:49:35.994Z,1707263375.994 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-02-06T23:49:35.994Z,1707263375.994 [Startup:StartupSatComms] Stopped 2024-02-06T23:49:35.994Z,1707263375.994 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-02-06T23:49:35.995Z,1707263375.995 [Startup](INFO): Completed Startup 2024-02-06T23:49:35.995Z,1707263375.995 [MissionManager](INFO): Startup is completed. 2024-02-06T23:49:35.995Z,1707263375.995 [MissionManager](INFO): Uninitializing Mission Startup 2024-02-06T23:49:36.001Z,1707263376.001 [Startup] Stopped 2024-02-06T23:49:36.001Z,1707263376.001 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-02-06T23:49:36.001Z,1707263376.001 [Startup:A.GoToSurface] Stopped 2024-02-06T23:49:36.001Z,1707263376.001 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-02-06T23:49:36.365Z,1707263376.365 [MissionManager](IMPORTANT): Started mission Default 2024-02-06T23:49:36.365Z,1707263376.365 [Default] Running Loop=1 2024-02-06T23:49:36.365Z,1707263376.365 [Default](DEBUG): Aggregate::initialize Default 2024-02-06T23:49:36.365Z,1707263376.365 [Default:B.GoToSurface] Running Loop=1 2024-02-06T23:49:36.365Z,1707263376.365 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-06T23:49:36.365Z,1707263376.365 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-06T23:49:36.366Z,1707263376.366 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-06T23:49:36.366Z,1707263376.366 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-06T23:49:36.366Z,1707263376.366 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-06T23:49:36.367Z,1707263376.367 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-06T23:49:36.367Z,1707263376.367 [Default:A.Wait] Running Loop=1 2024-02-06T23:49:36.367Z,1707263376.367 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-02-06T23:49:47.557Z,1707263387.557 [DataOverHttps](INFO): Sending 752 bytes from file Logs/20240206T234554/Express0001.lzma 2024-02-06T23:49:48.558Z,1707263388.558 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Express0001.lzma.bak 2024-02-06T23:49:48.558Z,1707263388.558 [DataOverHttps](INFO): SBD MOMSN=19267884 2024-02-06T23:49:49.693Z,1707263389.693 [Default:A.Wait](INFO): Done Waiting. 2024-02-06T23:49:49.693Z,1707263389.693 [Default:A.Wait] Stopped 2024-02-06T23:49:49.693Z,1707263389.693 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-02-06T23:49:50.107Z,1707263390.107 [Default:CheckIn] Running Loop=1 2024-02-06T23:49:50.107Z,1707263390.107 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-06T23:49:50.108Z,1707263390.108 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-06T23:49:50.526Z,1707263390.526 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-02-06T23:49:52.960Z,1707263392.960 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-02-06T23:50:05.892Z,1707263405.892 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-02-06T23:51:05.684Z,1707263465.684 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-02-06T23:51:27.108Z,1707263487.108 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-02-06T23:51:48.047Z,1707263508.047 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-02-06T23:51:53.357Z,1707263513.357 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-02-06T23:52:06.684Z,1707263526.684 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-02-06T23:52:06.684Z,1707263526.684 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-06T23:52:06.685Z,1707263526.685 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2024-02-06T23:52:06.685Z,1707263526.685 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-06T23:52:06.724Z,1707263526.724 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-06T23:52:06.725Z,1707263526.725 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-06T23:52:07.099Z,1707263527.099 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-06T23:52:07.099Z,1707263527.099 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-02-06T23:52:07.099Z,1707263527.099 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-06T23:52:07.099Z,1707263527.099 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2024-02-06T23:52:07.485Z,1707263527.485 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-06T23:52:13.159Z,1707263533.159 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-02-06T23:52:26.085Z,1707263546.085 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-02-06T23:54:50.283Z,1707263690.283 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-06T23:49:50.1Z 2024-02-06T23:54:50.283Z,1707263690.283 [Default:CheckIn:Read_GPS] Stopped 2024-02-06T23:54:50.283Z,1707263690.283 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-06T23:54:50.679Z,1707263690.679 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-06T23:54:54.296Z,1707263694.296 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2024-02-06T23:54:54.299Z,1707263694.299 [BPC1](INFO): Received data from all battery sticks. 2024-02-06T23:54:57.887Z,1707263697.887 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240206T234554/Courier0007.lzma 2024-02-06T23:54:58.890Z,1707263698.890 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Courier0007.lzma.bak 2024-02-06T23:54:58.890Z,1707263698.890 [DataOverHttps](INFO): SBD MOMSN=19267903 2024-02-06T23:55:07.636Z,1707263707.636 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-02-06T23:55:07.636Z,1707263707.636 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-06T23:55:07.637Z,1707263707.637 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2024-02-06T23:55:07.638Z,1707263707.638 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-06T23:55:07.648Z,1707263707.648 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-06T23:55:07.648Z,1707263707.648 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-06T23:55:08.054Z,1707263708.054 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-06T23:55:08.054Z,1707263708.054 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-02-06T23:55:08.055Z,1707263708.055 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-06T23:55:08.055Z,1707263708.055 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2024-02-06T23:55:08.445Z,1707263708.445 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-06T23:55:14.436Z,1707263714.436 [DataOverHttps](INFO): Sending 111 bytes from file Logs/20240206T234554/Express0005.lzma 2024-02-06T23:55:15.438Z,1707263715.438 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Express0005.lzma.bak 2024-02-06T23:55:15.438Z,1707263715.438 [DataOverHttps](INFO): SBD MOMSN=19267907 2024-02-06T23:55:30.952Z,1707263730.952 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20240206T234554/Express0008.lzma 2024-02-06T23:55:31.954Z,1707263731.954 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Express0008.lzma.bak 2024-02-06T23:55:31.954Z,1707263731.954 [DataOverHttps](INFO): SBD MOMSN=19267912 2024-02-06T23:55:33.094Z,1707263733.094 [Default:CheckIn:Read_Iridium] Stopped 2024-02-06T23:55:33.094Z,1707263733.094 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-06T23:55:33.094Z,1707263733.094 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-06T23:56:47.013Z,1707263807.013 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-02-06T23:56:47.013Z,1707263807.013 [NAL9602] Data Fault, FailCount= 1 2024-02-06T23:56:47.013Z,1707263807.013 [NAL9602](ERROR): Data Fault 2024-02-06T23:56:47.090Z,1707263807.090 [CBIT](ERROR): Data Fault in component: NAL9602 2024-02-06T23:56:47.417Z,1707263807.417 [NAL9602](INFO): Powering down 2024-02-06T23:56:48.277Z,1707263808.277 [CBIT](INFO): Clearing failed state for component NAL9602 2024-02-06T23:56:48.277Z,1707263808.277 [NAL9602] No Fault, FailCount= 1 2024-02-06T23:57:17.722Z,1707263837.722 [NAL9602](INFO): Powering up NAL9602 2024-02-06T23:57:28.623Z,1707263848.623 [NAL9602](INFO): NAL9602 initialized 2024-02-06T23:58:08.653Z,1707263888.653 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-02-06T23:58:08.653Z,1707263888.653 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-06T23:58:08.654Z,1707263888.654 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2024-02-06T23:58:08.654Z,1707263888.654 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-06T23:58:08.664Z,1707263888.664 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-06T23:58:08.665Z,1707263888.665 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-06T23:58:09.067Z,1707263889.067 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-06T23:58:09.067Z,1707263889.067 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-02-06T23:58:09.067Z,1707263889.067 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-06T23:58:09.067Z,1707263889.067 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2024-02-06T23:58:09.463Z,1707263889.463 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-07T00:00:33.690Z,1707264033.690 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-07T00:00:33.690Z,1707264033.690 [Default:CheckIn:C.Wait] Stopped 2024-02-07T00:00:33.690Z,1707264033.690 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-07T00:00:33.690Z,1707264033.690 [Default:CheckIn:D] Running Loop=1 2024-02-07T00:00:34.095Z,1707264034.095 [Default:CheckIn:D] Stopped 2024-02-07T00:00:34.095Z,1707264034.095 [Default:CheckIn:E] Running Loop=1 2024-02-07T00:00:34.483Z,1707264034.483 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.962162 min 2024-02-07T00:00:34.483Z,1707264034.483 [Default:CheckIn:E] Stopped 2024-02-07T00:00:34.483Z,1707264034.483 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-07T00:00:34.483Z,1707264034.483 [Default:CheckIn] Stopped 2024-02-07T00:00:34.483Z,1707264034.483 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-07T00:00:34.500Z,1707264034.500 [Default:CheckIn](INFO): Running loop #2 2024-02-07T00:00:34.500Z,1707264034.500 [Default:CheckIn] Running Loop=2 2024-02-07T00:00:34.500Z,1707264034.500 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-07T00:00:34.500Z,1707264034.500 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-07T00:00:51.843Z,1707264051.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000050.00,A,3648.14597,N,12147.22854,W,0.311,0.00,070224,,,A*76 2024-02-07T00:00:51.846Z,1707264051.846 [NAL9602](INFO): GPS fix at 20240207T000050: (36.802433, -121.787142) 2024-02-07T00:00:51.860Z,1707264051.860 [Default:CheckIn:Read_GPS] Stopped 2024-02-07T00:00:51.860Z,1707264051.860 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-07T00:00:58.004Z,1707264058.004 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20240206T234554/Courier0010.lzma 2024-02-07T00:00:59.006Z,1707264059.006 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Courier0010.lzma.bak 2024-02-07T00:00:59.006Z,1707264059.006 [DataOverHttps](INFO): SBD MOMSN=19267924 2024-02-07T00:01:14.884Z,1707264074.884 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20240206T234554/Express0011.lzma 2024-02-07T00:01:15.886Z,1707264075.886 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Express0011.lzma.bak 2024-02-07T00:01:15.886Z,1707264075.886 [DataOverHttps](INFO): SBD MOMSN=19267927 2024-02-07T00:01:16.935Z,1707264076.935 [Default:CheckIn:Read_Iridium] Stopped 2024-02-07T00:01:16.935Z,1707264076.935 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-07T00:01:16.935Z,1707264076.935 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-07T00:01:24.571Z,1707264084.571 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-07T00:06:17.521Z,1707264377.521 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-07T00:06:17.521Z,1707264377.521 [Default:CheckIn:C.Wait] Stopped 2024-02-07T00:06:17.521Z,1707264377.521 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-07T00:06:17.521Z,1707264377.521 [Default:CheckIn:D] Running Loop=1 2024-02-07T00:06:17.919Z,1707264377.919 [Default:CheckIn:D] Stopped 2024-02-07T00:06:17.919Z,1707264377.919 [Default:CheckIn:E] Running Loop=1 2024-02-07T00:06:18.328Z,1707264378.328 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.692573 min 2024-02-07T00:06:18.329Z,1707264378.329 [Default:CheckIn:E] Stopped 2024-02-07T00:06:18.329Z,1707264378.329 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-07T00:06:18.329Z,1707264378.329 [Default:CheckIn] Stopped 2024-02-07T00:06:18.329Z,1707264378.329 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-07T00:06:18.329Z,1707264378.329 [Default:CheckIn](INFO): Running loop #3 2024-02-07T00:06:18.329Z,1707264378.329 [Default:CheckIn] Running Loop=3 2024-02-07T00:06:18.329Z,1707264378.329 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-07T00:06:18.329Z,1707264378.329 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-07T00:06:20.334Z,1707264380.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000619.00,A,3648.14914,N,12147.22518,W,0.330,0.00,070224,,,A*7C 2024-02-07T00:06:20.336Z,1707264380.336 [NAL9602](INFO): GPS fix at 20240207T000619: (36.802486, -121.787086) 2024-02-07T00:06:20.368Z,1707264380.368 [Default:CheckIn:Read_GPS] Stopped 2024-02-07T00:06:20.368Z,1707264380.368 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-07T00:06:27.231Z,1707264387.231 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240206T234554/Courier0013.lzma 2024-02-07T00:06:28.234Z,1707264388.234 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Courier0013.lzma.bak 2024-02-07T00:06:28.234Z,1707264388.234 [DataOverHttps](INFO): SBD MOMSN=19267931 2024-02-07T00:06:44.256Z,1707264404.256 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240206T234554/Express0014.lzma 2024-02-07T00:06:45.258Z,1707264405.258 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Express0014.lzma.bak 2024-02-07T00:06:45.258Z,1707264405.258 [DataOverHttps](INFO): SBD MOMSN=19267934 2024-02-07T00:06:46.646Z,1707264406.646 [Default:CheckIn:Read_Iridium] Stopped 2024-02-07T00:06:46.646Z,1707264406.646 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-07T00:06:46.646Z,1707264406.646 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-07T00:07:51.642Z,1707264471.642 [NAL9602](INFO): SBD MO Status=2, MOMSN=14683, MT Status=2, MTMSN=0 2024-02-07T00:07:51.643Z,1707264471.643 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-07T00:08:19.928Z,1707264499.928 [NAL9602](INFO): SBD MO Status=2, MOMSN=14683, MT Status=2, MTMSN=0 2024-02-07T00:08:19.928Z,1707264499.928 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-07T00:08:34.867Z,1707264514.867 [NAL9602](INFO): SBD MO Status=0, MOMSN=14683, MT Status=0, MTMSN=0 2024-02-07T00:08:34.867Z,1707264514.867 [NAL9602](INFO): No messages in MT queue 2024-02-07T00:09:05.573Z,1707264545.573 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-07T00:11:47.245Z,1707264707.245 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-07T00:11:47.245Z,1707264707.245 [Default:CheckIn:C.Wait] Stopped 2024-02-07T00:11:47.246Z,1707264707.246 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-07T00:11:47.246Z,1707264707.246 [Default:CheckIn:D] Running Loop=1 2024-02-07T00:11:47.657Z,1707264707.657 [Default:CheckIn:D] Stopped 2024-02-07T00:11:47.657Z,1707264707.657 [Default:CheckIn:E] Running Loop=1 2024-02-07T00:11:48.069Z,1707264708.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.188192 min 2024-02-07T00:11:48.069Z,1707264708.069 [Default:CheckIn:E] Stopped 2024-02-07T00:11:48.069Z,1707264708.069 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-07T00:11:48.069Z,1707264708.069 [Default:CheckIn] Stopped 2024-02-07T00:11:48.069Z,1707264708.069 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-07T00:11:48.070Z,1707264708.070 [Default:CheckIn](INFO): Running loop #4 2024-02-07T00:11:48.070Z,1707264708.070 [Default:CheckIn] Running Loop=4 2024-02-07T00:11:48.070Z,1707264708.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-07T00:11:48.070Z,1707264708.070 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-07T00:11:50.047Z,1707264710.047 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001149.00,A,3648.15014,N,12147.22035,W,0.330,129.74,070224,,,A*74 2024-02-07T00:11:50.050Z,1707264710.050 [NAL9602](INFO): GPS fix at 20240207T001149: (36.802502, -121.787006) 2024-02-07T00:11:50.070Z,1707264710.070 [Default:CheckIn:Read_GPS] Stopped 2024-02-07T00:11:50.070Z,1707264710.070 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-07T00:11:59.831Z,1707264719.831 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240206T234554/Courier0016.lzma 2024-02-07T00:12:00.834Z,1707264720.834 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Courier0016.lzma.bak 2024-02-07T00:12:00.834Z,1707264720.834 [DataOverHttps](INFO): SBD MOMSN=19267939 2024-02-07T00:12:16.728Z,1707264736.728 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240206T234554/Express0017.lzma 2024-02-07T00:12:17.730Z,1707264737.730 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Express0017.lzma.bak 2024-02-07T00:12:17.730Z,1707264737.730 [DataOverHttps](INFO): SBD MOMSN=19267942 2024-02-07T00:12:18.786Z,1707264738.786 [Default:CheckIn:Read_Iridium] Stopped 2024-02-07T00:12:18.786Z,1707264738.786 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-07T00:12:18.786Z,1707264738.786 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-07T00:13:43.605Z,1707264823.605 [NAL9602](INFO): SBD MO Status=2, MOMSN=14684, MT Status=2, MTMSN=0 2024-02-07T00:13:43.606Z,1707264823.606 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-07T00:14:05.424Z,1707264845.424 [NAL9602](INFO): SBD MO Status=2, MOMSN=14684, MT Status=2, MTMSN=0 2024-02-07T00:14:05.424Z,1707264845.424 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-07T00:14:33.702Z,1707264873.702 [NAL9602](INFO): SBD MO Status=0, MOMSN=14684, MT Status=0, MTMSN=0 2024-02-07T00:14:33.702Z,1707264873.702 [NAL9602](INFO): No messages in MT queue 2024-02-07T00:15:04.404Z,1707264904.404 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-07T00:17:19.362Z,1707265039.362 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-07T00:17:19.362Z,1707265039.362 [Default:CheckIn:C.Wait] Stopped 2024-02-07T00:17:19.362Z,1707265039.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-07T00:17:19.362Z,1707265039.362 [Default:CheckIn:D] Running Loop=1 2024-02-07T00:17:19.777Z,1707265039.777 [Default:CheckIn:D] Stopped 2024-02-07T00:17:19.777Z,1707265039.777 [Default:CheckIn:E] Running Loop=1 2024-02-07T00:17:20.159Z,1707265040.159 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.723537 min 2024-02-07T00:17:20.159Z,1707265040.159 [Default:CheckIn:E] Stopped 2024-02-07T00:17:20.159Z,1707265040.159 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-07T00:17:20.159Z,1707265040.159 [Default:CheckIn] Stopped 2024-02-07T00:17:20.159Z,1707265040.159 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-07T00:17:20.161Z,1707265040.161 [Default:CheckIn](INFO): Running loop #5 2024-02-07T00:17:20.161Z,1707265040.161 [Default:CheckIn] Running Loop=5 2024-02-07T00:17:20.161Z,1707265040.161 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-07T00:17:20.161Z,1707265040.161 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-07T00:17:22.175Z,1707265042.175 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001721.00,A,3648.24802,N,12147.12971,W,3.965,19.52,070224,,,D*41 2024-02-07T00:17:22.178Z,1707265042.178 [NAL9602](INFO): GPS fix at 20240207T001721: (36.804134, -121.785495) 2024-02-07T00:17:22.190Z,1707265042.190 [Default:CheckIn:Read_GPS] Stopped 2024-02-07T00:17:22.191Z,1707265042.191 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-07T00:17:29.119Z,1707265049.119 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240206T234554/Courier0019.lzma 2024-02-07T00:17:30.122Z,1707265050.122 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Courier0019.lzma.bak 2024-02-07T00:17:30.122Z,1707265050.122 [DataOverHttps](INFO): SBD MOMSN=19267946 2024-02-07T00:17:46.012Z,1707265066.012 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240206T234554/Express0020.lzma 2024-02-07T00:17:47.014Z,1707265067.014 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Express0020.lzma.bak 2024-02-07T00:17:47.014Z,1707265067.014 [DataOverHttps](INFO): SBD MOMSN=19267949 2024-02-07T00:17:48.069Z,1707265068.069 [Default:CheckIn:Read_Iridium] Stopped 2024-02-07T00:17:48.069Z,1707265068.069 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-07T00:17:48.069Z,1707265068.069 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-07T00:17:50.065Z,1707265070.065 [NAL9602](INFO): SBD MO Status=0, MOMSN=14685, MT Status=0, MTMSN=0 2024-02-07T00:17:50.065Z,1707265070.065 [NAL9602](INFO): No messages in MT queue 2024-02-07T00:18:20.753Z,1707265100.753 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-07T00:19:42.145Z,1707265182.145 [DataOverHttps](IMPORTANT): SBD MTMSN=20240207T001941 2024-02-07T00:19:49.631Z,1707265189.631 [DataOverHttps](INFO): Received command: ibit 2024-02-07T00:19:49.725Z,1707265189.725 [CommandExec](IMPORTANT): got command ibit 2024-02-07T00:19:50.056Z,1707265190.056 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-02-07T00:19:50.057Z,1707265190.057 [IBIT](IMPORTANT): Beginning control surface checks. 2024-02-07T00:19:50.060Z,1707265190.060 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-07T00:19:51.655Z,1707265191.655 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001950.00,A,3648.41986,N,12147.11390,W,4.685,5.20,070224,,,D*7F 2024-02-07T00:19:51.657Z,1707265191.657 [NAL9602](INFO): GPS fix at 20240207T001950: (36.806998, -121.785232) 2024-02-07T00:20:00.978Z,1707265200.978 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006614 CHAN A1 (24V): 0.000605 CHAN A2 (12V): -0.005250 CHAN A3 (5V): -0.002513 CHAN B0 (3.3V): -0.000636 CHAN B1 (3.15aV): -0.001044 CHAN B2 (3.15bV): -0.001780 CHAN B3 (GND): -0.000262 OPEN: 0.004808 Full Scale: +/- 1 mA 2024-02-07T00:20:08.214Z,1707265208.214 [NAL9602](INFO): SBD MO Status=0, MOMSN=14686, MT Status=0, MTMSN=0 2024-02-07T00:20:08.214Z,1707265208.214 [NAL9602](INFO): No messages in MT queue 2024-02-07T00:20:09.431Z,1707265209.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002008.00,A,3648.44408,N,12147.11193,W,4.840,2.81,070224,,,D*7C 2024-02-07T00:20:09.433Z,1707265209.433 [NAL9602](INFO): GPS fix at 20240207T002008: (36.807401, -121.785199) 2024-02-07T00:20:11.851Z,1707265211.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002011.00,A,3648.44815,N,12147.11150,W,4.879,5.16,070224,,,D*78 2024-02-07T00:20:11.853Z,1707265211.853 [NAL9602](INFO): GPS fix at 20240207T002011: (36.807469, -121.785192) 2024-02-07T00:20:15.087Z,1707265215.087 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002014.00,A,3648.45215,N,12147.11102,W,4.685,5.15,070224,,,D*7F 2024-02-07T00:20:15.089Z,1707265215.089 [NAL9602](INFO): GPS fix at 20240207T002014: (36.807536, -121.785184) 2024-02-07T00:20:17.915Z,1707265217.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002017.00,A,3648.45621,N,12147.11077,W,4.743,2.14,070224,,,D*71 2024-02-07T00:20:17.917Z,1707265217.917 [NAL9602](INFO): GPS fix at 20240207T002017: (36.807603, -121.785179) 2024-02-07T00:20:21.143Z,1707265221.143 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002020.00,A,3648.46028,N,12147.11066,W,4.918,0.66,070224,,,D*7E 2024-02-07T00:20:21.145Z,1707265221.145 [NAL9602](INFO): GPS fix at 20240207T002020: (36.807671, -121.785178) 2024-02-07T00:20:23.971Z,1707265223.971 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002023.00,A,3648.46429,N,12147.11043,W,4.724,1.61,070224,,,D*78 2024-02-07T00:20:23.973Z,1707265223.973 [NAL9602](INFO): GPS fix at 20240207T002023: (36.807738, -121.785174) 2024-02-07T00:20:26.799Z,1707265226.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002026.00,A,3648.46825,N,12147.11024,W,4.704,1.77,070224,,,D*79 2024-02-07T00:20:26.801Z,1707265226.801 [NAL9602](INFO): GPS fix at 20240207T002026: (36.807804, -121.785171) 2024-02-07T00:20:30.031Z,1707265230.031 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002029.00,A,3648.47220,N,12147.11007,W,4.762,0.51,070224,,,D*7C 2024-02-07T00:20:30.034Z,1707265230.034 [NAL9602](INFO): GPS fix at 20240207T002029: (36.807870, -121.785168) 2024-02-07T00:20:32.864Z,1707265232.864 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002032.00,A,3648.47617,N,12147.10993,W,4.704,0.57,070224,,,D*75 2024-02-07T00:20:32.866Z,1707265232.866 [NAL9602](INFO): GPS fix at 20240207T002032: (36.807936, -121.785166) 2024-02-07T00:20:36.090Z,1707265236.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002035.00,A,3648.47998,N,12147.11007,W,4.529,356.79,070224,,,D*7E 2024-02-07T00:20:36.092Z,1707265236.092 [NAL9602](INFO): GPS fix at 20240207T002035: (36.808000, -121.785168) 2024-02-07T00:20:36.532Z,1707265236.532 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.807999 Longitude: -121.785172 2024-02-07T00:20:36.925Z,1707265236.925 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.174999 2024-02-07T00:20:36.925Z,1707265236.925 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-02-07T00:20:36.925Z,1707265236.925 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-02-07T00:20:37.343Z,1707265237.343 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-02-07T00:20:37.343Z,1707265237.343 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-02-07T00:20:37.343Z,1707265237.343 [IBIT](IMPORTANT): Pressure:8.655169 PSI 2024-02-07T00:20:37.348Z,1707265237.348 [IBIT](IMPORTANT): Humidity:30.612867 % 2024-02-07T00:20:37.734Z,1707265237.734 [IBIT](IMPORTANT): Vehicle Pitch:2.693548 degrees 2024-02-07T00:20:37.734Z,1707265237.734 [IBIT](IMPORTANT): Vehicle Roll:6.242020 degrees 2024-02-07T00:20:37.735Z,1707265237.735 [IBIT](IMPORTANT): Vehicle Heading:349.717896 degrees 2024-02-07T00:20:38.134Z,1707265238.134 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-02-07T00:20:38.134Z,1707265238.134 [IBIT](IMPORTANT): buoyancyNeutral: 315.338867 cc 2024-02-07T00:20:38.134Z,1707265238.134 [IBIT](IMPORTANT): massDefault: 0.398366 cm 2024-02-07T00:20:38.135Z,1707265238.135 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2024-02-07T00:20:38.135Z,1707265238.135 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2024-02-07T00:20:38.135Z,1707265238.135 [IBIT](IMPORTANT): IBIT PASSED 2024-02-07T00:21:08.408Z,1707265268.408 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-07T00:22:48.618Z,1707265368.618 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-07T00:22:48.618Z,1707265368.618 [Default:CheckIn:C.Wait] Stopped 2024-02-07T00:22:48.618Z,1707265368.618 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-07T00:22:48.618Z,1707265368.618 [Default:CheckIn:D] Running Loop=1 2024-02-07T00:22:49.031Z,1707265369.031 [Default:CheckIn:D] Stopped 2024-02-07T00:22:49.031Z,1707265369.031 [Default:CheckIn:E] Running Loop=1 2024-02-07T00:22:49.425Z,1707265369.425 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.211104 min 2024-02-07T00:22:49.425Z,1707265369.425 [Default:CheckIn:E] Stopped 2024-02-07T00:22:49.425Z,1707265369.425 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-07T00:22:49.425Z,1707265369.425 [Default:CheckIn] Stopped 2024-02-07T00:22:49.425Z,1707265369.425 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-07T00:22:49.425Z,1707265369.425 [Default:CheckIn](INFO): Running loop #6 2024-02-07T00:22:49.425Z,1707265369.425 [Default:CheckIn] Running Loop=6 2024-02-07T00:22:49.425Z,1707265369.425 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-07T00:22:49.425Z,1707265369.425 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-07T00:22:51.435Z,1707265371.435 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002250.00,A,3648.44140,N,12147.31898,W,6.745,235.81,070224,,,D*74 2024-02-07T00:22:51.437Z,1707265371.437 [NAL9602](INFO): GPS fix at 20240207T002250: (36.807357, -121.788650) 2024-02-07T00:22:51.449Z,1707265371.449 [Default:CheckIn:Read_GPS] Stopped 2024-02-07T00:22:51.449Z,1707265371.449 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-07T00:22:58.979Z,1707265378.979 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240206T234554/Courier0022.lzma 2024-02-07T00:22:59.982Z,1707265379.982 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Courier0022.lzma.bak 2024-02-07T00:22:59.982Z,1707265379.982 [DataOverHttps](INFO): SBD MOMSN=19267956 2024-02-07T00:23:15.842Z,1707265395.842 [DataOverHttps](INFO): Sending 788 bytes from file Logs/20240206T234554/Express0023.lzma 2024-02-07T00:23:16.842Z,1707265396.842 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Express0023.lzma.bak 2024-02-07T00:23:16.842Z,1707265396.842 [DataOverHttps](INFO): SBD MOMSN=19267959 2024-02-07T00:23:18.095Z,1707265398.095 [NAL9602](INFO): SBD MO Status=0, MOMSN=14687, MT Status=0, MTMSN=0 2024-02-07T00:23:18.095Z,1707265398.095 [NAL9602](INFO): No messages in MT queue 2024-02-07T00:23:32.623Z,1707265412.623 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240206T234554/Express0026.lzma 2024-02-07T00:23:33.626Z,1707265413.626 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Express0026.lzma.bak 2024-02-07T00:23:33.626Z,1707265413.626 [DataOverHttps](INFO): SBD MOMSN=19267986 2024-02-07T00:23:34.689Z,1707265414.689 [Default:CheckIn:Read_Iridium] Stopped 2024-02-07T00:23:34.689Z,1707265414.689 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-07T00:23:34.689Z,1707265414.689 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-07T00:23:48.801Z,1707265428.801 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-07T00:28:35.261Z,1707265715.261 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-07T00:28:35.261Z,1707265715.261 [Default:CheckIn:C.Wait] Stopped 2024-02-07T00:28:35.261Z,1707265715.261 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-07T00:28:35.261Z,1707265715.261 [Default:CheckIn:D] Running Loop=1 2024-02-07T00:28:35.679Z,1707265715.679 [Default:CheckIn:D] Stopped 2024-02-07T00:28:35.679Z,1707265715.679 [Default:CheckIn:E] Running Loop=1 2024-02-07T00:28:36.087Z,1707265716.087 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.988562 min 2024-02-07T00:28:36.087Z,1707265716.087 [Default:CheckIn:E] Stopped 2024-02-07T00:28:36.087Z,1707265716.087 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-07T00:28:36.087Z,1707265716.087 [Default:CheckIn] Stopped 2024-02-07T00:28:36.087Z,1707265716.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-07T00:28:36.087Z,1707265716.087 [Default:CheckIn](INFO): Running loop #7 2024-02-07T00:28:36.088Z,1707265716.088 [Default:CheckIn] Running Loop=7 2024-02-07T00:28:36.088Z,1707265716.088 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-07T00:28:36.088Z,1707265716.088 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-07T00:28:38.083Z,1707265718.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002837.00,A,3648.32755,N,12148.20084,W,0.739,116.78,070224,,,A*7A 2024-02-07T00:28:38.085Z,1707265718.085 [NAL9602](INFO): GPS fix at 20240207T002837: (36.805459, -121.803347) 2024-02-07T00:28:38.122Z,1707265718.122 [Default:CheckIn:Read_GPS] Stopped 2024-02-07T00:28:38.122Z,1707265718.122 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-07T00:28:45.063Z,1707265725.063 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240206T234554/Courier0028.lzma 2024-02-07T00:28:47.070Z,1707265727.070 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Courier0028.lzma.bak 2024-02-07T00:28:47.070Z,1707265727.070 [DataOverHttps](INFO): SBD MOMSN=19267988 2024-02-07T00:29:00.293Z,1707265740.293 [NAL9602](INFO): SBD MO Status=0, MOMSN=14688, MT Status=0, MTMSN=0 2024-02-07T00:29:00.293Z,1707265740.293 [NAL9602](INFO): No messages in MT queue 2024-02-07T00:29:30.997Z,1707265770.997 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-07T00:29:45.936Z,1707265785.936 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240206T234554/Express0029.lzma 2024-02-07T00:29:55.974Z,1707265795.974 [DataOverHttps](INFO): Moved sent file to Logs/20240206T234554/Express0029.lzma.bak 2024-02-07T00:29:55.974Z,1707265795.974 [DataOverHttps](INFO): SBD MOMSN=19267992 2024-02-07T00:29:57.277Z,1707265797.277 [Default:CheckIn:Read_Iridium] Stopped 2024-02-07T00:29:57.277Z,1707265797.277 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-07T00:29:57.277Z,1707265797.277 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-07T00:30:56.212Z,1707265856.212 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239204 2024-02-07T00:31:37.377Z,1707265897.377 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-07T00:32:12.516Z,1707265932.516 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-07T00:32:47.676Z,1707265967.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-07T00:33:22.824Z,1707266002.824 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-07T00:33:57.972Z,1707266037.972 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-07T00:34:33.112Z,1707266073.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-07T00:34:57.843Z,1707266097.843 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-07T00:34:57.843Z,1707266097.843 [Default:CheckIn:C.Wait] Stopped 2024-02-07T00:34:57.843Z,1707266097.843 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-07T00:34:57.843Z,1707266097.843 [Default:CheckIn:D] Running Loop=1 2024-02-07T00:34:58.253Z,1707266098.253 [Default:CheckIn:D] Stopped 2024-02-07T00:34:58.253Z,1707266098.253 [Default:CheckIn:E] Running Loop=1 2024-02-07T00:34:58.671Z,1707266098.671 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.364803 min 2024-02-07T00:34:58.671Z,1707266098.671 [Default:CheckIn:E] Stopped 2024-02-07T00:34:58.671Z,1707266098.671 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-07T00:34:58.672Z,1707266098.672 [Default:CheckIn] Stopped 2024-02-07T00:34:58.672Z,1707266098.672 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-07T00:34:58.672Z,1707266098.672 [Default:CheckIn](INFO): Running loop #8 2024-02-07T00:34:58.672Z,1707266098.672 [Default:CheckIn] Running Loop=8 2024-02-07T00:34:58.672Z,1707266098.672 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-07T00:34:58.672Z,1707266098.672 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-07T00:35:00.667Z,1707266100.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003459.00,A,3648.31196,N,12148.18216,W,0.389,105.79,070224,,,A*7B 2024-02-07T00:35:00.669Z,1707266100.669 [NAL9602](INFO): GPS fix at 20240207T003459: (36.805199, -121.803036) 2024-02-07T00:35:00.681Z,1707266100.681 [Default:CheckIn:Read_GPS] Stopped 2024-02-07T00:35:00.681Z,1707266100.681 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-07T00:35:08.260Z,1707266108.260 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-07T00:35:34.683Z,1707266134.683 [NAL9602](INFO): SBD MO Status=2, MOMSN=14689, MT Status=2, MTMSN=0 2024-02-07T00:35:34.683Z,1707266134.683 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-07T00:35:43.408Z,1707266143.408 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-07T00:36:04.866Z,1707266164.866 [DataOverHttps](IMPORTANT): SBD MTMSN=20240207T003604 2024-02-07T00:36:04.869Z,1707266164.869 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003537 2024-02-07T00:36:08.662Z,1707266168.662 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14689, MT Status=1, MTMSN=620 2024-02-07T00:36:08.716Z,1707266168.716 [NAL9602](INFO): Sent 73 bytes from file Logs/20240206T234554/Courier0031.lzma 2024-02-07T00:36:08.716Z,1707266168.716 [NAL9602](INFO): Packets left to send: 0