2024-06-17T15:45:50.626Z,1718639150.626 [Supervisor](DEBUG): Initializing supervisor.
2024-06-17T15:45:50.630Z,1718639150.630 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-06-17T15:45:50.630Z,1718639150.630 [SyncHandler](INFO): Protected caller Thread ID is 4573
2024-06-17T15:45:50.631Z,1718639150.631 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-06-17T15:45:50.632Z,1718639150.632 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-06-17T15:45:50.632Z,1718639150.632 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4574
2024-06-17T15:45:50.636Z,1718639150.636 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-06-17T15:45:50.653Z,1718639150.653 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-06-17T15:45:50.654Z,1718639150.654 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-06-17T15:45:50.654Z,1718639150.654 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4575
2024-06-17T15:45:50.659Z,1718639150.659 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-06-17T15:45:50.659Z,1718639150.659 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-06-17T15:45:50.660Z,1718639150.660 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4576
2024-06-17T15:45:50.662Z,1718639150.662 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-06-17T15:45:50.663Z,1718639150.663 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-06-17T15:45:50.663Z,1718639150.663 [logger ThreadHandler](INFO): Protected caller Thread ID is 4577
2024-06-17T15:45:50.667Z,1718639150.667 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-06-17T15:45:50.668Z,1718639150.668 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-06-17T15:45:50.670Z,1718639150.670 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-06-17T15:45:50.948Z,1718639150.948 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-06-17T15:45:50.948Z,1718639150.948 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-06-17T15:45:51.041Z,1718639151.041 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-06-17T15:45:51.592Z,1718639151.592 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-06-17T15:45:51.593Z,1718639151.593 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-06-17T15:45:51.953Z,1718639151.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-06-17T15:45:51.954Z,1718639151.954 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-06-17T15:45:52.063Z,1718639152.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-06-17T15:45:52.063Z,1718639152.063 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-06-17T15:45:52.482Z,1718639152.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-06-17T15:45:52.483Z,1718639152.483 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-06-17T15:45:52.711Z,1718639152.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-06-17T15:45:52.712Z,1718639152.712 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-06-17T15:45:53.252Z,1718639153.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-06-17T15:45:53.252Z,1718639153.252 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-06-17T15:45:53.400Z,1718639153.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-06-17T15:45:53.401Z,1718639153.401 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-06-17T15:45:53.493Z,1718639153.493 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-06-17T15:45:54.344Z,1718639154.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-06-17T15:45:54.344Z,1718639154.344 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-06-17T15:45:54.744Z,1718639154.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-06-17T15:45:54.744Z,1718639154.744 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-06-17T15:45:54.967Z,1718639154.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-06-17T15:45:54.968Z,1718639154.968 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-06-17T15:45:55.193Z,1718639155.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-06-17T15:45:55.193Z,1718639155.193 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-06-17T15:45:55.625Z,1718639155.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-06-17T15:45:55.625Z,1718639155.625 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-06-17T15:45:55.889Z,1718639155.889 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-06-17T15:45:55.891Z,1718639155.891 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-06-17T15:45:55.892Z,1718639155.892 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-06-17T15:45:55.995Z,1718639155.995 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-06-17T15:45:56.093Z,1718639156.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-06-17T15:45:56.207Z,1718639156.207 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-06-17T15:45:56.302Z,1718639156.302 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-06-17T15:45:56.417Z,1718639156.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-06-17T15:45:56.522Z,1718639156.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-06-17T15:45:56.647Z,1718639156.647 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-06-17T15:45:56.812Z,1718639156.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-06-17T15:45:56.964Z,1718639156.964 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-06-17T15:45:57.086Z,1718639157.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-06-17T15:45:57.361Z,1718639157.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-06-17T15:45:57.362Z,1718639157.362 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2024-06-17T15:45:57.363Z,1718639157.363 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-06-17T15:45:57.373Z,1718639157.373 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-06-17T15:45:57.426Z,1718639157.426 [VerticalControl](DEBUG): Construct VerticalControl.
2024-06-17T15:45:57.484Z,1718639157.484 [VerticalControl] Loaded
2024-06-17T15:45:57.485Z,1718639157.485 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-06-17T15:45:57.487Z,1718639157.487 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-06-17T15:45:57.533Z,1718639157.533 [HorizontalControl] Loaded
2024-06-17T15:45:57.533Z,1718639157.533 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-06-17T15:45:57.536Z,1718639157.536 [SpeedControl](DEBUG): Construct SpeedControl.
2024-06-17T15:45:57.539Z,1718639157.539 [SpeedControl] Loaded
2024-06-17T15:45:57.539Z,1718639157.539 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-06-17T15:45:57.542Z,1718639157.542 [LoopControl](DEBUG): Construct LoopControl.
2024-06-17T15:45:57.542Z,1718639157.542 [LoopControl] Loaded
2024-06-17T15:45:57.542Z,1718639157.542 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-06-17T15:45:57.543Z,1718639157.543 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-06-17T15:45:57.543Z,1718639157.543 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-06-17T15:45:57.553Z,1718639157.553 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-06-17T15:45:57.554Z,1718639157.554 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-06-17T15:45:57.721Z,1718639157.721 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-06-17T15:45:57.721Z,1718639157.721 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-06-17T15:45:57.874Z,1718639157.874 [BuoyancyServo] Loaded
2024-06-17T15:45:57.874Z,1718639157.874 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-06-17T15:45:57.895Z,1718639157.895 [ElevatorServo] Loaded
2024-06-17T15:45:57.896Z,1718639157.896 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-06-17T15:45:57.916Z,1718639157.916 [MassServo] Loaded
2024-06-17T15:45:57.917Z,1718639157.917 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-06-17T15:45:57.937Z,1718639157.937 [RudderServo] Loaded
2024-06-17T15:45:57.937Z,1718639157.937 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-06-17T15:45:57.953Z,1718639157.953 [ThrusterHE] Loaded
2024-06-17T15:45:57.953Z,1718639157.953 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-06-17T15:45:57.954Z,1718639157.954 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-06-17T15:45:57.954Z,1718639157.954 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-06-17T15:45:58.064Z,1718639158.064 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-06-17T15:45:58.064Z,1718639158.064 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-06-17T15:45:58.077Z,1718639158.077 [NavChart] Loaded
2024-06-17T15:45:58.077Z,1718639158.077 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-06-17T15:45:58.083Z,1718639158.083 [UniversalFixResidualReporter] Loaded
2024-06-17T15:45:58.083Z,1718639158.083 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-06-17T15:45:58.083Z,1718639158.083 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-06-17T15:45:58.084Z,1718639158.084 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-06-17T15:45:58.139Z,1718639158.139 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-06-17T15:45:58.139Z,1718639158.139 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-06-17T15:45:58.566Z,1718639158.566 [AHRS_M2] Loaded
2024-06-17T15:45:58.566Z,1718639158.566 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-06-17T15:45:59.381Z,1718639159.381 [BPC1] Loaded
2024-06-17T15:45:59.381Z,1718639159.381 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-06-17T15:45:59.455Z,1718639159.455 [DataOverHttps] Loaded
2024-06-17T15:45:59.455Z,1718639159.455 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-06-17T15:45:59.456Z,1718639159.456 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2024-06-17T15:45:59.457Z,1718639159.457 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4659
2024-06-17T15:45:59.480Z,1718639159.480 [Depth_Keller] Loaded
2024-06-17T15:45:59.480Z,1718639159.480 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-06-17T15:45:59.485Z,1718639159.485 [DropWeight] Loaded
2024-06-17T15:45:59.485Z,1718639159.485 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-06-17T15:45:59.548Z,1718639159.548 [NAL9602] Loaded
2024-06-17T15:45:59.549Z,1718639159.549 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-06-17T15:45:59.578Z,1718639159.578 [Onboard] Loaded
2024-06-17T15:45:59.578Z,1718639159.578 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-06-17T15:45:59.579Z,1718639159.579 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2024-06-17T15:45:59.579Z,1718639159.579 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4660
2024-06-17T15:45:59.594Z,1718639159.594 [Power24vConverter] Loaded
2024-06-17T15:45:59.595Z,1718639159.595 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-06-17T15:45:59.609Z,1718639159.609 [Radio_Surface] Loaded
2024-06-17T15:45:59.610Z,1718639159.610 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-06-17T15:45:59.611Z,1718639159.611 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2024-06-17T15:45:59.611Z,1718639159.611 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4661
2024-06-17T15:45:59.626Z,1718639159.626 [Sonardyne_Nano] Loaded
2024-06-17T15:45:59.626Z,1718639159.626 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-06-17T15:45:59.721Z,1718639159.721 [DAT] Loaded
2024-06-17T15:45:59.721Z,1718639159.721 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-06-17T15:45:59.722Z,1718639159.722 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C14E0
2024-06-17T15:45:59.722Z,1718639159.722 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4662
2024-06-17T15:45:59.723Z,1718639159.723 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-06-17T15:45:59.723Z,1718639159.723 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-06-17T15:45:59.772Z,1718639159.772 [DepthRateCalculator] Loaded
2024-06-17T15:45:59.772Z,1718639159.772 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-06-17T15:45:59.777Z,1718639159.777 [PitchRateCalculator] Loaded
2024-06-17T15:45:59.778Z,1718639159.778 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-06-17T15:45:59.786Z,1718639159.786 [SpeedCalculator] Loaded
2024-06-17T15:45:59.786Z,1718639159.786 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-06-17T15:45:59.791Z,1718639159.791 [YawRateCalculator] Loaded
2024-06-17T15:45:59.791Z,1718639159.791 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-06-17T15:45:59.809Z,1718639159.809 [ElevatorOffsetCalculator] Loaded
2024-06-17T15:45:59.810Z,1718639159.810 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-06-17T15:45:59.810Z,1718639159.810 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-06-17T15:45:59.811Z,1718639159.811 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-06-17T15:45:59.943Z,1718639159.943 [CANONSampler] Loaded
2024-06-17T15:45:59.943Z,1718639159.943 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-06-17T15:46:00.007Z,1718639160.007 [CTD_Seabird] Loaded
2024-06-17T15:46:00.008Z,1718639160.008 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-06-17T15:46:00.009Z,1718639160.009 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2024-06-17T15:46:00.009Z,1718639160.009 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4663
2024-06-17T15:46:00.029Z,1718639160.029 [PAR_Licor] Loaded
2024-06-17T15:46:00.029Z,1718639160.029 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-06-17T15:46:00.073Z,1718639160.073 [WetLabsBB2FL] Loaded
2024-06-17T15:46:00.073Z,1718639160.073 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-06-17T15:46:00.074Z,1718639160.074 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAD4E0
2024-06-17T15:46:00.074Z,1718639160.074 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4664
2024-06-17T15:46:00.075Z,1718639160.075 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-06-17T15:46:00.076Z,1718639160.076 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-06-17T15:46:00.134Z,1718639160.134 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-06-17T15:46:00.134Z,1718639160.134 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-06-17T15:46:00.964Z,1718639160.964 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-06-17T15:46:00.965Z,1718639160.965 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-06-17T15:46:01.102Z,1718639161.102 [SBIT](DEBUG): Construct Startup Built In Test.
2024-06-17T15:46:01.111Z,1718639161.111 [SBIT] Loaded
2024-06-17T15:46:01.111Z,1718639161.111 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-06-17T15:46:01.114Z,1718639161.114 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-06-17T15:46:01.126Z,1718639161.126 [IBIT] Loaded
2024-06-17T15:46:01.126Z,1718639161.126 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-06-17T15:46:01.132Z,1718639161.132 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-06-17T15:46:01.235Z,1718639161.235 [CBIT] Loaded
2024-06-17T15:46:01.236Z,1718639161.236 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-06-17T15:46:01.236Z,1718639161.236 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-06-17T15:46:01.237Z,1718639161.237 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-06-17T15:46:01.345Z,1718639161.345 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-06-17T15:46:01.351Z,1718639161.351 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-06-17T15:46:01.354Z,1718639161.354 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-06-17T15:46:01.366Z,1718639161.366 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-06-17T15:46:01.367Z,1718639161.367 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-06-17T15:46:01.367Z,1718639161.367 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4665
2024-06-17T15:46:01.371Z,1718639161.371 [Supervisor](INFO): Main Thread ID is 4383
2024-06-17T15:46:01.371Z,1718639161.371 [Supervisor](DEBUG): Running supervisor.
2024-06-17T15:46:01.372Z,1718639161.372 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4666
2024-06-17T15:46:01.373Z,1718639161.373 [CommandExec](INFO): Initializing the command executive.
2024-06-17T15:46:01.374Z,1718639161.374 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4667
2024-06-17T15:46:01.376Z,1718639161.376 [controlThread ThreadHandler](INFO): Handler Thread ID is 4668
2024-06-17T15:46:01.377Z,1718639161.377 [controlThread](DEBUG): Initializing ControlThread
2024-06-17T15:46:01.377Z,1718639161.377 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-06-17T15:46:01.379Z,1718639161.379 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-06-17T15:46:01.380Z,1718639161.380 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-06-17T15:46:01.380Z,1718639161.380 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-06-17T15:46:01.382Z,1718639161.382 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-06-17T15:46:01.383Z,1718639161.383 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-06-17T15:46:01.388Z,1718639161.388 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-06-17T15:46:01.389Z,1718639161.389 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-06-17T15:46:01.389Z,1718639161.389 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-06-17T15:46:01.389Z,1718639161.389 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-06-17T15:46:01.390Z,1718639161.390 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-06-17T15:46:01.395Z,1718639161.395 [SBIT](INFO): Initialize SBIT Component.
2024-06-17T15:46:01.395Z,1718639161.395 [SBIT](IMPORTANT): git: 2024-06-03
2024-06-17T15:46:01.395Z,1718639161.395 [SBIT](INFO): git hash: 7fa19da3a148abf4baea4b65318d99ca26f8c915
2024-06-17T15:46:01.396Z,1718639161.396 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-06-17T15:46:01.397Z,1718639161.397 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-06-17T15:46:01.398Z,1718639161.398 [SBIT](INFO): Beginning SBIT in 37.000000 seconds.
2024-06-17T15:46:01.399Z,1718639161.399 [IBIT](INFO): Initialize IBIT Component.
2024-06-17T15:46:01.399Z,1718639161.399 [CBIT](DEBUG): Initialize CBIT Component.
2024-06-17T15:46:01.400Z,1718639161.400 [logger ThreadHandler](INFO): Handler Thread ID is 4669
2024-06-17T15:46:01.410Z,1718639161.410 [CBIT](DEBUG): Initialized mux pins.
2024-06-17T15:46:01.411Z,1718639161.411 [CBIT](DEBUG): Initializing the watchdog timer.
2024-06-17T15:46:01.419Z,1718639161.419 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4670
2024-06-17T15:46:01.421Z,1718639161.421 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-06-17T15:46:01.431Z,1718639161.431 [Onboard ThreadHandler](INFO): Handler Thread ID is 4671
2024-06-17T15:46:01.435Z,1718639161.435 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-06-17T15:46:01.436Z,1718639161.436 [CBIT](DEBUG): Initializing heartbeat.
2024-06-17T15:46:01.451Z,1718639161.451 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4672
2024-06-17T15:46:01.467Z,1718639161.467 [DAT ThreadHandler](INFO): Handler Thread ID is 4673
2024-06-17T15:46:01.468Z,1718639161.468 [DAT](INFO): Powering up
2024-06-17T15:46:01.468Z,1718639161.468 [DAT](DEBUG): Initializing DAT.
2024-06-17T15:46:01.472Z,1718639161.472 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4675
2024-06-17T15:46:01.472Z,1718639161.472 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-06-17T15:46:01.477Z,1718639161.477 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4677
2024-06-17T15:46:01.483Z,1718639161.483 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4679
2024-06-17T15:46:01.486Z,1718639161.486 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-06-17T15:46:01.487Z,1718639161.487 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-06-17T15:46:01.487Z,1718639161.487 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-06-17T15:46:01.487Z,1718639161.487 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-06-17T15:46:01.487Z,1718639161.487 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-06-17T15:46:01.487Z,1718639161.487 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-06-17T15:46:01.488Z,1718639161.488 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-06-17T15:46:01.488Z,1718639161.488 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-06-17T15:46:01.506Z,1718639161.506 [CBIT](DEBUG): Deactivating GF circuits.
2024-06-17T15:46:01.507Z,1718639161.507 [CBIT](DEBUG): Deactivating emergency mode.
2024-06-17T15:46:01.511Z,1718639161.511 [WetLabsBB2FL](INFO): Powering up
2024-06-17T15:46:01.546Z,1718639161.546 [CBIT](DEBUG): Backplane powered.
2024-06-17T15:46:01.547Z,1718639161.547 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-06-17T15:46:01.548Z,1718639161.548 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-06-17T15:46:01.548Z,1718639161.548 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-06-17T15:46:01.549Z,1718639161.549 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-06-17T15:46:01.588Z,1718639161.588 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-06-17T15:46:01.593Z,1718639161.593 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-17T15:46:01.637Z,1718639161.637 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-06-17T15:46:01.638Z,1718639161.638 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-06-17T15:46:01.638Z,1718639161.638 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-06-17T15:46:01.639Z,1718639161.639 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-06-17T15:46:01.855Z,1718639161.855 [Radio_Surface](INFO): Powering up
2024-06-17T15:46:01.970Z,1718639161.970 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-06-17T15:46:01.988Z,1718639161.988 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-06-17T15:46:01.990Z,1718639161.990 [Default:A.Wait](DEBUG): Construct Wait.
2024-06-17T15:46:01.996Z,1718639161.996 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-17T15:46:02.037Z,1718639162.037 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-06-17T15:46:02.048Z,1718639162.048 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-06-17T15:46:02.062Z,1718639162.062 [Default:E.Execute](DEBUG): Construct Execute.
2024-06-17T15:46:02.065Z,1718639162.065 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-06-17T15:46:02.074Z,1718639162.074 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-06-17T15:46:02.087Z,1718639162.087 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-06-17T15:46:02.165Z,1718639162.165 [Power24vConverter](INFO): Powering up.
2024-06-17T15:46:02.165Z,1718639162.165 [Sonardyne_Nano](INFO): Initializing.
2024-06-17T15:46:02.183Z,1718639162.183 [CANONSampler](INFO): Powering down
2024-06-17T15:46:02.243Z,1718639162.243 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-06-17T15:46:02.251Z,1718639162.251 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-06-17T15:46:02.252Z,1718639162.252 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-17T15:46:02.259Z,1718639162.259 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-17T15:46:02.260Z,1718639162.260 [MassServo](DEBUG): Initializing EZServoServo.
2024-06-17T15:46:02.267Z,1718639162.267 [MassServo](DEBUG): Initializing MassServo.
2024-06-17T15:46:02.267Z,1718639162.267 [RudderServo](DEBUG): Initializing EZServoServo.
2024-06-17T15:46:02.275Z,1718639162.275 [RudderServo](DEBUG): Initializing RudderServo.
2024-06-17T15:46:02.276Z,1718639162.276 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-06-17T15:46:02.283Z,1718639162.283 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-06-17T15:46:02.520Z,1718639162.520 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-06-17T15:46:02.520Z,1718639162.520 [DropWeight] Hardware Fault, FailCount= 1
2024-06-17T15:46:02.520Z,1718639162.520 [DropWeight](ERROR): Hardware Fault
2024-06-17T15:46:02.550Z,1718639162.550 [CommandExec](FAULT): Scheduling is paused
2024-06-17T15:46:02.558Z,1718639162.558 [CBIT](INFO): Critical error at 20240617T154602
2024-06-17T15:46:02.559Z,1718639162.559 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-06-17T15:46:02.561Z,1718639162.561 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-06-17T15:46:02.561Z,1718639162.561 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-06-17T15:46:02.919Z,1718639162.919 [CBIT](INFO): Critical error at 20240617T154602
2024-06-17T15:46:03.587Z,1718639163.587 [WetLabsBB2FL](INFO): Powering down
2024-06-17T15:46:04.105Z,1718639164.105 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-06-17T15:46:05.744Z,1718639165.744 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2024-06-17T15:46:07.971Z,1718639167.971 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-06-17T15:46:14.083Z,1718639174.083 [DAT](INFO): DAT read:
2024-06-17T15:46:14.084Z,1718639174.084 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-06-17T15:46:15.850Z,1718639175.850 [DAT](INFO): DAT read: MF Frequency Band
2024-06-17T15:46:15.852Z,1718639175.852 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-06-17T15:46:15.852Z,1718639175.852 [DAT](INFO): DAT read: Jun 17 2024 15:46:09
2024-06-17T15:46:17.108Z,1718639177.108 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-06-17T15:46:17.109Z,1718639177.109 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-06-17T15:46:17.109Z,1718639177.109 [DAT](INFO): commRate: 1200
2024-06-17T15:46:19.174Z,1718639179.174 [DAT](INFO): entering command mode
2024-06-17T15:46:19.375Z,1718639179.375 [DAT](INFO): DAT read:
2024-06-17T15:46:19.376Z,1718639179.376 [DAT](INFO): DAT read: user:1>
2024-06-17T15:46:19.376Z,1718639179.376 [DAT](INFO): setting verbose to 3
2024-06-17T15:46:19.627Z,1718639179.627 [DAT](INFO): DAT read: user:1>
2024-06-17T15:46:19.628Z,1718639179.628 [DAT](INFO): DAT read: Verbose | 3
2024-06-17T15:46:19.629Z,1718639179.629 [DAT](INFO): set verbose to 3
2024-06-17T15:46:19.629Z,1718639179.629 [DAT](INFO): setting DatVerbose to 27440
2024-06-17T15:46:19.879Z,1718639179.879 [DAT](INFO): DAT read: user:2>
2024-06-17T15:46:19.880Z,1718639179.880 [DAT](INFO): DAT read: DatVerbose | 27440
2024-06-17T15:46:19.881Z,1718639179.881 [DAT](INFO): set DatVerbose to 27440
2024-06-17T15:46:19.881Z,1718639179.881 [DAT](INFO): setting transmit power to 8
2024-06-17T15:46:20.132Z,1718639180.132 [DAT](INFO): DAT read: user:3>
2024-06-17T15:46:20.133Z,1718639180.133 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-06-17T15:46:20.133Z,1718639180.133 [DAT](INFO): set transmit power to 8
2024-06-17T15:46:20.133Z,1718639180.133 [DAT](INFO): setting local address to 8
2024-06-17T15:46:20.383Z,1718639180.383 [DAT](INFO): DAT read: user:4>
2024-06-17T15:46:20.384Z,1718639180.384 [DAT](INFO): DAT read: LocalAddr | 8
2024-06-17T15:46:20.384Z,1718639180.384 [DAT](INFO): set local address to 8
2024-06-17T15:46:20.386Z,1718639180.386 [DAT](INFO): Setting time to: 15:46:20 And date to:6/17/2024
2024-06-17T15:46:20.635Z,1718639180.635 [DAT](INFO): DAT read: user:5>
2024-06-17T15:46:20.636Z,1718639180.636 [DAT](INFO): DAT read: Mon Jun 17, 2024 15:46:20
2024-06-17T15:46:20.636Z,1718639180.636 [DAT](INFO): Local DAT time set to Mon Jun 17, 2024 15:46:20
2024-06-17T15:46:29.564Z,1718639189.564 [NAL9602](INFO): Powering up NAL9602
2024-06-17T15:46:38.903Z,1718639198.903 [SBIT](IMPORTANT): Beginning Startup BIT
2024-06-17T15:46:38.911Z,1718639198.911 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-17T15:46:40.468Z,1718639200.468 [NAL9602](INFO): NAL9602 initialized
2024-06-17T15:46:48.924Z,1718639208.924 [CommandExec](IMPORTANT): got command strobe off
2024-06-17T15:46:48.924Z,1718639208.924 [CommandExec](IMPORTANT): Deactivating strobe
2024-06-17T15:46:49.825Z,1718639209.825 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-17T15:46:50.213Z,1718639210.213 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-17T15:47:01.172Z,1718639221.172 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-17T15:47:01.604Z,1718639221.604 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-17T15:47:12.419Z,1718639232.419 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-17T15:47:12.420Z,1718639232.420 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-06-17T15:47:33.037Z,1718639253.037 [SBIT](IMPORTANT): SBIT PASSED
2024-06-17T15:47:33.037Z,1718639253.037 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-06-17T15:47:33.038Z,1718639253.038 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-06-17T15:47:33.042Z,1718639253.042 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;
2024-06-17T15:47:33.043Z,1718639253.043 [SBIT](IMPORTANT): CANONSampler.simulateHardware=0 bool;
2024-06-17T15:47:33.043Z,1718639253.043 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=1 bool;
2024-06-17T15:47:33.043Z,1718639253.043 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;
2024-06-17T15:47:33.043Z,1718639253.043 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;
2024-06-17T15:47:33.043Z,1718639253.043 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2024-06-17T15:47:33.043Z,1718639253.043 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;
2024-06-17T15:47:33.043Z,1718639253.043 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;
2024-06-17T15:47:33.043Z,1718639253.043 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=255.575197 cubic_centimeter;
2024-06-17T15:47:33.043Z,1718639253.043 [SBIT](IMPORTANT): VerticalControl.massDefault=4.462539 millimeter;
2024-06-17T15:47:33.427Z,1718639253.427 [MissionManager](IMPORTANT): Started mission Startup
2024-06-17T15:47:33.428Z,1718639253.428 [Startup] Running Loop=1
2024-06-17T15:47:33.428Z,1718639253.428 [Startup](DEBUG): Aggregate::initialize Startup
2024-06-17T15:47:33.428Z,1718639253.428 [Startup:A.GoToSurface] Running Loop=1
2024-06-17T15:47:33.428Z,1718639253.428 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-17T15:47:33.428Z,1718639253.428 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-17T15:47:33.429Z,1718639253.429 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-17T15:47:33.430Z,1718639253.430 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-17T15:47:33.430Z,1718639253.430 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-17T15:47:33.431Z,1718639253.431 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-17T15:47:33.431Z,1718639253.431 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-17T15:47:33.433Z,1718639253.433 [Startup:StartupSatComms] Running Loop=1
2024-06-17T15:47:33.433Z,1718639253.433 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-06-17T15:47:33.433Z,1718639253.433 [Startup:StartupSatComms:A] Running Loop=1
2024-06-17T15:47:33.844Z,1718639253.844 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-06-17T15:48:33.617Z,1718639313.617 [Startup:StartupSatComms:A](INFO): Timed out from 2024-06-17T15:47:33.4Z
2024-06-17T15:48:33.617Z,1718639313.617 [Startup:StartupSatComms:A] Stopped
2024-06-17T15:48:33.617Z,1718639313.617 [Startup:StartupSatComms:B] Running Loop=1
2024-06-17T15:48:34.022Z,1718639314.022 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-17T15:48:44.381Z,1718639324.381 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005079
2024-06-17T15:48:51.599Z,1718639331.599 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20240617T154550/Courier0000.lzma
2024-06-17T15:48:52.600Z,1718639332.600 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0000.lzma.bak
2024-06-17T15:48:52.601Z,1718639332.601 [DataOverHttps](INFO): SBD MOMSN=21251584
2024-06-17T15:49:01.475Z,1718639341.475 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-06-17T15:49:01.475Z,1718639341.475 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T15:49:01.485Z,1718639341.485 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T15:49:01.891Z,1718639341.891 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T15:49:01.891Z,1718639341.891 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-06-17T15:49:07.531Z,1718639347.531 [DataOverHttps](INFO): Sending 944 bytes from file Logs/20240617T154550/Express0001.lzma
2024-06-17T15:49:08.528Z,1718639348.528 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0001.lzma.bak
2024-06-17T15:49:08.528Z,1718639348.528 [DataOverHttps](INFO): SBD MOMSN=21251589
2024-06-17T15:49:09.584Z,1718639349.584 [Startup:StartupSatComms:B] Stopped
2024-06-17T15:49:09.585Z,1718639349.585 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-06-17T15:49:09.585Z,1718639349.585 [Startup:StartupSatComms] Stopped
2024-06-17T15:49:09.585Z,1718639349.585 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-06-17T15:49:09.586Z,1718639349.586 [Startup](INFO): Completed Startup
2024-06-17T15:49:09.586Z,1718639349.586 [MissionManager](INFO): Startup is completed.
2024-06-17T15:49:09.586Z,1718639349.586 [MissionManager](INFO): Uninitializing Mission Startup
2024-06-17T15:49:09.586Z,1718639349.586 [Startup] Stopped
2024-06-17T15:49:09.586Z,1718639349.586 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-06-17T15:49:09.586Z,1718639349.586 [Startup:A.GoToSurface] Stopped
2024-06-17T15:49:09.586Z,1718639349.586 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-17T15:49:09.985Z,1718639349.985 [MissionManager](IMPORTANT): Started mission Default
2024-06-17T15:49:09.985Z,1718639349.985 [Default] Running Loop=1
2024-06-17T15:49:09.985Z,1718639349.985 [Default](DEBUG): Aggregate::initialize Default
2024-06-17T15:49:09.985Z,1718639349.985 [Default:B.GoToSurface] Running Loop=1
2024-06-17T15:49:09.985Z,1718639349.985 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-17T15:49:09.986Z,1718639349.986 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-17T15:49:09.986Z,1718639349.986 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-17T15:49:09.986Z,1718639349.986 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-17T15:49:09.987Z,1718639349.987 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-17T15:49:09.987Z,1718639349.987 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-17T15:49:09.987Z,1718639349.987 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-17T15:49:09.988Z,1718639349.988 [Default:A.Wait] Running Loop=1
2024-06-17T15:49:09.988Z,1718639349.988 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-06-17T15:49:23.297Z,1718639363.297 [Default:A.Wait](INFO): Done Waiting.
2024-06-17T15:49:23.297Z,1718639363.297 [Default:A.Wait] Stopped
2024-06-17T15:49:23.297Z,1718639363.297 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T15:49:23.709Z,1718639363.709 [Default:CheckIn] Running Loop=1
2024-06-17T15:49:23.709Z,1718639363.709 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T15:49:23.709Z,1718639363.709 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T15:49:24.104Z,1718639364.104 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-06-17T15:49:39.953Z,1718639379.953 [CommandLine](INFO): End of History
2024-06-17T15:49:53.793Z,1718639393.793 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2024-06-17T15:49:53.794Z,1718639393.794 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 90.000000 %
2024-06-17T15:50:21.878Z,1718639421.878 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 3.
2024-06-17T15:50:21.881Z,1718639421.881 [BPC1](INFO): Received data from all battery sticks.
2024-06-17T15:51:44.280Z,1718639504.280 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-06-17T15:52:02.471Z,1718639522.471 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-06-17T15:52:02.471Z,1718639522.471 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T15:52:02.506Z,1718639522.506 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T15:52:02.885Z,1718639522.885 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T15:52:02.885Z,1718639522.885 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-06-17T15:54:23.877Z,1718639663.877 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-06-17T15:49:23.7Z
2024-06-17T15:54:23.877Z,1718639663.877 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T15:54:23.877Z,1718639663.877 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T15:54:24.277Z,1718639664.277 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-17T15:54:31.258Z,1718639671.258 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240617T154550/Courier0004.lzma
2024-06-17T15:54:32.260Z,1718639672.260 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0004.lzma.bak
2024-06-17T15:54:32.260Z,1718639672.260 [DataOverHttps](INFO): SBD MOMSN=21251624
2024-06-17T15:54:47.967Z,1718639687.967 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20240617T154550/Express0005.lzma
2024-06-17T15:54:48.968Z,1718639688.968 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0005.lzma.bak
2024-06-17T15:54:48.968Z,1718639688.968 [DataOverHttps](INFO): SBD MOMSN=21251626
2024-06-17T15:54:50.156Z,1718639690.156 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T15:54:50.156Z,1718639690.156 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T15:54:50.156Z,1718639690.156 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T15:55:03.463Z,1718639703.463 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-06-17T15:55:03.463Z,1718639703.463 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T15:55:03.490Z,1718639703.490 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T15:55:03.876Z,1718639703.876 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T15:55:03.876Z,1718639703.876 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-06-17T15:56:43.248Z,1718639803.248 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-06-17T15:56:43.248Z,1718639803.248 [NAL9602] Data Fault, FailCount= 1
2024-06-17T15:56:43.248Z,1718639803.248 [NAL9602](ERROR): Data Fault
2024-06-17T15:56:43.267Z,1718639803.267 [CBIT](ERROR): Data Fault in component: NAL9602
2024-06-17T15:56:43.645Z,1718639803.645 [NAL9602](INFO): Powering down
2024-06-17T15:56:44.474Z,1718639804.474 [CBIT](INFO): Clearing failed state for component NAL9602
2024-06-17T15:56:44.475Z,1718639804.475 [NAL9602] No Fault, FailCount= 1
2024-06-17T15:57:13.948Z,1718639833.948 [NAL9602](INFO): Powering up NAL9602
2024-06-17T15:57:24.852Z,1718639844.852 [NAL9602](INFO): NAL9602 initialized
2024-06-17T15:58:04.476Z,1718639884.476 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-06-17T15:58:04.476Z,1718639884.476 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T15:58:04.487Z,1718639884.487 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T15:58:04.869Z,1718639884.869 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T15:58:04.869Z,1718639884.869 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-06-17T15:59:50.709Z,1718639990.709 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T15:59:50.709Z,1718639990.709 [Default:CheckIn:C.Wait] Stopped
2024-06-17T15:59:50.709Z,1718639990.709 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T15:59:50.709Z,1718639990.709 [Default:CheckIn:D] Running Loop=1
2024-06-17T15:59:51.117Z,1718639991.117 [Default:CheckIn:D] Stopped
2024-06-17T15:59:51.117Z,1718639991.117 [Default:CheckIn:E] Running Loop=1
2024-06-17T15:59:51.513Z,1718639991.513 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.685533 min
2024-06-17T15:59:51.513Z,1718639991.513 [Default:CheckIn:E] Stopped
2024-06-17T15:59:51.513Z,1718639991.513 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T15:59:51.513Z,1718639991.513 [Default:CheckIn] Stopped
2024-06-17T15:59:51.513Z,1718639991.513 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T15:59:51.513Z,1718639991.513 [Default:CheckIn](INFO): Running loop #2
2024-06-17T15:59:51.513Z,1718639991.513 [Default:CheckIn] Running Loop=2
2024-06-17T15:59:51.513Z,1718639991.513 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T15:59:51.513Z,1718639991.513 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T16:01:02.644Z,1718640062.644 [CBIT](INFO): Clearing failed state for component DropWeight
2024-06-17T16:01:02.644Z,1718640062.644 [DropWeight] No Fault, FailCount= 1
2024-06-17T16:01:05.472Z,1718640065.472 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-06-17T16:01:05.472Z,1718640065.472 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T16:01:05.509Z,1718640065.509 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T16:01:05.870Z,1718640065.870 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T16:01:05.870Z,1718640065.870 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-06-17T16:04:06.464Z,1718640246.464 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-06-17T16:04:06.464Z,1718640246.464 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T16:04:06.475Z,1718640246.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T16:04:06.864Z,1718640246.864 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T16:04:06.864Z,1718640246.864 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-06-17T16:04:51.709Z,1718640291.709 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-06-17T15:59:51.5Z
2024-06-17T16:04:51.709Z,1718640291.709 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T16:04:51.709Z,1718640291.709 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T16:04:58.962Z,1718640298.962 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240617T154550/Courier0007.lzma
2024-06-17T16:04:59.964Z,1718640299.964 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0007.lzma.bak
2024-06-17T16:04:59.964Z,1718640299.964 [DataOverHttps](INFO): SBD MOMSN=21251652
2024-06-17T16:05:15.463Z,1718640315.463 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20240617T154550/Express0008.lzma
2024-06-17T16:05:16.464Z,1718640316.464 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0008.lzma.bak
2024-06-17T16:05:16.464Z,1718640316.464 [DataOverHttps](INFO): SBD MOMSN=21251654
2024-06-17T16:05:17.551Z,1718640317.551 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T16:05:17.551Z,1718640317.551 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T16:05:17.551Z,1718640317.551 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T16:07:07.452Z,1718640427.452 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-06-17T16:07:07.452Z,1718640427.452 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T16:07:07.499Z,1718640427.499 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T16:07:07.853Z,1718640427.853 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T16:07:07.853Z,1718640427.853 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-06-17T16:07:27.629Z,1718640447.629 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-06-17T16:07:27.629Z,1718640447.629 [NAL9602] Data Fault, FailCount= 2
2024-06-17T16:07:27.630Z,1718640447.630 [NAL9602](ERROR): Data Fault
2024-06-17T16:07:27.652Z,1718640447.652 [CBIT](ERROR): Data Fault in component: NAL9602
2024-06-17T16:07:28.033Z,1718640448.033 [NAL9602](INFO): Powering down
2024-06-17T16:07:28.863Z,1718640448.863 [CBIT](INFO): Clearing failed state for component NAL9602
2024-06-17T16:07:28.863Z,1718640448.863 [NAL9602] No Fault, FailCount= 2
2024-06-17T16:07:58.333Z,1718640478.333 [NAL9602](INFO): Powering up NAL9602
2024-06-17T16:08:09.240Z,1718640489.240 [NAL9602](INFO): NAL9602 initialized
2024-06-17T16:10:08.443Z,1718640608.443 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-06-17T16:10:08.443Z,1718640608.443 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T16:10:08.454Z,1718640608.454 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T16:10:08.879Z,1718640608.879 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T16:10:08.879Z,1718640608.879 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-06-17T16:10:18.123Z,1718640618.123 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T16:10:18.123Z,1718640618.123 [Default:CheckIn:C.Wait] Stopped
2024-06-17T16:10:18.123Z,1718640618.123 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T16:10:18.123Z,1718640618.123 [Default:CheckIn:D] Running Loop=1
2024-06-17T16:10:18.537Z,1718640618.537 [Default:CheckIn:D] Stopped
2024-06-17T16:10:18.537Z,1718640618.537 [Default:CheckIn:E] Running Loop=1
2024-06-17T16:10:18.961Z,1718640618.961 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.142533 min
2024-06-17T16:10:18.961Z,1718640618.961 [Default:CheckIn:E] Stopped
2024-06-17T16:10:18.961Z,1718640618.961 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T16:10:18.962Z,1718640618.962 [Default:CheckIn] Stopped
2024-06-17T16:10:18.962Z,1718640618.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T16:10:18.962Z,1718640618.962 [Default:CheckIn](INFO): Running loop #3
2024-06-17T16:10:18.962Z,1718640618.962 [Default:CheckIn] Running Loop=3
2024-06-17T16:10:18.962Z,1718640618.962 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T16:10:18.962Z,1718640618.962 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T16:10:19.056Z,1718640619.056 [CommandExec](IMPORTANT): got command burn on
2024-06-17T16:10:19.057Z,1718640619.057 [CommandExec](IMPORTANT): Activating dropweight wire
2024-06-17T16:10:23.636Z,1718640623.635 [CommandExec](IMPORTANT): got command burn off
2024-06-17T16:10:23.636Z,1718640623.636 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-06-17T16:13:09.420Z,1718640789.420 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-06-17T16:13:09.420Z,1718640789.420 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T16:13:09.455Z,1718640789.455 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T16:13:09.832Z,1718640789.832 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T16:13:09.832Z,1718640789.832 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-06-17T16:15:19.105Z,1718640919.105 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-06-17T16:10:18.0Z
2024-06-17T16:15:19.105Z,1718640919.105 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T16:15:19.106Z,1718640919.106 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T16:15:25.890Z,1718640925.890 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240617T154550/Courier0010.lzma
2024-06-17T16:15:26.892Z,1718640926.892 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0010.lzma.bak
2024-06-17T16:15:26.892Z,1718640926.892 [DataOverHttps](INFO): SBD MOMSN=21251670
2024-06-17T16:15:42.331Z,1718640942.331 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20240617T154550/Express0011.lzma
2024-06-17T16:15:43.332Z,1718640943.332 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0011.lzma.bak
2024-06-17T16:15:43.332Z,1718640943.332 [DataOverHttps](INFO): SBD MOMSN=21251672
2024-06-17T16:15:44.560Z,1718640944.560 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T16:15:44.561Z,1718640944.561 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T16:15:44.561Z,1718640944.561 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T16:16:10.432Z,1718640970.432 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-06-17T16:16:10.432Z,1718640970.432 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T16:16:10.443Z,1718640970.443 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T16:16:10.845Z,1718640970.845 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T16:16:10.845Z,1718640970.845 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-06-17T16:18:10.085Z,1718641090.085 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-06-17T16:18:10.085Z,1718641090.085 [NAL9602] Data Fault, FailCount= 3
2024-06-17T16:18:10.085Z,1718641090.085 [NAL9602](ERROR): Data Fault
2024-06-17T16:18:10.103Z,1718641090.103 [CBIT](ERROR): Data Fault in component: NAL9602
2024-06-17T16:18:10.484Z,1718641090.484 [NAL9602](INFO): Powering down
2024-06-17T16:18:11.313Z,1718641091.313 [CBIT](INFO): Clearing failed state for component NAL9602
2024-06-17T16:18:11.313Z,1718641091.313 [NAL9602] No Fault, FailCount= 3
2024-06-17T16:18:40.833Z,1718641120.833 [NAL9602](INFO): Powering up NAL9602
2024-06-17T16:18:51.772Z,1718641131.772 [NAL9602](INFO): NAL9602 initialized
2024-06-17T16:19:11.611Z,1718641151.611 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-06-17T16:19:11.611Z,1718641151.611 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T16:19:11.623Z,1718641151.623 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T16:19:12.033Z,1718641152.033 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T16:19:12.033Z,1718641152.033 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-06-17T16:20:44.991Z,1718641244.991 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T16:20:44.991Z,1718641244.991 [Default:CheckIn:C.Wait] Stopped
2024-06-17T16:20:44.991Z,1718641244.991 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T16:20:44.991Z,1718641244.991 [Default:CheckIn:D] Running Loop=1
2024-06-17T16:20:45.377Z,1718641245.377 [Default:CheckIn:D] Stopped
2024-06-17T16:20:45.377Z,1718641245.377 [Default:CheckIn:E] Running Loop=1
2024-06-17T16:20:45.789Z,1718641245.789 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.589856 min
2024-06-17T16:20:45.789Z,1718641245.789 [Default:CheckIn:E] Stopped
2024-06-17T16:20:45.790Z,1718641245.790 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T16:20:45.790Z,1718641245.790 [Default:CheckIn] Stopped
2024-06-17T16:20:45.790Z,1718641245.790 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T16:20:45.790Z,1718641245.790 [Default:CheckIn](INFO): Running loop #4
2024-06-17T16:20:45.790Z,1718641245.790 [Default:CheckIn] Running Loop=4
2024-06-17T16:20:45.790Z,1718641245.790 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T16:20:45.790Z,1718641245.790 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T16:22:12.686Z,1718641332.686 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-06-17T16:22:12.686Z,1718641332.686 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-17T16:22:12.697Z,1718641332.697 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-17T16:22:13.108Z,1718641333.108 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-17T16:22:13.108Z,1718641333.108 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-06-17T16:23:01.998Z,1718641381.998 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162301.00,A,3648.16646,N,12147.25685,W,2.585,346.00,170624,,,A*71
2024-06-17T16:23:02.014Z,1718641382.014 [NAL9602](INFO): GPS fix at 20240617T162301: (36.802774, -121.787614)
2024-06-17T16:23:02.035Z,1718641382.035 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T16:23:02.035Z,1718641382.035 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T16:23:11.351Z,1718641391.351 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20240617T154550/Courier0013.lzma
2024-06-17T16:23:12.352Z,1718641392.352 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0013.lzma.bak
2024-06-17T16:23:12.352Z,1718641392.352 [DataOverHttps](INFO): SBD MOMSN=21251689
2024-06-17T16:23:28.727Z,1718641408.727 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20240617T154550/Express0014.lzma
2024-06-17T16:23:29.729Z,1718641409.729 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0014.lzma.bak
2024-06-17T16:23:29.730Z,1718641409.730 [DataOverHttps](INFO): SBD MOMSN=21251695
2024-06-17T16:23:31.104Z,1718641411.104 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T16:23:31.104Z,1718641411.104 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T16:23:31.104Z,1718641411.104 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T16:23:34.311Z,1718641414.311 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T16:28:12.948Z,1718641692.948 [DataOverHttps](IMPORTANT): SBD MTMSN=20240617T162812
2024-06-17T16:28:22.403Z,1718641702.403 [DataOverHttps](INFO): Received command: ibit
2024-06-17T16:28:22.491Z,1718641702.491 [CommandExec](IMPORTANT): got command ibit
2024-06-17T16:28:22.816Z,1718641702.816 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-06-17T16:28:22.816Z,1718641702.816 [IBIT](IMPORTANT): Beginning control surface checks.
2024-06-17T16:28:22.843Z,1718641702.843 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-17T16:28:24.410Z,1718641704.410 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162823.00,A,3648.13857,N,12147.23015,W,0.019,63.24,170624,,,A*48
2024-06-17T16:28:24.412Z,1718641704.412 [NAL9602](INFO): GPS fix at 20240617T162823: (36.802309, -121.787169)
2024-06-17T16:28:33.719Z,1718641713.719 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-17T16:28:34.146Z,1718641714.146 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-17T16:28:39.362Z,1718641719.362 [NAL9602](INFO): SBD MO Status=0, MOMSN=52773, MT Status=0, MTMSN=0
2024-06-17T16:28:39.362Z,1718641719.362 [NAL9602](INFO): No messages in MT queue
2024-06-17T16:28:40.571Z,1718641720.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162839.00,A,3648.13782,N,12147.23035,W,0.175,63.24,170624,,,A*4D
2024-06-17T16:28:40.573Z,1718641720.573 [NAL9602](INFO): GPS fix at 20240617T162839: (36.802297, -121.787172)
2024-06-17T16:28:42.992Z,1718641722.992 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162842.00,A,3648.13769,N,12147.23038,W,0.136,63.24,170624,,,A*4E
2024-06-17T16:28:42.995Z,1718641722.995 [NAL9602](INFO): GPS fix at 20240617T162842: (36.802295, -121.787173)
2024-06-17T16:28:45.087Z,1718641725.087 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-17T16:28:45.564Z,1718641725.564 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-17T16:28:45.825Z,1718641725.825 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162845.00,A,3648.13754,N,12147.23047,W,0.078,63.24,170624,,,A*44
2024-06-17T16:28:45.835Z,1718641725.835 [NAL9602](INFO): GPS fix at 20240617T162845: (36.802292, -121.787175)
2024-06-17T16:28:49.074Z,1718641729.074 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162848.00,A,3648.13736,N,12147.23046,W,0.156,63.24,170624,,,A*41
2024-06-17T16:28:49.076Z,1718641729.076 [NAL9602](INFO): GPS fix at 20240617T162848: (36.802289, -121.787174)
2024-06-17T16:28:51.927Z,1718641731.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162851.00,A,3648.13722,N,12147.23052,W,0.117,63.24,170624,,,A*4C
2024-06-17T16:28:51.930Z,1718641731.930 [NAL9602](INFO): GPS fix at 20240617T162851: (36.802287, -121.787175)
2024-06-17T16:28:54.752Z,1718641734.752 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162854.00,A,3648.13703,N,12147.23064,W,0.097,63.24,170624,,,A*46
2024-06-17T16:28:54.754Z,1718641734.754 [NAL9602](INFO): GPS fix at 20240617T162854: (36.802284, -121.787177)
2024-06-17T16:28:56.471Z,1718641736.471 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-17T16:28:56.472Z,1718641736.472 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-06-17T16:28:57.980Z,1718641737.980 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162857.00,A,3648.13687,N,12147.23064,W,0.117,63.24,170624,,,A*41
2024-06-17T16:28:57.983Z,1718641737.983 [NAL9602](INFO): GPS fix at 20240617T162857: (36.802281, -121.787177)
2024-06-17T16:29:00.810Z,1718641740.810 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162900.00,A,3648.13674,N,12147.23055,W,0.019,63.24,170624,,,A*43
2024-06-17T16:29:00.812Z,1718641740.812 [NAL9602](INFO): GPS fix at 20240617T162900: (36.802279, -121.787176)
2024-06-17T16:29:04.045Z,1718641744.045 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162903.00,A,3648.13664,N,12147.23043,W,0.058,63.24,170624,,,A*43
2024-06-17T16:29:04.055Z,1718641744.055 [NAL9602](INFO): GPS fix at 20240617T162903: (36.802277, -121.787174)
2024-06-17T16:29:06.877Z,1718641746.877 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162906.00,A,3648.13651,N,12147.23040,W,0.292,63.24,170624,,,A*47
2024-06-17T16:29:06.879Z,1718641746.879 [NAL9602](INFO): GPS fix at 20240617T162906: (36.802275, -121.787173)
2024-06-17T16:29:08.923Z,1718641748.923 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.802277 Longitude: -121.787170
2024-06-17T16:29:09.312Z,1718641749.312 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.690001
2024-06-17T16:29:09.312Z,1718641749.312 [IBIT](IMPORTANT): batteryCapacityThreshold: 20.000000 Ah
2024-06-17T16:29:09.312Z,1718641749.312 [IBIT](IMPORTANT): batteryVoltageThreshold: 12.000000 V
2024-06-17T16:29:09.716Z,1718641749.716 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-06-17T16:29:09.716Z,1718641749.716 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-06-17T16:29:09.717Z,1718641749.717 [IBIT](IMPORTANT): Pressure:10.348464 PSI
2024-06-17T16:29:09.717Z,1718641749.717 [IBIT](IMPORTANT): Humidity:14.576975 %
2024-06-17T16:29:10.119Z,1718641750.119 [IBIT](IMPORTANT): Vehicle Pitch:-1.146678 degrees
2024-06-17T16:29:10.119Z,1718641750.119 [IBIT](IMPORTANT): Vehicle Roll:-0.661478 degrees
2024-06-17T16:29:10.120Z,1718641750.120 [IBIT](IMPORTANT): Vehicle Heading:31.449835 degrees
2024-06-17T16:29:10.531Z,1718641750.531 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-06-17T16:29:10.531Z,1718641750.531 [IBIT](IMPORTANT): buoyancyNeutral: 255.575195 cc
2024-06-17T16:29:10.531Z,1718641750.531 [IBIT](IMPORTANT): massDefault: 0.446254 cm
2024-06-17T16:29:10.531Z,1718641750.531 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-06-17T16:29:10.532Z,1718641750.532 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-06-17T16:29:10.532Z,1718641750.532 [IBIT](IMPORTANT): IBIT PASSED
2024-06-17T16:29:10.926Z,1718641750.926 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T16:29:10.926Z,1718641750.926 [Default:CheckIn:C.Wait] Stopped
2024-06-17T16:29:10.926Z,1718641750.926 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T16:29:10.926Z,1718641750.926 [Default:CheckIn:D] Running Loop=1
2024-06-17T16:29:11.321Z,1718641751.321 [Default:CheckIn:D] Stopped
2024-06-17T16:29:11.321Z,1718641751.321 [Default:CheckIn:E] Running Loop=1
2024-06-17T16:29:11.732Z,1718641751.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.022270 min
2024-06-17T16:29:11.732Z,1718641751.732 [Default:CheckIn:E] Stopped
2024-06-17T16:29:11.732Z,1718641751.732 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T16:29:11.732Z,1718641751.732 [Default:CheckIn] Stopped
2024-06-17T16:29:11.732Z,1718641751.732 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T16:29:11.732Z,1718641751.732 [Default:CheckIn](INFO): Running loop #5
2024-06-17T16:29:11.732Z,1718641751.732 [Default:CheckIn] Running Loop=5
2024-06-17T16:29:11.732Z,1718641751.732 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T16:29:11.733Z,1718641751.733 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T16:29:13.736Z,1718641753.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162913.00,A,3648.13656,N,12147.23027,W,0.097,63.24,170624,,,A*42
2024-06-17T16:29:13.739Z,1718641753.739 [NAL9602](INFO): GPS fix at 20240617T162913: (36.802276, -121.787171)
2024-06-17T16:29:13.767Z,1718641753.767 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T16:29:13.767Z,1718641753.767 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T16:29:21.386Z,1718641761.386 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240617T154550/Courier0016.lzma
2024-06-17T16:29:22.388Z,1718641762.388 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0016.lzma.bak
2024-06-17T16:29:22.388Z,1718641762.388 [DataOverHttps](INFO): SBD MOMSN=21251708
2024-06-17T16:29:38.304Z,1718641778.304 [DataOverHttps](INFO): Sending 786 bytes from file Logs/20240617T154550/Express0017.lzma
2024-06-17T16:29:39.304Z,1718641779.304 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0017.lzma.bak
2024-06-17T16:29:39.304Z,1718641779.304 [DataOverHttps](INFO): SBD MOMSN=21251711
2024-06-17T16:29:46.460Z,1718641786.460 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T16:29:47.640Z,1718641787.640 [DataOverHttps](IMPORTANT): SBD MTMSN=20240617T162946
2024-06-17T16:29:55.090Z,1718641795.090 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240617T154550/Express0020.lzma
2024-06-17T16:29:55.092Z,1718641795.092 [DataOverHttps](INFO): Received command: failc
2024-06-17T16:29:55.099Z,1718641795.099 [CommandExec](IMPORTANT): got command failComponent
2024-06-17T16:29:55.099Z,1718641795.099 [CommandExec](IMPORTANT): Failed components:
2024-06-17T16:29:55.100Z,1718641795.100 [CommandExec](IMPORTANT): No failed Components.
2024-06-17T16:29:56.336Z,1718641796.336 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0020.lzma.bak
2024-06-17T16:29:56.336Z,1718641796.336 [DataOverHttps](INFO): SBD MOMSN=21251740
2024-06-17T16:30:11.926Z,1718641811.926 [DataOverHttps](INFO): Sending 37 bytes from file Logs/20240617T154550/Courier0022.lzma
2024-06-17T16:30:12.928Z,1718641812.928 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0022.lzma.bak
2024-06-17T16:30:12.928Z,1718641812.928 [DataOverHttps](INFO): SBD MOMSN=21251743
2024-06-17T16:30:32.030Z,1718641832.030 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240617T154550/Express0023.lzma
2024-06-17T16:30:33.032Z,1718641833.032 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0023.lzma.bak
2024-06-17T16:30:33.032Z,1718641833.032 [DataOverHttps](INFO): SBD MOMSN=21251745
2024-06-17T16:30:34.186Z,1718641834.186 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T16:30:34.186Z,1718641834.186 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T16:30:34.186Z,1718641834.186 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T16:35:34.766Z,1718642134.766 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T16:35:34.766Z,1718642134.766 [Default:CheckIn:C.Wait] Stopped
2024-06-17T16:35:34.766Z,1718642134.766 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T16:35:34.766Z,1718642134.766 [Default:CheckIn:D] Running Loop=1
2024-06-17T16:35:35.176Z,1718642135.176 [Default:CheckIn:D] Stopped
2024-06-17T16:35:35.176Z,1718642135.176 [Default:CheckIn:E] Running Loop=1
2024-06-17T16:35:35.592Z,1718642135.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.419840 min
2024-06-17T16:35:35.592Z,1718642135.592 [Default:CheckIn:E] Stopped
2024-06-17T16:35:35.593Z,1718642135.593 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T16:35:35.593Z,1718642135.593 [Default:CheckIn] Stopped
2024-06-17T16:35:35.593Z,1718642135.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T16:35:35.593Z,1718642135.593 [Default:CheckIn](INFO): Running loop #6
2024-06-17T16:35:35.593Z,1718642135.593 [Default:CheckIn] Running Loop=6
2024-06-17T16:35:35.593Z,1718642135.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T16:35:35.593Z,1718642135.593 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T16:35:37.581Z,1718642137.581 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163536.00,A,3648.13794,N,12147.23102,W,0.058,63.24,170624,,,A*42
2024-06-17T16:35:37.583Z,1718642137.583 [NAL9602](INFO): GPS fix at 20240617T163536: (36.802299, -121.787184)
2024-06-17T16:35:37.594Z,1718642137.594 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T16:35:37.594Z,1718642137.594 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T16:35:44.950Z,1718642144.950 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240617T154550/Courier0025.lzma
2024-06-17T16:35:45.952Z,1718642145.952 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0025.lzma.bak
2024-06-17T16:35:45.953Z,1718642145.953 [DataOverHttps](INFO): SBD MOMSN=21251758
2024-06-17T16:35:48.089Z,1718642148.089 [NAL9602](INFO): SBD MO Status=0, MOMSN=52774, MT Status=0, MTMSN=0
2024-06-17T16:35:48.089Z,1718642148.089 [NAL9602](INFO): No messages in MT queue
2024-06-17T16:36:01.946Z,1718642161.946 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240617T154550/Express0026.lzma
2024-06-17T16:36:02.948Z,1718642162.948 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0026.lzma.bak
2024-06-17T16:36:02.948Z,1718642162.948 [DataOverHttps](INFO): SBD MOMSN=21251761
2024-06-17T16:36:04.258Z,1718642164.258 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T16:36:04.258Z,1718642164.258 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T16:36:04.258Z,1718642164.258 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T16:36:18.787Z,1718642178.787 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T16:41:04.839Z,1718642464.839 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T16:41:04.839Z,1718642464.839 [Default:CheckIn:C.Wait] Stopped
2024-06-17T16:41:04.840Z,1718642464.840 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T16:41:04.840Z,1718642464.840 [Default:CheckIn:D] Running Loop=1
2024-06-17T16:41:05.237Z,1718642465.237 [Default:CheckIn:D] Stopped
2024-06-17T16:41:05.237Z,1718642465.237 [Default:CheckIn:E] Running Loop=1
2024-06-17T16:41:05.642Z,1718642465.642 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.920866 min
2024-06-17T16:41:05.642Z,1718642465.642 [Default:CheckIn:E] Stopped
2024-06-17T16:41:05.642Z,1718642465.642 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T16:41:05.642Z,1718642465.642 [Default:CheckIn] Stopped
2024-06-17T16:41:05.642Z,1718642465.642 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T16:41:05.642Z,1718642465.642 [Default:CheckIn](INFO): Running loop #7
2024-06-17T16:41:05.642Z,1718642465.642 [Default:CheckIn] Running Loop=7
2024-06-17T16:41:05.642Z,1718642465.642 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T16:41:05.643Z,1718642465.643 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T16:41:07.649Z,1718642467.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164106.00,A,3648.13924,N,12147.22994,W,0.039,63.24,170624,,,A*46
2024-06-17T16:41:07.652Z,1718642467.652 [NAL9602](INFO): GPS fix at 20240617T164106: (36.802321, -121.787166)
2024-06-17T16:41:07.662Z,1718642467.662 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T16:41:07.662Z,1718642467.662 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T16:41:15.494Z,1718642475.494 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240617T154550/Courier0028.lzma
2024-06-17T16:41:16.496Z,1718642476.496 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0028.lzma.bak
2024-06-17T16:41:16.496Z,1718642476.496 [DataOverHttps](INFO): SBD MOMSN=21251773
2024-06-17T16:41:22.606Z,1718642482.606 [NAL9602](INFO): SBD MO Status=0, MOMSN=52775, MT Status=0, MTMSN=0
2024-06-17T16:41:22.606Z,1718642482.606 [NAL9602](INFO): No messages in MT queue
2024-06-17T16:41:32.370Z,1718642492.370 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240617T154550/Express0029.lzma
2024-06-17T16:41:33.372Z,1718642493.372 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0029.lzma.bak
2024-06-17T16:41:33.373Z,1718642493.373 [DataOverHttps](INFO): SBD MOMSN=21251777
2024-06-17T16:41:34.730Z,1718642494.730 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T16:41:34.730Z,1718642494.730 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T16:41:34.730Z,1718642494.730 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T16:41:53.303Z,1718642513.303 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T16:46:35.307Z,1718642795.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T16:46:35.307Z,1718642795.307 [Default:CheckIn:C.Wait] Stopped
2024-06-17T16:46:35.307Z,1718642795.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T16:46:35.307Z,1718642795.307 [Default:CheckIn:D] Running Loop=1
2024-06-17T16:46:35.717Z,1718642795.717 [Default:CheckIn:D] Stopped
2024-06-17T16:46:35.717Z,1718642795.717 [Default:CheckIn:E] Running Loop=1
2024-06-17T16:46:36.114Z,1718642796.114 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.428866 min
2024-06-17T16:46:36.114Z,1718642796.114 [Default:CheckIn:E] Stopped
2024-06-17T16:46:36.114Z,1718642796.114 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T16:46:36.114Z,1718642796.114 [Default:CheckIn] Stopped
2024-06-17T16:46:36.114Z,1718642796.114 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T16:46:36.114Z,1718642796.114 [Default:CheckIn](INFO): Running loop #8
2024-06-17T16:46:36.115Z,1718642796.115 [Default:CheckIn] Running Loop=8
2024-06-17T16:46:36.115Z,1718642796.115 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T16:46:36.115Z,1718642796.115 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T16:46:38.125Z,1718642798.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164637.00,A,3648.13998,N,12147.23003,W,0.039,63.24,170624,,,A*42
2024-06-17T16:46:38.128Z,1718642798.128 [NAL9602](INFO): GPS fix at 20240617T164637: (36.802333, -121.787167)
2024-06-17T16:46:38.139Z,1718642798.139 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T16:46:38.139Z,1718642798.139 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T16:46:46.074Z,1718642806.074 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240617T154550/Courier0031.lzma
2024-06-17T16:46:47.076Z,1718642807.076 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0031.lzma.bak
2024-06-17T16:46:47.076Z,1718642807.076 [DataOverHttps](INFO): SBD MOMSN=21251787
2024-06-17T16:46:50.273Z,1718642810.273 [NAL9602](INFO): SBD MO Status=0, MOMSN=52776, MT Status=0, MTMSN=0
2024-06-17T16:46:50.273Z,1718642810.273 [NAL9602](INFO): No messages in MT queue
2024-06-17T16:47:05.716Z,1718642825.716 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240617T154550/Express0032.lzma
2024-06-17T16:47:06.716Z,1718642826.716 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0032.lzma.bak
2024-06-17T16:47:06.716Z,1718642826.716 [DataOverHttps](INFO): SBD MOMSN=21251790
2024-06-17T16:47:08.116Z,1718642828.116 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T16:47:08.116Z,1718642828.116 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T16:47:08.116Z,1718642828.116 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T16:47:21.031Z,1718642841.031 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T16:51:29.081Z,1718643089.081 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T16:51:29.083Z,1718643089.083 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-06-17T16:51:41.606Z,1718643101.606 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T16:51:54.937Z,1718643114.937 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T16:52:08.271Z,1718643128.271 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T16:52:08.710Z,1718643128.710 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T16:52:08.710Z,1718643128.710 [Default:CheckIn:C.Wait] Stopped
2024-06-17T16:52:08.710Z,1718643128.710 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T16:52:08.710Z,1718643128.710 [Default:CheckIn:D] Running Loop=1
2024-06-17T16:52:09.117Z,1718643129.117 [Default:CheckIn:D] Stopped
2024-06-17T16:52:09.117Z,1718643129.117 [Default:CheckIn:E] Running Loop=1
2024-06-17T16:52:09.498Z,1718643129.498 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.985527 min
2024-06-17T16:52:09.499Z,1718643129.499 [Default:CheckIn:E] Stopped
2024-06-17T16:52:09.499Z,1718643129.499 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T16:52:09.499Z,1718643129.499 [Default:CheckIn] Stopped
2024-06-17T16:52:09.499Z,1718643129.499 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T16:52:09.499Z,1718643129.499 [Default:CheckIn](INFO): Running loop #9
2024-06-17T16:52:09.499Z,1718643129.499 [Default:CheckIn] Running Loop=9
2024-06-17T16:52:09.499Z,1718643129.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T16:52:09.499Z,1718643129.499 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T16:52:11.525Z,1718643131.525 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165210.00,A,3648.13985,N,12147.22940,W,0.039,63.24,170624,,,A*41
2024-06-17T16:52:11.527Z,1718643131.527 [NAL9602](INFO): GPS fix at 20240617T165210: (36.802331, -121.787157)
2024-06-17T16:52:11.538Z,1718643131.538 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T16:52:11.538Z,1718643131.538 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T16:52:19.886Z,1718643139.886 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240617T154550/Courier0034.lzma
2024-06-17T16:52:20.888Z,1718643140.888 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0034.lzma.bak
2024-06-17T16:52:20.889Z,1718643140.889 [DataOverHttps](INFO): SBD MOMSN=21251800
2024-06-17T16:52:21.198Z,1718643141.198 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T16:52:34.149Z,1718643154.149 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T16:52:38.617Z,1718643158.617 [NAL9602](INFO): SBD MO Status=0, MOMSN=52777, MT Status=0, MTMSN=0
2024-06-17T16:52:38.617Z,1718643158.617 [NAL9602](INFO): No messages in MT queue
2024-06-17T16:52:39.394Z,1718643159.394 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240617T154550/Express0035.lzma
2024-06-17T16:52:40.396Z,1718643160.396 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0035.lzma.bak
2024-06-17T16:52:40.397Z,1718643160.397 [DataOverHttps](INFO): SBD MOMSN=21251803
2024-06-17T16:52:41.446Z,1718643161.446 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T16:52:41.446Z,1718643161.446 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T16:52:41.446Z,1718643161.446 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T16:52:46.675Z,1718643166.675 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T16:53:09.299Z,1718643189.299 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T16:54:54.819Z,1718643294.819 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-06-17T16:55:21.899Z,1718643321.899 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-06-17T16:55:21.901Z,1718643321.901 [BPC1](INFO): Received data from all battery sticks.
2024-06-17T16:56:07.939Z,1718643367.939 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2024-06-17T16:57:42.197Z,1718643462.197 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T16:57:42.197Z,1718643462.197 [Default:CheckIn:C.Wait] Stopped
2024-06-17T16:57:42.197Z,1718643462.197 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T16:57:42.197Z,1718643462.197 [Default:CheckIn:D] Running Loop=1
2024-06-17T16:57:42.597Z,1718643462.597 [Default:CheckIn:D] Stopped
2024-06-17T16:57:42.597Z,1718643462.597 [Default:CheckIn:E] Running Loop=1
2024-06-17T16:57:43.008Z,1718643463.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.543538 min
2024-06-17T16:57:43.008Z,1718643463.008 [Default:CheckIn:E] Stopped
2024-06-17T16:57:43.009Z,1718643463.009 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T16:57:43.009Z,1718643463.009 [Default:CheckIn] Stopped
2024-06-17T16:57:43.009Z,1718643463.009 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T16:57:43.009Z,1718643463.009 [Default:CheckIn](INFO): Running loop #10
2024-06-17T16:57:43.009Z,1718643463.009 [Default:CheckIn] Running Loop=10
2024-06-17T16:57:43.009Z,1718643463.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T16:57:43.009Z,1718643463.009 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T16:57:45.017Z,1718643465.017 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165744.00,A,3648.14032,N,12147.22890,W,0.019,63.24,170624,,,A*49
2024-06-17T16:57:45.019Z,1718643465.019 [NAL9602](INFO): GPS fix at 20240617T165744: (36.802339, -121.787148)
2024-06-17T16:57:45.051Z,1718643465.051 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T16:57:45.051Z,1718643465.051 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T16:57:53.022Z,1718643473.022 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240617T154550/Courier0037.lzma
2024-06-17T16:57:54.024Z,1718643474.024 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0037.lzma.bak
2024-06-17T16:57:54.024Z,1718643474.024 [DataOverHttps](INFO): SBD MOMSN=21251813
2024-06-17T16:58:09.986Z,1718643489.986 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240617T154550/Express0038.lzma
2024-06-17T16:58:10.988Z,1718643490.988 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0038.lzma.bak
2024-06-17T16:58:10.989Z,1718643490.989 [DataOverHttps](INFO): SBD MOMSN=21251816
2024-06-17T16:58:12.094Z,1718643492.094 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T16:58:12.094Z,1718643492.094 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T16:58:12.094Z,1718643492.094 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:00:20.225Z,1718643620.225 [NAL9602](INFO): SBD MO Status=2, MOMSN=52778, MT Status=2, MTMSN=0
2024-06-17T17:00:20.225Z,1718643620.225 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-17T17:01:05.941Z,1718643665.941 [NAL9602](INFO): SBD MO Status=2, MOMSN=52778, MT Status=2, MTMSN=0
2024-06-17T17:01:05.941Z,1718643665.941 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-17T17:01:21.293Z,1718643681.293 [NAL9602](INFO): SBD MO Status=0, MOMSN=52778, MT Status=0, MTMSN=0
2024-06-17T17:01:21.293Z,1718643681.293 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:01:51.995Z,1718643711.995 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:03:12.505Z,1718643792.505 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:03:12.505Z,1718643792.505 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:03:12.506Z,1718643792.506 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:03:12.506Z,1718643792.506 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:03:12.917Z,1718643792.917 [Default:CheckIn:D] Stopped
2024-06-17T17:03:12.917Z,1718643792.917 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:03:13.332Z,1718643793.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.048861 min
2024-06-17T17:03:13.332Z,1718643793.332 [Default:CheckIn:E] Stopped
2024-06-17T17:03:13.332Z,1718643793.332 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:03:13.332Z,1718643793.332 [Default:CheckIn] Stopped
2024-06-17T17:03:13.333Z,1718643793.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:03:13.333Z,1718643793.333 [Default:CheckIn](INFO): Running loop #11
2024-06-17T17:03:13.333Z,1718643793.333 [Default:CheckIn] Running Loop=11
2024-06-17T17:03:13.333Z,1718643793.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:03:13.333Z,1718643793.333 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:03:15.324Z,1718643795.324 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170314.00,A,3648.14080,N,12147.22866,W,0.000,0.00,170624,,,A*77
2024-06-17T17:03:15.327Z,1718643795.327 [NAL9602](INFO): GPS fix at 20240617T170314: (36.802347, -121.787144)
2024-06-17T17:03:15.337Z,1718643795.337 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:03:15.337Z,1718643795.337 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:03:22.958Z,1718643802.958 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240617T154550/Courier0040.lzma
2024-06-17T17:03:23.960Z,1718643803.960 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0040.lzma.bak
2024-06-17T17:03:23.960Z,1718643803.960 [DataOverHttps](INFO): SBD MOMSN=21251826
2024-06-17T17:03:25.020Z,1718643805.020 [NAL9602](INFO): SBD MO Status=0, MOMSN=52779, MT Status=0, MTMSN=0
2024-06-17T17:03:25.020Z,1718643805.020 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:03:39.826Z,1718643819.826 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240617T154550/Express0041.lzma
2024-06-17T17:03:40.828Z,1718643820.828 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0041.lzma.bak
2024-06-17T17:03:40.829Z,1718643820.829 [DataOverHttps](INFO): SBD MOMSN=21251830
2024-06-17T17:03:42.001Z,1718643822.001 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:03:42.001Z,1718643822.001 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:03:42.001Z,1718643822.001 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:03:55.727Z,1718643835.727 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:08:42.746Z,1718644122.746 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:08:42.746Z,1718644122.746 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:08:42.750Z,1718644122.750 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:08:42.750Z,1718644122.750 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:08:43.155Z,1718644123.155 [Default:CheckIn:D] Stopped
2024-06-17T17:08:43.156Z,1718644123.156 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:08:43.537Z,1718644123.537 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.552840 min
2024-06-17T17:08:43.538Z,1718644123.538 [Default:CheckIn:E] Stopped
2024-06-17T17:08:43.538Z,1718644123.538 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:08:43.538Z,1718644123.538 [Default:CheckIn] Stopped
2024-06-17T17:08:43.538Z,1718644123.538 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:08:43.538Z,1718644123.538 [Default:CheckIn](INFO): Running loop #12
2024-06-17T17:08:43.538Z,1718644123.538 [Default:CheckIn] Running Loop=12
2024-06-17T17:08:43.538Z,1718644123.538 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:08:43.539Z,1718644123.539 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:08:45.554Z,1718644125.554 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170844.00,A,3648.14058,N,12147.22838,W,0.039,0.00,170624,,,A*7D
2024-06-17T17:08:45.556Z,1718644125.556 [NAL9602](INFO): GPS fix at 20240617T170844: (36.802343, -121.787140)
2024-06-17T17:08:45.567Z,1718644125.567 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:08:45.567Z,1718644125.567 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:08:56.058Z,1718644136.058 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240617T154550/Courier0043.lzma
2024-06-17T17:08:57.061Z,1718644137.061 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0043.lzma.bak
2024-06-17T17:08:57.061Z,1718644137.061 [DataOverHttps](INFO): SBD MOMSN=21251839
2024-06-17T17:09:13.062Z,1718644153.062 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240617T154550/Express0044.lzma
2024-06-17T17:09:14.064Z,1718644154.064 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0044.lzma.bak
2024-06-17T17:09:14.065Z,1718644154.065 [DataOverHttps](INFO): SBD MOMSN=21251842
2024-06-17T17:09:15.111Z,1718644155.111 [NAL9602](INFO): SBD MO Status=0, MOMSN=52780, MT Status=0, MTMSN=0
2024-06-17T17:09:15.111Z,1718644155.111 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:09:15.121Z,1718644155.121 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:09:15.121Z,1718644155.121 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:09:15.122Z,1718644155.122 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:09:45.807Z,1718644185.807 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:14:15.518Z,1718644455.518 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:14:15.518Z,1718644455.518 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:14:15.518Z,1718644455.518 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:14:15.518Z,1718644455.518 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:14:15.963Z,1718644455.963 [Default:CheckIn:D] Stopped
2024-06-17T17:14:15.963Z,1718644455.963 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:14:16.334Z,1718644456.334 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.099634 min
2024-06-17T17:14:16.334Z,1718644456.334 [Default:CheckIn:E] Stopped
2024-06-17T17:14:16.334Z,1718644456.334 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:14:16.335Z,1718644456.335 [Default:CheckIn] Stopped
2024-06-17T17:14:16.335Z,1718644456.335 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:14:16.335Z,1718644456.335 [Default:CheckIn](INFO): Running loop #13
2024-06-17T17:14:16.335Z,1718644456.335 [Default:CheckIn] Running Loop=13
2024-06-17T17:14:16.335Z,1718644456.335 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:14:16.335Z,1718644456.335 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:14:18.333Z,1718644458.333 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171417.00,A,3648.13916,N,12147.22811,W,0.058,0.00,170624,,,A*7E
2024-06-17T17:14:18.353Z,1718644458.353 [NAL9602](INFO): GPS fix at 20240617T171417: (36.802319, -121.787135)
2024-06-17T17:14:18.372Z,1718644458.372 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:14:18.372Z,1718644458.372 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:14:28.522Z,1718644468.522 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240617T154550/Courier0046.lzma
2024-06-17T17:14:29.526Z,1718644469.526 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0046.lzma.bak
2024-06-17T17:14:29.526Z,1718644469.526 [DataOverHttps](INFO): SBD MOMSN=21251859
2024-06-17T17:14:36.984Z,1718644476.984 [NAL9602](INFO): SBD MO Status=0, MOMSN=52781, MT Status=0, MTMSN=0
2024-06-17T17:14:36.984Z,1718644476.984 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:14:45.362Z,1718644485.362 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240617T154550/Express0047.lzma
2024-06-17T17:14:46.364Z,1718644486.364 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0047.lzma.bak
2024-06-17T17:14:46.365Z,1718644486.365 [DataOverHttps](INFO): SBD MOMSN=21251862
2024-06-17T17:14:47.510Z,1718644487.510 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:14:47.511Z,1718644487.511 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:14:47.511Z,1718644487.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:15:07.743Z,1718644507.743 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:19:48.298Z,1718644788.298 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:19:48.298Z,1718644788.298 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:19:48.298Z,1718644788.298 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:19:48.298Z,1718644788.298 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:19:48.697Z,1718644788.697 [Default:CheckIn:D] Stopped
2024-06-17T17:19:48.697Z,1718644788.697 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:19:49.106Z,1718644789.106 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.645190 min
2024-06-17T17:19:49.107Z,1718644789.107 [Default:CheckIn:E] Stopped
2024-06-17T17:19:49.107Z,1718644789.107 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:19:49.107Z,1718644789.107 [Default:CheckIn] Stopped
2024-06-17T17:19:49.107Z,1718644789.107 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:19:49.107Z,1718644789.107 [Default:CheckIn](INFO): Running loop #14
2024-06-17T17:19:49.107Z,1718644789.107 [Default:CheckIn] Running Loop=14
2024-06-17T17:19:49.107Z,1718644789.107 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:19:49.107Z,1718644789.107 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:19:51.122Z,1718644791.122 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171950.00,A,3648.13811,N,12147.22509,W,0.039,0.00,170624,,,A*75
2024-06-17T17:19:51.124Z,1718644791.124 [NAL9602](INFO): GPS fix at 20240617T171950: (36.802302, -121.787085)
2024-06-17T17:19:51.135Z,1718644791.135 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:19:51.135Z,1718644791.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:19:58.562Z,1718644798.562 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240617T154550/Courier0049.lzma
2024-06-17T17:19:59.565Z,1718644799.565 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0049.lzma.bak
2024-06-17T17:19:59.565Z,1718644799.565 [DataOverHttps](INFO): SBD MOMSN=21251872
2024-06-17T17:20:10.509Z,1718644810.509 [NAL9602](INFO): SBD MO Status=2, MOMSN=52782, MT Status=2, MTMSN=0
2024-06-17T17:20:10.509Z,1718644810.509 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-17T17:20:15.463Z,1718644815.463 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240617T154550/Express0050.lzma
2024-06-17T17:20:16.464Z,1718644816.464 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0050.lzma.bak
2024-06-17T17:20:16.465Z,1718644816.465 [DataOverHttps](INFO): SBD MOMSN=21251875
2024-06-17T17:20:17.805Z,1718644817.805 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:20:17.805Z,1718644817.805 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:20:17.805Z,1718644817.805 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:20:32.385Z,1718644832.385 [NAL9602](INFO): SBD MO Status=0, MOMSN=52782, MT Status=0, MTMSN=0
2024-06-17T17:20:32.385Z,1718644832.385 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:21:03.148Z,1718644863.148 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:25:18.242Z,1718645118.242 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:25:18.242Z,1718645118.242 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:25:18.242Z,1718645118.242 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:25:18.242Z,1718645118.242 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:25:18.637Z,1718645118.637 [Default:CheckIn:D] Stopped
2024-06-17T17:25:18.637Z,1718645118.637 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:25:19.043Z,1718645119.043 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.144198 min
2024-06-17T17:25:19.043Z,1718645119.043 [Default:CheckIn:E] Stopped
2024-06-17T17:25:19.043Z,1718645119.043 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:25:19.043Z,1718645119.043 [Default:CheckIn] Stopped
2024-06-17T17:25:19.043Z,1718645119.043 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:25:19.044Z,1718645119.044 [Default:CheckIn](INFO): Running loop #15
2024-06-17T17:25:19.044Z,1718645119.044 [Default:CheckIn] Running Loop=15
2024-06-17T17:25:19.044Z,1718645119.044 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:25:19.044Z,1718645119.044 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:25:21.050Z,1718645121.050 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172520.00,A,3648.14038,N,12147.22791,W,0.019,0.00,170624,,,A*78
2024-06-17T17:25:21.052Z,1718645121.052 [NAL9602](INFO): GPS fix at 20240617T172520: (36.802340, -121.787132)
2024-06-17T17:25:21.063Z,1718645121.063 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:25:21.064Z,1718645121.064 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:25:28.450Z,1718645128.450 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240617T154550/Courier0052.lzma
2024-06-17T17:25:29.452Z,1718645129.452 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0052.lzma.bak
2024-06-17T17:25:29.453Z,1718645129.453 [DataOverHttps](INFO): SBD MOMSN=21251885
2024-06-17T17:25:45.314Z,1718645145.314 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240617T154550/Express0053.lzma
2024-06-17T17:25:46.317Z,1718645146.317 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0053.lzma.bak
2024-06-17T17:25:46.317Z,1718645146.317 [DataOverHttps](INFO): SBD MOMSN=21251888
2024-06-17T17:25:47.722Z,1718645147.722 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:25:47.722Z,1718645147.722 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:25:47.722Z,1718645147.722 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:26:05.487Z,1718645165.487 [NAL9602](INFO): SBD MO Status=2, MOMSN=52783, MT Status=2, MTMSN=0
2024-06-17T17:26:05.487Z,1718645165.487 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-17T17:26:24.129Z,1718645184.129 [NAL9602](INFO): SBD MO Status=0, MOMSN=52783, MT Status=0, MTMSN=0
2024-06-17T17:26:24.129Z,1718645184.129 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:26:54.881Z,1718645214.881 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:30:48.511Z,1718645448.511 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:30:48.511Z,1718645448.511 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:30:48.511Z,1718645448.511 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:30:48.511Z,1718645448.511 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:30:48.962Z,1718645448.962 [Default:CheckIn:D] Stopped
2024-06-17T17:30:48.962Z,1718645448.962 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:30:49.323Z,1718645449.323 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.649552 min
2024-06-17T17:30:49.323Z,1718645449.323 [Default:CheckIn:E] Stopped
2024-06-17T17:30:49.323Z,1718645449.323 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:30:49.323Z,1718645449.323 [Default:CheckIn] Stopped
2024-06-17T17:30:49.323Z,1718645449.323 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:30:49.323Z,1718645449.323 [Default:CheckIn](INFO): Running loop #16
2024-06-17T17:30:49.323Z,1718645449.323 [Default:CheckIn] Running Loop=16
2024-06-17T17:30:49.323Z,1718645449.323 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:30:49.324Z,1718645449.324 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:30:51.322Z,1718645451.322 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173050.00,A,3648.15054,N,12147.22133,W,0.097,28.76,170624,,,A*43
2024-06-17T17:30:51.324Z,1718645451.324 [NAL9602](INFO): GPS fix at 20240617T173050: (36.802509, -121.787022)
2024-06-17T17:30:51.368Z,1718645451.368 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:30:51.368Z,1718645451.368 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:31:00.338Z,1718645460.338 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240617T154550/Courier0055.lzma
2024-06-17T17:31:01.340Z,1718645461.340 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0055.lzma.bak
2024-06-17T17:31:01.340Z,1718645461.340 [DataOverHttps](INFO): SBD MOMSN=21251898
2024-06-17T17:31:07.897Z,1718645467.897 [NAL9602](INFO): SBD MO Status=0, MOMSN=52784, MT Status=0, MTMSN=0
2024-06-17T17:31:07.897Z,1718645467.897 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:31:17.150Z,1718645477.150 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240617T154550/Express0056.lzma
2024-06-17T17:31:18.152Z,1718645478.152 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0056.lzma.bak
2024-06-17T17:31:18.153Z,1718645478.153 [DataOverHttps](INFO): SBD MOMSN=21251901
2024-06-17T17:31:19.219Z,1718645479.219 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:31:19.219Z,1718645479.219 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:31:19.219Z,1718645479.219 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:31:38.604Z,1718645498.604 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:36:19.895Z,1718645779.895 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:36:19.895Z,1718645779.895 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:36:19.895Z,1718645779.895 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:36:19.895Z,1718645779.895 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:36:20.302Z,1718645780.302 [Default:CheckIn:D] Stopped
2024-06-17T17:36:20.303Z,1718645780.303 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:36:20.709Z,1718645780.709 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.171948 min
2024-06-17T17:36:20.709Z,1718645780.709 [Default:CheckIn:E] Stopped
2024-06-17T17:36:20.709Z,1718645780.709 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:36:20.709Z,1718645780.709 [Default:CheckIn] Stopped
2024-06-17T17:36:20.709Z,1718645780.709 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:36:20.709Z,1718645780.709 [Default:CheckIn](INFO): Running loop #17
2024-06-17T17:36:20.710Z,1718645780.710 [Default:CheckIn] Running Loop=17
2024-06-17T17:36:20.710Z,1718645780.710 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:36:20.710Z,1718645780.710 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:36:22.715Z,1718645782.715 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173622.00,A,3648.15641,N,12147.21546,W,2.022,37.78,170624,,,A*4B
2024-06-17T17:36:22.717Z,1718645782.717 [NAL9602](INFO): GPS fix at 20240617T173622: (36.802607, -121.786924)
2024-06-17T17:36:22.736Z,1718645782.736 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:36:22.736Z,1718645782.736 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:36:30.054Z,1718645790.054 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240617T154550/Courier0058.lzma
2024-06-17T17:36:31.057Z,1718645791.057 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0058.lzma.bak
2024-06-17T17:36:31.057Z,1718645791.057 [DataOverHttps](INFO): SBD MOMSN=21251913
2024-06-17T17:36:40.489Z,1718645800.489 [NAL9602](INFO): SBD MO Status=0, MOMSN=52785, MT Status=0, MTMSN=0
2024-06-17T17:36:40.489Z,1718645800.489 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:36:47.059Z,1718645807.059 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240617T154550/Express0059.lzma
2024-06-17T17:36:48.061Z,1718645808.061 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0059.lzma.bak
2024-06-17T17:36:48.061Z,1718645808.061 [DataOverHttps](INFO): SBD MOMSN=21251916
2024-06-17T17:36:49.394Z,1718645809.394 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:36:49.394Z,1718645809.394 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:36:49.394Z,1718645809.394 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:37:11.250Z,1718645831.250 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:41:50.174Z,1718646110.174 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:41:50.174Z,1718646110.174 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:41:50.174Z,1718646110.174 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:41:50.174Z,1718646110.174 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:41:50.577Z,1718646110.577 [Default:CheckIn:D] Stopped
2024-06-17T17:41:50.577Z,1718646110.577 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:41:50.996Z,1718646110.996 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.676538 min
2024-06-17T17:41:50.996Z,1718646110.996 [Default:CheckIn:E] Stopped
2024-06-17T17:41:50.996Z,1718646110.996 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:41:50.996Z,1718646110.996 [Default:CheckIn] Stopped
2024-06-17T17:41:50.996Z,1718646110.996 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:41:50.996Z,1718646110.996 [Default:CheckIn](INFO): Running loop #18
2024-06-17T17:41:50.996Z,1718646110.996 [Default:CheckIn] Running Loop=18
2024-06-17T17:41:50.997Z,1718646110.997 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:41:50.997Z,1718646110.997 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:41:52.997Z,1718646112.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174152.00,A,3648.40780,N,12147.10927,W,4.860,8.26,170624,,,A*76
2024-06-17T17:41:52.999Z,1718646112.999 [NAL9602](INFO): GPS fix at 20240617T174152: (36.806797, -121.785155)
2024-06-17T17:41:53.010Z,1718646113.010 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:41:53.010Z,1718646113.010 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:42:00.730Z,1718646120.730 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240617T154550/Courier0061.lzma
2024-06-17T17:42:01.732Z,1718646121.732 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0061.lzma.bak
2024-06-17T17:42:01.733Z,1718646121.733 [DataOverHttps](INFO): SBD MOMSN=21251928
2024-06-17T17:42:07.953Z,1718646127.953 [NAL9602](INFO): SBD MO Status=0, MOMSN=52786, MT Status=0, MTMSN=0
2024-06-17T17:42:07.953Z,1718646127.953 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:42:17.654Z,1718646137.654 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240617T154550/Express0062.lzma
2024-06-17T17:42:18.657Z,1718646138.657 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0062.lzma.bak
2024-06-17T17:42:18.657Z,1718646138.657 [DataOverHttps](INFO): SBD MOMSN=21251933
2024-06-17T17:42:19.701Z,1718646139.701 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:42:19.701Z,1718646139.701 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:42:19.701Z,1718646139.701 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:42:38.703Z,1718646158.703 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:47:20.384Z,1718646440.384 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:47:20.384Z,1718646440.384 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:47:20.384Z,1718646440.384 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:47:20.384Z,1718646440.384 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:47:20.795Z,1718646440.795 [Default:CheckIn:D] Stopped
2024-06-17T17:47:20.795Z,1718646440.795 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:47:21.203Z,1718646441.203 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.180168 min
2024-06-17T17:47:21.203Z,1718646441.203 [Default:CheckIn:E] Stopped
2024-06-17T17:47:21.204Z,1718646441.204 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:47:21.204Z,1718646441.204 [Default:CheckIn] Stopped
2024-06-17T17:47:21.204Z,1718646441.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:47:21.204Z,1718646441.204 [Default:CheckIn](INFO): Running loop #19
2024-06-17T17:47:21.204Z,1718646441.204 [Default:CheckIn] Running Loop=19
2024-06-17T17:47:21.204Z,1718646441.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:47:21.204Z,1718646441.204 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:47:23.205Z,1718646443.205 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174722.00,A,3648.33418,N,12147.60070,W,7.717,279.39,170624,,,A*7B
2024-06-17T17:47:23.208Z,1718646443.208 [NAL9602](INFO): GPS fix at 20240617T174722: (36.805570, -121.793345)
2024-06-17T17:47:23.239Z,1718646443.239 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:47:23.239Z,1718646443.239 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:47:30.886Z,1718646450.886 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240617T154550/Courier0064.lzma
2024-06-17T17:47:31.888Z,1718646451.888 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0064.lzma.bak
2024-06-17T17:47:31.889Z,1718646451.889 [DataOverHttps](INFO): SBD MOMSN=21251944
2024-06-17T17:47:47.827Z,1718646467.827 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240617T154550/Express0065.lzma
2024-06-17T17:47:48.829Z,1718646468.829 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0065.lzma.bak
2024-06-17T17:47:48.829Z,1718646468.829 [DataOverHttps](INFO): SBD MOMSN=21251947
2024-06-17T17:47:49.886Z,1718646469.886 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:47:49.886Z,1718646469.886 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:47:49.886Z,1718646469.886 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:48:25.049Z,1718646505.049 [NAL9602](INFO): SBD MO Status=0, MOMSN=52787, MT Status=0, MTMSN=0
2024-06-17T17:48:25.049Z,1718646505.049 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:48:55.767Z,1718646535.767 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:52:50.517Z,1718646770.517 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:52:50.517Z,1718646770.517 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:52:50.517Z,1718646770.517 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:52:50.517Z,1718646770.517 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:52:50.930Z,1718646770.930 [Default:CheckIn:D] Stopped
2024-06-17T17:52:50.930Z,1718646770.930 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:52:51.337Z,1718646771.337 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.682414 min
2024-06-17T17:52:51.338Z,1718646771.338 [Default:CheckIn:E] Stopped
2024-06-17T17:52:51.338Z,1718646771.338 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:52:51.338Z,1718646771.338 [Default:CheckIn] Stopped
2024-06-17T17:52:51.338Z,1718646771.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:52:51.338Z,1718646771.338 [Default:CheckIn](INFO): Running loop #20
2024-06-17T17:52:51.338Z,1718646771.338 [Default:CheckIn] Running Loop=20
2024-06-17T17:52:51.346Z,1718646771.346 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:52:51.347Z,1718646771.347 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:52:53.329Z,1718646773.329 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175252.00,A,3648.47587,N,12148.47762,W,9.797,262.13,170624,,,A*76
2024-06-17T17:52:53.331Z,1718646773.331 [NAL9602](INFO): GPS fix at 20240617T175252: (36.807931, -121.807960)
2024-06-17T17:52:53.350Z,1718646773.350 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:52:53.371Z,1718646773.371 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:53:01.930Z,1718646781.930 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240617T154550/Courier0067.lzma
2024-06-17T17:53:02.932Z,1718646782.932 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0067.lzma.bak
2024-06-17T17:53:02.933Z,1718646782.933 [DataOverHttps](INFO): SBD MOMSN=21251957
2024-06-17T17:53:12.053Z,1718646792.053 [NAL9602](INFO): SBD MO Status=0, MOMSN=52788, MT Status=0, MTMSN=0
2024-06-17T17:53:12.053Z,1718646792.053 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:53:36.859Z,1718646816.859 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240617T154550/Express0068.lzma
2024-06-17T17:53:37.861Z,1718646817.861 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0068.lzma.bak
2024-06-17T17:53:37.861Z,1718646817.861 [DataOverHttps](INFO): SBD MOMSN=21251965
2024-06-17T17:53:39.129Z,1718646819.129 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:53:39.129Z,1718646819.129 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:53:39.130Z,1718646819.130 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:53:42.759Z,1718646822.759 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T17:56:28.799Z,1718646988.799 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T17:56:41.729Z,1718647001.729 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T17:56:54.281Z,1718647014.281 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T17:57:07.211Z,1718647027.211 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T17:57:19.734Z,1718647039.734 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T17:57:32.661Z,1718647052.661 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T17:57:44.781Z,1718647064.781 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-17T17:58:39.760Z,1718647119.760 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T17:58:39.760Z,1718647119.760 [Default:CheckIn:C.Wait] Stopped
2024-06-17T17:58:39.760Z,1718647119.760 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T17:58:39.760Z,1718647119.760 [Default:CheckIn:D] Running Loop=1
2024-06-17T17:58:40.158Z,1718647120.158 [Default:CheckIn:D] Stopped
2024-06-17T17:58:40.158Z,1718647120.158 [Default:CheckIn:E] Running Loop=1
2024-06-17T17:58:40.557Z,1718647120.557 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.502873 min
2024-06-17T17:58:40.558Z,1718647120.558 [Default:CheckIn:E] Stopped
2024-06-17T17:58:40.558Z,1718647120.558 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T17:58:40.558Z,1718647120.558 [Default:CheckIn] Stopped
2024-06-17T17:58:40.558Z,1718647120.558 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T17:58:40.558Z,1718647120.558 [Default:CheckIn](INFO): Running loop #21
2024-06-17T17:58:40.558Z,1718647120.558 [Default:CheckIn] Running Loop=21
2024-06-17T17:58:40.558Z,1718647120.558 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T17:58:40.559Z,1718647120.559 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T17:58:42.632Z,1718647122.632 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175841.00,A,3648.09530,N,12149.49960,W,9.583,258.76,170624,,,A*76
2024-06-17T17:58:42.635Z,1718647122.635 [NAL9602](INFO): GPS fix at 20240617T175841: (36.801588, -121.824993)
2024-06-17T17:58:42.645Z,1718647122.645 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T17:58:42.645Z,1718647122.645 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T17:58:51.311Z,1718647131.311 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240617T154550/Courier0070.lzma
2024-06-17T17:58:52.312Z,1718647132.312 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0070.lzma.bak
2024-06-17T17:58:52.313Z,1718647132.313 [DataOverHttps](INFO): SBD MOMSN=21251974
2024-06-17T17:58:58.745Z,1718647138.745 [NAL9602](INFO): SBD MO Status=0, MOMSN=52789, MT Status=0, MTMSN=0
2024-06-17T17:58:58.745Z,1718647138.745 [NAL9602](INFO): No messages in MT queue
2024-06-17T17:59:13.158Z,1718647153.158 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240617T154550/Express0071.lzma
2024-06-17T17:59:14.160Z,1718647154.160 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0071.lzma.bak
2024-06-17T17:59:14.161Z,1718647154.161 [DataOverHttps](INFO): SBD MOMSN=21251981
2024-06-17T17:59:15.332Z,1718647155.332 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T17:59:15.332Z,1718647155.332 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T17:59:15.332Z,1718647155.332 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T17:59:29.451Z,1718647169.451 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T18:00:25.289Z,1718647225.289 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-06-17T18:00:25.292Z,1718647225.292 [BPC1](INFO): Received data from all battery sticks.
2024-06-17T18:04:16.134Z,1718647456.134 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-17T18:04:16.134Z,1718647456.134 [Default:CheckIn:C.Wait] Stopped
2024-06-17T18:04:16.134Z,1718647456.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-17T18:04:16.134Z,1718647456.134 [Default:CheckIn:D] Running Loop=1
2024-06-17T18:04:16.543Z,1718647456.543 [Default:CheckIn:D] Stopped
2024-06-17T18:04:16.543Z,1718647456.543 [Default:CheckIn:E] Running Loop=1
2024-06-17T18:04:16.938Z,1718647456.938 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 135.109285 min
2024-06-17T18:04:16.938Z,1718647456.938 [Default:CheckIn:E] Stopped
2024-06-17T18:04:16.955Z,1718647456.955 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-17T18:04:16.955Z,1718647456.955 [Default:CheckIn] Stopped
2024-06-17T18:04:16.955Z,1718647456.955 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-17T18:04:16.956Z,1718647456.956 [Default:CheckIn](INFO): Running loop #22
2024-06-17T18:04:16.956Z,1718647456.956 [Default:CheckIn] Running Loop=22
2024-06-17T18:04:16.956Z,1718647456.956 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-17T18:04:16.956Z,1718647456.956 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-17T18:04:18.946Z,1718647458.946 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180418.00,A,3647.89625,N,12150.58711,W,9.622,287.30,170624,,,A*74
2024-06-17T18:04:18.948Z,1718647458.948 [NAL9602](INFO): GPS fix at 20240617T180418: (36.798271, -121.843119)
2024-06-17T18:04:18.995Z,1718647458.995 [Default:CheckIn:Read_GPS] Stopped
2024-06-17T18:04:18.995Z,1718647458.995 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-17T18:04:33.573Z,1718647473.573 [NAL9602](INFO): SBD MO Status=0, MOMSN=52790, MT Status=0, MTMSN=0
2024-06-17T18:04:33.573Z,1718647473.573 [NAL9602](INFO): No messages in MT queue
2024-06-17T18:05:04.275Z,1718647504.275 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-17T18:05:22.079Z,1718647522.079 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-06-17T18:05:53.211Z,1718647553.211 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-06-17T18:06:24.335Z,1718647584.335 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-06-17T18:06:31.678Z,1718647591.678 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20240617T154550/Courier0073.lzma
2024-06-17T18:06:32.680Z,1718647592.680 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Courier0073.lzma.bak
2024-06-17T18:06:32.681Z,1718647592.681 [DataOverHttps](INFO): SBD MOMSN=21251993
2024-06-17T18:06:49.318Z,1718647609.318 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240617T154550/Express0074.lzma
2024-06-17T18:06:50.321Z,1718647610.321 [DataOverHttps](INFO): Moved sent file to Logs/20240617T154550/Express0074.lzma.bak
2024-06-17T18:06:50.321Z,1718647610.321 [DataOverHttps](INFO): SBD MOMSN=21251996
2024-06-17T18:06:51.576Z,1718647611.576 [Default:CheckIn:Read_Iridium] Stopped
2024-06-17T18:06:51.576Z,1718647611.576 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-17T18:06:51.576Z,1718647611.576 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-17T18:11:35.012Z,1718647895.012 [DataOverHttps](IMPORTANT): SBD MTMSN=20240617T181134
2024-06-17T18:11:42.348Z,1718647902.348 [DataOverHttps](INFO): Received command: restart logs