2023-01-17T16:33:02.533Z,1673973182.533 [Supervisor](DEBUG): Initializing supervisor.
2023-01-17T16:33:02.538Z,1673973182.538 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-01-17T16:33:02.538Z,1673973182.538 [SyncHandler](INFO): Protected caller Thread ID is 1780
2023-01-17T16:33:02.539Z,1673973182.539 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-01-17T16:33:02.540Z,1673973182.540 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-01-17T16:33:02.540Z,1673973182.540 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1781
2023-01-17T16:33:02.545Z,1673973182.545 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-01-17T16:33:02.563Z,1673973182.563 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-01-17T16:33:02.564Z,1673973182.564 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-01-17T16:33:02.564Z,1673973182.564 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1782
2023-01-17T16:33:02.568Z,1673973182.568 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-01-17T16:33:02.569Z,1673973182.569 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-01-17T16:33:02.569Z,1673973182.569 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1783
2023-01-17T16:33:02.571Z,1673973182.571 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-01-17T16:33:02.572Z,1673973182.572 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-01-17T16:33:02.573Z,1673973182.573 [logger ThreadHandler](INFO): Protected caller Thread ID is 1784
2023-01-17T16:33:02.576Z,1673973182.576 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-01-17T16:33:02.577Z,1673973182.577 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-01-17T16:33:02.578Z,1673973182.578 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-01-17T16:33:02.837Z,1673973182.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-01-17T16:33:02.837Z,1673973182.837 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-01-17T16:33:02.917Z,1673973182.917 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-01-17T16:33:03.369Z,1673973183.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-01-17T16:33:03.370Z,1673973183.370 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-01-17T16:33:03.679Z,1673973183.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-01-17T16:33:03.680Z,1673973183.680 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-01-17T16:33:03.775Z,1673973183.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-01-17T16:33:03.776Z,1673973183.776 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-01-17T16:33:04.149Z,1673973184.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-01-17T16:33:04.149Z,1673973184.149 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-01-17T16:33:04.350Z,1673973184.350 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-01-17T16:33:04.350Z,1673973184.350 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-01-17T16:33:04.830Z,1673973184.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-01-17T16:33:04.831Z,1673973184.831 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-01-17T16:33:04.963Z,1673973184.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-01-17T16:33:04.964Z,1673973184.964 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-01-17T16:33:05.043Z,1673973185.043 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-01-17T16:33:05.750Z,1673973185.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-01-17T16:33:05.751Z,1673973185.751 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-01-17T16:33:06.101Z,1673973186.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-01-17T16:33:06.102Z,1673973186.102 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-01-17T16:33:06.285Z,1673973186.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-01-17T16:33:06.285Z,1673973186.285 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-01-17T16:33:06.395Z,1673973186.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-01-17T16:33:06.396Z,1673973186.396 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-01-17T16:33:06.611Z,1673973186.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-01-17T16:33:06.612Z,1673973186.612 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-01-17T16:33:06.852Z,1673973186.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-01-17T16:33:06.854Z,1673973186.854 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-01-17T16:33:06.855Z,1673973186.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-01-17T16:33:07.162Z,1673973187.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-01-17T16:33:07.396Z,1673973187.396 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-01-17T16:33:07.529Z,1673973187.529 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-01-17T16:33:07.740Z,1673973187.740 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-01-17T16:33:07.878Z,1673973187.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-01-17T16:33:07.976Z,1673973187.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-01-17T16:33:08.150Z,1673973188.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-01-17T16:33:08.226Z,1673973188.226 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-01-17T16:33:08.368Z,1673973188.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-01-17T16:33:08.500Z,1673973188.500 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-01-17T16:33:08.613Z,1673973188.613 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-01-17T16:33:08.872Z,1673973188.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-01-17T16:33:08.873Z,1673973188.873 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2023-01-17T16:33:08.873Z,1673973188.873 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-01-17T16:33:08.887Z,1673973188.887 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-01-17T16:33:08.937Z,1673973188.937 [VerticalControl](DEBUG): Construct VerticalControl.
2023-01-17T16:33:08.997Z,1673973188.997 [VerticalControl] Loaded
2023-01-17T16:33:08.997Z,1673973188.997 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-01-17T16:33:08.000Z,1673973189.000 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-01-17T16:33:09.041Z,1673973189.041 [HorizontalControl] Loaded
2023-01-17T16:33:09.041Z,1673973189.041 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-01-17T16:33:09.043Z,1673973189.043 [SpeedControl](DEBUG): Construct SpeedControl.
2023-01-17T16:33:09.046Z,1673973189.046 [SpeedControl] Loaded
2023-01-17T16:33:09.047Z,1673973189.047 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-01-17T16:33:09.049Z,1673973189.049 [LoopControl](DEBUG): Construct LoopControl.
2023-01-17T16:33:09.050Z,1673973189.050 [LoopControl] Loaded
2023-01-17T16:33:09.050Z,1673973189.050 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-01-17T16:33:09.050Z,1673973189.050 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-01-17T16:33:09.051Z,1673973189.051 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-01-17T16:33:09.061Z,1673973189.061 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-01-17T16:33:09.061Z,1673973189.061 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-01-17T16:33:09.207Z,1673973189.207 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-01-17T16:33:09.208Z,1673973189.208 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-01-17T16:33:09.359Z,1673973189.359 [BuoyancyServo] Loaded
2023-01-17T16:33:09.359Z,1673973189.359 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-01-17T16:33:09.379Z,1673973189.379 [ElevatorServo] Loaded
2023-01-17T16:33:09.379Z,1673973189.379 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-01-17T16:33:09.398Z,1673973189.398 [MassServo] Loaded
2023-01-17T16:33:09.398Z,1673973189.398 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-01-17T16:33:09.416Z,1673973189.416 [RudderServo] Loaded
2023-01-17T16:33:09.417Z,1673973189.417 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-01-17T16:33:09.433Z,1673973189.433 [ThrusterServo] Loaded
2023-01-17T16:33:09.434Z,1673973189.434 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-01-17T16:33:09.434Z,1673973189.434 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-01-17T16:33:09.435Z,1673973189.435 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-01-17T16:33:09.544Z,1673973189.544 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-01-17T16:33:09.545Z,1673973189.545 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-01-17T16:33:09.559Z,1673973189.559 [NavChart] Loaded
2023-01-17T16:33:09.559Z,1673973189.559 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-01-17T16:33:09.565Z,1673973189.565 [UniversalFixResidualReporter] Loaded
2023-01-17T16:33:09.565Z,1673973189.565 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-01-17T16:33:09.566Z,1673973189.566 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-01-17T16:33:09.566Z,1673973189.566 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-01-17T16:33:09.621Z,1673973189.621 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-01-17T16:33:09.622Z,1673973189.622 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-01-17T16:33:10.004Z,1673973190.004 [AHRS_M2] Loaded
2023-01-17T16:33:10.004Z,1673973190.004 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-01-17T16:33:10.812Z,1673973190.812 [BPC1] Loaded
2023-01-17T16:33:10.812Z,1673973190.812 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-01-17T16:33:10.884Z,1673973190.884 [DataOverHttps] Loaded
2023-01-17T16:33:10.884Z,1673973190.884 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-01-17T16:33:10.885Z,1673973190.885 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408EE4E0
2023-01-17T16:33:10.886Z,1673973190.886 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1869
2023-01-17T16:33:10.906Z,1673973190.906 [Depth_Keller] Loaded
2023-01-17T16:33:10.907Z,1673973190.907 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-01-17T16:33:10.911Z,1673973190.911 [DropWeight] Loaded
2023-01-17T16:33:10.912Z,1673973190.912 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-01-17T16:33:10.972Z,1673973190.972 [NAL9602] Loaded
2023-01-17T16:33:10.972Z,1673973190.972 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-01-17T16:33:11.001Z,1673973191.001 [Onboard] Loaded
2023-01-17T16:33:11.002Z,1673973191.002 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-01-17T16:33:11.003Z,1673973191.003 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4091E4E0
2023-01-17T16:33:11.003Z,1673973191.003 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1870
2023-01-17T16:33:11.016Z,1673973191.016 [Power24vConverter] Loaded
2023-01-17T16:33:11.016Z,1673973191.016 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-01-17T16:33:11.030Z,1673973191.030 [Radio_Surface] Loaded
2023-01-17T16:33:11.030Z,1673973191.030 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-01-17T16:33:11.031Z,1673973191.031 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4094E4E0
2023-01-17T16:33:11.031Z,1673973191.031 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1871
2023-01-17T16:33:11.032Z,1673973191.032 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-01-17T16:33:11.033Z,1673973191.033 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-01-17T16:33:11.082Z,1673973191.082 [DepthRateCalculator] Loaded
2023-01-17T16:33:11.082Z,1673973191.082 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-01-17T16:33:11.087Z,1673973191.087 [PitchRateCalculator] Loaded
2023-01-17T16:33:11.088Z,1673973191.088 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-01-17T16:33:11.096Z,1673973191.096 [SpeedCalculator] Loaded
2023-01-17T16:33:11.097Z,1673973191.097 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-01-17T16:33:11.101Z,1673973191.101 [YawRateCalculator] Loaded
2023-01-17T16:33:11.101Z,1673973191.101 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-01-17T16:33:11.120Z,1673973191.120 [ElevatorOffsetCalculator] Loaded
2023-01-17T16:33:11.120Z,1673973191.120 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-01-17T16:33:11.121Z,1673973191.121 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-01-17T16:33:11.121Z,1673973191.121 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-01-17T16:33:11.248Z,1673973191.248 [CANONSampler] Loaded
2023-01-17T16:33:11.248Z,1673973191.248 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-01-17T16:33:11.307Z,1673973191.307 [CTD_Seabird] Loaded
2023-01-17T16:33:11.307Z,1673973191.307 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-01-17T16:33:11.308Z,1673973191.308 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A074E0
2023-01-17T16:33:11.308Z,1673973191.308 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1872
2023-01-17T16:33:11.328Z,1673973191.328 [PAR_Licor] Loaded
2023-01-17T16:33:11.329Z,1673973191.329 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-01-17T16:33:11.360Z,1673973191.360 [WetLabsBB2FL] Loaded
2023-01-17T16:33:11.360Z,1673973191.360 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-01-17T16:33:11.361Z,1673973191.361 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A374E0
2023-01-17T16:33:11.361Z,1673973191.361 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1873
2023-01-17T16:33:11.362Z,1673973191.362 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-01-17T16:33:11.363Z,1673973191.363 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-01-17T16:33:11.388Z,1673973191.388 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-01-17T16:33:11.389Z,1673973191.389 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-01-17T16:33:11.696Z,1673973191.696 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-01-17T16:33:11.697Z,1673973191.697 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-01-17T16:33:11.834Z,1673973191.834 [SBIT](DEBUG): Construct Startup Built In Test.
2023-01-17T16:33:11.843Z,1673973191.843 [SBIT] Loaded
2023-01-17T16:33:11.843Z,1673973191.843 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-01-17T16:33:11.846Z,1673973191.846 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-01-17T16:33:11.859Z,1673973191.859 [IBIT] Loaded
2023-01-17T16:33:11.859Z,1673973191.859 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-01-17T16:33:11.865Z,1673973191.865 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-01-17T16:33:11.968Z,1673973191.968 [CBIT] Loaded
2023-01-17T16:33:11.969Z,1673973191.969 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-01-17T16:33:11.969Z,1673973191.969 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-01-17T16:33:11.970Z,1673973191.970 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-01-17T16:33:12.399Z,1673973192.399 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-01-17T16:33:12.441Z,1673973192.441 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-01-17T16:33:12.455Z,1673973192.455 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-01-17T16:33:12.466Z,1673973192.466 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-01-17T16:33:12.467Z,1673973192.467 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B6F4E0
2023-01-17T16:33:12.468Z,1673973192.468 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1874
2023-01-17T16:33:12.603Z,1673973192.603 [Supervisor](INFO): Main Thread ID is 830
2023-01-17T16:33:12.603Z,1673973192.603 [Supervisor](DEBUG): Running supervisor.
2023-01-17T16:33:12.604Z,1673973192.604 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1875
2023-01-17T16:33:12.604Z,1673973192.604 [CommandExec](INFO): Initializing the command executive.
2023-01-17T16:33:12.606Z,1673973192.606 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1876
2023-01-17T16:33:12.608Z,1673973192.608 [controlThread ThreadHandler](INFO): Handler Thread ID is 1877
2023-01-17T16:33:12.609Z,1673973192.609 [controlThread](DEBUG): Initializing ControlThread
2023-01-17T16:33:12.610Z,1673973192.610 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-01-17T16:33:12.612Z,1673973192.612 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-01-17T16:33:12.612Z,1673973192.612 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-01-17T16:33:12.613Z,1673973192.613 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-01-17T16:33:12.615Z,1673973192.615 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-01-17T16:33:12.615Z,1673973192.615 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-01-17T16:33:12.621Z,1673973192.621 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-01-17T16:33:12.622Z,1673973192.622 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-01-17T16:33:12.622Z,1673973192.622 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-01-17T16:33:12.622Z,1673973192.622 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-01-17T16:33:12.623Z,1673973192.623 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-01-17T16:33:12.628Z,1673973192.628 [SBIT](INFO): Initialize SBIT Component.
2023-01-17T16:33:12.628Z,1673973192.628 [SBIT](IMPORTANT): git: 2023-01-12
2023-01-17T16:33:12.628Z,1673973192.628 [SBIT](INFO): git hash: 9912d4ecea2258dc5fc1412f04f143c5e875fcb2
2023-01-17T16:33:12.629Z,1673973192.629 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-01-17T16:33:12.630Z,1673973192.630 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-01-17T16:33:12.631Z,1673973192.631 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2023-01-17T16:33:12.632Z,1673973192.632 [IBIT](INFO): Initialize IBIT Component.
2023-01-17T16:33:12.633Z,1673973192.633 [CBIT](DEBUG): Initialize CBIT Component.
2023-01-17T16:33:12.633Z,1673973192.633 [logger ThreadHandler](INFO): Handler Thread ID is 1878
2023-01-17T16:33:12.643Z,1673973192.643 [CBIT](DEBUG): Initialized mux pins.
2023-01-17T16:33:12.644Z,1673973192.644 [CBIT](DEBUG): Initializing the watchdog timer.
2023-01-17T16:33:12.652Z,1673973192.652 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1879
2023-01-17T16:33:12.653Z,1673973192.653 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-01-17T16:33:12.664Z,1673973192.664 [Onboard ThreadHandler](INFO): Handler Thread ID is 1880
2023-01-17T16:33:12.668Z,1673973192.668 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-01-17T16:33:12.669Z,1673973192.669 [CBIT](DEBUG): Initializing heartbeat.
2023-01-17T16:33:12.684Z,1673973192.684 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1881
2023-01-17T16:33:12.696Z,1673973192.696 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1882
2023-01-17T16:33:12.697Z,1673973192.697 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-01-17T16:33:12.701Z,1673973192.701 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1884
2023-01-17T16:33:12.704Z,1673973192.704 [WetLabsBB2FL](INFO): Powering up
2023-01-17T16:33:12.705Z,1673973192.705 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1886
2023-01-17T16:33:12.709Z,1673973192.709 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-01-17T16:33:12.709Z,1673973192.709 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-01-17T16:33:12.709Z,1673973192.709 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-01-17T16:33:12.709Z,1673973192.709 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-01-17T16:33:12.709Z,1673973192.709 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-01-17T16:33:12.710Z,1673973192.710 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-01-17T16:33:12.710Z,1673973192.710 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-01-17T16:33:12.710Z,1673973192.710 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-01-17T16:33:12.739Z,1673973192.739 [CBIT](DEBUG): Deactivating GF circuits.
2023-01-17T16:33:12.739Z,1673973192.739 [CBIT](DEBUG): Deactivating emergency mode.
2023-01-17T16:33:12.775Z,1673973192.775 [CBIT](DEBUG): Backplane powered.
2023-01-17T16:33:12.776Z,1673973192.776 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-01-17T16:33:12.790Z,1673973192.790 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-01-17T16:33:12.814Z,1673973192.814 [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>


2023-01-17T16:33:12.814Z,1673973192.814 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-01-17T16:33:12.888Z,1673973192.888 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-01-17T16:33:12.889Z,1673973192.889 [Default:A.Wait](DEBUG): Construct Wait.
2023-01-17T16:33:12.902Z,1673973192.902 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-01-17T16:33:12.951Z,1673973192.951 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-01-17T16:33:12.954Z,1673973192.954 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-01-17T16:33:12.976Z,1673973192.976 [Default:E.Execute](DEBUG): Construct Execute.
2023-01-17T16:33:12.979Z,1673973192.979 [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="P4H">
                <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>


2023-01-17T16:33:12.991Z,1673973192.991 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,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,Reporter,LogSplitter,
2023-01-17T16:33:13.004Z,1673973193.004 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-01-17T16:33:13.074Z,1673973193.074 [Power24vConverter](INFO): Powering up.
2023-01-17T16:33:13.075Z,1673973193.075 [CANONSampler](INFO): Powering down
2023-01-17T16:33:13.088Z,1673973193.088 [Radio_Surface](INFO): Powering up
2023-01-17T16:33:13.113Z,1673973193.113 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:33:13.120Z,1673973193.120 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-01-17T16:33:13.121Z,1673973193.121 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:33:13.128Z,1673973193.128 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-01-17T16:33:13.129Z,1673973193.129 [MassServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:33:13.136Z,1673973193.136 [MassServo](DEBUG): Initializing MassServo.
2023-01-17T16:33:13.137Z,1673973193.137 [RudderServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:33:13.144Z,1673973193.144 [RudderServo](DEBUG): Initializing RudderServo.
2023-01-17T16:33:13.145Z,1673973193.145 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:33:13.152Z,1673973193.152 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-01-17T16:33:14.811Z,1673973194.811 [WetLabsBB2FL](INFO): Powering down
2023-01-17T16:33:19.728Z,1673973199.728 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-01-17T16:33:41.285Z,1673973221.285 [NAL9602](INFO): Powering up NAL9602
2023-01-17T16:33:52.197Z,1673973232.197 [NAL9602](INFO): NAL9602 initialized
2023-01-17T16:34:05.144Z,1673973245.144 [SBIT](IMPORTANT): Beginning Startup BIT
2023-01-17T16:34:05.148Z,1673973245.148 [CBIT](IMPORTANT): Beginning ground fault scan
2023-01-17T16:34:16.086Z,1673973256.086 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-01-17T16:34:16.491Z,1673973256.491 [CBIT](IMPORTANT): Beginning ground fault scan
2023-01-17T16:34:27.500Z,1673973267.500 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-01-17T16:34:27.873Z,1673973267.873 [CBIT](IMPORTANT): Beginning ground fault scan
2023-01-17T16:34:38.678Z,1673973278.678 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-01-17T16:34:38.678Z,1673973278.678 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-01-17T16:34:59.287Z,1673973299.287 [SBIT](IMPORTANT): SBIT PASSED
2023-01-17T16:34:59.288Z,1673973299.288 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-01-17T16:34:59.288Z,1673973299.288 [SBIT](IMPORTANT): AHRS_M2.readAccelerations=0 bool;

2023-01-17T16:34:59.288Z,1673973299.288 [SBIT](IMPORTANT): AHRS_M2.readAngularVelocities=0 bool;

2023-01-17T16:34:59.289Z,1673973299.289 [SBIT](IMPORTANT): AHRS_M2.readMagnetics=0 bool;

2023-01-17T16:34:59.289Z,1673973299.289 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-01-17T16:34:59.289Z,1673973299.289 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count;

2023-01-17T16:34:59.289Z,1673973299.289 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-01-17T16:34:59.289Z,1673973299.289 [SBIT](IMPORTANT): CANONSampler.sampleTimeout=10 minute;

2023-01-17T16:34:59.289Z,1673973299.289 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2023-01-17T16:34:59.289Z,1673973299.289 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-01-17T16:34:59.289Z,1673973299.289 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2023-01-17T16:34:59.290Z,1673973299.290 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2023-01-17T16:34:59.290Z,1673973299.290 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2023-01-17T16:34:59.290Z,1673973299.290 [SBIT](IMPORTANT): Express linearApproximation ThrusterServo.component_avgCurrent 10.000000 milliampere;

2023-01-17T16:34:59.290Z,1673973299.290 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=176.310712 cubic_centimeter;

2023-01-17T16:34:59.290Z,1673973299.290 [SBIT](IMPORTANT): VerticalControl.massDefault=-8.249717 millimeter;

2023-01-17T16:34:59.682Z,1673973299.682 [MissionManager](IMPORTANT): Started mission Startup
2023-01-17T16:34:59.682Z,1673973299.682 [Startup] Running Loop=1
2023-01-17T16:34:59.682Z,1673973299.682 [Startup](DEBUG): Aggregate::initialize Startup
2023-01-17T16:34:59.682Z,1673973299.682 [Startup:A.GoToSurface] Running Loop=1
2023-01-17T16:34:59.683Z,1673973299.683 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-01-17T16:34:59.699Z,1673973299.699 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-01-17T16:34:59.700Z,1673973299.700 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-01-17T16:34:59.700Z,1673973299.700 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-01-17T16:34:59.701Z,1673973299.701 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-01-17T16:34:59.701Z,1673973299.701 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-01-17T16:34:59.702Z,1673973299.702 [Startup:StartupSatComms] Running Loop=1
2023-01-17T16:34:59.702Z,1673973299.702 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-01-17T16:34:59.702Z,1673973299.702 [Startup:StartupSatComms:A] Running Loop=1
2023-01-17T16:35:00.086Z,1673973300.086 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-01-17T16:35:55.538Z,1673973355.538 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004995
2023-01-17T16:35:59.961Z,1673973359.961 [Startup:StartupSatComms:A](INFO): Timed out from 2023-01-17T16:34:59.7Z
2023-01-17T16:35:59.961Z,1673973359.961 [Startup:StartupSatComms:A] Stopped
2023-01-17T16:35:59.961Z,1673973359.961 [Startup:StartupSatComms:B] Running Loop=1
2023-01-17T16:36:00.293Z,1673973360.293 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-01-17T16:36:06.771Z,1673973366.771 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230117T162735/Courier0004.lzma
2023-01-17T16:36:07.773Z,1673973367.773 [DataOverHttps](INFO): Moved sent file to Logs/20230117T162735/Courier0004.lzma.bak
2023-01-17T16:36:07.774Z,1673973367.774 [DataOverHttps](INFO): SBD MOMSN=17401482
2023-01-17T16:36:12.946Z,1673973372.946 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-01-17T16:36:12.946Z,1673973372.946 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-01-17T16:36:12.968Z,1673973372.968 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-01-17T16:36:13.380Z,1673973373.380 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-01-17T16:36:13.380Z,1673973373.380 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-01-17T16:36:23.431Z,1673973383.431 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230117T163302/Courier0000.lzma
2023-01-17T16:36:24.433Z,1673973384.433 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0000.lzma.bak
2023-01-17T16:36:24.433Z,1673973384.433 [DataOverHttps](INFO): SBD MOMSN=17401484
2023-01-17T16:36:39.903Z,1673973399.903 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20230117T162735/Express0005.lzma
2023-01-17T16:36:40.905Z,1673973400.905 [DataOverHttps](INFO): Moved sent file to Logs/20230117T162735/Express0005.lzma.bak
2023-01-17T16:36:40.905Z,1673973400.905 [DataOverHttps](INFO): SBD MOMSN=17401487
2023-01-17T16:36:56.484Z,1673973416.484 [DataOverHttps](INFO): Sending 947 bytes from file Logs/20230117T163302/Express0001.lzma
2023-01-17T16:36:57.477Z,1673973417.477 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0001.lzma.bak
2023-01-17T16:36:57.477Z,1673973417.477 [DataOverHttps](INFO): SBD MOMSN=17401491
2023-01-17T16:36:58.882Z,1673973418.882 [Startup:StartupSatComms:B] Stopped
2023-01-17T16:36:58.883Z,1673973418.883 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-01-17T16:36:58.883Z,1673973418.883 [Startup:StartupSatComms] Stopped
2023-01-17T16:36:58.883Z,1673973418.883 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-01-17T16:36:58.884Z,1673973418.884 [Startup](INFO): Completed Startup
2023-01-17T16:36:58.884Z,1673973418.884 [MissionManager](INFO): Startup is completed.
2023-01-17T16:36:58.884Z,1673973418.884 [MissionManager](INFO): Uninitializing Mission Startup
2023-01-17T16:36:58.884Z,1673973418.884 [Startup] Stopped
2023-01-17T16:36:58.884Z,1673973418.884 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-01-17T16:36:58.884Z,1673973418.884 [Startup:A.GoToSurface] Stopped
2023-01-17T16:36:58.884Z,1673973418.884 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-01-17T16:36:59.277Z,1673973419.277 [MissionManager](IMPORTANT): Started mission Default
2023-01-17T16:36:59.278Z,1673973419.278 [Default] Running Loop=1
2023-01-17T16:36:59.278Z,1673973419.278 [Default](DEBUG): Aggregate::initialize Default
2023-01-17T16:36:59.278Z,1673973419.278 [Default:B.GoToSurface] Running Loop=1
2023-01-17T16:36:59.278Z,1673973419.278 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-01-17T16:36:59.278Z,1673973419.278 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-01-17T16:36:59.279Z,1673973419.279 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-01-17T16:36:59.279Z,1673973419.279 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-01-17T16:36:59.279Z,1673973419.279 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-01-17T16:36:59.280Z,1673973419.280 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-01-17T16:36:59.280Z,1673973419.280 [Default:A.Wait] Running Loop=1
2023-01-17T16:36:59.280Z,1673973419.280 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-01-17T16:37:12.593Z,1673973432.593 [Default:A.Wait](INFO): Done Waiting.
2023-01-17T16:37:12.593Z,1673973432.593 [Default:A.Wait] Stopped
2023-01-17T16:37:12.593Z,1673973432.593 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T16:37:13.003Z,1673973433.003 [Default:CheckIn] Running Loop=1
2023-01-17T16:37:13.004Z,1673973433.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T16:37:13.004Z,1673973433.004 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T16:37:13.384Z,1673973433.384 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:37:13.435Z,1673973433.435 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-01-17T16:37:21.524Z,1673973441.524 [CommandExec](IMPORTANT): got command burn on
2023-01-17T16:37:21.524Z,1673973441.524 [CommandExec](IMPORTANT): Activating dropweight wire
2023-01-17T16:37:28.736Z,1673973448.736 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:37:31.325Z,1673973451.325 [CommandExec](IMPORTANT): got command burn off
2023-01-17T16:37:31.325Z,1673973451.325 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-01-17T16:37:44.494Z,1673973464.494 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:37:48.130Z,1673973468.130 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-01-17T16:37:59.458Z,1673973479.458 [CommandExec](IMPORTANT): got command burn on
2023-01-17T16:37:59.458Z,1673973479.458 [CommandExec](IMPORTANT): Activating dropweight wire
2023-01-17T16:37:59.844Z,1673973479.844 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:38:03.076Z,1673973483.076 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-01-17T16:38:06.136Z,1673973486.136 [CommandExec](IMPORTANT): got command burn off
2023-01-17T16:38:06.137Z,1673973486.137 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-01-17T16:38:15.201Z,1673973495.201 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:38:15.612Z,1673973495.612 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-01-17T16:38:28.130Z,1673973508.130 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-01-17T16:38:30.548Z,1673973510.548 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:38:40.244Z,1673973520.244 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-01-17T16:38:46.310Z,1673973526.310 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:38:52.768Z,1673973532.768 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-01-17T16:38:55.605Z,1673973535.605 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-01-17T16:39:01.252Z,1673973541.252 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:39:05.292Z,1673973545.292 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-01-17T16:39:13.795Z,1673973553.795 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-01-17T16:39:13.795Z,1673973553.795 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-01-17T16:39:13.835Z,1673973553.835 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-01-17T16:39:14.206Z,1673973554.206 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-01-17T16:39:14.206Z,1673973554.206 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-01-17T16:39:16.206Z,1673973556.206 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:39:16.208Z,1673973556.208 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2023-01-17T16:39:31.552Z,1673973571.552 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2023-01-17T16:40:23.279Z,1673973623.279 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2023-01-17T16:40:23.283Z,1673973623.283 [BPC1](INFO): Received data from all battery sticks.
2023-01-17T16:42:13.177Z,1673973733.177 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-01-17T16:37:13.0Z
2023-01-17T16:42:13.177Z,1673973733.177 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T16:42:13.177Z,1673973733.177 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T16:42:13.595Z,1673973733.595 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-01-17T16:42:14.809Z,1673973734.809 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-01-17T16:42:14.809Z,1673973734.809 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-01-17T16:42:14.819Z,1673973734.819 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-01-17T16:42:15.217Z,1673973735.217 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-01-17T16:42:15.217Z,1673973735.217 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-01-17T16:42:20.047Z,1673973740.047 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230117T163302/Courier0004.lzma
2023-01-17T16:42:21.049Z,1673973741.049 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0004.lzma.bak
2023-01-17T16:42:21.049Z,1673973741.049 [DataOverHttps](INFO): SBD MOMSN=17401519
2023-01-17T16:42:36.440Z,1673973756.440 [DataOverHttps](INFO): Sending 294 bytes from file Logs/20230117T163302/Express0005.lzma
2023-01-17T16:42:37.441Z,1673973757.441 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0005.lzma.bak
2023-01-17T16:42:37.441Z,1673973757.441 [DataOverHttps](INFO): SBD MOMSN=17401521
2023-01-17T16:42:38.638Z,1673973758.638 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T16:42:38.638Z,1673973758.638 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T16:42:38.638Z,1673973758.638 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T16:43:10.452Z,1673973790.452 [CommandExec](IMPORTANT): got command failComponent
2023-01-17T16:43:10.452Z,1673973790.452 [CommandExec](IMPORTANT): Failed components:
2023-01-17T16:43:10.453Z,1673973790.453 [CommandExec](IMPORTANT): No failed Components.
2023-01-17T16:43:14.165Z,1673973794.165 [Power24vConverter](INFO): Powering down.
2023-01-17T16:43:53.353Z,1673973833.353 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-01-17T16:43:53.353Z,1673973833.353 [NAL9602] Data Fault, FailCount= 1
2023-01-17T16:43:53.353Z,1673973833.353 [NAL9602](ERROR): Data Fault
2023-01-17T16:43:53.393Z,1673973833.393 [CBIT](ERROR): Data Fault in component: NAL9602
2023-01-17T16:43:53.749Z,1673973833.749 [NAL9602](INFO): Powering down
2023-01-17T16:43:54.587Z,1673973834.587 [CBIT](INFO): Clearing failed state for component NAL9602
2023-01-17T16:43:54.587Z,1673973834.587 [NAL9602] No Fault, FailCount= 1
2023-01-17T16:44:24.057Z,1673973864.057 [NAL9602](INFO): Powering up NAL9602
2023-01-17T16:44:34.969Z,1673973874.969 [NAL9602](INFO): NAL9602 initialized
2023-01-17T16:45:15.807Z,1673973915.807 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-01-17T16:45:15.807Z,1673973915.807 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-01-17T16:45:15.845Z,1673973915.845 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-01-17T16:45:16.206Z,1673973916.206 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-01-17T16:45:16.206Z,1673973916.206 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-01-17T16:47:39.205Z,1673974059.205 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T16:47:39.205Z,1673974059.205 [Default:CheckIn:C.Wait] Stopped
2023-01-17T16:47:39.205Z,1673974059.205 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T16:47:39.205Z,1673974059.205 [Default:CheckIn:D] Running Loop=1
2023-01-17T16:47:39.603Z,1673974059.603 [Default:CheckIn:D] Stopped
2023-01-17T16:47:39.603Z,1673974059.603 [Default:CheckIn:E] Running Loop=1
2023-01-17T16:47:40.005Z,1673974060.005 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.672080 min 
2023-01-17T16:47:40.005Z,1673974060.005 [Default:CheckIn:E] Stopped
2023-01-17T16:47:40.005Z,1673974060.005 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T16:47:40.005Z,1673974060.005 [Default:CheckIn] Stopped
2023-01-17T16:47:40.005Z,1673974060.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T16:47:40.005Z,1673974060.005 [Default:CheckIn](INFO): Running loop #2
2023-01-17T16:47:40.005Z,1673974060.005 [Default:CheckIn] Running Loop=2
2023-01-17T16:47:40.005Z,1673974060.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T16:47:40.005Z,1673974060.005 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T16:48:16.808Z,1673974096.808 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-01-17T16:48:16.808Z,1673974096.808 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-01-17T16:48:16.838Z,1673974096.838 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-01-17T16:48:17.271Z,1673974097.271 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-01-17T16:48:17.271Z,1673974097.271 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-01-17T16:49:49.719Z,1673974189.719 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164948.00,A,3648.16834,N,12147.28638,W,0.330,0.00,170123,,,A*7B
2023-01-17T16:49:49.731Z,1673974189.731 [NAL9602](INFO): GPS fix at 20230117T164948: (36.802806, -121.788106)
2023-01-17T16:49:49.742Z,1673974189.742 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T16:49:49.742Z,1673974189.742 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T16:49:50.670Z,1673974190.670 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20230117T163302/Courier0007.lzma
2023-01-17T16:49:51.661Z,1673974191.661 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0007.lzma.bak
2023-01-17T16:49:51.661Z,1673974191.661 [DataOverHttps](INFO): SBD MOMSN=17401533
2023-01-17T16:50:08.451Z,1673974208.451 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20230117T163302/Express0008.lzma
2023-01-17T16:50:09.453Z,1673974209.453 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0008.lzma.bak
2023-01-17T16:50:09.453Z,1673974209.453 [DataOverHttps](INFO): SBD MOMSN=17401536
2023-01-17T16:50:10.742Z,1673974210.742 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T16:50:10.742Z,1673974210.742 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T16:50:10.742Z,1673974210.742 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T16:50:22.497Z,1673974222.497 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T16:55:11.168Z,1673974511.168 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T16:55:11.168Z,1673974511.168 [Default:CheckIn:C.Wait] Stopped
2023-01-17T16:55:11.168Z,1673974511.168 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T16:55:11.168Z,1673974511.168 [Default:CheckIn:D] Running Loop=1
2023-01-17T16:55:11.565Z,1673974511.565 [Default:CheckIn:D] Stopped
2023-01-17T16:55:11.565Z,1673974511.565 [Default:CheckIn:E] Running Loop=1
2023-01-17T16:55:11.971Z,1673974511.971 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.204793 min 
2023-01-17T16:55:11.971Z,1673974511.971 [Default:CheckIn:E] Stopped
2023-01-17T16:55:11.971Z,1673974511.971 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T16:55:11.971Z,1673974511.971 [Default:CheckIn] Stopped
2023-01-17T16:55:11.971Z,1673974511.971 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T16:55:11.972Z,1673974511.972 [Default:CheckIn](INFO): Running loop #3
2023-01-17T16:55:11.972Z,1673974511.972 [Default:CheckIn] Running Loop=3
2023-01-17T16:55:11.972Z,1673974511.972 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T16:55:11.972Z,1673974511.972 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T16:55:13.982Z,1673974513.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165512.00,A,3648.13259,N,12147.21591,W,0.078,195.34,170123,,,A*71
2023-01-17T16:55:13.985Z,1673974513.985 [NAL9602](INFO): GPS fix at 20230117T165512: (36.802210, -121.786932)
2023-01-17T16:55:14.014Z,1673974514.014 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T16:55:14.014Z,1673974514.014 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T16:55:21.187Z,1673974521.187 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230117T163302/Courier0010.lzma
2023-01-17T16:55:22.189Z,1673974522.189 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0010.lzma.bak
2023-01-17T16:55:22.189Z,1673974522.189 [DataOverHttps](INFO): SBD MOMSN=17401543
2023-01-17T16:55:37.827Z,1673974537.827 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20230117T163302/Express0011.lzma
2023-01-17T16:55:38.831Z,1673974538.831 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0011.lzma.bak
2023-01-17T16:55:38.831Z,1673974538.831 [DataOverHttps](INFO): SBD MOMSN=17401546
2023-01-17T16:55:39.870Z,1673974539.870 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T16:55:39.870Z,1673974539.870 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T16:55:39.870Z,1673974539.870 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T16:56:03.302Z,1673974563.302 [NAL9602](INFO): SBD MO Status=0, MOMSN=38370, MT Status=0, MTMSN=0
2023-01-17T16:56:03.302Z,1673974563.302 [NAL9602](INFO): No messages in MT queue
2023-01-17T16:56:33.000Z,1673974594.000 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T16:56:47.941Z,1673974607.941 [DataOverHttps](IMPORTANT): SBD MTMSN=20230117T165646
2023-01-17T16:56:58.475Z,1673974618.475 [DataOverHttps](INFO): Received command: ibit
2023-01-17T16:56:58.508Z,1673974618.508 [CommandExec](IMPORTANT): got command ibit
2023-01-17T16:56:58.745Z,1673974618.745 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-01-17T16:56:58.745Z,1673974618.745 [IBIT](IMPORTANT): Beginning control surface checks.
2023-01-17T16:56:58.765Z,1673974618.765 [CBIT](IMPORTANT): Beginning ground fault scan
2023-01-17T16:57:00.346Z,1673974620.346 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165659.00,A,3648.13200,N,12147.21479,W,0.039,195.34,170123,,,D*76
2023-01-17T16:57:00.349Z,1673974620.349 [NAL9602](INFO): GPS fix at 20230117T165659: (36.802200, -121.786913)
2023-01-17T16:57:01.672Z,1673974621.672 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-01-17T16:57:09.673Z,1673974629.673 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002676
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002176
CHAN A3 (5V): -0.001525
CHAN B0 (3.3V): 0.000076
CHAN B1 (3.15aV): -0.000308
CHAN B2 (3.15bV): -0.000510
CHAN B3 (GND): 0.000435
OPEN: -0.000538
Full Scale: +/- 1 mA
2023-01-17T16:57:11.654Z,1673974631.654 [NAL9602](INFO): SBD MO Status=0, MOMSN=38371, MT Status=0, MTMSN=0
2023-01-17T16:57:11.654Z,1673974631.654 [NAL9602](INFO): No messages in MT queue
2023-01-17T16:57:12.870Z,1673974632.870 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165712.00,A,3648.13162,N,12147.21478,W,0.039,195.34,170123,,,D*7E
2023-01-17T16:57:12.873Z,1673974632.873 [NAL9602](INFO): GPS fix at 20230117T165712: (36.802194, -121.786913)
2023-01-17T16:57:16.102Z,1673974636.102 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165715.00,A,3648.13159,N,12147.21482,W,0.058,195.34,170123,,,D*73
2023-01-17T16:57:16.105Z,1673974636.105 [NAL9602](INFO): GPS fix at 20230117T165715: (36.802193, -121.786914)
2023-01-17T16:57:18.927Z,1673974638.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165718.00,A,3648.13168,N,12147.21477,W,0.058,195.34,170123,,,D*76
2023-01-17T16:57:18.929Z,1673974638.929 [NAL9602](INFO): GPS fix at 20230117T165718: (36.802195, -121.786913)
2023-01-17T16:57:21.754Z,1673974641.754 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165721.00,A,3648.13164,N,12147.21476,W,0.039,195.34,170123,,,D*76
2023-01-17T16:57:21.757Z,1673974641.757 [NAL9602](INFO): GPS fix at 20230117T165721: (36.802194, -121.786913)
2023-01-17T16:57:24.986Z,1673974644.986 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165724.00,A,3648.13162,N,12147.21475,W,0.039,195.34,170123,,,D*76
2023-01-17T16:57:24.988Z,1673974644.988 [NAL9602](INFO): GPS fix at 20230117T165724: (36.802194, -121.786912)
2023-01-17T16:57:27.815Z,1673974647.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165727.00,A,3648.13159,N,12147.21476,W,0.078,195.34,170123,,,D*7B
2023-01-17T16:57:27.817Z,1673974647.817 [NAL9602](INFO): GPS fix at 20230117T165727: (36.802193, -121.786913)
2023-01-17T16:57:29.510Z,1673974649.510 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.764685
2023-01-17T16:57:29.510Z,1673974649.510 [IBIT](FAULT): Control surface position failure.
2023-01-17T16:57:31.046Z,1673974651.046 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165730.00,A,3648.13158,N,12147.21473,W,0.039,195.34,170123,,,D*7C
2023-01-17T16:57:31.049Z,1673974651.049 [NAL9602](INFO): GPS fix at 20230117T165730: (36.802193, -121.786912)
2023-01-17T16:57:33.878Z,1673974653.878 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165733.00,A,3648.13163,N,12147.21472,W,0.019,195.34,170123,,,D*74
2023-01-17T16:57:33.881Z,1673974653.881 [NAL9602](INFO): GPS fix at 20230117T165733: (36.802194, -121.786912)
2023-01-17T16:57:37.110Z,1673974657.110 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165736.00,A,3648.13160,N,12147.21476,W,0.039,195.34,170123,,,D*74
2023-01-17T16:57:37.113Z,1673974657.113 [NAL9602](INFO): GPS fix at 20230117T165736: (36.802193, -121.786913)
2023-01-17T16:57:39.934Z,1673974659.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165739.00,A,3648.13154,N,12147.21481,W,0.039,195.34,170123,,,D*74
2023-01-17T16:57:39.936Z,1673974659.936 [NAL9602](INFO): GPS fix at 20230117T165739: (36.802192, -121.786913)
2023-01-17T16:57:42.762Z,1673974662.762 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165742.00,A,3648.13150,N,12147.21482,W,0.039,195.34,170123,,,D*7F
2023-01-17T16:57:42.765Z,1673974662.765 [NAL9602](INFO): GPS fix at 20230117T165742: (36.802192, -121.786914)
2023-01-17T16:57:45.210Z,1673974665.210 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802193 Longitude: -121.786911
2023-01-17T16:57:45.626Z,1673974665.626 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-01-17T16:57:45.626Z,1673974665.626 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-01-17T16:57:45.627Z,1673974665.627 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-01-17T16:57:45.998Z,1673974665.998 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165745.00,A,3648.13153,N,12147.21480,W,0.078,195.34,170123,,,D*7C
2023-01-17T16:57:46.001Z,1673974666.001 [NAL9602](INFO): GPS fix at 20230117T165745: (36.802192, -121.786913)
2023-01-17T16:57:46.033Z,1673974666.033 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-01-17T16:57:46.033Z,1673974666.033 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-01-17T16:57:46.033Z,1673974666.033 [IBIT](IMPORTANT): Pressure:9.655967 PSI
2023-01-17T16:57:46.034Z,1673974666.034 [IBIT](IMPORTANT): Humidity:0.000000 %
2023-01-17T16:57:46.458Z,1673974666.458 [IBIT](IMPORTANT): Vehicle Pitch:-0.658588 degrees
2023-01-17T16:57:46.458Z,1673974666.458 [IBIT](IMPORTANT): Vehicle  Roll:-6.195414 degrees
2023-01-17T16:57:46.458Z,1673974666.458 [IBIT](IMPORTANT): Vehicle  Heading:17.769964 degrees
2023-01-17T16:57:46.826Z,1673974666.826 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-01-17T16:57:46.827Z,1673974666.827 [IBIT](IMPORTANT): buoyancyNeutral: 176.310715 cc
2023-01-17T16:57:46.828Z,1673974666.828 [IBIT](IMPORTANT): massDefault: -0.824972 cm
2023-01-17T16:57:46.828Z,1673974666.828 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-01-17T16:57:46.829Z,1673974666.829 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-01-17T16:57:46.829Z,1673974666.829 [IBIT](IMPORTANT): IBIT FAILED
2023-01-17T16:58:18.312Z,1673974698.312 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:00:40.622Z,1673974840.622 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:00:40.622Z,1673974840.622 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:00:40.622Z,1673974840.622 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:00:40.622Z,1673974840.622 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:00:41.026Z,1673974841.026 [Default:CheckIn:D] Stopped
2023-01-17T17:00:41.026Z,1673974841.026 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:00:41.427Z,1673974841.427 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.695801 min 
2023-01-17T17:00:41.427Z,1673974841.427 [Default:CheckIn:E] Stopped
2023-01-17T17:00:41.427Z,1673974841.427 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:00:41.428Z,1673974841.428 [Default:CheckIn] Stopped
2023-01-17T17:00:41.428Z,1673974841.428 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:00:41.428Z,1673974841.428 [Default:CheckIn](INFO): Running loop #4
2023-01-17T17:00:41.428Z,1673974841.428 [Default:CheckIn] Running Loop=4
2023-01-17T17:00:41.428Z,1673974841.428 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:00:41.428Z,1673974841.428 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:00:43.448Z,1673974843.448 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170042.00,A,3648.13154,N,12147.21639,W,0.019,195.34,170123,,,D*78
2023-01-17T17:00:43.450Z,1673974843.450 [NAL9602](INFO): GPS fix at 20230117T170042: (36.802192, -121.786940)
2023-01-17T17:00:43.461Z,1673974843.461 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:00:43.461Z,1673974843.461 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:00:53.223Z,1673974853.223 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0013.lzma
2023-01-17T17:00:54.225Z,1673974854.225 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0013.lzma.bak
2023-01-17T17:00:54.226Z,1673974854.226 [DataOverHttps](INFO): SBD MOMSN=17401553
2023-01-17T17:01:01.254Z,1673974861.254 [NAL9602](INFO): SBD MO Status=2, MOMSN=38372, MT Status=2, MTMSN=0
2023-01-17T17:01:01.254Z,1673974861.254 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T17:01:10.467Z,1673974870.467 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230117T163302/Courier0016.lzma
2023-01-17T17:01:10.551Z,1673974870.551 [NAL9602](INFO): SBD MO Status=0, MOMSN=38372, MT Status=0, MTMSN=0
2023-01-17T17:01:10.551Z,1673974870.551 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:01:11.469Z,1673974871.469 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0016.lzma.bak
2023-01-17T17:01:11.469Z,1673974871.469 [DataOverHttps](INFO): SBD MOMSN=17401556
2023-01-17T17:01:27.257Z,1673974887.257 [DataOverHttps](INFO): Sending 897 bytes from file Logs/20230117T163302/Express0014.lzma
2023-01-17T17:01:28.257Z,1673974888.257 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0014.lzma.bak
2023-01-17T17:01:28.257Z,1673974888.257 [DataOverHttps](INFO): SBD MOMSN=17401558
2023-01-17T17:01:41.256Z,1673974901.256 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:01:43.963Z,1673974903.963 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230117T163302/Express0017.lzma
2023-01-17T17:01:44.965Z,1673974904.965 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0017.lzma.bak
2023-01-17T17:01:44.965Z,1673974904.965 [DataOverHttps](INFO): SBD MOMSN=17401586
2023-01-17T17:01:46.106Z,1673974906.106 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:01:46.106Z,1673974906.106 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:01:46.106Z,1673974906.106 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:03:55.773Z,1673975035.773 [DataOverHttps](IMPORTANT): SBD MTMSN=20230117T170354
2023-01-17T17:04:03.215Z,1673975043.215 [DataOverHttps](INFO): Received command: ibit
2023-01-17T17:04:03.304Z,1673975043.304 [CommandExec](IMPORTANT): got command ibit
2023-01-17T17:04:03.592Z,1673975043.592 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-01-17T17:04:03.592Z,1673975043.592 [IBIT](IMPORTANT): Beginning control surface checks.
2023-01-17T17:04:03.625Z,1673975043.625 [CBIT](IMPORTANT): Beginning ground fault scan
2023-01-17T17:04:05.195Z,1673975045.195 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170404.00,A,3648.13187,N,12147.21607,W,0.000,195.34,170123,,,D*75
2023-01-17T17:04:05.197Z,1673975045.197 [NAL9602](INFO): GPS fix at 20230117T170404: (36.802198, -121.786935)
2023-01-17T17:04:06.508Z,1673975046.508 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-01-17T17:04:14.521Z,1673975054.521 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002363
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002033
CHAN A3 (5V): -0.001544
CHAN B0 (3.3V): 0.000057
CHAN B1 (3.15aV): -0.000261
CHAN B2 (3.15bV): -0.000498
CHAN B3 (GND): 0.000397
OPEN: -0.000377
Full Scale: +/- 1 mA
2023-01-17T17:04:34.390Z,1673975074.390 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.762486
2023-01-17T17:04:34.390Z,1673975074.390 [IBIT](FAULT): Control surface position failure.
2023-01-17T17:04:39.538Z,1673975079.538 [NAL9602](INFO): SBD MO Status=2, MOMSN=38373, MT Status=2, MTMSN=0
2023-01-17T17:04:39.538Z,1673975079.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T17:04:50.054Z,1673975090.054 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.802197 Longitude: -121.786934
2023-01-17T17:04:50.489Z,1673975090.489 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2023-01-17T17:04:50.489Z,1673975090.489 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-01-17T17:04:50.490Z,1673975090.490 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-01-17T17:04:50.859Z,1673975090.859 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-01-17T17:04:50.860Z,1673975090.860 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-01-17T17:04:50.860Z,1673975090.860 [IBIT](IMPORTANT): Pressure:9.669739 PSI
2023-01-17T17:04:50.860Z,1673975090.860 [IBIT](IMPORTANT): Humidity:0.000000 %
2023-01-17T17:04:51.263Z,1673975091.263 [IBIT](IMPORTANT): Vehicle Pitch:-0.647456 degrees
2023-01-17T17:04:51.272Z,1673975091.272 [IBIT](IMPORTANT): Vehicle  Roll:-6.189506 degrees
2023-01-17T17:04:51.272Z,1673975091.272 [IBIT](IMPORTANT): Vehicle  Heading:17.740099 degrees
2023-01-17T17:04:51.682Z,1673975091.682 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-01-17T17:04:51.682Z,1673975091.682 [IBIT](IMPORTANT): buoyancyNeutral: 176.310715 cc
2023-01-17T17:04:51.682Z,1673975091.682 [IBIT](IMPORTANT): massDefault: -0.824972 cm
2023-01-17T17:04:51.683Z,1673975091.683 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-01-17T17:04:51.683Z,1673975091.683 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-01-17T17:04:51.683Z,1673975091.683 [IBIT](IMPORTANT): IBIT FAILED
2023-01-17T17:04:59.330Z,1673975099.330 [NAL9602](INFO): SBD MO Status=0, MOMSN=38373, MT Status=0, MTMSN=0
2023-01-17T17:04:59.330Z,1673975099.330 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:05:30.036Z,1673975130.036 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:06:46.820Z,1673975206.820 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:06:46.820Z,1673975206.820 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:06:46.820Z,1673975206.820 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:06:46.820Z,1673975206.820 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:06:47.227Z,1673975207.227 [Default:CheckIn:D] Stopped
2023-01-17T17:06:47.227Z,1673975207.227 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:06:47.621Z,1673975207.621 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.799154 min 
2023-01-17T17:06:47.621Z,1673975207.621 [Default:CheckIn:E] Stopped
2023-01-17T17:06:47.622Z,1673975207.622 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:06:47.622Z,1673975207.622 [Default:CheckIn] Stopped
2023-01-17T17:06:47.622Z,1673975207.622 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:06:47.622Z,1673975207.622 [Default:CheckIn](INFO): Running loop #5
2023-01-17T17:06:47.622Z,1673975207.622 [Default:CheckIn] Running Loop=5
2023-01-17T17:06:47.622Z,1673975207.622 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:06:47.622Z,1673975207.622 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:06:49.642Z,1673975209.642 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170648.00,A,3648.13005,N,12147.22011,W,0.097,245.05,170123,,,D*74
2023-01-17T17:06:49.645Z,1673975209.645 [NAL9602](INFO): GPS fix at 20230117T170648: (36.802168, -121.787002)
2023-01-17T17:06:49.665Z,1673975209.665 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:06:49.665Z,1673975209.665 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:06:59.831Z,1673975219.831 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230117T163302/Courier0019.lzma
2023-01-17T17:07:00.833Z,1673975220.833 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0019.lzma.bak
2023-01-17T17:07:00.833Z,1673975220.833 [DataOverHttps](INFO): SBD MOMSN=17401591
2023-01-17T17:07:03.026Z,1673975223.026 [NAL9602](INFO): SBD MO Status=0, MOMSN=38374, MT Status=0, MTMSN=0
2023-01-17T17:07:03.026Z,1673975223.026 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:07:16.569Z,1673975236.569 [DataOverHttps](INFO): Sending 880 bytes from file Logs/20230117T163302/Express0020.lzma
2023-01-17T17:07:17.569Z,1673975237.569 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0020.lzma.bak
2023-01-17T17:07:17.569Z,1673975237.569 [DataOverHttps](INFO): SBD MOMSN=17401594
2023-01-17T17:07:33.255Z,1673975253.255 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230117T163302/Express0023.lzma
2023-01-17T17:07:33.727Z,1673975253.727 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:07:34.258Z,1673975254.258 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0023.lzma.bak
2023-01-17T17:07:34.258Z,1673975254.258 [DataOverHttps](INFO): SBD MOMSN=17401622
2023-01-17T17:07:35.368Z,1673975255.368 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:07:35.368Z,1673975255.368 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:07:35.368Z,1673975255.368 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:12:35.947Z,1673975555.947 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:12:35.947Z,1673975555.947 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:12:35.947Z,1673975555.947 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:12:35.947Z,1673975555.947 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:12:36.361Z,1673975556.361 [Default:CheckIn:D] Stopped
2023-01-17T17:12:36.361Z,1673975556.361 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:12:36.774Z,1673975556.774 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.618046 min 
2023-01-17T17:12:36.774Z,1673975556.774 [Default:CheckIn:E] Stopped
2023-01-17T17:12:36.774Z,1673975556.774 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:12:36.774Z,1673975556.774 [Default:CheckIn] Stopped
2023-01-17T17:12:36.774Z,1673975556.774 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:12:36.774Z,1673975556.774 [Default:CheckIn](INFO): Running loop #6
2023-01-17T17:12:36.774Z,1673975556.774 [Default:CheckIn] Running Loop=6
2023-01-17T17:12:36.775Z,1673975556.775 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:12:36.775Z,1673975556.775 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:12:38.768Z,1673975558.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171238.00,A,3648.13982,N,12147.21078,W,0.039,68.95,170123,,,D*4C
2023-01-17T17:12:38.770Z,1673975558.770 [NAL9602](INFO): GPS fix at 20230117T171238: (36.802330, -121.786846)
2023-01-17T17:12:38.781Z,1673975558.781 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:12:38.781Z,1673975558.781 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:12:46.087Z,1673975566.087 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0025.lzma
2023-01-17T17:12:47.089Z,1673975567.089 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0025.lzma.bak
2023-01-17T17:12:47.089Z,1673975567.089 [DataOverHttps](INFO): SBD MOMSN=17401626
2023-01-17T17:13:05.463Z,1673975585.463 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20230117T163302/Express0026.lzma
2023-01-17T17:13:06.465Z,1673975586.465 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0026.lzma.bak
2023-01-17T17:13:06.465Z,1673975586.465 [DataOverHttps](INFO): SBD MOMSN=17401629
2023-01-17T17:13:07.518Z,1673975587.518 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:13:07.518Z,1673975587.518 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:13:07.518Z,1673975587.518 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:13:11.126Z,1673975591.126 [NAL9602](INFO): SBD MO Status=0, MOMSN=38375, MT Status=0, MTMSN=0
2023-01-17T17:13:11.126Z,1673975591.126 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:13:41.828Z,1673975621.828 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:18:08.073Z,1673975888.073 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:18:08.073Z,1673975888.073 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:18:08.073Z,1673975888.073 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:18:08.074Z,1673975888.074 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:18:08.488Z,1673975888.488 [Default:CheckIn:D] Stopped
2023-01-17T17:18:08.488Z,1673975888.488 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:18:08.904Z,1673975888.904 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.153507 min 
2023-01-17T17:18:08.904Z,1673975888.904 [Default:CheckIn:E] Stopped
2023-01-17T17:18:08.905Z,1673975888.905 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:18:08.905Z,1673975888.905 [Default:CheckIn] Stopped
2023-01-17T17:18:08.905Z,1673975888.905 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:18:08.905Z,1673975888.905 [Default:CheckIn](INFO): Running loop #7
2023-01-17T17:18:08.905Z,1673975888.905 [Default:CheckIn] Running Loop=7
2023-01-17T17:18:08.905Z,1673975888.905 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:18:08.905Z,1673975888.905 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:18:10.898Z,1673975890.898 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171810.00,A,3648.14020,N,12147.21061,W,0.039,68.95,170123,,,D*42
2023-01-17T17:18:10.901Z,1673975890.901 [NAL9602](INFO): GPS fix at 20230117T171810: (36.802337, -121.786844)
2023-01-17T17:18:10.947Z,1673975890.947 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:18:10.947Z,1673975890.947 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:18:19.479Z,1673975899.479 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0028.lzma
2023-01-17T17:18:20.481Z,1673975900.481 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0028.lzma.bak
2023-01-17T17:18:20.481Z,1673975900.481 [DataOverHttps](INFO): SBD MOMSN=17401633
2023-01-17T17:18:25.078Z,1673975905.078 [NAL9602](INFO): SBD MO Status=0, MOMSN=38376, MT Status=0, MTMSN=0
2023-01-17T17:18:25.078Z,1673975905.078 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:18:38.683Z,1673975918.683 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20230117T163302/Express0029.lzma
2023-01-17T17:18:39.685Z,1673975919.685 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0029.lzma.bak
2023-01-17T17:18:39.685Z,1673975919.685 [DataOverHttps](INFO): SBD MOMSN=17401636
2023-01-17T17:18:40.834Z,1673975920.834 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:18:40.834Z,1673975920.834 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:18:40.834Z,1673975920.834 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:18:55.775Z,1673975935.775 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:23:41.494Z,1673976221.494 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:23:41.494Z,1673976221.494 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:23:41.494Z,1673976221.494 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:23:41.495Z,1673976221.495 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:23:41.907Z,1673976221.907 [Default:CheckIn:D] Stopped
2023-01-17T17:23:41.907Z,1673976221.907 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:23:42.297Z,1673976222.297 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.710486 min 
2023-01-17T17:23:42.297Z,1673976222.297 [Default:CheckIn:E] Stopped
2023-01-17T17:23:42.297Z,1673976222.297 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:23:42.297Z,1673976222.297 [Default:CheckIn] Stopped
2023-01-17T17:23:42.297Z,1673976222.297 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:23:42.298Z,1673976222.298 [Default:CheckIn](INFO): Running loop #8
2023-01-17T17:23:42.298Z,1673976222.298 [Default:CheckIn] Running Loop=8
2023-01-17T17:23:42.298Z,1673976222.298 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:23:42.298Z,1673976222.298 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:23:44.311Z,1673976224.311 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172343.00,A,3648.14014,N,12147.20894,W,0.058,46.87,170123,,,A*45
2023-01-17T17:23:44.313Z,1673976224.313 [NAL9602](INFO): GPS fix at 20230117T172343: (36.802336, -121.786816)
2023-01-17T17:23:44.325Z,1673976224.325 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:23:44.325Z,1673976224.325 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:23:51.891Z,1673976231.891 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0031.lzma
2023-01-17T17:23:52.893Z,1673976232.893 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0031.lzma.bak
2023-01-17T17:23:52.893Z,1673976232.893 [DataOverHttps](INFO): SBD MOMSN=17401640
2023-01-17T17:23:58.854Z,1673976238.854 [NAL9602](INFO): SBD MO Status=0, MOMSN=38377, MT Status=0, MTMSN=0
2023-01-17T17:23:58.854Z,1673976238.854 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:24:08.607Z,1673976248.607 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20230117T163302/Express0032.lzma
2023-01-17T17:24:09.609Z,1673976249.609 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0032.lzma.bak
2023-01-17T17:24:09.609Z,1673976249.609 [DataOverHttps](INFO): SBD MOMSN=17401643
2023-01-17T17:24:10.991Z,1673976250.991 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:24:10.991Z,1673976250.991 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:24:10.991Z,1673976250.991 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:24:29.564Z,1673976269.564 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:29:11.628Z,1673976551.628 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:29:11.628Z,1673976551.628 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:29:11.628Z,1673976551.628 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:29:11.628Z,1673976551.628 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:29:12.023Z,1673976552.023 [Default:CheckIn:D] Stopped
2023-01-17T17:29:12.023Z,1673976552.023 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:29:12.443Z,1673976552.443 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.212419 min 
2023-01-17T17:29:12.443Z,1673976552.443 [Default:CheckIn:E] Stopped
2023-01-17T17:29:12.443Z,1673976552.443 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:29:12.444Z,1673976552.444 [Default:CheckIn] Stopped
2023-01-17T17:29:12.444Z,1673976552.444 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:29:12.444Z,1673976552.444 [Default:CheckIn](INFO): Running loop #9
2023-01-17T17:29:12.444Z,1673976552.444 [Default:CheckIn] Running Loop=9
2023-01-17T17:29:12.444Z,1673976552.444 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:29:12.444Z,1673976552.444 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:29:14.438Z,1673976554.438 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172913.00,A,3648.14117,N,12147.20850,W,0.058,46.87,170123,,,A*40
2023-01-17T17:29:14.444Z,1673976554.444 [NAL9602](INFO): GPS fix at 20230117T172913: (36.802353, -121.786808)
2023-01-17T17:29:14.471Z,1673976554.471 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:29:14.471Z,1673976554.471 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:29:20.447Z,1673976560.447 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0034.lzma
2023-01-17T17:29:21.449Z,1673976561.449 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0034.lzma.bak
2023-01-17T17:29:21.449Z,1673976561.449 [DataOverHttps](INFO): SBD MOMSN=17401648
2023-01-17T17:29:29.385Z,1673976569.385 [NAL9602](INFO): SBD MO Status=0, MOMSN=38378, MT Status=0, MTMSN=0
2023-01-17T17:29:29.385Z,1673976569.385 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:29:37.175Z,1673976577.175 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20230117T163302/Express0035.lzma
2023-01-17T17:29:38.177Z,1673976578.177 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0035.lzma.bak
2023-01-17T17:29:38.177Z,1673976578.177 [DataOverHttps](INFO): SBD MOMSN=17401652
2023-01-17T17:29:39.504Z,1673976579.504 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:29:39.504Z,1673976579.504 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:29:39.504Z,1673976579.504 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:30:00.092Z,1673976600.092 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:30:47.237Z,1673976647.237 [DataOverHttps](IMPORTANT): SBD MTMSN=20230117T173046
2023-01-17T17:30:57.575Z,1673976657.575 [DataOverHttps](INFO): Received command: ibit
2023-01-17T17:30:57.576Z,1673976657.576 [CommandExec](IMPORTANT): got command ibit
2023-01-17T17:30:57.582Z,1673976657.582 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-01-17T17:30:57.583Z,1673976657.583 [IBIT](IMPORTANT): Beginning control surface checks.
2023-01-17T17:30:57.586Z,1673976657.586 [CBIT](IMPORTANT): Beginning ground fault scan
2023-01-17T17:30:59.139Z,1673976659.139 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173058.00,A,3648.14205,N,12147.21056,W,0.058,46.87,170123,,,A*48
2023-01-17T17:30:59.141Z,1673976659.141 [NAL9602](INFO): GPS fix at 20230117T173058: (36.802368, -121.786843)
2023-01-17T17:31:00.456Z,1673976660.456 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-01-17T17:31:08.446Z,1673976668.446 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-01-17T17:31:08.856Z,1673976668.856 [CBIT](IMPORTANT): Beginning ground fault scan
2023-01-17T17:31:11.710Z,1673976671.710 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-01-17T17:31:17.326Z,1673976677.326 [NAL9602](INFO): SBD MO Status=0, MOMSN=38379, MT Status=0, MTMSN=0
2023-01-17T17:31:17.326Z,1673976677.326 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:31:18.534Z,1673976678.534 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173117.00,A,3648.14195,N,12147.21058,W,0.078,46.87,170123,,,A*44
2023-01-17T17:31:18.537Z,1673976678.537 [NAL9602](INFO): GPS fix at 20230117T173117: (36.802366, -121.786843)
2023-01-17T17:31:19.863Z,1673976679.863 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002637
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002129
CHAN A3 (5V): -0.001619
CHAN B0 (3.3V): -0.000296
CHAN B1 (3.15aV): 0.000205
CHAN B2 (3.15bV): -0.000067
CHAN B3 (GND): -0.000092
OPEN: -0.000607
Full Scale: +/- 1 mA
2023-01-17T17:31:20.954Z,1673976680.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173120.00,A,3648.14193,N,12147.21062,W,0.039,46.87,170123,,,A*4A
2023-01-17T17:31:20.957Z,1673976680.957 [NAL9602](INFO): GPS fix at 20230117T173120: (36.802366, -121.786844)
2023-01-17T17:31:24.186Z,1673976684.186 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173123.00,A,3648.14197,N,12147.21050,W,0.039,46.87,170123,,,A*4C
2023-01-17T17:31:24.199Z,1673976684.199 [NAL9602](INFO): GPS fix at 20230117T173123: (36.802366, -121.786842)
2023-01-17T17:31:27.015Z,1673976687.015 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173126.00,A,3648.14198,N,12147.21040,W,0.039,46.87,170123,,,A*47
2023-01-17T17:31:27.017Z,1673976687.017 [NAL9602](INFO): GPS fix at 20230117T173126: (36.802366, -121.786840)
2023-01-17T17:31:27.910Z,1673976687.909 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.797676
2023-01-17T17:31:27.910Z,1673976687.910 [IBIT](FAULT): Control surface position failure.
2023-01-17T17:31:29.846Z,1673976689.846 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173129.00,A,3648.14198,N,12147.21038,W,0.019,46.87,170123,,,A*45
2023-01-17T17:31:29.848Z,1673976689.848 [NAL9602](INFO): GPS fix at 20230117T173129: (36.802366, -121.786840)
2023-01-17T17:31:33.073Z,1673976693.073 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173132.00,A,3648.14192,N,12147.21059,W,0.039,46.87,170123,,,A*40
2023-01-17T17:31:33.076Z,1673976693.076 [NAL9602](INFO): GPS fix at 20230117T173132: (36.802365, -121.786843)
2023-01-17T17:31:35.902Z,1673976695.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173135.00,A,3648.14191,N,12147.21054,W,0.039,46.87,170123,,,A*49
2023-01-17T17:31:35.905Z,1673976695.905 [NAL9602](INFO): GPS fix at 20230117T173135: (36.802365, -121.786842)
2023-01-17T17:31:39.135Z,1673976699.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173138.00,A,3648.14182,N,12147.21037,W,0.000,46.87,170123,,,A*49
2023-01-17T17:31:39.137Z,1673976699.137 [NAL9602](INFO): GPS fix at 20230117T173138: (36.802364, -121.786839)
2023-01-17T17:31:41.962Z,1673976701.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173141.00,A,3648.14178,N,12147.21031,W,0.019,46.87,170123,,,A*4C
2023-01-17T17:31:41.965Z,1673976701.965 [NAL9602](INFO): GPS fix at 20230117T173141: (36.802363, -121.786839)
2023-01-17T17:31:43.604Z,1673976703.604 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802364 Longitude: -121.786835
2023-01-17T17:31:44.023Z,1673976704.023 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2023-01-17T17:31:44.023Z,1673976704.023 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-01-17T17:31:44.024Z,1673976704.024 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-01-17T17:31:44.405Z,1673976704.405 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-01-17T17:31:44.405Z,1673976704.405 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-01-17T17:31:44.406Z,1673976704.406 [IBIT](IMPORTANT): Pressure:9.671633 PSI
2023-01-17T17:31:44.406Z,1673976704.406 [IBIT](IMPORTANT): Humidity:0.000000 %
2023-01-17T17:31:44.812Z,1673976704.812 [IBIT](IMPORTANT): Vehicle Pitch:-0.598573 degrees
2023-01-17T17:31:44.813Z,1673976704.813 [IBIT](IMPORTANT): Vehicle  Roll:-6.956910 degrees
2023-01-17T17:31:44.813Z,1673976704.813 [IBIT](IMPORTANT): Vehicle  Heading:206.791519 degrees
2023-01-17T17:31:45.224Z,1673976705.224 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-01-17T17:31:45.224Z,1673976705.224 [IBIT](IMPORTANT): buoyancyNeutral: 176.310715 cc
2023-01-17T17:31:45.225Z,1673976705.225 [IBIT](IMPORTANT): massDefault: -0.824972 cm
2023-01-17T17:31:45.225Z,1673976705.225 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-01-17T17:31:45.225Z,1673976705.225 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-01-17T17:31:45.225Z,1673976705.225 [IBIT](IMPORTANT): IBIT FAILED
2023-01-17T17:32:14.688Z,1673976734.688 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:34:40.258Z,1673976880.258 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:34:40.258Z,1673976880.258 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:34:40.258Z,1673976880.258 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:34:40.258Z,1673976880.258 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:34:40.657Z,1673976880.657 [Default:CheckIn:D] Stopped
2023-01-17T17:34:40.657Z,1673976880.657 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:34:41.066Z,1673976881.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.689648 min 
2023-01-17T17:34:41.067Z,1673976881.067 [Default:CheckIn:E] Stopped
2023-01-17T17:34:41.067Z,1673976881.067 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:34:41.067Z,1673976881.067 [Default:CheckIn] Stopped
2023-01-17T17:34:41.067Z,1673976881.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:34:41.067Z,1673976881.067 [Default:CheckIn](INFO): Running loop #10
2023-01-17T17:34:41.067Z,1673976881.067 [Default:CheckIn] Running Loop=10
2023-01-17T17:34:41.067Z,1673976881.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:34:41.068Z,1673976881.068 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:34:43.074Z,1673976883.074 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173442.00,A,3648.14130,N,12147.20846,W,0.019,46.87,170123,,,A*4F
2023-01-17T17:34:43.077Z,1673976883.077 [NAL9602](INFO): GPS fix at 20230117T173442: (36.802355, -121.786808)
2023-01-17T17:34:43.108Z,1673976883.108 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:34:43.108Z,1673976883.108 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:34:53.223Z,1673976893.223 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0037.lzma
2023-01-17T17:34:54.225Z,1673976894.225 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0037.lzma.bak
2023-01-17T17:34:54.225Z,1673976894.225 [DataOverHttps](INFO): SBD MOMSN=17401658
2023-01-17T17:35:10.869Z,1673976910.869 [DataOverHttps](INFO): Sending 864 bytes from file Logs/20230117T163302/Express0038.lzma
2023-01-17T17:35:11.869Z,1673976911.869 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0038.lzma.bak
2023-01-17T17:35:11.869Z,1673976911.869 [DataOverHttps](INFO): SBD MOMSN=17401662
2023-01-17T17:35:20.363Z,1673976920.363 [NAL9602](INFO): SBD MO Status=0, MOMSN=38380, MT Status=0, MTMSN=0
2023-01-17T17:35:20.363Z,1673976920.363 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:35:27.707Z,1673976927.707 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230117T163302/Express0041.lzma
2023-01-17T17:35:28.710Z,1673976928.710 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0041.lzma.bak
2023-01-17T17:35:28.710Z,1673976928.710 [DataOverHttps](INFO): SBD MOMSN=17401691
2023-01-17T17:35:30.060Z,1673976930.060 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:35:30.060Z,1673976930.060 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:35:30.060Z,1673976930.060 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:35:51.051Z,1673976951.051 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:40:30.649Z,1673977230.649 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:40:30.649Z,1673977230.649 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:40:30.649Z,1673977230.649 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:40:30.649Z,1673977230.649 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:40:31.054Z,1673977231.054 [Default:CheckIn:D] Stopped
2023-01-17T17:40:31.054Z,1673977231.054 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:40:31.462Z,1673977231.462 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.529602 min 
2023-01-17T17:40:31.463Z,1673977231.463 [Default:CheckIn:E] Stopped
2023-01-17T17:40:31.463Z,1673977231.463 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:40:31.463Z,1673977231.463 [Default:CheckIn] Stopped
2023-01-17T17:40:31.463Z,1673977231.463 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:40:31.463Z,1673977231.463 [Default:CheckIn](INFO): Running loop #11
2023-01-17T17:40:31.463Z,1673977231.463 [Default:CheckIn] Running Loop=11
2023-01-17T17:40:31.463Z,1673977231.463 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:40:31.464Z,1673977231.464 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:40:33.474Z,1673977233.474 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174032.00,A,3648.13879,N,12147.20719,W,0.369,170.78,170123,,,A*7D
2023-01-17T17:40:33.477Z,1673977233.477 [NAL9602](INFO): GPS fix at 20230117T174032: (36.802313, -121.786787)
2023-01-17T17:40:33.517Z,1673977233.517 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:40:33.517Z,1673977233.517 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:40:41.263Z,1673977241.263 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230117T163302/Courier0043.lzma
2023-01-17T17:40:42.265Z,1673977242.265 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0043.lzma.bak
2023-01-17T17:40:42.265Z,1673977242.265 [DataOverHttps](INFO): SBD MOMSN=17401696
2023-01-17T17:40:43.978Z,1673977243.978 [NAL9602](INFO): SBD MO Status=0, MOMSN=38381, MT Status=0, MTMSN=0
2023-01-17T17:40:43.978Z,1673977243.978 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:41:01.079Z,1673977261.079 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20230117T163302/Express0044.lzma
2023-01-17T17:41:02.081Z,1673977262.081 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0044.lzma.bak
2023-01-17T17:41:02.081Z,1673977262.081 [DataOverHttps](INFO): SBD MOMSN=17401699
2023-01-17T17:41:03.416Z,1673977263.416 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:41:03.416Z,1673977263.416 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:41:03.416Z,1673977263.416 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:41:14.713Z,1673977274.713 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:45:20.770Z,1673977520.770 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T17:45:21.588Z,1673977521.588 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2023-01-17T17:45:26.426Z,1673977526.426 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-01-17T17:45:35.719Z,1673977535.719 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T17:45:39.354Z,1673977539.354 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-01-17T17:45:51.069Z,1673977551.069 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T17:46:04.020Z,1673977564.020 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:46:04.020Z,1673977564.020 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:46:04.020Z,1673977564.020 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:46:04.020Z,1673977564.020 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:46:04.415Z,1673977564.415 [Default:CheckIn:D] Stopped
2023-01-17T17:46:04.415Z,1673977564.415 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:46:04.825Z,1673977564.825 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.085612 min 
2023-01-17T17:46:04.825Z,1673977564.825 [Default:CheckIn:E] Stopped
2023-01-17T17:46:04.825Z,1673977564.825 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:46:04.825Z,1673977564.825 [Default:CheckIn] Stopped
2023-01-17T17:46:04.825Z,1673977564.825 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:46:04.825Z,1673977564.825 [Default:CheckIn](INFO): Running loop #12
2023-01-17T17:46:04.825Z,1673977564.825 [Default:CheckIn] Running Loop=12
2023-01-17T17:46:04.825Z,1673977564.825 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:46:04.825Z,1673977564.825 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:46:06.826Z,1673977566.826 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T17:46:06.835Z,1673977566.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174606.00,A,3648.13828,N,12147.20478,W,0.350,170.78,170123,,,A*76
2023-01-17T17:46:06.853Z,1673977566.853 [NAL9602](INFO): GPS fix at 20230117T174606: (36.802305, -121.786746)
2023-01-17T17:46:06.885Z,1673977566.885 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:46:06.886Z,1673977566.886 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:46:13.851Z,1673977573.851 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0046.lzma
2023-01-17T17:46:14.853Z,1673977574.853 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0046.lzma.bak
2023-01-17T17:46:14.853Z,1673977574.853 [DataOverHttps](INFO): SBD MOMSN=17401703
2023-01-17T17:46:22.207Z,1673977582.207 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T17:46:33.317Z,1673977593.317 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20230117T163302/Express0047.lzma
2023-01-17T17:46:34.317Z,1673977594.317 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0047.lzma.bak
2023-01-17T17:46:34.317Z,1673977594.317 [DataOverHttps](INFO): SBD MOMSN=17401706
2023-01-17T17:46:35.584Z,1673977595.584 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:46:35.584Z,1673977595.584 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:46:35.584Z,1673977595.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:46:37.178Z,1673977597.178 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T17:46:38.006Z,1673977598.006 [NAL9602](INFO): SBD MO Status=2, MOMSN=38382, MT Status=2, MTMSN=0
2023-01-17T17:46:38.006Z,1673977598.006 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T17:46:52.530Z,1673977612.530 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T17:46:53.777Z,1673977613.777 [NAL9602](INFO): SBD MO Status=2, MOMSN=38382, MT Status=2, MTMSN=0
2023-01-17T17:46:53.777Z,1673977613.777 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T17:47:07.478Z,1673977627.478 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T17:47:22.426Z,1673977642.426 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T17:47:36.970Z,1673977656.970 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2023-01-17T17:47:47.078Z,1673977667.078 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2023-01-17T17:47:47.080Z,1673977667.080 [BPC1](INFO): Received data from all battery sticks.
2023-01-17T17:48:52.121Z,1673977732.121 [NAL9602](INFO): SBD MO Status=0, MOMSN=38382, MT Status=0, MTMSN=0
2023-01-17T17:48:52.121Z,1673977732.121 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:49:22.828Z,1673977762.828 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:51:36.174Z,1673977896.174 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:51:36.175Z,1673977896.175 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:51:36.175Z,1673977896.175 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:51:36.175Z,1673977896.175 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:51:36.566Z,1673977896.566 [Default:CheckIn:D] Stopped
2023-01-17T17:51:36.566Z,1673977896.566 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:51:36.977Z,1673977896.977 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.621468 min 
2023-01-17T17:51:36.977Z,1673977896.977 [Default:CheckIn:E] Stopped
2023-01-17T17:51:36.977Z,1673977896.977 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:51:36.977Z,1673977896.977 [Default:CheckIn] Stopped
2023-01-17T17:51:36.977Z,1673977896.977 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:51:36.977Z,1673977896.977 [Default:CheckIn](INFO): Running loop #13
2023-01-17T17:51:36.978Z,1673977896.978 [Default:CheckIn] Running Loop=13
2023-01-17T17:51:36.978Z,1673977896.978 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:51:36.978Z,1673977896.978 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:51:38.990Z,1673977898.990 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175138.00,A,3648.13839,N,12147.20606,W,0.000,170.78,170123,,,A*70
2023-01-17T17:51:38.992Z,1673977898.992 [NAL9602](INFO): GPS fix at 20230117T175138: (36.802307, -121.786768)
2023-01-17T17:51:39.002Z,1673977899.002 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:51:39.002Z,1673977899.002 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:51:46.471Z,1673977906.471 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20230117T163302/Courier0049.lzma
2023-01-17T17:51:47.473Z,1673977907.473 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0049.lzma.bak
2023-01-17T17:51:47.473Z,1673977907.473 [DataOverHttps](INFO): SBD MOMSN=17401710
2023-01-17T17:51:56.354Z,1673977916.354 [NAL9602](INFO): SBD MO Status=0, MOMSN=38383, MT Status=0, MTMSN=0
2023-01-17T17:51:56.354Z,1673977916.354 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:52:03.287Z,1673977923.287 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20230117T163302/Express0050.lzma
2023-01-17T17:52:04.289Z,1673977924.289 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0050.lzma.bak
2023-01-17T17:52:04.289Z,1673977924.289 [DataOverHttps](INFO): SBD MOMSN=17401713
2023-01-17T17:52:05.652Z,1673977925.652 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:52:05.652Z,1673977925.652 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:52:05.652Z,1673977925.652 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:52:27.062Z,1673977947.062 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T17:57:06.237Z,1673978226.237 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T17:57:06.237Z,1673978226.237 [Default:CheckIn:C.Wait] Stopped
2023-01-17T17:57:06.237Z,1673978226.237 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T17:57:06.237Z,1673978226.237 [Default:CheckIn:D] Running Loop=1
2023-01-17T17:57:06.634Z,1673978226.634 [Default:CheckIn:D] Stopped
2023-01-17T17:57:06.634Z,1673978226.634 [Default:CheckIn:E] Running Loop=1
2023-01-17T17:57:07.039Z,1673978227.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.122599 min 
2023-01-17T17:57:07.039Z,1673978227.039 [Default:CheckIn:E] Stopped
2023-01-17T17:57:07.039Z,1673978227.039 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T17:57:07.039Z,1673978227.039 [Default:CheckIn] Stopped
2023-01-17T17:57:07.039Z,1673978227.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T17:57:07.039Z,1673978227.039 [Default:CheckIn](INFO): Running loop #14
2023-01-17T17:57:07.040Z,1673978227.040 [Default:CheckIn] Running Loop=14
2023-01-17T17:57:07.040Z,1673978227.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T17:57:07.040Z,1673978227.040 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T17:57:09.054Z,1673978229.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175708.00,A,3648.14982,N,12147.19697,W,1.341,24.56,170123,,,D*4F
2023-01-17T17:57:09.057Z,1673978229.057 [NAL9602](INFO): GPS fix at 20230117T175708: (36.802497, -121.786616)
2023-01-17T17:57:09.067Z,1673978229.067 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T17:57:09.067Z,1673978229.067 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T17:57:16.031Z,1673978236.031 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230117T163302/Courier0052.lzma
2023-01-17T17:57:17.033Z,1673978237.033 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0052.lzma.bak
2023-01-17T17:57:17.033Z,1673978237.033 [DataOverHttps](INFO): SBD MOMSN=17401717
2023-01-17T17:57:32.683Z,1673978252.683 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20230117T163302/Express0053.lzma
2023-01-17T17:57:33.290Z,1673978253.290 [NAL9602](INFO): SBD MO Status=0, MOMSN=38384, MT Status=0, MTMSN=0
2023-01-17T17:57:33.290Z,1673978253.290 [NAL9602](INFO): No messages in MT queue
2023-01-17T17:57:33.686Z,1673978253.685 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0053.lzma.bak
2023-01-17T17:57:33.686Z,1673978253.686 [DataOverHttps](INFO): SBD MOMSN=17401720
2023-01-17T17:57:34.920Z,1673978254.920 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T17:57:34.920Z,1673978254.920 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T17:57:34.920Z,1673978254.920 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T17:58:03.996Z,1673978283.996 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T18:02:35.502Z,1673978555.502 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T18:02:35.502Z,1673978555.502 [Default:CheckIn:C.Wait] Stopped
2023-01-17T18:02:35.502Z,1673978555.502 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T18:02:35.502Z,1673978555.502 [Default:CheckIn:D] Running Loop=1
2023-01-17T18:02:35.910Z,1673978555.910 [Default:CheckIn:D] Stopped
2023-01-17T18:02:35.910Z,1673978555.910 [Default:CheckIn:E] Running Loop=1
2023-01-17T18:02:36.310Z,1673978556.310 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.610531 min 
2023-01-17T18:02:36.311Z,1673978556.311 [Default:CheckIn:E] Stopped
2023-01-17T18:02:36.311Z,1673978556.311 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T18:02:36.311Z,1673978556.311 [Default:CheckIn] Stopped
2023-01-17T18:02:36.311Z,1673978556.311 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T18:02:36.311Z,1673978556.311 [Default:CheckIn](INFO): Running loop #15
2023-01-17T18:02:36.311Z,1673978556.311 [Default:CheckIn] Running Loop=15
2023-01-17T18:02:36.311Z,1673978556.311 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T18:02:36.312Z,1673978556.312 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T18:02:38.318Z,1673978558.318 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180237.00,A,3648.20003,N,12147.17486,W,1.575,51.32,170123,,,D*46
2023-01-17T18:02:38.321Z,1673978558.321 [NAL9602](INFO): GPS fix at 20230117T180237: (36.803334, -121.786248)
2023-01-17T18:02:38.350Z,1673978558.350 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T18:02:38.350Z,1673978558.350 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T18:02:45.747Z,1673978565.747 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0055.lzma
2023-01-17T18:02:46.749Z,1673978566.749 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0055.lzma.bak
2023-01-17T18:02:46.749Z,1673978566.749 [DataOverHttps](INFO): SBD MOMSN=17401724
2023-01-17T18:03:00.210Z,1673978580.210 [NAL9602](INFO): SBD MO Status=2, MOMSN=38385, MT Status=2, MTMSN=0
2023-01-17T18:03:00.211Z,1673978580.211 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T18:03:05.023Z,1673978585.023 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20230117T163302/Express0056.lzma
2023-01-17T18:03:06.025Z,1673978586.025 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0056.lzma.bak
2023-01-17T18:03:06.026Z,1673978586.026 [DataOverHttps](INFO): SBD MOMSN=17401727
2023-01-17T18:03:07.086Z,1673978587.086 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T18:03:07.086Z,1673978587.086 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T18:03:07.087Z,1673978587.087 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T18:03:49.498Z,1673978629.498 [NAL9602](INFO): SBD MO Status=2, MOMSN=38385, MT Status=2, MTMSN=0
2023-01-17T18:03:49.498Z,1673978629.498 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T18:04:18.181Z,1673978658.181 [NAL9602](INFO): SBD MO Status=0, MOMSN=38385, MT Status=0, MTMSN=0
2023-01-17T18:04:18.181Z,1673978658.181 [NAL9602](INFO): No messages in MT queue
2023-01-17T18:04:48.900Z,1673978688.900 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T18:08:07.696Z,1673978887.696 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T18:08:07.696Z,1673978887.696 [Default:CheckIn:C.Wait] Stopped
2023-01-17T18:08:07.696Z,1673978887.696 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T18:08:07.696Z,1673978887.696 [Default:CheckIn:D] Running Loop=1
2023-01-17T18:08:08.088Z,1673978888.088 [Default:CheckIn:D] Stopped
2023-01-17T18:08:08.088Z,1673978888.088 [Default:CheckIn:E] Running Loop=1
2023-01-17T18:08:08.504Z,1673978888.504 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.146842 min 
2023-01-17T18:08:08.505Z,1673978888.505 [Default:CheckIn:E] Stopped
2023-01-17T18:08:08.505Z,1673978888.505 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T18:08:08.505Z,1673978888.505 [Default:CheckIn] Stopped
2023-01-17T18:08:08.505Z,1673978888.505 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T18:08:08.505Z,1673978888.505 [Default:CheckIn](INFO): Running loop #16
2023-01-17T18:08:08.505Z,1673978888.505 [Default:CheckIn] Running Loop=16
2023-01-17T18:08:08.505Z,1673978888.505 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T18:08:08.505Z,1673978888.505 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T18:08:10.511Z,1673978890.511 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180809.00,A,3648.25756,N,12147.12792,W,0.719,19.72,170123,,,D*41
2023-01-17T18:08:10.513Z,1673978890.513 [NAL9602](INFO): GPS fix at 20230117T180809: (36.804293, -121.785465)
2023-01-17T18:08:10.546Z,1673978890.546 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T18:08:10.547Z,1673978890.547 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T18:08:18.487Z,1673978898.487 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0058.lzma
2023-01-17T18:08:19.490Z,1673978899.490 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0058.lzma.bak
2023-01-17T18:08:19.490Z,1673978899.490 [DataOverHttps](INFO): SBD MOMSN=17401731
2023-01-17T18:08:37.923Z,1673978917.923 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20230117T163302/Express0059.lzma
2023-01-17T18:08:38.925Z,1673978918.925 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0059.lzma.bak
2023-01-17T18:08:38.926Z,1673978918.926 [DataOverHttps](INFO): SBD MOMSN=17401734
2023-01-17T18:08:40.035Z,1673978920.035 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T18:08:40.035Z,1673978920.035 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T18:08:40.035Z,1673978920.035 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T18:08:54.977Z,1673978934.977 [NAL9602](INFO): SBD MO Status=2, MOMSN=38386, MT Status=2, MTMSN=0
2023-01-17T18:08:54.977Z,1673978934.977 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T18:09:59.618Z,1673978999.618 [NAL9602](INFO): SBD MO Status=2, MOMSN=38386, MT Status=2, MTMSN=0
2023-01-17T18:09:59.618Z,1673978999.618 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T18:10:12.138Z,1673979012.138 [NAL9602](INFO): SBD MO Status=0, MOMSN=38386, MT Status=0, MTMSN=0
2023-01-17T18:10:12.138Z,1673979012.138 [NAL9602](INFO): No messages in MT queue
2023-01-17T18:10:42.852Z,1673979042.852 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T18:13:40.674Z,1673979220.674 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T18:13:40.674Z,1673979220.674 [Default:CheckIn:C.Wait] Stopped
2023-01-17T18:13:40.674Z,1673979220.674 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T18:13:40.674Z,1673979220.674 [Default:CheckIn:D] Running Loop=1
2023-01-17T18:13:41.084Z,1673979221.084 [Default:CheckIn:D] Stopped
2023-01-17T18:13:41.084Z,1673979221.084 [Default:CheckIn:E] Running Loop=1
2023-01-17T18:13:41.478Z,1673979221.478 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.696769 min 
2023-01-17T18:13:41.479Z,1673979221.479 [Default:CheckIn:E] Stopped
2023-01-17T18:13:41.479Z,1673979221.479 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T18:13:41.479Z,1673979221.479 [Default:CheckIn] Stopped
2023-01-17T18:13:41.479Z,1673979221.479 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T18:13:41.479Z,1673979221.479 [Default:CheckIn](INFO): Running loop #17
2023-01-17T18:13:41.511Z,1673979221.511 [Default:CheckIn] Running Loop=17
2023-01-17T18:13:41.511Z,1673979221.511 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T18:13:41.511Z,1673979221.511 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T18:13:43.494Z,1673979223.494 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181342.00,A,3648.33523,N,12147.10954,W,0.758,16.06,170123,,,D*4C
2023-01-17T18:13:43.496Z,1673979223.496 [NAL9602](INFO): GPS fix at 20230117T181342: (36.805587, -121.785159)
2023-01-17T18:13:43.506Z,1673979223.506 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T18:13:43.506Z,1673979223.506 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T18:13:51.299Z,1673979231.299 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0061.lzma
2023-01-17T18:13:52.301Z,1673979232.301 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0061.lzma.bak
2023-01-17T18:13:52.301Z,1673979232.301 [DataOverHttps](INFO): SBD MOMSN=17401739
2023-01-17T18:14:08.115Z,1673979248.115 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20230117T163302/Express0062.lzma
2023-01-17T18:14:09.117Z,1673979249.117 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0062.lzma.bak
2023-01-17T18:14:09.117Z,1673979249.117 [DataOverHttps](INFO): SBD MOMSN=17401742
2023-01-17T18:14:10.175Z,1673979250.175 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T18:14:10.175Z,1673979250.175 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T18:14:10.175Z,1673979250.175 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T18:14:22.273Z,1673979262.273 [NAL9602](INFO): SBD MO Status=0, MOMSN=38387, MT Status=0, MTMSN=0
2023-01-17T18:14:22.273Z,1673979262.273 [NAL9602](INFO): No messages in MT queue
2023-01-17T18:14:52.975Z,1673979292.975 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T18:19:10.868Z,1673979550.868 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T18:19:10.868Z,1673979550.868 [Default:CheckIn:C.Wait] Stopped
2023-01-17T18:19:10.868Z,1673979550.868 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T18:19:10.868Z,1673979550.868 [Default:CheckIn:D] Running Loop=1
2023-01-17T18:19:11.288Z,1673979551.288 [Default:CheckIn:D] Stopped
2023-01-17T18:19:11.288Z,1673979551.288 [Default:CheckIn:E] Running Loop=1
2023-01-17T18:19:11.674Z,1673979551.674 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.200171 min 
2023-01-17T18:19:11.675Z,1673979551.675 [Default:CheckIn:E] Stopped
2023-01-17T18:19:11.675Z,1673979551.675 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T18:19:11.675Z,1673979551.675 [Default:CheckIn] Stopped
2023-01-17T18:19:11.675Z,1673979551.675 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T18:19:11.675Z,1673979551.675 [Default:CheckIn](INFO): Running loop #18
2023-01-17T18:19:11.675Z,1673979551.675 [Default:CheckIn] Running Loop=18
2023-01-17T18:19:11.675Z,1673979551.675 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T18:19:11.676Z,1673979551.676 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T18:19:13.686Z,1673979553.686 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181912.00,A,3648.41385,N,12147.10790,W,0.914,8.61,170123,,,D*72
2023-01-17T18:19:13.689Z,1673979553.689 [NAL9602](INFO): GPS fix at 20230117T181912: (36.806897, -121.785132)
2023-01-17T18:19:13.698Z,1673979553.698 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T18:19:13.699Z,1673979553.699 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T18:19:20.883Z,1673979560.883 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230117T163302/Courier0064.lzma
2023-01-17T18:19:21.885Z,1673979561.885 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0064.lzma.bak
2023-01-17T18:19:21.885Z,1673979561.885 [DataOverHttps](INFO): SBD MOMSN=17401746
2023-01-17T18:19:37.696Z,1673979577.696 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20230117T163302/Express0065.lzma
2023-01-17T18:19:38.697Z,1673979578.697 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0065.lzma.bak
2023-01-17T18:19:38.698Z,1673979578.698 [DataOverHttps](INFO): SBD MOMSN=17401749
2023-01-17T18:19:39.959Z,1673979579.959 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T18:19:39.959Z,1673979579.959 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T18:19:39.959Z,1673979579.959 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T18:19:44.389Z,1673979584.389 [NAL9602](INFO): SBD MO Status=2, MOMSN=38388, MT Status=2, MTMSN=0
2023-01-17T18:19:44.389Z,1673979584.389 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T18:20:04.586Z,1673979604.586 [NAL9602](INFO): SBD MO Status=2, MOMSN=38388, MT Status=2, MTMSN=0
2023-01-17T18:20:04.586Z,1673979604.586 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T18:20:49.431Z,1673979649.431 [NAL9602](INFO): SBD MO Status=2, MOMSN=38388, MT Status=2, MTMSN=0
2023-01-17T18:20:49.432Z,1673979649.432 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-01-17T18:21:18.531Z,1673979678.531 [NAL9602](INFO): SBD MO Status=0, MOMSN=38388, MT Status=0, MTMSN=0
2023-01-17T18:21:18.531Z,1673979678.531 [NAL9602](INFO): No messages in MT queue
2023-01-17T18:21:49.231Z,1673979709.231 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T18:24:40.603Z,1673979880.603 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T18:24:40.603Z,1673979880.603 [Default:CheckIn:C.Wait] Stopped
2023-01-17T18:24:40.603Z,1673979880.603 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T18:24:40.603Z,1673979880.603 [Default:CheckIn:D] Running Loop=1
2023-01-17T18:24:40.998Z,1673979880.998 [Default:CheckIn:D] Stopped
2023-01-17T18:24:40.998Z,1673979880.998 [Default:CheckIn:E] Running Loop=1
2023-01-17T18:24:41.408Z,1673979881.408 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.695345 min 
2023-01-17T18:24:41.409Z,1673979881.409 [Default:CheckIn:E] Stopped
2023-01-17T18:24:41.409Z,1673979881.409 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T18:24:41.409Z,1673979881.409 [Default:CheckIn] Stopped
2023-01-17T18:24:41.409Z,1673979881.409 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T18:24:41.409Z,1673979881.409 [Default:CheckIn](INFO): Running loop #19
2023-01-17T18:24:41.409Z,1673979881.409 [Default:CheckIn] Running Loop=19
2023-01-17T18:24:41.409Z,1673979881.409 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T18:24:41.409Z,1673979881.409 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T18:24:43.411Z,1673979883.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182442.00,A,3648.51953,N,12147.19108,W,1.166,254.59,170123,,,D*7B
2023-01-17T18:24:43.413Z,1673979883.413 [NAL9602](INFO): GPS fix at 20230117T182442: (36.808659, -121.786518)
2023-01-17T18:24:43.443Z,1673979883.443 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T18:24:43.443Z,1673979883.443 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T18:24:53.387Z,1673979893.387 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230117T163302/Courier0067.lzma
2023-01-17T18:24:53.549Z,1673979893.549 [NAL9602](INFO): SBD MO Status=0, MOMSN=38389, MT Status=0, MTMSN=0
2023-01-17T18:24:53.549Z,1673979893.549 [NAL9602](INFO): No messages in MT queue
2023-01-17T18:24:54.416Z,1673979894.416 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0067.lzma.bak
2023-01-17T18:24:54.417Z,1673979894.417 [DataOverHttps](INFO): SBD MOMSN=17401754
2023-01-17T18:25:10.403Z,1673979910.403 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20230117T163302/Express0068.lzma
2023-01-17T18:25:11.405Z,1673979911.405 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0068.lzma.bak
2023-01-17T18:25:11.406Z,1673979911.406 [DataOverHttps](INFO): SBD MOMSN=17401757
2023-01-17T18:25:12.547Z,1673979912.547 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T18:25:12.547Z,1673979912.547 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T18:25:12.547Z,1673979912.547 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T18:25:24.256Z,1673979924.256 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T18:30:13.302Z,1673980213.302 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T18:30:13.302Z,1673980213.302 [Default:CheckIn:C.Wait] Stopped
2023-01-17T18:30:13.302Z,1673980213.302 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T18:30:13.302Z,1673980213.302 [Default:CheckIn:D] Running Loop=1
2023-01-17T18:30:13.750Z,1673980213.750 [Default:CheckIn:D] Stopped
2023-01-17T18:30:13.750Z,1673980213.750 [Default:CheckIn:E] Running Loop=1
2023-01-17T18:30:14.119Z,1673980214.119 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.241203 min 
2023-01-17T18:30:14.119Z,1673980214.119 [Default:CheckIn:E] Stopped
2023-01-17T18:30:14.120Z,1673980214.120 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T18:30:14.120Z,1673980214.120 [Default:CheckIn] Stopped
2023-01-17T18:30:14.120Z,1673980214.120 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T18:30:14.120Z,1673980214.120 [Default:CheckIn](INFO): Running loop #20
2023-01-17T18:30:14.120Z,1673980214.120 [Default:CheckIn] Running Loop=20
2023-01-17T18:30:14.120Z,1673980214.120 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T18:30:14.120Z,1673980214.120 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T18:30:16.118Z,1673980216.118 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183015.00,A,3648.29701,N,12147.72279,W,8.689,260.35,170123,,,D*70
2023-01-17T18:30:16.120Z,1673980216.120 [NAL9602](INFO): GPS fix at 20230117T183015: (36.804950, -121.795380)
2023-01-17T18:30:16.130Z,1673980216.130 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T18:30:16.130Z,1673980216.130 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T18:30:25.811Z,1673980225.811 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0070.lzma
2023-01-17T18:30:26.814Z,1673980226.814 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0070.lzma.bak
2023-01-17T18:30:26.814Z,1673980226.814 [DataOverHttps](INFO): SBD MOMSN=17401762
2023-01-17T18:30:31.122Z,1673980231.122 [NAL9602](INFO): SBD MO Status=0, MOMSN=38390, MT Status=0, MTMSN=0
2023-01-17T18:30:31.122Z,1673980231.122 [NAL9602](INFO): No messages in MT queue
2023-01-17T18:30:42.779Z,1673980242.779 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20230117T163302/Express0071.lzma
2023-01-17T18:30:43.781Z,1673980243.781 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0071.lzma.bak
2023-01-17T18:30:43.782Z,1673980243.782 [DataOverHttps](INFO): SBD MOMSN=17401765
2023-01-17T18:30:44.865Z,1673980244.865 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T18:30:44.865Z,1673980244.865 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T18:30:44.865Z,1673980244.865 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T18:31:01.823Z,1673980261.823 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T18:35:45.658Z,1673980545.658 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T18:35:45.658Z,1673980545.658 [Default:CheckIn:C.Wait] Stopped
2023-01-17T18:35:45.658Z,1673980545.658 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T18:35:45.658Z,1673980545.658 [Default:CheckIn:D] Running Loop=1
2023-01-17T18:35:46.080Z,1673980546.080 [Default:CheckIn:D] Stopped
2023-01-17T18:35:46.080Z,1673980546.080 [Default:CheckIn:E] Running Loop=1
2023-01-17T18:35:46.466Z,1673980546.466 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.780037 min 
2023-01-17T18:35:46.466Z,1673980546.466 [Default:CheckIn:E] Stopped
2023-01-17T18:35:46.466Z,1673980546.466 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T18:35:46.466Z,1673980546.466 [Default:CheckIn] Stopped
2023-01-17T18:35:46.466Z,1673980546.466 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T18:35:46.467Z,1673980546.467 [Default:CheckIn](INFO): Running loop #21
2023-01-17T18:35:46.467Z,1673980546.467 [Default:CheckIn] Running Loop=21
2023-01-17T18:35:46.467Z,1673980546.467 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T18:35:46.467Z,1673980546.467 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T18:35:48.475Z,1673980548.475 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183547.00,A,3648.03967,N,12149.13507,W,16.309,256.19,170123,,,D*4A
2023-01-17T18:35:48.477Z,1673980548.477 [NAL9602](INFO): GPS fix at 20230117T183547: (36.800661, -121.818918)
2023-01-17T18:35:48.488Z,1673980548.488 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T18:35:48.488Z,1673980548.488 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T18:35:54.771Z,1673980554.771 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230117T163302/Courier0073.lzma
2023-01-17T18:35:55.773Z,1673980555.773 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Courier0073.lzma.bak
2023-01-17T18:35:55.774Z,1673980555.774 [DataOverHttps](INFO): SBD MOMSN=17401769
2023-01-17T18:36:11.483Z,1673980571.483 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20230117T163302/Express0074.lzma
2023-01-17T18:36:12.485Z,1673980572.485 [DataOverHttps](INFO): Moved sent file to Logs/20230117T163302/Express0074.lzma.bak
2023-01-17T18:36:12.486Z,1673980572.486 [DataOverHttps](INFO): SBD MOMSN=17401772
2023-01-17T18:36:13.547Z,1673980573.547 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T18:36:13.547Z,1673980573.547 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T18:36:13.547Z,1673980573.547 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T18:36:17.978Z,1673980577.978 [NAL9602](INFO): SBD MO Status=0, MOMSN=38391, MT Status=0, MTMSN=0
2023-01-17T18:36:17.978Z,1673980577.978 [NAL9602](INFO): No messages in MT queue
2023-01-17T18:36:48.672Z,1673980608.672 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T18:37:45.912Z,1673980665.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-01-17T18:38:17.040Z,1673980697.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-01-17T18:40:19.768Z,1673980819.768 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2023-01-17T18:40:50.892Z,1673980850.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-01-17T18:41:14.161Z,1673980874.161 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-01-17T18:41:14.161Z,1673980874.161 [Default:CheckIn:C.Wait] Stopped
2023-01-17T18:41:14.161Z,1673980874.161 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T18:41:14.161Z,1673980874.161 [Default:CheckIn:D] Running Loop=1
2023-01-17T18:41:14.572Z,1673980874.572 [Default:CheckIn:D] Stopped
2023-01-17T18:41:14.572Z,1673980874.572 [Default:CheckIn:E] Running Loop=1
2023-01-17T18:41:14.980Z,1673980874.980 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.254907 min 
2023-01-17T18:41:14.980Z,1673980874.980 [Default:CheckIn:E] Stopped
2023-01-17T18:41:14.980Z,1673980874.980 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-01-17T18:41:14.981Z,1673980874.981 [Default:CheckIn] Stopped
2023-01-17T18:41:14.981Z,1673980874.981 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T18:41:14.981Z,1673980874.981 [Default:CheckIn](INFO): Running loop #22
2023-01-17T18:41:14.981Z,1673980874.981 [Default:CheckIn] Running Loop=22
2023-01-17T18:41:14.981Z,1673980874.981 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T18:41:14.981Z,1673980874.981 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T18:41:16.986Z,1673980876.986 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184116.00,A,3647.88604,N,12150.71723,W,11.644,271.64,170123,,,A*42
2023-01-17T18:41:16.998Z,1673980876.998 [NAL9602](INFO): GPS fix at 20230117T184116: (36.798101, -121.845287)
2023-01-17T18:41:17.008Z,1673980877.008 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T18:41:17.008Z,1673980877.008 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-01-17T18:41:22.032Z,1673980882.032 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-01-17T18:41:53.160Z,1673980913.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-01-17T18:41:56.170Z,1673980916.170 [NAL9602](INFO): SBD MO Status=0, MOMSN=38392, MT Status=0, MTMSN=0
2023-01-17T18:41:56.170Z,1673980916.170 [NAL9602](INFO): No messages in MT queue
2023-01-17T18:42:24.284Z,1673980944.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-01-17T18:42:26.876Z,1673980946.876 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T18:42:43.360Z,1673980963.360 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.027512
2023-01-17T18:43:03.286Z,1673980983.286 [NAL9602](INFO): SBD MO Status=1, MOMSN=38393, MT Status=0, MTMSN=0
2023-01-17T18:43:03.344Z,1673980983.344 [NAL9602](INFO): Sent 73 bytes from file Logs/20230117T163302/Courier0076.lzma
2023-01-17T18:43:03.344Z,1673980983.344 [NAL9602](INFO): Packets left to send: 0
2023-01-17T18:43:12.618Z,1673980992.618 [NAL9602](INFO): SBD MO Status=1, MOMSN=38394, MT Status=0, MTMSN=0
2023-01-17T18:43:12.676Z,1673980992.676 [NAL9602](INFO): Sent 167 bytes from file Logs/20230117T163302/Express0077.lzma
2023-01-17T18:43:12.676Z,1673980992.676 [NAL9602](INFO): Packets left to send: 0
2023-01-17T18:43:14.484Z,1673980994.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-01-17T18:43:19.890Z,1673980999.890 [NAL9602](INFO): SBD MO Status=0, MOMSN=38395, MT Status=0, MTMSN=0
2023-01-17T18:43:19.976Z,1673980999.976 [Default:CheckIn:Read_Iridium] Stopped
2023-01-17T18:43:19.976Z,1673980999.976 [Default:CheckIn:C.Wait] Running Loop=1
2023-01-17T18:43:19.976Z,1673980999.976 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-01-17T18:43:32.108Z,1673981012.108 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003192
2023-01-17T18:43:50.598Z,1673981030.598 [NAL9602](INFO): Not Powering down - fast GPS
2023-01-17T18:47:47.905Z,1673981267.905 [DataOverHttps](IMPORTANT): SBD MTMSN=20230117T184746