2024-11-11T17:03:54.676Z,1731344634.676 [Supervisor](DEBUG): Initializing supervisor.
2024-11-11T17:03:54.680Z,1731344634.680 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-11-11T17:03:54.680Z,1731344634.680 [SyncHandler](INFO): Protected caller Thread ID is 838
2024-11-11T17:03:54.681Z,1731344634.681 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-11-11T17:03:54.682Z,1731344634.682 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-11-11T17:03:54.682Z,1731344634.682 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 839
2024-11-11T17:03:54.686Z,1731344634.686 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-11-11T17:03:54.703Z,1731344634.703 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-11-11T17:03:54.704Z,1731344634.704 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-11-11T17:03:54.704Z,1731344634.704 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 840
2024-11-11T17:03:54.709Z,1731344634.709 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-11-11T17:03:54.710Z,1731344634.710 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-11-11T17:03:54.710Z,1731344634.710 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 841
2024-11-11T17:03:54.712Z,1731344634.712 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-11-11T17:03:54.713Z,1731344634.713 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-11-11T17:03:54.713Z,1731344634.713 [logger ThreadHandler](INFO): Protected caller Thread ID is 842
2024-11-11T17:03:54.717Z,1731344634.717 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-11-11T17:03:54.717Z,1731344634.717 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-11-11T17:03:54.721Z,1731344634.721 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-11-11T17:03:55.006Z,1731344635.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-11-11T17:03:55.008Z,1731344635.008 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-11-11T17:03:55.102Z,1731344635.102 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-11-11T17:03:55.662Z,1731344635.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-11-11T17:03:55.664Z,1731344635.664 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-11-11T17:03:56.034Z,1731344636.034 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-11-11T17:03:56.036Z,1731344636.036 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-11-11T17:03:56.145Z,1731344636.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-11-11T17:03:56.147Z,1731344636.147 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-11-11T17:03:56.583Z,1731344636.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-11-11T17:03:56.584Z,1731344636.584 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-11-11T17:03:56.816Z,1731344636.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-11-11T17:03:56.818Z,1731344636.818 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-11-11T17:03:57.395Z,1731344637.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-11-11T17:03:57.424Z,1731344637.424 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-11-11T17:03:58.268Z,1731344638.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-11-11T17:03:58.271Z,1731344638.271 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-11-11T17:03:58.394Z,1731344638.394 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-11-11T17:03:59.288Z,1731344639.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-11-11T17:03:59.289Z,1731344639.289 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-11-11T17:03:59.698Z,1731344639.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-11-11T17:03:59.700Z,1731344639.700 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-11-11T17:03:59.912Z,1731344639.912 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-11-11T17:03:59.913Z,1731344639.913 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-11-11T17:04:00.039Z,1731344640.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-11-11T17:04:00.039Z,1731344640.039 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-11-11T17:04:00.285Z,1731344640.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-11-11T17:04:00.287Z,1731344640.287 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-11-11T17:04:00.556Z,1731344640.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-11-11T17:04:00.558Z,1731344640.558 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-11-11T17:04:00.561Z,1731344640.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-11-11T17:04:00.660Z,1731344640.660 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-11-11T17:04:00.775Z,1731344640.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-11-11T17:04:00.873Z,1731344640.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-11-11T17:04:00.989Z,1731344640.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-11-11T17:04:01.094Z,1731344641.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-11-11T17:04:01.225Z,1731344641.225 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-11-11T17:04:01.394Z,1731344641.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-11-11T17:04:01.558Z,1731344641.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-11-11T17:04:01.683Z,1731344641.683 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-11-11T17:04:01.965Z,1731344641.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-11-11T17:04:01.966Z,1731344641.966 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-11-11T17:04:01.980Z,1731344641.980 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-11-11T17:04:02.064Z,1731344642.064 [VerticalControl](DEBUG): Construct VerticalControl.
2024-11-11T17:04:02.126Z,1731344642.126 [VerticalControl] Loaded
2024-11-11T17:04:02.126Z,1731344642.126 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-11-11T17:04:02.129Z,1731344642.129 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-11-11T17:04:02.176Z,1731344642.176 [HorizontalControl] Loaded
2024-11-11T17:04:02.177Z,1731344642.177 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-11-11T17:04:02.179Z,1731344642.179 [SpeedControl](DEBUG): Construct SpeedControl.
2024-11-11T17:04:02.182Z,1731344642.182 [SpeedControl] Loaded
2024-11-11T17:04:02.183Z,1731344642.183 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-11-11T17:04:02.185Z,1731344642.185 [LoopControl](DEBUG): Construct LoopControl.
2024-11-11T17:04:02.186Z,1731344642.186 [LoopControl] Loaded
2024-11-11T17:04:02.186Z,1731344642.186 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-11-11T17:04:02.186Z,1731344642.186 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-11-11T17:04:02.188Z,1731344642.188 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-11-11T17:04:02.204Z,1731344642.204 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-11-11T17:04:02.205Z,1731344642.205 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-11-11T17:04:02.436Z,1731344642.436 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-11-11T17:04:02.436Z,1731344642.436 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-11-11T17:04:03.112Z,1731344643.112 [BuoyancyServo] Loaded
2024-11-11T17:04:03.112Z,1731344643.112 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-11-11T17:04:03.135Z,1731344643.135 [ElevatorServo] Loaded
2024-11-11T17:04:03.135Z,1731344643.135 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-11-11T17:04:03.156Z,1731344643.156 [MassServo] Loaded
2024-11-11T17:04:03.157Z,1731344643.157 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-11-11T17:04:03.248Z,1731344643.248 [RudderServo] Loaded
2024-11-11T17:04:03.248Z,1731344643.248 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-11-11T17:04:03.354Z,1731344643.354 [ThrusterHE] Loaded
2024-11-11T17:04:03.354Z,1731344643.354 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-11-11T17:04:03.354Z,1731344643.354 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-11-11T17:04:03.355Z,1731344643.355 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-11-11T17:04:03.508Z,1731344643.508 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-11-11T17:04:03.509Z,1731344643.509 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-11-11T17:04:03.522Z,1731344643.522 [NavChart] Loaded
2024-11-11T17:04:03.523Z,1731344643.523 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-11-11T17:04:03.528Z,1731344643.528 [UniversalFixResidualReporter] Loaded
2024-11-11T17:04:03.529Z,1731344643.529 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-11-11T17:04:03.529Z,1731344643.529 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-11-11T17:04:03.530Z,1731344643.530 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-11-11T17:04:03.622Z,1731344643.622 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-11-11T17:04:03.622Z,1731344643.622 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-11-11T17:04:04.454Z,1731344644.454 [AHRS_M2] Loaded
2024-11-11T17:04:04.454Z,1731344644.454 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-11-11T17:04:05.302Z,1731344645.302 [BPC1] Loaded
2024-11-11T17:04:05.302Z,1731344645.302 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-11-11T17:04:05.442Z,1731344645.442 [DataOverHttps] Loaded
2024-11-11T17:04:05.442Z,1731344645.442 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-11-11T17:04:05.443Z,1731344645.443 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409454E0
2024-11-11T17:04:05.443Z,1731344645.443 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 921
2024-11-11T17:04:05.467Z,1731344645.467 [Depth_Keller] Loaded
2024-11-11T17:04:05.468Z,1731344645.468 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-11-11T17:04:05.472Z,1731344645.472 [DropWeight] Loaded
2024-11-11T17:04:05.473Z,1731344645.473 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-11-11T17:04:05.538Z,1731344645.538 [NAL9602] Loaded
2024-11-11T17:04:05.538Z,1731344645.538 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-11-11T17:04:05.569Z,1731344645.569 [Onboard] Loaded
2024-11-11T17:04:05.569Z,1731344645.569 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-11-11T17:04:05.570Z,1731344645.570 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409754E0
2024-11-11T17:04:05.571Z,1731344645.571 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 922
2024-11-11T17:04:05.586Z,1731344645.586 [Power24vConverter] Loaded
2024-11-11T17:04:05.586Z,1731344645.586 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-11-11T17:04:05.603Z,1731344645.603 [Radio_Surface] Loaded
2024-11-11T17:04:05.603Z,1731344645.603 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-11-11T17:04:05.604Z,1731344645.604 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A54E0
2024-11-11T17:04:05.604Z,1731344645.604 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 923
2024-11-11T17:04:05.620Z,1731344645.620 [Sonardyne_Nano] Loaded
2024-11-11T17:04:05.620Z,1731344645.620 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-11-11T17:04:05.621Z,1731344645.621 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-11-11T17:04:05.622Z,1731344645.622 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-11-11T17:04:05.708Z,1731344645.708 [DepthRateCalculator] Loaded
2024-11-11T17:04:05.708Z,1731344645.708 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-11-11T17:04:05.713Z,1731344645.713 [PitchRateCalculator] Loaded
2024-11-11T17:04:05.713Z,1731344645.713 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-11-11T17:04:05.723Z,1731344645.723 [SpeedCalculator] Loaded
2024-11-11T17:04:05.723Z,1731344645.723 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-11-11T17:04:05.727Z,1731344645.727 [YawRateCalculator] Loaded
2024-11-11T17:04:05.728Z,1731344645.728 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-11-11T17:04:05.747Z,1731344645.747 [ElevatorOffsetCalculator] Loaded
2024-11-11T17:04:05.747Z,1731344645.747 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-11-11T17:04:05.748Z,1731344645.748 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-11-11T17:04:05.748Z,1731344645.748 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-11-11T17:04:05.946Z,1731344645.946 [CANONSampler] Loaded
2024-11-11T17:04:05.946Z,1731344645.946 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-11-11T17:04:06.009Z,1731344646.009 [CTD_Seabird] Loaded
2024-11-11T17:04:06.010Z,1731344646.010 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-11-11T17:04:06.011Z,1731344646.011 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A614E0
2024-11-11T17:04:06.011Z,1731344646.011 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 924
2024-11-11T17:04:06.034Z,1731344646.034 [PAR_Licor] Loaded
2024-11-11T17:04:06.035Z,1731344646.035 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-11-11T17:04:06.082Z,1731344646.082 [WetLabsBB2FL] Loaded
2024-11-11T17:04:06.082Z,1731344646.082 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-11-11T17:04:06.083Z,1731344646.083 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A914E0
2024-11-11T17:04:06.084Z,1731344646.084 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 925
2024-11-11T17:04:06.084Z,1731344646.084 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-11-11T17:04:06.085Z,1731344646.085 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-11-11T17:04:06.129Z,1731344646.129 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-11-11T17:04:06.130Z,1731344646.130 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-11-11T17:04:06.573Z,1731344646.573 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-11-11T17:04:06.575Z,1731344646.575 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-11-11T17:04:06.766Z,1731344646.766 [SBIT](DEBUG): Construct Startup Built In Test.
2024-11-11T17:04:06.775Z,1731344646.775 [SBIT] Loaded
2024-11-11T17:04:06.775Z,1731344646.775 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-11-11T17:04:06.778Z,1731344646.778 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-11-11T17:04:06.791Z,1731344646.791 [IBIT] Loaded
2024-11-11T17:04:06.791Z,1731344646.791 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-11-11T17:04:06.797Z,1731344646.797 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-11-11T17:04:06.904Z,1731344646.904 [CBIT] Loaded
2024-11-11T17:04:06.904Z,1731344646.904 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-11-11T17:04:06.905Z,1731344646.905 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-11-11T17:04:06.906Z,1731344646.906 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-11-11T17:04:07.085Z,1731344647.085 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-11-11T17:04:07.091Z,1731344647.091 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-11-11T17:04:07.094Z,1731344647.094 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-11-11T17:04:07.105Z,1731344647.105 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-11-11T17:04:07.106Z,1731344647.106 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF04E0
2024-11-11T17:04:07.107Z,1731344647.107 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926
2024-11-11T17:04:07.111Z,1731344647.111 [Supervisor](INFO): Main Thread ID is 831
2024-11-11T17:04:07.111Z,1731344647.111 [Supervisor](DEBUG): Running supervisor.
2024-11-11T17:04:07.112Z,1731344647.112 [CommandExec ThreadHandler](INFO): Handler Thread ID is 927
2024-11-11T17:04:07.112Z,1731344647.112 [CommandExec](INFO): Initializing the command executive.
2024-11-11T17:04:07.114Z,1731344647.114 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928
2024-11-11T17:04:07.116Z,1731344647.116 [controlThread ThreadHandler](INFO): Handler Thread ID is 929
2024-11-11T17:04:07.117Z,1731344647.117 [controlThread](DEBUG): Initializing ControlThread
2024-11-11T17:04:07.117Z,1731344647.117 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-11-11T17:04:07.119Z,1731344647.119 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-11-11T17:04:07.120Z,1731344647.120 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-11-11T17:04:07.120Z,1731344647.120 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-11-11T17:04:07.122Z,1731344647.122 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-11-11T17:04:07.123Z,1731344647.123 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-11-11T17:04:07.127Z,1731344647.127 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-11-11T17:04:07.128Z,1731344647.128 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-11-11T17:04:07.128Z,1731344647.128 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-11-11T17:04:07.128Z,1731344647.128 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-11-11T17:04:07.129Z,1731344647.129 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-11-11T17:04:07.133Z,1731344647.133 [SBIT](INFO): Initialize SBIT Component.
2024-11-11T17:04:07.134Z,1731344647.134 [SBIT](IMPORTANT): git: 2024-11-11
2024-11-11T17:04:07.134Z,1731344647.134 [SBIT](INFO): git hash: af6c16382058edc8016ef70a2b26bfc8eee51be6
2024-11-11T17:04:07.134Z,1731344647.134 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-11-11T17:04:07.135Z,1731344647.135 [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-11-11T17:04:07.136Z,1731344647.136 [SBIT](INFO): Beginning SBIT in 45.000000 seconds.
2024-11-11T17:04:07.137Z,1731344647.137 [IBIT](INFO): Initialize IBIT Component.
2024-11-11T17:04:07.138Z,1731344647.138 [CBIT](DEBUG): Initialize CBIT Component.
2024-11-11T17:04:07.139Z,1731344647.139 [logger ThreadHandler](INFO): Handler Thread ID is 930
2024-11-11T17:04:07.149Z,1731344647.149 [CBIT](DEBUG): Initialized mux pins.
2024-11-11T17:04:07.149Z,1731344647.149 [CBIT](DEBUG): Initializing the watchdog timer.
2024-11-11T17:04:07.157Z,1731344647.157 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 931
2024-11-11T17:04:07.159Z,1731344647.159 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-11-11T17:04:07.169Z,1731344647.169 [Onboard ThreadHandler](INFO): Handler Thread ID is 932
2024-11-11T17:04:07.174Z,1731344647.174 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-11-11T17:04:07.174Z,1731344647.174 [CBIT](DEBUG): Initializing heartbeat.
2024-11-11T17:04:07.190Z,1731344647.190 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 933
2024-11-11T17:04:07.205Z,1731344647.205 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 934
2024-11-11T17:04:07.206Z,1731344647.206 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-11-11T17:04:07.211Z,1731344647.211 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 936
2024-11-11T17:04:07.218Z,1731344647.218 [WetLabsBB2FL](INFO): Powering up
2024-11-11T17:04:07.219Z,1731344647.219 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 938
2024-11-11T17:04:07.228Z,1731344647.228 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-11-11T17:04:07.229Z,1731344647.229 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-11-11T17:04:07.229Z,1731344647.229 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-11-11T17:04:07.229Z,1731344647.229 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-11-11T17:04:07.229Z,1731344647.229 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-11-11T17:04:07.229Z,1731344647.229 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-11-11T17:04:07.230Z,1731344647.230 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-11-11T17:04:07.230Z,1731344647.230 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-11-11T17:04:07.245Z,1731344647.245 [CBIT](DEBUG): Deactivating GF circuits.
2024-11-11T17:04:07.245Z,1731344647.245 [CBIT](DEBUG): Deactivating emergency mode.
2024-11-11T17:04:07.285Z,1731344647.285 [CBIT](DEBUG): Backplane powered.
2024-11-11T17:04:07.286Z,1731344647.286 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-11-11T17:04:07.287Z,1731344647.287 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-11-11T17:04:07.287Z,1731344647.287 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-11-11T17:04:07.291Z,1731344647.291 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-11-11T17:04:07.356Z,1731344647.356 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-11-11T17:04:07.360Z,1731344647.360 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-11-11T17:04:07.400Z,1731344647.400 [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-11-11T17:04:07.401Z,1731344647.401 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-11-11T17:04:07.401Z,1731344647.401 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-11-11T17:04:07.414Z,1731344647.414 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-11-11T17:04:07.593Z,1731344647.593 [Radio_Surface](INFO): Powering up
2024-11-11T17:04:07.667Z,1731344647.667 [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-11-11T17:04:07.689Z,1731344647.689 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-11-11T17:04:07.690Z,1731344647.690 [Default:A.Wait](DEBUG): Construct Wait.
2024-11-11T17:04:07.692Z,1731344647.692 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-11-11T17:04:07.750Z,1731344647.750 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-11-11T17:04:07.753Z,1731344647.753 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-11-11T17:04:07.775Z,1731344647.775 [Default:E.Execute](DEBUG): Construct Execute.
2024-11-11T17:04:07.778Z,1731344647.778 [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-11-11T17:04:07.789Z,1731344647.789 [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-11-11T17:04:07.801Z,1731344647.801 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-11-11T17:04:07.949Z,1731344647.949 [Power24vConverter](INFO): Powering up.
2024-11-11T17:04:07.950Z,1731344647.950 [Sonardyne_Nano](INFO): Initializing.
2024-11-11T17:04:07.969Z,1731344647.969 [CANONSampler](INFO): Powering down
2024-11-11T17:04:08.004Z,1731344648.004 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-11-11T17:04:08.017Z,1731344648.017 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-11-11T17:04:08.018Z,1731344648.018 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-11-11T17:04:08.025Z,1731344648.025 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-11-11T17:04:08.026Z,1731344648.026 [MassServo](DEBUG): Initializing EZServoServo.
2024-11-11T17:04:08.033Z,1731344648.033 [MassServo](DEBUG): Initializing MassServo.
2024-11-11T17:04:08.034Z,1731344648.034 [RudderServo](DEBUG): Initializing EZServoServo.
2024-11-11T17:04:08.041Z,1731344648.041 [RudderServo](DEBUG): Initializing RudderServo.
2024-11-11T17:04:08.042Z,1731344648.042 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-11-11T17:04:08.050Z,1731344648.050 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-11-11T17:04:09.829Z,1731344649.829 [WetLabsBB2FL](INFO): Powering down
2024-11-11T17:04:10.228Z,1731344650.228 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-11-11T17:04:11.462Z,1731344651.462 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2024-11-11T17:04:13.714Z,1731344653.714 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-11-11T17:04:35.683Z,1731344675.683 [NAL9602](INFO): Powering up NAL9602
2024-11-11T17:04:46.591Z,1731344686.591 [NAL9602](INFO): NAL9602 initialized
2024-11-11T17:04:52.673Z,1731344692.673 [SBIT](IMPORTANT): Beginning Startup BIT
2024-11-11T17:04:52.685Z,1731344692.685 [CBIT](IMPORTANT): Beginning ground fault scan
2024-11-11T17:04:53.582Z,1731344693.582 [CommandExec](IMPORTANT): got command strobe off
2024-11-11T17:04:53.583Z,1731344693.583 [CommandExec](IMPORTANT): Deactivating strobe
2024-11-11T17:05:03.588Z,1731344703.588 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-11-11T17:05:04.000Z,1731344704.000 [CBIT](IMPORTANT): Beginning ground fault scan
2024-11-11T17:05:15.766Z,1731344715.766 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-11-11T17:05:16.179Z,1731344716.179 [CBIT](IMPORTANT): Beginning ground fault scan
2024-11-11T17:05:27.067Z,1731344727.067 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002804
CHAN A1 (24V): 0.000860
CHAN A2 (12V): -0.002171
CHAN A3 (5V): -0.001566
CHAN B0 (3.3V): -0.000016
CHAN B1 (3.15aV): -0.000287
CHAN B2 (3.15bV): -0.000651
CHAN B3 (GND): -0.000159
OPEN: -0.000476
Full Scale: +/- 1 mA
2024-11-11T17:05:46.827Z,1731344746.827 [SBIT](IMPORTANT): SBIT PASSED
2024-11-11T17:05:46.828Z,1731344746.828 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-11-11T17:05:46.828Z,1731344746.828 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count;
2024-11-11T17:05:46.829Z,1731344746.829 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-11-11T17:05:46.829Z,1731344746.829 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-11-11T17:05:46.829Z,1731344746.829 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-11-11T17:05:46.829Z,1731344746.829 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2024-11-11T17:05:46.829Z,1731344746.829 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2024-11-11T17:05:46.829Z,1731344746.829 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=243.321146 cubic_centimeter;
2024-11-11T17:05:46.830Z,1731344746.830 [SBIT](IMPORTANT): VerticalControl.massDefault=6.260265 millimeter;
2024-11-11T17:05:47.230Z,1731344747.230 [MissionManager](IMPORTANT): Started mission Startup
2024-11-11T17:05:47.231Z,1731344747.231 [Startup] Running Loop=1
2024-11-11T17:05:47.231Z,1731344747.231 [Startup](DEBUG): Aggregate::initialize Startup
2024-11-11T17:05:47.231Z,1731344747.231 [Startup:A.GoToSurface] Running Loop=1
2024-11-11T17:05:47.231Z,1731344747.231 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-11-11T17:05:47.231Z,1731344747.231 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-11-11T17:05:47.232Z,1731344747.232 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-11-11T17:05:47.232Z,1731344747.232 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-11-11T17:05:47.232Z,1731344747.232 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-11-11T17:05:47.233Z,1731344747.233 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-11-11T17:05:47.233Z,1731344747.233 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-11-11T17:05:47.235Z,1731344747.235 [Startup:StartupSatComms] Running Loop=1
2024-11-11T17:05:47.235Z,1731344747.235 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-11-11T17:05:47.235Z,1731344747.235 [Startup:StartupSatComms:A] Running Loop=1
2024-11-11T17:05:47.652Z,1731344747.652 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-11-11T17:06:41.976Z,1731344801.976 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004963
2024-11-11T17:06:47.422Z,1731344807.422 [Startup:StartupSatComms:A](INFO): Timed out from 2024-11-11T17:05:47.2Z
2024-11-11T17:06:47.422Z,1731344807.422 [Startup:StartupSatComms:A] Stopped
2024-11-11T17:06:47.422Z,1731344807.422 [Startup:StartupSatComms:B] Running Loop=1
2024-11-11T17:06:47.832Z,1731344807.832 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-11-11T17:06:54.780Z,1731344814.780 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20241111T162453/Courier0013.lzma
2024-11-11T17:06:55.783Z,1731344815.783 [DataOverHttps](INFO): Moved sent file to Logs/20241111T162453/Courier0013.lzma.bak
2024-11-11T17:06:55.783Z,1731344815.783 [DataOverHttps](INFO): SBD MOMSN=23389311
2024-11-11T17:07:07.248Z,1731344827.248 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-11-11T17:07:07.248Z,1731344827.248 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-11T17:07:07.271Z,1731344827.271 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-11T17:07:07.667Z,1731344827.667 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-11T17:07:07.667Z,1731344827.667 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-11-11T17:07:11.224Z,1731344831.224 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241111T170354/Courier0000.lzma
2024-11-11T17:07:12.226Z,1731344832.226 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0000.lzma.bak
2024-11-11T17:07:12.226Z,1731344832.226 [DataOverHttps](INFO): SBD MOMSN=23389313
2024-11-11T17:07:27.924Z,1731344847.924 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20241111T162453/Express0014.lzma
2024-11-11T17:07:28.926Z,1731344848.926 [DataOverHttps](INFO): Moved sent file to Logs/20241111T162453/Express0014.lzma.bak
2024-11-11T17:07:28.926Z,1731344848.926 [DataOverHttps](INFO): SBD MOMSN=23389316
2024-11-11T17:07:30.641Z,1731344850.641 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:07:35.881Z,1731344855.881 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:07:44.505Z,1731344864.505 [DataOverHttps](INFO): Sending 911 bytes from file Logs/20241111T170354/Express0001.lzma
2024-11-11T17:07:45.506Z,1731344865.506 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0001.lzma.bak
2024-11-11T17:07:45.506Z,1731344865.506 [DataOverHttps](INFO): SBD MOMSN=23389318
2024-11-11T17:07:46.832Z,1731344866.832 [Startup:StartupSatComms:B] Stopped
2024-11-11T17:07:46.833Z,1731344866.833 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-11-11T17:07:46.833Z,1731344866.833 [Startup:StartupSatComms] Stopped
2024-11-11T17:07:46.833Z,1731344866.833 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-11-11T17:07:46.834Z,1731344866.834 [Startup](INFO): Completed Startup
2024-11-11T17:07:46.834Z,1731344866.834 [MissionManager](INFO): Startup is completed.
2024-11-11T17:07:46.834Z,1731344866.834 [MissionManager](INFO): Uninitializing Mission Startup
2024-11-11T17:07:46.834Z,1731344866.834 [Startup] Stopped
2024-11-11T17:07:46.834Z,1731344866.834 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-11-11T17:07:46.834Z,1731344866.834 [Startup:A.GoToSurface] Stopped
2024-11-11T17:07:46.834Z,1731344866.834 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-11-11T17:07:47.193Z,1731344867.193 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:07:47.214Z,1731344867.214 [MissionManager](IMPORTANT): Started mission Default
2024-11-11T17:07:47.214Z,1731344867.214 [Default] Running Loop=1
2024-11-11T17:07:47.214Z,1731344867.214 [Default](DEBUG): Aggregate::initialize Default
2024-11-11T17:07:47.214Z,1731344867.214 [Default:B.GoToSurface] Running Loop=1
2024-11-11T17:07:47.214Z,1731344867.214 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-11-11T17:07:47.215Z,1731344867.215 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-11-11T17:07:47.215Z,1731344867.215 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-11-11T17:07:47.215Z,1731344867.215 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-11-11T17:07:47.216Z,1731344867.216 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-11-11T17:07:47.216Z,1731344867.216 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-11-11T17:07:47.232Z,1731344867.232 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-11-11T17:07:47.233Z,1731344867.233 [Default:A.Wait] Running Loop=1
2024-11-11T17:07:47.233Z,1731344867.233 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-11-11T17:07:59.315Z,1731344879.315 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:08:00.543Z,1731344880.543 [Default:A.Wait](INFO): Done Waiting.
2024-11-11T17:08:00.543Z,1731344880.543 [Default:A.Wait] Stopped
2024-11-11T17:08:00.543Z,1731344880.543 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T17:08:00.946Z,1731344880.946 [Default:CheckIn] Running Loop=1
2024-11-11T17:08:00.947Z,1731344880.947 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T17:08:00.947Z,1731344880.947 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T17:08:01.359Z,1731344881.359 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-11-11T17:08:11.029Z,1731344891.029 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:08:23.164Z,1731344903.164 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:08:35.269Z,1731344915.269 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:08:47.389Z,1731344927.389 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:08:59.166Z,1731344939.166 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:09:11.285Z,1731344951.285 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:09:23.809Z,1731344963.809 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:09:35.929Z,1731344975.929 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:09:47.246Z,1731344987.246 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:09:50.510Z,1731344990.510 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-11-11T17:10:08.268Z,1731345008.268 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-11-11T17:10:08.268Z,1731345008.268 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-11T17:10:08.279Z,1731345008.279 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-11T17:10:08.692Z,1731345008.692 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-11T17:10:08.692Z,1731345008.692 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-11-11T17:13:01.325Z,1731345181.325 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-11-11T17:08:00.9Z
2024-11-11T17:13:01.325Z,1731345181.325 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T17:13:01.325Z,1731345181.325 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T17:13:01.723Z,1731345181.723 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-11-11T17:13:08.180Z,1731345188.180 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241111T170354/Courier0004.lzma
2024-11-11T17:13:09.183Z,1731345189.183 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0004.lzma.bak
2024-11-11T17:13:09.183Z,1731345189.183 [DataOverHttps](INFO): SBD MOMSN=23389347
2024-11-11T17:13:09.449Z,1731345189.449 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-11-11T17:13:09.449Z,1731345189.449 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-11T17:13:09.460Z,1731345189.460 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-11T17:13:09.931Z,1731345189.931 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-11T17:13:09.931Z,1731345189.931 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-11-11T17:13:24.776Z,1731345204.776 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20241111T170354/Express0005.lzma
2024-11-11T17:13:25.778Z,1731345205.778 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0005.lzma.bak
2024-11-11T17:13:25.778Z,1731345205.778 [DataOverHttps](INFO): SBD MOMSN=23389349
2024-11-11T17:13:26.823Z,1731345206.823 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T17:13:26.823Z,1731345206.823 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T17:13:26.823Z,1731345206.823 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T17:14:08.816Z,1731345248.816 [Power24vConverter](INFO): Powering down.
2024-11-11T17:14:47.982Z,1731345287.982 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-11-11T17:14:47.982Z,1731345287.982 [NAL9602] Data Fault, FailCount= 1
2024-11-11T17:14:47.982Z,1731345287.982 [NAL9602](ERROR): Data Fault
2024-11-11T17:14:48.049Z,1731345288.049 [CBIT](ERROR): Data Fault in component: NAL9602
2024-11-11T17:14:48.390Z,1731345288.390 [NAL9602](INFO): Powering down
2024-11-11T17:14:49.227Z,1731345289.227 [CBIT](INFO): Clearing failed state for component NAL9602
2024-11-11T17:14:49.227Z,1731345289.227 [NAL9602] No Fault, FailCount= 1
2024-11-11T17:15:18.690Z,1731345318.690 [NAL9602](INFO): Powering up NAL9602
2024-11-11T17:15:29.598Z,1731345329.598 [NAL9602](INFO): NAL9602 initialized
2024-11-11T17:16:10.426Z,1731345370.426 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-11-11T17:16:10.426Z,1731345370.426 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-11T17:16:10.462Z,1731345370.462 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-11T17:16:10.843Z,1731345370.843 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-11T17:16:10.843Z,1731345370.843 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-11-11T17:17:12.205Z,1731345432.205 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:17:25.941Z,1731345445.941 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:17:39.273Z,1731345459.273 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:17:52.605Z,1731345472.605 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:18:05.938Z,1731345485.938 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:18:19.269Z,1731345499.269 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:18:27.399Z,1731345507.399 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T17:18:27.399Z,1731345507.399 [Default:CheckIn:C.Wait] Stopped
2024-11-11T17:18:27.399Z,1731345507.399 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T17:18:27.399Z,1731345507.399 [Default:CheckIn:D] Running Loop=1
2024-11-11T17:18:27.792Z,1731345507.792 [Default:CheckIn:D] Stopped
2024-11-11T17:18:27.792Z,1731345507.792 [Default:CheckIn:E] Running Loop=1
2024-11-11T17:18:28.211Z,1731345508.211 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.676293 min
2024-11-11T17:18:28.211Z,1731345508.211 [Default:CheckIn:E] Stopped
2024-11-11T17:18:28.211Z,1731345508.211 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T17:18:28.211Z,1731345508.211 [Default:CheckIn] Stopped
2024-11-11T17:18:28.211Z,1731345508.211 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T17:18:28.212Z,1731345508.212 [Default:CheckIn](INFO): Running loop #2
2024-11-11T17:18:28.212Z,1731345508.212 [Default:CheckIn] Running Loop=2
2024-11-11T17:18:28.212Z,1731345508.212 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T17:18:28.212Z,1731345508.212 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T17:18:33.009Z,1731345513.009 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:18:46.337Z,1731345526.337 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:18:59.669Z,1731345539.669 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:19:11.401Z,1731345551.401 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-11-11T17:19:11.401Z,1731345551.401 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-11T17:19:11.412Z,1731345551.412 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-11T17:19:11.819Z,1731345551.819 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-11T17:19:11.819Z,1731345551.819 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-11-11T17:19:13.001Z,1731345553.001 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T17:19:27.141Z,1731345567.141 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-11-11T17:21:03.716Z,1731345663.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172102.00,A,3648.17177,N,12147.28565,W,2.410,139.63,111124,,,A*77
2024-11-11T17:21:03.719Z,1731345663.719 [NAL9602](INFO): GPS fix at 20241111T172102: (36.802863, -121.788094)
2024-11-11T17:21:03.801Z,1731345663.801 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T17:21:03.801Z,1731345663.801 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T17:21:09.617Z,1731345669.617 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20241111T170354/Courier0007.lzma
2024-11-11T17:21:10.618Z,1731345670.618 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0007.lzma.bak
2024-11-11T17:21:10.618Z,1731345670.618 [DataOverHttps](INFO): SBD MOMSN=23389357
2024-11-11T17:21:27.596Z,1731345687.596 [DataOverHttps](INFO): Sending 317 bytes from file Logs/20241111T170354/Express0008.lzma
2024-11-11T17:21:28.598Z,1731345688.598 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0008.lzma.bak
2024-11-11T17:21:28.598Z,1731345688.598 [DataOverHttps](INFO): SBD MOMSN=23389360
2024-11-11T17:21:29.688Z,1731345689.688 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T17:21:29.688Z,1731345689.688 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T17:21:29.688Z,1731345689.688 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T17:21:36.541Z,1731345696.541 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T17:21:53.507Z,1731345713.507 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-11-11T17:21:53.531Z,1731345713.531 [BPC1](ERROR): Battery stick #62 (s/n: 01FE) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2024-11-11T17:21:53.553Z,1731345713.553 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2024-11-11T17:21:53.556Z,1731345713.556 [BPC1](INFO): Received data from all battery sticks.
2024-11-11T17:26:30.277Z,1731345990.277 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T17:26:30.277Z,1731345990.277 [Default:CheckIn:C.Wait] Stopped
2024-11-11T17:26:30.277Z,1731345990.277 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T17:26:30.277Z,1731345990.277 [Default:CheckIn:D] Running Loop=1
2024-11-11T17:26:30.675Z,1731345990.675 [Default:CheckIn:D] Stopped
2024-11-11T17:26:30.675Z,1731345990.675 [Default:CheckIn:E] Running Loop=1
2024-11-11T17:26:31.089Z,1731345991.089 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.724343 min
2024-11-11T17:26:31.089Z,1731345991.089 [Default:CheckIn:E] Stopped
2024-11-11T17:26:31.089Z,1731345991.089 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T17:26:31.089Z,1731345991.089 [Default:CheckIn] Stopped
2024-11-11T17:26:31.089Z,1731345991.089 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T17:26:31.089Z,1731345991.089 [Default:CheckIn](INFO): Running loop #3
2024-11-11T17:26:31.090Z,1731345991.090 [Default:CheckIn] Running Loop=3
2024-11-11T17:26:31.090Z,1731345991.090 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T17:26:31.090Z,1731345991.090 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T17:26:33.100Z,1731345993.100 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172632.00,A,3648.16762,N,12147.28059,W,1.205,139.63,111124,,,A*7B
2024-11-11T17:26:33.102Z,1731345993.102 [NAL9602](INFO): GPS fix at 20241111T172632: (36.802794, -121.788010)
2024-11-11T17:26:33.113Z,1731345993.113 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T17:26:33.113Z,1731345993.113 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T17:26:41.064Z,1731346001.064 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20241111T170354/Courier0010.lzma
2024-11-11T17:26:42.066Z,1731346002.066 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0010.lzma.bak
2024-11-11T17:26:42.066Z,1731346002.066 [DataOverHttps](INFO): SBD MOMSN=23389368
2024-11-11T17:27:01.188Z,1731346021.188 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241111T170354/Express0011.lzma
2024-11-11T17:27:02.190Z,1731346022.190 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0011.lzma.bak
2024-11-11T17:27:02.190Z,1731346022.190 [DataOverHttps](INFO): SBD MOMSN=23389371
2024-11-11T17:27:03.457Z,1731346023.457 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T17:27:03.457Z,1731346023.457 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T17:27:03.457Z,1731346023.457 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T17:28:39.183Z,1731346119.183 [NAL9602](INFO): SBD MO Status=0, MOMSN=59774, MT Status=0, MTMSN=0
2024-11-11T17:28:39.183Z,1731346119.183 [NAL9602](INFO): No messages in MT queue
2024-11-11T17:29:09.890Z,1731346149.890 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T17:30:43.782Z,1731346243.782 [DataOverHttps](IMPORTANT): SBD MTMSN=20241111T173042
2024-11-11T17:30:53.709Z,1731346253.709 [DataOverHttps](INFO): Received command: ibit
2024-11-11T17:30:53.749Z,1731346253.749 [CommandExec](IMPORTANT): got command ibit
2024-11-11T17:30:53.817Z,1731346253.817 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-11-11T17:30:53.817Z,1731346253.817 [IBIT](IMPORTANT): Beginning control surface checks.
2024-11-11T17:30:53.821Z,1731346253.821 [CBIT](IMPORTANT): Beginning ground fault scan
2024-11-11T17:30:54.710Z,1731346254.710 [DataOverHttps](IMPORTANT): SBD MTMSN=20241111T173053
2024-11-11T17:30:55.399Z,1731346255.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173054.00,A,3648.14310,N,12147.24284,W,1.089,98.72,111124,,,A*4D
2024-11-11T17:30:55.401Z,1731346255.401 [NAL9602](INFO): GPS fix at 20241111T173054: (36.802385, -121.787381)
2024-11-11T17:30:56.737Z,1731346256.737 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2024-11-11T17:31:03.336Z,1731346263.336 [DataOverHttps](INFO): Received command: failc
2024-11-11T17:31:03.385Z,1731346263.385 [CommandExec](IMPORTANT): got command failComponent
2024-11-11T17:31:03.385Z,1731346263.385 [CommandExec](IMPORTANT): Failed components:
2024-11-11T17:31:03.386Z,1731346263.386 [CommandExec](IMPORTANT): No failed Components.
2024-11-11T17:31:04.726Z,1731346264.726 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002705
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002291
CHAN A3 (5V): -0.001269
CHAN B0 (3.3V): -0.000040
CHAN B1 (3.15aV): -0.000475
CHAN B2 (3.15bV): -0.000054
CHAN B3 (GND): 0.000406
OPEN: -0.000500
Full Scale: +/- 1 mA
2024-11-11T17:31:38.623Z,1731346298.623 [NAL9602](INFO): SBD MO Status=0, MOMSN=59775, MT Status=0, MTMSN=0
2024-11-11T17:31:38.623Z,1731346298.623 [NAL9602](INFO): No messages in MT queue
2024-11-11T17:31:39.836Z,1731346299.836 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173139.00,A,3648.13900,N,12147.23562,W,2.624,75.22,111124,,,A*47
2024-11-11T17:31:39.838Z,1731346299.838 [NAL9602](INFO): GPS fix at 20241111T173139: (36.802317, -121.787260)
2024-11-11T17:31:39.879Z,1731346299.879 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802319 Longitude: -121.787262
2024-11-11T17:31:40.295Z,1731346300.295 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2024-11-11T17:31:40.295Z,1731346300.295 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-11-11T17:31:40.295Z,1731346300.295 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-11-11T17:31:40.666Z,1731346300.666 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-11-11T17:31:40.666Z,1731346300.666 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-11-11T17:31:40.666Z,1731346300.666 [IBIT](IMPORTANT): Pressure:9.286088 PSI
2024-11-11T17:31:40.666Z,1731346300.666 [IBIT](IMPORTANT): Humidity:16.335001 %
2024-11-11T17:31:41.074Z,1731346301.074 [IBIT](IMPORTANT): Vehicle Pitch:-0.824138 degrees
2024-11-11T17:31:41.075Z,1731346301.075 [IBIT](IMPORTANT): Vehicle Roll:0.937760 degrees
2024-11-11T17:31:41.075Z,1731346301.075 [IBIT](IMPORTANT): Vehicle Heading:219.376434 degrees
2024-11-11T17:31:41.557Z,1731346301.557 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-11-11T17:31:41.558Z,1731346301.558 [IBIT](IMPORTANT): buoyancyNeutral: 243.321152 cc
2024-11-11T17:31:41.558Z,1731346301.558 [IBIT](IMPORTANT): massDefault: 0.626026 cm
2024-11-11T17:31:41.558Z,1731346301.558 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-11-11T17:31:41.558Z,1731346301.558 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-11-11T17:31:41.559Z,1731346301.559 [IBIT](IMPORTANT): IBIT PASSED
2024-11-11T17:32:04.104Z,1731346324.104 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T17:32:04.104Z,1731346324.104 [Default:CheckIn:C.Wait] Stopped
2024-11-11T17:32:04.104Z,1731346324.104 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T17:32:04.104Z,1731346324.104 [Default:CheckIn:D] Running Loop=1
2024-11-11T17:32:04.492Z,1731346324.492 [Default:CheckIn:D] Stopped
2024-11-11T17:32:04.492Z,1731346324.492 [Default:CheckIn:E] Running Loop=1
2024-11-11T17:32:04.895Z,1731346324.895 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.287956 min
2024-11-11T17:32:04.896Z,1731346324.896 [Default:CheckIn:E] Stopped
2024-11-11T17:32:04.896Z,1731346324.896 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T17:32:04.896Z,1731346324.896 [Default:CheckIn] Stopped
2024-11-11T17:32:04.896Z,1731346324.896 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T17:32:04.896Z,1731346324.896 [Default:CheckIn](INFO): Running loop #4
2024-11-11T17:32:04.896Z,1731346324.896 [Default:CheckIn] Running Loop=4
2024-11-11T17:32:04.896Z,1731346324.896 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T17:32:04.896Z,1731346324.896 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T17:32:06.904Z,1731346326.904 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173206.00,A,3648.14123,N,12147.22973,W,0.078,78.91,111124,,,A*43
2024-11-11T17:32:06.906Z,1731346326.906 [NAL9602](INFO): GPS fix at 20241111T173206: (36.802354, -121.787162)
2024-11-11T17:32:06.917Z,1731346326.917 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T17:32:06.917Z,1731346326.917 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T17:32:14.084Z,1731346334.084 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241111T170354/Courier0013.lzma
2024-11-11T17:32:15.087Z,1731346335.087 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0013.lzma.bak
2024-11-11T17:32:15.087Z,1731346335.087 [DataOverHttps](INFO): SBD MOMSN=23389384
2024-11-11T17:32:33.774Z,1731346353.774 [DataOverHttps](INFO): Sending 906 bytes from file Logs/20241111T170354/Express0014.lzma
2024-11-11T17:32:34.774Z,1731346354.774 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0014.lzma.bak
2024-11-11T17:32:34.775Z,1731346354.775 [DataOverHttps](INFO): SBD MOMSN=23389387
2024-11-11T17:32:39.698Z,1731346359.698 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T17:32:51.977Z,1731346371.977 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20241111T170354/Express0017.lzma
2024-11-11T17:32:52.978Z,1731346372.978 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0017.lzma.bak
2024-11-11T17:32:52.978Z,1731346372.978 [DataOverHttps](INFO): SBD MOMSN=23389417
2024-11-11T17:32:54.272Z,1731346374.272 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T17:32:54.272Z,1731346374.272 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T17:32:54.272Z,1731346374.272 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T17:37:54.874Z,1731346674.874 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T17:37:54.875Z,1731346674.875 [Default:CheckIn:C.Wait] Stopped
2024-11-11T17:37:54.875Z,1731346674.875 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T17:37:54.875Z,1731346674.875 [Default:CheckIn:D] Running Loop=1
2024-11-11T17:37:55.276Z,1731346675.276 [Default:CheckIn:D] Stopped
2024-11-11T17:37:55.276Z,1731346675.276 [Default:CheckIn:E] Running Loop=1
2024-11-11T17:37:55.676Z,1731346675.676 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.134363 min
2024-11-11T17:37:55.676Z,1731346675.676 [Default:CheckIn:E] Stopped
2024-11-11T17:37:55.676Z,1731346675.676 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T17:37:55.676Z,1731346675.676 [Default:CheckIn] Stopped
2024-11-11T17:37:55.676Z,1731346675.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T17:37:55.677Z,1731346675.677 [Default:CheckIn](INFO): Running loop #5
2024-11-11T17:37:55.677Z,1731346675.677 [Default:CheckIn] Running Loop=5
2024-11-11T17:37:55.677Z,1731346675.677 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T17:37:55.677Z,1731346675.677 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T17:37:57.684Z,1731346677.684 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173756.00,A,3648.14027,N,12147.22816,W,0.058,78.91,111124,,,D*43
2024-11-11T17:37:57.686Z,1731346677.686 [NAL9602](INFO): GPS fix at 20241111T173756: (36.802338, -121.787136)
2024-11-11T17:37:57.697Z,1731346677.697 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T17:37:57.697Z,1731346677.697 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T17:38:04.936Z,1731346684.936 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241111T170354/Courier0019.lzma
2024-11-11T17:38:05.938Z,1731346685.938 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0019.lzma.bak
2024-11-11T17:38:05.938Z,1731346685.938 [DataOverHttps](INFO): SBD MOMSN=23389425
2024-11-11T17:38:15.867Z,1731346695.867 [NAL9602](INFO): SBD MO Status=0, MOMSN=59776, MT Status=0, MTMSN=0
2024-11-11T17:38:15.867Z,1731346695.867 [NAL9602](INFO): No messages in MT queue
2024-11-11T17:38:24.641Z,1731346704.641 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20241111T170354/Express0020.lzma
2024-11-11T17:38:25.670Z,1731346705.670 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0020.lzma.bak
2024-11-11T17:38:25.670Z,1731346705.670 [DataOverHttps](INFO): SBD MOMSN=23389428
2024-11-11T17:38:26.838Z,1731346706.838 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T17:38:26.838Z,1731346706.838 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T17:38:26.838Z,1731346706.838 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T17:38:46.631Z,1731346726.631 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T17:43:27.447Z,1731347007.447 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T17:43:27.447Z,1731347007.447 [Default:CheckIn:C.Wait] Stopped
2024-11-11T17:43:27.447Z,1731347007.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T17:43:27.447Z,1731347007.447 [Default:CheckIn:D] Running Loop=1
2024-11-11T17:43:27.849Z,1731347007.849 [Default:CheckIn:D] Stopped
2024-11-11T17:43:27.849Z,1731347007.849 [Default:CheckIn:E] Running Loop=1
2024-11-11T17:43:28.263Z,1731347008.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.677246 min
2024-11-11T17:43:28.263Z,1731347008.263 [Default:CheckIn:E] Stopped
2024-11-11T17:43:28.264Z,1731347008.264 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T17:43:28.264Z,1731347008.264 [Default:CheckIn] Stopped
2024-11-11T17:43:28.264Z,1731347008.264 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T17:43:28.264Z,1731347008.264 [Default:CheckIn](INFO): Running loop #6
2024-11-11T17:43:28.264Z,1731347008.264 [Default:CheckIn] Running Loop=6
2024-11-11T17:43:28.268Z,1731347008.268 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T17:43:28.268Z,1731347008.268 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T17:43:30.263Z,1731347010.263 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174329.00,A,3648.14180,N,12147.22623,W,0.019,78.91,111124,,,D*49
2024-11-11T17:43:30.266Z,1731347010.266 [NAL9602](INFO): GPS fix at 20241111T174329: (36.802363, -121.787104)
2024-11-11T17:43:30.277Z,1731347010.277 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T17:43:30.277Z,1731347010.277 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T17:43:37.616Z,1731347017.616 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241111T170354/Courier0022.lzma
2024-11-11T17:43:38.618Z,1731347018.618 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0022.lzma.bak
2024-11-11T17:43:38.618Z,1731347018.618 [DataOverHttps](INFO): SBD MOMSN=23389436
2024-11-11T17:43:41.574Z,1731347021.574 [NAL9602](INFO): SBD MO Status=0, MOMSN=59777, MT Status=0, MTMSN=0
2024-11-11T17:43:41.574Z,1731347021.574 [NAL9602](INFO): No messages in MT queue
2024-11-11T17:43:54.384Z,1731347034.384 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20241111T170354/Express0023.lzma
2024-11-11T17:43:55.386Z,1731347035.386 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0023.lzma.bak
2024-11-11T17:43:55.386Z,1731347035.386 [DataOverHttps](INFO): SBD MOMSN=23389440
2024-11-11T17:43:56.536Z,1731347036.536 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T17:43:56.536Z,1731347036.536 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T17:43:56.536Z,1731347036.536 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T17:44:12.278Z,1731347052.278 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T17:48:57.149Z,1731347337.149 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T17:48:57.149Z,1731347337.149 [Default:CheckIn:C.Wait] Stopped
2024-11-11T17:48:57.149Z,1731347337.149 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T17:48:57.149Z,1731347337.149 [Default:CheckIn:D] Running Loop=1
2024-11-11T17:48:57.557Z,1731347337.557 [Default:CheckIn:D] Stopped
2024-11-11T17:48:57.557Z,1731347337.557 [Default:CheckIn:E] Running Loop=1
2024-11-11T17:48:57.943Z,1731347337.943 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.172380 min
2024-11-11T17:48:57.943Z,1731347337.943 [Default:CheckIn:E] Stopped
2024-11-11T17:48:57.943Z,1731347337.943 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T17:48:57.943Z,1731347337.943 [Default:CheckIn] Stopped
2024-11-11T17:48:57.943Z,1731347337.943 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T17:48:57.944Z,1731347337.944 [Default:CheckIn](INFO): Running loop #7
2024-11-11T17:48:57.944Z,1731347337.944 [Default:CheckIn] Running Loop=7
2024-11-11T17:48:57.944Z,1731347337.944 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T17:48:57.944Z,1731347337.944 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T17:48:59.955Z,1731347339.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174859.00,A,3648.14102,N,12147.22900,W,0.039,78.91,111124,,,D*43
2024-11-11T17:48:59.958Z,1731347339.958 [NAL9602](INFO): GPS fix at 20241111T174859: (36.802350, -121.787150)
2024-11-11T17:48:59.968Z,1731347339.968 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T17:48:59.969Z,1731347339.969 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T17:49:08.012Z,1731347348.012 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241111T170354/Courier0025.lzma
2024-11-11T17:49:09.015Z,1731347349.015 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0025.lzma.bak
2024-11-11T17:49:09.015Z,1731347349.015 [DataOverHttps](INFO): SBD MOMSN=23389447
2024-11-11T17:49:11.267Z,1731347351.267 [NAL9602](INFO): SBD MO Status=0, MOMSN=59778, MT Status=0, MTMSN=0
2024-11-11T17:49:11.267Z,1731347351.267 [NAL9602](INFO): No messages in MT queue
2024-11-11T17:49:24.748Z,1731347364.748 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20241111T170354/Express0026.lzma
2024-11-11T17:49:25.750Z,1731347365.750 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0026.lzma.bak
2024-11-11T17:49:25.750Z,1731347365.750 [DataOverHttps](INFO): SBD MOMSN=23389450
2024-11-11T17:49:27.044Z,1731347367.044 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T17:49:27.044Z,1731347367.044 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T17:49:27.044Z,1731347367.044 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T17:49:41.974Z,1731347381.974 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T17:54:12.658Z,1731347652.658 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2024-11-11T17:54:27.619Z,1731347667.619 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T17:54:27.620Z,1731347667.620 [Default:CheckIn:C.Wait] Stopped
2024-11-11T17:54:27.620Z,1731347667.620 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T17:54:27.620Z,1731347667.620 [Default:CheckIn:D] Running Loop=1
2024-11-11T17:54:28.031Z,1731347668.031 [Default:CheckIn:D] Stopped
2024-11-11T17:54:28.031Z,1731347668.031 [Default:CheckIn:E] Running Loop=1
2024-11-11T17:54:28.427Z,1731347668.427 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.680282 min
2024-11-11T17:54:28.427Z,1731347668.427 [Default:CheckIn:E] Stopped
2024-11-11T17:54:28.427Z,1731347668.427 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T17:54:28.427Z,1731347668.427 [Default:CheckIn] Stopped
2024-11-11T17:54:28.427Z,1731347668.427 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T17:54:28.427Z,1731347668.427 [Default:CheckIn](INFO): Running loop #8
2024-11-11T17:54:28.427Z,1731347668.427 [Default:CheckIn] Running Loop=8
2024-11-11T17:54:28.427Z,1731347668.427 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T17:54:28.428Z,1731347668.428 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T17:54:30.439Z,1731347670.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175429.00,A,3648.14029,N,12147.22904,W,0.019,78.91,111124,,,D*47
2024-11-11T17:54:30.442Z,1731347670.442 [NAL9602](INFO): GPS fix at 20241111T175429: (36.802338, -121.787151)
2024-11-11T17:54:30.486Z,1731347670.486 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T17:54:30.486Z,1731347670.486 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T17:54:37.680Z,1731347677.680 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241111T170354/Courier0028.lzma
2024-11-11T17:54:38.682Z,1731347678.682 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0028.lzma.bak
2024-11-11T17:54:38.682Z,1731347678.682 [DataOverHttps](INFO): SBD MOMSN=23389460
2024-11-11T17:54:44.579Z,1731347684.579 [NAL9602](INFO): SBD MO Status=0, MOMSN=59779, MT Status=0, MTMSN=0
2024-11-11T17:54:44.579Z,1731347684.579 [NAL9602](INFO): No messages in MT queue
2024-11-11T17:54:57.260Z,1731347697.260 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20241111T170354/Express0029.lzma
2024-11-11T17:54:58.262Z,1731347698.262 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0029.lzma.bak
2024-11-11T17:54:58.262Z,1731347698.262 [DataOverHttps](INFO): SBD MOMSN=23389463
2024-11-11T17:54:59.600Z,1731347699.600 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T17:54:59.600Z,1731347699.600 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T17:54:59.600Z,1731347699.600 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T17:55:15.345Z,1731347715.345 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T18:00:00.191Z,1731348000.191 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T18:00:00.191Z,1731348000.191 [Default:CheckIn:C.Wait] Stopped
2024-11-11T18:00:00.191Z,1731348000.191 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T18:00:00.191Z,1731348000.191 [Default:CheckIn:D] Running Loop=1
2024-11-11T18:00:00.592Z,1731348000.592 [Default:CheckIn:D] Stopped
2024-11-11T18:00:00.592Z,1731348000.592 [Default:CheckIn:E] Running Loop=1
2024-11-11T18:00:00.992Z,1731348000.992 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.222965 min
2024-11-11T18:00:00.992Z,1731348000.992 [Default:CheckIn:E] Stopped
2024-11-11T18:00:00.992Z,1731348000.992 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T18:00:00.993Z,1731348000.993 [Default:CheckIn] Stopped
2024-11-11T18:00:00.993Z,1731348000.993 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T18:00:00.993Z,1731348000.993 [Default:CheckIn](INFO): Running loop #9
2024-11-11T18:00:00.993Z,1731348000.993 [Default:CheckIn] Running Loop=9
2024-11-11T18:00:00.993Z,1731348000.993 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T18:00:00.993Z,1731348000.993 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T18:00:03.001Z,1731348003.001 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180002.00,A,3648.14036,N,12147.22891,W,0.078,78.91,111124,,,D*44
2024-11-11T18:00:03.003Z,1731348003.003 [NAL9602](INFO): GPS fix at 20241111T180002: (36.802339, -121.787149)
2024-11-11T18:00:03.014Z,1731348003.014 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T18:00:03.014Z,1731348003.014 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T18:00:10.356Z,1731348010.356 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241111T170354/Courier0031.lzma
2024-11-11T18:00:11.358Z,1731348011.358 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0031.lzma.bak
2024-11-11T18:00:11.358Z,1731348011.358 [DataOverHttps](INFO): SBD MOMSN=23389474
2024-11-11T18:00:15.119Z,1731348015.119 [NAL9602](INFO): SBD MO Status=0, MOMSN=59780, MT Status=0, MTMSN=0
2024-11-11T18:00:15.119Z,1731348015.119 [NAL9602](INFO): No messages in MT queue
2024-11-11T18:00:30.080Z,1731348030.080 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20241111T170354/Express0032.lzma
2024-11-11T18:00:31.082Z,1731348031.082 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0032.lzma.bak
2024-11-11T18:00:31.082Z,1731348031.082 [DataOverHttps](INFO): SBD MOMSN=23389477
2024-11-11T18:00:32.137Z,1731348032.137 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T18:00:32.137Z,1731348032.137 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T18:00:32.137Z,1731348032.137 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T18:00:45.856Z,1731348045.856 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T18:05:32.719Z,1731348332.719 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T18:05:32.719Z,1731348332.719 [Default:CheckIn:C.Wait] Stopped
2024-11-11T18:05:32.719Z,1731348332.719 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T18:05:32.719Z,1731348332.719 [Default:CheckIn:D] Running Loop=1
2024-11-11T18:05:33.118Z,1731348333.118 [Default:CheckIn:D] Stopped
2024-11-11T18:05:33.118Z,1731348333.118 [Default:CheckIn:E] Running Loop=1
2024-11-11T18:05:33.531Z,1731348333.531 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.765068 min
2024-11-11T18:05:33.531Z,1731348333.531 [Default:CheckIn:E] Stopped
2024-11-11T18:05:33.531Z,1731348333.531 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T18:05:33.531Z,1731348333.531 [Default:CheckIn] Stopped
2024-11-11T18:05:33.532Z,1731348333.532 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T18:05:33.532Z,1731348333.532 [Default:CheckIn](INFO): Running loop #10
2024-11-11T18:05:33.532Z,1731348333.532 [Default:CheckIn] Running Loop=10
2024-11-11T18:05:33.532Z,1731348333.532 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T18:05:33.532Z,1731348333.532 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T18:05:35.539Z,1731348335.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180534.00,A,3648.14983,N,12147.22316,W,0.039,26.05,111124,,,D*44
2024-11-11T18:05:35.542Z,1731348335.542 [NAL9602](INFO): GPS fix at 20241111T180534: (36.802497, -121.787053)
2024-11-11T18:05:35.553Z,1731348335.553 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T18:05:35.553Z,1731348335.553 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T18:05:43.540Z,1731348343.540 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241111T170354/Courier0034.lzma
2024-11-11T18:05:44.543Z,1731348344.543 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0034.lzma.bak
2024-11-11T18:05:44.543Z,1731348344.543 [DataOverHttps](INFO): SBD MOMSN=23389485
2024-11-11T18:05:59.774Z,1731348359.774 [NAL9602](INFO): SBD MO Status=0, MOMSN=59781, MT Status=0, MTMSN=0
2024-11-11T18:05:59.774Z,1731348359.774 [NAL9602](INFO): No messages in MT queue
2024-11-11T18:06:00.285Z,1731348360.285 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241111T170354/Express0035.lzma
2024-11-11T18:06:01.286Z,1731348361.286 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0035.lzma.bak
2024-11-11T18:06:01.286Z,1731348361.286 [DataOverHttps](INFO): SBD MOMSN=23389488
2024-11-11T18:06:02.623Z,1731348362.623 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T18:06:02.623Z,1731348362.623 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T18:06:02.623Z,1731348362.623 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T18:06:30.481Z,1731348390.481 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T18:11:03.205Z,1731348663.205 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T18:11:03.205Z,1731348663.205 [Default:CheckIn:C.Wait] Stopped
2024-11-11T18:11:03.205Z,1731348663.205 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T18:11:03.205Z,1731348663.205 [Default:CheckIn:D] Running Loop=1
2024-11-11T18:11:03.612Z,1731348663.612 [Default:CheckIn:D] Stopped
2024-11-11T18:11:03.612Z,1731348663.612 [Default:CheckIn:E] Running Loop=1
2024-11-11T18:11:04.003Z,1731348664.003 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.273291 min
2024-11-11T18:11:04.003Z,1731348664.003 [Default:CheckIn:E] Stopped
2024-11-11T18:11:04.003Z,1731348664.003 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T18:11:04.003Z,1731348664.003 [Default:CheckIn] Stopped
2024-11-11T18:11:04.003Z,1731348664.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T18:11:04.003Z,1731348664.003 [Default:CheckIn](INFO): Running loop #11
2024-11-11T18:11:04.003Z,1731348664.003 [Default:CheckIn] Running Loop=11
2024-11-11T18:11:04.004Z,1731348664.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T18:11:04.004Z,1731348664.004 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T18:11:06.032Z,1731348666.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181105.00,A,3648.14926,N,12147.22304,W,0.058,26.05,111124,,,D*48
2024-11-11T18:11:06.034Z,1731348666.034 [NAL9602](INFO): GPS fix at 20241111T181105: (36.802488, -121.787051)
2024-11-11T18:11:06.074Z,1731348666.074 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T18:11:06.074Z,1731348666.074 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T18:11:13.388Z,1731348673.388 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241111T170354/Courier0037.lzma
2024-11-11T18:11:13.730Z,1731348673.730 [NAL9602](INFO): SBD MO Status=0, MOMSN=59782, MT Status=0, MTMSN=0
2024-11-11T18:11:13.730Z,1731348673.730 [NAL9602](INFO): No messages in MT queue
2024-11-11T18:11:14.390Z,1731348674.390 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0037.lzma.bak
2024-11-11T18:11:14.391Z,1731348674.391 [DataOverHttps](INFO): SBD MOMSN=23389496
2024-11-11T18:11:30.156Z,1731348690.156 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20241111T170354/Express0038.lzma
2024-11-11T18:11:31.158Z,1731348691.158 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0038.lzma.bak
2024-11-11T18:11:31.159Z,1731348691.159 [DataOverHttps](INFO): SBD MOMSN=23389499
2024-11-11T18:11:32.295Z,1731348692.295 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T18:11:32.295Z,1731348692.295 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T18:11:32.295Z,1731348692.295 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T18:11:44.397Z,1731348704.397 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T18:16:32.881Z,1731348992.881 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T18:16:32.881Z,1731348992.881 [Default:CheckIn:C.Wait] Stopped
2024-11-11T18:16:32.881Z,1731348992.881 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T18:16:32.881Z,1731348992.881 [Default:CheckIn:D] Running Loop=1
2024-11-11T18:16:33.281Z,1731348993.281 [Default:CheckIn:D] Stopped
2024-11-11T18:16:33.281Z,1731348993.281 [Default:CheckIn:E] Running Loop=1
2024-11-11T18:16:33.699Z,1731348993.699 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.767782 min
2024-11-11T18:16:33.699Z,1731348993.699 [Default:CheckIn:E] Stopped
2024-11-11T18:16:33.699Z,1731348993.699 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T18:16:33.699Z,1731348993.699 [Default:CheckIn] Stopped
2024-11-11T18:16:33.699Z,1731348993.699 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T18:16:33.699Z,1731348993.699 [Default:CheckIn](INFO): Running loop #12
2024-11-11T18:16:33.699Z,1731348993.699 [Default:CheckIn] Running Loop=12
2024-11-11T18:16:33.699Z,1731348993.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T18:16:33.699Z,1731348993.699 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T18:16:35.699Z,1731348995.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181634.00,A,3648.40757,N,12147.11355,W,5.870,7.99,111124,,,D*71
2024-11-11T18:16:35.702Z,1731348995.702 [NAL9602](INFO): GPS fix at 20241111T181634: (36.806793, -121.785226)
2024-11-11T18:16:35.712Z,1731348995.712 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T18:16:35.712Z,1731348995.712 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T18:16:43.292Z,1731349003.292 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241111T170354/Courier0040.lzma
2024-11-11T18:16:44.296Z,1731349004.296 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0040.lzma.bak
2024-11-11T18:16:44.297Z,1731349004.297 [DataOverHttps](INFO): SBD MOMSN=23389507
2024-11-11T18:16:49.071Z,1731349009.071 [NAL9602](INFO): SBD MO Status=0, MOMSN=59783, MT Status=0, MTMSN=0
2024-11-11T18:16:49.071Z,1731349009.071 [NAL9602](INFO): No messages in MT queue
2024-11-11T18:17:02.968Z,1731349022.968 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20241111T170354/Express0041.lzma
2024-11-11T18:17:03.970Z,1731349023.970 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0041.lzma.bak
2024-11-11T18:17:03.970Z,1731349023.970 [DataOverHttps](INFO): SBD MOMSN=23389510
2024-11-11T18:17:05.263Z,1731349025.263 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T18:17:05.263Z,1731349025.263 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T18:17:05.263Z,1731349025.263 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T18:17:19.796Z,1731349039.796 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T18:22:05.839Z,1731349325.839 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T18:22:05.839Z,1731349325.839 [Default:CheckIn:C.Wait] Stopped
2024-11-11T18:22:05.839Z,1731349325.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T18:22:05.839Z,1731349325.839 [Default:CheckIn:D] Running Loop=1
2024-11-11T18:22:06.245Z,1731349326.245 [Default:CheckIn:D] Stopped
2024-11-11T18:22:06.246Z,1731349326.246 [Default:CheckIn:E] Running Loop=1
2024-11-11T18:22:06.649Z,1731349326.649 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.317188 min
2024-11-11T18:22:06.649Z,1731349326.649 [Default:CheckIn:E] Stopped
2024-11-11T18:22:06.649Z,1731349326.649 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T18:22:06.649Z,1731349326.649 [Default:CheckIn] Stopped
2024-11-11T18:22:06.649Z,1731349326.649 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T18:22:06.649Z,1731349326.649 [Default:CheckIn](INFO): Running loop #13
2024-11-11T18:22:06.649Z,1731349326.649 [Default:CheckIn] Running Loop=13
2024-11-11T18:22:06.649Z,1731349326.649 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T18:22:06.649Z,1731349326.649 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T18:22:08.663Z,1731349328.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182207.00,A,3648.15992,N,12148.66959,W,3.246,254.30,111124,,,D*76
2024-11-11T18:22:08.674Z,1731349328.674 [NAL9602](INFO): GPS fix at 20241111T182207: (36.802665, -121.811160)
2024-11-11T18:22:08.685Z,1731349328.685 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T18:22:08.685Z,1731349328.685 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T18:22:17.372Z,1731349337.372 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241111T170354/Courier0043.lzma
2024-11-11T18:22:18.374Z,1731349338.374 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0043.lzma.bak
2024-11-11T18:22:18.374Z,1731349338.374 [DataOverHttps](INFO): SBD MOMSN=23389518
2024-11-11T18:22:24.863Z,1731349344.863 [NAL9602](INFO): SBD MO Status=0, MOMSN=59784, MT Status=0, MTMSN=0
2024-11-11T18:22:24.863Z,1731349344.863 [NAL9602](INFO): No messages in MT queue
2024-11-11T18:22:38.296Z,1731349358.296 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20241111T170354/Express0044.lzma
2024-11-11T18:22:39.298Z,1731349359.298 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0044.lzma.bak
2024-11-11T18:22:39.298Z,1731349359.298 [DataOverHttps](INFO): SBD MOMSN=23389521
2024-11-11T18:22:40.642Z,1731349360.642 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T18:22:40.642Z,1731349360.642 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T18:22:40.642Z,1731349360.642 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T18:22:55.578Z,1731349375.578 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T18:23:00.421Z,1731349380.421 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-11-11T18:23:13.348Z,1731349393.348 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-11-11T18:23:25.872Z,1731349405.872 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-11-11T18:23:38.799Z,1731349418.799 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-11-11T18:23:52.134Z,1731349432.134 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-11-11T18:24:05.475Z,1731349445.475 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-11-11T18:24:18.001Z,1731349458.001 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-11-11T18:26:50.406Z,1731349610.406 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:27:02.463Z,1731349622.463 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:27:14.179Z,1731349634.179 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:27:26.718Z,1731349646.718 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:27:39.241Z,1731349659.241 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:27:41.290Z,1731349661.290 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-11T18:27:41.290Z,1731349661.290 [Default:CheckIn:C.Wait] Stopped
2024-11-11T18:27:41.290Z,1731349661.290 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-11T18:27:41.290Z,1731349661.290 [Default:CheckIn:D] Running Loop=1
2024-11-11T18:27:41.675Z,1731349661.675 [Default:CheckIn:D] Stopped
2024-11-11T18:27:41.675Z,1731349661.675 [Default:CheckIn:E] Running Loop=1
2024-11-11T18:27:42.086Z,1731349662.086 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.907682 min
2024-11-11T18:27:42.087Z,1731349662.087 [Default:CheckIn:E] Stopped
2024-11-11T18:27:42.087Z,1731349662.087 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-11T18:27:42.087Z,1731349662.087 [Default:CheckIn] Stopped
2024-11-11T18:27:42.087Z,1731349662.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-11T18:27:42.087Z,1731349662.087 [Default:CheckIn](INFO): Running loop #14
2024-11-11T18:27:42.087Z,1731349662.087 [Default:CheckIn] Running Loop=14
2024-11-11T18:27:42.087Z,1731349662.087 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-11T18:27:42.087Z,1731349662.087 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-11T18:27:44.095Z,1731349664.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182743.00,A,3647.82940,N,12150.87336,W,0.078,85.85,111124,,,D*44
2024-11-11T18:27:44.106Z,1731349664.106 [NAL9602](INFO): GPS fix at 20241111T182743: (36.797157, -121.847889)
2024-11-11T18:27:44.118Z,1731349664.118 [Default:CheckIn:Read_GPS] Stopped
2024-11-11T18:27:44.118Z,1731349664.118 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-11T18:27:51.359Z,1731349671.359 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:27:54.752Z,1731349674.752 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241111T170354/Courier0046.lzma
2024-11-11T18:27:54.999Z,1731349674.999 [NAL9602](INFO): SBD MO Status=0, MOMSN=59785, MT Status=0, MTMSN=0
2024-11-11T18:27:54.999Z,1731349674.999 [NAL9602](INFO): No messages in MT queue
2024-11-11T18:27:55.754Z,1731349675.754 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Courier0046.lzma.bak
2024-11-11T18:27:55.754Z,1731349675.754 [DataOverHttps](INFO): SBD MOMSN=23389529
2024-11-11T18:28:03.479Z,1731349683.479 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:28:16.004Z,1731349696.004 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:28:21.200Z,1731349701.200 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20241111T170354/Express0047.lzma
2024-11-11T18:28:22.202Z,1731349702.202 [DataOverHttps](INFO): Moved sent file to Logs/20241111T170354/Express0047.lzma.bak
2024-11-11T18:28:22.203Z,1731349702.203 [DataOverHttps](INFO): SBD MOMSN=23389533
2024-11-11T18:28:23.335Z,1731349703.335 [Default:CheckIn:Read_Iridium] Stopped
2024-11-11T18:28:23.336Z,1731349703.336 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-11T18:28:23.336Z,1731349703.336 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-11T18:28:25.734Z,1731349705.734 [NAL9602](INFO): Not Powering down - fast GPS
2024-11-11T18:28:28.150Z,1731349708.150 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:28:39.871Z,1731349719.871 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:28:51.579Z,1731349731.579 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:29:03.699Z,1731349743.699 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-11-11T18:29:17.047Z,1731349757.047 [BPC1](ERROR): Battery stick #30 (s/n: 01B7) reported OVER_TEMP_ALARM. Status code: 0x1717.
2024-11-11T18:29:17.048Z,1731349757.048 [BPC1](ERROR): Battery stick #62 (s/n: 01FE) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2024-11-11T18:29:17.068Z,1731349757.068 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2024-11-11T18:29:17.071Z,1731349757.071 [BPC1](INFO): Received data from all battery sticks.
2024-11-11T18:31:33.514Z,1731349893.514 [DataOverHttps](IMPORTANT): SBD MTMSN=20241111T183132
2024-11-11T18:31:40.848Z,1731349900.848 [DataOverHttps](INFO): Received command: restart logs