2022-06-09T18:16:54.784Z,1654798614.784 [Supervisor](DEBUG): Initializing supervisor.
2022-06-09T18:16:54.789Z,1654798614.789 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-06-09T18:16:54.790Z,1654798614.790 [SyncHandler](INFO): Protected caller Thread ID is 7041
2022-06-09T18:16:54.790Z,1654798614.790 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-06-09T18:16:54.791Z,1654798614.791 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-06-09T18:16:54.792Z,1654798614.792 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7042
2022-06-09T18:16:54.796Z,1654798614.796 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-06-09T18:16:54.816Z,1654798614.816 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-06-09T18:16:54.817Z,1654798614.817 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-06-09T18:16:54.817Z,1654798614.817 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7043
2022-06-09T18:16:54.821Z,1654798614.821 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-06-09T18:16:54.822Z,1654798614.822 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-06-09T18:16:54.823Z,1654798614.823 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7044
2022-06-09T18:16:54.825Z,1654798614.825 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-06-09T18:16:54.826Z,1654798614.826 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-06-09T18:16:54.826Z,1654798614.826 [logger ThreadHandler](INFO): Protected caller Thread ID is 7045
2022-06-09T18:16:54.830Z,1654798614.830 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-06-09T18:16:54.831Z,1654798614.831 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-06-09T18:16:54.832Z,1654798614.832 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-06-09T18:16:55.278Z,1654798615.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-06-09T18:16:55.279Z,1654798615.279 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-06-09T18:16:55.511Z,1654798615.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-06-09T18:16:55.511Z,1654798615.511 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-06-09T18:16:55.716Z,1654798615.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-06-09T18:16:55.716Z,1654798615.716 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-06-09T18:16:55.826Z,1654798615.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-06-09T18:16:55.827Z,1654798615.827 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-06-09T18:16:56.208Z,1654798616.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-06-09T18:16:56.209Z,1654798616.209 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-06-09T18:16:56.554Z,1654798616.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-06-09T18:16:56.554Z,1654798616.554 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-06-09T18:16:56.652Z,1654798616.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-06-09T18:16:56.652Z,1654798616.652 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-06-09T18:16:56.877Z,1654798616.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-06-09T18:16:56.878Z,1654798616.878 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-06-09T18:16:57.470Z,1654798617.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-06-09T18:16:57.471Z,1654798617.471 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-06-09T18:16:57.842Z,1654798617.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-06-09T18:16:57.842Z,1654798617.842 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-06-09T18:16:57.923Z,1654798617.923 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-06-09T18:16:58.143Z,1654798618.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-06-09T18:16:58.143Z,1654798618.143 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-06-09T18:16:58.273Z,1654798618.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-06-09T18:16:58.274Z,1654798618.274 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-06-09T18:16:59.032Z,1654798619.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-06-09T18:16:59.032Z,1654798619.032 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-06-09T18:16:59.112Z,1654798619.112 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-06-09T18:16:59.451Z,1654798619.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-06-09T18:16:59.453Z,1654798619.453 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2022-06-09T18:16:59.454Z,1654798619.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2022-06-09T18:17:00.288Z,1654798620.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2022-06-09T18:17:00.385Z,1654798620.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2022-06-09T18:17:00.505Z,1654798620.505 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2022-06-09T18:17:00.608Z,1654798620.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2022-06-09T18:17:00.691Z,1654798620.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2022-06-09T18:17:00.777Z,1654798620.777 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2022-06-09T18:17:00.894Z,1654798620.894 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2022-06-09T18:17:00.995Z,1654798620.995 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2022-06-09T18:17:01.154Z,1654798621.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2022-06-09T18:17:01.423Z,1654798621.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-06-09T18:17:01.424Z,1654798621.424 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2022-06-09T18:17:01.520Z,1654798621.520 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2022-06-09T18:17:01.521Z,1654798621.521 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-06-09T18:17:01.532Z,1654798621.532 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-06-09T18:17:01.859Z,1654798621.859 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-06-09T18:17:01.859Z,1654798621.859 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-06-09T18:17:01.974Z,1654798621.974 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-06-09T18:17:01.975Z,1654798621.975 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-06-09T18:17:02.014Z,1654798622.014 [DeadReckonUsingSpeedCalculator] Loaded
2022-06-09T18:17:02.014Z,1654798622.014 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2022-06-09T18:17:02.028Z,1654798622.028 [NavChart] Loaded
2022-06-09T18:17:02.028Z,1654798622.028 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-06-09T18:17:02.033Z,1654798622.033 [UniversalFixResidualReporter] Loaded
2022-06-09T18:17:02.034Z,1654798622.034 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-06-09T18:17:02.034Z,1654798622.034 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-06-09T18:17:02.035Z,1654798622.035 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-06-09T18:17:02.045Z,1654798622.045 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-06-09T18:17:02.046Z,1654798622.046 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-06-09T18:17:02.244Z,1654798622.244 [CTD_Seabird] Loaded
2022-06-09T18:17:02.245Z,1654798622.245 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-06-09T18:17:02.246Z,1654798622.246 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406614E0
2022-06-09T18:17:02.246Z,1654798622.246 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7127
2022-06-09T18:17:02.267Z,1654798622.267 [PAR_Licor] Loaded
2022-06-09T18:17:02.267Z,1654798622.267 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-06-09T18:17:02.298Z,1654798622.298 [WetLabsBB2FL] Loaded
2022-06-09T18:17:02.299Z,1654798622.299 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-06-09T18:17:02.300Z,1654798622.300 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406914E0
2022-06-09T18:17:02.300Z,1654798622.300 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7128
2022-06-09T18:17:02.301Z,1654798622.301 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-06-09T18:17:02.301Z,1654798622.301 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-06-09T18:17:02.354Z,1654798622.354 [VerticalControl](DEBUG): Construct VerticalControl.
2022-06-09T18:17:02.417Z,1654798622.417 [VerticalControl] Loaded
2022-06-09T18:17:02.417Z,1654798622.417 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-06-09T18:17:02.420Z,1654798622.420 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-06-09T18:17:02.460Z,1654798622.460 [HorizontalControl] Loaded
2022-06-09T18:17:02.460Z,1654798622.460 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-06-09T18:17:02.463Z,1654798622.463 [SpeedControl](DEBUG): Construct SpeedControl.
2022-06-09T18:17:02.466Z,1654798622.466 [SpeedControl] Loaded
2022-06-09T18:17:02.466Z,1654798622.466 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-06-09T18:17:02.469Z,1654798622.469 [LoopControl](DEBUG): Construct LoopControl.
2022-06-09T18:17:02.470Z,1654798622.470 [LoopControl] Loaded
2022-06-09T18:17:02.470Z,1654798622.470 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-06-09T18:17:02.470Z,1654798622.470 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-06-09T18:17:02.471Z,1654798622.471 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-06-09T18:17:02.522Z,1654798622.522 [DepthRateCalculator] Loaded
2022-06-09T18:17:02.522Z,1654798622.522 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-06-09T18:17:02.528Z,1654798622.528 [PitchRateCalculator] Loaded
2022-06-09T18:17:02.528Z,1654798622.528 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-06-09T18:17:02.537Z,1654798622.537 [SpeedCalculator] Loaded
2022-06-09T18:17:02.538Z,1654798622.538 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-06-09T18:17:02.542Z,1654798622.542 [YawRateCalculator] Loaded
2022-06-09T18:17:02.542Z,1654798622.542 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-06-09T18:17:02.560Z,1654798622.560 [ElevatorOffsetCalculator] Loaded
2022-06-09T18:17:02.560Z,1654798622.560 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-06-09T18:17:02.561Z,1654798622.561 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-06-09T18:17:02.562Z,1654798622.562 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-06-09T18:17:02.661Z,1654798622.661 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-06-09T18:17:02.662Z,1654798622.662 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-06-09T18:17:02.722Z,1654798622.722 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-06-09T18:17:02.722Z,1654798622.722 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-06-09T18:17:02.851Z,1654798622.851 [BuoyancyServo] Loaded
2022-06-09T18:17:02.852Z,1654798622.852 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-06-09T18:17:02.872Z,1654798622.872 [ElevatorServo] Loaded
2022-06-09T18:17:02.872Z,1654798622.872 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-06-09T18:17:02.891Z,1654798622.891 [MassServo] Loaded
2022-06-09T18:17:02.891Z,1654798622.891 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-06-09T18:17:02.910Z,1654798622.910 [RudderServo] Loaded
2022-06-09T18:17:02.910Z,1654798622.910 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-06-09T18:17:02.926Z,1654798622.926 [ThrusterHE] Loaded
2022-06-09T18:17:02.926Z,1654798622.926 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-06-09T18:17:02.926Z,1654798622.926 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-06-09T18:17:02.927Z,1654798622.927 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-06-09T18:17:03.040Z,1654798623.040 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-06-09T18:17:03.041Z,1654798623.041 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-06-09T18:17:03.451Z,1654798623.451 [AHRS_M2] Loaded
2022-06-09T18:17:03.451Z,1654798623.451 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-06-09T18:17:03.487Z,1654798623.487 [BackseatComponent] Loaded
2022-06-09T18:17:03.487Z,1654798623.487 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-06-09T18:17:03.488Z,1654798623.488 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A894E0
2022-06-09T18:17:03.489Z,1654798623.489 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7129
2022-06-09T18:17:03.492Z,1654798623.492 [LcmUniversalReporter] Loaded
2022-06-09T18:17:03.492Z,1654798623.492 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-06-09T18:17:04.349Z,1654798624.349 [BPC1] Loaded
2022-06-09T18:17:04.349Z,1654798624.349 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-06-09T18:17:04.425Z,1654798624.425 [DataOverHttps] Loaded
2022-06-09T18:17:04.425Z,1654798624.425 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-06-09T18:17:04.426Z,1654798624.426 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AB94E0
2022-06-09T18:17:04.427Z,1654798624.427 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7130
2022-06-09T18:17:04.447Z,1654798624.447 [Depth_Keller] Loaded
2022-06-09T18:17:04.447Z,1654798624.447 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-06-09T18:17:04.452Z,1654798624.452 [DropWeight] Loaded
2022-06-09T18:17:04.452Z,1654798624.452 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-06-09T18:17:04.514Z,1654798624.514 [NAL9602] Loaded
2022-06-09T18:17:04.514Z,1654798624.514 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-06-09T18:17:04.544Z,1654798624.544 [Onboard] Loaded
2022-06-09T18:17:04.545Z,1654798624.545 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-06-09T18:17:04.546Z,1654798624.546 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AE94E0
2022-06-09T18:17:04.546Z,1654798624.546 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7131
2022-06-09T18:17:04.552Z,1654798624.552 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2022-06-09T18:17:04.564Z,1654798624.564 [PowerOnly] Loaded
2022-06-09T18:17:04.564Z,1654798624.564 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2022-06-09T18:17:04.576Z,1654798624.576 [Power24vConverter] Loaded
2022-06-09T18:17:04.576Z,1654798624.576 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-06-09T18:17:04.590Z,1654798624.590 [Radio_Surface] Loaded
2022-06-09T18:17:04.590Z,1654798624.590 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-06-09T18:17:04.591Z,1654798624.591 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B194E0
2022-06-09T18:17:04.591Z,1654798624.591 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7132
2022-06-09T18:17:04.681Z,1654798624.681 [DAT] Loaded
2022-06-09T18:17:04.681Z,1654798624.681 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-06-09T18:17:04.682Z,1654798624.682 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B494E0
2022-06-09T18:17:04.683Z,1654798624.683 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7133
2022-06-09T18:17:04.683Z,1654798624.683 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-06-09T18:17:04.684Z,1654798624.684 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-06-09T18:17:05.342Z,1654798625.342 [SBIT](DEBUG): Construct Startup Built In Test.
2022-06-09T18:17:05.364Z,1654798625.364 [SBIT] Loaded
2022-06-09T18:17:05.364Z,1654798625.364 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-06-09T18:17:05.367Z,1654798625.367 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-06-09T18:17:05.394Z,1654798625.394 [IBIT] Loaded
2022-06-09T18:17:05.395Z,1654798625.395 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-06-09T18:17:05.401Z,1654798625.401 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-06-09T18:17:05.514Z,1654798625.514 [CBIT] Loaded
2022-06-09T18:17:05.514Z,1654798625.514 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-06-09T18:17:05.515Z,1654798625.515 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-06-09T18:17:05.515Z,1654798625.515 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-06-09T18:17:05.543Z,1654798625.543 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-06-09T18:17:05.549Z,1654798625.549 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-06-09T18:17:05.552Z,1654798625.552 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-06-09T18:17:05.563Z,1654798625.563 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-06-09T18:17:05.564Z,1654798625.564 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2022-06-09T18:17:05.565Z,1654798625.565 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7134
2022-06-09T18:17:05.569Z,1654798625.569 [Supervisor](INFO): Main Thread ID is 6690
2022-06-09T18:17:05.569Z,1654798625.569 [Supervisor](DEBUG): Running supervisor.
2022-06-09T18:17:05.570Z,1654798625.570 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7135
2022-06-09T18:17:05.571Z,1654798625.571 [CommandExec](INFO): Initializing the command executive.
2022-06-09T18:17:05.572Z,1654798625.572 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7136
2022-06-09T18:17:05.574Z,1654798625.574 [controlThread ThreadHandler](INFO): Handler Thread ID is 7137
2022-06-09T18:17:05.575Z,1654798625.575 [controlThread](DEBUG): Initializing ControlThread
2022-06-09T18:17:05.576Z,1654798625.576 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-06-09T18:17:05.577Z,1654798625.577 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-06-09T18:17:05.577Z,1654798625.577 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-06-09T18:17:05.580Z,1654798625.580 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-06-09T18:17:05.581Z,1654798625.581 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-06-09T18:17:05.582Z,1654798625.582 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-06-09T18:17:05.582Z,1654798625.582 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-06-09T18:17:05.583Z,1654798625.583 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-06-09T18:17:05.583Z,1654798625.583 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-06-09T18:17:05.583Z,1654798625.583 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-06-09T18:17:05.584Z,1654798625.584 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-06-09T18:17:05.584Z,1654798625.584 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-06-09T18:17:05.590Z,1654798625.590 [SBIT](INFO): Initialize SBIT Component.
2022-06-09T18:17:05.590Z,1654798625.590 [SBIT](IMPORTANT): git: 2022-06-09A
2022-06-09T18:17:05.591Z,1654798625.591 [SBIT](INFO): git hash: 1bf64768ecb3a3dab2506d81722262f698222fdf
2022-06-09T18:17:05.591Z,1654798625.591 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-06-09T18:17:05.592Z,1654798625.592 [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
2022-06-09T18:17:05.593Z,1654798625.593 [SBIT](INFO): Beginning SBIT in 94.000000 seconds.
2022-06-09T18:17:05.594Z,1654798625.594 [IBIT](INFO): Initialize IBIT Component.
2022-06-09T18:17:05.595Z,1654798625.595 [CBIT](DEBUG): Initialize CBIT Component.
2022-06-09T18:17:05.596Z,1654798625.596 [logger ThreadHandler](INFO): Handler Thread ID is 7138
2022-06-09T18:17:05.612Z,1654798625.612 [CBIT](DEBUG): Initialized mux pins.
2022-06-09T18:17:05.612Z,1654798625.612 [CBIT](DEBUG): Initializing the watchdog timer.
2022-06-09T18:17:05.613Z,1654798625.613 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7139
2022-06-09T18:17:05.613Z,1654798625.613 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-06-09T18:17:05.618Z,1654798625.618 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7141
2022-06-09T18:17:05.620Z,1654798625.620 [WetLabsBB2FL](INFO): Powering up
2022-06-09T18:17:05.621Z,1654798625.621 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7143
2022-06-09T18:17:05.632Z,1654798625.632 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7144
2022-06-09T18:17:05.633Z,1654798625.633 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-06-09T18:17:05.636Z,1654798625.636 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-06-09T18:17:05.636Z,1654798625.636 [CBIT](DEBUG): Initializing heartbeat.
2022-06-09T18:17:05.644Z,1654798625.644 [Onboard ThreadHandler](INFO): Handler Thread ID is 7145
2022-06-09T18:17:05.662Z,1654798625.662 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7146
2022-06-09T18:17:05.676Z,1654798625.676 [DAT ThreadHandler](INFO): Handler Thread ID is 7147
2022-06-09T18:17:05.677Z,1654798625.677 [DAT](INFO): Powering up
2022-06-09T18:17:05.677Z,1654798625.677 [DAT](DEBUG): Initializing DAT.
2022-06-09T18:17:05.681Z,1654798625.681 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7149
2022-06-09T18:17:05.684Z,1654798625.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-06-09T18:17:05.684Z,1654798625.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-06-09T18:17:05.685Z,1654798625.685 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-06-09T18:17:05.685Z,1654798625.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-06-09T18:17:05.685Z,1654798625.685 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-06-09T18:17:05.685Z,1654798625.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-06-09T18:17:05.685Z,1654798625.685 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-06-09T18:17:05.685Z,1654798625.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-06-09T18:17:05.708Z,1654798625.708 [CBIT](DEBUG): Deactivating GF circuits.
2022-06-09T18:17:05.708Z,1654798625.708 [CBIT](DEBUG): Deactivating emergency mode.
2022-06-09T18:17:05.744Z,1654798625.744 [CBIT](DEBUG): Backplane powered.
2022-06-09T18:17:05.745Z,1654798625.745 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-06-09T18:17:05.757Z,1654798625.757 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-06-09T18:17:05.784Z,1654798625.784 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2022-06-09T18:17:05.785Z,1654798625.785 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-06-09T18:17:05.856Z,1654798625.856 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-06-09T18:17:05.857Z,1654798625.857 [Default:A.Wait](DEBUG): Construct Wait.
2022-06-09T18:17:05.860Z,1654798625.860 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-06-09T18:17:05.905Z,1654798625.905 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-06-09T18:17:05.908Z,1654798625.908 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-06-09T18:17:05.942Z,1654798625.942 [Default:E.Execute](DEBUG): Construct Execute.
2022-06-09T18:17:05.953Z,1654798625.953 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2022-06-09T18:17:05.958Z,1654798625.958 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-06-09T18:17:05.985Z,1654798625.985 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-06-09T18:17:06.058Z,1654798626.058 [Power24vConverter](INFO): Powering up.
2022-06-09T18:17:06.068Z,1654798626.068 [Radio_Surface](INFO): Powering up
2022-06-09T18:17:06.116Z,1654798626.116 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-06-09T18:17:06.120Z,1654798626.120 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-06-09T18:17:06.122Z,1654798626.122 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-06-09T18:17:06.128Z,1654798626.128 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-06-09T18:17:06.129Z,1654798626.129 [MassServo](DEBUG): Initializing EZServoServo.
2022-06-09T18:17:06.140Z,1654798626.140 [MassServo](DEBUG): Initializing MassServo.
2022-06-09T18:17:06.141Z,1654798626.141 [RudderServo](DEBUG): Initializing EZServoServo.
2022-06-09T18:17:06.148Z,1654798626.148 [RudderServo](DEBUG): Initializing RudderServo.
2022-06-09T18:17:06.149Z,1654798626.149 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-06-09T18:17:06.156Z,1654798626.156 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-06-09T18:17:07.108Z,1654798627.108 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-06-09T18:17:07.108Z,1654798627.108 [RudderServo](FAULT): Rudder failed to initialize
2022-06-09T18:17:07.108Z,1654798627.108 [RudderServo] Communications Fault, FailCount= 1
2022-06-09T18:17:07.108Z,1654798627.108 [RudderServo](ERROR): Communications Fault
2022-06-09T18:17:07.112Z,1654798627.112 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-06-09T18:17:07.314Z,1654798627.314 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-06-09T18:17:07.315Z,1654798627.315 [RudderServo](INFO): Powering down
2022-06-09T18:17:08.012Z,1654798628.012 [RudderServo](DEBUG): Initializing EZServoServo.
2022-06-09T18:17:08.133Z,1654798628.133 [RudderServo](DEBUG): Initializing RudderServo.
2022-06-09T18:17:08.137Z,1654798628.137 [CBIT](INFO): Clearing failed state for component RudderServo
2022-06-09T18:17:08.137Z,1654798628.137 [RudderServo] No Fault, FailCount= 1
2022-06-09T18:17:08.232Z,1654798628.232 [WetLabsBB2FL](INFO): Powering down
2022-06-09T18:17:11.908Z,1654798631.908 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-06-09T18:17:20.547Z,1654798640.547 [DAT](INFO): commRate: 800
2022-06-09T18:17:22.616Z,1654798642.616 [DAT](INFO): entering command mode
2022-06-09T18:17:22.813Z,1654798642.813 [DAT](INFO): setting verbose to 3
2022-06-09T18:17:23.065Z,1654798643.065 [DAT](INFO): set verbose to 3
2022-06-09T18:17:23.066Z,1654798643.066 [DAT](INFO): setting DatVerbose to 27440
2022-06-09T18:17:23.317Z,1654798643.317 [DAT](INFO): set DatVerbose to 27440
2022-06-09T18:17:23.318Z,1654798643.318 [DAT](INFO): setting transmit power to 8
2022-06-09T18:17:23.569Z,1654798643.569 [DAT](INFO): set transmit power to 8
2022-06-09T18:17:23.570Z,1654798643.570 [DAT](INFO): setting local address to 10
2022-06-09T18:17:23.822Z,1654798643.822 [DAT](INFO): set local address to 10
2022-06-09T18:17:34.662Z,1654798654.662 [NAL9602](INFO): Powering up NAL9602
2022-06-09T18:17:45.570Z,1654798665.570 [NAL9602](INFO): NAL9602 initialized
2022-06-09T18:18:40.122Z,1654798720.122 [SBIT](IMPORTANT): Beginning Startup BIT
2022-06-09T18:18:40.126Z,1654798720.126 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-09T18:18:51.101Z,1654798731.101 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008764
CHAN A1 (24V): 0.000333
CHAN A2 (12V): -0.005116
CHAN A3 (5V): -0.002942
CHAN B0 (3.3V): -0.000802
CHAN B1 (3.15aV): -0.001241
CHAN B2 (3.15bV): -0.001356
CHAN B3 (GND): -0.001036
OPEN: 0.004643
Full Scale: +/- 1 mA
2022-06-09T18:18:57.496Z,1654798737.496 [CommandExec](IMPORTANT): got command failComponent
2022-06-09T18:18:57.497Z,1654798737.497 [CommandExec](IMPORTANT): Failed components:
2022-06-09T18:18:57.497Z,1654798737.497 [CommandExec](IMPORTANT): No failed Components.
2022-06-09T18:19:33.698Z,1654798773.698 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004880
2022-06-09T18:19:34.280Z,1654798774.280 [SBIT](IMPORTANT): SBIT PASSED
2022-06-09T18:19:34.280Z,1654798774.280 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-06-09T18:19:34.281Z,1654798774.281 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool;

2022-06-09T18:19:34.281Z,1654798774.281 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=6 none;

2022-06-09T18:19:34.281Z,1654798774.281 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere;

2022-06-09T18:19:34.282Z,1654798774.282 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2022-06-09T18:19:34.282Z,1654798774.282 [SBIT](IMPORTANT): Express none _.ayeris_disk_space;

2022-06-09T18:19:34.282Z,1654798774.282 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 250.000000 count;

2022-06-09T18:19:34.282Z,1654798774.282 [SBIT](IMPORTANT): Express none _.ayeris_particle_size;

2022-06-09T18:19:34.282Z,1654798774.282 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour;

2022-06-09T18:19:34.282Z,1654798774.282 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt;

2022-06-09T18:19:34.282Z,1654798774.282 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=298.384344 cubic_centimeter;

2022-06-09T18:19:34.282Z,1654798774.282 [SBIT](IMPORTANT): VerticalControl.massDefault=18.516984 millimeter;

2022-06-09T18:19:34.668Z,1654798774.668 [MissionManager](IMPORTANT): Started mission Startup
2022-06-09T18:19:34.668Z,1654798774.668 [Startup] Running Loop=1
2022-06-09T18:19:34.669Z,1654798774.669 [Startup](DEBUG): Aggregate::initialize Startup
2022-06-09T18:19:34.669Z,1654798774.669 [Startup:A.GoToSurface] Running Loop=1
2022-06-09T18:19:34.669Z,1654798774.669 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-06-09T18:19:34.669Z,1654798774.669 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-06-09T18:19:34.670Z,1654798774.670 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-06-09T18:19:34.670Z,1654798774.670 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-06-09T18:19:34.670Z,1654798774.670 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-06-09T18:19:34.671Z,1654798774.671 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-06-09T18:19:34.672Z,1654798774.672 [Startup:StartupSatComms] Running Loop=1
2022-06-09T18:19:34.672Z,1654798774.672 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-06-09T18:19:34.672Z,1654798774.672 [Startup:StartupSatComms:A] Running Loop=1
2022-06-09T18:19:35.065Z,1654798775.065 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-06-09T18:20:05.764Z,1654798805.764 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-06-09T18:20:05.764Z,1654798805.764 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-09T18:20:05.765Z,1654798805.765 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2022-06-09T18:20:05.765Z,1654798805.765 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-06-09T18:20:05.780Z,1654798805.780 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-09T18:20:05.780Z,1654798805.780 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-06-09T18:20:06.197Z,1654798806.197 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-09T18:20:06.198Z,1654798806.198 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-06-09T18:20:06.198Z,1654798806.198 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-06-09T18:20:06.198Z,1654798806.198 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2022-06-09T18:20:06.688Z,1654798806.688 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-06-09T18:20:34.858Z,1654798834.858 [Startup:StartupSatComms:A](INFO): Timed out from 2022-06-09T18:19:34.7Z
2022-06-09T18:20:34.858Z,1654798834.858 [Startup:StartupSatComms:A] Stopped
2022-06-09T18:20:34.858Z,1654798834.858 [Startup:StartupSatComms:B] Running Loop=1
2022-06-09T18:20:35.265Z,1654798835.265 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-06-09T18:20:44.400Z,1654798844.400 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20220609T180336/Courier0004.lzma
2022-06-09T18:20:45.402Z,1654798845.402 [DataOverHttps](INFO): Moved sent file to Logs/20220609T180336/Courier0004.lzma.bak
2022-06-09T18:20:45.402Z,1654798845.402 [DataOverHttps](INFO): SBD MOMSN=16875500
2022-06-09T18:21:02.227Z,1654798862.227 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220609T181654/Courier0000.lzma
2022-06-09T18:21:03.229Z,1654798863.229 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0000.lzma.bak
2022-06-09T18:21:03.230Z,1654798863.230 [DataOverHttps](INFO): SBD MOMSN=16875502
2022-06-09T18:21:18.943Z,1654798878.943 [DataOverHttps](INFO): Sending 1493 bytes from file Logs/20220609T180336/Express0005.lzma
2022-06-09T18:21:19.941Z,1654798879.941 [DataOverHttps](INFO): Moved sent file to Logs/20220609T180336/Express0005.lzma.bak
2022-06-09T18:21:19.942Z,1654798879.942 [DataOverHttps](INFO): SBD MOMSN=16875505
2022-06-09T18:21:35.221Z,1654798895.221 [Startup:StartupSatComms:B](INFO): Timed out from 2022-06-09T18:20:34.9Z
2022-06-09T18:21:35.221Z,1654798895.221 [Startup:StartupSatComms:B] Stopped
2022-06-09T18:21:35.222Z,1654798895.222 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-06-09T18:21:35.222Z,1654798895.222 [Startup:StartupSatComms] Stopped
2022-06-09T18:21:35.222Z,1654798895.222 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-06-09T18:21:35.223Z,1654798895.223 [Startup](INFO): Completed Startup
2022-06-09T18:21:35.223Z,1654798895.223 [MissionManager](INFO): Startup is completed.
2022-06-09T18:21:35.223Z,1654798895.223 [MissionManager](INFO): Uninitializing Mission Startup
2022-06-09T18:21:35.223Z,1654798895.223 [Startup] Stopped
2022-06-09T18:21:35.223Z,1654798895.223 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-06-09T18:21:35.223Z,1654798895.223 [Startup:A.GoToSurface] Stopped
2022-06-09T18:21:35.223Z,1654798895.223 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-06-09T18:21:35.509Z,1654798895.509 [DataOverHttps](INFO): Sending 977 bytes from file Logs/20220609T181654/Express0001.lzma
2022-06-09T18:21:35.693Z,1654798895.693 [MissionManager](IMPORTANT): Started mission Default
2022-06-09T18:21:35.693Z,1654798895.693 [Default] Running Loop=1
2022-06-09T18:21:35.693Z,1654798895.693 [Default](DEBUG): Aggregate::initialize Default
2022-06-09T18:21:35.693Z,1654798895.693 [Default:B.GoToSurface] Running Loop=1
2022-06-09T18:21:35.693Z,1654798895.693 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-06-09T18:21:35.693Z,1654798895.693 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-06-09T18:21:35.694Z,1654798895.694 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-06-09T18:21:35.694Z,1654798895.694 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-06-09T18:21:35.694Z,1654798895.694 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-06-09T18:21:35.695Z,1654798895.695 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-06-09T18:21:35.695Z,1654798895.695 [Default:A.Wait] Running Loop=1
2022-06-09T18:21:35.695Z,1654798895.695 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-06-09T18:21:36.509Z,1654798896.509 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0001.lzma.bak
2022-06-09T18:21:36.509Z,1654798896.509 [DataOverHttps](INFO): SBD MOMSN=16875581
2022-06-09T18:21:49.035Z,1654798909.035 [Default:A.Wait](INFO): Done Waiting.
2022-06-09T18:21:49.035Z,1654798909.035 [Default:A.Wait] Stopped
2022-06-09T18:21:49.035Z,1654798909.035 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T18:21:49.427Z,1654798909.427 [Default:CheckIn] Running Loop=1
2022-06-09T18:21:49.427Z,1654798909.427 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T18:21:49.427Z,1654798909.427 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T18:21:49.803Z,1654798909.803 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-06-09T18:22:48.799Z,1654798968.799 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-09T18:23:06.935Z,1654798986.935 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-06-09T18:23:06.935Z,1654798986.935 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-09T18:23:06.936Z,1654798986.936 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2022-06-09T18:23:06.936Z,1654798986.936 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-06-09T18:23:06.951Z,1654798986.951 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-09T18:23:06.951Z,1654798986.951 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-06-09T18:23:07.337Z,1654798987.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-09T18:23:07.337Z,1654798987.337 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-06-09T18:23:07.338Z,1654798987.338 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-06-09T18:23:07.338Z,1654798987.338 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2022-06-09T18:23:07.750Z,1654798987.750 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-06-09T18:24:28.935Z,1654799068.935 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-06-09T18:24:28.940Z,1654799068.940 [BPC1](INFO): Received data from all battery sticks.
2022-06-09T18:26:07.961Z,1654799167.961 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-06-09T18:26:07.961Z,1654799167.961 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-09T18:26:07.962Z,1654799167.962 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2022-06-09T18:26:07.962Z,1654799167.962 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-06-09T18:26:07.978Z,1654799167.978 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-09T18:26:07.978Z,1654799167.978 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-06-09T18:26:08.391Z,1654799168.391 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-09T18:26:08.396Z,1654799168.396 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-06-09T18:26:08.396Z,1654799168.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-06-09T18:26:08.396Z,1654799168.396 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2022-06-09T18:26:08.763Z,1654799168.763 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-06-09T18:26:49.598Z,1654799209.598 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-09T18:21:49.4Z
2022-06-09T18:26:49.598Z,1654799209.598 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T18:26:49.598Z,1654799209.598 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T18:26:49.999Z,1654799209.999 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-06-09T18:26:56.319Z,1654799216.319 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220609T181654/Courier0004.lzma
2022-06-09T18:26:57.321Z,1654799217.321 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0004.lzma.bak
2022-06-09T18:26:57.322Z,1654799217.322 [DataOverHttps](INFO): SBD MOMSN=16875607
2022-06-09T18:27:13.023Z,1654799233.023 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20220609T181654/Express0005.lzma
2022-06-09T18:27:14.025Z,1654799234.025 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0005.lzma.bak
2022-06-09T18:27:14.026Z,1654799234.026 [DataOverHttps](INFO): SBD MOMSN=16875609
2022-06-09T18:27:15.440Z,1654799235.440 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T18:27:15.440Z,1654799235.440 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T18:27:15.440Z,1654799235.440 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T18:27:46.533Z,1654799266.533 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-09T18:27:46.534Z,1654799266.534 [NAL9602] Data Fault, FailCount= 1
2022-06-09T18:27:46.534Z,1654799266.534 [NAL9602](ERROR): Data Fault
2022-06-09T18:27:46.574Z,1654799266.574 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-09T18:27:46.937Z,1654799266.937 [NAL9602](INFO): Powering down
2022-06-09T18:27:47.767Z,1654799267.767 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-09T18:27:47.767Z,1654799267.767 [NAL9602] No Fault, FailCount= 1
2022-06-09T18:28:17.282Z,1654799297.282 [NAL9602](INFO): Powering up NAL9602
2022-06-09T18:28:28.202Z,1654799308.202 [NAL9602](INFO): NAL9602 initialized
2022-06-09T18:29:09.052Z,1654799349.052 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-06-09T18:29:09.052Z,1654799349.052 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-09T18:29:09.053Z,1654799349.053 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2022-06-09T18:29:09.053Z,1654799349.053 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-06-09T18:29:09.063Z,1654799349.063 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-09T18:29:09.064Z,1654799349.064 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-06-09T18:29:09.449Z,1654799349.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-09T18:29:09.449Z,1654799349.449 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-06-09T18:29:09.450Z,1654799349.450 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-06-09T18:29:09.450Z,1654799349.450 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2022-06-09T18:29:09.819Z,1654799349.819 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-06-09T18:31:29.941Z,1654799489.941 [DataOverHttps](IMPORTANT): SBD MTMSN=20220609T183129
2022-06-09T18:31:34.047Z,1654799494.047 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183132.00,A,3648.14048,N,12147.24337,W,0.019,0.00,090622,,,A*71
2022-06-09T18:31:34.051Z,1654799494.051 [NAL9602](INFO): GPS fix at 20220609T183132: (36.802341, -121.787390)
2022-06-09T18:31:37.777Z,1654799497.777 [DataOverHttps](INFO): Received command: ibit
2022-06-09T18:31:37.833Z,1654799497.833 [CommandExec](IMPORTANT): got command ibit
2022-06-09T18:31:38.169Z,1654799498.169 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-06-09T18:31:38.169Z,1654799498.169 [IBIT](IMPORTANT): Beginning control surface checks.
2022-06-09T18:31:38.173Z,1654799498.173 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-09T18:31:39.710Z,1654799499.710 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183138.00,A,3648.14022,N,12147.24426,W,0.078,0.00,090622,,,A*77
2022-06-09T18:31:39.712Z,1654799499.712 [NAL9602](INFO): GPS fix at 20220609T183138: (36.802337, -121.787404)
2022-06-09T18:31:42.946Z,1654799502.946 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183141.00,A,3648.14031,N,12147.24435,W,0.156,0.00,090622,,,A*74
2022-06-09T18:31:42.948Z,1654799502.948 [NAL9602](INFO): GPS fix at 20220609T183141: (36.802338, -121.787406)
2022-06-09T18:31:45.763Z,1654799505.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183144.00,A,3648.14050,N,12147.24430,W,0.330,0.00,090622,,,A*71
2022-06-09T18:31:45.766Z,1654799505.766 [NAL9602](INFO): GPS fix at 20220609T183144: (36.802342, -121.787405)
2022-06-09T18:31:48.994Z,1654799508.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183147.00,A,3648.14078,N,12147.24421,W,0.058,0.00,090622,,,A*75
2022-06-09T18:31:49.004Z,1654799509.004 [NAL9602](INFO): GPS fix at 20220609T183147: (36.802346, -121.787403)
2022-06-09T18:31:49.120Z,1654799509.120 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005442
CHAN A1 (24V): 0.000418
CHAN A2 (12V): -0.005534
CHAN A3 (5V): -0.003114
CHAN B0 (3.3V): -0.001134
CHAN B1 (3.15aV): -0.001197
CHAN B2 (3.15bV): -0.001056
CHAN B3 (GND): -0.000580
OPEN: 0.004564
Full Scale: +/- 1 mA
2022-06-09T18:31:51.823Z,1654799511.823 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183150.00,A,3648.14107,N,12147.24422,W,0.194,0.00,090622,,,A*78
2022-06-09T18:31:51.834Z,1654799511.834 [NAL9602](INFO): GPS fix at 20220609T183150: (36.802351, -121.787404)
2022-06-09T18:31:53.558Z,1654799513.558 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.689905
2022-06-09T18:31:53.558Z,1654799513.558 [IBIT](FAULT): Control surface position failure.
2022-06-09T18:31:55.053Z,1654799515.053 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183153.00,A,3648.14102,N,12147.24445,W,0.058,0.00,090622,,,A*7E
2022-06-09T18:31:55.055Z,1654799515.055 [NAL9602](INFO): GPS fix at 20220609T183153: (36.802350, -121.787408)
2022-06-09T18:31:57.884Z,1654799517.884 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183156.00,A,3648.14100,N,12147.24470,W,0.058,0.00,090622,,,A*7F
2022-06-09T18:31:57.886Z,1654799517.886 [NAL9602](INFO): GPS fix at 20220609T183156: (36.802350, -121.787412)
2022-06-09T18:32:00.721Z,1654799520.721 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183159.00,A,3648.14107,N,12147.24479,W,0.194,0.00,090622,,,A*7F
2022-06-09T18:32:00.724Z,1654799520.724 [NAL9602](INFO): GPS fix at 20220609T183159: (36.802351, -121.787413)
2022-06-09T18:32:03.945Z,1654799523.945 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183202.00,A,3648.14107,N,12147.24488,W,0.019,0.00,090622,,,A*78
2022-06-09T18:32:03.947Z,1654799523.947 [NAL9602](INFO): GPS fix at 20220609T183202: (36.802351, -121.787415)
2022-06-09T18:32:06.772Z,1654799526.772 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183205.00,A,3648.14101,N,12147.24485,W,0.097,0.00,090622,,,A*72
2022-06-09T18:32:06.775Z,1654799526.775 [NAL9602](INFO): GPS fix at 20220609T183205: (36.802350, -121.787414)
2022-06-09T18:32:10.003Z,1654799530.003 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183208.00,A,3648.14091,N,12147.24479,W,0.136,0.00,090622,,,A*7E
2022-06-09T18:32:10.005Z,1654799530.005 [NAL9602](INFO): GPS fix at 20220609T183208: (36.802349, -121.787413)
2022-06-09T18:32:12.834Z,1654799532.834 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183211.00,A,3648.14078,N,12147.24477,W,0.156,0.00,090622,,,A*79
2022-06-09T18:32:12.837Z,1654799532.837 [NAL9602](INFO): GPS fix at 20220609T183211: (36.802346, -121.787413)
2022-06-09T18:32:16.066Z,1654799536.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183214.00,A,3648.14075,N,12147.24465,W,0.156,0.00,090622,,,A*72
2022-06-09T18:32:16.069Z,1654799536.069 [NAL9602](INFO): GPS fix at 20220609T183214: (36.802346, -121.787411)
2022-06-09T18:32:18.894Z,1654799538.894 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183217.00,A,3648.14068,N,12147.24452,W,0.039,0.00,090622,,,A*71
2022-06-09T18:32:18.896Z,1654799538.896 [NAL9602](INFO): GPS fix at 20220609T183217: (36.802345, -121.787409)
2022-06-09T18:32:22.125Z,1654799542.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183220.00,A,3648.14069,N,12147.24435,W,0.175,0.00,090622,,,A*7C
2022-06-09T18:32:22.127Z,1654799542.127 [NAL9602](INFO): GPS fix at 20220609T183220: (36.802345, -121.787406)
2022-06-09T18:32:24.955Z,1654799544.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183223.00,A,3648.14068,N,12147.24423,W,0.136,0.00,090622,,,A*7E
2022-06-09T18:32:24.958Z,1654799544.958 [NAL9602](INFO): GPS fix at 20220609T183223: (36.802345, -121.787404)
2022-06-09T18:32:27.777Z,1654799547.777 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183226.00,A,3648.14075,N,12147.24406,W,0.194,0.00,090622,,,D*7D
2022-06-09T18:32:27.779Z,1654799547.779 [NAL9602](INFO): GPS fix at 20220609T183226: (36.802346, -121.787401)
2022-06-09T18:32:31.014Z,1654799551.014 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183229.00,A,3648.14074,N,12147.24382,W,0.039,0.00,090622,,,D*7E
2022-06-09T18:32:31.024Z,1654799551.024 [NAL9602](INFO): GPS fix at 20220609T183229: (36.802346, -121.787397)
2022-06-09T18:32:33.840Z,1654799553.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183232.00,A,3648.14071,N,12147.24365,W,0.039,0.00,090622,,,D*78
2022-06-09T18:32:33.842Z,1654799553.842 [NAL9602](INFO): GPS fix at 20220609T183232: (36.802345, -121.787394)
2022-06-09T18:32:37.072Z,1654799557.072 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183235.00,A,3648.14075,N,12147.24353,W,0.253,0.00,090622,,,D*70
2022-06-09T18:32:37.074Z,1654799557.074 [NAL9602](INFO): GPS fix at 20220609T183235: (36.802346, -121.787392)
2022-06-09T18:32:39.898Z,1654799559.898 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183238.00,A,3648.14092,N,12147.24323,W,0.525,0.00,090622,,,D*75
2022-06-09T18:32:39.900Z,1654799559.900 [NAL9602](INFO): GPS fix at 20220609T183238: (36.802349, -121.787387)
2022-06-09T18:32:42.735Z,1654799562.735 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183241.00,A,3648.14056,N,12147.24118,W,2.080,0.00,090622,,,D*71
2022-06-09T18:32:42.737Z,1654799562.737 [NAL9602](INFO): GPS fix at 20220609T183241: (36.802343, -121.787353)
2022-06-09T18:32:45.959Z,1654799565.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183244.00,A,3648.13974,N,12147.23858,W,2.605,104.16,090622,,,D*79
2022-06-09T18:32:45.961Z,1654799565.961 [NAL9602](INFO): GPS fix at 20220609T183244: (36.802329, -121.787310)
2022-06-09T18:32:48.794Z,1654799568.794 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183247.00,A,3648.13919,N,12147.23640,W,2.410,96.51,090622,,,D*49
2022-06-09T18:32:48.797Z,1654799568.797 [NAL9602](INFO): GPS fix at 20220609T183247: (36.802320, -121.787273)
2022-06-09T18:32:52.018Z,1654799572.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183250.00,A,3648.13877,N,12147.23322,W,2.683,95.67,090622,,,D*49
2022-06-09T18:32:52.020Z,1654799572.020 [NAL9602](INFO): GPS fix at 20220609T183250: (36.802313, -121.787220)
2022-06-09T18:32:54.850Z,1654799574.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183253.00,A,3648.13877,N,12147.23035,W,1.924,85.41,090622,,,D*4B
2022-06-09T18:32:54.852Z,1654799574.852 [NAL9602](INFO): GPS fix at 20220609T183253: (36.802313, -121.787172)
2022-06-09T18:32:58.078Z,1654799578.078 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183256.00,A,3648.13913,N,12147.22853,W,0.700,85.41,090622,,,D*4D
2022-06-09T18:32:58.080Z,1654799578.080 [NAL9602](INFO): GPS fix at 20220609T183256: (36.802319, -121.787142)
2022-06-09T18:33:00.911Z,1654799580.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183259.00,A,3648.13876,N,12147.22844,W,0.311,85.41,090622,,,D*42
2022-06-09T18:33:00.913Z,1654799580.913 [NAL9602](INFO): GPS fix at 20220609T183259: (36.802313, -121.787141)
2022-06-09T18:33:03.733Z,1654799583.733 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183302.00,A,3648.13818,N,12147.22859,W,0.292,85.41,090622,,,D*43
2022-06-09T18:33:03.736Z,1654799583.736 [NAL9602](INFO): GPS fix at 20220609T183302: (36.802303, -121.787143)
2022-06-09T18:33:06.971Z,1654799586.971 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183305.00,A,3648.13790,N,12147.22856,W,0.292,85.41,090622,,,D*44
2022-06-09T18:33:06.973Z,1654799586.973 [NAL9602](INFO): GPS fix at 20220609T183305: (36.802298, -121.787143)
2022-06-09T18:33:09.793Z,1654799589.793 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183308.00,A,3648.13777,N,12147.22841,W,0.156,85.41,090622,,,D*4D
2022-06-09T18:33:09.795Z,1654799589.795 [NAL9602](INFO): GPS fix at 20220609T183308: (36.802296, -121.787140)
2022-06-09T18:33:13.032Z,1654799593.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183311.00,A,3648.13761,N,12147.22828,W,0.175,85.41,090622,,,D*4C
2022-06-09T18:33:13.034Z,1654799593.034 [NAL9602](INFO): GPS fix at 20220609T183311: (36.802293, -121.787138)
2022-06-09T18:33:15.854Z,1654799595.854 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183314.00,A,3648.13761,N,12147.22824,W,0.117,85.41,090622,,,D*41
2022-06-09T18:33:15.856Z,1654799595.856 [NAL9602](INFO): GPS fix at 20220609T183314: (36.802293, -121.787137)
2022-06-09T18:33:19.091Z,1654799599.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183317.00,A,3648.13753,N,12147.22824,W,0.175,85.41,090622,,,D*47
2022-06-09T18:33:19.093Z,1654799599.093 [NAL9602](INFO): GPS fix at 20220609T183317: (36.802292, -121.787137)
2022-06-09T18:33:21.915Z,1654799601.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183320.00,A,3648.13742,N,12147.22827,W,1.108,85.41,090622,,,D*4B
2022-06-09T18:33:21.917Z,1654799601.917 [NAL9602](INFO): GPS fix at 20220609T183320: (36.802290, -121.787138)
2022-06-09T18:33:24.741Z,1654799604.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183323.00,A,3648.13673,N,12147.22851,W,0.078,85.41,090622,,,D*4D
2022-06-09T18:33:24.744Z,1654799604.744 [NAL9602](INFO): GPS fix at 20220609T183323: (36.802279, -121.787142)
2022-06-09T18:33:27.973Z,1654799607.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183326.00,A,3648.13905,N,12147.22703,W,0.350,85.41,090622,,,D*47
2022-06-09T18:33:27.976Z,1654799607.976 [NAL9602](INFO): GPS fix at 20220609T183326: (36.802318, -121.787117)
2022-06-09T18:33:29.205Z,1654799609.205 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2022-06-09T18:33:29.616Z,1654799609.616 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.770000
2022-06-09T18:33:29.616Z,1654799609.616 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2022-06-09T18:33:29.616Z,1654799609.616 [IBIT](IMPORTANT): batteryVoltageThreshold: 11.000000 V
2022-06-09T18:33:30.020Z,1654799610.020 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-06-09T18:33:30.020Z,1654799610.020 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-06-09T18:33:30.021Z,1654799610.021 [IBIT](IMPORTANT): Pressure:8.165596 PSI
2022-06-09T18:33:30.021Z,1654799610.021 [IBIT](IMPORTANT): Humidity:29.117325 %
2022-06-09T18:33:30.439Z,1654799610.439 [IBIT](IMPORTANT): Vehicle Pitch:-1.076937 degrees
2022-06-09T18:33:30.439Z,1654799610.439 [IBIT](IMPORTANT): Vehicle  Roll:-2.146817 degrees
2022-06-09T18:33:30.440Z,1654799610.440 [IBIT](IMPORTANT): Vehicle  Heading:23.176891 degrees
2022-06-09T18:33:30.807Z,1654799610.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183329.00,A,3648.13988,N,12147.22659,W,0.097,85.41,090622,,,D*4B
2022-06-09T18:33:30.809Z,1654799610.809 [NAL9602](INFO): GPS fix at 20220609T183329: (36.802331, -121.787110)
2022-06-09T18:33:30.850Z,1654799610.850 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-06-09T18:33:30.850Z,1654799610.850 [IBIT](IMPORTANT): buoyancyNeutral: 298.384338 cc
2022-06-09T18:33:30.851Z,1654799610.851 [IBIT](IMPORTANT): massDefault: 1.851699 cm
2022-06-09T18:33:30.851Z,1654799610.851 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2022-06-09T18:33:30.851Z,1654799610.851 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2022-06-09T18:33:30.852Z,1654799610.852 [IBIT](IMPORTANT): IBIT FAILED
2022-06-09T18:33:31.242Z,1654799611.242 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T18:33:31.242Z,1654799611.242 [Default:CheckIn:C.Wait] Stopped
2022-06-09T18:33:31.242Z,1654799611.242 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T18:33:31.242Z,1654799611.242 [Default:CheckIn:D] Running Loop=1
2022-06-09T18:33:31.629Z,1654799611.629 [Default:CheckIn:D] Stopped
2022-06-09T18:33:31.629Z,1654799611.629 [Default:CheckIn:E] Running Loop=1
2022-06-09T18:33:32.026Z,1654799612.026 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.932260 min 
2022-06-09T18:33:32.026Z,1654799612.026 [Default:CheckIn:E] Stopped
2022-06-09T18:33:32.026Z,1654799612.026 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T18:33:32.027Z,1654799612.027 [Default:CheckIn] Stopped
2022-06-09T18:33:32.027Z,1654799612.027 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T18:33:32.027Z,1654799612.027 [Default:CheckIn](INFO): Running loop #2
2022-06-09T18:33:32.027Z,1654799612.027 [Default:CheckIn] Running Loop=2
2022-06-09T18:33:32.027Z,1654799612.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T18:33:32.027Z,1654799612.027 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T18:33:34.043Z,1654799614.043 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183332.00,A,3648.14017,N,12147.22617,W,0.019,85.41,090622,,,D*45
2022-06-09T18:33:34.045Z,1654799614.045 [NAL9602](INFO): GPS fix at 20220609T183332: (36.802336, -121.787103)
2022-06-09T18:33:34.057Z,1654799614.057 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T18:33:34.057Z,1654799614.057 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T18:33:41.771Z,1654799621.771 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20220609T181654/Courier0007.lzma
2022-06-09T18:33:42.773Z,1654799622.773 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0007.lzma.bak
2022-06-09T18:33:42.774Z,1654799622.774 [DataOverHttps](INFO): SBD MOMSN=16875621
2022-06-09T18:34:04.281Z,1654799644.281 [DataOverHttps](INFO): Sending 995 bytes from file Logs/20220609T181654/Express0008.lzma
2022-06-09T18:34:05.281Z,1654799645.281 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0008.lzma.bak
2022-06-09T18:34:05.282Z,1654799645.282 [DataOverHttps](INFO): SBD MOMSN=16875624
2022-06-09T18:34:06.409Z,1654799646.409 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T18:34:06.832Z,1654799646.832 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T18:34:06.832Z,1654799646.832 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T18:34:06.832Z,1654799646.832 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T18:39:07.402Z,1654799947.402 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T18:39:07.402Z,1654799947.402 [Default:CheckIn:C.Wait] Stopped
2022-06-09T18:39:07.402Z,1654799947.402 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T18:39:07.402Z,1654799947.402 [Default:CheckIn:D] Running Loop=1
2022-06-09T18:39:07.811Z,1654799947.811 [Default:CheckIn:D] Stopped
2022-06-09T18:39:07.811Z,1654799947.811 [Default:CheckIn:E] Running Loop=1
2022-06-09T18:39:08.220Z,1654799948.220 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.535303 min 
2022-06-09T18:39:08.220Z,1654799948.220 [Default:CheckIn:E] Stopped
2022-06-09T18:39:08.220Z,1654799948.220 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T18:39:08.220Z,1654799948.220 [Default:CheckIn] Stopped
2022-06-09T18:39:08.221Z,1654799948.221 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T18:39:08.221Z,1654799948.221 [Default:CheckIn](INFO): Running loop #3
2022-06-09T18:39:08.221Z,1654799948.221 [Default:CheckIn] Running Loop=3
2022-06-09T18:39:08.221Z,1654799948.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T18:39:08.221Z,1654799948.221 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T18:39:10.223Z,1654799950.223 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183908.00,A,3648.13948,N,12147.22733,W,0.019,85.41,090622,,,D*45
2022-06-09T18:39:10.234Z,1654799950.234 [NAL9602](INFO): GPS fix at 20220609T183908: (36.802325, -121.787122)
2022-06-09T18:39:10.250Z,1654799950.250 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T18:39:10.250Z,1654799950.250 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T18:39:18.315Z,1654799958.315 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220609T181654/Courier0013.lzma
2022-06-09T18:39:19.317Z,1654799959.317 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0013.lzma.bak
2022-06-09T18:39:19.318Z,1654799959.318 [DataOverHttps](INFO): SBD MOMSN=16875653
2022-06-09T18:39:24.766Z,1654799964.766 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=7861, MT Status=1, MTMSN=194
2022-06-09T18:39:24.767Z,1654799964.767 [NAL9602](INFO): Data available in MT queue
2022-06-09T18:39:25.304Z,1654799965.304 [NAL9602](INFO): Received command: configSet CBIT.gfScanTimeout 0.25 hour persist
2022-06-09T18:39:25.733Z,1654799965.733 [CommandExec](IMPORTANT): got command configSet CBIT.gfScanTimeout 0.25 hour persist
2022-06-09T18:39:35.795Z,1654799975.795 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220609T181654/Express0014.lzma
2022-06-09T18:39:36.797Z,1654799976.797 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0014.lzma.bak
2022-06-09T18:39:36.798Z,1654799976.798 [DataOverHttps](INFO): SBD MOMSN=16875656
2022-06-09T18:39:38.117Z,1654799978.117 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T18:39:38.117Z,1654799978.117 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T18:39:38.117Z,1654799978.117 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T18:39:55.889Z,1654799995.889 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T18:40:21.053Z,1654800021.053 [DataOverHttps](IMPORTANT): SBD MTMSN=20220609T184020
2022-06-09T18:40:28.543Z,1654800028.543 [DataOverHttps](INFO): Received command: ibit
2022-06-09T18:40:28.617Z,1654800028.617 [CommandExec](IMPORTANT): got command ibit
2022-06-09T18:40:28.623Z,1654800028.623 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-06-09T18:40:28.624Z,1654800028.624 [IBIT](IMPORTANT): Beginning control surface checks.
2022-06-09T18:40:28.627Z,1654800028.627 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-09T18:40:30.214Z,1654800030.214 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184028.00,A,3648.14109,N,12147.22704,W,0.058,85.41,090622,,,D*42
2022-06-09T18:40:30.217Z,1654800030.217 [NAL9602](INFO): GPS fix at 20220609T184028: (36.802352, -121.787117)
2022-06-09T18:40:39.527Z,1654800039.527 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006728
CHAN A1 (24V): 0.000295
CHAN A2 (12V): -0.005160
CHAN A3 (5V): -0.002777
CHAN B0 (3.3V): -0.001525
CHAN B1 (3.15aV): -0.001156
CHAN B2 (3.15bV): -0.001176
CHAN B3 (GND): -0.000787
OPEN: 0.004871
Full Scale: +/- 1 mA
2022-06-09T18:40:59.306Z,1654800059.306 [NAL9602](INFO): SBD MO Status=2, MOMSN=7862, MT Status=2, MTMSN=0
2022-06-09T18:40:59.306Z,1654800059.306 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-09T18:41:15.489Z,1654800075.489 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802353 Longitude: -121.787117
2022-06-09T18:41:15.887Z,1654800075.887 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.770000
2022-06-09T18:41:15.888Z,1654800075.888 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2022-06-09T18:41:15.888Z,1654800075.888 [IBIT](IMPORTANT): batteryVoltageThreshold: 11.000000 V
2022-06-09T18:41:16.301Z,1654800076.301 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-06-09T18:41:16.301Z,1654800076.301 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-06-09T18:41:16.301Z,1654800076.301 [IBIT](IMPORTANT): Pressure:8.189870 PSI
2022-06-09T18:41:16.301Z,1654800076.301 [IBIT](IMPORTANT): Humidity:29.007446 %
2022-06-09T18:41:16.707Z,1654800076.707 [IBIT](IMPORTANT): Vehicle Pitch:-1.092893 degrees
2022-06-09T18:41:16.707Z,1654800076.707 [IBIT](IMPORTANT): Vehicle  Roll:-2.144402 degrees
2022-06-09T18:41:16.712Z,1654800076.712 [IBIT](IMPORTANT): Vehicle  Heading:23.191416 degrees
2022-06-09T18:41:17.096Z,1654800077.096 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-06-09T18:41:17.097Z,1654800077.097 [IBIT](IMPORTANT): buoyancyNeutral: 298.384338 cc
2022-06-09T18:41:17.097Z,1654800077.097 [IBIT](IMPORTANT): massDefault: 1.851699 cm
2022-06-09T18:41:17.097Z,1654800077.097 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2022-06-09T18:41:17.097Z,1654800077.097 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2022-06-09T18:41:17.097Z,1654800077.097 [IBIT](IMPORTANT): IBIT PASSED
2022-06-09T18:41:34.045Z,1654800094.045 [NAL9602](INFO): SBD MO Status=2, MOMSN=7862, MT Status=2, MTMSN=0
2022-06-09T18:41:34.045Z,1654800094.045 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-09T18:41:50.220Z,1654800110.220 [NAL9602](INFO): SBD MO Status=2, MOMSN=7862, MT Status=2, MTMSN=0
2022-06-09T18:41:50.220Z,1654800110.220 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-09T18:42:07.582Z,1654800127.582 [NAL9602](INFO): SBD MO Status=0, MOMSN=7862, MT Status=0, MTMSN=0
2022-06-09T18:42:07.582Z,1654800127.582 [NAL9602](INFO): No messages in MT queue
2022-06-09T18:42:38.284Z,1654800158.284 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T18:44:38.694Z,1654800278.694 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T18:44:38.694Z,1654800278.694 [Default:CheckIn:C.Wait] Stopped
2022-06-09T18:44:38.694Z,1654800278.694 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T18:44:38.694Z,1654800278.694 [Default:CheckIn:D] Running Loop=1
2022-06-09T18:44:39.086Z,1654800279.086 [Default:CheckIn:D] Stopped
2022-06-09T18:44:39.086Z,1654800279.086 [Default:CheckIn:E] Running Loop=1
2022-06-09T18:44:39.496Z,1654800279.496 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.056553 min 
2022-06-09T18:44:39.496Z,1654800279.496 [Default:CheckIn:E] Stopped
2022-06-09T18:44:39.497Z,1654800279.497 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T18:44:39.497Z,1654800279.497 [Default:CheckIn] Stopped
2022-06-09T18:44:39.497Z,1654800279.497 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T18:44:39.497Z,1654800279.497 [Default:CheckIn](INFO): Running loop #4
2022-06-09T18:44:39.497Z,1654800279.497 [Default:CheckIn] Running Loop=4
2022-06-09T18:44:39.497Z,1654800279.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T18:44:39.497Z,1654800279.497 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T18:44:41.507Z,1654800281.507 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184440.00,A,3648.14000,N,12147.22733,W,0.000,85.41,090622,,,D*49
2022-06-09T18:44:41.509Z,1654800281.509 [NAL9602](INFO): GPS fix at 20220609T184440: (36.802333, -121.787122)
2022-06-09T18:44:41.521Z,1654800281.521 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T18:44:41.521Z,1654800281.521 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T18:44:49.411Z,1654800289.411 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220609T181654/Courier0016.lzma
2022-06-09T18:44:50.414Z,1654800290.414 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0016.lzma.bak
2022-06-09T18:44:50.414Z,1654800290.414 [DataOverHttps](INFO): SBD MOMSN=16875663
2022-06-09T18:44:53.224Z,1654800293.224 [NAL9602](INFO): SBD MO Status=0, MOMSN=7863, MT Status=0, MTMSN=0
2022-06-09T18:44:53.225Z,1654800293.225 [NAL9602](INFO): No messages in MT queue
2022-06-09T18:45:06.459Z,1654800306.459 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220609T181654/Courier0019.lzma
2022-06-09T18:45:07.462Z,1654800307.462 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0019.lzma.bak
2022-06-09T18:45:07.462Z,1654800307.462 [DataOverHttps](INFO): SBD MOMSN=16875666
2022-06-09T18:45:23.633Z,1654800323.633 [DataOverHttps](INFO): Sending 836 bytes from file Logs/20220609T181654/Express0017.lzma
2022-06-09T18:45:23.924Z,1654800323.924 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T18:45:24.633Z,1654800324.633 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0017.lzma.bak
2022-06-09T18:45:24.634Z,1654800324.634 [DataOverHttps](INFO): SBD MOMSN=16875668
2022-06-09T18:45:40.611Z,1654800340.611 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20220609T181654/Express0020.lzma
2022-06-09T18:45:41.614Z,1654800341.614 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0020.lzma.bak
2022-06-09T18:45:41.614Z,1654800341.614 [DataOverHttps](INFO): SBD MOMSN=16875696
2022-06-09T18:45:42.945Z,1654800342.945 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T18:45:42.945Z,1654800342.945 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T18:45:42.946Z,1654800342.946 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T18:50:43.577Z,1654800643.577 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T18:50:43.577Z,1654800643.577 [Default:CheckIn:C.Wait] Stopped
2022-06-09T18:50:43.577Z,1654800643.577 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T18:50:43.577Z,1654800643.577 [Default:CheckIn:D] Running Loop=1
2022-06-09T18:50:43.970Z,1654800643.970 [Default:CheckIn:D] Stopped
2022-06-09T18:50:43.970Z,1654800643.970 [Default:CheckIn:E] Running Loop=1
2022-06-09T18:50:44.381Z,1654800644.381 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.137952 min 
2022-06-09T18:50:44.381Z,1654800644.381 [Default:CheckIn:E] Stopped
2022-06-09T18:50:44.381Z,1654800644.381 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T18:50:44.381Z,1654800644.381 [Default:CheckIn] Stopped
2022-06-09T18:50:44.382Z,1654800644.382 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T18:50:44.382Z,1654800644.382 [Default:CheckIn](INFO): Running loop #5
2022-06-09T18:50:44.382Z,1654800644.382 [Default:CheckIn] Running Loop=5
2022-06-09T18:50:44.382Z,1654800644.382 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T18:50:44.382Z,1654800644.382 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T18:50:46.391Z,1654800646.391 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185045.00,A,3648.13941,N,12147.22856,W,0.058,85.41,090622,,,D*43
2022-06-09T18:50:46.393Z,1654800646.393 [NAL9602](INFO): GPS fix at 20220609T185045: (36.802323, -121.787143)
2022-06-09T18:50:46.406Z,1654800646.406 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T18:50:46.406Z,1654800646.406 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T18:50:53.999Z,1654800653.999 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220609T181654/Courier0022.lzma
2022-06-09T18:50:55.001Z,1654800655.001 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0022.lzma.bak
2022-06-09T18:50:55.002Z,1654800655.002 [DataOverHttps](INFO): SBD MOMSN=16875701
2022-06-09T18:51:11.163Z,1654800671.163 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220609T181654/Express0023.lzma
2022-06-09T18:51:12.165Z,1654800672.165 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0023.lzma.bak
2022-06-09T18:51:12.166Z,1654800672.166 [DataOverHttps](INFO): SBD MOMSN=16875704
2022-06-09T18:51:12.656Z,1654800672.656 [NAL9602](INFO): SBD MO Status=2, MOMSN=7864, MT Status=2, MTMSN=0
2022-06-09T18:51:12.656Z,1654800672.656 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-09T18:51:13.468Z,1654800673.468 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T18:51:13.468Z,1654800673.468 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T18:51:13.469Z,1654800673.469 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T18:51:29.618Z,1654800689.618 [NAL9602](INFO): SBD MO Status=0, MOMSN=7864, MT Status=0, MTMSN=0
2022-06-09T18:51:29.618Z,1654800689.618 [NAL9602](INFO): No messages in MT queue
2022-06-09T18:52:00.392Z,1654800720.392 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T18:55:39.791Z,1654800939.791 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-09T18:55:50.735Z,1654800950.735 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006445
CHAN A1 (24V): 0.000537
CHAN A2 (12V): -0.005735
CHAN A3 (5V): -0.002926
CHAN B0 (3.3V): -0.001031
CHAN B1 (3.15aV): -0.001443
CHAN B2 (3.15bV): -0.001312
CHAN B3 (GND): -0.000834
OPEN: 0.004805
Full Scale: +/- 1 mA
2022-06-09T18:56:14.116Z,1654800974.116 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T18:56:14.116Z,1654800974.116 [Default:CheckIn:C.Wait] Stopped
2022-06-09T18:56:14.116Z,1654800974.116 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T18:56:14.116Z,1654800974.116 [Default:CheckIn:D] Running Loop=1
2022-06-09T18:56:14.523Z,1654800974.523 [Default:CheckIn:D] Stopped
2022-06-09T18:56:14.523Z,1654800974.523 [Default:CheckIn:E] Running Loop=1
2022-06-09T18:56:14.935Z,1654800974.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.647168 min 
2022-06-09T18:56:14.936Z,1654800974.936 [Default:CheckIn:E] Stopped
2022-06-09T18:56:14.936Z,1654800974.936 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T18:56:14.936Z,1654800974.936 [Default:CheckIn] Stopped
2022-06-09T18:56:14.936Z,1654800974.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T18:56:14.936Z,1654800974.936 [Default:CheckIn](INFO): Running loop #6
2022-06-09T18:56:14.936Z,1654800974.936 [Default:CheckIn] Running Loop=6
2022-06-09T18:56:14.936Z,1654800974.936 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T18:56:14.936Z,1654800974.936 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T18:56:16.934Z,1654800976.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185616.00,A,3648.13924,N,12147.22985,W,0.039,85.41,090622,,,D*48
2022-06-09T18:56:16.936Z,1654800976.936 [NAL9602](INFO): GPS fix at 20220609T185616: (36.802321, -121.787164)
2022-06-09T18:56:16.948Z,1654800976.948 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T18:56:16.948Z,1654800976.948 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T18:56:25.063Z,1654800985.063 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220609T181654/Courier0025.lzma
2022-06-09T18:56:26.065Z,1654800986.065 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0025.lzma.bak
2022-06-09T18:56:26.066Z,1654800986.066 [DataOverHttps](INFO): SBD MOMSN=16875709
2022-06-09T18:56:42.368Z,1654801002.368 [DataOverHttps](INFO): Sending 304 bytes from file Logs/20220609T181654/Express0026.lzma
2022-06-09T18:56:42.397Z,1654801002.397 [NAL9602](INFO): SBD MO Status=0, MOMSN=7865, MT Status=0, MTMSN=0
2022-06-09T18:56:42.397Z,1654801002.397 [NAL9602](INFO): No messages in MT queue
2022-06-09T18:56:43.366Z,1654801003.366 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0026.lzma.bak
2022-06-09T18:56:43.366Z,1654801003.366 [DataOverHttps](INFO): SBD MOMSN=16875712
2022-06-09T18:56:44.827Z,1654801004.827 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T18:56:44.827Z,1654801004.827 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T18:56:44.827Z,1654801004.827 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T18:57:13.088Z,1654801033.088 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T19:01:45.412Z,1654801305.412 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T19:01:45.412Z,1654801305.412 [Default:CheckIn:C.Wait] Stopped
2022-06-09T19:01:45.412Z,1654801305.412 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T19:01:45.412Z,1654801305.412 [Default:CheckIn:D] Running Loop=1
2022-06-09T19:01:45.803Z,1654801305.803 [Default:CheckIn:D] Stopped
2022-06-09T19:01:45.803Z,1654801305.803 [Default:CheckIn:E] Running Loop=1
2022-06-09T19:01:46.206Z,1654801306.206 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.168494 min 
2022-06-09T19:01:46.207Z,1654801306.207 [Default:CheckIn:E] Stopped
2022-06-09T19:01:46.207Z,1654801306.207 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T19:01:46.207Z,1654801306.207 [Default:CheckIn] Stopped
2022-06-09T19:01:46.207Z,1654801306.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T19:01:46.207Z,1654801306.207 [Default:CheckIn](INFO): Running loop #7
2022-06-09T19:01:46.207Z,1654801306.207 [Default:CheckIn] Running Loop=7
2022-06-09T19:01:46.207Z,1654801306.207 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T19:01:46.208Z,1654801306.208 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T19:01:48.234Z,1654801308.234 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190147.00,A,3648.14198,N,12147.23637,W,0.233,54.42,090622,,,D*47
2022-06-09T19:01:48.236Z,1654801308.236 [NAL9602](INFO): GPS fix at 20220609T190147: (36.802366, -121.787273)
2022-06-09T19:01:48.277Z,1654801308.277 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T19:01:48.277Z,1654801308.277 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T19:01:58.927Z,1654801318.927 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20220609T181654/Courier0028.lzma
2022-06-09T19:01:59.930Z,1654801319.930 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0028.lzma.bak
2022-06-09T19:01:59.930Z,1654801319.930 [DataOverHttps](INFO): SBD MOMSN=16875719
2022-06-09T19:02:10.939Z,1654801330.939 [NAL9602](INFO): SBD MO Status=2, MOMSN=7866, MT Status=2, MTMSN=0
2022-06-09T19:02:10.939Z,1654801330.939 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-09T19:02:16.503Z,1654801336.503 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220609T181654/Express0029.lzma
2022-06-09T19:02:17.506Z,1654801337.506 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0029.lzma.bak
2022-06-09T19:02:17.506Z,1654801337.506 [DataOverHttps](INFO): SBD MOMSN=16875722
2022-06-09T19:02:19.036Z,1654801339.036 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T19:02:19.036Z,1654801339.036 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T19:02:19.036Z,1654801339.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T19:02:51.347Z,1654801371.347 [NAL9602](INFO): SBD MO Status=0, MOMSN=7866, MT Status=0, MTMSN=0
2022-06-09T19:02:51.347Z,1654801371.347 [NAL9602](INFO): No messages in MT queue
2022-06-09T19:03:22.043Z,1654801402.043 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T19:07:19.610Z,1654801639.610 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T19:07:19.610Z,1654801639.610 [Default:CheckIn:C.Wait] Stopped
2022-06-09T19:07:19.610Z,1654801639.610 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T19:07:19.610Z,1654801639.610 [Default:CheckIn:D] Running Loop=1
2022-06-09T19:07:20.015Z,1654801640.015 [Default:CheckIn:D] Stopped
2022-06-09T19:07:20.015Z,1654801640.015 [Default:CheckIn:E] Running Loop=1
2022-06-09T19:07:20.428Z,1654801640.428 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.738704 min 
2022-06-09T19:07:20.428Z,1654801640.428 [Default:CheckIn:E] Stopped
2022-06-09T19:07:20.428Z,1654801640.428 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T19:07:20.428Z,1654801640.428 [Default:CheckIn] Stopped
2022-06-09T19:07:20.428Z,1654801640.428 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T19:07:20.429Z,1654801640.429 [Default:CheckIn](INFO): Running loop #8
2022-06-09T19:07:20.429Z,1654801640.429 [Default:CheckIn] Running Loop=8
2022-06-09T19:07:20.429Z,1654801640.429 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T19:07:20.429Z,1654801640.429 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T19:07:22.429Z,1654801642.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190721.00,A,3648.16689,N,12147.21420,W,0.253,15.49,090622,,,D*4A
2022-06-09T19:07:22.431Z,1654801642.431 [NAL9602](INFO): GPS fix at 20220609T190721: (36.802782, -121.786903)
2022-06-09T19:07:22.460Z,1654801642.460 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T19:07:22.460Z,1654801642.460 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T19:07:30.455Z,1654801650.455 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220609T181654/Courier0031.lzma
2022-06-09T19:07:31.458Z,1654801651.458 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0031.lzma.bak
2022-06-09T19:07:31.458Z,1654801651.458 [DataOverHttps](INFO): SBD MOMSN=16875744
2022-06-09T19:07:37.782Z,1654801657.782 [NAL9602](INFO): SBD MO Status=0, MOMSN=7867, MT Status=0, MTMSN=0
2022-06-09T19:07:37.782Z,1654801657.782 [NAL9602](INFO): No messages in MT queue
2022-06-09T19:07:47.619Z,1654801667.619 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220609T181654/Express0032.lzma
2022-06-09T19:07:48.622Z,1654801668.622 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0032.lzma.bak
2022-06-09T19:07:48.622Z,1654801668.622 [DataOverHttps](INFO): SBD MOMSN=16875747
2022-06-09T19:07:50.342Z,1654801670.342 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T19:07:50.342Z,1654801670.342 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T19:07:50.342Z,1654801670.342 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T19:08:08.535Z,1654801688.535 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T19:10:50.992Z,1654801850.992 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-09T19:11:01.880Z,1654801861.880 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006230
CHAN A1 (24V): 0.000916
CHAN A2 (12V): -0.004881
CHAN A3 (5V): -0.002746
CHAN B0 (3.3V): -0.000960
CHAN B1 (3.15aV): -0.000935
CHAN B2 (3.15bV): -0.001462
CHAN B3 (GND): -0.000654
OPEN: 0.004890
Full Scale: +/- 1 mA
2022-06-09T19:12:50.953Z,1654801970.953 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T19:12:50.953Z,1654801970.953 [Default:CheckIn:C.Wait] Stopped
2022-06-09T19:12:50.953Z,1654801970.953 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T19:12:50.953Z,1654801970.953 [Default:CheckIn:D] Running Loop=1
2022-06-09T19:12:51.364Z,1654801971.364 [Default:CheckIn:D] Stopped
2022-06-09T19:12:51.364Z,1654801971.364 [Default:CheckIn:E] Running Loop=1
2022-06-09T19:12:51.751Z,1654801971.751 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.261182 min 
2022-06-09T19:12:51.751Z,1654801971.751 [Default:CheckIn:E] Stopped
2022-06-09T19:12:51.751Z,1654801971.751 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T19:12:51.751Z,1654801971.751 [Default:CheckIn] Stopped
2022-06-09T19:12:51.751Z,1654801971.751 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T19:12:51.751Z,1654801971.751 [Default:CheckIn](INFO): Running loop #9
2022-06-09T19:12:51.751Z,1654801971.751 [Default:CheckIn] Running Loop=9
2022-06-09T19:12:51.752Z,1654801971.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T19:12:51.752Z,1654801971.752 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T19:12:53.763Z,1654801973.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191253.00,A,3648.16696,N,12147.21514,W,0.039,15.49,090622,,,A*48
2022-06-09T19:12:53.765Z,1654801973.765 [NAL9602](INFO): GPS fix at 20220609T191253: (36.802783, -121.786919)
2022-06-09T19:12:53.777Z,1654801973.777 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T19:12:53.777Z,1654801973.777 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T19:13:01.303Z,1654801981.303 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220609T181654/Courier0034.lzma
2022-06-09T19:13:02.306Z,1654801982.306 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0034.lzma.bak
2022-06-09T19:13:02.306Z,1654801982.306 [DataOverHttps](INFO): SBD MOMSN=16875758
2022-06-09T19:13:18.564Z,1654801998.564 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20220609T181654/Express0035.lzma
2022-06-09T19:13:19.566Z,1654801999.566 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0035.lzma.bak
2022-06-09T19:13:19.566Z,1654801999.566 [DataOverHttps](INFO): SBD MOMSN=16875762
2022-06-09T19:13:20.849Z,1654802000.849 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T19:13:20.849Z,1654802000.849 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T19:13:20.849Z,1654802000.849 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T19:14:07.294Z,1654802047.294 [NAL9602](INFO): SBD MO Status=0, MOMSN=7868, MT Status=0, MTMSN=0
2022-06-09T19:14:07.294Z,1654802047.294 [NAL9602](INFO): No messages in MT queue
2022-06-09T19:14:37.997Z,1654802077.997 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T19:16:38.269Z,1654802198.269 [CommandExec](IMPORTANT): got command show variable
2022-06-09T19:16:38.269Z,1654802198.269 [CommandExec](FAULT): Incomplete syntax. Try: help show
2022-06-09T19:16:49.531Z,1654802209.531 [CommandExec](IMPORTANT): got command show variable DAT
2022-06-09T19:16:49.597Z,1654802209.597 [CommandExec](IMPORTANT): Vehicle.sendDataToShore (bool)
2022-06-09T19:16:49.599Z,1654802209.599 [CommandExec](IMPORTANT): DAT.loadControl (none)
2022-06-09T19:16:49.600Z,1654802209.600 [CommandExec](IMPORTANT): DAT.uart (none)
2022-06-09T19:16:49.600Z,1654802209.600 [CommandExec](IMPORTANT): DAT.baud (bit_per_second)
2022-06-09T19:16:49.606Z,1654802209.606 [CommandExec](IMPORTANT): Docked.dataTimeout (second)
2022-06-09T19:16:49.608Z,1654802209.608 [CommandExec](IMPORTANT): StratificationFrontDetector.loadAtStartup (bool)
2022-06-09T19:16:49.609Z,1654802209.609 [CommandExec](IMPORTANT): DepAvgTempFrontDetector.loadAtStartup (bool)
2022-06-09T19:16:49.610Z,1654802209.610 [CommandExec](IMPORTANT): BuoyancyServo.loadAtStartup (bool)
2022-06-09T19:16:49.611Z,1654802209.611 [CommandExec](IMPORTANT): DockingServo.loadAtStartup (bool)
2022-06-09T19:16:49.612Z,1654802209.612 [CommandExec](IMPORTANT): ElevatorServo.loadAtStartup (bool)
2022-06-09T19:16:49.613Z,1654802209.613 [CommandExec](IMPORTANT): MassServo.loadAtStartup (bool)
2022-06-09T19:16:49.613Z,1654802209.613 [CommandExec](IMPORTANT): RudderServo.loadAtStartup (bool)
2022-06-09T19:16:49.614Z,1654802209.614 [CommandExec](IMPORTANT): ThrusterHE.loadAtStartup (bool)
2022-06-09T19:16:49.615Z,1654802209.615 [CommandExec](IMPORTANT): ThrusterServo.loadAtStartup (bool)
2022-06-09T19:16:49.616Z,1654802209.616 [CommandExec](IMPORTANT): HorizontalControl.loadAtStartup (bool)
2022-06-09T19:16:49.617Z,1654802209.617 [CommandExec](IMPORTANT): LoopControl.loadAtStartup (bool)
2022-06-09T19:16:49.617Z,1654802209.617 [CommandExec](IMPORTANT): SpeedControl.loadAtStartup (bool)
2022-06-09T19:16:49.618Z,1654802209.618 [CommandExec](IMPORTANT): VerticalControl.loadAtStartup (bool)
2022-06-09T19:16:49.621Z,1654802209.621 [CommandExec](IMPORTANT): DepthRateCalculator.loadAtStartup (bool)
2022-06-09T19:16:49.621Z,1654802209.621 [CommandExec](IMPORTANT): TempGradientCalculator.loadAtStartup (bool)
2022-06-09T19:16:49.622Z,1654802209.622 [CommandExec](IMPORTANT): PitchRateCalculator.loadAtStartup (bool)
2022-06-09T19:16:49.623Z,1654802209.623 [CommandExec](IMPORTANT): SpeedCalculator.loadAtStartup (bool)
2022-06-09T19:16:49.623Z,1654802209.623 [CommandExec](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup (bool)
2022-06-09T19:16:49.624Z,1654802209.624 [CommandExec](IMPORTANT): YawRateCalculator.loadAtStartup (bool)
2022-06-09T19:16:49.625Z,1654802209.625 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup (bool)
2022-06-09T19:16:49.625Z,1654802209.625 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup (bool)
2022-06-09T19:16:49.627Z,1654802209.627 [CommandExec](IMPORTANT): ExternalSim.loadAtStartup (bool)
2022-06-09T19:16:49.627Z,1654802209.627 [CommandExec](IMPORTANT): InternalEnvSim.loadAtStartup (bool)
2022-06-09T19:16:49.628Z,1654802209.628 [CommandExec](IMPORTANT): InternalSim.loadAtStartup (bool)
2022-06-09T19:16:49.629Z,1654802209.629 [CommandExec](IMPORTANT): NavigationSim.loadAtStartup (bool)
2022-06-09T19:16:49.693Z,1654802209.693 [CommandExec](IMPORTANT): Config/Simulator.oceanModelData (none)
2022-06-09T19:16:49.694Z,1654802209.694 [CommandExec](IMPORTANT): Aanderaa_O2.loadAtStartup (bool)
2022-06-09T19:16:49.695Z,1654802209.695 [CommandExec](IMPORTANT): CANONSampler.loadAtStartup (bool)
2022-06-09T19:16:49.695Z,1654802209.695 [CommandExec](IMPORTANT): CTD_NeilBrown.loadAtStartup (bool)
2022-06-09T19:16:49.729Z,1654802209.729 [CommandExec](IMPORTANT): CTD_Seabird.loadAtStartup (bool)
2022-06-09T19:16:49.731Z,1654802209.731 [CommandExec](IMPORTANT): ESPComponent.loadAtStartup (bool)
2022-06-09T19:16:49.732Z,1654802209.732 [CommandExec](IMPORTANT): PAR_Licor.loadAtStartup (bool)
2022-06-09T19:16:49.732Z,1654802209.732 [CommandExec](IMPORTANT): VemcoVR2C.loadAtStartup (bool)
2022-06-09T19:16:49.733Z,1654802209.733 [CommandExec](IMPORTANT): WetLabsBB2FL.loadAtStartup (bool)
2022-06-09T19:16:49.734Z,1654802209.734 [CommandExec](IMPORTANT): WetLabsSeaOWL_UV_A.loadAtStartup (bool)
2022-06-09T19:16:49.734Z,1654802209.734 [CommandExec](IMPORTANT): WetLabsUBAT.loadAtStartup (bool)
2022-06-09T19:16:49.735Z,1654802209.735 [CommandExec](IMPORTANT): CBIT.loadAtStartup (bool)
2022-06-09T19:16:49.767Z,1654802209.767 [CommandExec](IMPORTANT): SBIT.loadAtStartup (bool)
2022-06-09T19:16:49.767Z,1654802209.767 [CommandExec](IMPORTANT): IBIT.loadAtStartup (bool)
2022-06-09T19:16:49.768Z,1654802209.768 [CommandExec](IMPORTANT): AHRS_M2.loadAtStartup (bool)
2022-06-09T19:16:49.769Z,1654802209.769 [CommandExec](IMPORTANT): AMEcho.loadAtStartup (bool)
2022-06-09T19:16:49.769Z,1654802209.769 [CommandExec](IMPORTANT): AcousticModem_Benthos_ATM900.loadAtStartup (bool)
2022-06-09T19:16:49.770Z,1654802209.770 [CommandExec](IMPORTANT): BackseatComponent.loadAtStartup (bool)
2022-06-09T19:16:49.771Z,1654802209.771 [CommandExec](IMPORTANT): BPC1.loadAtStartup (bool)
2022-06-09T19:16:49.771Z,1654802209.771 [CommandExec](IMPORTANT): BR_Ping1D.loadAtStartup (bool)
2022-06-09T19:16:49.795Z,1654802209.795 [CommandExec](IMPORTANT): DataOverHttps.loadAtStartup (bool)
2022-06-09T19:16:49.795Z,1654802209.795 [CommandExec](IMPORTANT): DataOverHttps.connectionTimeout (second)
2022-06-09T19:16:49.795Z,1654802209.795 [CommandExec](IMPORTANT): DataOverHttps.period (second)
2022-06-09T19:16:49.800Z,1654802209.800 [CommandExec](IMPORTANT): DataOverHttps.power (watt)
2022-06-09T19:16:49.801Z,1654802209.801 [CommandExec](IMPORTANT): DataOverHttps.timeout (minute)
2022-06-09T19:16:49.801Z,1654802209.801 [CommandExec](IMPORTANT): DataOverHttps.verbosity (count)
2022-06-09T19:16:49.801Z,1654802209.801 [CommandExec](IMPORTANT): DAT.loadAtStartup (bool)
2022-06-09T19:16:49.801Z,1654802209.801 [CommandExec](IMPORTANT): DAT.simulateHardware (bool)
2022-06-09T19:16:49.802Z,1654802209.802 [CommandExec](IMPORTANT): DAT.maxAckTimeouts (count)
2022-06-09T19:16:49.802Z,1654802209.802 [CommandExec](IMPORTANT): DAT.convertPhaseDataToDirection (bool)
2022-06-09T19:16:49.802Z,1654802209.802 [CommandExec](IMPORTANT): DAT.ignoreElevationAngle (bool)
2022-06-09T19:16:49.803Z,1654802209.803 [CommandExec](IMPORTANT): DAT.txPower (enum)
2022-06-09T19:16:49.803Z,1654802209.803 [CommandExec](IMPORTANT): DAT.localAddress (count)
2022-06-09T19:16:49.816Z,1654802209.816 [CommandExec](IMPORTANT): DAT.sbdAddress (enum)
2022-06-09T19:16:49.816Z,1654802209.816 [CommandExec](IMPORTANT): DAT.transponderAddress (enum)
2022-06-09T19:16:49.816Z,1654802209.816 [CommandExec](IMPORTANT): DAT.sendExpress (bool)
2022-06-09T19:16:49.817Z,1654802209.817 [CommandExec](IMPORTANT): DAT.surfaceThreshold (meter)
2022-06-09T19:16:49.817Z,1654802209.817 [CommandExec](IMPORTANT): DAT.verbosity (count)
2022-06-09T19:16:49.817Z,1654802209.817 [CommandExec](IMPORTANT): DDM.loadAtStartup (bool)
2022-06-09T19:16:49.818Z,1654802209.818 [CommandExec](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2022-06-09T19:16:49.819Z,1654802209.819 [CommandExec](IMPORTANT): Depth_Keller.loadAtStartup (bool)
2022-06-09T19:16:49.832Z,1654802209.832 [CommandExec](IMPORTANT): DepthKeller33X.loadAtStartup (bool)
2022-06-09T19:16:49.833Z,1654802209.833 [CommandExec](IMPORTANT): DropWeight.loadAtStartup (bool)
2022-06-09T19:16:49.833Z,1654802209.833 [CommandExec](IMPORTANT): DVL_micro.loadAtStartup (bool)
2022-06-09T19:16:49.834Z,1654802209.834 [CommandExec](IMPORTANT): GobyModem.loadAtStartup (bool)
2022-06-09T19:16:49.835Z,1654802209.835 [CommandExec](IMPORTANT): Micromodem.loadAtStartup (bool)
2022-06-09T19:16:49.835Z,1654802209.835 [CommandExec](IMPORTANT): Micromodem.dataRate (enum)
2022-06-09T19:16:49.837Z,1654802209.837 [CommandExec](IMPORTANT): NAL9602.loadAtStartup (bool)
2022-06-09T19:16:49.838Z,1654802209.838 [CommandExec](IMPORTANT): NanoDVR.loadAtStartup (bool)
2022-06-09T19:16:49.838Z,1654802209.838 [CommandExec](IMPORTANT): Onboard.loadAtStartup (bool)
2022-06-09T19:16:49.839Z,1654802209.839 [CommandExec](IMPORTANT): Phins_Multibeam.loadAtStartup (bool)
2022-06-09T19:16:49.853Z,1654802209.853 [CommandExec](IMPORTANT): Power24vConverter.loadAtStartup (bool)
2022-06-09T19:16:49.854Z,1654802209.854 [CommandExec](IMPORTANT): PowerOnly.loadAtStartup (bool)
2022-06-09T19:16:49.854Z,1654802209.854 [CommandExec](IMPORTANT): PNI_TCM.loadAtStartup (bool)
2022-06-09T19:16:49.855Z,1654802209.855 [CommandExec](IMPORTANT): Radio_Surface.loadAtStartup (bool)
2022-06-09T19:16:49.864Z,1654802209.864 [CommandExec](IMPORTANT): RDI_Pathfinder.loadAtStartup (bool)
2022-06-09T19:16:49.864Z,1654802209.864 [CommandExec](IMPORTANT): RDI_PathfinderUp.loadAtStartup (bool)
2022-06-09T19:16:49.865Z,1654802209.865 [CommandExec](IMPORTANT): Rowe_600.loadAtStartup (bool)
2022-06-09T19:16:49.866Z,1654802209.866 [CommandExec](IMPORTANT): SCPI.loadAtStartup (bool)
2022-06-09T19:16:49.866Z,1654802209.866 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.loadAtStartup (bool)
2022-06-09T19:16:49.867Z,1654802209.867 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.loadAtStartup (bool)
2022-06-09T19:16:49.872Z,1654802209.872 [CommandExec](IMPORTANT): DeadReckonWithRespectToWater.loadAtStartup (bool)
2022-06-09T19:16:49.873Z,1654802209.873 [CommandExec](IMPORTANT): DeadReckonWithRespectToSeafloor.loadAtStartup (bool)
2022-06-09T19:16:49.874Z,1654802209.874 [CommandExec](IMPORTANT): DeadReckonUsingDVLWaterTrack.loadAtStartup (bool)
2022-06-09T19:16:49.874Z,1654802209.874 [CommandExec](IMPORTANT): DeadReckonUsingCompactModelForecast.loadAtStartup (bool)
2022-06-09T19:16:49.875Z,1654802209.875 [CommandExec](IMPORTANT): LBLNavigation.loadAtStartup (bool)
2022-06-09T19:16:49.881Z,1654802209.881 [CommandExec](IMPORTANT): NavChart.loadAtStartup (bool)
2022-06-09T19:16:49.881Z,1654802209.881 [CommandExec](IMPORTANT): UniversalFixResidualReporter.loadAtStartup (bool)
2022-06-09T19:16:49.882Z,1654802209.882 [CommandExec](IMPORTANT): WorkSite.loadAtStartup (bool)
2022-06-09T19:16:49.968Z,1654802209.968 [CommandExec](IMPORTANT): DataOverHttps.enableBroadcast (bool)
2022-06-09T19:16:49.968Z,1654802209.968 [CommandExec](IMPORTANT): DataOverHttps.platform_communications (bool)
2022-06-09T19:16:49.968Z,1654802209.968 [CommandExec](IMPORTANT): DataOverHttps.connectionStatus (bool)
2022-06-09T19:16:49.972Z,1654802209.972 [CommandExec](IMPORTANT): DAT.enableBroadcast (bool)
2022-06-09T19:16:49.972Z,1654802209.972 [CommandExec](IMPORTANT): DAT.component_voltage (volt)
2022-06-09T19:16:49.972Z,1654802209.972 [CommandExec](IMPORTANT): DAT.component_avgVoltage (volt)
2022-06-09T19:16:49.973Z,1654802209.973 [CommandExec](IMPORTANT): DAT.component_current (milliampere)
2022-06-09T19:16:49.973Z,1654802209.973 [CommandExec](IMPORTANT): DAT.component_avgCurrent (milliampere)
2022-06-09T19:16:49.974Z,1654802209.974 [CommandExec](IMPORTANT): DAT.acoustic_contact_address (enum)
2022-06-09T19:16:49.974Z,1654802209.974 [CommandExec](IMPORTANT): DAT.acoustic_contact_direction_vehicle_frame (none)
2022-06-09T19:16:49.974Z,1654802209.974 [CommandExec](IMPORTANT): DAT.platform_communications (bool)
2022-06-09T19:16:49.974Z,1654802209.974 [CommandExec](IMPORTANT): DAT.acoustic_contact_range (meter)
2022-06-09T19:16:49.975Z,1654802209.975 [CommandExec](IMPORTANT): DAT.acoustic_receive_time (epoch_second)
2022-06-09T19:16:49.975Z,1654802209.975 [CommandExec](IMPORTANT): DAT.acoustic_transmit_time (epoch_second)
2022-06-09T19:16:49.000Z,1654802210.000 [CommandExec](IMPORTANT): DAT.LVL1 (count)
2022-06-09T19:16:49.000Z,1654802210.000 [CommandExec](IMPORTANT): DAT.LVL2 (count)
2022-06-09T19:16:50.000Z,1654802210.000 [CommandExec](IMPORTANT): DAT.LVL3 (count)
2022-06-09T19:16:50.001Z,1654802210.001 [CommandExec](IMPORTANT): DAT.LVL4 (count)
2022-06-09T19:16:50.001Z,1654802210.001 [CommandExec](IMPORTANT): DAT.AGC (count)
2022-06-09T19:16:50.001Z,1654802210.001 [CommandExec](IMPORTANT): DAT.phaseA (radian)
2022-06-09T19:16:50.001Z,1654802210.001 [CommandExec](IMPORTANT): DAT.phaseB (radian)
2022-06-09T19:16:50.002Z,1654802210.002 [CommandExec](IMPORTANT): DAT.phaseC (radian)
2022-06-09T19:16:50.002Z,1654802210.002 [CommandExec](IMPORTANT): DAT.rawAzimuth (degree)
2022-06-09T19:16:50.002Z,1654802210.002 [CommandExec](IMPORTANT): DAT.rawElevation (degree)
2022-06-09T19:16:50.003Z,1654802210.003 [CommandExec](IMPORTANT): DAT.calibratedAzimuth (degree)
2022-06-09T19:16:50.003Z,1654802210.003 [CommandExec](IMPORTANT): DAT.calibratedElevation (degree)
2022-06-09T19:16:50.003Z,1654802210.003 [CommandExec](IMPORTANT): DAT.rotatedAzimuth (degree)
2022-06-09T19:16:50.004Z,1654802210.004 [CommandExec](IMPORTANT): DAT.rotatedElevation (degree)
2022-06-09T19:16:50.004Z,1654802210.004 [CommandExec](IMPORTANT): DAT.acoustic_wakeup (count)
2022-06-09T19:16:50.004Z,1654802210.004 [CommandExec](IMPORTANT): DAT.range_request (count)
2022-06-09T19:16:50.005Z,1654802210.005 [CommandExec](IMPORTANT): DAT.localAddressReading (enum)
2022-06-09T19:16:50.005Z,1654802210.005 [CommandExec](IMPORTANT): DAT.deviceEnableRequested (bool)
2022-06-09T19:16:50.005Z,1654802210.005 [CommandExec](IMPORTANT): DAT.azimuth_instrumentFrame (radian)
2022-06-09T19:16:50.006Z,1654802210.006 [CommandExec](IMPORTANT): DAT.elevation_instrumentFrame (radian)
2022-06-09T19:16:50.006Z,1654802210.006 [CommandExec](IMPORTANT): DAT.azimuth_vehicleFrame (radian)
2022-06-09T19:16:50.006Z,1654802210.006 [CommandExec](IMPORTANT): DAT.elevation_vehicleFrame (radian)
2022-06-09T19:16:50.007Z,1654802210.007 [CommandExec](IMPORTANT): DAT.direction_instrumentFrame (none)
2022-06-09T19:17:44.694Z,1654802264.694 [CommandExec](IMPORTANT): got command get DAT.sbdAddress
2022-06-09T19:17:44.695Z,1654802264.695 [CommandExec](IMPORTANT): DAT.sbdAddress 1 enum
2022-06-09T19:18:21.440Z,1654802301.440 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T19:18:21.440Z,1654802301.440 [Default:CheckIn:C.Wait] Stopped
2022-06-09T19:18:21.440Z,1654802301.440 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T19:18:21.440Z,1654802301.440 [Default:CheckIn:D] Running Loop=1
2022-06-09T19:18:21.850Z,1654802301.850 [Default:CheckIn:D] Stopped
2022-06-09T19:18:21.851Z,1654802301.851 [Default:CheckIn:E] Running Loop=1
2022-06-09T19:18:22.243Z,1654802302.243 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.769291 min 
2022-06-09T19:18:22.243Z,1654802302.243 [Default:CheckIn:E] Stopped
2022-06-09T19:18:22.243Z,1654802302.243 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T19:18:22.243Z,1654802302.243 [Default:CheckIn] Stopped
2022-06-09T19:18:22.243Z,1654802302.243 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T19:18:22.243Z,1654802302.243 [Default:CheckIn](INFO): Running loop #10
2022-06-09T19:18:22.244Z,1654802302.244 [Default:CheckIn] Running Loop=10
2022-06-09T19:18:22.244Z,1654802302.244 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T19:18:22.244Z,1654802302.244 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T19:18:24.256Z,1654802304.256 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191823.00,A,3648.31855,N,12147.12323,W,3.402,10.06,090622,,,A*42
2022-06-09T19:18:24.258Z,1654802304.258 [NAL9602](INFO): GPS fix at 20220609T191823: (36.805309, -121.785387)
2022-06-09T19:18:24.270Z,1654802304.270 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T19:18:24.270Z,1654802304.270 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T19:18:31.751Z,1654802311.751 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220609T181654/Courier0037.lzma
2022-06-09T19:18:32.754Z,1654802312.754 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0037.lzma.bak
2022-06-09T19:18:32.754Z,1654802312.754 [DataOverHttps](INFO): SBD MOMSN=16875776
2022-06-09T19:18:39.445Z,1654802319.445 [CommandExec](IMPORTANT): got command get DAT.surfaceThreshold
2022-06-09T19:18:39.446Z,1654802319.446 [CommandExec](IMPORTANT): DAT.surfaceThreshold 0.500000 m
2022-06-09T19:18:49.782Z,1654802329.782 [DataOverHttps](INFO): Sending 1837 bytes from file Logs/20220609T181654/Express0038.lzma
2022-06-09T19:18:50.782Z,1654802330.782 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0038.lzma.bak
2022-06-09T19:18:50.782Z,1654802330.782 [DataOverHttps](INFO): SBD MOMSN=16875779
2022-06-09T19:18:52.224Z,1654802332.224 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T19:18:52.224Z,1654802332.224 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T19:18:52.225Z,1654802332.225 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T19:20:59.066Z,1654802459.066 [NAL9602](INFO): SBD MO Status=2, MOMSN=7869, MT Status=2, MTMSN=0
2022-06-09T19:20:59.067Z,1654802459.067 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-09T19:21:16.031Z,1654802476.031 [NAL9602](INFO): SBD MO Status=2, MOMSN=7869, MT Status=2, MTMSN=0
2022-06-09T19:21:16.031Z,1654802476.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-09T19:21:37.849Z,1654802497.849 [NAL9602](INFO): SBD MO Status=0, MOMSN=7869, MT Status=0, MTMSN=0
2022-06-09T19:21:37.849Z,1654802497.849 [NAL9602](INFO): No messages in MT queue
2022-06-09T19:22:08.593Z,1654802528.593 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T19:23:52.843Z,1654802632.843 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T19:23:52.843Z,1654802632.843 [Default:CheckIn:C.Wait] Stopped
2022-06-09T19:23:52.843Z,1654802632.843 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T19:23:52.843Z,1654802632.843 [Default:CheckIn:D] Running Loop=1
2022-06-09T19:23:53.254Z,1654802633.254 [Default:CheckIn:D] Stopped
2022-06-09T19:23:53.254Z,1654802633.254 [Default:CheckIn:E] Running Loop=1
2022-06-09T19:23:53.671Z,1654802633.671 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.292688 min 
2022-06-09T19:23:53.671Z,1654802633.671 [Default:CheckIn:E] Stopped
2022-06-09T19:23:53.676Z,1654802633.676 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T19:23:53.676Z,1654802633.676 [Default:CheckIn] Stopped
2022-06-09T19:23:53.676Z,1654802633.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T19:23:53.676Z,1654802633.676 [Default:CheckIn](INFO): Running loop #11
2022-06-09T19:23:53.676Z,1654802633.676 [Default:CheckIn] Running Loop=11
2022-06-09T19:23:53.676Z,1654802633.676 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T19:23:53.676Z,1654802633.676 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T19:23:55.671Z,1654802635.671 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192354.00,A,3648.35441,N,12147.51806,W,11.780,235.63,090622,,,A*40
2022-06-09T19:23:55.685Z,1654802635.685 [NAL9602](INFO): GPS fix at 20220609T192354: (36.805907, -121.791968)
2022-06-09T19:23:55.706Z,1654802635.706 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T19:23:55.706Z,1654802635.706 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T19:24:03.475Z,1654802643.475 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220609T181654/Courier0040.lzma
2022-06-09T19:24:04.477Z,1654802644.477 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0040.lzma.bak
2022-06-09T19:24:04.478Z,1654802644.478 [DataOverHttps](INFO): SBD MOMSN=16876006
2022-06-09T19:24:15.054Z,1654802655.054 [NAL9602](INFO): SBD MO Status=0, MOMSN=7870, MT Status=0, MTMSN=0
2022-06-09T19:24:15.054Z,1654802655.054 [NAL9602](INFO): No messages in MT queue
2022-06-09T19:24:20.540Z,1654802660.540 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20220609T181654/Express0041.lzma
2022-06-09T19:24:21.542Z,1654802661.542 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0041.lzma.bak
2022-06-09T19:24:21.542Z,1654802661.542 [DataOverHttps](INFO): SBD MOMSN=16876010
2022-06-09T19:24:23.164Z,1654802663.164 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T19:24:23.164Z,1654802663.164 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T19:24:23.164Z,1654802663.164 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T19:24:45.751Z,1654802685.751 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T19:26:02.137Z,1654802762.137 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-09T19:26:13.099Z,1654802773.099 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006138
CHAN A1 (24V): 0.000958
CHAN A2 (12V): -0.005264
CHAN A3 (5V): -0.002872
CHAN B0 (3.3V): -0.001364
CHAN B1 (3.15aV): -0.001640
CHAN B2 (3.15bV): -0.001149
CHAN B3 (GND): 0.000032
OPEN: 0.004824
Full Scale: +/- 1 mA
2022-06-09T19:29:23.728Z,1654802963.728 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T19:29:23.728Z,1654802963.728 [Default:CheckIn:C.Wait] Stopped
2022-06-09T19:29:23.728Z,1654802963.728 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T19:29:23.728Z,1654802963.728 [Default:CheckIn:D] Running Loop=1
2022-06-09T19:29:24.139Z,1654802964.139 [Default:CheckIn:D] Stopped
2022-06-09T19:29:24.139Z,1654802964.139 [Default:CheckIn:E] Running Loop=1
2022-06-09T19:29:24.550Z,1654802964.550 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.807426 min 
2022-06-09T19:29:24.550Z,1654802964.550 [Default:CheckIn:E] Stopped
2022-06-09T19:29:24.550Z,1654802964.550 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T19:29:24.551Z,1654802964.551 [Default:CheckIn] Stopped
2022-06-09T19:29:24.551Z,1654802964.551 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T19:29:24.551Z,1654802964.551 [Default:CheckIn](INFO): Running loop #12
2022-06-09T19:29:24.551Z,1654802964.551 [Default:CheckIn] Running Loop=12
2022-06-09T19:29:24.551Z,1654802964.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T19:29:24.551Z,1654802964.551 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T19:29:26.624Z,1654802966.624 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192925.00,A,3648.13528,N,12148.95920,W,13.529,259.91,090622,,,A*40
2022-06-09T19:29:26.626Z,1654802966.626 [NAL9602](INFO): GPS fix at 20220609T192925: (36.802255, -121.815987)
2022-06-09T19:29:26.646Z,1654802966.646 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T19:29:26.646Z,1654802966.646 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T19:29:43.627Z,1654802983.627 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220609T181654/Courier0043.lzma
2022-06-09T19:29:44.630Z,1654802984.630 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0043.lzma.bak
2022-06-09T19:29:44.630Z,1654802984.630 [DataOverHttps](INFO): SBD MOMSN=16876016
2022-06-09T19:29:47.555Z,1654802987.555 [NAL9602](INFO): SBD MO Status=2, MOMSN=7871, MT Status=2, MTMSN=0
2022-06-09T19:29:47.555Z,1654802987.555 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-09T19:29:58.058Z,1654802998.058 [NAL9602](INFO): SBD MO Status=0, MOMSN=7871, MT Status=0, MTMSN=0
2022-06-09T19:29:58.058Z,1654802998.058 [NAL9602](INFO): No messages in MT queue
2022-06-09T19:30:28.763Z,1654803028.763 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T19:30:45.329Z,1654803045.329 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.700375
2022-06-09T19:30:54.516Z,1654803054.516 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002832
2022-06-09T19:31:02.036Z,1654803062.036 [DataOverHttps](INFO): Sending 317 bytes from file Logs/20220609T181654/Express0044.lzma
2022-06-09T19:31:03.038Z,1654803063.038 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0044.lzma.bak
2022-06-09T19:31:03.038Z,1654803063.038 [DataOverHttps](INFO): SBD MOMSN=16876019
2022-06-09T19:31:04.509Z,1654803064.509 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T19:31:04.509Z,1654803064.509 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T19:31:04.509Z,1654803064.509 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T19:31:11.301Z,1654803071.301 [NAL9602](INFO): SBD MO Status=2, MOMSN=7872, MT Status=2, MTMSN=0
2022-06-09T19:31:11.301Z,1654803071.301 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-09T19:31:42.021Z,1654803102.021 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T19:31:54.129Z,1654803114.129 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-06-09T19:31:54.156Z,1654803114.156 [BPC1](INFO): Received data from all battery sticks.
2022-06-09T19:36:05.090Z,1654803365.090 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-09T19:36:05.091Z,1654803365.091 [Default:CheckIn:C.Wait] Stopped
2022-06-09T19:36:05.091Z,1654803365.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-09T19:36:05.091Z,1654803365.091 [Default:CheckIn:D] Running Loop=1
2022-06-09T19:36:05.503Z,1654803365.503 [Default:CheckIn:D] Stopped
2022-06-09T19:36:05.503Z,1654803365.503 [Default:CheckIn:E] Running Loop=1
2022-06-09T19:36:05.897Z,1654803365.897 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.496834 min 
2022-06-09T19:36:05.897Z,1654803365.897 [Default:CheckIn:E] Stopped
2022-06-09T19:36:05.897Z,1654803365.897 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-09T19:36:05.897Z,1654803365.897 [Default:CheckIn] Stopped
2022-06-09T19:36:05.898Z,1654803365.898 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-09T19:36:05.898Z,1654803365.898 [Default:CheckIn](INFO): Running loop #13
2022-06-09T19:36:05.898Z,1654803365.898 [Default:CheckIn] Running Loop=13
2022-06-09T19:36:05.898Z,1654803365.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-09T19:36:05.898Z,1654803365.898 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-09T19:36:07.902Z,1654803367.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193607.00,A,3647.84213,N,12150.81221,W,1.361,295.27,090622,,,A*72
2022-06-09T19:36:07.904Z,1654803367.904 [NAL9602](INFO): GPS fix at 20220609T193607: (36.797369, -121.846870)
2022-06-09T19:36:07.916Z,1654803367.916 [Default:CheckIn:Read_GPS] Stopped
2022-06-09T19:36:07.916Z,1654803367.916 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-09T19:36:15.719Z,1654803375.719 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20220609T181654/Courier0046.lzma
2022-06-09T19:36:16.721Z,1654803376.721 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Courier0046.lzma.bak
2022-06-09T19:36:16.722Z,1654803376.722 [DataOverHttps](INFO): SBD MOMSN=16876025
2022-06-09T19:36:32.964Z,1654803392.964 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220609T181654/Express0047.lzma
2022-06-09T19:36:34.970Z,1654803394.970 [DataOverHttps](INFO): Moved sent file to Logs/20220609T181654/Express0047.lzma.bak
2022-06-09T19:36:34.970Z,1654803394.970 [DataOverHttps](INFO): SBD MOMSN=16876028
2022-06-09T19:36:36.218Z,1654803396.218 [Default:CheckIn:Read_Iridium] Stopped
2022-06-09T19:36:36.218Z,1654803396.218 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-09T19:36:36.218Z,1654803396.218 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-09T19:36:55.982Z,1654803415.982 [NAL9602](INFO): SBD MO Status=1, MOMSN=7872, MT Status=0, MTMSN=0
2022-06-09T19:36:55.982Z,1654803415.982 [NAL9602](INFO): No messages in MT queue
2022-06-09T19:37:26.684Z,1654803446.684 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-09T19:39:55.030Z,1654803595.030 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -27.00 mm (1 active estimators).
2022-06-09T19:40:53.037Z,1654803653.037 [DataOverHttps](IMPORTANT): SBD MTMSN=20220609T194052