2025-07-17T22:22:42.335Z,1752790962.335 [Supervisor](DEBUG): Initializing supervisor. 2025-07-17T22:22:42.340Z,1752790962.340 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-07-17T22:22:42.341Z,1752790962.341 [SyncHandler](INFO): Protected caller Thread ID is 1278 2025-07-17T22:22:42.341Z,1752790962.341 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-07-17T22:22:42.342Z,1752790962.342 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-07-17T22:22:42.343Z,1752790962.343 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1279 2025-07-17T22:22:42.347Z,1752790962.347 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-07-17T22:22:42.367Z,1752790962.367 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-07-17T22:22:42.368Z,1752790962.368 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-07-17T22:22:42.369Z,1752790962.369 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1280 2025-07-17T22:22:42.373Z,1752790962.373 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-07-17T22:22:42.374Z,1752790962.374 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-07-17T22:22:42.375Z,1752790962.375 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1281 2025-07-17T22:22:42.377Z,1752790962.377 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-07-17T22:22:42.378Z,1752790962.378 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-07-17T22:22:42.378Z,1752790962.378 [logger ThreadHandler](INFO): Protected caller Thread ID is 1282 2025-07-17T22:22:42.382Z,1752790962.382 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-07-17T22:22:42.382Z,1752790962.382 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-07-17T22:22:42.384Z,1752790962.384 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-07-17T22:22:42.722Z,1752790962.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-07-17T22:22:42.722Z,1752790962.722 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-07-17T22:22:43.480Z,1752790963.480 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-07-17T22:22:43.480Z,1752790963.480 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-07-17T22:22:43.597Z,1752790963.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-07-17T22:22:43.598Z,1752790963.598 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-07-17T22:22:44.150Z,1752790964.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-07-17T22:22:44.150Z,1752790964.150 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-07-17T22:22:44.255Z,1752790964.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-07-17T22:22:44.256Z,1752790964.256 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-07-17T22:22:44.355Z,1752790964.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-07-17T22:22:44.355Z,1752790964.355 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-07-17T22:22:44.818Z,1752790964.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-07-17T22:22:44.819Z,1752790964.819 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-07-17T22:22:45.258Z,1752790965.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-07-17T22:22:45.259Z,1752790965.259 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-07-17T22:22:45.406Z,1752790965.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-07-17T22:22:45.406Z,1752790965.406 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-07-17T22:22:45.662Z,1752790965.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-07-17T22:22:45.662Z,1752790965.662 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-07-17T22:22:46.230Z,1752790966.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-07-17T22:22:46.230Z,1752790966.230 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-07-17T22:22:46.626Z,1752790966.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-07-17T22:22:46.627Z,1752790966.627 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-07-17T22:22:46.715Z,1752790966.715 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-07-17T22:22:46.905Z,1752790966.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-07-17T22:22:46.905Z,1752790966.905 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-07-17T22:22:47.164Z,1752790967.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-07-17T22:22:47.165Z,1752790967.165 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-07-17T22:22:47.477Z,1752790967.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-07-17T22:22:47.479Z,1752790967.479 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2025-07-17T22:22:47.480Z,1752790967.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2025-07-17T22:22:47.587Z,1752790967.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2025-07-17T22:22:47.747Z,1752790967.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2025-07-17T22:22:47.842Z,1752790967.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2025-07-17T22:22:47.935Z,1752790967.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2025-07-17T22:22:48.090Z,1752790968.090 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg 2025-07-17T22:22:48.339Z,1752790968.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2025-07-17T22:22:48.589Z,1752790968.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2025-07-17T22:22:48.870Z,1752790968.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-07-17T22:22:48.871Z,1752790968.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2025-07-17T22:22:49.012Z,1752790969.012 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2025-07-17T22:22:49.113Z,1752790969.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2025-07-17T22:22:49.214Z,1752790969.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2025-07-17T22:22:49.308Z,1752790969.308 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/ 2025-07-17T22:22:49.308Z,1752790969.308 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-07-17T22:22:49.313Z,1752790969.313 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-07-17T22:22:49.453Z,1752790969.453 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-07-17T22:22:49.454Z,1752790969.454 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-07-17T22:22:49.464Z,1752790969.464 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-07-17T22:22:49.465Z,1752790969.465 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-07-17T22:22:49.518Z,1752790969.518 [DepthRateCalculator] Loaded 2025-07-17T22:22:49.518Z,1752790969.518 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-07-17T22:22:49.524Z,1752790969.524 [PitchRateCalculator] Loaded 2025-07-17T22:22:49.524Z,1752790969.524 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-07-17T22:22:49.535Z,1752790969.535 [SpeedCalculator] Loaded 2025-07-17T22:22:49.535Z,1752790969.535 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-07-17T22:22:49.540Z,1752790969.540 [YawRateCalculator] Loaded 2025-07-17T22:22:49.540Z,1752790969.540 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-07-17T22:22:49.563Z,1752790969.563 [ElevatorOffsetCalculator] Loaded 2025-07-17T22:22:49.563Z,1752790969.563 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-07-17T22:22:49.563Z,1752790969.563 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-07-17T22:22:49.564Z,1752790969.564 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-07-17T22:22:49.621Z,1752790969.621 [VerticalControl](DEBUG): Construct VerticalControl. 2025-07-17T22:22:49.679Z,1752790969.679 [VerticalControl] Loaded 2025-07-17T22:22:49.679Z,1752790969.679 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-07-17T22:22:49.682Z,1752790969.682 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-07-17T22:22:49.730Z,1752790969.730 [HorizontalControl] Loaded 2025-07-17T22:22:49.731Z,1752790969.731 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-07-17T22:22:49.733Z,1752790969.733 [SpeedControl](DEBUG): Construct SpeedControl. 2025-07-17T22:22:49.736Z,1752790969.736 [SpeedControl] Loaded 2025-07-17T22:22:49.736Z,1752790969.736 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-07-17T22:22:49.739Z,1752790969.739 [LoopControl](DEBUG): Construct LoopControl. 2025-07-17T22:22:49.739Z,1752790969.739 [LoopControl] Loaded 2025-07-17T22:22:49.740Z,1752790969.740 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-07-17T22:22:49.740Z,1752790969.740 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-07-17T22:22:49.740Z,1752790969.740 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-07-17T22:22:50.020Z,1752790970.020 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-07-17T22:22:50.020Z,1752790970.020 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-07-17T22:22:50.034Z,1752790970.034 [NavChart] Loaded 2025-07-17T22:22:50.035Z,1752790970.035 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-07-17T22:22:50.040Z,1752790970.040 [UniversalFixResidualReporter] Loaded 2025-07-17T22:22:50.040Z,1752790970.040 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-07-17T22:22:50.041Z,1752790970.041 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-07-17T22:22:50.042Z,1752790970.042 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-07-17T22:22:50.482Z,1752790970.482 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-07-17T22:22:50.482Z,1752790970.482 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-07-17T22:22:51.057Z,1752790971.057 [AHRS_M2] Loaded 2025-07-17T22:22:51.057Z,1752790971.057 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-07-17T22:22:51.097Z,1752790971.097 [BackseatComponent] Loaded 2025-07-17T22:22:51.097Z,1752790971.097 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-07-17T22:22:51.098Z,1752790971.098 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0 2025-07-17T22:22:51.099Z,1752790971.099 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1367 2025-07-17T22:22:51.102Z,1752790971.102 [LcmUniversalReporter] Loaded 2025-07-17T22:22:51.102Z,1752790971.102 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-07-17T22:22:51.956Z,1752790971.956 [BPC1] Loaded 2025-07-17T22:22:51.957Z,1752790971.957 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-07-17T22:22:52.059Z,1752790972.059 [DAT] Loaded 2025-07-17T22:22:52.059Z,1752790972.059 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-07-17T22:22:52.060Z,1752790972.060 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0 2025-07-17T22:22:52.060Z,1752790972.060 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1368 2025-07-17T22:22:52.137Z,1752790972.137 [DataOverHttps] Loaded 2025-07-17T22:22:52.137Z,1752790972.137 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-07-17T22:22:52.138Z,1752790972.138 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4099E4E0 2025-07-17T22:22:52.139Z,1752790972.139 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1369 2025-07-17T22:22:52.163Z,1752790972.163 [Depth_Keller] Loaded 2025-07-17T22:22:52.163Z,1752790972.163 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-07-17T22:22:52.229Z,1752790972.229 [NAL9602] Loaded 2025-07-17T22:22:52.229Z,1752790972.229 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-07-17T22:22:52.259Z,1752790972.259 [Onboard] Loaded 2025-07-17T22:22:52.260Z,1752790972.260 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-07-17T22:22:52.261Z,1752790972.261 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409CE4E0 2025-07-17T22:22:52.261Z,1752790972.261 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1370 2025-07-17T22:22:52.276Z,1752790972.276 [Power24vConverter] Loaded 2025-07-17T22:22:52.276Z,1752790972.276 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-07-17T22:22:52.292Z,1752790972.292 [Radio_Surface] Loaded 2025-07-17T22:22:52.292Z,1752790972.292 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-07-17T22:22:52.293Z,1752790972.293 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409FE4E0 2025-07-17T22:22:52.294Z,1752790972.294 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1371 2025-07-17T22:22:52.341Z,1752790972.341 [RDI_Pathfinder] Loaded 2025-07-17T22:22:52.341Z,1752790972.341 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-07-17T22:22:52.357Z,1752790972.357 [Sonardyne_Nano] Loaded 2025-07-17T22:22:52.357Z,1752790972.357 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-07-17T22:22:52.358Z,1752790972.358 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-07-17T22:22:52.358Z,1752790972.358 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-07-17T22:22:52.502Z,1752790972.502 [BuoyancyServo] Loaded 2025-07-17T22:22:52.502Z,1752790972.502 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-07-17T22:22:52.526Z,1752790972.526 [ElevatorServo] Loaded 2025-07-17T22:22:52.526Z,1752790972.526 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-07-17T22:22:52.558Z,1752790972.558 [DockingStepper] Loaded 2025-07-17T22:22:52.558Z,1752790972.558 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2025-07-17T22:22:52.581Z,1752790972.581 [MassServo] Loaded 2025-07-17T22:22:52.582Z,1752790972.582 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-07-17T22:22:52.603Z,1752790972.603 [RudderServo] Loaded 2025-07-17T22:22:52.603Z,1752790972.603 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-07-17T22:22:52.620Z,1752790972.620 [ThrusterHE] Loaded 2025-07-17T22:22:52.620Z,1752790972.620 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-07-17T22:22:52.621Z,1752790972.621 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-07-17T22:22:52.621Z,1752790972.621 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-07-17T22:22:52.683Z,1752790972.683 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-07-17T22:22:52.683Z,1752790972.683 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-07-17T22:22:52.713Z,1752790972.713 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-07-17T22:22:52.714Z,1752790972.714 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-07-17T22:22:53.076Z,1752790973.076 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-07-17T22:22:53.077Z,1752790973.077 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-07-17T22:22:53.255Z,1752790973.255 [CTD_Seabird] Loaded 2025-07-17T22:22:53.256Z,1752790973.256 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-07-17T22:22:53.257Z,1752790973.257 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BBE4E0 2025-07-17T22:22:53.257Z,1752790973.257 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1372 2025-07-17T22:22:53.294Z,1752790973.294 [ESPComponent] Loaded 2025-07-17T22:22:53.295Z,1752790973.295 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-07-17T22:22:53.317Z,1752790973.317 [PAR_Licor] Loaded 2025-07-17T22:22:53.318Z,1752790973.318 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-07-17T22:22:53.364Z,1752790973.364 [WetLabsBB2FL] Loaded 2025-07-17T22:22:53.364Z,1752790973.364 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-07-17T22:22:53.365Z,1752790973.365 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BEE4E0 2025-07-17T22:22:53.366Z,1752790973.366 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1373 2025-07-17T22:22:53.367Z,1752790973.367 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-07-17T22:22:53.367Z,1752790973.367 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-07-17T22:22:53.536Z,1752790973.536 [SBIT](DEBUG): Construct Startup Built In Test. 2025-07-17T22:22:53.545Z,1752790973.545 [SBIT] Loaded 2025-07-17T22:22:53.546Z,1752790973.546 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-07-17T22:22:53.549Z,1752790973.549 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-07-17T22:22:53.562Z,1752790973.562 [IBIT] Loaded 2025-07-17T22:22:53.562Z,1752790973.562 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-07-17T22:22:53.568Z,1752790973.568 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-07-17T22:22:53.652Z,1752790973.652 [CBIT] Loaded 2025-07-17T22:22:53.652Z,1752790973.652 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-07-17T22:22:53.686Z,1752790973.686 [GFScanner] Loaded 2025-07-17T22:22:53.686Z,1752790973.686 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-07-17T22:22:53.687Z,1752790973.687 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-07-17T22:22:53.693Z,1752790973.693 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-07-17T22:22:53.696Z,1752790973.696 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-07-17T22:22:53.708Z,1752790973.708 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-07-17T22:22:53.709Z,1752790973.709 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0 2025-07-17T22:22:53.709Z,1752790973.709 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1374 2025-07-17T22:22:53.713Z,1752790973.713 [Supervisor](INFO): Main Thread ID is 825 2025-07-17T22:22:53.714Z,1752790973.714 [Supervisor](DEBUG): Running supervisor. 2025-07-17T22:22:53.714Z,1752790973.714 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1375 2025-07-17T22:22:53.715Z,1752790973.715 [CommandExec](INFO): Initializing the command executive. 2025-07-17T22:22:53.716Z,1752790973.716 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1376 2025-07-17T22:22:53.719Z,1752790973.719 [controlThread ThreadHandler](INFO): Handler Thread ID is 1377 2025-07-17T22:22:53.719Z,1752790973.719 [controlThread](DEBUG): Initializing ControlThread 2025-07-17T22:22:53.720Z,1752790973.720 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-07-17T22:22:53.720Z,1752790973.720 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-07-17T22:22:53.721Z,1752790973.721 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-07-17T22:22:53.721Z,1752790973.721 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-07-17T22:22:53.721Z,1752790973.721 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-07-17T22:22:53.722Z,1752790973.722 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-07-17T22:22:53.724Z,1752790973.724 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-07-17T22:22:53.724Z,1752790973.724 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-07-17T22:22:53.725Z,1752790973.725 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-07-17T22:22:53.726Z,1752790973.726 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-07-17T22:22:53.726Z,1752790973.726 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-07-17T22:22:53.730Z,1752790973.730 [SBIT](INFO): Initialize SBIT Component. 2025-07-17T22:22:53.731Z,1752790973.731 [SBIT](IMPORTANT): git: 2025-07-16 2025-07-17T22:22:53.731Z,1752790973.731 [SBIT](INFO): git hash: d69d2715ddd00fa751675e8858faf22d094d5d81 2025-07-17T22:22:53.731Z,1752790973.731 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-07-17T22:22:53.732Z,1752790973.732 [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 2025-07-17T22:22:53.733Z,1752790973.733 [SBIT](INFO): Beginning SBIT in 95.000000 seconds. 2025-07-17T22:22:53.734Z,1752790973.734 [IBIT](INFO): Initialize IBIT Component. 2025-07-17T22:22:53.735Z,1752790973.735 [CBIT](DEBUG): Initialize CBIT Component. 2025-07-17T22:22:53.735Z,1752790973.735 [logger ThreadHandler](INFO): Handler Thread ID is 1378 2025-07-17T22:22:53.746Z,1752790973.746 [CBIT](DEBUG): Initialized mux pins. 2025-07-17T22:22:53.746Z,1752790973.746 [CBIT](DEBUG): Initializing the watchdog timer. 2025-07-17T22:22:53.754Z,1752790973.754 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1379 2025-07-17T22:22:53.767Z,1752790973.767 [DAT ThreadHandler](INFO): Handler Thread ID is 1380 2025-07-17T22:22:53.767Z,1752790973.767 [DAT](INFO): Powering up 2025-07-17T22:22:53.767Z,1752790973.767 [DAT](DEBUG): Initializing DAT. 2025-07-17T22:22:53.770Z,1752790973.770 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-07-17T22:22:53.770Z,1752790973.770 [CBIT](DEBUG): Initializing heartbeat. 2025-07-17T22:22:53.772Z,1752790973.772 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1382 2025-07-17T22:22:53.774Z,1752790973.774 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-07-17T22:22:53.783Z,1752790973.783 [Onboard ThreadHandler](INFO): Handler Thread ID is 1383 2025-07-17T22:22:53.800Z,1752790973.800 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1384 2025-07-17T22:22:53.819Z,1752790973.819 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1385 2025-07-17T22:22:53.819Z,1752790973.819 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-07-17T22:22:53.824Z,1752790973.824 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1387 2025-07-17T22:22:53.826Z,1752790973.826 [WetLabsBB2FL](INFO): Powering up 2025-07-17T22:22:53.827Z,1752790973.827 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1389 2025-07-17T22:22:53.830Z,1752790973.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-07-17T22:22:53.830Z,1752790973.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-07-17T22:22:53.830Z,1752790973.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-07-17T22:22:53.830Z,1752790973.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-07-17T22:22:53.831Z,1752790973.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-07-17T22:22:53.831Z,1752790973.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-07-17T22:22:53.831Z,1752790973.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-07-17T22:22:53.831Z,1752790973.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-07-17T22:22:53.842Z,1752790973.842 [CBIT](DEBUG): Deactivating emergency mode. 2025-07-17T22:22:53.882Z,1752790973.882 [CBIT](DEBUG): Backplane powered. 2025-07-17T22:22:53.882Z,1752790973.882 [GFScanner](DEBUG): Initializing GFScanner 2025-07-17T22:22:53.882Z,1752790973.882 [GFScanner](DEBUG): Deactivating GF circuits. 2025-07-17T22:22:53.887Z,1752790973.887 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-07-17T22:22:53.888Z,1752790973.888 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-07-17T22:22:53.888Z,1752790973.888 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-07-17T22:22:53.889Z,1752790973.889 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-07-17T22:22:53.953Z,1752790973.953 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-07-17T22:22:53.957Z,1752790973.957 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-17T22:22:53.997Z,1752790973.997 [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 } } } 2025-07-17T22:22:54.030Z,1752790974.030 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-07-17T22:22:54.030Z,1752790974.030 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-07-17T22:22:54.031Z,1752790974.031 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-07-17T22:22:54.206Z,1752790974.206 [Radio_Surface](INFO): Powering up 2025-07-17T22:22:54.333Z,1752790974.333 [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 2025-07-17T22:22:54.355Z,1752790974.355 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-07-17T22:22:54.356Z,1752790974.356 [Default:A.Wait](DEBUG): Construct Wait. 2025-07-17T22:22:54.367Z,1752790974.367 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-17T22:22:54.413Z,1752790974.413 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-07-17T22:22:54.416Z,1752790974.416 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-07-17T22:22:54.439Z,1752790974.439 [Default:E.Execute](DEBUG): Construct Execute. 2025-07-17T22:22:54.458Z,1752790974.458 [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" } } 2025-07-17T22:22:54.463Z,1752790974.463 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-07-17T22:22:54.486Z,1752790974.486 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-07-17T22:22:54.547Z,1752790974.547 [Depth_Keller](INFO): Initializing. 2025-07-17T22:22:54.548Z,1752790974.548 [Power24vConverter](INFO): Powering up. 2025-07-17T22:22:54.549Z,1752790974.549 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-07-17T22:22:54.554Z,1752790974.554 [Sonardyne_Nano](INFO): Initializing. 2025-07-17T22:22:54.602Z,1752790974.602 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-17T22:22:54.632Z,1752790974.632 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-07-17T22:22:54.638Z,1752790974.638 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-07-17T22:22:54.639Z,1752790974.639 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-07-17T22:22:54.646Z,1752790974.646 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-07-17T22:22:54.647Z,1752790974.647 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-07-17T22:22:54.654Z,1752790974.654 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-07-17T22:22:54.655Z,1752790974.655 [MassServo](DEBUG): Initializing EZServoServo. 2025-07-17T22:22:54.662Z,1752790974.662 [MassServo](DEBUG): Initializing MassServo. 2025-07-17T22:22:54.663Z,1752790974.663 [RudderServo](DEBUG): Initializing EZServoServo. 2025-07-17T22:22:54.670Z,1752790974.670 [RudderServo](DEBUG): Initializing RudderServo. 2025-07-17T22:22:54.671Z,1752790974.671 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-07-17T22:22:54.678Z,1752790974.678 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-07-17T22:22:55.935Z,1752790975.935 [WetLabsBB2FL](INFO): Powering down 2025-07-17T22:22:56.492Z,1752790976.492 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-07-17T22:22:58.150Z,1752790978.150 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207 2025-07-17T22:22:58.553Z,1752790978.553 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-07-17T22:23:05.863Z,1752790985.863 [DAT](INFO): DAT read: 2025-07-17T22:23:05.864Z,1752790985.864 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-07-17T22:23:07.631Z,1752790987.631 [DAT](INFO): DAT read: MF Frequency Band 2025-07-17T22:23:07.632Z,1752790987.632 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-07-17T22:23:07.633Z,1752790987.633 [DAT](INFO): DAT read: Jul 17 2025 22:23:02 2025-07-17T22:23:08.894Z,1752790988.894 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-07-17T22:23:08.898Z,1752790988.898 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-07-17T22:23:08.900Z,1752790988.900 [DAT](INFO): commRate: 600 2025-07-17T22:23:10.966Z,1752790990.966 [DAT](INFO): entering command mode 2025-07-17T22:23:11.162Z,1752790991.162 [DAT](INFO): DAT read: 2025-07-17T22:23:11.163Z,1752790991.163 [DAT](INFO): DAT read: user:1> 2025-07-17T22:23:11.163Z,1752790991.163 [DAT](INFO): setting verbose to 3 2025-07-17T22:23:11.415Z,1752790991.415 [DAT](INFO): DAT read: user:1> 2025-07-17T22:23:11.416Z,1752790991.416 [DAT](INFO): DAT read: Verbose | 3 2025-07-17T22:23:11.416Z,1752790991.416 [DAT](INFO): set verbose to 3 2025-07-17T22:23:11.417Z,1752790991.417 [DAT](INFO): setting DatVerbose to 27440 2025-07-17T22:23:11.667Z,1752790991.667 [DAT](INFO): DAT read: user:2> 2025-07-17T22:23:11.668Z,1752790991.668 [DAT](INFO): DAT read: DatVerbose | 27440 2025-07-17T22:23:11.668Z,1752790991.668 [DAT](INFO): set DatVerbose to 27440 2025-07-17T22:23:11.669Z,1752790991.669 [DAT](INFO): setting transmit power to 8 2025-07-17T22:23:11.920Z,1752790991.920 [DAT](INFO): DAT read: user:3> 2025-07-17T22:23:11.921Z,1752790991.921 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-07-17T22:23:11.922Z,1752790991.922 [DAT](INFO): set transmit power to 8 2025-07-17T22:23:11.922Z,1752790991.922 [DAT](INFO): setting local address to 11 2025-07-17T22:23:12.171Z,1752790992.171 [DAT](INFO): DAT read: user:4> 2025-07-17T22:23:12.172Z,1752790992.172 [DAT](INFO): DAT read: LocalAddr | 11 2025-07-17T22:23:12.172Z,1752790992.172 [DAT](INFO): set local address to 11 2025-07-17T22:23:12.173Z,1752790992.173 [DAT](INFO): Setting time to: 22:23:12 And date to:7/17/2025 2025-07-17T22:23:12.423Z,1752790992.423 [DAT](INFO): DAT read: user:5> 2025-07-17T22:23:12.424Z,1752790992.424 [DAT](INFO): DAT read: Thu Jul 17, 2025 22:23:12 2025-07-17T22:23:12.424Z,1752790992.424 [DAT](INFO): Local DAT time set to Thu Jul 17, 2025 22:23:12 2025-07-17T22:23:22.383Z,1752791002.383 [NAL9602](INFO): Powering up NAL9602 2025-07-17T22:23:33.291Z,1752791013.291 [NAL9602](INFO): NAL9602 initialized 2025-07-17T22:24:29.465Z,1752791069.465 [SBIT](IMPORTANT): Beginning Startup BIT 2025-07-17T22:24:29.470Z,1752791069.470 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-17T22:24:34.031Z,1752791074.031 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004070 CHAN A1 (24V): -0.001599 CHAN A2 (12V): -0.000434 CHAN A3 (5V): 0.000151 CHAN B0 (3.3V): 0.000863 CHAN B1 (3.15aV): 0.000583 CHAN B2 (3.15bV): 0.000325 CHAN B3 (GND): 0.000484 OPEN: 0.005392 Full Scale: +/- 1 mA 2025-07-17T22:24:59.017Z,1752791099.017 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,-3276,V 2025-07-17T22:25:19.237Z,1752791119.237 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0.00, +0.0 +0.00, 20.00,130.00 2025-07-17T22:25:23.305Z,1752791123.305 [SBIT](IMPORTANT): SBIT PASSED 2025-07-17T22:25:23.305Z,1752791123.305 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-07-17T22:25:23.306Z,1752791123.306 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2025-07-17T22:25:23.306Z,1752791123.306 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=207.059715 cubic_centimeter; 2025-07-17T22:25:23.306Z,1752791123.306 [SBIT](IMPORTANT): VerticalControl.massDefault=18.810418 millimeter; 2025-07-17T22:25:23.676Z,1752791123.676 [MissionManager](IMPORTANT): Started mission Startup 2025-07-17T22:25:23.676Z,1752791123.676 [Startup] Running Loop=1 2025-07-17T22:25:23.676Z,1752791123.676 [Startup](DEBUG): Aggregate::initialize Startup 2025-07-17T22:25:23.676Z,1752791123.676 [Startup:A.GoToSurface] Running Loop=1 2025-07-17T22:25:23.676Z,1752791123.676 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-17T22:25:23.677Z,1752791123.677 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-07-17T22:25:23.677Z,1752791123.677 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-07-17T22:25:23.678Z,1752791123.678 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-07-17T22:25:23.678Z,1752791123.678 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-07-17T22:25:23.679Z,1752791123.679 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-17T22:25:23.679Z,1752791123.679 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-17T22:25:23.681Z,1752791123.681 [Startup:StartupSatComms] Running Loop=1 2025-07-17T22:25:23.681Z,1752791123.681 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-07-17T22:25:23.681Z,1752791123.681 [Startup:StartupSatComms:A] Running Loop=1 2025-07-17T22:25:24.080Z,1752791124.080 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-07-17T22:25:30.903Z,1752791130.903 [DataOverHttps](IMPORTANT): SBD MTMSN=20250717T222529 2025-07-17T22:25:30.914Z,1752791130.914 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.011621 2025-07-17T22:25:38.502Z,1752791138.502 [DataOverHttps](INFO): Received command: strobe off 2025-07-17T22:25:38.515Z,1752791138.515 [CommandExec](IMPORTANT): got command strobe off 2025-07-17T22:25:38.515Z,1752791138.515 [CommandExec](IMPORTANT): Deactivating strobe 2025-07-17T22:25:53.990Z,1752791153.990 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-07-17T22:25:53.990Z,1752791153.990 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-17T22:25:54.001Z,1752791154.001 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-17T22:25:54.403Z,1752791154.403 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-17T22:25:54.403Z,1752791154.403 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-07-17T22:26:23.872Z,1752791183.872 [Startup:StartupSatComms:A](INFO): Timed out from 2025-07-17T22:25:23.7Z 2025-07-17T22:26:23.873Z,1752791183.873 [Startup:StartupSatComms:A] Stopped 2025-07-17T22:26:23.873Z,1752791183.873 [Startup:StartupSatComms:B] Running Loop=1 2025-07-17T22:26:24.296Z,1752791184.296 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-17T22:26:30.770Z,1752791190.770 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-17T22:26:31.526Z,1752791191.526 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250717T221623/Courier0004.lzma 2025-07-17T22:26:32.528Z,1752791192.528 [DataOverHttps](INFO): Moved sent file to Logs/20250717T221623/Courier0004.lzma.bak 2025-07-17T22:26:32.528Z,1752791192.528 [DataOverHttps](INFO): SBD MOMSN=25542891 2025-07-17T22:26:35.174Z,1752791195.174 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:26:48.105Z,1752791208.105 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:26:48.500Z,1752791208.500 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250717T222242/Courier0000.lzma 2025-07-17T22:26:49.500Z,1752791209.500 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Courier0000.lzma.bak 2025-07-17T22:26:49.500Z,1752791209.500 [DataOverHttps](INFO): SBD MOMSN=25542893 2025-07-17T22:27:01.036Z,1752791221.036 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:27:05.470Z,1752791225.470 [DataOverHttps](INFO): Sending 337 bytes from file Logs/20250717T221623/Express0005.lzma 2025-07-17T22:27:06.472Z,1752791226.472 [DataOverHttps](INFO): Moved sent file to Logs/20250717T221623/Express0005.lzma.bak 2025-07-17T22:27:06.472Z,1752791226.472 [DataOverHttps](INFO): SBD MOMSN=25542898 2025-07-17T22:27:13.968Z,1752791233.968 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:27:22.427Z,1752791242.427 [DataOverHttps](INFO): Sending 892 bytes from file Logs/20250717T222242/Express0001.lzma 2025-07-17T22:27:23.428Z,1752791243.428 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0001.lzma.bak 2025-07-17T22:27:23.428Z,1752791243.428 [DataOverHttps](INFO): SBD MOMSN=25542912 2025-07-17T22:27:24.084Z,1752791244.084 [Startup:StartupSatComms:B](INFO): Timed out from 2025-07-17T22:26:23.9Z 2025-07-17T22:27:24.084Z,1752791244.084 [Startup:StartupSatComms:B] Stopped 2025-07-17T22:27:24.084Z,1752791244.084 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-07-17T22:27:24.084Z,1752791244.084 [Startup:StartupSatComms] Stopped 2025-07-17T22:27:24.084Z,1752791244.084 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-07-17T22:27:24.085Z,1752791244.085 [Startup](INFO): Completed Startup 2025-07-17T22:27:24.085Z,1752791244.085 [MissionManager](INFO): Startup is completed. 2025-07-17T22:27:24.085Z,1752791244.085 [MissionManager](INFO): Uninitializing Mission Startup 2025-07-17T22:27:24.086Z,1752791244.086 [Startup] Stopped 2025-07-17T22:27:24.086Z,1752791244.086 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-07-17T22:27:24.087Z,1752791244.087 [Startup:A.GoToSurface] Stopped 2025-07-17T22:27:24.087Z,1752791244.087 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-07-17T22:27:24.488Z,1752791244.488 [MissionManager](IMPORTANT): Started mission Default 2025-07-17T22:27:24.488Z,1752791244.488 [Default] Running Loop=1 2025-07-17T22:27:24.488Z,1752791244.488 [Default](DEBUG): Aggregate::initialize Default 2025-07-17T22:27:24.489Z,1752791244.489 [Default:B.GoToSurface] Running Loop=1 2025-07-17T22:27:24.489Z,1752791244.489 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-17T22:27:24.489Z,1752791244.489 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-07-17T22:27:24.489Z,1752791244.489 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-07-17T22:27:24.489Z,1752791244.489 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-07-17T22:27:24.490Z,1752791244.490 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-07-17T22:27:24.490Z,1752791244.490 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-17T22:27:24.491Z,1752791244.491 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-17T22:27:24.491Z,1752791244.491 [Default:A.Wait] Running Loop=1 2025-07-17T22:27:24.491Z,1752791244.491 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-07-17T22:27:26.944Z,1752791246.944 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:27:37.831Z,1752791257.831 [Default:A.Wait](INFO): Done Waiting. 2025-07-17T22:27:37.831Z,1752791257.831 [Default:A.Wait] Stopped 2025-07-17T22:27:37.831Z,1752791257.831 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-07-17T22:27:38.225Z,1752791258.225 [Default:CheckIn] Running Loop=1 2025-07-17T22:27:38.225Z,1752791258.225 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-17T22:27:38.225Z,1752791258.225 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-17T22:27:38.628Z,1752791258.628 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-07-17T22:27:39.824Z,1752791259.824 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:27:52.752Z,1752791272.752 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:28:05.681Z,1752791285.681 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:28:18.608Z,1752791298.608 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:28:31.536Z,1752791311.536 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:28:42.048Z,1752791322.048 [NAL9602](INFO): SBD MO Status=2, MOMSN=12894, MT Status=2, MTMSN=0 2025-07-17T22:28:42.049Z,1752791322.049 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-17T22:28:42.451Z,1752791322.451 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-17T22:28:44.466Z,1752791324.466 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-07-17T22:28:55.023Z,1752791335.023 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-07-17T22:28:55.023Z,1752791335.023 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-17T22:28:55.034Z,1752791335.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-17T22:28:55.431Z,1752791335.431 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-17T22:28:55.431Z,1752791335.431 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-07-17T22:28:57.805Z,1752791337.805 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222857.00,A,3648.16859,N,12147.25565,W,2.352,105.92,170725,,,A*71 2025-07-17T22:28:57.828Z,1752791337.828 [NAL9602](INFO): GPS fix at 20250717T222857: (36.802810, -121.787594) 2025-07-17T22:28:57.839Z,1752791337.839 [Default:CheckIn:Read_GPS] Stopped 2025-07-17T22:28:57.839Z,1752791337.839 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-17T22:28:58.311Z,1752791338.311 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-17T22:29:06.115Z,1752791346.115 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20250717T222242/Courier0007.lzma 2025-07-17T22:29:07.116Z,1752791347.116 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Courier0007.lzma.bak 2025-07-17T22:29:07.116Z,1752791347.116 [DataOverHttps](INFO): SBD MOMSN=25542932 2025-07-17T22:29:24.549Z,1752791364.549 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250717T222242/Express0005.lzma 2025-07-17T22:29:25.551Z,1752791365.551 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0005.lzma.bak 2025-07-17T22:29:25.552Z,1752791365.552 [DataOverHttps](INFO): SBD MOMSN=25542935 2025-07-17T22:29:30.536Z,1752791370.536 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-17T22:29:34.123Z,1752791374.123 [DataOverHttps](IMPORTANT): SBD MTMSN=20250717T222933 2025-07-17T22:29:41.738Z,1752791381.738 [DataOverHttps](INFO): Sending 397 bytes from file Logs/20250717T222242/Express0008.lzma 2025-07-17T22:29:41.741Z,1752791381.741 [DataOverHttps](INFO): Received command: failc 2025-07-17T22:29:41.746Z,1752791381.746 [CommandExec](IMPORTANT): got command failComponent 2025-07-17T22:29:41.747Z,1752791381.747 [CommandExec](IMPORTANT): Failed components: 2025-07-17T22:29:41.747Z,1752791381.747 [CommandExec](IMPORTANT): No failed Components. 2025-07-17T22:29:42.964Z,1752791382.964 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0008.lzma.bak 2025-07-17T22:29:42.964Z,1752791382.964 [DataOverHttps](IMPORTANT): SBD MOMSN=25542939, MTMSN=20250717T222941 2025-07-17T22:29:50.382Z,1752791390.382 [DataOverHttps](INFO): Received command: ibit 2025-07-17T22:29:50.487Z,1752791390.487 [CommandExec](IMPORTANT): got command ibit 2025-07-17T22:29:50.754Z,1752791390.754 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-07-17T22:29:50.754Z,1752791390.754 [IBIT](IMPORTANT): Beginning control surface checks. 2025-07-17T22:29:50.758Z,1752791390.758 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-17T22:29:52.352Z,1752791392.352 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222951.00,A,3648.14233,N,12147.24903,W,0.078,99.84,170725,,,A*45 2025-07-17T22:29:52.355Z,1752791392.355 [NAL9602](INFO): GPS fix at 20250717T222951: (36.802372, -121.787484) 2025-07-17T22:29:55.431Z,1752791395.431 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005123 CHAN A1 (24V): -0.001446 CHAN A2 (12V): -0.000005 CHAN A3 (5V): 0.000473 CHAN B0 (3.3V): 0.000331 CHAN B1 (3.15aV): 0.000185 CHAN B2 (3.15bV): 0.000283 CHAN B3 (GND): 0.000526 OPEN: 0.005264 Full Scale: +/- 1 mA 2025-07-17T22:29:59.341Z,1752791399.341 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250717T222242/Courier0010.lzma 2025-07-17T22:30:00.347Z,1752791400.347 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Courier0010.lzma.bak 2025-07-17T22:30:00.347Z,1752791400.347 [DataOverHttps](INFO): SBD MOMSN=25542945 2025-07-17T22:30:17.905Z,1752791417.905 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250717T222242/Courier0013.lzma 2025-07-17T22:30:18.911Z,1752791418.911 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Courier0013.lzma.bak 2025-07-17T22:30:18.912Z,1752791418.912 [DataOverHttps](INFO): SBD MOMSN=25542947 2025-07-17T22:30:35.382Z,1752791435.382 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250717T222242/Express0011.lzma 2025-07-17T22:30:36.384Z,1752791436.384 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0011.lzma.bak 2025-07-17T22:30:36.384Z,1752791436.384 [DataOverHttps](INFO): SBD MOMSN=25542949 2025-07-17T22:30:37.237Z,1752791437.237 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.802372 Longitude: -121.787483 2025-07-17T22:30:37.627Z,1752791437.627 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2025-07-17T22:30:38.021Z,1752791438.021 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-07-17T22:30:38.021Z,1752791438.021 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-07-17T22:30:38.038Z,1752791438.038 [IBIT](IMPORTANT): Pressure:10.273739 PSI 2025-07-17T22:30:38.038Z,1752791438.038 [IBIT](IMPORTANT): Humidity:25.857649 % 2025-07-17T22:30:38.455Z,1752791438.455 [IBIT](IMPORTANT): Vehicle Pitch:0.342653 degrees 2025-07-17T22:30:38.455Z,1752791438.455 [IBIT](IMPORTANT): Vehicle Roll:-3.672810 degrees 2025-07-17T22:30:38.455Z,1752791438.455 [IBIT](IMPORTANT): Vehicle Heading:213.263596 degrees 2025-07-17T22:30:38.831Z,1752791438.831 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-07-17T22:30:38.832Z,1752791438.832 [IBIT](IMPORTANT): buoyancyNeutral: 207.059708 cc 2025-07-17T22:30:38.832Z,1752791438.832 [IBIT](IMPORTANT): massDefault: 1.881042 cm 2025-07-17T22:30:38.832Z,1752791438.832 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2025-07-17T22:30:38.832Z,1752791438.832 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-07-17T22:30:38.833Z,1752791438.833 [IBIT](IMPORTANT): IBIT PASSED 2025-07-17T22:30:52.871Z,1752791452.871 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20250717T222242/Express0014.lzma 2025-07-17T22:30:53.872Z,1752791453.872 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0014.lzma.bak 2025-07-17T22:30:53.872Z,1752791453.872 [DataOverHttps](INFO): SBD MOMSN=25542958 2025-07-17T22:30:54.566Z,1752791454.566 [NAL9602](INFO): SBD MO Status=2, MOMSN=12894, MT Status=2, MTMSN=0 2025-07-17T22:30:54.566Z,1752791454.566 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-17T22:30:55.013Z,1752791455.013 [Default:CheckIn:Read_Iridium] Stopped 2025-07-17T22:30:55.013Z,1752791455.013 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-17T22:30:55.013Z,1752791455.013 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-17T22:31:22.443Z,1752791482.443 [NAL9602](INFO): SBD MO Status=2, MOMSN=12894, MT Status=2, MTMSN=0 2025-07-17T22:31:22.443Z,1752791482.443 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-17T22:31:49.104Z,1752791509.104 [NAL9602](INFO): SBD MO Status=0, MOMSN=12894, MT Status=0, MTMSN=0 2025-07-17T22:31:49.105Z,1752791509.105 [NAL9602](INFO): No messages in MT queue 2025-07-17T22:32:19.811Z,1752791539.811 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-17T22:32:49.855Z,1752791569.855 [DataOverHttps](IMPORTANT): SBD MTMSN=20250717T223248 2025-07-17T22:32:57.553Z,1752791577.553 [DataOverHttps](INFO): Received command: ibit 2025-07-17T22:32:57.563Z,1752791577.563 [CommandExec](IMPORTANT): got command ibit 2025-07-17T22:32:57.823Z,1752791577.823 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-07-17T22:32:57.823Z,1752791577.823 [IBIT](IMPORTANT): Beginning control surface checks. 2025-07-17T22:32:57.830Z,1752791577.830 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-17T22:32:59.408Z,1752791579.408 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223258.00,A,3648.15152,N,12147.22210,W,0.058,22.74,170725,,,A*41 2025-07-17T22:32:59.411Z,1752791579.411 [NAL9602](INFO): GPS fix at 20250717T223258: (36.802525, -121.787035) 2025-07-17T22:33:02.604Z,1752791582.604 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004815 CHAN A1 (24V): -0.002273 CHAN A2 (12V): 0.000187 CHAN A3 (5V): 0.000263 CHAN B0 (3.3V): 0.000839 CHAN B1 (3.15aV): 0.000556 CHAN B2 (3.15bV): 0.000431 CHAN B3 (GND): 0.000805 OPEN: 0.005277 Full Scale: +/- 1 mA 2025-07-17T22:33:45.493Z,1752791625.493 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-17T22:34:05.752Z,1752791645.752 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 1 Latitude: 36.802525 Longitude: -121.787033 2025-07-17T22:34:06.155Z,1752791646.155 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2025-07-17T22:34:06.565Z,1752791646.565 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-07-17T22:34:06.565Z,1752791646.565 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-07-17T22:34:06.565Z,1752791646.565 [IBIT](IMPORTANT): Pressure:10.337743 PSI 2025-07-17T22:34:06.570Z,1752791646.570 [IBIT](IMPORTANT): Humidity:25.741667 % 2025-07-17T22:34:07.040Z,1752791647.040 [IBIT](IMPORTANT): Vehicle Pitch:-1.949079 degrees 2025-07-17T22:34:07.040Z,1752791647.040 [IBIT](IMPORTANT): Vehicle Roll:4.143590 degrees 2025-07-17T22:34:07.041Z,1752791647.041 [IBIT](IMPORTANT): Vehicle Heading:69.454124 degrees 2025-07-17T22:34:07.352Z,1752791647.352 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-07-17T22:34:07.352Z,1752791647.352 [IBIT](IMPORTANT): buoyancyNeutral: 207.059708 cc 2025-07-17T22:34:07.353Z,1752791647.353 [IBIT](IMPORTANT): massDefault: 1.881042 cm 2025-07-17T22:34:07.353Z,1752791647.353 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2025-07-17T22:34:07.353Z,1752791647.353 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-07-17T22:34:07.353Z,1752791647.353 [IBIT](IMPORTANT): IBIT PASSED 2025-07-17T22:34:24.297Z,1752791664.297 [NAL9602](INFO): SBD MO Status=2, MOMSN=12895, MT Status=2, MTMSN=0 2025-07-17T22:34:24.297Z,1752791664.297 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-17T22:35:05.963Z,1752791705.963 [NAL9602](INFO): SBD MO Status=2, MOMSN=12895, MT Status=2, MTMSN=0 2025-07-17T22:35:05.963Z,1752791705.963 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-17T22:35:53.568Z,1752791753.568 [NAL9602](INFO): SBD MO Status=0, MOMSN=12895, MT Status=0, MTMSN=0 2025-07-17T22:35:53.568Z,1752791753.568 [NAL9602](INFO): No messages in MT queue 2025-07-17T22:35:55.596Z,1752791755.596 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-17T22:35:55.596Z,1752791755.596 [Default:CheckIn:C.Wait] Stopped 2025-07-17T22:35:55.596Z,1752791755.596 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-17T22:35:55.596Z,1752791755.596 [Default:CheckIn:D] Running Loop=1 2025-07-17T22:35:56.011Z,1752791756.011 [Default:CheckIn:D] Stopped 2025-07-17T22:35:56.011Z,1752791756.011 [Default:CheckIn:E] Running Loop=1 2025-07-17T22:35:56.415Z,1752791756.415 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.525352 min 2025-07-17T22:35:56.415Z,1752791756.415 [Default:CheckIn:E] Stopped 2025-07-17T22:35:56.415Z,1752791756.415 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-17T22:35:56.415Z,1752791756.415 [Default:CheckIn] Stopped 2025-07-17T22:35:56.415Z,1752791756.415 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-17T22:35:56.415Z,1752791756.415 [Default:CheckIn](INFO): Running loop #2 2025-07-17T22:35:56.415Z,1752791756.415 [Default:CheckIn] Running Loop=2 2025-07-17T22:35:56.415Z,1752791756.415 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-17T22:35:56.415Z,1752791756.415 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-17T22:35:58.423Z,1752791758.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223557.00,A,3648.15091,N,12147.21965,W,0.078,21.90,170725,,,A*46 2025-07-17T22:35:58.425Z,1752791758.425 [NAL9602](INFO): GPS fix at 20250717T223557: (36.802515, -121.786994) 2025-07-17T22:35:58.437Z,1752791758.437 [Default:CheckIn:Read_GPS] Stopped 2025-07-17T22:35:58.437Z,1752791758.437 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-17T22:36:06.581Z,1752791766.581 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250717T222242/Courier0016.lzma 2025-07-17T22:36:07.584Z,1752791767.584 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Courier0016.lzma.bak 2025-07-17T22:36:07.584Z,1752791767.584 [DataOverHttps](INFO): SBD MOMSN=25542971 2025-07-17T22:36:23.617Z,1752791783.617 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250717T222242/Courier0019.lzma 2025-07-17T22:36:24.620Z,1752791784.620 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Courier0019.lzma.bak 2025-07-17T22:36:24.620Z,1752791784.620 [DataOverHttps](INFO): SBD MOMSN=25542980 2025-07-17T22:36:30.331Z,1752791790.331 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-17T22:36:41.148Z,1752791801.148 [DataOverHttps](INFO): Sending 1010 bytes from file Logs/20250717T222242/Express0017.lzma 2025-07-17T22:36:42.148Z,1752791802.148 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0017.lzma.bak 2025-07-17T22:36:42.148Z,1752791802.148 [DataOverHttps](INFO): SBD MOMSN=25542982 2025-07-17T22:36:58.418Z,1752791818.418 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250717T222242/Express0020.lzma 2025-07-17T22:36:59.420Z,1752791819.420 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0020.lzma.bak 2025-07-17T22:36:59.420Z,1752791819.420 [DataOverHttps](INFO): SBD MOMSN=25543025 2025-07-17T22:37:00.639Z,1752791820.639 [Default:CheckIn:Read_Iridium] Stopped 2025-07-17T22:37:00.639Z,1752791820.639 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-17T22:37:00.640Z,1752791820.640 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-17T22:42:01.257Z,1752792121.257 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-17T22:42:01.257Z,1752792121.257 [Default:CheckIn:C.Wait] Stopped 2025-07-17T22:42:01.257Z,1752792121.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-17T22:42:01.257Z,1752792121.257 [Default:CheckIn:D] Running Loop=1 2025-07-17T22:42:01.647Z,1752792121.647 [Default:CheckIn:D] Stopped 2025-07-17T22:42:01.647Z,1752792121.647 [Default:CheckIn:E] Running Loop=1 2025-07-17T22:42:02.031Z,1752792122.031 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.619303 min 2025-07-17T22:42:02.031Z,1752792122.031 [Default:CheckIn:E] Stopped 2025-07-17T22:42:02.031Z,1752792122.031 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-17T22:42:02.031Z,1752792122.031 [Default:CheckIn] Stopped 2025-07-17T22:42:02.031Z,1752792122.031 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-17T22:42:02.031Z,1752792122.031 [Default:CheckIn](INFO): Running loop #3 2025-07-17T22:42:02.031Z,1752792122.031 [Default:CheckIn] Running Loop=3 2025-07-17T22:42:02.031Z,1752792122.031 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-17T22:42:02.031Z,1752792122.031 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-17T22:42:04.044Z,1752792124.044 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224203.00,A,3648.16403,N,12147.20433,W,1.575,84.54,170725,,,A*4A 2025-07-17T22:42:04.047Z,1752792124.047 [NAL9602](INFO): GPS fix at 20250717T224203: (36.802734, -121.786739) 2025-07-17T22:42:04.058Z,1752792124.058 [Default:CheckIn:Read_GPS] Stopped 2025-07-17T22:42:04.058Z,1752792124.058 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-17T22:42:12.237Z,1752792132.237 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250717T222242/Courier0022.lzma 2025-07-17T22:42:13.240Z,1752792133.240 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Courier0022.lzma.bak 2025-07-17T22:42:13.240Z,1752792133.240 [DataOverHttps](INFO): SBD MOMSN=25543040 2025-07-17T22:42:29.573Z,1752792149.573 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250717T222242/Express0023.lzma 2025-07-17T22:42:30.576Z,1752792150.576 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0023.lzma.bak 2025-07-17T22:42:30.576Z,1752792150.576 [DataOverHttps](INFO): SBD MOMSN=25543043 2025-07-17T22:42:31.949Z,1752792151.949 [Default:CheckIn:Read_Iridium] Stopped 2025-07-17T22:42:31.949Z,1752792151.949 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-17T22:42:31.950Z,1752792151.950 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-17T22:42:54.134Z,1752792174.134 [NAL9602](INFO): SBD MO Status=2, MOMSN=12896, MT Status=2, MTMSN=0 2025-07-17T22:42:54.134Z,1752792174.134 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-17T22:43:05.444Z,1752792185.444 [NAL9602](INFO): SBD MO Status=0, MOMSN=12896, MT Status=0, MTMSN=0 2025-07-17T22:43:05.444Z,1752792185.444 [NAL9602](INFO): No messages in MT queue 2025-07-17T22:43:36.151Z,1752792216.151 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-17T22:44:04.861Z,1752792244.861 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-07-17T22:44:26.711Z,1752792266.711 [DataOverHttps](IMPORTANT): SBD MTMSN=20250717T224425 2025-07-17T22:44:34.493Z,1752792274.493 [DataOverHttps](INFO): Received command: ibit 2025-07-17T22:44:34.543Z,1752792274.543 [CommandExec](IMPORTANT): got command ibit 2025-07-17T22:44:34.765Z,1752792274.765 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-07-17T22:44:34.766Z,1752792274.766 [IBIT](IMPORTANT): Beginning control surface checks. 2025-07-17T22:44:34.769Z,1752792274.769 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-17T22:44:36.345Z,1752792276.345 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224435.00,A,3648.31343,N,12147.11654,W,5.151,6.14,170725,,,A*76 2025-07-17T22:44:36.347Z,1752792276.347 [NAL9602](INFO): GPS fix at 20250717T224435: (36.805224, -121.785276) 2025-07-17T22:44:39.478Z,1752792279.478 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004826 CHAN A1 (24V): -0.001797 CHAN A2 (12V): -0.000239 CHAN A3 (5V): 0.000242 CHAN B0 (3.3V): 0.000384 CHAN B1 (3.15aV): 0.000424 CHAN B2 (3.15bV): 0.000235 CHAN B3 (GND): 0.000429 OPEN: 0.005311 Full Scale: +/- 1 mA 2025-07-17T22:44:44.958Z,1752792284.958 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00 2025-07-17T22:45:55.235Z,1752792355.235 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +0 +0.00, +0.00, 0.00,1363.96 2025-07-17T22:46:25.906Z,1752792385.906 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired. 2025-07-17T22:46:26.370Z,1752792386.370 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2025-07-17T22:46:26.752Z,1752792386.752 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-07-17T22:46:26.752Z,1752792386.752 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-07-17T22:46:26.752Z,1752792386.752 [IBIT](IMPORTANT): Pressure:10.339167 PSI 2025-07-17T22:46:26.752Z,1752792386.752 [IBIT](IMPORTANT): Humidity:24.142351 % 2025-07-17T22:46:27.180Z,1752792387.180 [IBIT](IMPORTANT): Vehicle Pitch:1.641062 degrees 2025-07-17T22:46:27.180Z,1752792387.180 [IBIT](IMPORTANT): Vehicle Roll:14.740817 degrees 2025-07-17T22:46:27.180Z,1752792387.180 [IBIT](IMPORTANT): Vehicle Heading:312.424591 degrees 2025-07-17T22:46:27.548Z,1752792387.548 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-07-17T22:46:27.548Z,1752792387.548 [IBIT](IMPORTANT): buoyancyNeutral: 207.059708 cc 2025-07-17T22:46:27.548Z,1752792387.548 [IBIT](IMPORTANT): massDefault: 1.881042 cm 2025-07-17T22:46:27.548Z,1752792387.548 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2025-07-17T22:46:27.549Z,1752792387.549 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-07-17T22:46:27.549Z,1752792387.549 [IBIT](IMPORTANT): IBIT FAILED 2025-07-17T22:47:32.572Z,1752792452.572 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-17T22:47:32.572Z,1752792452.572 [Default:CheckIn:C.Wait] Stopped 2025-07-17T22:47:32.572Z,1752792452.572 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-17T22:47:32.573Z,1752792452.573 [Default:CheckIn:D] Running Loop=1 2025-07-17T22:47:33.008Z,1752792453.008 [Default:CheckIn:D] Stopped 2025-07-17T22:47:33.009Z,1752792453.009 [Default:CheckIn:E] Running Loop=1 2025-07-17T22:47:33.405Z,1752792453.405 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.142000 min 2025-07-17T22:47:33.405Z,1752792453.405 [Default:CheckIn:E] Stopped 2025-07-17T22:47:33.405Z,1752792453.405 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-17T22:47:33.405Z,1752792453.405 [Default:CheckIn] Stopped 2025-07-17T22:47:33.405Z,1752792453.405 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-17T22:47:33.405Z,1752792453.405 [Default:CheckIn](INFO): Running loop #4 2025-07-17T22:47:33.405Z,1752792453.405 [Default:CheckIn] Running Loop=4 2025-07-17T22:47:33.406Z,1752792453.406 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-17T22:47:33.406Z,1752792453.406 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-17T22:48:03.657Z,1752792483.657 [NAL9602](INFO): SBD MO Status=0, MOMSN=12897, MT Status=0, MTMSN=0 2025-07-17T22:48:03.657Z,1752792483.657 [NAL9602](INFO): No messages in MT queue 2025-07-17T22:48:04.935Z,1752792484.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224804.00,A,3648.47484,N,12147.29204,W,6.104,239.26,170725,,,D*76 2025-07-17T22:48:04.937Z,1752792484.937 [NAL9602](INFO): GPS fix at 20250717T224804: (36.807914, -121.788201) 2025-07-17T22:48:05.070Z,1752792485.070 [Default:CheckIn:Read_GPS] Stopped 2025-07-17T22:48:05.070Z,1752792485.070 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-17T22:48:08.733Z,1752792488.733 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250717T222242/Courier0025.lzma 2025-07-17T22:48:09.475Z,1752792489.475 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Courier0025.lzma.bak 2025-07-17T22:48:09.476Z,1752792489.476 [DataOverHttps](INFO): SBD MOMSN=25543056 2025-07-17T22:48:25.959Z,1752792505.959 [DataOverHttps](INFO): Sending 788 bytes from file Logs/20250717T222242/Express0026.lzma 2025-07-17T22:48:28.968Z,1752792508.968 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0026.lzma.bak 2025-07-17T22:48:28.968Z,1752792508.968 [DataOverHttps](INFO): SBD MOMSN=25543065 2025-07-17T22:48:37.687Z,1752792517.687 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-17T22:48:45.129Z,1752792525.129 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250717T222242/Express0029.lzma 2025-07-17T22:48:46.132Z,1752792526.132 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0029.lzma.bak 2025-07-17T22:48:46.132Z,1752792526.132 [DataOverHttps](INFO): SBD MOMSN=25543090 2025-07-17T22:48:47.387Z,1752792527.387 [Default:CheckIn:Read_Iridium] Stopped 2025-07-17T22:48:47.387Z,1752792527.387 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-17T22:48:47.387Z,1752792527.387 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-17T22:50:36.504Z,1752792636.504 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-17T22:53:47.999Z,1752792827.999 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-17T22:53:47.999Z,1752792827.999 [Default:CheckIn:C.Wait] Stopped 2025-07-17T22:53:47.999Z,1752792827.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-17T22:53:47.999Z,1752792827.999 [Default:CheckIn:D] Running Loop=1 2025-07-17T22:53:48.402Z,1752792828.402 [Default:CheckIn:D] Stopped 2025-07-17T22:53:48.402Z,1752792828.402 [Default:CheckIn:E] Running Loop=1 2025-07-17T22:53:48.820Z,1752792828.820 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.398560 min 2025-07-17T22:53:48.820Z,1752792828.820 [Default:CheckIn:E] Stopped 2025-07-17T22:53:48.820Z,1752792828.820 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-17T22:53:48.820Z,1752792828.820 [Default:CheckIn] Stopped 2025-07-17T22:53:48.820Z,1752792828.820 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-17T22:53:48.820Z,1752792828.820 [Default:CheckIn](INFO): Running loop #5 2025-07-17T22:53:48.820Z,1752792828.820 [Default:CheckIn] Running Loop=5 2025-07-17T22:53:48.820Z,1752792828.820 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-17T22:53:48.820Z,1752792828.820 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-17T22:53:50.805Z,1752792830.805 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225350.00,A,3648.16736,N,12148.52775,W,14.540,248.70,170725,,,D*41 2025-07-17T22:53:50.815Z,1752792830.815 [NAL9602](INFO): GPS fix at 20250717T225350: (36.802789, -121.808796) 2025-07-17T22:53:50.827Z,1752792830.827 [Default:CheckIn:Read_GPS] Stopped 2025-07-17T22:53:50.827Z,1752792830.827 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-17T22:53:54.843Z,1752792834.843 [BPC1](ERROR): Battery stick #5 (s/n: 00C2) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-17T22:53:54.844Z,1752792834.844 [BPC1](ERROR): Battery stick #8 (s/n: 027C) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0. 2025-07-17T22:53:54.845Z,1752792834.845 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-17T22:53:54.848Z,1752792834.848 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-17T22:53:54.850Z,1752792834.850 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0. 2025-07-17T22:53:54.851Z,1752792834.851 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-17T22:53:54.852Z,1752792834.852 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-17T22:53:54.853Z,1752792834.853 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-17T22:53:54.853Z,1752792834.853 [BPC1](INFO): Calculating totals. Valid battery stick count: 34. Valid reserve battery stick count: 2. 2025-07-17T22:53:54.857Z,1752792834.857 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-07-17T22:53:58.753Z,1752792838.753 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250717T222242/Courier0031.lzma 2025-07-17T22:53:59.756Z,1752792839.756 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Courier0031.lzma.bak 2025-07-17T22:53:59.756Z,1752792839.756 [DataOverHttps](INFO): SBD MOMSN=25543105 2025-07-17T22:54:16.438Z,1752792856.438 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20250717T222242/Express0032.lzma 2025-07-17T22:54:17.440Z,1752792857.440 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Express0032.lzma.bak 2025-07-17T22:54:17.440Z,1752792857.440 [DataOverHttps](INFO): SBD MOMSN=25543108 2025-07-17T22:54:18.704Z,1752792858.704 [Default:CheckIn:Read_Iridium] Stopped 2025-07-17T22:54:18.704Z,1752792858.704 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-17T22:54:18.704Z,1752792858.704 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-17T22:55:59.301Z,1752792959.301 [NAL9602](INFO): SBD MO Status=2, MOMSN=12898, MT Status=2, MTMSN=0 2025-07-17T22:55:59.301Z,1752792959.301 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-17T22:56:44.948Z,1752793004.948 [NAL9602](INFO): SBD MO Status=2, MOMSN=12898, MT Status=2, MTMSN=0 2025-07-17T22:56:44.949Z,1752793004.949 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-17T22:56:53.840Z,1752793013.840 [NAL9602](INFO): SBD MO Status=0, MOMSN=12898, MT Status=0, MTMSN=0 2025-07-17T22:56:53.840Z,1752793013.840 [NAL9602](INFO): No messages in MT queue 2025-07-17T22:57:24.539Z,1752793044.539 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-17T22:59:19.304Z,1752793159.304 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-17T22:59:19.304Z,1752793159.304 [Default:CheckIn:C.Wait] Stopped 2025-07-17T22:59:19.304Z,1752793159.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-17T22:59:19.304Z,1752793159.304 [Default:CheckIn:D] Running Loop=1 2025-07-17T22:59:19.696Z,1752793159.696 [Default:CheckIn:D] Stopped 2025-07-17T22:59:19.696Z,1752793159.696 [Default:CheckIn:E] Running Loop=1 2025-07-17T22:59:20.110Z,1752793160.110 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.920119 min 2025-07-17T22:59:20.110Z,1752793160.110 [Default:CheckIn:E] Stopped 2025-07-17T22:59:20.110Z,1752793160.110 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-17T22:59:20.110Z,1752793160.110 [Default:CheckIn] Stopped 2025-07-17T22:59:20.111Z,1752793160.111 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-17T22:59:20.111Z,1752793160.111 [Default:CheckIn](INFO): Running loop #6 2025-07-17T22:59:20.111Z,1752793160.111 [Default:CheckIn] Running Loop=6 2025-07-17T22:59:20.111Z,1752793160.111 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-17T22:59:20.111Z,1752793160.111 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-17T22:59:22.104Z,1752793162.104 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225921.00,A,3647.93003,N,12148.89240,W,0.253,152.36,170725,,,D*71 2025-07-17T22:59:22.107Z,1752793162.107 [NAL9602](INFO): GPS fix at 20250717T225921: (36.798834, -121.814873) 2025-07-17T22:59:22.120Z,1752793162.120 [Default:CheckIn:Read_GPS] Stopped 2025-07-17T22:59:22.120Z,1752793162.120 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-17T22:59:30.242Z,1752793170.242 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20250717T222242/Courier0034.lzma 2025-07-17T22:59:31.244Z,1752793171.244 [DataOverHttps](INFO): Moved sent file to Logs/20250717T222242/Courier0034.lzma.bak 2025-07-17T22:59:31.244Z,1752793171.244 [DataOverHttps](IMPORTANT): SBD MOMSN=25543139, MTMSN=20250717T225930 2025-07-17T22:59:38.925Z,1752793178.925 [DataOverHttps](INFO): Received command: restart logs