2022-10-18T15:25:50.057Z,1666106750.057 [Supervisor](DEBUG): Initializing supervisor.
2022-10-18T15:25:50.062Z,1666106750.062 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-10-18T15:25:50.062Z,1666106750.062 [SyncHandler](INFO): Protected caller Thread ID is 1032
2022-10-18T15:25:50.063Z,1666106750.063 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-10-18T15:25:50.064Z,1666106750.064 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-10-18T15:25:50.064Z,1666106750.064 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1033
2022-10-18T15:25:50.068Z,1666106750.068 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-10-18T15:25:50.087Z,1666106750.087 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-10-18T15:25:50.088Z,1666106750.088 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-10-18T15:25:50.088Z,1666106750.088 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1034
2022-10-18T15:25:50.093Z,1666106750.093 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-10-18T15:25:50.094Z,1666106750.094 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-10-18T15:25:50.094Z,1666106750.094 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1035
2022-10-18T15:25:50.096Z,1666106750.096 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-10-18T15:25:50.097Z,1666106750.097 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-10-18T15:25:50.098Z,1666106750.098 [logger ThreadHandler](INFO): Protected caller Thread ID is 1036
2022-10-18T15:25:50.101Z,1666106750.101 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-10-18T15:25:50.102Z,1666106750.102 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-10-18T15:25:50.103Z,1666106750.103 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-10-18T15:25:50.197Z,1666106750.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-10-18T15:25:50.198Z,1666106750.198 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-10-18T15:25:50.814Z,1666106750.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-10-18T15:25:50.815Z,1666106750.815 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-10-18T15:25:51.025Z,1666106751.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-10-18T15:25:51.026Z,1666106751.026 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-10-18T15:25:51.104Z,1666106751.104 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-10-18T15:25:51.232Z,1666106751.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-10-18T15:25:51.233Z,1666106751.233 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-10-18T15:25:51.313Z,1666106751.313 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-10-18T15:25:51.419Z,1666106751.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-10-18T15:25:51.419Z,1666106751.419 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-10-18T15:25:51.772Z,1666106751.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-10-18T15:25:51.773Z,1666106751.773 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-10-18T15:25:52.239Z,1666106752.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-10-18T15:25:52.239Z,1666106752.239 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-10-18T15:25:52.448Z,1666106752.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-10-18T15:25:52.448Z,1666106752.448 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-10-18T15:25:52.626Z,1666106752.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-10-18T15:25:52.627Z,1666106752.627 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-10-18T15:25:53.081Z,1666106753.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-10-18T15:25:53.081Z,1666106753.081 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-10-18T15:25:53.354Z,1666106753.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-10-18T15:25:53.355Z,1666106753.355 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-10-18T15:25:53.560Z,1666106753.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-10-18T15:25:53.561Z,1666106753.561 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-10-18T15:25:53.910Z,1666106753.910 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-10-18T15:25:53.911Z,1666106753.911 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-10-18T15:25:54.230Z,1666106754.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-10-18T15:25:54.232Z,1666106754.232 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2022-10-18T15:25:54.233Z,1666106754.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2022-10-18T15:25:54.316Z,1666106754.316 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2022-10-18T15:25:54.494Z,1666106754.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2022-10-18T15:25:54.775Z,1666106754.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2022-10-18T15:25:54.930Z,1666106754.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2022-10-18T15:25:55.061Z,1666106755.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2022-10-18T15:25:55.385Z,1666106755.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2022-10-18T15:25:55.789Z,1666106755.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-10-18T15:25:55.790Z,1666106755.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2022-10-18T15:25:55.882Z,1666106755.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2022-10-18T15:25:55.979Z,1666106755.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2022-10-18T15:25:56.114Z,1666106756.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2022-10-18T15:25:56.212Z,1666106756.212 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2022-10-18T15:25:56.212Z,1666106756.212 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-10-18T15:25:56.227Z,1666106756.227 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-10-18T15:25:56.322Z,1666106756.322 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-10-18T15:25:56.322Z,1666106756.322 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-10-18T15:25:56.372Z,1666106756.372 [VerticalControl](DEBUG): Construct VerticalControl.
2022-10-18T15:25:56.431Z,1666106756.431 [VerticalControl] Loaded
2022-10-18T15:25:56.432Z,1666106756.432 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-10-18T15:25:56.434Z,1666106756.434 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-10-18T15:25:56.476Z,1666106756.476 [HorizontalControl] Loaded
2022-10-18T15:25:56.477Z,1666106756.477 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-10-18T15:25:56.479Z,1666106756.479 [SpeedControl](DEBUG): Construct SpeedControl.
2022-10-18T15:25:56.482Z,1666106756.482 [SpeedControl] Loaded
2022-10-18T15:25:56.482Z,1666106756.482 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-10-18T15:25:56.485Z,1666106756.485 [LoopControl](DEBUG): Construct LoopControl.
2022-10-18T15:25:56.485Z,1666106756.485 [LoopControl] Loaded
2022-10-18T15:25:56.486Z,1666106756.486 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-10-18T15:25:56.486Z,1666106756.486 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-10-18T15:25:56.487Z,1666106756.487 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-10-18T15:25:56.537Z,1666106756.537 [DepthRateCalculator] Loaded
2022-10-18T15:25:56.538Z,1666106756.538 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-10-18T15:25:56.543Z,1666106756.543 [PitchRateCalculator] Loaded
2022-10-18T15:25:56.543Z,1666106756.543 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-10-18T15:25:56.553Z,1666106756.553 [SpeedCalculator] Loaded
2022-10-18T15:25:56.553Z,1666106756.553 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-10-18T15:25:56.558Z,1666106756.558 [YawRateCalculator] Loaded
2022-10-18T15:25:56.558Z,1666106756.558 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-10-18T15:25:56.576Z,1666106756.576 [ElevatorOffsetCalculator] Loaded
2022-10-18T15:25:56.576Z,1666106756.576 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-10-18T15:25:56.577Z,1666106756.577 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-10-18T15:25:56.577Z,1666106756.577 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-10-18T15:25:56.603Z,1666106756.603 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-10-18T15:25:56.604Z,1666106756.604 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-10-18T15:25:56.659Z,1666106756.659 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-10-18T15:25:56.660Z,1666106756.660 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-10-18T15:25:56.968Z,1666106756.968 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-10-18T15:25:56.968Z,1666106756.968 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-10-18T15:25:57.118Z,1666106757.118 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-10-18T15:25:57.118Z,1666106757.118 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-10-18T15:25:57.526Z,1666106757.526 [AHRS_M2] Loaded
2022-10-18T15:25:57.526Z,1666106757.526 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-10-18T15:25:57.560Z,1666106757.560 [BackseatComponent] Loaded
2022-10-18T15:25:57.561Z,1666106757.561 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-10-18T15:25:57.562Z,1666106757.562 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409644E0
2022-10-18T15:25:57.562Z,1666106757.562 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1118
2022-10-18T15:25:57.565Z,1666106757.565 [LcmUniversalReporter] Loaded
2022-10-18T15:25:57.565Z,1666106757.565 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-10-18T15:25:58.352Z,1666106758.352 [BPC1] Loaded
2022-10-18T15:25:58.352Z,1666106758.352 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-10-18T15:25:58.432Z,1666106758.432 [DataOverHttps] Loaded
2022-10-18T15:25:58.432Z,1666106758.432 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-10-18T15:25:58.433Z,1666106758.433 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409944E0
2022-10-18T15:25:58.434Z,1666106758.434 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1119
2022-10-18T15:25:58.454Z,1666106758.454 [Depth_Keller] Loaded
2022-10-18T15:25:58.455Z,1666106758.455 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-10-18T15:25:58.459Z,1666106758.459 [DropWeight] Loaded
2022-10-18T15:25:58.460Z,1666106758.460 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-10-18T15:25:58.521Z,1666106758.521 [NAL9602] Loaded
2022-10-18T15:25:58.521Z,1666106758.521 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-10-18T15:25:58.550Z,1666106758.550 [Onboard] Loaded
2022-10-18T15:25:58.550Z,1666106758.550 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-10-18T15:25:58.551Z,1666106758.551 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409C44E0
2022-10-18T15:25:58.552Z,1666106758.552 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1120
2022-10-18T15:25:58.564Z,1666106758.564 [Power24vConverter] Loaded
2022-10-18T15:25:58.564Z,1666106758.564 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-10-18T15:25:58.577Z,1666106758.577 [Radio_Surface] Loaded
2022-10-18T15:25:58.577Z,1666106758.577 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-10-18T15:25:58.578Z,1666106758.578 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409F44E0
2022-10-18T15:25:58.579Z,1666106758.579 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1121
2022-10-18T15:25:58.624Z,1666106758.624 [RDI_Pathfinder] Loaded
2022-10-18T15:25:58.624Z,1666106758.624 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2022-10-18T15:25:58.714Z,1666106758.714 [DAT] Loaded
2022-10-18T15:25:58.714Z,1666106758.714 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-10-18T15:25:58.715Z,1666106758.715 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A244E0
2022-10-18T15:25:58.715Z,1666106758.715 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1122
2022-10-18T15:25:58.716Z,1666106758.716 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-10-18T15:25:58.716Z,1666106758.716 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-10-18T15:25:58.828Z,1666106758.828 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-10-18T15:25:58.828Z,1666106758.828 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-10-18T15:25:58.842Z,1666106758.842 [NavChart] Loaded
2022-10-18T15:25:58.842Z,1666106758.842 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-10-18T15:25:58.848Z,1666106758.848 [UniversalFixResidualReporter] Loaded
2022-10-18T15:25:58.848Z,1666106758.848 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-10-18T15:25:58.849Z,1666106758.849 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-10-18T15:25:58.849Z,1666106758.849 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-10-18T15:25:58.988Z,1666106758.988 [SBIT](DEBUG): Construct Startup Built In Test.
2022-10-18T15:25:58.997Z,1666106758.997 [SBIT] Loaded
2022-10-18T15:25:58.998Z,1666106758.998 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-10-18T15:25:59.001Z,1666106759.001 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-10-18T15:25:59.013Z,1666106759.013 [IBIT] Loaded
2022-10-18T15:25:59.014Z,1666106759.014 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-10-18T15:25:59.019Z,1666106759.019 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-10-18T15:25:59.125Z,1666106759.125 [CBIT] Loaded
2022-10-18T15:25:59.125Z,1666106759.125 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-10-18T15:25:59.126Z,1666106759.126 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-10-18T15:25:59.126Z,1666106759.126 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-10-18T15:25:59.248Z,1666106759.248 [BuoyancyServo] Loaded
2022-10-18T15:25:59.248Z,1666106759.248 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-10-18T15:25:59.270Z,1666106759.270 [ElevatorServo] Loaded
2022-10-18T15:25:59.270Z,1666106759.270 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-10-18T15:25:59.290Z,1666106759.290 [MassServo] Loaded
2022-10-18T15:25:59.290Z,1666106759.290 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-10-18T15:25:59.309Z,1666106759.309 [RudderServo] Loaded
2022-10-18T15:25:59.309Z,1666106759.309 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-10-18T15:25:59.326Z,1666106759.326 [ThrusterServo] Loaded
2022-10-18T15:25:59.327Z,1666106759.327 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2022-10-18T15:25:59.327Z,1666106759.327 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-10-18T15:25:59.328Z,1666106759.328 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-10-18T15:25:59.338Z,1666106759.338 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-10-18T15:25:59.338Z,1666106759.338 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-10-18T15:25:59.507Z,1666106759.507 [CTD_Seabird] Loaded
2022-10-18T15:25:59.507Z,1666106759.507 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-10-18T15:25:59.508Z,1666106759.508 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B6E4E0
2022-10-18T15:25:59.508Z,1666106759.508 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1123
2022-10-18T15:25:59.529Z,1666106759.529 [PAR_Licor] Loaded
2022-10-18T15:25:59.529Z,1666106759.529 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-10-18T15:25:59.561Z,1666106759.561 [WetLabsBB2FL] Loaded
2022-10-18T15:25:59.561Z,1666106759.561 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-10-18T15:25:59.562Z,1666106759.562 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B9E4E0
2022-10-18T15:25:59.563Z,1666106759.563 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1124
2022-10-18T15:25:59.582Z,1666106759.582 [WetLabsUBAT] Loaded
2022-10-18T15:25:59.583Z,1666106759.583 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2022-10-18T15:25:59.584Z,1666106759.584 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BCE4E0
2022-10-18T15:25:59.584Z,1666106759.584 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1125
2022-10-18T15:25:59.585Z,1666106759.585 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-10-18T15:25:59.598Z,1666106759.598 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-10-18T15:25:59.609Z,1666106759.609 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-10-18T15:25:59.655Z,1666106759.655 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-10-18T15:25:59.657Z,1666106759.657 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFE4E0
2022-10-18T15:25:59.657Z,1666106759.657 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1126
2022-10-18T15:25:59.661Z,1666106759.661 [Supervisor](INFO): Main Thread ID is 830
2022-10-18T15:25:59.662Z,1666106759.662 [Supervisor](DEBUG): Running supervisor.
2022-10-18T15:25:59.662Z,1666106759.662 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1127
2022-10-18T15:25:59.663Z,1666106759.663 [CommandExec](INFO): Initializing the command executive.
2022-10-18T15:25:59.666Z,1666106759.666 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1128
2022-10-18T15:25:59.668Z,1666106759.668 [controlThread ThreadHandler](INFO): Handler Thread ID is 1129
2022-10-18T15:25:59.669Z,1666106759.669 [controlThread](DEBUG): Initializing ControlThread
2022-10-18T15:25:59.670Z,1666106759.670 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-10-18T15:25:59.671Z,1666106759.671 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-10-18T15:25:59.672Z,1666106759.672 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-10-18T15:25:59.673Z,1666106759.673 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-10-18T15:25:59.673Z,1666106759.673 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-10-18T15:25:59.673Z,1666106759.673 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-10-18T15:25:59.673Z,1666106759.673 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-10-18T15:25:59.674Z,1666106759.674 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-10-18T15:25:59.674Z,1666106759.674 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-10-18T15:25:59.679Z,1666106759.679 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-10-18T15:25:59.679Z,1666106759.679 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-10-18T15:25:59.680Z,1666106759.680 [SBIT](INFO): Initialize SBIT Component.
2022-10-18T15:25:59.680Z,1666106759.680 [SBIT](IMPORTANT): git: 2022-09-08
2022-10-18T15:25:59.680Z,1666106759.680 [SBIT](INFO): git hash: 1923f3a7c5c14ef35d1c7a327e27a495a1a7ac01
2022-10-18T15:25:59.681Z,1666106759.681 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-10-18T15:25:59.682Z,1666106759.682 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-10-18T15:25:59.683Z,1666106759.683 [SBIT](INFO): Beginning SBIT in 47.000000 seconds.
2022-10-18T15:25:59.683Z,1666106759.683 [IBIT](INFO): Initialize IBIT Component.
2022-10-18T15:25:59.684Z,1666106759.684 [CBIT](DEBUG): Initialize CBIT Component.
2022-10-18T15:25:59.685Z,1666106759.685 [logger ThreadHandler](INFO): Handler Thread ID is 1130
2022-10-18T15:25:59.697Z,1666106759.697 [CBIT](DEBUG): Initialized mux pins.
2022-10-18T15:25:59.697Z,1666106759.697 [CBIT](DEBUG): Initializing the watchdog timer.
2022-10-18T15:25:59.705Z,1666106759.705 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1131
2022-10-18T15:25:59.717Z,1666106759.717 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1132
2022-10-18T15:25:59.718Z,1666106759.718 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-10-18T15:25:59.721Z,1666106759.721 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-10-18T15:25:59.721Z,1666106759.721 [CBIT](DEBUG): Initializing heartbeat.
2022-10-18T15:25:59.729Z,1666106759.729 [Onboard ThreadHandler](INFO): Handler Thread ID is 1133
2022-10-18T15:25:59.752Z,1666106759.752 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1134
2022-10-18T15:25:59.758Z,1666106759.758 [DAT ThreadHandler](INFO): Handler Thread ID is 1135
2022-10-18T15:25:59.758Z,1666106759.758 [DAT](INFO): Powering up
2022-10-18T15:25:59.759Z,1666106759.759 [DAT](DEBUG): Initializing DAT.
2022-10-18T15:25:59.760Z,1666106759.760 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1137
2022-10-18T15:25:59.761Z,1666106759.761 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1138
2022-10-18T15:25:59.763Z,1666106759.763 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1139
2022-10-18T15:25:59.789Z,1666106759.789 [WetLabsBB2FL](INFO): Powering up
2022-10-18T15:25:59.790Z,1666106759.790 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-10-18T15:25:59.793Z,1666106759.793 [CBIT](DEBUG): Deactivating GF circuits.
2022-10-18T15:25:59.793Z,1666106759.793 [CBIT](DEBUG): Deactivating emergency mode.
2022-10-18T15:25:59.764Z,1666106759.764 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1140
2022-10-18T15:25:59.799Z,1666106759.799 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-10-18T15:25:59.800Z,1666106759.800 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-10-18T15:25:59.800Z,1666106759.800 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-10-18T15:25:59.800Z,1666106759.800 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-10-18T15:25:59.800Z,1666106759.800 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-10-18T15:25:59.800Z,1666106759.800 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-10-18T15:25:59.801Z,1666106759.801 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-10-18T15:25:59.801Z,1666106759.801 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-10-18T15:25:59.833Z,1666106759.833 [CBIT](DEBUG): Backplane powered.
2022-10-18T15:25:59.838Z,1666106759.838 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-10-18T15:25:59.851Z,1666106759.851 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-10-18T15:25:59.857Z,1666106759.857 [WetLabsUBAT](INFO): Powering up
2022-10-18T15:25:59.908Z,1666106759.908 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2022-10-18T15:25:59.909Z,1666106759.909 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-10-18T15:25:59.966Z,1666106759.966 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-10-18T15:25:59.967Z,1666106759.967 [Default:A.Wait](DEBUG): Construct Wait.
2022-10-18T15:25:59.969Z,1666106759.969 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-10-18T15:26:00.017Z,1666106760.017 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-10-18T15:26:00.019Z,1666106760.019 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-10-18T15:26:00.045Z,1666106760.045 [Default:E.Execute](DEBUG): Construct Execute.
2022-10-18T15:26:00.049Z,1666106760.049 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2022-10-18T15:26:00.060Z,1666106760.060 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-10-18T15:26:00.074Z,1666106760.074 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-10-18T15:26:00.139Z,1666106760.139 [Power24vConverter](INFO): Powering up.
2022-10-18T15:26:00.140Z,1666106760.140 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2022-10-18T15:26:00.157Z,1666106760.157 [Radio_Surface](INFO): Powering up
2022-10-18T15:26:00.183Z,1666106760.183 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-10-18T15:26:00.189Z,1666106760.189 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-10-18T15:26:00.190Z,1666106760.190 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-10-18T15:26:00.197Z,1666106760.197 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-10-18T15:26:00.198Z,1666106760.198 [MassServo](DEBUG): Initializing EZServoServo.
2022-10-18T15:26:00.205Z,1666106760.205 [MassServo](DEBUG): Initializing MassServo.
2022-10-18T15:26:00.206Z,1666106760.206 [RudderServo](DEBUG): Initializing EZServoServo.
2022-10-18T15:26:00.213Z,1666106760.213 [RudderServo](DEBUG): Initializing RudderServo.
2022-10-18T15:26:00.214Z,1666106760.214 [ThrusterServo](DEBUG): Initializing EZServoServo.
2022-10-18T15:26:00.221Z,1666106760.221 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2022-10-18T15:26:01.209Z,1666106761.209 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-10-18T15:26:01.209Z,1666106761.209 [RudderServo](FAULT): Rudder failed to initialize
2022-10-18T15:26:01.209Z,1666106761.209 [RudderServo] Communications Fault, FailCount= 1
2022-10-18T15:26:01.209Z,1666106761.209 [RudderServo](ERROR): Communications Fault
2022-10-18T15:26:01.309Z,1666106761.309 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-10-18T15:26:01.514Z,1666106761.514 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-10-18T15:26:01.514Z,1666106761.514 [RudderServo](INFO): Powering down
2022-10-18T15:26:02.145Z,1666106762.145 [RudderServo](DEBUG): Initializing EZServoServo.
2022-10-18T15:26:02.266Z,1666106762.266 [RudderServo](DEBUG): Initializing RudderServo.
2022-10-18T15:26:02.270Z,1666106762.270 [CBIT](INFO): Clearing failed state for component RudderServo
2022-10-18T15:26:02.270Z,1666106762.270 [RudderServo] No Fault, FailCount= 1
2022-10-18T15:26:02.377Z,1666106762.377 [WetLabsBB2FL](INFO): Powering down
2022-10-18T15:26:07.826Z,1666106767.826 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-10-18T15:26:09.125Z,1666106769.125 [WetLabsUBAT](INFO): Powering down
2022-10-18T15:26:11.603Z,1666106771.603 [DAT](INFO): DAT read: 

2022-10-18T15:26:11.606Z,1666106771.606 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2022-10-18T15:26:13.366Z,1666106773.366 [DAT](INFO): DAT read: MF Frequency Band

2022-10-18T15:26:13.367Z,1666106773.367 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2022-10-18T15:26:13.367Z,1666106773.367 [DAT](INFO): DAT read: Oct 18 2022 15:26:06 

2022-10-18T15:26:14.374Z,1666106774.374 [DAT](INFO): DAT read: Features enabled [Bearing]

2022-10-18T15:26:14.375Z,1666106774.375 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2022-10-18T15:26:14.376Z,1666106774.376 [DAT](INFO): commRate: 800
2022-10-18T15:26:16.441Z,1666106776.441 [DAT](INFO): entering command mode
2022-10-18T15:26:16.641Z,1666106776.641 [DAT](INFO): DAT read: 

2022-10-18T15:26:16.642Z,1666106776.642 [DAT](INFO): DAT read: user:1>

2022-10-18T15:26:16.642Z,1666106776.642 [DAT](INFO): setting verbose to 3
2022-10-18T15:26:16.897Z,1666106776.897 [DAT](INFO): DAT read: user:1>

2022-10-18T15:26:16.898Z,1666106776.898 [DAT](INFO): DAT read: Verbose         | 3           

2022-10-18T15:26:16.898Z,1666106776.898 [DAT](INFO): set verbose to 3
2022-10-18T15:26:16.899Z,1666106776.899 [DAT](INFO): setting DatVerbose to 27440
2022-10-18T15:26:17.145Z,1666106777.145 [DAT](INFO): DAT read: user:2>

2022-10-18T15:26:17.146Z,1666106777.146 [DAT](INFO): DAT read: DatVerbose      | 27440       

2022-10-18T15:26:17.147Z,1666106777.147 [DAT](INFO): set DatVerbose to 27440
2022-10-18T15:26:17.147Z,1666106777.147 [DAT](INFO): setting transmit power to 8
2022-10-18T15:26:17.397Z,1666106777.397 [DAT](INFO): DAT read: user:3>

2022-10-18T15:26:17.398Z,1666106777.398 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2022-10-18T15:26:17.399Z,1666106777.399 [DAT](INFO): set transmit power to 8
2022-10-18T15:26:17.399Z,1666106777.399 [DAT](INFO): setting local address to 9
2022-10-18T15:26:17.651Z,1666106777.651 [DAT](INFO): DAT read: user:4>

2022-10-18T15:26:17.654Z,1666106777.654 [DAT](INFO): DAT read: LocalAddr       | 9           

2022-10-18T15:26:17.655Z,1666106777.655 [DAT](INFO): set local address to 9
2022-10-18T15:26:17.659Z,1666106777.659 [DAT](INFO): Setting time to: 15:26:17 And date to:10/18/2022
2022-10-18T15:26:17.901Z,1666106777.901 [DAT](INFO): DAT read: user:5>

2022-10-18T15:26:17.902Z,1666106777.902 [DAT](INFO): DAT read: Tue Oct 18, 2022  15:26:17  

2022-10-18T15:26:17.903Z,1666106777.903 [DAT](INFO): Local DAT time set to Tue Oct 18, 2022  15:26:17  

2022-10-18T15:26:28.794Z,1666106788.794 [NAL9602](INFO): Powering up NAL9602
2022-10-18T15:26:39.702Z,1666106799.702 [NAL9602](INFO): NAL9602 initialized
2022-10-18T15:26:47.449Z,1666106807.449 [SBIT](IMPORTANT): Beginning Startup BIT
2022-10-18T15:26:47.458Z,1666106807.458 [CBIT](IMPORTANT): Beginning ground fault scan
2022-10-18T15:26:58.374Z,1666106818.374 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009939
CHAN A1 (24V): 0.000007
CHAN A2 (12V): -0.006655
CHAN A3 (5V): -0.002195
CHAN B0 (3.3V): 0.000275
CHAN B1 (3.15aV): 0.000054
CHAN B2 (3.15bV): -0.000331
CHAN B3 (GND): 0.001716
OPEN: 0.005665
Full Scale: +/- 1 mA
2022-10-18T15:27:41.548Z,1666106861.548 [SBIT](IMPORTANT): SBIT PASSED
2022-10-18T15:27:41.549Z,1666106861.549 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-10-18T15:27:41.549Z,1666106861.549 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2022-10-18T15:27:41.550Z,1666106861.550 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2022-10-18T15:27:41.550Z,1666106861.550 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2022-10-18T15:27:41.550Z,1666106861.550 [SBIT](IMPORTANT): DAT.baud=115200 bit_per_second;

2022-10-18T15:27:41.550Z,1666106861.550 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2022-10-18T15:27:41.550Z,1666106861.550 [SBIT](IMPORTANT): DAT.surfaceThreshold=0 meter;

2022-10-18T15:27:41.550Z,1666106861.550 [SBIT](IMPORTANT): DAT.verbosity=2 count;

2022-10-18T15:27:41.550Z,1666106861.550 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2022-10-18T15:27:41.550Z,1666106861.550 [SBIT](IMPORTANT): Express none _.adinos;

2022-10-18T15:27:41.551Z,1666106861.551 [SBIT](IMPORTANT): Express none _.aother;

2022-10-18T15:27:41.551Z,1666106861.551 [SBIT](IMPORTANT): Express linearApproximation _.dinoflagellates 10000000000.000000 none;

2022-10-18T15:27:41.551Z,1666106861.551 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=24 ampere_hour;

2022-10-18T15:27:41.551Z,1666106861.551 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2022-10-18T15:27:41.551Z,1666106861.551 [SBIT](IMPORTANT): Power24vConverter.simulateHardware=0 bool;

2022-10-18T15:27:41.551Z,1666106861.551 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool;

2022-10-18T15:27:41.551Z,1666106861.551 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=220.217773 cubic_centimeter;

2022-10-18T15:27:41.551Z,1666106861.551 [SBIT](IMPORTANT): VerticalControl.massDefault=6.834666 millimeter;

2022-10-18T15:27:41.950Z,1666106861.950 [MissionManager](IMPORTANT): Started mission Startup
2022-10-18T15:27:41.951Z,1666106861.951 [Startup] Running Loop=1
2022-10-18T15:27:41.951Z,1666106861.951 [Startup](DEBUG): Aggregate::initialize Startup
2022-10-18T15:27:41.951Z,1666106861.951 [Startup:A.GoToSurface] Running Loop=1
2022-10-18T15:27:41.951Z,1666106861.951 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-10-18T15:27:41.952Z,1666106861.952 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-10-18T15:27:41.952Z,1666106861.952 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-10-18T15:27:41.969Z,1666106861.969 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-10-18T15:27:41.969Z,1666106861.969 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-10-18T15:27:41.969Z,1666106861.969 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-10-18T15:27:41.971Z,1666106861.971 [Startup:StartupSatComms] Running Loop=1
2022-10-18T15:27:41.971Z,1666106861.971 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-10-18T15:27:41.971Z,1666106861.971 [Startup:StartupSatComms:A] Running Loop=1
2022-10-18T15:27:42.344Z,1666106862.344 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-10-18T15:28:27.803Z,1666106907.803 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004863
2022-10-18T15:28:42.123Z,1666106922.123 [Startup:StartupSatComms:A](INFO): Timed out from 2022-10-18T15:27:41.0Z
2022-10-18T15:28:42.124Z,1666106922.124 [Startup:StartupSatComms:A] Stopped
2022-10-18T15:28:42.124Z,1666106922.124 [Startup:StartupSatComms:B] Running Loop=1
2022-10-18T15:28:42.557Z,1666106922.557 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-10-18T15:28:49.549Z,1666106929.549 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20221018T152550/Courier0000.lzma
2022-10-18T15:28:50.551Z,1666106930.551 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Courier0000.lzma.bak
2022-10-18T15:28:50.551Z,1666106930.551 [DataOverHttps](INFO): SBD MOMSN=17247848
2022-10-18T15:29:00.011Z,1666106940.011 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-10-18T15:29:00.011Z,1666106940.011 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-18T15:29:00.022Z,1666106940.022 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-18T15:29:00.398Z,1666106940.398 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-18T15:29:00.398Z,1666106940.398 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-10-18T15:29:06.435Z,1666106946.435 [DataOverHttps](INFO): Sending 1043 bytes from file Logs/20221018T152550/Express0001.lzma
2022-10-18T15:29:07.434Z,1666106947.434 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Express0001.lzma.bak
2022-10-18T15:29:07.434Z,1666106947.434 [DataOverHttps](INFO): SBD MOMSN=17247851
2022-10-18T15:29:08.818Z,1666106948.818 [Startup:StartupSatComms:B] Stopped
2022-10-18T15:29:08.818Z,1666106948.818 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-10-18T15:29:08.818Z,1666106948.818 [Startup:StartupSatComms] Stopped
2022-10-18T15:29:08.818Z,1666106948.818 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-10-18T15:29:08.819Z,1666106948.819 [Startup](INFO): Completed Startup
2022-10-18T15:29:08.819Z,1666106948.819 [MissionManager](INFO): Startup is completed.
2022-10-18T15:29:08.819Z,1666106948.819 [MissionManager](INFO): Uninitializing Mission Startup
2022-10-18T15:29:08.819Z,1666106948.819 [Startup] Stopped
2022-10-18T15:29:08.820Z,1666106948.820 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-10-18T15:29:08.820Z,1666106948.820 [Startup:A.GoToSurface] Stopped
2022-10-18T15:29:08.820Z,1666106948.820 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-10-18T15:29:09.218Z,1666106949.218 [MissionManager](IMPORTANT): Started mission Default
2022-10-18T15:29:09.219Z,1666106949.219 [Default] Running Loop=1
2022-10-18T15:29:09.219Z,1666106949.219 [Default](DEBUG): Aggregate::initialize Default
2022-10-18T15:29:09.219Z,1666106949.219 [Default:B.GoToSurface] Running Loop=1
2022-10-18T15:29:09.219Z,1666106949.219 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-10-18T15:29:09.219Z,1666106949.219 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-10-18T15:29:09.220Z,1666106949.220 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-10-18T15:29:09.220Z,1666106949.220 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-10-18T15:29:09.220Z,1666106949.220 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-10-18T15:29:09.221Z,1666106949.221 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-10-18T15:29:09.221Z,1666106949.221 [Default:A.Wait] Running Loop=1
2022-10-18T15:29:09.221Z,1666106949.221 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-10-18T15:29:22.527Z,1666106962.527 [Default:A.Wait](INFO): Done Waiting.
2022-10-18T15:29:22.528Z,1666106962.528 [Default:A.Wait] Stopped
2022-10-18T15:29:22.528Z,1666106962.528 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T15:29:22.931Z,1666106962.931 [Default:CheckIn] Running Loop=1
2022-10-18T15:29:22.931Z,1666106962.931 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T15:29:22.931Z,1666106962.931 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T15:29:23.337Z,1666106963.337 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-10-18T15:29:39.541Z,1666106979.541 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-10-18T15:30:37.962Z,1666107037.962 [CommandExec](IMPORTANT): got command burn on
2022-10-18T15:30:37.962Z,1666107037.962 [CommandExec](IMPORTANT): Activating dropweight wire
2022-10-18T15:30:47.326Z,1666107047.326 [CommandExec](IMPORTANT): got command burn off
2022-10-18T15:30:47.326Z,1666107047.326 [CommandExec](IMPORTANT): Deactivating dropweight wire
2022-10-18T15:31:31.465Z,1666107091.465 [CommandExec](IMPORTANT): got command failComponent
2022-10-18T15:31:31.465Z,1666107091.465 [CommandExec](IMPORTANT): Failed components:
2022-10-18T15:31:31.466Z,1666107091.466 [CommandExec](IMPORTANT): No failed Components.
2022-10-18T15:31:42.727Z,1666107102.727 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-18T15:32:01.023Z,1666107121.023 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-10-18T15:32:01.023Z,1666107121.023 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-18T15:32:01.035Z,1666107121.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-18T15:32:01.373Z,1666107121.373 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-18T15:32:01.373Z,1666107121.373 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-10-18T15:32:27.173Z,1666107147.173 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-10-18T15:32:27.176Z,1666107147.176 [BPC1](INFO): Received data from all battery sticks.
2022-10-18T15:33:07.092Z,1666107187.092 [CommandExec](IMPORTANT): got command get Onboard.Pressure
2022-10-18T15:33:07.093Z,1666107187.093 [CommandExec](IMPORTANT): Onboard.Pressure 53562.125000 Pa
2022-10-18T15:33:16.402Z,1666107196.402 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2022-10-18T15:33:16.402Z,1666107196.402 [CommandExec](IMPORTANT): Onboard.Humidity 23.202295 %
2022-10-18T15:34:23.147Z,1666107263.147 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-10-18T15:29:22.9Z
2022-10-18T15:34:23.147Z,1666107263.147 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T15:34:23.147Z,1666107263.147 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T15:34:23.535Z,1666107263.535 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-10-18T15:34:39.416Z,1666107279.416 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20221018T152550/Courier0004.lzma
2022-10-18T15:34:40.419Z,1666107280.419 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Courier0004.lzma.bak
2022-10-18T15:34:40.419Z,1666107280.419 [DataOverHttps](INFO): SBD MOMSN=17247881
2022-10-18T15:34:56.252Z,1666107296.252 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20221018T152550/Express0005.lzma
2022-10-18T15:34:57.255Z,1666107297.255 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Express0005.lzma.bak
2022-10-18T15:34:57.255Z,1666107297.255 [DataOverHttps](INFO): SBD MOMSN=17247883
2022-10-18T15:34:58.311Z,1666107298.311 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T15:34:58.311Z,1666107298.311 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T15:34:58.311Z,1666107298.311 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T15:35:01.969Z,1666107301.969 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-10-18T15:35:01.970Z,1666107301.970 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-18T15:35:01.984Z,1666107301.984 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-18T15:35:02.346Z,1666107302.346 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-18T15:35:02.346Z,1666107302.346 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-10-18T15:36:40.882Z,1666107400.882 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-10-18T15:36:40.882Z,1666107400.882 [NAL9602] Data Fault, FailCount= 1
2022-10-18T15:36:40.882Z,1666107400.882 [NAL9602](ERROR): Data Fault
2022-10-18T15:36:40.899Z,1666107400.899 [CBIT](ERROR): Data Fault in component: NAL9602
2022-10-18T15:36:41.284Z,1666107401.284 [NAL9602](INFO): Powering down
2022-10-18T15:36:42.130Z,1666107402.130 [CBIT](INFO): Clearing failed state for component NAL9602
2022-10-18T15:36:42.130Z,1666107402.130 [NAL9602] No Fault, FailCount= 1
2022-10-18T15:37:11.614Z,1666107431.614 [NAL9602](INFO): Powering up NAL9602
2022-10-18T15:37:22.522Z,1666107442.522 [NAL9602](INFO): NAL9602 initialized
2022-10-18T15:38:02.942Z,1666107482.942 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-10-18T15:38:02.942Z,1666107482.942 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-18T15:38:02.962Z,1666107482.962 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-18T15:38:03.382Z,1666107483.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-18T15:38:03.382Z,1666107483.382 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-10-18T15:39:58.895Z,1666107598.895 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T15:39:58.895Z,1666107598.895 [Default:CheckIn:C.Wait] Stopped
2022-10-18T15:39:58.895Z,1666107598.895 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T15:39:58.895Z,1666107598.895 [Default:CheckIn:D] Running Loop=1
2022-10-18T15:39:59.329Z,1666107599.329 [Default:CheckIn:D] Stopped
2022-10-18T15:39:59.329Z,1666107599.329 [Default:CheckIn:E] Running Loop=1
2022-10-18T15:39:59.765Z,1666107599.765 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.835169 min 
2022-10-18T15:39:59.765Z,1666107599.765 [Default:CheckIn:E] Stopped
2022-10-18T15:39:59.765Z,1666107599.765 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T15:39:59.765Z,1666107599.765 [Default:CheckIn] Stopped
2022-10-18T15:39:59.765Z,1666107599.765 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T15:39:59.765Z,1666107599.765 [Default:CheckIn](INFO): Running loop #2
2022-10-18T15:39:59.765Z,1666107599.765 [Default:CheckIn] Running Loop=2
2022-10-18T15:39:59.765Z,1666107599.765 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T15:39:59.766Z,1666107599.766 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T15:41:03.977Z,1666107663.977 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-10-18T15:41:03.978Z,1666107663.978 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-18T15:41:03.992Z,1666107663.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-18T15:41:04.380Z,1666107664.380 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-18T15:41:04.380Z,1666107664.380 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-10-18T15:43:26.201Z,1666107806.201 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154324.00,A,3648.13492,N,12147.22714,W,0.117,0.00,181022,,,A*71
2022-10-18T15:43:26.204Z,1666107806.204 [NAL9602](INFO): GPS fix at 20221018T154324: (36.802249, -121.787119)
2022-10-18T15:43:26.217Z,1666107806.217 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T15:43:26.217Z,1666107806.217 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T15:43:35.715Z,1666107815.715 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20221018T152550/Courier0007.lzma
2022-10-18T15:43:36.710Z,1666107816.710 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Courier0007.lzma.bak
2022-10-18T15:43:36.711Z,1666107816.711 [DataOverHttps](INFO): SBD MOMSN=17247898
2022-10-18T15:43:54.161Z,1666107834.161 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20221018T152550/Express0008.lzma
2022-10-18T15:43:55.162Z,1666107835.162 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Express0008.lzma.bak
2022-10-18T15:43:55.162Z,1666107835.162 [DataOverHttps](INFO): SBD MOMSN=17247901
2022-10-18T15:43:56.509Z,1666107836.509 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T15:43:56.509Z,1666107836.509 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T15:43:56.509Z,1666107836.509 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T15:43:58.502Z,1666107838.502 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T15:45:15.014Z,1666107915.014 [DataOverHttps](IMPORTANT): SBD MTMSN=20221018T154514
2022-10-18T15:45:22.981Z,1666107922.981 [DataOverHttps](INFO): Received command: ibit
2022-10-18T15:45:23.069Z,1666107923.069 [CommandExec](IMPORTANT): got command ibit
2022-10-18T15:45:23.373Z,1666107923.373 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-10-18T15:45:23.373Z,1666107923.373 [IBIT](IMPORTANT): Beginning control surface checks.
2022-10-18T15:45:23.376Z,1666107923.376 [CBIT](IMPORTANT): Beginning ground fault scan
2022-10-18T15:45:24.960Z,1666107924.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154523.00,A,3648.13508,N,12147.23084,W,0.097,0.00,181022,,,D*71
2022-10-18T15:45:24.971Z,1666107924.971 [NAL9602](INFO): GPS fix at 20221018T154523: (36.802251, -121.787181)
2022-10-18T15:45:34.281Z,1666107934.281 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011497
CHAN A1 (24V): 0.000540
CHAN A2 (12V): -0.007353
CHAN A3 (5V): -0.001784
CHAN B0 (3.3V): -0.000395
CHAN B1 (3.15aV): 0.000449
CHAN B2 (3.15bV): -0.000269
CHAN B3 (GND): 0.001574
OPEN: 0.005670
Full Scale: +/- 1 mA
2022-10-18T15:45:48.396Z,1666107948.396 [NAL9602](INFO): SBD MO Status=0, MOMSN=21433, MT Status=0, MTMSN=0
2022-10-18T15:45:48.396Z,1666107948.396 [NAL9602](INFO): No messages in MT queue
2022-10-18T15:45:49.609Z,1666107949.609 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154547.00,A,3648.13565,N,12147.22967,W,0.039,0.00,181022,,,D*79
2022-10-18T15:45:49.612Z,1666107949.612 [NAL9602](INFO): GPS fix at 20221018T154547: (36.802261, -121.787161)
2022-10-18T15:45:52.033Z,1666107952.033 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154550.00,A,3648.13587,N,12147.22943,W,0.078,0.00,181022,,,D*70
2022-10-18T15:45:52.035Z,1666107952.035 [NAL9602](INFO): GPS fix at 20221018T154550: (36.802264, -121.787157)
2022-10-18T15:45:55.260Z,1666107955.260 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154553.00,A,3648.13591,N,12147.22916,W,0.058,0.00,181022,,,D*76
2022-10-18T15:45:55.262Z,1666107955.262 [NAL9602](INFO): GPS fix at 20221018T154553: (36.802265, -121.787153)
2022-10-18T15:45:58.091Z,1666107958.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154556.00,A,3648.13580,N,12147.22908,W,0.019,0.00,181022,,,D*79
2022-10-18T15:45:58.094Z,1666107958.094 [NAL9602](INFO): GPS fix at 20221018T154556: (36.802263, -121.787151)
2022-10-18T15:46:00.920Z,1666107960.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154559.00,A,3648.13565,N,12147.22912,W,0.019,0.00,181022,,,D*76
2022-10-18T15:46:00.922Z,1666107960.922 [NAL9602](INFO): GPS fix at 20221018T154559: (36.802261, -121.787152)
2022-10-18T15:46:04.148Z,1666107964.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154602.00,A,3648.13540,N,12147.22911,W,0.039,0.00,181022,,,D*7D
2022-10-18T15:46:04.151Z,1666107964.151 [NAL9602](INFO): GPS fix at 20221018T154602: (36.802257, -121.787152)
2022-10-18T15:46:06.995Z,1666107966.995 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154605.00,A,3648.13521,N,12147.22917,W,0.000,0.00,181022,,,D*71
2022-10-18T15:46:06.998Z,1666107966.998 [NAL9602](INFO): GPS fix at 20221018T154605: (36.802253, -121.787153)
2022-10-18T15:46:09.495Z,1666107969.495 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802254 Longitude: -121.787155
2022-10-18T15:46:09.950Z,1666107969.950 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2022-10-18T15:46:09.951Z,1666107969.951 [IBIT](IMPORTANT): batteryCapacityThreshold: 24.000000 Ah
2022-10-18T15:46:09.951Z,1666107969.951 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.000000 V
2022-10-18T15:46:10.208Z,1666107970.208 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154608.00,A,3648.13488,N,12147.22912,W,0.039,0.00,181022,,,D*71
2022-10-18T15:46:10.210Z,1666107970.210 [NAL9602](INFO): GPS fix at 20221018T154608: (36.802248, -121.787152)
2022-10-18T15:46:10.311Z,1666107970.311 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-10-18T15:46:10.311Z,1666107970.311 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-10-18T15:46:10.311Z,1666107970.311 [IBIT](IMPORTANT): Pressure:7.744487 PSI
2022-10-18T15:46:10.312Z,1666107970.312 [IBIT](IMPORTANT): Humidity:23.214504 %
2022-10-18T15:46:10.706Z,1666107970.706 [IBIT](IMPORTANT): Vehicle Pitch:0.190510 degrees
2022-10-18T15:46:10.706Z,1666107970.706 [IBIT](IMPORTANT): Vehicle  Roll:-3.658859 degrees
2022-10-18T15:46:10.707Z,1666107970.707 [IBIT](IMPORTANT): Vehicle  Heading:343.063324 degrees
2022-10-18T15:46:11.118Z,1666107971.118 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-10-18T15:46:11.118Z,1666107971.118 [IBIT](IMPORTANT): buoyancyNeutral: 220.217773 cc
2022-10-18T15:46:11.119Z,1666107971.119 [IBIT](IMPORTANT): massDefault: 0.683467 cm
2022-10-18T15:46:11.119Z,1666107971.119 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2022-10-18T15:46:11.119Z,1666107971.119 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2022-10-18T15:46:11.119Z,1666107971.119 [IBIT](IMPORTANT): IBIT PASSED
2022-10-18T15:46:42.529Z,1666108002.529 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T15:48:57.089Z,1666108137.089 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T15:48:57.089Z,1666108137.089 [Default:CheckIn:C.Wait] Stopped
2022-10-18T15:48:57.089Z,1666108137.089 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T15:48:57.089Z,1666108137.089 [Default:CheckIn:D] Running Loop=1
2022-10-18T15:48:57.484Z,1666108137.484 [Default:CheckIn:D] Stopped
2022-10-18T15:48:57.484Z,1666108137.484 [Default:CheckIn:E] Running Loop=1
2022-10-18T15:48:57.982Z,1666108137.982 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.804417 min 
2022-10-18T15:48:57.982Z,1666108137.982 [Default:CheckIn:E] Stopped
2022-10-18T15:48:57.982Z,1666108137.982 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T15:48:57.982Z,1666108137.982 [Default:CheckIn] Stopped
2022-10-18T15:48:57.982Z,1666108137.982 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T15:48:57.982Z,1666108137.982 [Default:CheckIn](INFO): Running loop #3
2022-10-18T15:48:57.982Z,1666108137.982 [Default:CheckIn] Running Loop=3
2022-10-18T15:48:57.982Z,1666108137.982 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T15:48:57.982Z,1666108137.982 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T15:48:59.903Z,1666108139.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154858.00,A,3648.13447,N,12147.22763,W,0.078,0.00,181022,,,D*74
2022-10-18T15:48:59.906Z,1666108139.906 [NAL9602](INFO): GPS fix at 20221018T154858: (36.802241, -121.787127)
2022-10-18T15:48:59.966Z,1666108139.966 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T15:48:59.966Z,1666108139.966 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T15:49:07.701Z,1666108147.701 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221018T152550/Courier0010.lzma
2022-10-18T15:49:08.694Z,1666108148.694 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Courier0010.lzma.bak
2022-10-18T15:49:08.695Z,1666108148.695 [DataOverHttps](INFO): SBD MOMSN=17247908
2022-10-18T15:49:13.635Z,1666108153.635 [NAL9602](INFO): SBD MO Status=0, MOMSN=21434, MT Status=0, MTMSN=0
2022-10-18T15:49:13.635Z,1666108153.635 [NAL9602](INFO): No messages in MT queue
2022-10-18T15:49:25.449Z,1666108165.449 [DataOverHttps](INFO): Sending 813 bytes from file Logs/20221018T152550/Express0011.lzma
2022-10-18T15:49:26.450Z,1666108166.450 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Express0011.lzma.bak
2022-10-18T15:49:26.451Z,1666108166.451 [DataOverHttps](INFO): SBD MOMSN=17247911
2022-10-18T15:49:27.810Z,1666108167.810 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T15:49:27.810Z,1666108167.810 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T15:49:27.810Z,1666108167.810 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T15:49:44.338Z,1666108184.338 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T15:50:05.453Z,1666108205.453 [RudderServo](ERROR): getPosition uart error serial timeout
2022-10-18T15:50:05.453Z,1666108205.453 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2022-10-18T15:50:05.453Z,1666108205.453 [RudderServo] Communications Fault, FailCount= 1
2022-10-18T15:50:05.453Z,1666108205.453 [RudderServo](ERROR): Communications Fault
2022-10-18T15:50:05.457Z,1666108205.457 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-10-18T15:50:05.920Z,1666108205.920 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-10-18T15:50:05.920Z,1666108205.920 [RudderServo](INFO): Powering down
2022-10-18T15:50:06.675Z,1666108206.675 [RudderServo](DEBUG): Initializing EZServoServo.
2022-10-18T15:50:06.794Z,1666108206.794 [RudderServo](DEBUG): Initializing RudderServo.
2022-10-18T15:50:06.801Z,1666108206.801 [CBIT](INFO): Clearing failed state for component RudderServo
2022-10-18T15:50:06.801Z,1666108206.801 [RudderServo] No Fault, FailCount= 1
2022-10-18T15:54:28.468Z,1666108468.468 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T15:54:28.468Z,1666108468.468 [Default:CheckIn:C.Wait] Stopped
2022-10-18T15:54:28.468Z,1666108468.468 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T15:54:28.468Z,1666108468.468 [Default:CheckIn:D] Running Loop=1
2022-10-18T15:54:28.862Z,1666108468.862 [Default:CheckIn:D] Stopped
2022-10-18T15:54:28.862Z,1666108468.862 [Default:CheckIn:E] Running Loop=1
2022-10-18T15:54:29.265Z,1666108469.265 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.327389 min 
2022-10-18T15:54:29.265Z,1666108469.265 [Default:CheckIn:E] Stopped
2022-10-18T15:54:29.265Z,1666108469.265 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T15:54:29.265Z,1666108469.265 [Default:CheckIn] Stopped
2022-10-18T15:54:29.265Z,1666108469.265 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T15:54:29.265Z,1666108469.265 [Default:CheckIn](INFO): Running loop #4
2022-10-18T15:54:29.265Z,1666108469.265 [Default:CheckIn] Running Loop=4
2022-10-18T15:54:29.266Z,1666108469.266 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T15:54:29.266Z,1666108469.266 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T15:54:31.273Z,1666108471.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155430.00,A,3648.16738,N,12147.21536,W,0.175,254.14,181022,,,D*72
2022-10-18T15:54:31.275Z,1666108471.275 [NAL9602](INFO): GPS fix at 20221018T155430: (36.802790, -121.786923)
2022-10-18T15:54:31.287Z,1666108471.287 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T15:54:31.287Z,1666108471.287 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T15:54:38.784Z,1666108478.784 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221018T152550/Courier0016.lzma
2022-10-18T15:54:39.787Z,1666108479.787 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Courier0016.lzma.bak
2022-10-18T15:54:39.787Z,1666108479.787 [DataOverHttps](INFO): SBD MOMSN=17247938
2022-10-18T15:54:41.371Z,1666108481.371 [NAL9602](INFO): SBD MO Status=0, MOMSN=21435, MT Status=0, MTMSN=0
2022-10-18T15:54:41.371Z,1666108481.371 [NAL9602](INFO): No messages in MT queue
2022-10-18T15:54:56.336Z,1666108496.336 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20221018T152550/Express0017.lzma
2022-10-18T15:54:57.339Z,1666108497.339 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Express0017.lzma.bak
2022-10-18T15:54:57.339Z,1666108497.339 [DataOverHttps](INFO): SBD MOMSN=17247941
2022-10-18T15:54:58.359Z,1666108498.359 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T15:54:58.359Z,1666108498.359 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T15:54:58.359Z,1666108498.359 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T15:55:12.081Z,1666108512.081 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T15:59:58.936Z,1666108798.936 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T15:59:58.936Z,1666108798.936 [Default:CheckIn:C.Wait] Stopped
2022-10-18T15:59:58.936Z,1666108798.936 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T15:59:58.936Z,1666108798.936 [Default:CheckIn:D] Running Loop=1
2022-10-18T15:59:59.340Z,1666108799.340 [Default:CheckIn:D] Stopped
2022-10-18T15:59:59.340Z,1666108799.340 [Default:CheckIn:E] Running Loop=1
2022-10-18T15:59:59.811Z,1666108799.811 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.835350 min 
2022-10-18T15:59:59.811Z,1666108799.811 [Default:CheckIn:E] Stopped
2022-10-18T15:59:59.811Z,1666108799.811 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T15:59:59.811Z,1666108799.811 [Default:CheckIn] Stopped
2022-10-18T15:59:59.812Z,1666108799.812 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T15:59:59.812Z,1666108799.812 [Default:CheckIn](INFO): Running loop #5
2022-10-18T15:59:59.812Z,1666108799.812 [Default:CheckIn] Running Loop=5
2022-10-18T15:59:59.812Z,1666108799.812 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T15:59:59.812Z,1666108799.812 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:00:01.765Z,1666108801.765 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160000.00,A,3648.16758,N,12147.21445,W,0.058,254.14,181022,,,A*7B
2022-10-18T16:00:01.767Z,1666108801.767 [NAL9602](INFO): GPS fix at 20221018T160000: (36.802793, -121.786907)
2022-10-18T16:00:01.798Z,1666108801.798 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:00:01.798Z,1666108801.798 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:00:09.408Z,1666108809.408 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221018T152550/Courier0019.lzma
2022-10-18T16:00:10.240Z,1666108810.240 [NAL9602](INFO): SBD MO Status=0, MOMSN=21436, MT Status=0, MTMSN=0
2022-10-18T16:00:10.240Z,1666108810.240 [NAL9602](INFO): No messages in MT queue
2022-10-18T16:00:10.413Z,1666108810.413 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Courier0019.lzma.bak
2022-10-18T16:00:10.413Z,1666108810.413 [DataOverHttps](INFO): SBD MOMSN=17247946
2022-10-18T16:00:29.980Z,1666108829.980 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20221018T152550/Express0020.lzma
2022-10-18T16:00:30.982Z,1666108830.982 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Express0020.lzma.bak
2022-10-18T16:00:30.983Z,1666108830.983 [DataOverHttps](INFO): SBD MOMSN=17247949
2022-10-18T16:00:32.142Z,1666108832.142 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:00:32.142Z,1666108832.142 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:00:32.142Z,1666108832.142 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:00:40.992Z,1666108840.992 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:05:32.752Z,1666109132.752 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T16:05:32.752Z,1666109132.752 [Default:CheckIn:C.Wait] Stopped
2022-10-18T16:05:32.752Z,1666109132.752 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T16:05:32.752Z,1666109132.752 [Default:CheckIn:D] Running Loop=1
2022-10-18T16:05:33.160Z,1666109133.160 [Default:CheckIn:D] Stopped
2022-10-18T16:05:33.160Z,1666109133.160 [Default:CheckIn:E] Running Loop=1
2022-10-18T16:05:33.568Z,1666109133.568 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.399019 min 
2022-10-18T16:05:33.568Z,1666109133.568 [Default:CheckIn:E] Stopped
2022-10-18T16:05:33.568Z,1666109133.568 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T16:05:33.568Z,1666109133.568 [Default:CheckIn] Stopped
2022-10-18T16:05:33.568Z,1666109133.568 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T16:05:33.568Z,1666109133.568 [Default:CheckIn](INFO): Running loop #6
2022-10-18T16:05:33.568Z,1666109133.568 [Default:CheckIn] Running Loop=6
2022-10-18T16:05:33.569Z,1666109133.569 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T16:05:33.569Z,1666109133.569 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:05:35.571Z,1666109135.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160534.00,A,3648.19511,N,12147.16479,W,1.205,47.70,181022,,,D*4E
2022-10-18T16:05:35.574Z,1666109135.574 [NAL9602](INFO): GPS fix at 20221018T160534: (36.803252, -121.786080)
2022-10-18T16:05:35.585Z,1666109135.585 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:05:35.585Z,1666109135.585 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:05:43.268Z,1666109143.268 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221018T152550/Courier0022.lzma
2022-10-18T16:05:44.270Z,1666109144.270 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Courier0022.lzma.bak
2022-10-18T16:05:44.271Z,1666109144.271 [DataOverHttps](INFO): SBD MOMSN=17247953
2022-10-18T16:05:56.171Z,1666109156.171 [NAL9602](INFO): SBD MO Status=0, MOMSN=21437, MT Status=0, MTMSN=0
2022-10-18T16:05:56.171Z,1666109156.171 [NAL9602](INFO): No messages in MT queue
2022-10-18T16:06:00.580Z,1666109160.580 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20221018T152550/Express0023.lzma
2022-10-18T16:06:01.582Z,1666109161.582 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Express0023.lzma.bak
2022-10-18T16:06:01.583Z,1666109161.583 [DataOverHttps](INFO): SBD MOMSN=17247956
2022-10-18T16:06:02.665Z,1666109162.665 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:06:02.665Z,1666109162.665 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:06:02.665Z,1666109162.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:06:26.874Z,1666109186.874 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:11:03.280Z,1666109463.280 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T16:11:03.280Z,1666109463.280 [Default:CheckIn:C.Wait] Stopped
2022-10-18T16:11:03.280Z,1666109463.280 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T16:11:03.280Z,1666109463.280 [Default:CheckIn:D] Running Loop=1
2022-10-18T16:11:03.684Z,1666109463.684 [Default:CheckIn:D] Stopped
2022-10-18T16:11:03.684Z,1666109463.684 [Default:CheckIn:E] Running Loop=1
2022-10-18T16:11:04.106Z,1666109464.106 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.907756 min 
2022-10-18T16:11:04.106Z,1666109464.106 [Default:CheckIn:E] Stopped
2022-10-18T16:11:04.106Z,1666109464.106 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T16:11:04.106Z,1666109464.106 [Default:CheckIn] Stopped
2022-10-18T16:11:04.106Z,1666109464.106 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T16:11:04.107Z,1666109464.107 [Default:CheckIn](INFO): Running loop #7
2022-10-18T16:11:04.107Z,1666109464.107 [Default:CheckIn] Running Loop=7
2022-10-18T16:11:04.107Z,1666109464.107 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T16:11:04.107Z,1666109464.107 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:11:05.764Z,1666109465.764 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2022-10-18T16:11:06.099Z,1666109466.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161105.00,A,3648.44897,N,12147.33714,W,7.503,232.50,181022,,,D*78
2022-10-18T16:11:06.110Z,1666109466.110 [NAL9602](INFO): GPS fix at 20221018T161105: (36.807483, -121.788952)
2022-10-18T16:11:06.121Z,1666109466.121 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:11:06.121Z,1666109466.121 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:11:13.472Z,1666109473.472 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221018T152550/Courier0025.lzma
2022-10-18T16:11:14.474Z,1666109474.474 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Courier0025.lzma.bak
2022-10-18T16:11:14.475Z,1666109474.475 [DataOverHttps](INFO): SBD MOMSN=17247960
2022-10-18T16:11:17.407Z,1666109477.407 [NAL9602](INFO): SBD MO Status=0, MOMSN=21438, MT Status=0, MTMSN=0
2022-10-18T16:11:17.408Z,1666109477.408 [NAL9602](INFO): No messages in MT queue
2022-10-18T16:11:30.728Z,1666109490.728 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20221018T152550/Express0026.lzma
2022-10-18T16:11:31.734Z,1666109491.734 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Express0026.lzma.bak
2022-10-18T16:11:31.734Z,1666109491.734 [DataOverHttps](INFO): SBD MOMSN=17247963
2022-10-18T16:11:32.777Z,1666109492.777 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:11:32.777Z,1666109492.777 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:11:32.778Z,1666109492.778 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:11:48.119Z,1666109508.119 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:16:33.420Z,1666109793.420 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T16:16:33.420Z,1666109793.420 [Default:CheckIn:C.Wait] Stopped
2022-10-18T16:16:33.420Z,1666109793.420 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T16:16:33.420Z,1666109793.420 [Default:CheckIn:D] Running Loop=1
2022-10-18T16:16:33.871Z,1666109793.871 [Default:CheckIn:D] Stopped
2022-10-18T16:16:33.871Z,1666109793.871 [Default:CheckIn:E] Running Loop=1
2022-10-18T16:16:34.232Z,1666109794.232 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.410876 min 
2022-10-18T16:16:34.232Z,1666109794.232 [Default:CheckIn:E] Stopped
2022-10-18T16:16:34.232Z,1666109794.232 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T16:16:34.232Z,1666109794.232 [Default:CheckIn] Stopped
2022-10-18T16:16:34.232Z,1666109794.232 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T16:16:34.232Z,1666109794.232 [Default:CheckIn](INFO): Running loop #8
2022-10-18T16:16:34.232Z,1666109794.232 [Default:CheckIn] Running Loop=8
2022-10-18T16:16:34.232Z,1666109794.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T16:16:34.233Z,1666109794.233 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:16:36.246Z,1666109796.246 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161635.00,A,3648.30622,N,12149.06331,W,26.320,274.20,181022,,,D*45
2022-10-18T16:16:36.248Z,1666109796.248 [NAL9602](INFO): GPS fix at 20221018T161635: (36.805104, -121.817722)
2022-10-18T16:16:36.264Z,1666109796.264 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:16:36.264Z,1666109796.264 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:16:44.492Z,1666109804.492 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221018T152550/Courier0028.lzma
2022-10-18T16:16:45.494Z,1666109805.494 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Courier0028.lzma.bak
2022-10-18T16:16:45.495Z,1666109805.495 [DataOverHttps](INFO): SBD MOMSN=17247967
2022-10-18T16:17:02.296Z,1666109822.296 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20221018T152550/Express0029.lzma
2022-10-18T16:17:02.903Z,1666109822.903 [NAL9602](INFO): SBD MO Status=0, MOMSN=21439, MT Status=0, MTMSN=0
2022-10-18T16:17:02.903Z,1666109822.903 [NAL9602](INFO): No messages in MT queue
2022-10-18T16:17:03.298Z,1666109823.298 [DataOverHttps](INFO): Moved sent file to Logs/20221018T152550/Express0029.lzma.bak
2022-10-18T16:17:03.299Z,1666109823.299 [DataOverHttps](INFO): SBD MOMSN=17247971
2022-10-18T16:17:04.528Z,1666109824.528 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:17:04.528Z,1666109824.528 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:17:04.528Z,1666109824.528 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:17:33.610Z,1666109853.610 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:18:49.061Z,1666109929.061 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247326
2022-10-18T16:19:21.197Z,1666109961.197 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:19:56.357Z,1666109996.357 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:20:31.505Z,1666110031.505 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:21:06.661Z,1666110066.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:21:41.803Z,1666110101.803 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:22:05.117Z,1666110125.117 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T16:22:05.118Z,1666110125.118 [Default:CheckIn:C.Wait] Stopped
2022-10-18T16:22:05.118Z,1666110125.118 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T16:22:05.118Z,1666110125.118 [Default:CheckIn:D] Running Loop=1
2022-10-18T16:22:05.512Z,1666110125.512 [Default:CheckIn:D] Stopped
2022-10-18T16:22:05.512Z,1666110125.512 [Default:CheckIn:E] Running Loop=1
2022-10-18T16:22:05.954Z,1666110125.954 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.938216 min 
2022-10-18T16:22:05.954Z,1666110125.954 [Default:CheckIn:E] Stopped
2022-10-18T16:22:05.954Z,1666110125.954 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T16:22:05.954Z,1666110125.954 [Default:CheckIn] Stopped
2022-10-18T16:22:05.954Z,1666110125.954 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T16:22:05.955Z,1666110125.955 [Default:CheckIn](INFO): Running loop #9
2022-10-18T16:22:05.955Z,1666110125.955 [Default:CheckIn] Running Loop=9
2022-10-18T16:22:05.955Z,1666110125.955 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T16:22:05.955Z,1666110125.955 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:22:07.931Z,1666110127.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162207.00,A,3648.26657,N,12152.26360,W,27.136,267.52,181022,,,D*49
2022-10-18T16:22:07.933Z,1666110127.933 [NAL9602](INFO): GPS fix at 20221018T162207: (36.804443, -121.871060)
2022-10-18T16:22:07.981Z,1666110127.981 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:22:07.982Z,1666110127.982 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:22:16.945Z,1666110136.945 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:22:29.439Z,1666110149.439 [NAL9602](INFO): SBD MO Status=1, MOMSN=21440, MT Status=0, MTMSN=0
2022-10-18T16:22:29.493Z,1666110149.493 [NAL9602](INFO): Sent 73 bytes from file Logs/20221018T152550/Courier0031.lzma
2022-10-18T16:22:29.493Z,1666110149.493 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:22:40.820Z,1666110160.820 [NAL9602](INFO): SBD MO Status=1, MOMSN=21441, MT Status=0, MTMSN=0
2022-10-18T16:22:40.889Z,1666110160.889 [NAL9602](INFO): Sent 139 bytes from file Logs/20221018T152550/Express0032.lzma
2022-10-18T16:22:40.889Z,1666110160.889 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:22:52.085Z,1666110172.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:22:54.167Z,1666110174.167 [NAL9602](INFO): SBD MO Status=2, MOMSN=21442, MT Status=2, MTMSN=0
2022-10-18T16:22:54.168Z,1666110174.168 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-10-18T16:23:16.391Z,1666110196.391 [NAL9602](INFO): SBD MO Status=0, MOMSN=21442, MT Status=0, MTMSN=0
2022-10-18T16:23:16.478Z,1666110196.478 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:23:16.478Z,1666110196.478 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:23:16.478Z,1666110196.478 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:23:27.225Z,1666110207.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:23:47.094Z,1666110227.094 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:24:02.373Z,1666110242.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:24:37.513Z,1666110277.513 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:25:12.669Z,1666110312.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:25:47.817Z,1666110347.817 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:26:22.975Z,1666110382.975 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:26:58.138Z,1666110418.138 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:27:33.281Z,1666110453.281 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:28:08.433Z,1666110488.433 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:28:16.973Z,1666110496.973 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T16:28:16.973Z,1666110496.973 [Default:CheckIn:C.Wait] Stopped
2022-10-18T16:28:16.973Z,1666110496.973 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T16:28:16.973Z,1666110496.973 [Default:CheckIn:D] Running Loop=1
2022-10-18T16:28:17.409Z,1666110497.409 [Default:CheckIn:D] Stopped
2022-10-18T16:28:17.409Z,1666110497.409 [Default:CheckIn:E] Running Loop=1
2022-10-18T16:28:17.846Z,1666110497.846 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.136161 min 
2022-10-18T16:28:17.846Z,1666110497.846 [Default:CheckIn:E] Stopped
2022-10-18T16:28:17.846Z,1666110497.846 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T16:28:17.846Z,1666110497.846 [Default:CheckIn] Stopped
2022-10-18T16:28:17.846Z,1666110497.846 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T16:28:17.847Z,1666110497.847 [Default:CheckIn](INFO): Running loop #10
2022-10-18T16:28:17.847Z,1666110497.847 [Default:CheckIn] Running Loop=10
2022-10-18T16:28:17.847Z,1666110497.847 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T16:28:17.847Z,1666110497.847 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:28:19.800Z,1666110499.800 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162818.00,A,3648.22459,N,12155.81266,W,27.991,270.21,181022,,,A*4A
2022-10-18T16:28:19.811Z,1666110499.811 [NAL9602](INFO): GPS fix at 20221018T162818: (36.803743, -121.930211)
2022-10-18T16:28:19.847Z,1666110499.847 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:28:19.847Z,1666110499.847 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:28:43.573Z,1666110523.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:28:56.267Z,1666110536.267 [NAL9602](INFO): SBD MO Status=1, MOMSN=21443, MT Status=0, MTMSN=0
2022-10-18T16:28:56.325Z,1666110536.325 [NAL9602](INFO): Sent 74 bytes from file Logs/20221018T152550/Courier0034.lzma
2022-10-18T16:28:56.325Z,1666110536.325 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:29:14.926Z,1666110554.926 [NAL9602](INFO): SBD MO Status=1, MOMSN=21444, MT Status=0, MTMSN=0
2022-10-18T16:29:14.977Z,1666110554.977 [NAL9602](INFO): Sent 140 bytes from file Logs/20221018T152550/Express0035.lzma
2022-10-18T16:29:14.977Z,1666110554.977 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:29:18.741Z,1666110558.741 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:29:27.450Z,1666110567.450 [NAL9602](INFO): SBD MO Status=0, MOMSN=21445, MT Status=0, MTMSN=0
2022-10-18T16:29:27.544Z,1666110567.544 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:29:27.544Z,1666110567.544 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:29:27.544Z,1666110567.544 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:29:53.881Z,1666110593.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:29:58.170Z,1666110598.170 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:30:29.021Z,1666110629.021 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:31:04.173Z,1666110664.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:31:39.325Z,1666110699.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:32:14.465Z,1666110734.465 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:32:49.605Z,1666110769.605 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:33:24.757Z,1666110804.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:33:59.921Z,1666110839.921 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:34:28.092Z,1666110868.092 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T16:34:28.092Z,1666110868.092 [Default:CheckIn:C.Wait] Stopped
2022-10-18T16:34:28.097Z,1666110868.097 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T16:34:28.097Z,1666110868.097 [Default:CheckIn:D] Running Loop=1
2022-10-18T16:34:28.469Z,1666110868.469 [Default:CheckIn:D] Stopped
2022-10-18T16:34:28.469Z,1666110868.469 [Default:CheckIn:E] Running Loop=1
2022-10-18T16:34:28.928Z,1666110868.928 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.320829 min 
2022-10-18T16:34:28.928Z,1666110868.928 [Default:CheckIn:E] Stopped
2022-10-18T16:34:28.929Z,1666110868.929 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T16:34:28.929Z,1666110868.929 [Default:CheckIn] Stopped
2022-10-18T16:34:28.929Z,1666110868.929 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T16:34:28.929Z,1666110868.929 [Default:CheckIn](INFO): Running loop #11
2022-10-18T16:34:28.929Z,1666110868.929 [Default:CheckIn] Running Loop=11
2022-10-18T16:34:28.929Z,1666110868.929 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T16:34:28.929Z,1666110868.929 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:34:30.860Z,1666110870.860 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163430.00,A,3648.21922,N,12159.18579,W,26.747,271.90,181022,,,A*45
2022-10-18T16:34:30.862Z,1666110870.862 [NAL9602](INFO): GPS fix at 20221018T163430: (36.803654, -121.986430)
2022-10-18T16:34:30.898Z,1666110870.898 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:34:30.898Z,1666110870.898 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:34:35.074Z,1666110875.074 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:34:54.811Z,1666110894.811 [NAL9602](INFO): SBD MO Status=1, MOMSN=21446, MT Status=0, MTMSN=0
2022-10-18T16:34:54.873Z,1666110894.873 [NAL9602](INFO): Sent 74 bytes from file Logs/20221018T152550/Courier0037.lzma
2022-10-18T16:34:54.873Z,1666110894.873 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:35:00.527Z,1666110900.527 [NAL9602](INFO): SBD MO Status=1, MOMSN=21447, MT Status=0, MTMSN=0
2022-10-18T16:35:00.585Z,1666110900.585 [NAL9602](INFO): Sent 140 bytes from file Logs/20221018T152550/Express0038.lzma
2022-10-18T16:35:00.585Z,1666110900.585 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:35:08.603Z,1666110908.603 [NAL9602](INFO): SBD MO Status=0, MOMSN=21448, MT Status=0, MTMSN=0
2022-10-18T16:35:08.705Z,1666110908.705 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:35:08.705Z,1666110908.705 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:35:08.705Z,1666110908.705 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:35:10.213Z,1666110910.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:35:39.341Z,1666110939.341 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:35:45.377Z,1666110945.377 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:36:20.517Z,1666110980.517 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:36:55.657Z,1666111015.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:37:30.797Z,1666111050.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:38:05.957Z,1666111085.957 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:38:41.105Z,1666111121.105 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:39:16.265Z,1666111156.265 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:39:51.405Z,1666111191.405 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:39:54.242Z,1666111194.242 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-10-18T16:39:54.260Z,1666111194.260 [BPC1](INFO): Received data from all battery sticks.
2022-10-18T16:40:09.196Z,1666111209.196 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T16:40:09.196Z,1666111209.196 [Default:CheckIn:C.Wait] Stopped
2022-10-18T16:40:09.196Z,1666111209.196 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T16:40:09.196Z,1666111209.196 [Default:CheckIn:D] Running Loop=1
2022-10-18T16:40:09.602Z,1666111209.602 [Default:CheckIn:D] Stopped
2022-10-18T16:40:09.602Z,1666111209.602 [Default:CheckIn:E] Running Loop=1
2022-10-18T16:40:10.027Z,1666111210.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.006388 min 
2022-10-18T16:40:10.028Z,1666111210.028 [Default:CheckIn:E] Stopped
2022-10-18T16:40:10.028Z,1666111210.028 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T16:40:10.028Z,1666111210.028 [Default:CheckIn] Stopped
2022-10-18T16:40:10.028Z,1666111210.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T16:40:10.028Z,1666111210.028 [Default:CheckIn](INFO): Running loop #12
2022-10-18T16:40:10.028Z,1666111210.028 [Default:CheckIn] Running Loop=12
2022-10-18T16:40:10.028Z,1666111210.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T16:40:10.029Z,1666111210.029 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:40:12.012Z,1666111212.011 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164011.00,A,3648.34564,N,12202.34971,W,26.359,271.05,181022,,,A*4F
2022-10-18T16:40:12.014Z,1666111212.014 [NAL9602](INFO): GPS fix at 20221018T164011: (36.805761, -122.039162)
2022-10-18T16:40:12.065Z,1666111212.065 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:40:12.066Z,1666111212.066 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:40:26.545Z,1666111226.545 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:40:31.077Z,1666111231.077 [NAL9602](INFO): SBD MO Status=1, MOMSN=21449, MT Status=0, MTMSN=0
2022-10-18T16:40:31.125Z,1666111231.125 [NAL9602](INFO): Sent 85 bytes from file Logs/20221018T152550/Courier0040.lzma
2022-10-18T16:40:31.125Z,1666111231.125 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:40:47.309Z,1666111247.309 [NAL9602](INFO): SBD MO Status=1, MOMSN=21450, MT Status=0, MTMSN=0
2022-10-18T16:40:47.357Z,1666111247.357 [NAL9602](INFO): Sent 140 bytes from file Logs/20221018T152550/Express0041.lzma
2022-10-18T16:40:47.357Z,1666111247.357 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:41:01.697Z,1666111261.697 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:41:04.675Z,1666111264.675 [NAL9602](INFO): SBD MO Status=2, MOMSN=21451, MT Status=2, MTMSN=0
2022-10-18T16:41:04.676Z,1666111264.676 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-10-18T16:41:15.261Z,1666111275.261 [NAL9602](INFO): SBD MO Status=0, MOMSN=21451, MT Status=0, MTMSN=0
2022-10-18T16:41:15.414Z,1666111275.414 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:41:15.414Z,1666111275.414 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:41:15.414Z,1666111275.414 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:41:36.841Z,1666111296.841 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:41:45.899Z,1666111305.899 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:42:11.989Z,1666111331.989 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:42:47.129Z,1666111367.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:43:22.269Z,1666111402.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:43:57.409Z,1666111437.409 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:44:32.549Z,1666111472.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:45:07.689Z,1666111507.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:45:34.174Z,1666111534.174 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2022-10-18T16:45:42.829Z,1666111542.829 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:46:15.857Z,1666111575.857 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T16:46:15.857Z,1666111575.857 [Default:CheckIn:C.Wait] Stopped
2022-10-18T16:46:15.857Z,1666111575.857 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T16:46:15.858Z,1666111575.858 [Default:CheckIn:D] Running Loop=1
2022-10-18T16:46:16.197Z,1666111576.197 [Default:CheckIn:D] Stopped
2022-10-18T16:46:16.197Z,1666111576.197 [Default:CheckIn:E] Running Loop=1
2022-10-18T16:46:16.617Z,1666111576.617 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.116300 min 
2022-10-18T16:46:16.617Z,1666111576.617 [Default:CheckIn:E] Stopped
2022-10-18T16:46:16.617Z,1666111576.617 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T16:46:16.617Z,1666111576.617 [Default:CheckIn] Stopped
2022-10-18T16:46:16.617Z,1666111576.617 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T16:46:16.618Z,1666111576.618 [Default:CheckIn](INFO): Running loop #13
2022-10-18T16:46:16.618Z,1666111576.618 [Default:CheckIn] Running Loop=13
2022-10-18T16:46:16.618Z,1666111576.618 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T16:46:16.618Z,1666111576.618 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:46:17.977Z,1666111577.977 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:46:18.604Z,1666111578.604 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164617.00,A,3648.51547,N,12205.67426,W,25.736,270.62,181022,,,A*4D
2022-10-18T16:46:18.606Z,1666111578.606 [NAL9602](INFO): GPS fix at 20221018T164617: (36.808591, -122.094571)
2022-10-18T16:46:18.626Z,1666111578.626 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:46:18.626Z,1666111578.626 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:46:34.840Z,1666111594.840 [NAL9602](INFO): SBD MO Status=1, MOMSN=21452, MT Status=0, MTMSN=0
2022-10-18T16:46:34.892Z,1666111594.892 [NAL9602](INFO): Sent 74 bytes from file Logs/20221018T152550/Courier0043.lzma
2022-10-18T16:46:34.892Z,1666111594.892 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:46:47.444Z,1666111607.444 [NAL9602](INFO): SBD MO Status=2, MOMSN=21453, MT Status=2, MTMSN=0
2022-10-18T16:46:47.445Z,1666111607.445 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-10-18T16:46:53.129Z,1666111613.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:46:59.239Z,1666111619.239 [NAL9602](INFO): SBD MO Status=1, MOMSN=21453, MT Status=0, MTMSN=0
2022-10-18T16:46:59.297Z,1666111619.297 [NAL9602](INFO): Sent 140 bytes from file Logs/20221018T152550/Express0044.lzma
2022-10-18T16:46:59.297Z,1666111619.297 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:47:20.243Z,1666111640.243 [NAL9602](INFO): SBD MO Status=0, MOMSN=21454, MT Status=0, MTMSN=0
2022-10-18T16:47:20.321Z,1666111640.321 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:47:20.321Z,1666111640.321 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:47:20.321Z,1666111640.321 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:47:28.269Z,1666111648.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:47:50.944Z,1666111670.944 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:48:03.409Z,1666111683.409 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:48:38.549Z,1666111718.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:49:13.689Z,1666111753.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:49:48.829Z,1666111788.829 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:50:23.981Z,1666111823.981 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:50:59.129Z,1666111859.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:51:34.285Z,1666111894.285 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:52:09.425Z,1666111929.425 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:52:20.840Z,1666111940.840 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T16:52:20.840Z,1666111940.840 [Default:CheckIn:C.Wait] Stopped
2022-10-18T16:52:20.840Z,1666111940.840 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T16:52:20.840Z,1666111940.840 [Default:CheckIn:D] Running Loop=1
2022-10-18T16:52:21.237Z,1666111941.237 [Default:CheckIn:D] Stopped
2022-10-18T16:52:21.237Z,1666111941.237 [Default:CheckIn:E] Running Loop=1
2022-10-18T16:52:21.641Z,1666111941.641 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.200293 min 
2022-10-18T16:52:21.641Z,1666111941.641 [Default:CheckIn:E] Stopped
2022-10-18T16:52:21.641Z,1666111941.641 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T16:52:21.641Z,1666111941.641 [Default:CheckIn] Stopped
2022-10-18T16:52:21.642Z,1666111941.642 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T16:52:21.642Z,1666111941.642 [Default:CheckIn](INFO): Running loop #14
2022-10-18T16:52:21.642Z,1666111941.642 [Default:CheckIn] Running Loop=14
2022-10-18T16:52:21.642Z,1666111941.642 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T16:52:21.642Z,1666111941.642 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:52:23.648Z,1666111943.648 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165222.00,A,3648.68881,N,12208.83612,W,23.773,279.27,181022,,,A*4E
2022-10-18T16:52:23.651Z,1666111943.651 [NAL9602](INFO): GPS fix at 20221018T165222: (36.811480, -122.147269)
2022-10-18T16:52:23.662Z,1666111943.662 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:52:23.662Z,1666111943.662 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:52:38.279Z,1666111958.279 [NAL9602](INFO): SBD MO Status=1, MOMSN=21455, MT Status=0, MTMSN=0
2022-10-18T16:52:38.341Z,1666111958.341 [NAL9602](INFO): Sent 74 bytes from file Logs/20221018T152550/Courier0046.lzma
2022-10-18T16:52:38.341Z,1666111958.341 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:52:44.412Z,1666111964.412 [NAL9602](INFO): SBD MO Status=1, MOMSN=21456, MT Status=0, MTMSN=0
2022-10-18T16:52:44.465Z,1666111964.465 [NAL9602](INFO): Sent 140 bytes from file Logs/20221018T152550/Express0047.lzma
2022-10-18T16:52:44.465Z,1666111964.465 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:52:44.573Z,1666111964.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:52:52.492Z,1666111972.492 [NAL9602](INFO): SBD MO Status=0, MOMSN=21457, MT Status=0, MTMSN=0
2022-10-18T16:52:52.581Z,1666111972.581 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:52:52.582Z,1666111972.582 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:52:52.582Z,1666111972.582 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:53:19.718Z,1666111999.718 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:53:23.194Z,1666112003.194 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:53:54.857Z,1666112034.857 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:54:30.001Z,1666112070.001 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:55:05.141Z,1666112105.141 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:55:40.289Z,1666112140.289 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:56:15.430Z,1666112175.430 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:56:50.581Z,1666112210.581 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:57:25.721Z,1666112245.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:57:53.075Z,1666112273.075 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T16:57:53.075Z,1666112273.075 [Default:CheckIn:C.Wait] Stopped
2022-10-18T16:57:53.075Z,1666112273.075 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T16:57:53.075Z,1666112273.075 [Default:CheckIn:D] Running Loop=1
2022-10-18T16:57:53.481Z,1666112273.481 [Default:CheckIn:D] Stopped
2022-10-18T16:57:53.481Z,1666112273.481 [Default:CheckIn:E] Running Loop=1
2022-10-18T16:57:53.911Z,1666112273.911 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.737695 min 
2022-10-18T16:57:53.911Z,1666112273.911 [Default:CheckIn:E] Stopped
2022-10-18T16:57:53.911Z,1666112273.911 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T16:57:53.911Z,1666112273.911 [Default:CheckIn] Stopped
2022-10-18T16:57:53.911Z,1666112273.911 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T16:57:53.911Z,1666112273.911 [Default:CheckIn](INFO): Running loop #15
2022-10-18T16:57:53.911Z,1666112273.911 [Default:CheckIn] Running Loop=15
2022-10-18T16:57:53.911Z,1666112273.911 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T16:57:53.911Z,1666112273.911 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T16:57:55.900Z,1666112275.900 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165755.00,A,3648.67237,N,12209.03845,W,0.719,162.80,181022,,,D*7A
2022-10-18T16:57:55.903Z,1666112275.903 [NAL9602](INFO): GPS fix at 20221018T165755: (36.811206, -122.150641)
2022-10-18T16:57:55.953Z,1666112275.953 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T16:57:55.953Z,1666112275.953 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T16:58:00.877Z,1666112280.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:58:20.195Z,1666112300.195 [NAL9602](INFO): SBD MO Status=1, MOMSN=21458, MT Status=0, MTMSN=0
2022-10-18T16:58:20.261Z,1666112300.261 [NAL9602](INFO): Sent 74 bytes from file Logs/20221018T152550/Courier0049.lzma
2022-10-18T16:58:20.261Z,1666112300.261 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:58:31.187Z,1666112311.187 [NAL9602](INFO): SBD MO Status=1, MOMSN=21459, MT Status=0, MTMSN=0
2022-10-18T16:58:31.237Z,1666112311.237 [NAL9602](INFO): Sent 130 bytes from file Logs/20221018T152550/Express0050.lzma
2022-10-18T16:58:31.237Z,1666112311.237 [NAL9602](INFO): Packets left to send: 0
2022-10-18T16:58:36.021Z,1666112316.021 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:58:50.987Z,1666112330.987 [NAL9602](INFO): SBD MO Status=0, MOMSN=21460, MT Status=0, MTMSN=0
2022-10-18T16:58:51.078Z,1666112331.078 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T16:58:51.078Z,1666112331.078 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T16:58:51.078Z,1666112331.078 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T16:59:11.161Z,1666112351.161 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T16:59:21.690Z,1666112361.690 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T16:59:46.319Z,1666112386.319 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:00:21.469Z,1666112421.469 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:00:56.610Z,1666112456.610 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:01:31.749Z,1666112491.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:02:06.897Z,1666112526.897 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:02:42.045Z,1666112562.045 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:03:17.185Z,1666112597.185 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:03:51.576Z,1666112631.576 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T17:03:51.576Z,1666112631.576 [Default:CheckIn:C.Wait] Stopped
2022-10-18T17:03:51.576Z,1666112631.576 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T17:03:51.576Z,1666112631.576 [Default:CheckIn:D] Running Loop=1
2022-10-18T17:03:51.996Z,1666112631.996 [Default:CheckIn:D] Stopped
2022-10-18T17:03:51.996Z,1666112631.996 [Default:CheckIn:E] Running Loop=1
2022-10-18T17:03:52.341Z,1666112632.341 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:03:52.390Z,1666112632.390 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.712948 min 
2022-10-18T17:03:52.390Z,1666112632.390 [Default:CheckIn:E] Stopped
2022-10-18T17:03:52.390Z,1666112632.390 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T17:03:52.390Z,1666112632.390 [Default:CheckIn] Stopped
2022-10-18T17:03:52.391Z,1666112632.391 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T17:03:52.391Z,1666112632.391 [Default:CheckIn](INFO): Running loop #16
2022-10-18T17:03:52.391Z,1666112632.391 [Default:CheckIn] Running Loop=16
2022-10-18T17:03:52.391Z,1666112632.391 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T17:03:52.391Z,1666112632.391 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T17:03:54.391Z,1666112634.391 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170353.00,A,3648.62012,N,12209.02525,W,0.467,140.86,181022,,,A*7F
2022-10-18T17:03:54.394Z,1666112634.394 [NAL9602](INFO): GPS fix at 20221018T170353: (36.810335, -122.150421)
2022-10-18T17:03:54.405Z,1666112634.405 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T17:03:54.405Z,1666112634.405 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T17:04:15.508Z,1666112655.508 [NAL9602](INFO): SBD MO Status=2, MOMSN=21461, MT Status=2, MTMSN=0
2022-10-18T17:04:15.508Z,1666112655.508 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-10-18T17:04:27.485Z,1666112667.485 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:04:44.268Z,1666112684.268 [NAL9602](INFO): SBD MO Status=1, MOMSN=21461, MT Status=0, MTMSN=0
2022-10-18T17:04:44.329Z,1666112684.329 [NAL9602](INFO): Sent 74 bytes from file Logs/20221018T152550/Courier0052.lzma
2022-10-18T17:04:44.329Z,1666112684.329 [NAL9602](INFO): Packets left to send: 0
2022-10-18T17:04:53.248Z,1666112693.248 [NAL9602](INFO): SBD MO Status=1, MOMSN=21462, MT Status=0, MTMSN=0
2022-10-18T17:04:53.305Z,1666112693.305 [NAL9602](INFO): Sent 130 bytes from file Logs/20221018T152550/Express0053.lzma
2022-10-18T17:04:53.305Z,1666112693.305 [NAL9602](INFO): Packets left to send: 0
2022-10-18T17:05:02.646Z,1666112702.646 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:05:03.734Z,1666112703.734 [NAL9602](INFO): SBD MO Status=0, MOMSN=21463, MT Status=0, MTMSN=0
2022-10-18T17:05:03.845Z,1666112703.845 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T17:05:03.845Z,1666112703.845 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T17:05:03.846Z,1666112703.846 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T17:05:34.438Z,1666112734.438 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T17:05:37.789Z,1666112737.789 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:06:12.929Z,1666112772.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:06:48.081Z,1666112808.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:07:23.233Z,1666112843.233 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:07:58.373Z,1666112878.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:08:33.521Z,1666112913.521 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:09:08.681Z,1666112948.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:09:43.830Z,1666112983.830 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:10:04.323Z,1666113004.323 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T17:10:04.323Z,1666113004.323 [Default:CheckIn:C.Wait] Stopped
2022-10-18T17:10:04.323Z,1666113004.323 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T17:10:04.323Z,1666113004.323 [Default:CheckIn:D] Running Loop=1
2022-10-18T17:10:04.732Z,1666113004.732 [Default:CheckIn:D] Stopped
2022-10-18T17:10:04.732Z,1666113004.732 [Default:CheckIn:E] Running Loop=1
2022-10-18T17:10:05.150Z,1666113005.150 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.925220 min 
2022-10-18T17:10:05.150Z,1666113005.150 [Default:CheckIn:E] Stopped
2022-10-18T17:10:05.150Z,1666113005.150 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T17:10:05.150Z,1666113005.150 [Default:CheckIn] Stopped
2022-10-18T17:10:05.150Z,1666113005.150 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T17:10:05.151Z,1666113005.151 [Default:CheckIn](INFO): Running loop #17
2022-10-18T17:10:05.151Z,1666113005.151 [Default:CheckIn] Running Loop=17
2022-10-18T17:10:05.151Z,1666113005.151 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T17:10:05.151Z,1666113005.151 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T17:10:07.146Z,1666113007.146 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171006.00,A,3648.56141,N,12209.00088,W,0.894,115.11,181022,,,D*76
2022-10-18T17:10:07.148Z,1666113007.148 [NAL9602](INFO): GPS fix at 20221018T171006: (36.809357, -122.150015)
2022-10-18T17:10:07.159Z,1666113007.159 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T17:10:07.159Z,1666113007.159 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T17:10:18.969Z,1666113018.969 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:10:26.179Z,1666113026.179 [NAL9602](INFO): SBD MO Status=1, MOMSN=21464, MT Status=0, MTMSN=0
2022-10-18T17:10:26.233Z,1666113026.233 [NAL9602](INFO): Sent 74 bytes from file Logs/20221018T152550/Courier0055.lzma
2022-10-18T17:10:26.233Z,1666113026.233 [NAL9602](INFO): Packets left to send: 0
2022-10-18T17:10:35.983Z,1666113035.983 [NAL9602](INFO): SBD MO Status=1, MOMSN=21465, MT Status=0, MTMSN=0
2022-10-18T17:10:36.033Z,1666113036.033 [NAL9602](INFO): Sent 128 bytes from file Logs/20221018T152550/Express0056.lzma
2022-10-18T17:10:36.033Z,1666113036.033 [NAL9602](INFO): Packets left to send: 0
2022-10-18T17:10:45.280Z,1666113045.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=21466, MT Status=0, MTMSN=0
2022-10-18T17:10:45.370Z,1666113045.370 [Default:CheckIn:Read_Iridium] Stopped
2022-10-18T17:10:45.370Z,1666113045.370 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-18T17:10:45.370Z,1666113045.370 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-18T17:10:54.109Z,1666113054.109 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:11:16.072Z,1666113076.072 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-18T17:11:29.249Z,1666113089.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:12:04.401Z,1666113124.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:12:39.553Z,1666113159.553 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:13:14.693Z,1666113194.693 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:13:49.833Z,1666113229.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:14:24.989Z,1666113264.989 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:15:00.129Z,1666113300.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:15:35.277Z,1666113335.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-18T17:15:45.974Z,1666113345.974 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-18T17:15:45.974Z,1666113345.974 [Default:CheckIn:C.Wait] Stopped
2022-10-18T17:15:45.974Z,1666113345.974 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-18T17:15:45.974Z,1666113345.974 [Default:CheckIn:D] Running Loop=1
2022-10-18T17:15:46.352Z,1666113346.352 [Default:CheckIn:D] Stopped
2022-10-18T17:15:46.352Z,1666113346.352 [Default:CheckIn:E] Running Loop=1
2022-10-18T17:15:46.756Z,1666113346.756 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.618888 min 
2022-10-18T17:15:46.756Z,1666113346.756 [Default:CheckIn:E] Stopped
2022-10-18T17:15:46.756Z,1666113346.756 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-18T17:15:46.756Z,1666113346.756 [Default:CheckIn] Stopped
2022-10-18T17:15:46.756Z,1666113346.756 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-18T17:15:46.757Z,1666113346.757 [Default:CheckIn](INFO): Running loop #18
2022-10-18T17:15:46.757Z,1666113346.757 [Default:CheckIn] Running Loop=18
2022-10-18T17:15:46.757Z,1666113346.757 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-18T17:15:46.757Z,1666113346.757 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-18T17:15:48.766Z,1666113348.766 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171547.00,A,3648.52503,N,12208.96849,W,0.661,142.64,181022,,,D*7F
2022-10-18T17:15:48.768Z,1666113348.768 [NAL9602](INFO): GPS fix at 20221018T171547: (36.808751, -122.149475)
2022-10-18T17:15:48.779Z,1666113348.779 [Default:CheckIn:Read_GPS] Stopped
2022-10-18T17:15:48.779Z,1666113348.779 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-18T17:16:03.415Z,1666113363.415 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=21467, MT Status=1, MTMSN=1146
2022-10-18T17:16:03.479Z,1666113363.479 [NAL9602](INFO): Sent 74 bytes from file Logs/20221018T152550/Courier0058.lzma
2022-10-18T17:16:03.479Z,1666113363.479 [NAL9602](INFO): Packets left to send: 0
2022-10-18T17:16:03.909Z,1666113363.909 [NAL9602](INFO): Received command: restart logs