2022-04-06T16:47:59.815Z,1649263679.815 [Supervisor](DEBUG): Initializing supervisor.
2022-04-06T16:47:59.820Z,1649263679.820 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-04-06T16:47:59.820Z,1649263679.820 [SyncHandler](INFO): Protected caller Thread ID is 3007
2022-04-06T16:47:59.821Z,1649263679.821 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-04-06T16:47:59.821Z,1649263679.821 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-04-06T16:47:59.822Z,1649263679.822 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3008
2022-04-06T16:47:59.826Z,1649263679.826 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-04-06T16:47:59.844Z,1649263679.844 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-04-06T16:47:59.845Z,1649263679.845 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-04-06T16:47:59.845Z,1649263679.845 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3009
2022-04-06T16:47:59.849Z,1649263679.849 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-04-06T16:47:59.850Z,1649263679.850 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-04-06T16:47:59.851Z,1649263679.851 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3010
2022-04-06T16:47:59.853Z,1649263679.853 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-04-06T16:47:59.853Z,1649263679.853 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-04-06T16:47:59.854Z,1649263679.854 [logger ThreadHandler](INFO): Protected caller Thread ID is 3011
2022-04-06T16:47:59.857Z,1649263679.857 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-04-06T16:47:59.858Z,1649263679.858 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-04-06T16:47:59.859Z,1649263679.859 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-04-06T16:48:00.273Z,1649263680.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-04-06T16:48:00.273Z,1649263680.273 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-04-06T16:48:00.484Z,1649263680.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-04-06T16:48:00.484Z,1649263680.484 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-04-06T16:48:00.669Z,1649263680.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-04-06T16:48:00.669Z,1649263680.669 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-04-06T16:48:00.775Z,1649263680.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-04-06T16:48:00.776Z,1649263680.776 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-04-06T16:48:01.130Z,1649263681.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-04-06T16:48:01.131Z,1649263681.131 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-04-06T16:48:01.460Z,1649263681.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-04-06T16:48:01.460Z,1649263681.460 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-04-06T16:48:01.556Z,1649263681.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-04-06T16:48:01.556Z,1649263681.556 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-04-06T16:48:01.770Z,1649263681.770 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-04-06T16:48:01.770Z,1649263681.770 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-04-06T16:48:02.370Z,1649263682.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-04-06T16:48:02.371Z,1649263682.371 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-04-06T16:48:03.049Z,1649263683.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-04-06T16:48:03.050Z,1649263683.050 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-04-06T16:48:03.130Z,1649263683.130 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-04-06T16:48:03.339Z,1649263683.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-04-06T16:48:03.340Z,1649263683.340 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-04-06T16:48:03.465Z,1649263683.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-04-06T16:48:03.465Z,1649263683.465 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-04-06T16:48:04.176Z,1649263684.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-04-06T16:48:04.176Z,1649263684.176 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-04-06T16:48:04.256Z,1649263684.256 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-04-06T16:48:04.540Z,1649263684.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-04-06T16:48:04.542Z,1649263684.542 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2022-04-06T16:48:04.543Z,1649263684.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2022-04-06T16:48:04.722Z,1649263684.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2022-04-06T16:48:04.815Z,1649263684.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2022-04-06T16:48:04.927Z,1649263684.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2022-04-06T16:48:05.027Z,1649263685.027 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2022-04-06T16:48:05.110Z,1649263685.110 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2022-04-06T16:48:05.196Z,1649263685.196 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2022-04-06T16:48:05.311Z,1649263685.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2022-04-06T16:48:05.411Z,1649263685.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2022-04-06T16:48:05.567Z,1649263685.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2022-04-06T16:48:05.829Z,1649263685.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-04-06T16:48:05.829Z,1649263685.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2022-04-06T16:48:05.923Z,1649263685.923 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-04-06T16:48:05.938Z,1649263685.938 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-04-06T16:48:06.245Z,1649263686.245 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-04-06T16:48:06.246Z,1649263686.246 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-04-06T16:48:06.355Z,1649263686.355 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-04-06T16:48:06.356Z,1649263686.356 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-04-06T16:48:06.393Z,1649263686.393 [DeadReckonUsingSpeedCalculator] Loaded
2022-04-06T16:48:06.394Z,1649263686.394 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2022-04-06T16:48:06.407Z,1649263686.407 [NavChart] Loaded
2022-04-06T16:48:06.407Z,1649263686.407 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-04-06T16:48:06.412Z,1649263686.412 [UniversalFixResidualReporter] Loaded
2022-04-06T16:48:06.413Z,1649263686.413 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-04-06T16:48:06.413Z,1649263686.413 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-04-06T16:48:06.414Z,1649263686.414 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-04-06T16:48:06.424Z,1649263686.424 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-04-06T16:48:06.424Z,1649263686.424 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-04-06T16:48:06.614Z,1649263686.614 [CTD_Seabird] Loaded
2022-04-06T16:48:06.614Z,1649263686.614 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-04-06T16:48:06.615Z,1649263686.615 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406614E0
2022-04-06T16:48:06.616Z,1649263686.616 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3093
2022-04-06T16:48:06.635Z,1649263686.635 [PAR_Licor] Loaded
2022-04-06T16:48:06.635Z,1649263686.635 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-04-06T16:48:06.665Z,1649263686.665 [WetLabsBB2FL] Loaded
2022-04-06T16:48:06.666Z,1649263686.666 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-04-06T16:48:06.667Z,1649263686.667 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406914E0
2022-04-06T16:48:06.667Z,1649263686.667 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3094
2022-04-06T16:48:06.668Z,1649263686.668 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-04-06T16:48:06.668Z,1649263686.668 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-04-06T16:48:06.720Z,1649263686.720 [VerticalControl](DEBUG): Construct VerticalControl.
2022-04-06T16:48:06.781Z,1649263686.781 [VerticalControl] Loaded
2022-04-06T16:48:06.781Z,1649263686.781 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-04-06T16:48:06.784Z,1649263686.784 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-04-06T16:48:06.823Z,1649263686.823 [HorizontalControl] Loaded
2022-04-06T16:48:06.823Z,1649263686.823 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-04-06T16:48:06.826Z,1649263686.826 [SpeedControl](DEBUG): Construct SpeedControl.
2022-04-06T16:48:06.829Z,1649263686.829 [SpeedControl] Loaded
2022-04-06T16:48:06.829Z,1649263686.829 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-04-06T16:48:06.832Z,1649263686.832 [LoopControl](DEBUG): Construct LoopControl.
2022-04-06T16:48:06.832Z,1649263686.832 [LoopControl] Loaded
2022-04-06T16:48:06.833Z,1649263686.833 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-04-06T16:48:06.833Z,1649263686.833 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-04-06T16:48:06.834Z,1649263686.834 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-04-06T16:48:06.882Z,1649263686.882 [DepthRateCalculator] Loaded
2022-04-06T16:48:06.883Z,1649263686.883 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-04-06T16:48:06.887Z,1649263686.887 [PitchRateCalculator] Loaded
2022-04-06T16:48:06.888Z,1649263686.888 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-04-06T16:48:06.897Z,1649263686.897 [SpeedCalculator] Loaded
2022-04-06T16:48:06.897Z,1649263686.897 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-04-06T16:48:06.902Z,1649263686.902 [YawRateCalculator] Loaded
2022-04-06T16:48:06.902Z,1649263686.902 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-04-06T16:48:06.919Z,1649263686.919 [ElevatorOffsetCalculator] Loaded
2022-04-06T16:48:06.920Z,1649263686.920 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-04-06T16:48:06.920Z,1649263686.920 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-04-06T16:48:06.921Z,1649263686.921 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-04-06T16:48:07.014Z,1649263687.014 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-04-06T16:48:07.015Z,1649263687.015 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-04-06T16:48:07.072Z,1649263687.072 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-04-06T16:48:07.072Z,1649263687.072 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-04-06T16:48:07.195Z,1649263687.195 [BuoyancyServo] Loaded
2022-04-06T16:48:07.195Z,1649263687.195 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-04-06T16:48:07.214Z,1649263687.214 [ElevatorServo] Loaded
2022-04-06T16:48:07.215Z,1649263687.215 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-04-06T16:48:07.233Z,1649263687.233 [MassServo] Loaded
2022-04-06T16:48:07.233Z,1649263687.233 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-04-06T16:48:07.251Z,1649263687.251 [RudderServo] Loaded
2022-04-06T16:48:07.252Z,1649263687.252 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-04-06T16:48:07.266Z,1649263687.266 [ThrusterHE] Loaded
2022-04-06T16:48:07.267Z,1649263687.267 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-04-06T16:48:07.267Z,1649263687.267 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-04-06T16:48:07.267Z,1649263687.267 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-04-06T16:48:07.374Z,1649263687.374 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-04-06T16:48:07.374Z,1649263687.374 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-04-06T16:48:08.085Z,1649263688.085 [AHRS_M2] Loaded
2022-04-06T16:48:08.085Z,1649263688.085 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-04-06T16:48:08.121Z,1649263688.121 [BackseatComponent] Loaded
2022-04-06T16:48:08.121Z,1649263688.121 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-04-06T16:48:08.122Z,1649263688.122 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A884E0
2022-04-06T16:48:08.122Z,1649263688.122 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3095
2022-04-06T16:48:08.125Z,1649263688.125 [LcmUniversalReporter] Loaded
2022-04-06T16:48:08.125Z,1649263688.125 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-04-06T16:48:08.951Z,1649263688.951 [BPC1] Loaded
2022-04-06T16:48:08.951Z,1649263688.951 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-04-06T16:48:09.026Z,1649263689.026 [DataOverHttps] Loaded
2022-04-06T16:48:09.027Z,1649263689.027 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-04-06T16:48:09.028Z,1649263689.028 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AB84E0
2022-04-06T16:48:09.028Z,1649263689.028 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3096
2022-04-06T16:48:09.048Z,1649263689.048 [Depth_Keller] Loaded
2022-04-06T16:48:09.048Z,1649263689.048 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-04-06T16:48:09.107Z,1649263689.107 [NAL9602] Loaded
2022-04-06T16:48:09.107Z,1649263689.107 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-04-06T16:48:09.136Z,1649263689.136 [Onboard] Loaded
2022-04-06T16:48:09.136Z,1649263689.136 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-04-06T16:48:09.137Z,1649263689.137 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AE84E0
2022-04-06T16:48:09.137Z,1649263689.137 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3097
2022-04-06T16:48:09.149Z,1649263689.149 [Power24vConverter] Loaded
2022-04-06T16:48:09.149Z,1649263689.149 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-04-06T16:48:09.162Z,1649263689.162 [Radio_Surface] Loaded
2022-04-06T16:48:09.162Z,1649263689.162 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-04-06T16:48:09.163Z,1649263689.163 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B184E0
2022-04-06T16:48:09.163Z,1649263689.163 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3098
2022-04-06T16:48:09.250Z,1649263689.250 [DAT] Loaded
2022-04-06T16:48:09.250Z,1649263689.250 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-04-06T16:48:09.252Z,1649263689.252 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B484E0
2022-04-06T16:48:09.252Z,1649263689.252 [DAT ThreadHandler](INFO): Protected caller Thread ID is 3099
2022-04-06T16:48:09.252Z,1649263689.252 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-04-06T16:48:09.253Z,1649263689.253 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-04-06T16:48:09.395Z,1649263689.395 [SBIT](DEBUG): Construct Startup Built In Test.
2022-04-06T16:48:09.404Z,1649263689.404 [SBIT] Loaded
2022-04-06T16:48:09.405Z,1649263689.405 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-04-06T16:48:09.407Z,1649263689.407 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-04-06T16:48:09.422Z,1649263689.422 [IBIT] Loaded
2022-04-06T16:48:09.422Z,1649263689.422 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-04-06T16:48:09.428Z,1649263689.428 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-04-06T16:48:09.532Z,1649263689.532 [CBIT] Loaded
2022-04-06T16:48:09.533Z,1649263689.533 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-04-06T16:48:09.533Z,1649263689.533 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-04-06T16:48:09.533Z,1649263689.533 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-04-06T16:48:09.560Z,1649263689.560 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-04-06T16:48:09.566Z,1649263689.566 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-04-06T16:48:09.568Z,1649263689.568 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-04-06T16:48:09.579Z,1649263689.579 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-04-06T16:48:09.580Z,1649263689.580 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD64E0
2022-04-06T16:48:09.580Z,1649263689.580 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3100
2022-04-06T16:48:09.585Z,1649263689.585 [Supervisor](INFO): Main Thread ID is 830
2022-04-06T16:48:09.585Z,1649263689.585 [Supervisor](DEBUG): Running supervisor.
2022-04-06T16:48:09.586Z,1649263689.586 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3101
2022-04-06T16:48:09.586Z,1649263689.586 [CommandExec](INFO): Initializing the command executive.
2022-04-06T16:48:09.588Z,1649263689.588 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3102
2022-04-06T16:48:09.589Z,1649263689.589 [controlThread ThreadHandler](INFO): Handler Thread ID is 3103
2022-04-06T16:48:09.590Z,1649263689.590 [controlThread](DEBUG): Initializing ControlThread
2022-04-06T16:48:09.591Z,1649263689.591 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T16:48:09.592Z,1649263689.592 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-04-06T16:48:09.592Z,1649263689.592 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-04-06T16:48:09.595Z,1649263689.595 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-04-06T16:48:09.597Z,1649263689.597 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-04-06T16:48:09.597Z,1649263689.597 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-04-06T16:48:09.598Z,1649263689.598 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-04-06T16:48:09.598Z,1649263689.598 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-04-06T16:48:09.599Z,1649263689.599 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-04-06T16:48:09.599Z,1649263689.599 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-04-06T16:48:09.599Z,1649263689.599 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-04-06T16:48:09.600Z,1649263689.600 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-04-06T16:48:09.603Z,1649263689.603 [SBIT](INFO): Initialize SBIT Component.
2022-04-06T16:48:09.604Z,1649263689.604 [SBIT](IMPORTANT): git: 2022-04-05
2022-04-06T16:48:09.604Z,1649263689.604 [SBIT](INFO): git hash: cccad0d8b20443efe47ed2aff4809dc4b5ecd32b
2022-04-06T16:48:09.604Z,1649263689.604 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-04-06T16:48:09.605Z,1649263689.605 [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-04-06T16:48:09.606Z,1649263689.606 [SBIT](INFO): Beginning SBIT in 34.000000 seconds.
2022-04-06T16:48:09.607Z,1649263689.607 [IBIT](INFO): Initialize IBIT Component.
2022-04-06T16:48:09.608Z,1649263689.608 [CBIT](DEBUG): Initialize CBIT Component.
2022-04-06T16:48:09.609Z,1649263689.609 [logger ThreadHandler](INFO): Handler Thread ID is 3104
2022-04-06T16:48:09.619Z,1649263689.619 [CBIT](DEBUG): Initialized mux pins.
2022-04-06T16:48:09.619Z,1649263689.619 [CBIT](DEBUG): Initializing the watchdog timer.
2022-04-06T16:48:09.627Z,1649263689.627 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3105
2022-04-06T16:48:09.628Z,1649263689.628 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-04-06T16:48:09.632Z,1649263689.632 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3107
2022-04-06T16:48:09.635Z,1649263689.635 [WetLabsBB2FL](INFO): Powering up
2022-04-06T16:48:09.636Z,1649263689.636 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3109
2022-04-06T16:48:09.643Z,1649263689.643 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-04-06T16:48:09.643Z,1649263689.643 [CBIT](DEBUG): Initializing heartbeat.
2022-04-06T16:48:09.644Z,1649263689.644 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3110
2022-04-06T16:48:09.645Z,1649263689.645 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-04-06T16:48:09.659Z,1649263689.659 [Onboard ThreadHandler](INFO): Handler Thread ID is 3111
2022-04-06T16:48:09.677Z,1649263689.677 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3112
2022-04-06T16:48:09.699Z,1649263689.699 [DAT ThreadHandler](INFO): Handler Thread ID is 3113
2022-04-06T16:48:09.700Z,1649263689.700 [DAT](INFO): Powering up
2022-04-06T16:48:09.700Z,1649263689.700 [DAT](DEBUG): Initializing DAT.
2022-04-06T16:48:09.704Z,1649263689.704 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3115
2022-04-06T16:48:09.707Z,1649263689.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-04-06T16:48:09.707Z,1649263689.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-04-06T16:48:09.707Z,1649263689.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-04-06T16:48:09.707Z,1649263689.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-04-06T16:48:09.707Z,1649263689.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-04-06T16:48:09.708Z,1649263689.708 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-04-06T16:48:09.708Z,1649263689.708 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-04-06T16:48:09.708Z,1649263689.708 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-04-06T16:48:09.708Z,1649263689.708 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-04-06T16:48:09.708Z,1649263689.708 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-04-06T16:48:09.708Z,1649263689.708 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-04-06T16:48:09.709Z,1649263689.709 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-04-06T16:48:09.709Z,1649263689.709 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-04-06T16:48:09.709Z,1649263689.709 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-04-06T16:48:09.709Z,1649263689.709 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-04-06T16:48:09.709Z,1649263689.709 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-04-06T16:48:09.715Z,1649263689.715 [CBIT](DEBUG): Deactivating GF circuits.
2022-04-06T16:48:09.715Z,1649263689.715 [CBIT](DEBUG): Deactivating emergency mode.
2022-04-06T16:48:09.751Z,1649263689.751 [CBIT](DEBUG): Backplane powered.
2022-04-06T16:48:09.752Z,1649263689.752 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-04-06T16:48:09.764Z,1649263689.764 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-06T16:48:09.783Z,1649263689.783 [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-04-06T16:48:09.783Z,1649263689.783 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-04-06T16:48:09.852Z,1649263689.852 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-04-06T16:48:09.853Z,1649263689.853 [Default:A.Wait](DEBUG): Construct Wait.
2022-04-06T16:48:09.871Z,1649263689.871 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-06T16:48:09.899Z,1649263689.899 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-04-06T16:48:09.901Z,1649263689.901 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-04-06T16:48:09.923Z,1649263689.923 [Default:E.Execute](DEBUG): Construct Execute.
2022-04-06T16:48:09.943Z,1649263689.943 [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-04-06T16:48:09.947Z,1649263689.947 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,NAL9602,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-04-06T16:48:09.996Z,1649263689.996 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-04-06T16:48:10.062Z,1649263690.062 [Power24vConverter](INFO): Powering up.
2022-04-06T16:48:10.083Z,1649263690.083 [Radio_Surface](INFO): Powering up
2022-04-06T16:48:10.111Z,1649263690.111 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-04-06T16:48:10.119Z,1649263690.119 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-04-06T16:48:10.120Z,1649263690.120 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-04-06T16:48:10.131Z,1649263690.131 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-04-06T16:48:10.132Z,1649263690.132 [MassServo](DEBUG): Initializing EZServoServo.
2022-04-06T16:48:10.139Z,1649263690.139 [MassServo](DEBUG): Initializing MassServo.
2022-04-06T16:48:10.140Z,1649263690.140 [RudderServo](DEBUG): Initializing EZServoServo.
2022-04-06T16:48:10.147Z,1649263690.147 [RudderServo](DEBUG): Initializing RudderServo.
2022-04-06T16:48:10.148Z,1649263690.148 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-04-06T16:48:10.155Z,1649263690.155 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-04-06T16:48:11.115Z,1649263691.115 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-04-06T16:48:11.115Z,1649263691.115 [RudderServo](FAULT): Rudder failed to initialize
2022-04-06T16:48:11.115Z,1649263691.115 [RudderServo] Communications Fault, FailCount= 1
2022-04-06T16:48:11.115Z,1649263691.115 [RudderServo](ERROR): Communications Fault
2022-04-06T16:48:11.119Z,1649263691.119 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-04-06T16:48:11.301Z,1649263691.301 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-04-06T16:48:11.302Z,1649263691.302 [RudderServo](INFO): Powering down
2022-04-06T16:48:12.003Z,1649263692.003 [RudderServo](DEBUG): Initializing EZServoServo.
2022-04-06T16:48:12.124Z,1649263692.124 [RudderServo](DEBUG): Initializing RudderServo.
2022-04-06T16:48:12.129Z,1649263692.129 [CBIT](INFO): Clearing failed state for component RudderServo
2022-04-06T16:48:12.129Z,1649263692.129 [RudderServo] No Fault, FailCount= 1
2022-04-06T16:48:12.247Z,1649263692.247 [WetLabsBB2FL](INFO): Powering down
2022-04-06T16:48:15.880Z,1649263695.880 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-04-06T16:48:16.663Z,1649263696.663 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-04-06T16:48:21.297Z,1649263701.297 [DAT](INFO): DAT read: 

2022-04-06T16:48:21.299Z,1649263701.299 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2022-04-06T16:48:22.808Z,1649263702.808 [DAT](INFO): DAT read: MF Frequency Band

2022-04-06T16:48:22.809Z,1649263702.809 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2022-04-06T16:48:22.809Z,1649263702.809 [DAT](INFO): DAT read: Apr  6 2022 16:48:17 

2022-04-06T16:48:23.816Z,1649263703.816 [DAT](INFO): DAT read: Features enabled [Bearing]

2022-04-06T16:48:23.817Z,1649263703.817 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2022-04-06T16:48:23.817Z,1649263703.817 [DAT](INFO): commRate: 800
2022-04-06T16:48:25.883Z,1649263705.883 [DAT](INFO): entering command mode
2022-04-06T16:48:26.083Z,1649263706.083 [DAT](INFO): DAT read: 

2022-04-06T16:48:26.084Z,1649263706.084 [DAT](INFO): DAT read: user:1>

2022-04-06T16:48:26.084Z,1649263706.084 [DAT](INFO): setting verbose to 3
2022-04-06T16:48:26.335Z,1649263706.335 [DAT](INFO): DAT read: user:1>

2022-04-06T16:48:26.336Z,1649263706.336 [DAT](INFO): DAT read: Verbose         | 3           

2022-04-06T16:48:26.337Z,1649263706.337 [DAT](INFO): set verbose to 3
2022-04-06T16:48:26.337Z,1649263706.337 [DAT](INFO): setting DatVerbose to 27440
2022-04-06T16:48:26.587Z,1649263706.587 [DAT](INFO): DAT read: user:2>

2022-04-06T16:48:26.588Z,1649263706.588 [DAT](INFO): DAT read: DatVerbose      | 27440       

2022-04-06T16:48:26.588Z,1649263706.588 [DAT](INFO): set DatVerbose to 27440
2022-04-06T16:48:26.589Z,1649263706.589 [DAT](INFO): setting transmit power to 8
2022-04-06T16:48:26.839Z,1649263706.839 [DAT](INFO): DAT read: user:3>

2022-04-06T16:48:26.840Z,1649263706.840 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2022-04-06T16:48:26.841Z,1649263706.841 [DAT](INFO): set transmit power to 8
2022-04-06T16:48:26.841Z,1649263706.841 [DAT](INFO): setting local address to 10
2022-04-06T16:48:27.091Z,1649263707.091 [DAT](INFO): DAT read: user:4>

2022-04-06T16:48:27.092Z,1649263707.092 [DAT](INFO): DAT read: LocalAddr       | 10          

2022-04-06T16:48:27.093Z,1649263707.093 [DAT](INFO): set local address to 10
2022-04-06T16:48:27.094Z,1649263707.094 [DAT](INFO): Setting time to: 16:48:27 And date to:4/6/2022
2022-04-06T16:48:27.343Z,1649263707.343 [DAT](INFO): DAT read: user:5>

2022-04-06T16:48:27.344Z,1649263707.344 [DAT](INFO): DAT read: Wed Apr 6, 2022  16:48:27  

2022-04-06T16:48:27.344Z,1649263707.344 [DAT](INFO): Local DAT time set to Wed Apr 6, 2022  16:48:27  

2022-04-06T16:48:39.456Z,1649263719.456 [NAL9602](INFO): Powering up NAL9602
2022-04-06T16:48:44.328Z,1649263724.328 [SBIT](IMPORTANT): Beginning Startup BIT
2022-04-06T16:48:44.336Z,1649263724.336 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-06T16:48:50.368Z,1649263730.368 [NAL9602](INFO): NAL9602 initialized
2022-04-06T16:48:55.289Z,1649263735.289 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006812
CHAN A1 (24V): 0.000686
CHAN A2 (12V): -0.005470
CHAN A3 (5V): -0.002199
CHAN B0 (3.3V): -0.001067
CHAN B1 (3.15aV): -0.001777
CHAN B2 (3.15bV): -0.001606
CHAN B3 (GND): -0.000431
OPEN: 0.004649
Full Scale: +/- 1 mA
2022-04-06T16:49:38.456Z,1649263778.456 [SBIT](IMPORTANT): SBIT PASSED
2022-04-06T16:49:38.457Z,1649263778.457 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-04-06T16:49:38.458Z,1649263778.458 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool;

2022-04-06T16:49:38.458Z,1649263778.458 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=6 none;

2022-04-06T16:49:38.458Z,1649263778.458 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere;

2022-04-06T16:49:38.458Z,1649263778.458 [SBIT](IMPORTANT): CBIT.gfScanTimeout=0.5 hour;

2022-04-06T16:49:38.460Z,1649263778.460 [SBIT](IMPORTANT): DAT.sbdAddress=21 enum;

2022-04-06T16:49:38.461Z,1649263778.461 [SBIT](IMPORTANT): DAT.surfaceThreshold=3 meter;

2022-04-06T16:49:38.461Z,1649263778.461 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2022-04-06T16:49:38.461Z,1649263778.461 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2022-04-06T16:49:38.461Z,1649263778.461 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 10.000000 gigabyte;

2022-04-06T16:49:38.461Z,1649263778.461 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 100.000000 count;

2022-04-06T16:49:38.461Z,1649263778.461 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_size 2.500000 millimeter;

2022-04-06T16:49:38.461Z,1649263778.461 [SBIT](IMPORTANT): Express linearApproximation _.eyeris_particle_counts 100.000000 count;

2022-04-06T16:49:38.461Z,1649263778.461 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour;

2022-04-06T16:49:38.462Z,1649263778.462 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt;

2022-04-06T16:49:38.462Z,1649263778.462 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;

2022-04-06T16:49:38.462Z,1649263778.462 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=157.684833 cubic_centimeter;

2022-04-06T16:49:38.462Z,1649263778.462 [SBIT](IMPORTANT): VerticalControl.massDefault=-3.662165 millimeter;

2022-04-06T16:49:38.876Z,1649263778.876 [MissionManager](IMPORTANT): Started mission Startup
2022-04-06T16:49:38.877Z,1649263778.877 [Startup] Running Loop=1
2022-04-06T16:49:38.877Z,1649263778.877 [Startup](DEBUG): Aggregate::initialize Startup
2022-04-06T16:49:38.877Z,1649263778.877 [Startup:A.GoToSurface] Running Loop=1
2022-04-06T16:49:38.877Z,1649263778.877 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-06T16:49:38.878Z,1649263778.878 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-06T16:49:38.878Z,1649263778.878 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-06T16:49:38.879Z,1649263778.879 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-06T16:49:38.879Z,1649263778.879 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-06T16:49:38.879Z,1649263778.879 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-06T16:49:38.881Z,1649263778.881 [Startup:StartupSatComms] Running Loop=1
2022-04-06T16:49:38.881Z,1649263778.881 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-04-06T16:49:38.881Z,1649263778.881 [Startup:StartupSatComms:A] Running Loop=1
2022-04-06T16:49:39.285Z,1649263779.285 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-04-06T16:50:39.056Z,1649263839.056 [Startup:StartupSatComms:A](INFO): Timed out from 2022-04-06T16:49:38.9Z
2022-04-06T16:50:39.057Z,1649263839.057 [Startup:StartupSatComms:A] Stopped
2022-04-06T16:50:39.057Z,1649263839.057 [Startup:StartupSatComms:B] Running Loop=1
2022-04-06T16:50:39.457Z,1649263839.457 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-06T16:51:09.775Z,1649263869.775 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-04-06T16:51:09.775Z,1649263869.775 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T16:51:09.776Z,1649263869.776 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2022-04-06T16:51:09.776Z,1649263869.776 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T16:51:09.787Z,1649263869.787 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T16:51:09.787Z,1649263869.787 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T16:51:10.185Z,1649263870.185 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T16:51:10.185Z,1649263870.185 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-04-06T16:51:10.186Z,1649263870.186 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T16:51:10.186Z,1649263870.186 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2022-04-06T16:51:10.569Z,1649263870.569 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T16:51:32.902Z,1649263892.902 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004470
2022-04-06T16:51:39.277Z,1649263899.277 [Startup:StartupSatComms:B](INFO): Timed out from 2022-04-06T16:50:39.1Z
2022-04-06T16:51:39.277Z,1649263899.277 [Startup:StartupSatComms:B] Stopped
2022-04-06T16:51:39.277Z,1649263899.277 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-04-06T16:51:39.277Z,1649263899.277 [Startup:StartupSatComms] Stopped
2022-04-06T16:51:39.277Z,1649263899.277 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-04-06T16:51:39.278Z,1649263899.278 [Startup](INFO): Completed Startup
2022-04-06T16:51:39.278Z,1649263899.278 [MissionManager](INFO): Startup is completed.
2022-04-06T16:51:39.283Z,1649263899.283 [MissionManager](INFO): Uninitializing Mission Startup
2022-04-06T16:51:39.283Z,1649263899.283 [Startup] Stopped
2022-04-06T16:51:39.283Z,1649263899.283 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-04-06T16:51:39.283Z,1649263899.283 [Startup:A.GoToSurface] Stopped
2022-04-06T16:51:39.283Z,1649263899.283 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-06T16:51:39.665Z,1649263899.665 [MissionManager](IMPORTANT): Started mission Default
2022-04-06T16:51:39.665Z,1649263899.665 [Default] Running Loop=1
2022-04-06T16:51:39.665Z,1649263899.665 [Default](DEBUG): Aggregate::initialize Default
2022-04-06T16:51:39.665Z,1649263899.665 [Default:B.GoToSurface] Running Loop=1
2022-04-06T16:51:39.665Z,1649263899.665 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-06T16:51:39.665Z,1649263899.665 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-06T16:51:39.666Z,1649263899.666 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-06T16:51:39.666Z,1649263899.666 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-06T16:51:39.670Z,1649263899.670 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-06T16:51:39.671Z,1649263899.671 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-06T16:51:39.671Z,1649263899.671 [Default:A.Wait] Running Loop=1
2022-04-06T16:51:39.671Z,1649263899.671 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-04-06T16:51:41.262Z,1649263901.262 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220406T162745/Courier0007.lzma
2022-04-06T16:51:42.264Z,1649263902.264 [DataOverHttps](INFO): Moved sent file to Logs/20220406T162745/Courier0007.lzma.bak
2022-04-06T16:51:42.265Z,1649263902.265 [DataOverHttps](INFO): SBD MOMSN=16628074
2022-04-06T16:51:53.063Z,1649263913.063 [Default:A.Wait](INFO): Done Waiting.
2022-04-06T16:51:53.063Z,1649263913.063 [Default:A.Wait] Stopped
2022-04-06T16:51:53.063Z,1649263913.063 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T16:51:53.477Z,1649263913.477 [Default:CheckIn] Running Loop=1
2022-04-06T16:51:53.477Z,1649263913.477 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T16:51:53.477Z,1649263913.477 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T16:51:53.953Z,1649263913.953 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-04-06T16:53:53.448Z,1649264033.448 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-06T16:54:10.881Z,1649264050.881 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-04-06T16:54:10.881Z,1649264050.881 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T16:54:10.883Z,1649264050.883 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2022-04-06T16:54:10.883Z,1649264050.883 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T16:54:10.921Z,1649264050.921 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T16:54:10.921Z,1649264050.921 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T16:54:11.239Z,1649264051.239 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T16:54:11.239Z,1649264051.239 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-04-06T16:54:11.240Z,1649264051.240 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T16:54:11.240Z,1649264051.240 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2022-04-06T16:54:11.643Z,1649264051.643 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T16:55:37.270Z,1649264137.270 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-04-06T16:55:37.273Z,1649264137.273 [BPC1](INFO): Received data from all battery sticks.
2022-04-06T16:56:53.642Z,1649264213.642 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-06T16:51:53.5Z
2022-04-06T16:56:53.642Z,1649264213.642 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T16:56:53.642Z,1649264213.642 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T16:56:54.041Z,1649264214.041 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-06T16:57:01.350Z,1649264221.350 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220406T164759/Courier0000.lzma
2022-04-06T16:57:02.352Z,1649264222.352 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0000.lzma.bak
2022-04-06T16:57:02.352Z,1649264222.352 [DataOverHttps](INFO): SBD MOMSN=16628129
2022-04-06T16:57:11.824Z,1649264231.824 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-04-06T16:57:11.824Z,1649264231.824 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T16:57:11.825Z,1649264231.825 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2022-04-06T16:57:11.825Z,1649264231.825 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T16:57:11.835Z,1649264231.835 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T16:57:11.836Z,1649264231.836 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T16:57:12.256Z,1649264232.256 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T16:57:12.256Z,1649264232.256 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-04-06T16:57:12.257Z,1649264232.257 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T16:57:12.257Z,1649264232.257 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2022-04-06T16:57:12.621Z,1649264232.621 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T16:57:19.954Z,1649264239.954 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20220406T164759/Courier0004.lzma
2022-04-06T16:57:20.957Z,1649264240.957 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0004.lzma.bak
2022-04-06T16:57:20.957Z,1649264240.957 [DataOverHttps](INFO): SBD MOMSN=16628132
2022-04-06T16:57:38.611Z,1649264258.611 [DataOverHttps](INFO): Sending 515 bytes from file Logs/20220406T162745/Express0008.lzma
2022-04-06T16:57:39.612Z,1649264259.612 [DataOverHttps](INFO): Moved sent file to Logs/20220406T162745/Express0008.lzma.bak
2022-04-06T16:57:39.613Z,1649264259.613 [DataOverHttps](INFO): SBD MOMSN=16628134
2022-04-06T16:57:59.608Z,1649264279.608 [DataOverHttps](INFO): Sending 1016 bytes from file Logs/20220406T164759/Express0001.lzma
2022-04-06T16:58:00.609Z,1649264280.609 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0001.lzma.bak
2022-04-06T16:58:00.609Z,1649264280.609 [DataOverHttps](INFO): SBD MOMSN=16628152
2022-04-06T16:58:19.268Z,1649264299.268 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20220406T164759/Express0005.lzma
2022-04-06T16:58:20.264Z,1649264300.264 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0005.lzma.bak
2022-04-06T16:58:20.264Z,1649264300.264 [DataOverHttps](INFO): SBD MOMSN=16628181
2022-04-06T16:58:23.397Z,1649264303.397 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T16:58:23.397Z,1649264303.397 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T16:58:23.397Z,1649264303.397 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T16:58:52.465Z,1649264332.465 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-06T16:58:52.465Z,1649264332.465 [NAL9602] Data Fault, FailCount= 1
2022-04-06T16:58:52.465Z,1649264332.465 [NAL9602](ERROR): Data Fault
2022-04-06T16:58:52.483Z,1649264332.483 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-06T16:58:52.868Z,1649264332.868 [NAL9602](INFO): Powering down
2022-04-06T16:58:53.706Z,1649264333.706 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-06T16:58:53.706Z,1649264333.706 [NAL9602] No Fault, FailCount= 1
2022-04-06T16:59:23.172Z,1649264363.172 [NAL9602](INFO): Powering up NAL9602
2022-04-06T16:59:34.078Z,1649264374.078 [NAL9602](INFO): NAL9602 initialized
2022-04-06T17:00:12.909Z,1649264412.909 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-04-06T17:00:12.909Z,1649264412.909 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:00:12.910Z,1649264412.910 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2022-04-06T17:00:12.910Z,1649264412.910 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:00:12.921Z,1649264412.921 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:00:12.922Z,1649264412.922 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:00:13.330Z,1649264413.330 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:00:13.330Z,1649264413.330 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-04-06T17:00:13.331Z,1649264413.331 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:00:13.331Z,1649264413.331 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2022-04-06T17:00:13.733Z,1649264413.733 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:03:13.899Z,1649264593.899 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-04-06T17:03:13.899Z,1649264593.899 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:03:13.900Z,1649264593.900 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2022-04-06T17:03:13.900Z,1649264593.900 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:03:13.911Z,1649264593.911 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:03:13.911Z,1649264593.911 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:03:14.356Z,1649264594.356 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:03:14.356Z,1649264594.356 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-04-06T17:03:14.356Z,1649264594.356 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:03:14.356Z,1649264594.356 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2022-04-06T17:03:14.736Z,1649264594.736 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:03:24.036Z,1649264604.036 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T17:03:24.036Z,1649264604.036 [Default:CheckIn:C.Wait] Stopped
2022-04-06T17:03:24.036Z,1649264604.036 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T17:03:24.036Z,1649264604.036 [Default:CheckIn:D] Running Loop=1
2022-04-06T17:03:24.411Z,1649264604.411 [Default:CheckIn:D] Stopped
2022-04-06T17:03:24.411Z,1649264604.411 [Default:CheckIn:E] Running Loop=1
2022-04-06T17:03:24.816Z,1649264604.816 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.745767 min 
2022-04-06T17:03:24.816Z,1649264604.816 [Default:CheckIn:E] Stopped
2022-04-06T17:03:24.816Z,1649264604.816 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T17:03:24.817Z,1649264604.817 [Default:CheckIn] Stopped
2022-04-06T17:03:24.817Z,1649264604.817 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T17:03:24.817Z,1649264604.817 [Default:CheckIn](INFO): Running loop #2
2022-04-06T17:03:24.817Z,1649264604.817 [Default:CheckIn] Running Loop=2
2022-04-06T17:03:24.817Z,1649264604.817 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T17:03:24.817Z,1649264604.817 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T17:06:14.913Z,1649264774.913 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-04-06T17:06:14.913Z,1649264774.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:06:14.914Z,1649264774.914 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2022-04-06T17:06:14.914Z,1649264774.914 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:06:14.924Z,1649264774.924 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:06:14.924Z,1649264774.924 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:06:15.361Z,1649264775.361 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:06:15.361Z,1649264775.361 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-04-06T17:06:15.361Z,1649264775.361 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:06:15.361Z,1649264775.361 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2022-04-06T17:06:15.722Z,1649264775.722 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:08:25.006Z,1649264905.006 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-06T17:03:24.8Z
2022-04-06T17:08:25.006Z,1649264905.006 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T17:08:25.006Z,1649264905.006 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T17:08:32.942Z,1649264912.942 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220406T164759/Courier0007.lzma
2022-04-06T17:08:33.944Z,1649264913.944 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0007.lzma.bak
2022-04-06T17:08:33.944Z,1649264913.944 [DataOverHttps](INFO): SBD MOMSN=16628193
2022-04-06T17:08:51.754Z,1649264931.754 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20220406T164759/Express0008.lzma
2022-04-06T17:08:52.756Z,1649264932.756 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0008.lzma.bak
2022-04-06T17:08:52.756Z,1649264932.756 [DataOverHttps](INFO): SBD MOMSN=16628195
2022-04-06T17:08:55.748Z,1649264935.748 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T17:08:55.748Z,1649264935.748 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T17:08:55.748Z,1649264935.748 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T17:09:15.901Z,1649264955.901 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-04-06T17:09:15.902Z,1649264955.902 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:09:15.903Z,1649264955.903 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2022-04-06T17:09:15.903Z,1649264955.903 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:09:15.915Z,1649264955.915 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:09:15.915Z,1649264955.915 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:09:16.327Z,1649264956.327 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:09:16.327Z,1649264956.327 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-04-06T17:09:16.327Z,1649264956.327 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:09:16.327Z,1649264956.327 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2022-04-06T17:09:16.739Z,1649264956.739 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:09:37.297Z,1649264977.297 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-06T17:09:37.297Z,1649264977.297 [NAL9602] Data Fault, FailCount= 2
2022-04-06T17:09:37.297Z,1649264977.297 [NAL9602](ERROR): Data Fault
2022-04-06T17:09:37.313Z,1649264977.313 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-06T17:09:37.710Z,1649264977.710 [NAL9602](INFO): Powering down
2022-04-06T17:09:38.528Z,1649264978.528 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-06T17:09:38.528Z,1649264978.528 [NAL9602] No Fault, FailCount= 2
2022-04-06T17:10:08.004Z,1649265008.004 [NAL9602](INFO): Powering up NAL9602
2022-04-06T17:10:18.920Z,1649265018.920 [NAL9602](INFO): NAL9602 initialized
2022-04-06T17:12:16.919Z,1649265136.919 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-04-06T17:12:16.919Z,1649265136.919 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:12:16.920Z,1649265136.920 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2022-04-06T17:12:16.920Z,1649265136.920 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:12:16.931Z,1649265136.931 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:12:16.931Z,1649265136.931 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:12:17.307Z,1649265137.307 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:12:17.307Z,1649265137.307 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-04-06T17:12:17.307Z,1649265137.307 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:12:17.307Z,1649265137.307 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2022-04-06T17:12:17.699Z,1649265137.699 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:13:56.344Z,1649265236.344 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T17:13:56.344Z,1649265236.344 [Default:CheckIn:C.Wait] Stopped
2022-04-06T17:13:56.344Z,1649265236.344 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T17:13:56.344Z,1649265236.344 [Default:CheckIn:D] Running Loop=1
2022-04-06T17:13:56.756Z,1649265236.756 [Default:CheckIn:D] Stopped
2022-04-06T17:13:56.756Z,1649265236.756 [Default:CheckIn:E] Running Loop=1
2022-04-06T17:13:57.197Z,1649265237.197 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.284845 min 
2022-04-06T17:13:57.197Z,1649265237.197 [Default:CheckIn:E] Stopped
2022-04-06T17:13:57.197Z,1649265237.197 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T17:13:57.197Z,1649265237.197 [Default:CheckIn] Stopped
2022-04-06T17:13:57.198Z,1649265237.198 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T17:13:57.198Z,1649265237.198 [Default:CheckIn](INFO): Running loop #3
2022-04-06T17:13:57.198Z,1649265237.198 [Default:CheckIn] Running Loop=3
2022-04-06T17:13:57.198Z,1649265237.198 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T17:13:57.198Z,1649265237.198 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T17:14:15.108Z,1649265255.108 [CommandExec](IMPORTANT): got command burn on
2022-04-06T17:14:15.108Z,1649265255.108 [CommandExec](IMPORTANT): Activating dropweight wire
2022-04-06T17:14:43.320Z,1649265283.320 [CommandExec](IMPORTANT): got command burn off
2022-04-06T17:14:43.320Z,1649265283.320 [CommandExec](IMPORTANT): Deactivating dropweight wire
2022-04-06T17:15:17.959Z,1649265317.959 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-04-06T17:15:17.959Z,1649265317.959 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:15:17.960Z,1649265317.960 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2022-04-06T17:15:17.960Z,1649265317.960 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:15:17.971Z,1649265317.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:15:17.971Z,1649265317.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:15:18.369Z,1649265318.369 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:15:18.369Z,1649265318.369 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-04-06T17:15:18.369Z,1649265318.369 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:15:18.369Z,1649265318.369 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2022-04-06T17:15:18.738Z,1649265318.738 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:17:17.671Z,1649265437.671 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.527271
2022-04-06T17:17:45.019Z,1649265465.019 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002906
2022-04-06T17:18:19.016Z,1649265499.016 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-04-06T17:18:19.016Z,1649265499.016 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:18:19.017Z,1649265499.017 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2022-04-06T17:18:19.017Z,1649265499.017 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:18:19.027Z,1649265499.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:18:19.027Z,1649265499.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:18:19.448Z,1649265499.448 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:18:19.448Z,1649265499.448 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-04-06T17:18:19.449Z,1649265499.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:18:19.449Z,1649265499.449 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2022-04-06T17:18:19.834Z,1649265499.834 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:18:26.203Z,1649265506.203 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:18:45.283Z,1649265525.283 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.267384
2022-04-06T17:18:55.394Z,1649265535.394 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-06T17:18:57.395Z,1649265537.395 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-06T17:13:57.2Z
2022-04-06T17:18:57.395Z,1649265537.395 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T17:18:57.395Z,1649265537.395 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T17:19:06.536Z,1649265546.536 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006665
CHAN A1 (24V): 0.000215
CHAN A2 (12V): -0.005191
CHAN A3 (5V): -0.002777
CHAN B0 (3.3V): -0.001304
CHAN B1 (3.15aV): -0.001685
CHAN B2 (3.15bV): -0.001403
CHAN B3 (GND): -0.000506
OPEN: 0.004980
Full Scale: +/- 1 mA
2022-04-06T17:19:36.487Z,1649265576.487 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:20:11.627Z,1649265611.627 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:20:23.932Z,1649265623.932 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-06T17:20:23.932Z,1649265623.932 [NAL9602] Data Fault, FailCount= 3
2022-04-06T17:20:23.932Z,1649265623.932 [NAL9602](ERROR): Data Fault
2022-04-06T17:20:23.978Z,1649265623.978 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-06T17:20:24.336Z,1649265624.336 [NAL9602](INFO): Powering down
2022-04-06T17:20:25.171Z,1649265625.171 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-06T17:20:25.171Z,1649265625.171 [NAL9602] No Fault, FailCount= 3
2022-04-06T17:20:46.767Z,1649265646.767 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:20:54.639Z,1649265654.639 [NAL9602](INFO): Powering up NAL9602
2022-04-06T17:21:05.543Z,1649265665.543 [NAL9602](INFO): NAL9602 initialized
2022-04-06T17:21:20.095Z,1649265680.095 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-04-06T17:21:20.095Z,1649265680.095 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:21:20.096Z,1649265680.096 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2022-04-06T17:21:20.096Z,1649265680.096 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:21:20.106Z,1649265680.106 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:21:20.106Z,1649265680.106 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:21:20.500Z,1649265680.500 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:21:20.500Z,1649265680.500 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-04-06T17:21:20.501Z,1649265680.501 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:21:20.501Z,1649265680.501 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2022-04-06T17:21:20.887Z,1649265680.887 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:21:21.907Z,1649265681.907 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:21:57.051Z,1649265717.051 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:22:32.207Z,1649265752.207 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:23:07.351Z,1649265787.351 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:23:42.495Z,1649265822.495 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:24:17.655Z,1649265857.655 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:24:21.071Z,1649265861.071 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-04-06T17:24:21.071Z,1649265861.071 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:24:21.072Z,1649265861.072 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2022-04-06T17:24:21.072Z,1649265861.072 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:24:21.083Z,1649265861.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:24:21.083Z,1649265861.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:24:21.548Z,1649265861.548 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:24:21.548Z,1649265861.548 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-04-06T17:24:21.549Z,1649265861.549 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:24:21.549Z,1649265861.549 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2022-04-06T17:24:21.901Z,1649265861.901 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:24:52.799Z,1649265892.799 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:25:27.939Z,1649265927.939 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:26:03.099Z,1649265963.099 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:26:38.239Z,1649265998.239 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:27:13.387Z,1649266033.387 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:27:22.567Z,1649266042.567 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2022-04-06T17:27:22.567Z,1649266042.567 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:27:22.568Z,1649266042.568 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2022-04-06T17:27:22.568Z,1649266042.568 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:27:22.579Z,1649266042.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:27:22.580Z,1649266042.580 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:27:22.698Z,1649266042.698 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:27:22.698Z,1649266042.698 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2022-04-06T17:27:22.699Z,1649266042.699 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:27:22.699Z,1649266042.699 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2022-04-06T17:27:23.027Z,1649266043.027 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:27:48.543Z,1649266068.543 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:28:23.683Z,1649266103.683 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:28:58.827Z,1649266138.827 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:29:33.987Z,1649266173.987 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:30:09.135Z,1649266209.135 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:30:23.264Z,1649266223.264 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2022-04-06T17:30:23.264Z,1649266223.264 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:30:23.265Z,1649266223.265 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2022-04-06T17:30:23.265Z,1649266223.265 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:30:23.276Z,1649266223.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:30:23.276Z,1649266223.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:30:23.694Z,1649266223.694 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:30:23.694Z,1649266223.694 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2022-04-06T17:30:23.694Z,1649266223.694 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:30:23.694Z,1649266223.694 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2022-04-06T17:30:24.075Z,1649266224.075 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:30:44.275Z,1649266244.275 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:31:06.829Z,1649266266.829 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-06T17:31:06.829Z,1649266266.829 [NAL9602] Data Fault, FailCount= 4
2022-04-06T17:31:06.829Z,1649266266.829 [NAL9602](ERROR): Data Fault
2022-04-06T17:31:06.845Z,1649266266.845 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-06T17:31:07.238Z,1649266267.238 [NAL9602](INFO): Powering down
2022-04-06T17:31:08.060Z,1649266268.060 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-06T17:31:08.060Z,1649266268.060 [NAL9602] No Fault, FailCount= 4
2022-04-06T17:31:19.423Z,1649266279.423 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T17:31:37.540Z,1649266297.540 [NAL9602](INFO): Powering up NAL9602
2022-04-06T17:31:48.449Z,1649266308.449 [NAL9602](INFO): NAL9602 initialized
2022-04-06T17:32:10.439Z,1649266330.439 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002886
2022-04-06T17:32:17.710Z,1649266337.710 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220406T164759/Courier0010.lzma
2022-04-06T17:32:18.712Z,1649266338.712 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0010.lzma.bak
2022-04-06T17:32:18.713Z,1649266338.713 [DataOverHttps](INFO): SBD MOMSN=16628222
2022-04-06T17:32:36.279Z,1649266356.279 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20220406T164759/Express0011.lzma
2022-04-06T17:32:37.280Z,1649266357.280 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0011.lzma.bak
2022-04-06T17:32:37.281Z,1649266357.281 [DataOverHttps](INFO): SBD MOMSN=16628224
2022-04-06T17:32:40.253Z,1649266360.253 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T17:32:40.253Z,1649266360.253 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T17:32:40.253Z,1649266360.253 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T17:33:24.266Z,1649266404.266 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2022-04-06T17:33:24.266Z,1649266404.266 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:33:24.267Z,1649266404.267 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2022-04-06T17:33:24.267Z,1649266404.267 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:33:24.278Z,1649266404.278 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:33:24.278Z,1649266404.278 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:33:24.701Z,1649266404.701 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:33:24.701Z,1649266404.701 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2022-04-06T17:33:24.702Z,1649266404.702 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:33:24.702Z,1649266404.702 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2022-04-06T17:33:25.099Z,1649266405.099 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:36:25.343Z,1649266585.343 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2022-04-06T17:36:25.343Z,1649266585.343 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:36:25.344Z,1649266585.344 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2022-04-06T17:36:25.344Z,1649266585.344 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:36:25.364Z,1649266585.364 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:36:25.364Z,1649266585.364 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:36:25.772Z,1649266585.772 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:36:25.772Z,1649266585.772 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2022-04-06T17:36:25.773Z,1649266585.773 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:36:25.773Z,1649266585.773 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2022-04-06T17:36:26.135Z,1649266586.135 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:37:40.889Z,1649266660.889 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T17:37:40.889Z,1649266660.889 [Default:CheckIn:C.Wait] Stopped
2022-04-06T17:37:40.889Z,1649266660.889 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T17:37:40.889Z,1649266660.889 [Default:CheckIn:D] Running Loop=1
2022-04-06T17:37:41.295Z,1649266661.295 [Default:CheckIn:D] Stopped
2022-04-06T17:37:41.295Z,1649266661.295 [Default:CheckIn:E] Running Loop=1
2022-04-06T17:37:41.729Z,1649266661.729 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.027169 min 
2022-04-06T17:37:41.729Z,1649266661.729 [Default:CheckIn:E] Stopped
2022-04-06T17:37:41.729Z,1649266661.729 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T17:37:41.729Z,1649266661.729 [Default:CheckIn] Stopped
2022-04-06T17:37:41.729Z,1649266661.729 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T17:37:41.729Z,1649266661.729 [Default:CheckIn](INFO): Running loop #4
2022-04-06T17:37:41.729Z,1649266661.729 [Default:CheckIn] Running Loop=4
2022-04-06T17:37:41.729Z,1649266661.729 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T17:37:41.729Z,1649266661.729 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T17:39:26.359Z,1649266766.359 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2022-04-06T17:39:26.359Z,1649266766.359 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:39:26.360Z,1649266766.360 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2022-04-06T17:39:26.360Z,1649266766.360 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:39:26.371Z,1649266766.371 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:39:26.371Z,1649266766.371 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:39:26.748Z,1649266766.748 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:39:26.748Z,1649266766.748 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2022-04-06T17:39:26.748Z,1649266766.748 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:39:26.748Z,1649266766.748 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2022-04-06T17:39:27.137Z,1649266767.137 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:41:49.744Z,1649266909.744 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-06T17:41:49.744Z,1649266909.744 [NAL9602] Data Fault, FailCount= 5
2022-04-06T17:41:49.744Z,1649266909.744 [NAL9602](ERROR): Data Fault
2022-04-06T17:41:49.797Z,1649266909.797 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-06T17:41:49.797Z,1649266909.797 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-04-06T17:41:50.148Z,1649266910.148 [NAL9602](INFO): Powering down
2022-04-06T17:41:50.171Z,1649266910.171 [CommandExec](FAULT): Scheduling is paused
2022-04-06T17:41:50.172Z,1649266910.172 [CBIT](INFO): Critical error at 20220406T174149
2022-04-06T17:41:50.172Z,1649266910.172 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-04-06T17:42:27.352Z,1649266947.352 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2022-04-06T17:42:27.352Z,1649266947.352 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:42:27.353Z,1649266947.353 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2022-04-06T17:42:27.353Z,1649266947.353 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:42:27.400Z,1649266947.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:42:27.400Z,1649266947.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:42:27.753Z,1649266947.753 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:42:27.753Z,1649266947.753 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2022-04-06T17:42:27.754Z,1649266947.754 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:42:27.754Z,1649266947.754 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2022-04-06T17:42:28.121Z,1649266948.121 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:42:41.867Z,1649266961.867 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-06T17:37:41.7Z
2022-04-06T17:42:41.867Z,1649266961.867 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T17:42:41.867Z,1649266961.867 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T17:42:49.694Z,1649266969.694 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20220406T164759/Courier0013.lzma
2022-04-06T17:42:50.696Z,1649266970.696 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0013.lzma.bak
2022-04-06T17:42:50.696Z,1649266970.696 [DataOverHttps](INFO): SBD MOMSN=16628240
2022-04-06T17:43:07.930Z,1649266987.930 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20220406T164759/Express0014.lzma
2022-04-06T17:43:08.932Z,1649266988.932 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0014.lzma.bak
2022-04-06T17:43:08.933Z,1649266988.933 [DataOverHttps](INFO): SBD MOMSN=16628243
2022-04-06T17:43:11.913Z,1649266991.913 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T17:43:11.913Z,1649266991.913 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T17:43:11.913Z,1649266991.913 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T17:43:49.888Z,1649267029.888 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-06T17:43:49.888Z,1649267029.888 [NAL9602] No Fault, FailCount= 5
2022-04-06T17:43:50.264Z,1649267030.264 [NAL9602](INFO): Powering up NAL9602
2022-04-06T17:44:01.200Z,1649267041.200 [NAL9602](INFO): NAL9602 initialized
2022-04-06T17:45:28.472Z,1649267128.472 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2022-04-06T17:45:28.472Z,1649267128.472 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:45:28.474Z,1649267128.474 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2022-04-06T17:45:28.474Z,1649267128.474 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:45:28.484Z,1649267128.484 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:45:28.484Z,1649267128.484 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:45:28.860Z,1649267128.860 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:45:28.860Z,1649267128.860 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2022-04-06T17:45:28.860Z,1649267128.860 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:45:28.860Z,1649267128.860 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2022-04-06T17:45:29.257Z,1649267129.257 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:48:12.525Z,1649267292.525 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T17:48:12.525Z,1649267292.525 [Default:CheckIn:C.Wait] Stopped
2022-04-06T17:48:12.525Z,1649267292.525 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T17:48:12.526Z,1649267292.526 [Default:CheckIn:D] Running Loop=1
2022-04-06T17:48:12.941Z,1649267292.941 [Default:CheckIn:D] Stopped
2022-04-06T17:48:12.941Z,1649267292.941 [Default:CheckIn:E] Running Loop=1
2022-04-06T17:48:13.338Z,1649267293.338 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.554606 min 
2022-04-06T17:48:13.338Z,1649267293.338 [Default:CheckIn:E] Stopped
2022-04-06T17:48:13.338Z,1649267293.338 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T17:48:13.339Z,1649267293.339 [Default:CheckIn] Stopped
2022-04-06T17:48:13.339Z,1649267293.339 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T17:48:13.339Z,1649267293.339 [Default:CheckIn](INFO): Running loop #5
2022-04-06T17:48:13.339Z,1649267293.339 [Default:CheckIn] Running Loop=5
2022-04-06T17:48:13.339Z,1649267293.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T17:48:13.339Z,1649267293.339 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T17:48:29.481Z,1649267309.481 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2022-04-06T17:48:29.481Z,1649267309.481 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-06T17:48:29.482Z,1649267309.482 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2022-04-06T17:48:29.482Z,1649267309.482 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-04-06T17:48:29.492Z,1649267309.492 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-06T17:48:29.492Z,1649267309.492 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-04-06T17:48:29.912Z,1649267309.912 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-06T17:48:29.912Z,1649267309.912 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2022-04-06T17:48:29.912Z,1649267309.912 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-04-06T17:48:29.913Z,1649267309.913 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2022-04-06T17:48:30.320Z,1649267310.320 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-04-06T17:49:06.682Z,1649267346.682 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-06T17:49:17.624Z,1649267357.624 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006050
CHAN A1 (24V): 0.000633
CHAN A2 (12V): -0.005561
CHAN A3 (5V): -0.003554
CHAN B0 (3.3V): -0.001820
CHAN B1 (3.15aV): -0.001338
CHAN B2 (3.15bV): -0.001720
CHAN B3 (GND): -0.000383
OPEN: 0.004615
Full Scale: +/- 1 mA
2022-04-06T17:50:09.666Z,1649267409.666 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175008.00,A,3648.12901,N,12147.22394,W,0.117,0.00,060422,,,A*7F
2022-04-06T17:50:09.669Z,1649267409.669 [NAL9602](INFO): GPS fix at 20220406T175008: (36.802150, -121.787066)
2022-04-06T17:50:09.682Z,1649267409.682 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T17:50:09.682Z,1649267409.682 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T17:50:19.328Z,1649267419.328 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20220406T164759/Courier0016.lzma
2022-04-06T17:50:20.346Z,1649267420.346 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0016.lzma.bak
2022-04-06T17:50:20.346Z,1649267420.346 [DataOverHttps](INFO): SBD MOMSN=16628257
2022-04-06T17:50:39.656Z,1649267439.656 [DataOverHttps](INFO): Sending 411 bytes from file Logs/20220406T164759/Express0017.lzma
2022-04-06T17:50:40.657Z,1649267440.657 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0017.lzma.bak
2022-04-06T17:50:40.657Z,1649267440.657 [DataOverHttps](INFO): SBD MOMSN=16628260
2022-04-06T17:50:42.400Z,1649267442.400 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T17:50:43.665Z,1649267443.665 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T17:50:43.665Z,1649267443.665 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T17:50:43.665Z,1649267443.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T17:53:34.971Z,1649267614.971 [CommandExec](IMPORTANT): got command ibit
2022-04-06T17:53:35.340Z,1649267615.340 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-04-06T17:53:35.340Z,1649267615.340 [IBIT](IMPORTANT): Beginning control surface checks.
2022-04-06T17:53:35.344Z,1649267615.344 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-06T17:53:36.938Z,1649267616.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175335.00,A,3648.14069,N,12147.22902,W,0.039,0.00,060422,,,A*7B
2022-04-06T17:53:36.941Z,1649267616.941 [NAL9602](INFO): GPS fix at 20220406T175335: (36.802345, -121.787150)
2022-04-06T17:53:46.265Z,1649267626.265 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006318
CHAN A1 (24V): 0.000655
CHAN A2 (12V): -0.005178
CHAN A3 (5V): -0.002010
CHAN B0 (3.3V): -0.001711
CHAN B1 (3.15aV): -0.001378
CHAN B2 (3.15bV): -0.001991
CHAN B3 (GND): -0.000433
OPEN: 0.004653
Full Scale: +/- 1 mA
2022-04-06T17:53:50.669Z,1649267630.669 [NAL9602](INFO): SBD MO Status=0, MOMSN=4544, MT Status=0, MTMSN=0
2022-04-06T17:53:50.669Z,1649267630.669 [NAL9602](INFO): No messages in MT queue
2022-04-06T17:53:50.669Z,1649267630.669 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2022-04-06T17:53:51.880Z,1649267631.880 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175350.00,A,3648.14027,N,12147.22941,W,0.058,24.31,060422,,,A*46
2022-04-06T17:53:51.899Z,1649267631.899 [NAL9602](INFO): GPS fix at 20220406T175350: (36.802338, -121.787157)
2022-04-06T17:53:54.709Z,1649267634.709 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175353.00,A,3648.14040,N,12147.22942,W,0.097,24.31,060422,,,A*44
2022-04-06T17:53:54.712Z,1649267634.712 [NAL9602](INFO): GPS fix at 20220406T175353: (36.802340, -121.787157)
2022-04-06T17:53:57.985Z,1649267637.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175356.00,A,3648.14051,N,12147.22935,W,0.175,24.31,060422,,,A*4C
2022-04-06T17:53:57.995Z,1649267637.995 [NAL9602](INFO): GPS fix at 20220406T175356: (36.802342, -121.787156)
2022-04-06T17:54:00.769Z,1649267640.769 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175359.00,A,3648.14109,N,12147.22899,W,0.058,24.31,060422,,,A*46
2022-04-06T17:54:00.783Z,1649267640.783 [NAL9602](INFO): GPS fix at 20220406T175359: (36.802352, -121.787150)
2022-04-06T17:54:04.010Z,1649267644.010 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175402.00,A,3648.14181,N,12147.22853,W,0.058,24.31,060422,,,A*49
2022-04-06T17:54:04.012Z,1649267644.012 [NAL9602](INFO): GPS fix at 20220406T175402: (36.802363, -121.787142)
2022-04-06T17:54:06.829Z,1649267646.829 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175405.00,A,3648.14227,N,12147.22823,W,0.058,24.31,060422,,,A*46
2022-04-06T17:54:06.832Z,1649267646.832 [NAL9602](INFO): GPS fix at 20220406T175405: (36.802371, -121.787137)
2022-04-06T17:54:10.066Z,1649267650.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175408.00,A,3648.14258,N,12147.22796,W,0.019,24.31,060422,,,A*47
2022-04-06T17:54:10.069Z,1649267650.069 [NAL9602](INFO): GPS fix at 20220406T175408: (36.802376, -121.787133)
2022-04-06T17:54:12.893Z,1649267652.893 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175411.00,A,3648.14280,N,12147.22774,W,0.000,24.31,060422,,,A*4E
2022-04-06T17:54:12.895Z,1649267652.895 [NAL9602](INFO): GPS fix at 20220406T175411: (36.802380, -121.787129)
2022-04-06T17:54:15.716Z,1649267655.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175414.00,A,3648.14284,N,12147.22767,W,0.058,24.31,060422,,,A*40
2022-04-06T17:54:15.718Z,1649267655.718 [NAL9602](INFO): GPS fix at 20220406T175414: (36.802381, -121.787128)
2022-04-06T17:54:18.948Z,1649267658.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175417.00,A,3648.14273,N,12147.22767,W,0.097,24.31,060422,,,A*48
2022-04-06T17:54:18.951Z,1649267658.951 [NAL9602](INFO): GPS fix at 20220406T175417: (36.802379, -121.787128)
2022-04-06T17:54:21.781Z,1649267661.781 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175420.00,A,3648.14278,N,12147.22758,W,0.019,24.31,060422,,,A*4D
2022-04-06T17:54:21.784Z,1649267661.784 [NAL9602](INFO): GPS fix at 20220406T175420: (36.802380, -121.787126)
2022-04-06T17:54:21.827Z,1649267661.827 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802380 Longitude: -121.787125
2022-04-06T17:54:22.228Z,1649267662.228 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.413000
2022-04-06T17:54:22.228Z,1649267662.228 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2022-04-06T17:54:22.229Z,1649267662.229 [IBIT](IMPORTANT): batteryVoltageThreshold: 11.000000 V
2022-04-06T17:54:22.605Z,1649267662.605 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-04-06T17:54:22.605Z,1649267662.605 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-04-06T17:54:22.605Z,1649267662.605 [IBIT](IMPORTANT): Pressure:8.112365 PSI
2022-04-06T17:54:22.606Z,1649267662.606 [IBIT](IMPORTANT): Humidity:28.183374 %
2022-04-06T17:54:23.048Z,1649267663.048 [IBIT](IMPORTANT): Vehicle Pitch:-0.177587 degrees
2022-04-06T17:54:23.049Z,1649267663.049 [IBIT](IMPORTANT): Vehicle  Roll:1.955293 degrees
2022-04-06T17:54:23.049Z,1649267663.049 [IBIT](IMPORTANT): Vehicle  Heading:285.647736 degrees
2022-04-06T17:54:23.414Z,1649267663.414 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-04-06T17:54:23.435Z,1649267663.435 [IBIT](IMPORTANT): buoyancyNeutral: 157.684830 cc
2022-04-06T17:54:23.435Z,1649267663.435 [IBIT](IMPORTANT): massDefault: -0.366217 cm
2022-04-06T17:54:23.435Z,1649267663.435 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2022-04-06T17:54:23.436Z,1649267663.436 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2022-04-06T17:54:23.436Z,1649267663.436 [IBIT](IMPORTANT): IBIT PASSED
2022-04-06T17:54:54.500Z,1649267694.500 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T17:55:44.210Z,1649267744.210 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T17:55:44.210Z,1649267744.210 [Default:CheckIn:C.Wait] Stopped
2022-04-06T17:55:44.210Z,1649267744.210 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T17:55:44.211Z,1649267744.211 [Default:CheckIn:D] Running Loop=1
2022-04-06T17:55:44.622Z,1649267744.622 [Default:CheckIn:D] Stopped
2022-04-06T17:55:44.622Z,1649267744.622 [Default:CheckIn:E] Running Loop=1
2022-04-06T17:55:45.009Z,1649267745.009 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.082617 min 
2022-04-06T17:55:45.009Z,1649267745.009 [Default:CheckIn:E] Stopped
2022-04-06T17:55:45.009Z,1649267745.009 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T17:55:45.009Z,1649267745.009 [Default:CheckIn] Stopped
2022-04-06T17:55:45.009Z,1649267745.009 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T17:55:45.009Z,1649267745.009 [Default:CheckIn](INFO): Running loop #6
2022-04-06T17:55:45.009Z,1649267745.009 [Default:CheckIn] Running Loop=6
2022-04-06T17:55:45.009Z,1649267745.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T17:55:45.009Z,1649267745.009 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T17:55:47.037Z,1649267747.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175545.00,A,3648.14235,N,12147.22685,W,0.019,24.31,060422,,,A*47
2022-04-06T17:55:47.039Z,1649267747.039 [NAL9602](INFO): GPS fix at 20220406T175545: (36.802372, -121.787114)
2022-04-06T17:55:47.060Z,1649267747.060 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T17:55:47.060Z,1649267747.060 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T17:55:58.329Z,1649267758.329 [NAL9602](INFO): SBD MO Status=0, MOMSN=4545, MT Status=0, MTMSN=0
2022-04-06T17:55:58.329Z,1649267758.329 [NAL9602](INFO): No messages in MT queue
2022-04-06T17:56:00.592Z,1649267760.592 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0019.lzma
2022-04-06T17:56:01.589Z,1649267761.589 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0019.lzma.bak
2022-04-06T17:56:01.589Z,1649267761.589 [DataOverHttps](INFO): SBD MOMSN=16628330
2022-04-06T17:56:20.163Z,1649267780.163 [DataOverHttps](INFO): Sending 763 bytes from file Logs/20220406T164759/Express0020.lzma
2022-04-06T17:56:21.164Z,1649267781.164 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0020.lzma.bak
2022-04-06T17:56:21.165Z,1649267781.165 [DataOverHttps](INFO): SBD MOMSN=16628333
2022-04-06T17:56:24.638Z,1649267784.638 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T17:56:24.638Z,1649267784.638 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T17:56:24.638Z,1649267784.638 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T17:56:29.043Z,1649267789.043 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:01:25.298Z,1649268085.298 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:01:25.298Z,1649268085.298 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:01:25.298Z,1649268085.298 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:01:25.298Z,1649268085.298 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:01:25.686Z,1649268085.686 [Default:CheckIn:D] Stopped
2022-04-06T18:01:25.686Z,1649268085.686 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:01:26.079Z,1649268086.079 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.767017 min 
2022-04-06T18:01:26.079Z,1649268086.079 [Default:CheckIn:E] Stopped
2022-04-06T18:01:26.079Z,1649268086.079 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:01:26.079Z,1649268086.079 [Default:CheckIn] Stopped
2022-04-06T18:01:26.079Z,1649268086.079 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:01:26.080Z,1649268086.080 [Default:CheckIn](INFO): Running loop #7
2022-04-06T18:01:26.080Z,1649268086.080 [Default:CheckIn] Running Loop=7
2022-04-06T18:01:26.080Z,1649268086.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:01:26.080Z,1649268086.080 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:01:28.089Z,1649268088.089 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180127.00,A,3648.14803,N,12147.22308,W,0.058,25.51,060422,,,A*40
2022-04-06T18:01:28.092Z,1649268088.092 [NAL9602](INFO): GPS fix at 20220406T180127: (36.802467, -121.787051)
2022-04-06T18:01:28.104Z,1649268088.104 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:01:28.104Z,1649268088.104 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:01:37.154Z,1649268097.154 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0022.lzma
2022-04-06T18:01:38.156Z,1649268098.156 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0022.lzma.bak
2022-04-06T18:01:38.156Z,1649268098.156 [DataOverHttps](INFO): SBD MOMSN=16628415
2022-04-06T18:01:42.333Z,1649268102.333 [NAL9602](INFO): SBD MO Status=0, MOMSN=4546, MT Status=0, MTMSN=0
2022-04-06T18:01:42.333Z,1649268102.333 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:02:01.427Z,1649268121.427 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220406T164759/Express0023.lzma
2022-04-06T18:02:02.429Z,1649268122.429 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0023.lzma.bak
2022-04-06T18:02:02.429Z,1649268122.429 [DataOverHttps](INFO): SBD MOMSN=16628418
2022-04-06T18:02:05.861Z,1649268125.861 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:02:05.861Z,1649268125.861 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:02:05.861Z,1649268125.861 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:02:12.711Z,1649268132.711 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:03:08.463Z,1649268188.463 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-04-06T18:03:08.465Z,1649268188.465 [BPC1](INFO): Received data from all battery sticks.
2022-04-06T18:07:06.448Z,1649268426.448 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:07:06.448Z,1649268426.448 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:07:06.449Z,1649268426.449 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:07:06.449Z,1649268426.449 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:07:06.855Z,1649268426.855 [Default:CheckIn:D] Stopped
2022-04-06T18:07:06.855Z,1649268426.855 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:07:07.262Z,1649268427.262 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.453166 min 
2022-04-06T18:07:07.262Z,1649268427.262 [Default:CheckIn:E] Stopped
2022-04-06T18:07:07.262Z,1649268427.262 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:07:07.262Z,1649268427.262 [Default:CheckIn] Stopped
2022-04-06T18:07:07.262Z,1649268427.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:07:07.262Z,1649268427.262 [Default:CheckIn](INFO): Running loop #8
2022-04-06T18:07:07.262Z,1649268427.262 [Default:CheckIn] Running Loop=8
2022-04-06T18:07:07.267Z,1649268427.267 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:07:07.267Z,1649268427.267 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:07:09.264Z,1649268429.264 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180708.00,A,3648.14737,N,12147.22458,W,0.175,25.51,060422,,,A*4F
2022-04-06T18:07:09.266Z,1649268429.266 [NAL9602](INFO): GPS fix at 20220406T180708: (36.802456, -121.787076)
2022-04-06T18:07:09.278Z,1649268429.278 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:07:09.278Z,1649268429.278 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:07:17.898Z,1649268437.898 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20220406T164759/Courier0025.lzma
2022-04-06T18:07:19.221Z,1649268439.221 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0025.lzma.bak
2022-04-06T18:07:19.221Z,1649268439.221 [DataOverHttps](INFO): SBD MOMSN=16628431
2022-04-06T18:07:19.367Z,1649268439.367 [NAL9602](INFO): SBD MO Status=0, MOMSN=4547, MT Status=0, MTMSN=0
2022-04-06T18:07:19.368Z,1649268439.368 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:07:36.806Z,1649268456.806 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220406T164759/Express0026.lzma
2022-04-06T18:07:37.808Z,1649268457.808 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0026.lzma.bak
2022-04-06T18:07:37.809Z,1649268457.809 [DataOverHttps](INFO): SBD MOMSN=16628434
2022-04-06T18:07:40.832Z,1649268460.832 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:07:40.832Z,1649268460.832 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:07:40.832Z,1649268460.832 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:07:49.790Z,1649268469.790 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:12:41.483Z,1649268761.483 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:12:41.483Z,1649268761.483 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:12:41.483Z,1649268761.483 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:12:41.483Z,1649268761.483 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:12:41.850Z,1649268761.850 [Default:CheckIn:D] Stopped
2022-04-06T18:12:41.854Z,1649268761.854 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:12:42.257Z,1649268762.257 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.036426 min 
2022-04-06T18:12:42.257Z,1649268762.257 [Default:CheckIn:E] Stopped
2022-04-06T18:12:42.257Z,1649268762.257 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:12:42.257Z,1649268762.257 [Default:CheckIn] Stopped
2022-04-06T18:12:42.258Z,1649268762.258 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:12:42.258Z,1649268762.258 [Default:CheckIn](INFO): Running loop #9
2022-04-06T18:12:42.258Z,1649268762.258 [Default:CheckIn] Running Loop=9
2022-04-06T18:12:42.258Z,1649268762.258 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:12:42.258Z,1649268762.258 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:12:44.253Z,1649268764.253 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181243.00,A,3648.14722,N,12147.22880,W,0.914,25.51,060422,,,A*46
2022-04-06T18:12:44.256Z,1649268764.256 [NAL9602](INFO): GPS fix at 20220406T181243: (36.802454, -121.787147)
2022-04-06T18:12:44.268Z,1649268764.268 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:12:44.268Z,1649268764.268 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:12:46.296Z,1649268766.296 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220406T164759/Courier0028.lzma
2022-04-06T18:12:47.300Z,1649268767.300 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0028.lzma.bak
2022-04-06T18:12:47.300Z,1649268767.300 [DataOverHttps](INFO): SBD MOMSN=16628446
2022-04-06T18:12:55.158Z,1649268775.158 [NAL9602](INFO): SBD MO Status=0, MOMSN=4548, MT Status=0, MTMSN=0
2022-04-06T18:12:55.158Z,1649268775.158 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:13:05.638Z,1649268785.638 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0029.lzma
2022-04-06T18:13:06.640Z,1649268786.640 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0029.lzma.bak
2022-04-06T18:13:06.641Z,1649268786.641 [DataOverHttps](INFO): SBD MOMSN=16628449
2022-04-06T18:13:09.721Z,1649268789.721 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:13:09.721Z,1649268789.721 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:13:09.721Z,1649268789.721 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:13:25.859Z,1649268805.859 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:18:10.350Z,1649269090.350 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:18:10.350Z,1649269090.350 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:18:10.350Z,1649269090.350 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:18:10.350Z,1649269090.350 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:18:10.769Z,1649269090.769 [Default:CheckIn:D] Stopped
2022-04-06T18:18:10.769Z,1649269090.769 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:18:11.169Z,1649269091.169 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.518392 min 
2022-04-06T18:18:11.169Z,1649269091.169 [Default:CheckIn:E] Stopped
2022-04-06T18:18:11.169Z,1649269091.169 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:18:11.170Z,1649269091.170 [Default:CheckIn] Stopped
2022-04-06T18:18:11.170Z,1649269091.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:18:11.170Z,1649269091.170 [Default:CheckIn](INFO): Running loop #10
2022-04-06T18:18:11.170Z,1649269091.170 [Default:CheckIn] Running Loop=10
2022-04-06T18:18:11.170Z,1649269091.170 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:18:11.170Z,1649269091.170 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:18:13.165Z,1649269093.165 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181812.00,A,3648.13838,N,12147.22932,W,0.292,254.46,060422,,,A*74
2022-04-06T18:18:13.167Z,1649269093.167 [NAL9602](INFO): GPS fix at 20220406T181812: (36.802306, -121.787155)
2022-04-06T18:18:13.179Z,1649269093.179 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:18:13.179Z,1649269093.179 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:18:22.350Z,1649269102.350 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0031.lzma
2022-04-06T18:18:23.353Z,1649269103.353 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0031.lzma.bak
2022-04-06T18:18:23.353Z,1649269103.353 [DataOverHttps](INFO): SBD MOMSN=16628459
2022-04-06T18:18:24.908Z,1649269104.908 [NAL9602](INFO): SBD MO Status=0, MOMSN=4549, MT Status=0, MTMSN=0
2022-04-06T18:18:24.908Z,1649269104.908 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:18:45.034Z,1649269125.034 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220406T164759/Express0032.lzma
2022-04-06T18:18:46.036Z,1649269126.036 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0032.lzma.bak
2022-04-06T18:18:46.037Z,1649269126.037 [DataOverHttps](INFO): SBD MOMSN=16628462
2022-04-06T18:18:49.175Z,1649269129.175 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:18:49.175Z,1649269129.175 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:18:49.175Z,1649269129.175 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:18:55.615Z,1649269135.615 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:23:46.644Z,1649269426.644 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-06T18:23:49.849Z,1649269429.849 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:23:49.849Z,1649269429.849 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:23:49.849Z,1649269429.849 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:23:49.849Z,1649269429.849 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:23:50.270Z,1649269430.270 [Default:CheckIn:D] Stopped
2022-04-06T18:23:50.270Z,1649269430.270 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:23:50.665Z,1649269430.665 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.176742 min 
2022-04-06T18:23:50.665Z,1649269430.665 [Default:CheckIn:E] Stopped
2022-04-06T18:23:50.665Z,1649269430.665 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:23:50.665Z,1649269430.665 [Default:CheckIn] Stopped
2022-04-06T18:23:50.665Z,1649269430.665 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:23:50.665Z,1649269430.665 [Default:CheckIn](INFO): Running loop #11
2022-04-06T18:23:50.665Z,1649269430.665 [Default:CheckIn] Running Loop=11
2022-04-06T18:23:50.665Z,1649269430.665 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:23:50.666Z,1649269430.666 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:23:52.623Z,1649269432.623 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182351.00,A,3648.13961,N,12147.23039,W,0.136,353.73,060422,,,A*78
2022-04-06T18:23:52.625Z,1649269432.625 [NAL9602](INFO): GPS fix at 20220406T182351: (36.802327, -121.787173)
2022-04-06T18:23:52.636Z,1649269432.636 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:23:52.636Z,1649269432.636 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:23:55.817Z,1649269435.817 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0034.lzma
2022-04-06T18:23:56.825Z,1649269436.825 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0034.lzma.bak
2022-04-06T18:23:56.825Z,1649269436.825 [DataOverHttps](INFO): SBD MOMSN=16628474
2022-04-06T18:23:57.746Z,1649269437.746 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006807
CHAN A1 (24V): -0.000131
CHAN A2 (12V): -0.005667
CHAN A3 (5V): -0.002561
CHAN B0 (3.3V): -0.001396
CHAN B1 (3.15aV): -0.001079
CHAN B2 (3.15bV): -0.001581
CHAN B3 (GND): -0.000471
OPEN: 0.004784
Full Scale: +/- 1 mA
2022-04-06T18:24:08.611Z,1649269448.611 [NAL9602](INFO): SBD MO Status=0, MOMSN=4550, MT Status=0, MTMSN=0
2022-04-06T18:24:08.612Z,1649269448.612 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:24:16.186Z,1649269456.186 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20220406T164759/Express0035.lzma
2022-04-06T18:24:17.188Z,1649269457.188 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0035.lzma.bak
2022-04-06T18:24:17.189Z,1649269457.189 [DataOverHttps](INFO): SBD MOMSN=16628477
2022-04-06T18:24:20.334Z,1649269460.334 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:24:20.334Z,1649269460.334 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:24:20.354Z,1649269460.354 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:24:39.303Z,1649269479.303 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:29:20.962Z,1649269760.962 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:29:20.962Z,1649269760.962 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:29:20.962Z,1649269760.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:29:20.962Z,1649269760.962 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:29:21.347Z,1649269761.347 [Default:CheckIn:D] Stopped
2022-04-06T18:29:21.347Z,1649269761.347 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:29:21.748Z,1649269761.748 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.694710 min 
2022-04-06T18:29:21.748Z,1649269761.748 [Default:CheckIn:E] Stopped
2022-04-06T18:29:21.748Z,1649269761.748 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:29:21.748Z,1649269761.748 [Default:CheckIn] Stopped
2022-04-06T18:29:21.748Z,1649269761.748 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:29:21.748Z,1649269761.748 [Default:CheckIn](INFO): Running loop #12
2022-04-06T18:29:21.748Z,1649269761.748 [Default:CheckIn] Running Loop=12
2022-04-06T18:29:21.748Z,1649269761.748 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:29:21.749Z,1649269761.749 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:29:23.762Z,1649269763.762 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182923.00,A,3648.14162,N,12147.22905,W,0.097,250.19,060422,,,A*78
2022-04-06T18:29:23.767Z,1649269763.767 [NAL9602](INFO): GPS fix at 20220406T182923: (36.802360, -121.787151)
2022-04-06T18:29:23.784Z,1649269763.784 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:29:23.784Z,1649269763.784 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:29:32.954Z,1649269772.954 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0037.lzma
2022-04-06T18:29:33.956Z,1649269773.956 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0037.lzma.bak
2022-04-06T18:29:33.957Z,1649269773.957 [DataOverHttps](INFO): SBD MOMSN=16628489
2022-04-06T18:29:44.765Z,1649269784.765 [NAL9602](INFO): SBD MO Status=0, MOMSN=4551, MT Status=0, MTMSN=0
2022-04-06T18:29:44.765Z,1649269784.765 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:29:55.903Z,1649269795.903 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20220406T164759/Express0038.lzma
2022-04-06T18:29:56.905Z,1649269796.905 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0038.lzma.bak
2022-04-06T18:29:56.905Z,1649269796.905 [DataOverHttps](INFO): SBD MOMSN=16628492
2022-04-06T18:30:01.847Z,1649269801.847 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:30:01.847Z,1649269801.847 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:30:01.847Z,1649269801.847 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:30:15.531Z,1649269815.531 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:35:02.491Z,1649270102.491 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:35:02.491Z,1649270102.491 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:35:02.491Z,1649270102.491 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:35:02.491Z,1649270102.491 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:35:02.891Z,1649270102.891 [Default:CheckIn:D] Stopped
2022-04-06T18:35:02.891Z,1649270102.891 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:35:03.300Z,1649270103.300 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.387028 min 
2022-04-06T18:35:03.301Z,1649270103.301 [Default:CheckIn:E] Stopped
2022-04-06T18:35:03.301Z,1649270103.301 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:35:03.301Z,1649270103.301 [Default:CheckIn] Stopped
2022-04-06T18:35:03.301Z,1649270103.301 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:35:03.301Z,1649270103.301 [Default:CheckIn](INFO): Running loop #13
2022-04-06T18:35:03.301Z,1649270103.301 [Default:CheckIn] Running Loop=13
2022-04-06T18:35:03.301Z,1649270103.301 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:35:03.301Z,1649270103.301 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:35:05.276Z,1649270105.276 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183504.00,A,3648.14212,N,12147.22848,W,0.039,250.19,060422,,,A*78
2022-04-06T18:35:05.279Z,1649270105.279 [NAL9602](INFO): GPS fix at 20220406T183504: (36.802369, -121.787141)
2022-04-06T18:35:05.290Z,1649270105.290 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:35:05.290Z,1649270105.290 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:35:13.782Z,1649270113.782 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0040.lzma
2022-04-06T18:35:14.784Z,1649270114.784 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0040.lzma.bak
2022-04-06T18:35:14.785Z,1649270114.785 [DataOverHttps](INFO): SBD MOMSN=16628507
2022-04-06T18:35:14.976Z,1649270114.976 [NAL9602](INFO): SBD MO Status=0, MOMSN=4552, MT Status=0, MTMSN=0
2022-04-06T18:35:14.977Z,1649270114.977 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:35:32.774Z,1649270132.774 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220406T164759/Express0041.lzma
2022-04-06T18:35:33.777Z,1649270133.777 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0041.lzma.bak
2022-04-06T18:35:33.777Z,1649270133.777 [DataOverHttps](INFO): SBD MOMSN=16628512
2022-04-06T18:35:36.819Z,1649270136.819 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:35:36.819Z,1649270136.819 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:35:36.820Z,1649270136.820 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:35:45.686Z,1649270145.686 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:40:37.430Z,1649270437.430 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:40:37.430Z,1649270437.430 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:40:37.430Z,1649270437.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:40:37.430Z,1649270437.430 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:40:37.837Z,1649270437.837 [Default:CheckIn:D] Stopped
2022-04-06T18:40:37.837Z,1649270437.837 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:40:38.248Z,1649270438.248 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.969539 min 
2022-04-06T18:40:38.248Z,1649270438.248 [Default:CheckIn:E] Stopped
2022-04-06T18:40:38.248Z,1649270438.248 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:40:38.248Z,1649270438.248 [Default:CheckIn] Stopped
2022-04-06T18:40:38.248Z,1649270438.248 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:40:38.248Z,1649270438.248 [Default:CheckIn](INFO): Running loop #14
2022-04-06T18:40:38.248Z,1649270438.248 [Default:CheckIn] Running Loop=14
2022-04-06T18:40:38.248Z,1649270438.248 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:40:38.249Z,1649270438.249 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:40:40.261Z,1649270440.261 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184039.00,A,3648.14369,N,12147.22801,W,0.156,250.19,060422,,,A*7C
2022-04-06T18:40:40.263Z,1649270440.263 [NAL9602](INFO): GPS fix at 20220406T184039: (36.802395, -121.787133)
2022-04-06T18:40:40.275Z,1649270440.275 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:40:40.275Z,1649270440.275 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:40:48.910Z,1649270448.910 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0043.lzma
2022-04-06T18:40:49.912Z,1649270449.912 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0043.lzma.bak
2022-04-06T18:40:49.913Z,1649270449.913 [DataOverHttps](INFO): SBD MOMSN=16628527
2022-04-06T18:41:02.905Z,1649270462.905 [NAL9602](INFO): SBD MO Status=0, MOMSN=4553, MT Status=0, MTMSN=0
2022-04-06T18:41:02.905Z,1649270462.905 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:41:08.122Z,1649270468.122 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0044.lzma
2022-04-06T18:41:09.207Z,1649270469.207 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0044.lzma.bak
2022-04-06T18:41:09.208Z,1649270469.208 [DataOverHttps](INFO): SBD MOMSN=16628531
2022-04-06T18:41:12.220Z,1649270472.220 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:41:12.220Z,1649270472.220 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:41:12.221Z,1649270472.221 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:41:33.606Z,1649270493.606 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:46:12.861Z,1649270772.861 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:46:12.861Z,1649270772.861 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:46:12.861Z,1649270772.861 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:46:12.861Z,1649270772.861 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:46:13.248Z,1649270773.248 [Default:CheckIn:D] Stopped
2022-04-06T18:46:13.248Z,1649270773.248 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:46:13.647Z,1649270773.647 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.559725 min 
2022-04-06T18:46:13.647Z,1649270773.647 [Default:CheckIn:E] Stopped
2022-04-06T18:46:13.647Z,1649270773.647 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:46:13.647Z,1649270773.647 [Default:CheckIn] Stopped
2022-04-06T18:46:13.647Z,1649270773.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:46:13.647Z,1649270773.647 [Default:CheckIn](INFO): Running loop #15
2022-04-06T18:46:13.647Z,1649270773.647 [Default:CheckIn] Running Loop=15
2022-04-06T18:46:13.647Z,1649270773.647 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:46:13.647Z,1649270773.647 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:46:15.664Z,1649270775.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184615.00,A,3648.14010,N,12147.23050,W,0.019,216.01,060422,,,A*75
2022-04-06T18:46:15.667Z,1649270775.667 [NAL9602](INFO): GPS fix at 20220406T184615: (36.802335, -121.787175)
2022-04-06T18:46:15.678Z,1649270775.678 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:46:15.678Z,1649270775.678 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:46:24.594Z,1649270784.594 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0046.lzma
2022-04-06T18:46:25.596Z,1649270785.596 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0046.lzma.bak
2022-04-06T18:46:25.597Z,1649270785.597 [DataOverHttps](INFO): SBD MOMSN=16628544
2022-04-06T18:46:43.870Z,1649270803.870 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220406T164759/Express0047.lzma
2022-04-06T18:46:44.872Z,1649270804.872 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0047.lzma.bak
2022-04-06T18:46:44.873Z,1649270804.873 [DataOverHttps](INFO): SBD MOMSN=16628547
2022-04-06T18:46:48.013Z,1649270808.013 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:46:48.013Z,1649270808.013 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:46:48.013Z,1649270808.013 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:47:02.533Z,1649270822.533 [NAL9602](INFO): SBD MO Status=0, MOMSN=4554, MT Status=0, MTMSN=0
2022-04-06T18:47:02.533Z,1649270822.533 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:47:33.227Z,1649270853.227 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:51:48.658Z,1649271108.658 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:51:48.658Z,1649271108.658 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:51:48.658Z,1649271108.658 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:51:48.658Z,1649271108.658 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:51:49.035Z,1649271109.035 [Default:CheckIn:D] Stopped
2022-04-06T18:51:49.035Z,1649271109.035 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:51:49.489Z,1649271109.489 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.156177 min 
2022-04-06T18:51:49.489Z,1649271109.489 [Default:CheckIn:E] Stopped
2022-04-06T18:51:49.489Z,1649271109.489 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:51:49.489Z,1649271109.489 [Default:CheckIn] Stopped
2022-04-06T18:51:49.490Z,1649271109.490 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:51:49.490Z,1649271109.490 [Default:CheckIn](INFO): Running loop #16
2022-04-06T18:51:49.490Z,1649271109.490 [Default:CheckIn] Running Loop=16
2022-04-06T18:51:49.490Z,1649271109.490 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:51:49.490Z,1649271109.490 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:51:51.482Z,1649271111.482 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185150.00,A,3648.14029,N,12147.22986,W,0.078,216.01,060422,,,A*7C
2022-04-06T18:51:51.488Z,1649271111.488 [NAL9602](INFO): GPS fix at 20220406T185150: (36.802338, -121.787164)
2022-04-06T18:51:51.556Z,1649271111.556 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:51:51.556Z,1649271111.556 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:51:55.052Z,1649271115.052 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0049.lzma
2022-04-06T18:51:56.056Z,1649271116.056 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0049.lzma.bak
2022-04-06T18:51:56.057Z,1649271116.057 [DataOverHttps](INFO): SBD MOMSN=16628561
2022-04-06T18:52:16.204Z,1649271136.204 [NAL9602](INFO): SBD MO Status=0, MOMSN=4555, MT Status=0, MTMSN=0
2022-04-06T18:52:16.204Z,1649271136.204 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:52:16.310Z,1649271136.310 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220406T164759/Express0050.lzma
2022-04-06T18:52:17.313Z,1649271137.313 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0050.lzma.bak
2022-04-06T18:52:17.313Z,1649271137.313 [DataOverHttps](INFO): SBD MOMSN=16628564
2022-04-06T18:52:20.312Z,1649271140.312 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:52:20.312Z,1649271140.312 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:52:20.312Z,1649271140.312 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:52:46.911Z,1649271166.911 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T18:53:58.064Z,1649271238.064 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-06T18:54:08.973Z,1649271248.973 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006729
CHAN A1 (24V): 0.000495
CHAN A2 (12V): -0.005722
CHAN A3 (5V): -0.003051
CHAN B0 (3.3V): -0.000845
CHAN B1 (3.15aV): -0.001402
CHAN B2 (3.15bV): -0.001155
CHAN B3 (GND): -0.000865
OPEN: 0.004512
Full Scale: +/- 1 mA
2022-04-06T18:57:20.865Z,1649271440.865 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T18:57:20.865Z,1649271440.865 [Default:CheckIn:C.Wait] Stopped
2022-04-06T18:57:20.865Z,1649271440.865 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T18:57:20.865Z,1649271440.865 [Default:CheckIn:D] Running Loop=1
2022-04-06T18:57:21.308Z,1649271441.308 [Default:CheckIn:D] Stopped
2022-04-06T18:57:21.308Z,1649271441.308 [Default:CheckIn:E] Running Loop=1
2022-04-06T18:57:21.671Z,1649271441.671 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.694043 min 
2022-04-06T18:57:21.671Z,1649271441.671 [Default:CheckIn:E] Stopped
2022-04-06T18:57:21.671Z,1649271441.671 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T18:57:21.671Z,1649271441.671 [Default:CheckIn] Stopped
2022-04-06T18:57:21.671Z,1649271441.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T18:57:21.672Z,1649271441.672 [Default:CheckIn](INFO): Running loop #17
2022-04-06T18:57:21.672Z,1649271441.672 [Default:CheckIn] Running Loop=17
2022-04-06T18:57:21.672Z,1649271441.672 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T18:57:21.672Z,1649271441.672 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T18:57:23.681Z,1649271443.681 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185723.00,A,3648.14175,N,12147.22858,W,0.019,348.76,060422,,,A*79
2022-04-06T18:57:23.695Z,1649271443.695 [NAL9602](INFO): GPS fix at 20220406T185723: (36.802363, -121.787143)
2022-04-06T18:57:23.707Z,1649271443.707 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T18:57:23.707Z,1649271443.707 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T18:57:32.430Z,1649271452.430 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0052.lzma
2022-04-06T18:57:33.433Z,1649271453.433 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0052.lzma.bak
2022-04-06T18:57:33.433Z,1649271453.433 [DataOverHttps](INFO): SBD MOMSN=16628573
2022-04-06T18:57:52.858Z,1649271472.858 [NAL9602](INFO): SBD MO Status=0, MOMSN=4556, MT Status=0, MTMSN=0
2022-04-06T18:57:52.858Z,1649271472.858 [NAL9602](INFO): No messages in MT queue
2022-04-06T18:57:55.267Z,1649271475.267 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20220406T164759/Express0053.lzma
2022-04-06T18:57:56.269Z,1649271476.269 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0053.lzma.bak
2022-04-06T18:57:56.269Z,1649271476.269 [DataOverHttps](INFO): SBD MOMSN=16628576
2022-04-06T18:58:01.738Z,1649271481.738 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T18:58:01.738Z,1649271481.738 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T18:58:01.738Z,1649271481.738 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T18:58:23.530Z,1649271503.530 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:03:02.384Z,1649271782.384 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:03:02.384Z,1649271782.384 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:03:02.384Z,1649271782.384 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:03:02.385Z,1649271782.385 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:03:02.831Z,1649271782.831 [Default:CheckIn:D] Stopped
2022-04-06T19:03:02.831Z,1649271782.831 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:03:03.190Z,1649271783.190 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.386108 min 
2022-04-06T19:03:03.190Z,1649271783.190 [Default:CheckIn:E] Stopped
2022-04-06T19:03:03.190Z,1649271783.190 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:03:03.190Z,1649271783.190 [Default:CheckIn] Stopped
2022-04-06T19:03:03.190Z,1649271783.190 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:03:03.190Z,1649271783.190 [Default:CheckIn](INFO): Running loop #18
2022-04-06T19:03:03.190Z,1649271783.190 [Default:CheckIn] Running Loop=18
2022-04-06T19:03:03.191Z,1649271783.191 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:03:03.191Z,1649271783.191 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:03:05.213Z,1649271785.213 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190304.00,A,3648.14050,N,12147.22914,W,0.039,348.76,060422,,,A*71
2022-04-06T19:03:05.215Z,1649271785.215 [NAL9602](INFO): GPS fix at 20220406T190304: (36.802342, -121.787152)
2022-04-06T19:03:05.227Z,1649271785.227 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:03:05.227Z,1649271785.227 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:03:13.770Z,1649271793.770 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0055.lzma
2022-04-06T19:03:14.772Z,1649271794.772 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0055.lzma.bak
2022-04-06T19:03:14.773Z,1649271794.773 [DataOverHttps](INFO): SBD MOMSN=16628587
2022-04-06T19:03:32.778Z,1649271812.778 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0056.lzma
2022-04-06T19:03:33.781Z,1649271813.781 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0056.lzma.bak
2022-04-06T19:03:33.781Z,1649271813.781 [DataOverHttps](INFO): SBD MOMSN=16628590
2022-04-06T19:03:36.757Z,1649271816.757 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T19:03:36.757Z,1649271816.757 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T19:03:36.757Z,1649271816.757 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T19:04:30.110Z,1649271870.110 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-06T19:07:49.689Z,1649272069.689 [NAL9602](INFO): SBD MO Status=0, MOMSN=4557, MT Status=0, MTMSN=0
2022-04-06T19:07:49.690Z,1649272069.690 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:08:20.411Z,1649272100.411 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:08:37.378Z,1649272117.378 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:08:37.378Z,1649272117.378 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:08:37.378Z,1649272117.378 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:08:37.383Z,1649272117.383 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:08:37.789Z,1649272117.789 [Default:CheckIn:D] Stopped
2022-04-06T19:08:37.789Z,1649272117.789 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:08:38.178Z,1649272118.178 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.968734 min 
2022-04-06T19:08:38.179Z,1649272118.179 [Default:CheckIn:E] Stopped
2022-04-06T19:08:38.179Z,1649272118.179 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:08:38.179Z,1649272118.179 [Default:CheckIn] Stopped
2022-04-06T19:08:38.179Z,1649272118.179 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:08:38.179Z,1649272118.179 [Default:CheckIn](INFO): Running loop #19
2022-04-06T19:08:38.179Z,1649272118.179 [Default:CheckIn] Running Loop=19
2022-04-06T19:08:38.179Z,1649272118.179 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:08:38.179Z,1649272118.179 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:08:40.190Z,1649272120.190 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190839.00,A,3648.14338,N,12147.22945,W,0.097,348.76,060422,,,A*79
2022-04-06T19:08:40.193Z,1649272120.193 [NAL9602](INFO): GPS fix at 20220406T190839: (36.802390, -121.787158)
2022-04-06T19:08:40.228Z,1649272120.228 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:08:40.228Z,1649272120.228 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:08:48.677Z,1649272128.677 [NAL9602](INFO): SBD MO Status=0, MOMSN=4558, MT Status=0, MTMSN=0
2022-04-06T19:08:48.677Z,1649272128.677 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:08:49.502Z,1649272129.502 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0058.lzma
2022-04-06T19:08:50.505Z,1649272130.505 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0058.lzma.bak
2022-04-06T19:08:50.505Z,1649272130.505 [DataOverHttps](INFO): SBD MOMSN=16628599
2022-04-06T19:09:07.946Z,1649272147.946 [DataOverHttps](INFO): Sending 1612 bytes from file Logs/20220406T164759/Courier9223.lzma
2022-04-06T19:09:08.945Z,1649272148.945 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier9223.lzma.bak
2022-04-06T19:09:08.945Z,1649272148.945 [DataOverHttps](INFO): SBD MOMSN=16628602
2022-04-06T19:09:19.383Z,1649272159.383 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:09:27.258Z,1649272167.258 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0059.lzma
2022-04-06T19:09:28.261Z,1649272168.261 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0059.lzma.bak
2022-04-06T19:09:28.261Z,1649272168.261 [DataOverHttps](INFO): SBD MOMSN=16628604
2022-04-06T19:09:31.543Z,1649272171.543 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T19:09:31.543Z,1649272171.543 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T19:09:31.543Z,1649272171.543 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T19:10:34.547Z,1649272234.547 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-04-06T19:10:34.549Z,1649272234.549 [BPC1](INFO): Received data from all battery sticks.
2022-04-06T19:14:32.118Z,1649272472.118 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:14:32.118Z,1649272472.118 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:14:32.118Z,1649272472.118 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:14:32.118Z,1649272472.118 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:14:32.529Z,1649272472.529 [Default:CheckIn:D] Stopped
2022-04-06T19:14:32.529Z,1649272472.529 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:14:32.952Z,1649272472.952 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.881071 min 
2022-04-06T19:14:32.952Z,1649272472.952 [Default:CheckIn:E] Stopped
2022-04-06T19:14:32.952Z,1649272472.952 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:14:32.952Z,1649272472.952 [Default:CheckIn] Stopped
2022-04-06T19:14:32.952Z,1649272472.952 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:14:32.952Z,1649272472.952 [Default:CheckIn](INFO): Running loop #20
2022-04-06T19:14:32.952Z,1649272472.952 [Default:CheckIn] Running Loop=20
2022-04-06T19:14:32.953Z,1649272472.953 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:14:32.953Z,1649272472.953 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:14:34.933Z,1649272474.933 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191434.00,A,3648.14077,N,12147.22835,W,0.058,348.76,060422,,,A*74
2022-04-06T19:14:34.935Z,1649272474.935 [NAL9602](INFO): GPS fix at 20220406T191434: (36.802346, -121.787139)
2022-04-06T19:14:34.947Z,1649272474.947 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:14:34.947Z,1649272474.947 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:14:43.730Z,1649272483.730 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20220406T164759/Courier0061.lzma
2022-04-06T19:14:44.733Z,1649272484.733 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0061.lzma.bak
2022-04-06T19:14:44.733Z,1649272484.733 [DataOverHttps](INFO): SBD MOMSN=16628613
2022-04-06T19:15:02.846Z,1649272502.846 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220406T164759/Express0062.lzma
2022-04-06T19:15:03.849Z,1649272503.849 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0062.lzma.bak
2022-04-06T19:15:03.849Z,1649272503.849 [DataOverHttps](INFO): SBD MOMSN=16628616
2022-04-06T19:15:06.878Z,1649272506.878 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T19:15:06.878Z,1649272506.878 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T19:15:06.878Z,1649272506.878 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T19:16:07.847Z,1649272567.847 [NAL9602](INFO): SBD MO Status=2, MOMSN=4559, MT Status=2, MTMSN=0
2022-04-06T19:16:07.848Z,1649272567.848 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T19:16:40.975Z,1649272600.975 [NAL9602](INFO): SBD MO Status=2, MOMSN=4559, MT Status=2, MTMSN=0
2022-04-06T19:16:40.976Z,1649272600.976 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T19:16:59.156Z,1649272619.156 [NAL9602](INFO): SBD MO Status=0, MOMSN=4559, MT Status=0, MTMSN=0
2022-04-06T19:16:59.156Z,1649272619.156 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:17:29.863Z,1649272649.863 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:20:07.611Z,1649272807.611 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:20:07.611Z,1649272807.611 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:20:07.611Z,1649272807.611 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:20:07.611Z,1649272807.611 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:20:07.950Z,1649272807.950 [Default:CheckIn:D] Stopped
2022-04-06T19:20:07.950Z,1649272807.950 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:20:08.364Z,1649272808.364 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.471419 min 
2022-04-06T19:20:08.365Z,1649272808.365 [Default:CheckIn:E] Stopped
2022-04-06T19:20:08.365Z,1649272808.365 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:20:08.365Z,1649272808.365 [Default:CheckIn] Stopped
2022-04-06T19:20:08.365Z,1649272808.365 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:20:08.365Z,1649272808.365 [Default:CheckIn](INFO): Running loop #21
2022-04-06T19:20:08.365Z,1649272808.365 [Default:CheckIn] Running Loop=21
2022-04-06T19:20:08.365Z,1649272808.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:20:08.365Z,1649272808.365 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:20:10.368Z,1649272810.368 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192009.00,A,3648.14190,N,12147.22949,W,0.058,0.00,060422,,,A*71
2022-04-06T19:20:10.394Z,1649272810.394 [NAL9602](INFO): GPS fix at 20220406T192009: (36.802365, -121.787158)
2022-04-06T19:20:10.421Z,1649272810.421 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:20:10.422Z,1649272810.422 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:20:13.993Z,1649272813.993 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0064.lzma
2022-04-06T19:20:15.044Z,1649272815.044 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0064.lzma.bak
2022-04-06T19:20:15.045Z,1649272815.045 [DataOverHttps](INFO): SBD MOMSN=16628622
2022-04-06T19:20:33.005Z,1649272833.005 [DataOverHttps](INFO): Sending 1865 bytes from file Logs/20220406T164759/Courier9224.lzma
2022-04-06T19:20:34.058Z,1649272834.058 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier9224.lzma.bak
2022-04-06T19:20:34.059Z,1649272834.059 [DataOverHttps](INFO): SBD MOMSN=16628628
2022-04-06T19:20:34.622Z,1649272834.622 [NAL9602](INFO): SBD MO Status=2, MOMSN=4560, MT Status=2, MTMSN=0
2022-04-06T19:20:34.622Z,1649272834.622 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T19:20:52.122Z,1649272852.122 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0065.lzma
2022-04-06T19:20:53.125Z,1649272853.125 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0065.lzma.bak
2022-04-06T19:20:53.125Z,1649272853.125 [DataOverHttps](INFO): SBD MOMSN=16628630
2022-04-06T19:20:56.063Z,1649272856.063 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T19:20:56.063Z,1649272856.063 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T19:20:56.063Z,1649272856.063 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T19:21:09.377Z,1649272869.377 [NAL9602](INFO): SBD MO Status=2, MOMSN=4560, MT Status=2, MTMSN=0
2022-04-06T19:21:09.377Z,1649272869.377 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T19:22:45.531Z,1649272965.531 [NAL9602](INFO): SBD MO Status=2, MOMSN=4560, MT Status=2, MTMSN=0
2022-04-06T19:22:45.532Z,1649272965.532 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T19:23:02.895Z,1649272982.895 [NAL9602](INFO): SBD MO Status=2, MOMSN=4560, MT Status=2, MTMSN=0
2022-04-06T19:23:02.895Z,1649272982.895 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T19:23:21.075Z,1649273001.075 [NAL9602](INFO): SBD MO Status=0, MOMSN=4560, MT Status=0, MTMSN=0
2022-04-06T19:23:21.075Z,1649273001.075 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:23:51.774Z,1649273031.774 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:24:09.174Z,1649273049.174 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-06T19:24:20.101Z,1649273060.101 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005994
CHAN A1 (24V): 0.000101
CHAN A2 (12V): -0.005587
CHAN A3 (5V): -0.003099
CHAN B0 (3.3V): -0.001219
CHAN B1 (3.15aV): -0.001129
CHAN B2 (3.15bV): -0.001415
CHAN B3 (GND): -0.000600
OPEN: 0.004771
Full Scale: +/- 1 mA
2022-04-06T19:25:56.658Z,1649273156.658 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:25:56.658Z,1649273156.658 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:25:56.658Z,1649273156.658 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:25:56.658Z,1649273156.658 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:25:57.092Z,1649273157.092 [Default:CheckIn:D] Stopped
2022-04-06T19:25:57.092Z,1649273157.092 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:25:57.549Z,1649273157.549 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.290462 min 
2022-04-06T19:25:57.549Z,1649273157.549 [Default:CheckIn:E] Stopped
2022-04-06T19:25:57.549Z,1649273157.549 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:25:57.550Z,1649273157.550 [Default:CheckIn] Stopped
2022-04-06T19:25:57.550Z,1649273157.550 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:25:57.550Z,1649273157.550 [Default:CheckIn](INFO): Running loop #22
2022-04-06T19:25:57.550Z,1649273157.550 [Default:CheckIn] Running Loop=22
2022-04-06T19:25:57.550Z,1649273157.550 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:25:57.550Z,1649273157.550 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:25:59.453Z,1649273159.453 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192558.00,A,3648.14156,N,12147.22823,W,0.039,0.00,060422,,,A*70
2022-04-06T19:25:59.456Z,1649273159.456 [NAL9602](INFO): GPS fix at 20220406T192558: (36.802359, -121.787137)
2022-04-06T19:25:59.517Z,1649273159.517 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:25:59.517Z,1649273159.517 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:26:02.051Z,1649273162.051 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0067.lzma
2022-04-06T19:26:03.056Z,1649273163.056 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0067.lzma.bak
2022-04-06T19:26:03.057Z,1649273163.057 [DataOverHttps](INFO): SBD MOMSN=16628639
2022-04-06T19:26:14.630Z,1649273174.630 [NAL9602](INFO): SBD MO Status=0, MOMSN=4561, MT Status=0, MTMSN=0
2022-04-06T19:26:14.630Z,1649273174.630 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:26:22.047Z,1649273182.047 [DataOverHttps](INFO): Sending 308 bytes from file Logs/20220406T164759/Express0068.lzma
2022-04-06T19:26:23.049Z,1649273183.049 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0068.lzma.bak
2022-04-06T19:26:23.049Z,1649273183.049 [DataOverHttps](INFO): SBD MOMSN=16628642
2022-04-06T19:26:25.987Z,1649273185.987 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T19:26:25.987Z,1649273185.987 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T19:26:25.987Z,1649273185.987 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T19:26:45.362Z,1649273205.362 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:31:26.634Z,1649273486.634 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:31:26.639Z,1649273486.639 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:31:26.639Z,1649273486.639 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:31:26.639Z,1649273486.639 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:31:27.017Z,1649273487.017 [Default:CheckIn:D] Stopped
2022-04-06T19:31:27.017Z,1649273487.017 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:31:27.430Z,1649273487.430 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.789193 min 
2022-04-06T19:31:27.430Z,1649273487.430 [Default:CheckIn:E] Stopped
2022-04-06T19:31:27.430Z,1649273487.430 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:31:27.430Z,1649273487.430 [Default:CheckIn] Stopped
2022-04-06T19:31:27.430Z,1649273487.430 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:31:27.430Z,1649273487.430 [Default:CheckIn](INFO): Running loop #23
2022-04-06T19:31:27.431Z,1649273487.431 [Default:CheckIn] Running Loop=23
2022-04-06T19:31:27.431Z,1649273487.431 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:31:27.431Z,1649273487.431 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:31:29.432Z,1649273489.432 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193128.00,A,3648.14104,N,12147.22842,W,0.019,0.00,060422,,,A*70
2022-04-06T19:31:29.434Z,1649273489.434 [NAL9602](INFO): GPS fix at 20220406T193128: (36.802351, -121.787140)
2022-04-06T19:31:29.472Z,1649273489.472 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:31:29.472Z,1649273489.472 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:31:38.066Z,1649273498.066 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0070.lzma
2022-04-06T19:31:39.069Z,1649273499.069 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0070.lzma.bak
2022-04-06T19:31:39.069Z,1649273499.069 [DataOverHttps](INFO): SBD MOMSN=16628654
2022-04-06T19:31:42.763Z,1649273502.763 [NAL9602](INFO): SBD MO Status=0, MOMSN=4562, MT Status=0, MTMSN=0
2022-04-06T19:31:42.763Z,1649273502.763 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:32:03.582Z,1649273523.582 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20220406T164759/Express0071.lzma
2022-04-06T19:32:04.585Z,1649273524.585 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0071.lzma.bak
2022-04-06T19:32:04.585Z,1649273524.585 [DataOverHttps](INFO): SBD MOMSN=16628660
2022-04-06T19:32:07.526Z,1649273527.526 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T19:32:07.526Z,1649273527.526 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T19:32:07.526Z,1649273527.526 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T19:32:13.563Z,1649273533.563 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:37:08.198Z,1649273828.198 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:37:08.198Z,1649273828.198 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:37:08.199Z,1649273828.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:37:08.199Z,1649273828.199 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:37:08.614Z,1649273828.614 [Default:CheckIn:D] Stopped
2022-04-06T19:37:08.614Z,1649273828.614 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:37:09.003Z,1649273829.003 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.482471 min 
2022-04-06T19:37:09.003Z,1649273829.003 [Default:CheckIn:E] Stopped
2022-04-06T19:37:09.003Z,1649273829.003 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:37:09.003Z,1649273829.003 [Default:CheckIn] Stopped
2022-04-06T19:37:09.003Z,1649273829.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:37:09.003Z,1649273829.003 [Default:CheckIn](INFO): Running loop #24
2022-04-06T19:37:09.003Z,1649273829.003 [Default:CheckIn] Running Loop=24
2022-04-06T19:37:09.003Z,1649273829.003 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:37:09.004Z,1649273829.004 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:37:11.012Z,1649273831.012 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193710.00,A,3648.14223,N,12147.22800,W,0.019,48.50,060422,,,A*44
2022-04-06T19:37:11.014Z,1649273831.014 [NAL9602](INFO): GPS fix at 20220406T193710: (36.802371, -121.787133)
2022-04-06T19:37:11.026Z,1649273831.026 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:37:11.026Z,1649273831.026 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:37:19.926Z,1649273839.926 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0073.lzma
2022-04-06T19:37:20.931Z,1649273840.931 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0073.lzma.bak
2022-04-06T19:37:20.931Z,1649273840.931 [DataOverHttps](INFO): SBD MOMSN=16628675
2022-04-06T19:37:25.157Z,1649273845.157 [NAL9602](INFO): SBD MO Status=0, MOMSN=4563, MT Status=0, MTMSN=0
2022-04-06T19:37:25.157Z,1649273845.157 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:37:39.266Z,1649273859.266 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0074.lzma
2022-04-06T19:37:40.269Z,1649273860.269 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0074.lzma.bak
2022-04-06T19:37:40.269Z,1649273860.269 [DataOverHttps](INFO): SBD MOMSN=16628678
2022-04-06T19:37:43.350Z,1649273863.350 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T19:37:43.350Z,1649273863.350 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T19:37:43.350Z,1649273863.350 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T19:37:55.939Z,1649273875.939 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:42:44.046Z,1649274164.046 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:42:44.046Z,1649274164.046 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:42:44.046Z,1649274164.046 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:42:44.046Z,1649274164.046 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:42:44.459Z,1649274164.459 [Default:CheckIn:D] Stopped
2022-04-06T19:42:44.459Z,1649274164.459 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:42:44.889Z,1649274164.889 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.079899 min 
2022-04-06T19:42:44.889Z,1649274164.889 [Default:CheckIn:E] Stopped
2022-04-06T19:42:44.889Z,1649274164.889 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:42:44.889Z,1649274164.889 [Default:CheckIn] Stopped
2022-04-06T19:42:44.889Z,1649274164.889 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:42:44.890Z,1649274164.890 [Default:CheckIn](INFO): Running loop #25
2022-04-06T19:42:44.890Z,1649274164.890 [Default:CheckIn] Running Loop=25
2022-04-06T19:42:44.890Z,1649274164.890 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:42:44.890Z,1649274164.890 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:42:46.865Z,1649274166.865 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194246.00,A,3648.13997,N,12147.22843,W,0.039,210.01,060422,,,A*78
2022-04-06T19:42:46.867Z,1649274166.867 [NAL9602](INFO): GPS fix at 20220406T194246: (36.802333, -121.787141)
2022-04-06T19:42:46.889Z,1649274166.889 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:42:46.889Z,1649274166.889 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:42:50.367Z,1649274170.367 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0076.lzma
2022-04-06T19:42:51.445Z,1649274171.445 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0076.lzma.bak
2022-04-06T19:42:51.445Z,1649274171.445 [DataOverHttps](INFO): SBD MOMSN=16628688
2022-04-06T19:43:07.141Z,1649274187.141 [NAL9602](INFO): SBD MO Status=2, MOMSN=4564, MT Status=2, MTMSN=0
2022-04-06T19:43:07.141Z,1649274187.141 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T19:43:09.494Z,1649274189.494 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0077.lzma
2022-04-06T19:43:10.497Z,1649274190.497 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0077.lzma.bak
2022-04-06T19:43:10.497Z,1649274190.497 [DataOverHttps](INFO): SBD MOMSN=16628694
2022-04-06T19:43:13.644Z,1649274193.644 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T19:43:13.644Z,1649274193.644 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T19:43:13.644Z,1649274193.644 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T19:43:22.093Z,1649274202.093 [NAL9602](INFO): SBD MO Status=0, MOMSN=4564, MT Status=0, MTMSN=0
2022-04-06T19:43:22.093Z,1649274202.093 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:43:52.795Z,1649274232.795 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:48:14.256Z,1649274494.256 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:48:14.256Z,1649274494.256 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:48:14.257Z,1649274494.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:48:14.257Z,1649274494.257 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:48:14.673Z,1649274494.673 [Default:CheckIn:D] Stopped
2022-04-06T19:48:14.673Z,1649274494.673 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:48:15.065Z,1649274495.065 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 176.583464 min 
2022-04-06T19:48:15.065Z,1649274495.065 [Default:CheckIn:E] Stopped
2022-04-06T19:48:15.066Z,1649274495.066 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:48:15.066Z,1649274495.066 [Default:CheckIn] Stopped
2022-04-06T19:48:15.066Z,1649274495.066 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:48:15.066Z,1649274495.066 [Default:CheckIn](INFO): Running loop #26
2022-04-06T19:48:15.066Z,1649274495.066 [Default:CheckIn] Running Loop=26
2022-04-06T19:48:15.066Z,1649274495.066 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:48:15.066Z,1649274495.066 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:48:17.074Z,1649274497.074 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194816.00,A,3648.14074,N,12147.22704,W,0.019,210.01,060422,,,A*7A
2022-04-06T19:48:17.077Z,1649274497.077 [NAL9602](INFO): GPS fix at 20220406T194816: (36.802346, -121.787117)
2022-04-06T19:48:17.088Z,1649274497.088 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:48:17.088Z,1649274497.088 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:48:26.314Z,1649274506.314 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0079.lzma
2022-04-06T19:48:27.318Z,1649274507.318 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0079.lzma.bak
2022-04-06T19:48:27.319Z,1649274507.319 [DataOverHttps](INFO): SBD MOMSN=16628703
2022-04-06T19:48:31.696Z,1649274511.696 [NAL9602](INFO): SBD MO Status=0, MOMSN=4565, MT Status=0, MTMSN=0
2022-04-06T19:48:31.696Z,1649274511.696 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:48:45.870Z,1649274525.870 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220406T164759/Express0080.lzma
2022-04-06T19:48:46.873Z,1649274526.873 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0080.lzma.bak
2022-04-06T19:48:46.873Z,1649274526.873 [DataOverHttps](INFO): SBD MOMSN=16628709
2022-04-06T19:48:49.897Z,1649274529.897 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T19:48:49.897Z,1649274529.897 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T19:48:49.898Z,1649274529.898 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T19:49:02.395Z,1649274542.395 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:53:50.580Z,1649274830.580 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:53:50.580Z,1649274830.580 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:53:50.580Z,1649274830.580 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:53:50.580Z,1649274830.580 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:53:50.965Z,1649274830.965 [Default:CheckIn:D] Stopped
2022-04-06T19:53:50.965Z,1649274830.965 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:53:51.346Z,1649274831.346 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.188330 min 
2022-04-06T19:53:51.346Z,1649274831.346 [Default:CheckIn:E] Stopped
2022-04-06T19:53:51.346Z,1649274831.346 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:53:51.346Z,1649274831.346 [Default:CheckIn] Stopped
2022-04-06T19:53:51.346Z,1649274831.346 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:53:51.351Z,1649274831.351 [Default:CheckIn](INFO): Running loop #27
2022-04-06T19:53:51.351Z,1649274831.351 [Default:CheckIn] Running Loop=27
2022-04-06T19:53:51.351Z,1649274831.351 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:53:51.351Z,1649274831.351 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:53:53.361Z,1649274833.361 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195352.00,A,3648.14135,N,12147.22766,W,0.039,210.01,060422,,,A*72
2022-04-06T19:53:53.367Z,1649274833.367 [NAL9602](INFO): GPS fix at 20220406T195352: (36.802356, -121.787128)
2022-04-06T19:53:53.441Z,1649274833.441 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:53:53.441Z,1649274833.441 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:53:56.389Z,1649274836.389 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0082.lzma
2022-04-06T19:53:57.404Z,1649274837.404 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0082.lzma.bak
2022-04-06T19:53:57.405Z,1649274837.405 [DataOverHttps](INFO): SBD MOMSN=16628714
2022-04-06T19:54:09.940Z,1649274849.940 [NAL9602](INFO): SBD MO Status=0, MOMSN=4566, MT Status=0, MTMSN=0
2022-04-06T19:54:09.940Z,1649274849.940 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:54:17.718Z,1649274857.718 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220406T164759/Express0083.lzma
2022-04-06T19:54:18.721Z,1649274858.721 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0083.lzma.bak
2022-04-06T19:54:18.721Z,1649274858.721 [DataOverHttps](INFO): SBD MOMSN=16628720
2022-04-06T19:54:20.109Z,1649274860.109 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-06T19:54:21.677Z,1649274861.677 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T19:54:21.677Z,1649274861.677 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T19:54:21.677Z,1649274861.677 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T19:54:30.975Z,1649274870.975 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007022
CHAN A1 (24V): 0.000845
CHAN A2 (12V): -0.005193
CHAN A3 (5V): -0.002889
CHAN B0 (3.3V): -0.001323
CHAN B1 (3.15aV): -0.001117
CHAN B2 (3.15bV): -0.001465
CHAN B3 (GND): -0.000335
OPEN: 0.004496
Full Scale: +/- 1 mA
2022-04-06T19:54:40.642Z,1649274880.642 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T19:59:22.264Z,1649275162.264 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T19:59:22.264Z,1649275162.264 [Default:CheckIn:C.Wait] Stopped
2022-04-06T19:59:22.264Z,1649275162.264 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T19:59:22.264Z,1649275162.264 [Default:CheckIn:D] Running Loop=1
2022-04-06T19:59:22.670Z,1649275162.670 [Default:CheckIn:D] Stopped
2022-04-06T19:59:22.671Z,1649275162.671 [Default:CheckIn:E] Running Loop=1
2022-04-06T19:59:23.081Z,1649275163.081 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 187.716764 min 
2022-04-06T19:59:23.081Z,1649275163.081 [Default:CheckIn:E] Stopped
2022-04-06T19:59:23.081Z,1649275163.081 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T19:59:23.081Z,1649275163.081 [Default:CheckIn] Stopped
2022-04-06T19:59:23.081Z,1649275163.081 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T19:59:23.081Z,1649275163.081 [Default:CheckIn](INFO): Running loop #28
2022-04-06T19:59:23.081Z,1649275163.081 [Default:CheckIn] Running Loop=28
2022-04-06T19:59:23.082Z,1649275163.082 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T19:59:23.082Z,1649275163.082 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T19:59:25.076Z,1649275165.076 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195924.00,A,3648.13942,N,12147.22707,W,0.117,210.01,060422,,,A*7C
2022-04-06T19:59:25.078Z,1649275165.078 [NAL9602](INFO): GPS fix at 20220406T195924: (36.802324, -121.787118)
2022-04-06T19:59:25.091Z,1649275165.091 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T19:59:25.091Z,1649275165.091 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T19:59:33.702Z,1649275173.702 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0085.lzma
2022-04-06T19:59:34.705Z,1649275174.705 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0085.lzma.bak
2022-04-06T19:59:34.705Z,1649275174.705 [DataOverHttps](INFO): SBD MOMSN=16628728
2022-04-06T19:59:39.636Z,1649275179.636 [NAL9602](INFO): SBD MO Status=0, MOMSN=4567, MT Status=0, MTMSN=0
2022-04-06T19:59:39.636Z,1649275179.636 [NAL9602](INFO): No messages in MT queue
2022-04-06T19:59:56.203Z,1649275196.203 [DataOverHttps](INFO): Sending 309 bytes from file Logs/20220406T164759/Express0086.lzma
2022-04-06T19:59:57.205Z,1649275197.205 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0086.lzma.bak
2022-04-06T19:59:57.205Z,1649275197.205 [DataOverHttps](INFO): SBD MOMSN=16628734
2022-04-06T20:00:02.783Z,1649275202.783 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:00:02.783Z,1649275202.783 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:00:02.783Z,1649275202.783 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:00:10.386Z,1649275210.386 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T20:05:03.370Z,1649275503.370 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T20:05:03.370Z,1649275503.370 [Default:CheckIn:C.Wait] Stopped
2022-04-06T20:05:03.370Z,1649275503.370 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T20:05:03.371Z,1649275503.371 [Default:CheckIn:D] Running Loop=1
2022-04-06T20:05:03.765Z,1649275503.765 [Default:CheckIn:D] Stopped
2022-04-06T20:05:03.765Z,1649275503.765 [Default:CheckIn:E] Running Loop=1
2022-04-06T20:05:04.179Z,1649275504.179 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 193.401676 min 
2022-04-06T20:05:04.180Z,1649275504.180 [Default:CheckIn:E] Stopped
2022-04-06T20:05:04.180Z,1649275504.180 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T20:05:04.180Z,1649275504.180 [Default:CheckIn] Stopped
2022-04-06T20:05:04.180Z,1649275504.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T20:05:04.180Z,1649275504.180 [Default:CheckIn](INFO): Running loop #29
2022-04-06T20:05:04.180Z,1649275504.180 [Default:CheckIn] Running Loop=29
2022-04-06T20:05:04.180Z,1649275504.180 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T20:05:04.180Z,1649275504.180 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T20:05:06.189Z,1649275506.189 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200505.00,A,3648.14026,N,12147.22671,W,0.039,210.01,060422,,,A*7D
2022-04-06T20:05:06.203Z,1649275506.203 [NAL9602](INFO): GPS fix at 20220406T200505: (36.802338, -121.787112)
2022-04-06T20:05:06.231Z,1649275506.231 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T20:05:06.231Z,1649275506.231 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T20:05:14.670Z,1649275514.670 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0088.lzma
2022-04-06T20:05:15.673Z,1649275515.673 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0088.lzma.bak
2022-04-06T20:05:15.673Z,1649275515.673 [DataOverHttps](INFO): SBD MOMSN=16628746
2022-04-06T20:05:21.580Z,1649275521.580 [NAL9602](INFO): SBD MO Status=0, MOMSN=4568, MT Status=0, MTMSN=0
2022-04-06T20:05:21.580Z,1649275521.580 [NAL9602](INFO): No messages in MT queue
2022-04-06T20:05:33.938Z,1649275533.938 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0089.lzma
2022-04-06T20:05:34.942Z,1649275534.942 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0089.lzma.bak
2022-04-06T20:05:34.942Z,1649275534.942 [DataOverHttps](INFO): SBD MOMSN=16628749
2022-04-06T20:05:38.146Z,1649275538.146 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:05:38.146Z,1649275538.146 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:05:38.146Z,1649275538.146 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:05:52.307Z,1649275552.307 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T20:10:38.773Z,1649275838.773 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T20:10:38.773Z,1649275838.773 [Default:CheckIn:C.Wait] Stopped
2022-04-06T20:10:38.773Z,1649275838.773 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T20:10:38.773Z,1649275838.773 [Default:CheckIn:D] Running Loop=1
2022-04-06T20:10:39.182Z,1649275839.182 [Default:CheckIn:D] Stopped
2022-04-06T20:10:39.182Z,1649275839.182 [Default:CheckIn:E] Running Loop=1
2022-04-06T20:10:39.581Z,1649275839.581 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 198.991943 min 
2022-04-06T20:10:39.581Z,1649275839.581 [Default:CheckIn:E] Stopped
2022-04-06T20:10:39.581Z,1649275839.581 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T20:10:39.581Z,1649275839.581 [Default:CheckIn] Stopped
2022-04-06T20:10:39.581Z,1649275839.581 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T20:10:39.582Z,1649275839.582 [Default:CheckIn](INFO): Running loop #30
2022-04-06T20:10:39.582Z,1649275839.582 [Default:CheckIn] Running Loop=30
2022-04-06T20:10:39.582Z,1649275839.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T20:10:39.582Z,1649275839.582 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T20:10:41.600Z,1649275841.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201040.00,A,3648.14205,N,12147.22625,W,0.019,210.01,060422,,,A*78
2022-04-06T20:10:41.607Z,1649275841.607 [NAL9602](INFO): GPS fix at 20220406T201040: (36.802368, -121.787104)
2022-04-06T20:10:41.630Z,1649275841.630 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T20:10:41.630Z,1649275841.630 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T20:10:53.014Z,1649275853.014 [NAL9602](INFO): SBD MO Status=0, MOMSN=4569, MT Status=0, MTMSN=0
2022-04-06T20:10:53.014Z,1649275853.014 [NAL9602](INFO): No messages in MT queue
2022-04-06T20:10:54.291Z,1649275854.291 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0091.lzma
2022-04-06T20:10:55.293Z,1649275855.293 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0091.lzma.bak
2022-04-06T20:10:55.293Z,1649275855.293 [DataOverHttps](INFO): SBD MOMSN=16628760
2022-04-06T20:11:13.618Z,1649275873.618 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220406T164759/Express0092.lzma
2022-04-06T20:11:14.621Z,1649275874.621 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0092.lzma.bak
2022-04-06T20:11:14.621Z,1649275874.621 [DataOverHttps](INFO): SBD MOMSN=16628763
2022-04-06T20:11:18.070Z,1649275878.070 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:11:18.070Z,1649275878.070 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:11:18.070Z,1649275878.070 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:11:23.663Z,1649275883.663 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T20:16:18.686Z,1649276178.686 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T20:16:18.686Z,1649276178.686 [Default:CheckIn:C.Wait] Stopped
2022-04-06T20:16:18.686Z,1649276178.686 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T20:16:18.686Z,1649276178.686 [Default:CheckIn:D] Running Loop=1
2022-04-06T20:16:19.096Z,1649276179.096 [Default:CheckIn:D] Stopped
2022-04-06T20:16:19.096Z,1649276179.096 [Default:CheckIn:E] Running Loop=1
2022-04-06T20:16:19.486Z,1649276179.486 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 204.657194 min 
2022-04-06T20:16:19.486Z,1649276179.486 [Default:CheckIn:E] Stopped
2022-04-06T20:16:19.486Z,1649276179.486 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T20:16:19.486Z,1649276179.486 [Default:CheckIn] Stopped
2022-04-06T20:16:19.507Z,1649276179.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T20:16:19.507Z,1649276179.507 [Default:CheckIn](INFO): Running loop #31
2022-04-06T20:16:19.507Z,1649276179.507 [Default:CheckIn] Running Loop=31
2022-04-06T20:16:19.507Z,1649276179.507 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T20:16:19.507Z,1649276179.507 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T20:16:21.493Z,1649276181.493 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201620.00,A,3648.14069,N,12147.22746,W,0.019,0.00,060422,,,A*76
2022-04-06T20:16:21.495Z,1649276181.495 [NAL9602](INFO): GPS fix at 20220406T201620: (36.802345, -121.787124)
2022-04-06T20:16:21.507Z,1649276181.507 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T20:16:21.507Z,1649276181.507 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T20:16:30.530Z,1649276190.530 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0094.lzma
2022-04-06T20:16:31.533Z,1649276191.533 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0094.lzma.bak
2022-04-06T20:16:31.533Z,1649276191.533 [DataOverHttps](INFO): SBD MOMSN=16628776
2022-04-06T20:16:35.638Z,1649276195.638 [NAL9602](INFO): SBD MO Status=0, MOMSN=4570, MT Status=0, MTMSN=0
2022-04-06T20:16:35.639Z,1649276195.639 [NAL9602](INFO): No messages in MT queue
2022-04-06T20:16:50.202Z,1649276210.202 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20220406T164759/Express0095.lzma
2022-04-06T20:16:51.205Z,1649276211.205 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0095.lzma.bak
2022-04-06T20:16:51.205Z,1649276211.205 [DataOverHttps](INFO): SBD MOMSN=16628779
2022-04-06T20:16:54.249Z,1649276214.249 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:16:54.249Z,1649276214.249 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:16:54.249Z,1649276214.249 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:17:06.341Z,1649276226.341 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T20:18:04.116Z,1649276284.116 [BPC1](ERROR): Battery stick #34 (s/n: 0260) reported OVER_TEMP_ALARM. Status code: 0x1717.
2022-04-06T20:18:04.117Z,1649276284.117 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2022-04-06T20:18:04.119Z,1649276284.119 [BPC1](INFO): Received data from all battery sticks.
2022-04-06T20:21:54.833Z,1649276514.833 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T20:21:54.833Z,1649276514.833 [Default:CheckIn:C.Wait] Stopped
2022-04-06T20:21:54.833Z,1649276514.833 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T20:21:54.833Z,1649276514.833 [Default:CheckIn:D] Running Loop=1
2022-04-06T20:21:55.261Z,1649276515.261 [Default:CheckIn:D] Stopped
2022-04-06T20:21:55.261Z,1649276515.261 [Default:CheckIn:E] Running Loop=1
2022-04-06T20:21:55.689Z,1649276515.689 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 210.259928 min 
2022-04-06T20:21:55.690Z,1649276515.690 [Default:CheckIn:E] Stopped
2022-04-06T20:21:55.690Z,1649276515.690 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T20:21:55.690Z,1649276515.690 [Default:CheckIn] Stopped
2022-04-06T20:21:55.690Z,1649276515.690 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T20:21:55.690Z,1649276515.690 [Default:CheckIn](INFO): Running loop #32
2022-04-06T20:21:55.690Z,1649276515.690 [Default:CheckIn] Running Loop=32
2022-04-06T20:21:55.690Z,1649276515.690 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T20:21:55.703Z,1649276515.703 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T20:21:57.660Z,1649276517.660 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202156.00,A,3648.14110,N,12147.22861,W,0.019,0.00,060422,,,A*76
2022-04-06T20:21:57.662Z,1649276517.662 [NAL9602](INFO): GPS fix at 20220406T202156: (36.802352, -121.787143)
2022-04-06T20:21:57.713Z,1649276517.713 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T20:21:57.713Z,1649276517.713 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T20:22:00.212Z,1649276520.212 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20220406T164759/Courier0097.lzma
2022-04-06T20:22:01.217Z,1649276521.217 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0097.lzma.bak
2022-04-06T20:22:01.217Z,1649276521.217 [DataOverHttps](INFO): SBD MOMSN=16628795
2022-04-06T20:22:12.944Z,1649276532.944 [NAL9602](INFO): SBD MO Status=0, MOMSN=4571, MT Status=0, MTMSN=0
2022-04-06T20:22:12.944Z,1649276532.944 [NAL9602](INFO): No messages in MT queue
2022-04-06T20:22:19.326Z,1649276539.326 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0098.lzma
2022-04-06T20:22:20.329Z,1649276540.329 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0098.lzma.bak
2022-04-06T20:22:20.329Z,1649276540.329 [DataOverHttps](INFO): SBD MOMSN=16628798
2022-04-06T20:22:23.469Z,1649276543.469 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:22:23.469Z,1649276543.469 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:22:23.469Z,1649276543.469 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:22:43.633Z,1649276563.633 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T20:24:31.202Z,1649276671.202 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-06T20:24:42.129Z,1649276682.129 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005966
CHAN A1 (24V): 0.000655
CHAN A2 (12V): -0.005399
CHAN A3 (5V): -0.002557
CHAN B0 (3.3V): -0.000428
CHAN B1 (3.15aV): -0.001320
CHAN B2 (3.15bV): -0.001397
CHAN B3 (GND): -0.000687
OPEN: 0.004709
Full Scale: +/- 1 mA
2022-04-06T20:27:24.149Z,1649276844.149 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T20:27:24.149Z,1649276844.149 [Default:CheckIn:C.Wait] Stopped
2022-04-06T20:27:24.149Z,1649276844.149 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T20:27:24.149Z,1649276844.149 [Default:CheckIn:D] Running Loop=1
2022-04-06T20:27:24.579Z,1649276844.579 [Default:CheckIn:D] Stopped
2022-04-06T20:27:24.579Z,1649276844.579 [Default:CheckIn:E] Running Loop=1
2022-04-06T20:27:24.970Z,1649276844.970 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 215.748568 min 
2022-04-06T20:27:24.970Z,1649276844.970 [Default:CheckIn:E] Stopped
2022-04-06T20:27:24.970Z,1649276844.970 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T20:27:24.970Z,1649276844.970 [Default:CheckIn] Stopped
2022-04-06T20:27:24.971Z,1649276844.971 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T20:27:24.971Z,1649276844.971 [Default:CheckIn](INFO): Running loop #33
2022-04-06T20:27:24.971Z,1649276844.971 [Default:CheckIn] Running Loop=33
2022-04-06T20:27:24.972Z,1649276844.972 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T20:27:24.972Z,1649276844.972 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T20:27:26.972Z,1649276846.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202726.00,A,3648.14107,N,12147.23078,W,0.233,0.00,060422,,,A*7A
2022-04-06T20:27:26.975Z,1649276846.975 [NAL9602](INFO): GPS fix at 20220406T202726: (36.802351, -121.787180)
2022-04-06T20:27:27.006Z,1649276847.006 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T20:27:27.006Z,1649276847.006 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T20:27:35.962Z,1649276855.962 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0100.lzma
2022-04-06T20:27:36.965Z,1649276856.965 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0100.lzma.bak
2022-04-06T20:27:36.965Z,1649276856.965 [DataOverHttps](INFO): SBD MOMSN=16628809
2022-04-06T20:27:48.484Z,1649276868.484 [NAL9602](INFO): SBD MO Status=2, MOMSN=4572, MT Status=2, MTMSN=0
2022-04-06T20:27:48.484Z,1649276868.484 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T20:27:58.209Z,1649276878.209 [NAL9602](INFO): SBD MO Status=0, MOMSN=4572, MT Status=0, MTMSN=0
2022-04-06T20:27:58.209Z,1649276878.209 [NAL9602](INFO): No messages in MT queue
2022-04-06T20:27:58.319Z,1649276878.319 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20220406T164759/Express0101.lzma
2022-04-06T20:27:59.321Z,1649276879.321 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0101.lzma.bak
2022-04-06T20:27:59.321Z,1649276879.321 [DataOverHttps](INFO): SBD MOMSN=16628812
2022-04-06T20:28:04.310Z,1649276884.310 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:28:04.310Z,1649276884.310 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:28:04.310Z,1649276884.310 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:28:28.915Z,1649276908.915 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T20:33:04.854Z,1649277184.854 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T20:33:04.854Z,1649277184.854 [Default:CheckIn:C.Wait] Stopped
2022-04-06T20:33:04.854Z,1649277184.854 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T20:33:04.854Z,1649277184.854 [Default:CheckIn:D] Running Loop=1
2022-04-06T20:33:05.263Z,1649277185.263 [Default:CheckIn:D] Stopped
2022-04-06T20:33:05.263Z,1649277185.263 [Default:CheckIn:E] Running Loop=1
2022-04-06T20:33:05.673Z,1649277185.673 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 221.426628 min 
2022-04-06T20:33:05.674Z,1649277185.674 [Default:CheckIn:E] Stopped
2022-04-06T20:33:05.674Z,1649277185.674 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T20:33:05.674Z,1649277185.674 [Default:CheckIn] Stopped
2022-04-06T20:33:05.674Z,1649277185.674 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T20:33:05.674Z,1649277185.674 [Default:CheckIn](INFO): Running loop #34
2022-04-06T20:33:05.674Z,1649277185.674 [Default:CheckIn] Running Loop=34
2022-04-06T20:33:05.674Z,1649277185.674 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T20:33:05.679Z,1649277185.679 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T20:33:07.672Z,1649277187.672 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203307.00,A,3648.14239,N,12147.22989,W,0.019,0.00,060422,,,A*7E
2022-04-06T20:33:07.674Z,1649277187.674 [NAL9602](INFO): GPS fix at 20220406T203307: (36.802373, -121.787165)
2022-04-06T20:33:07.685Z,1649277187.685 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T20:33:07.685Z,1649277187.685 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T20:33:16.390Z,1649277196.390 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0103.lzma
2022-04-06T20:33:17.393Z,1649277197.393 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0103.lzma.bak
2022-04-06T20:33:17.393Z,1649277197.393 [DataOverHttps](INFO): SBD MOMSN=16628825
2022-04-06T20:33:35.553Z,1649277215.553 [NAL9602](INFO): SBD MO Status=0, MOMSN=4573, MT Status=0, MTMSN=0
2022-04-06T20:33:35.553Z,1649277215.553 [NAL9602](INFO): No messages in MT queue
2022-04-06T20:33:35.798Z,1649277215.798 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0104.lzma
2022-04-06T20:33:36.801Z,1649277216.801 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0104.lzma.bak
2022-04-06T20:33:36.801Z,1649277216.801 [DataOverHttps](INFO): SBD MOMSN=16628828
2022-04-06T20:33:40.024Z,1649277220.024 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:33:40.025Z,1649277220.025 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:33:40.025Z,1649277220.025 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:34:06.297Z,1649277246.297 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T20:38:40.789Z,1649277520.789 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T20:38:40.789Z,1649277520.789 [Default:CheckIn:C.Wait] Stopped
2022-04-06T20:38:40.789Z,1649277520.789 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T20:38:40.789Z,1649277520.789 [Default:CheckIn:D] Running Loop=1
2022-04-06T20:38:41.173Z,1649277521.173 [Default:CheckIn:D] Stopped
2022-04-06T20:38:41.173Z,1649277521.173 [Default:CheckIn:E] Running Loop=1
2022-04-06T20:38:41.618Z,1649277521.618 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 227.025130 min 
2022-04-06T20:38:41.618Z,1649277521.618 [Default:CheckIn:E] Stopped
2022-04-06T20:38:41.618Z,1649277521.618 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T20:38:41.618Z,1649277521.618 [Default:CheckIn] Stopped
2022-04-06T20:38:41.618Z,1649277521.618 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T20:38:41.618Z,1649277521.618 [Default:CheckIn](INFO): Running loop #35
2022-04-06T20:38:41.618Z,1649277521.618 [Default:CheckIn] Running Loop=35
2022-04-06T20:38:41.619Z,1649277521.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T20:38:41.619Z,1649277521.619 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T20:38:43.579Z,1649277523.579 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203842.00,A,3648.14219,N,12147.22754,W,0.039,0.00,060422,,,A*7A
2022-04-06T20:38:43.581Z,1649277523.581 [NAL9602](INFO): GPS fix at 20220406T203842: (36.802370, -121.787126)
2022-04-06T20:38:43.592Z,1649277523.592 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T20:38:43.592Z,1649277523.592 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T20:38:52.050Z,1649277532.050 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0106.lzma
2022-04-06T20:38:53.053Z,1649277533.053 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0106.lzma.bak
2022-04-06T20:38:53.053Z,1649277533.053 [DataOverHttps](INFO): SBD MOMSN=16628841
2022-04-06T20:38:56.105Z,1649277536.105 [NAL9602](INFO): SBD MO Status=0, MOMSN=4574, MT Status=0, MTMSN=0
2022-04-06T20:38:56.105Z,1649277536.105 [NAL9602](INFO): No messages in MT queue
2022-04-06T20:39:11.438Z,1649277551.438 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20220406T164759/Express0107.lzma
2022-04-06T20:39:12.441Z,1649277552.441 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0107.lzma.bak
2022-04-06T20:39:12.441Z,1649277552.441 [DataOverHttps](INFO): SBD MOMSN=16628844
2022-04-06T20:39:15.526Z,1649277555.526 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:39:15.526Z,1649277555.526 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:39:15.527Z,1649277555.527 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:39:26.847Z,1649277566.847 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T20:44:16.190Z,1649277856.190 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T20:44:16.190Z,1649277856.190 [Default:CheckIn:C.Wait] Stopped
2022-04-06T20:44:16.190Z,1649277856.190 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T20:44:16.190Z,1649277856.190 [Default:CheckIn:D] Running Loop=1
2022-04-06T20:44:16.599Z,1649277856.599 [Default:CheckIn:D] Stopped
2022-04-06T20:44:16.599Z,1649277856.599 [Default:CheckIn:E] Running Loop=1
2022-04-06T20:44:17.030Z,1649277857.030 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 232.615560 min 
2022-04-06T20:44:17.030Z,1649277857.030 [Default:CheckIn:E] Stopped
2022-04-06T20:44:17.035Z,1649277857.035 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T20:44:17.035Z,1649277857.035 [Default:CheckIn] Stopped
2022-04-06T20:44:17.035Z,1649277857.035 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T20:44:17.035Z,1649277857.035 [Default:CheckIn](INFO): Running loop #36
2022-04-06T20:44:17.035Z,1649277857.035 [Default:CheckIn] Running Loop=36
2022-04-06T20:44:17.035Z,1649277857.035 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T20:44:17.035Z,1649277857.035 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T20:44:19.013Z,1649277859.013 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204418.00,A,3648.14039,N,12147.22356,W,0.136,219.59,060422,,,A*70
2022-04-06T20:44:19.015Z,1649277859.015 [NAL9602](INFO): GPS fix at 20220406T204418: (36.802340, -121.787059)
2022-04-06T20:44:19.071Z,1649277859.071 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T20:44:19.071Z,1649277859.071 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T20:44:28.110Z,1649277868.110 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0109.lzma
2022-04-06T20:44:29.113Z,1649277869.113 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0109.lzma.bak
2022-04-06T20:44:29.113Z,1649277869.113 [DataOverHttps](INFO): SBD MOMSN=16628911
2022-04-06T20:44:37.113Z,1649277877.113 [NAL9602](INFO): SBD MO Status=0, MOMSN=4575, MT Status=0, MTMSN=0
2022-04-06T20:44:37.113Z,1649277877.113 [NAL9602](INFO): No messages in MT queue
2022-04-06T20:44:47.187Z,1649277887.187 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220406T164759/Express0110.lzma
2022-04-06T20:44:48.189Z,1649277888.189 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0110.lzma.bak
2022-04-06T20:44:48.189Z,1649277888.189 [DataOverHttps](INFO): SBD MOMSN=16628914
2022-04-06T20:44:51.280Z,1649277891.280 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:44:51.280Z,1649277891.280 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:44:51.280Z,1649277891.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:45:07.814Z,1649277907.814 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T20:49:51.866Z,1649278191.866 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T20:49:51.867Z,1649278191.867 [Default:CheckIn:C.Wait] Stopped
2022-04-06T20:49:51.867Z,1649278191.867 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T20:49:51.867Z,1649278191.867 [Default:CheckIn:D] Running Loop=1
2022-04-06T20:49:52.274Z,1649278192.274 [Default:CheckIn:D] Stopped
2022-04-06T20:49:52.274Z,1649278192.274 [Default:CheckIn:E] Running Loop=1
2022-04-06T20:49:52.675Z,1649278192.675 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 238.210156 min 
2022-04-06T20:49:52.675Z,1649278192.675 [Default:CheckIn:E] Stopped
2022-04-06T20:49:52.676Z,1649278192.676 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T20:49:52.676Z,1649278192.676 [Default:CheckIn] Stopped
2022-04-06T20:49:52.676Z,1649278192.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T20:49:52.676Z,1649278192.676 [Default:CheckIn](INFO): Running loop #37
2022-04-06T20:49:52.676Z,1649278192.676 [Default:CheckIn] Running Loop=37
2022-04-06T20:49:52.676Z,1649278192.676 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T20:49:52.676Z,1649278192.676 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T20:49:54.684Z,1649278194.684 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204954.00,A,3648.16683,N,12147.21571,W,0.078,330.97,060422,,,A*73
2022-04-06T20:49:54.686Z,1649278194.686 [NAL9602](INFO): GPS fix at 20220406T204954: (36.802780, -121.786929)
2022-04-06T20:49:54.719Z,1649278194.719 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T20:49:54.719Z,1649278194.719 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T20:50:03.298Z,1649278203.298 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0112.lzma
2022-04-06T20:50:04.301Z,1649278204.301 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0112.lzma.bak
2022-04-06T20:50:04.301Z,1649278204.301 [DataOverHttps](INFO): SBD MOMSN=16628926
2022-04-06T20:50:18.527Z,1649278218.527 [NAL9602](INFO): SBD MO Status=2, MOMSN=4576, MT Status=2, MTMSN=0
2022-04-06T20:50:18.527Z,1649278218.527 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T20:50:23.002Z,1649278223.002 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220406T164759/Express0113.lzma
2022-04-06T20:50:24.005Z,1649278224.005 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0113.lzma.bak
2022-04-06T20:50:24.005Z,1649278224.005 [DataOverHttps](INFO): SBD MOMSN=16628929
2022-04-06T20:50:27.058Z,1649278227.058 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:50:27.058Z,1649278227.058 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:50:27.058Z,1649278227.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:50:45.605Z,1649278245.605 [NAL9602](INFO): SBD MO Status=0, MOMSN=4576, MT Status=0, MTMSN=0
2022-04-06T20:50:45.605Z,1649278245.605 [NAL9602](INFO): No messages in MT queue
2022-04-06T20:51:16.294Z,1649278276.294 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T20:54:42.388Z,1649278482.388 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-06T20:54:53.285Z,1649278493.285 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006436
CHAN A1 (24V): 0.001246
CHAN A2 (12V): -0.005575
CHAN A3 (5V): -0.003008
CHAN B0 (3.3V): -0.001248
CHAN B1 (3.15aV): -0.001234
CHAN B2 (3.15bV): -0.001699
CHAN B3 (GND): -0.000516
OPEN: 0.004682
Full Scale: +/- 1 mA
2022-04-06T20:55:27.626Z,1649278527.626 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T20:55:27.626Z,1649278527.626 [Default:CheckIn:C.Wait] Stopped
2022-04-06T20:55:27.626Z,1649278527.626 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T20:55:27.626Z,1649278527.626 [Default:CheckIn:D] Running Loop=1
2022-04-06T20:55:28.037Z,1649278528.037 [Default:CheckIn:D] Stopped
2022-04-06T20:55:28.037Z,1649278528.037 [Default:CheckIn:E] Running Loop=1
2022-04-06T20:55:28.425Z,1649278528.425 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 243.806201 min 
2022-04-06T20:55:28.425Z,1649278528.425 [Default:CheckIn:E] Stopped
2022-04-06T20:55:28.425Z,1649278528.425 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T20:55:28.425Z,1649278528.425 [Default:CheckIn] Stopped
2022-04-06T20:55:28.426Z,1649278528.426 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T20:55:28.426Z,1649278528.426 [Default:CheckIn](INFO): Running loop #38
2022-04-06T20:55:28.426Z,1649278528.426 [Default:CheckIn] Running Loop=38
2022-04-06T20:55:28.426Z,1649278528.426 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T20:55:28.426Z,1649278528.426 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T20:55:30.436Z,1649278530.436 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205529.00,A,3648.16740,N,12147.21495,W,0.058,234.84,060422,,,A*74
2022-04-06T20:55:30.438Z,1649278530.438 [NAL9602](INFO): GPS fix at 20220406T205529: (36.802790, -121.786916)
2022-04-06T20:55:30.450Z,1649278530.450 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T20:55:30.450Z,1649278530.450 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T20:55:43.990Z,1649278543.990 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220406T164759/Courier0115.lzma
2022-04-06T20:55:44.993Z,1649278544.993 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0115.lzma.bak
2022-04-06T20:55:44.993Z,1649278544.993 [DataOverHttps](INFO): SBD MOMSN=16628940
2022-04-06T20:55:46.698Z,1649278546.698 [NAL9602](INFO): SBD MO Status=0, MOMSN=4577, MT Status=0, MTMSN=0
2022-04-06T20:55:46.707Z,1649278546.707 [NAL9602](INFO): No messages in MT queue
2022-04-06T20:56:05.271Z,1649278565.271 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20220406T164759/Express0116.lzma
2022-04-06T20:56:06.273Z,1649278566.273 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0116.lzma.bak
2022-04-06T20:56:06.273Z,1649278566.273 [DataOverHttps](INFO): SBD MOMSN=16628943
2022-04-06T20:56:09.314Z,1649278569.314 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T20:56:09.314Z,1649278569.314 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T20:56:09.319Z,1649278569.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T20:56:17.383Z,1649278577.383 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T21:01:09.890Z,1649278869.890 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T21:01:09.890Z,1649278869.890 [Default:CheckIn:C.Wait] Stopped
2022-04-06T21:01:09.890Z,1649278869.890 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T21:01:09.890Z,1649278869.890 [Default:CheckIn:D] Running Loop=1
2022-04-06T21:01:10.300Z,1649278870.300 [Default:CheckIn:D] Stopped
2022-04-06T21:01:10.301Z,1649278870.301 [Default:CheckIn:E] Running Loop=1
2022-04-06T21:01:10.697Z,1649278870.697 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 249.510596 min 
2022-04-06T21:01:10.697Z,1649278870.697 [Default:CheckIn:E] Stopped
2022-04-06T21:01:10.698Z,1649278870.698 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T21:01:10.698Z,1649278870.698 [Default:CheckIn] Stopped
2022-04-06T21:01:10.698Z,1649278870.698 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T21:01:10.698Z,1649278870.698 [Default:CheckIn](INFO): Running loop #39
2022-04-06T21:01:10.698Z,1649278870.698 [Default:CheckIn] Running Loop=39
2022-04-06T21:01:10.698Z,1649278870.698 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T21:01:10.698Z,1649278870.698 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T21:01:12.708Z,1649278872.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210112.00,A,3648.30067,N,12147.12056,W,5.268,10.39,060422,,,A*47
2022-04-06T21:01:12.710Z,1649278872.710 [NAL9602](INFO): GPS fix at 20220406T210112: (36.805011, -121.785343)
2022-04-06T21:01:12.721Z,1649278872.721 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T21:01:12.721Z,1649278872.721 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T21:01:21.342Z,1649278881.342 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20220406T164759/Courier0118.lzma
2022-04-06T21:01:22.345Z,1649278882.345 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0118.lzma.bak
2022-04-06T21:01:22.345Z,1649278882.345 [DataOverHttps](INFO): SBD MOMSN=16628956
2022-04-06T21:01:26.849Z,1649278886.849 [NAL9602](INFO): SBD MO Status=0, MOMSN=4578, MT Status=0, MTMSN=0
2022-04-06T21:01:26.849Z,1649278886.849 [NAL9602](INFO): No messages in MT queue
2022-04-06T21:01:43.563Z,1649278903.563 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220406T164759/Express0119.lzma
2022-04-06T21:01:44.565Z,1649278904.565 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0119.lzma.bak
2022-04-06T21:01:44.565Z,1649278904.565 [DataOverHttps](INFO): SBD MOMSN=16628959
2022-04-06T21:01:49.561Z,1649278909.561 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T21:01:49.561Z,1649278909.561 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T21:01:49.561Z,1649278909.561 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T21:01:57.602Z,1649278917.602 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T21:06:50.204Z,1649279210.204 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T21:06:50.205Z,1649279210.205 [Default:CheckIn:C.Wait] Stopped
2022-04-06T21:06:50.205Z,1649279210.205 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T21:06:50.205Z,1649279210.205 [Default:CheckIn:D] Running Loop=1
2022-04-06T21:06:50.607Z,1649279210.607 [Default:CheckIn:D] Stopped
2022-04-06T21:06:50.607Z,1649279210.607 [Default:CheckIn:E] Running Loop=1
2022-04-06T21:06:50.993Z,1649279210.993 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 255.182357 min 
2022-04-06T21:06:50.993Z,1649279210.993 [Default:CheckIn:E] Stopped
2022-04-06T21:06:50.993Z,1649279210.993 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T21:06:50.993Z,1649279210.993 [Default:CheckIn] Stopped
2022-04-06T21:06:50.993Z,1649279210.993 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T21:06:50.993Z,1649279210.993 [Default:CheckIn](INFO): Running loop #40
2022-04-06T21:06:50.993Z,1649279210.993 [Default:CheckIn] Running Loop=40
2022-04-06T21:06:50.994Z,1649279210.994 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T21:06:50.994Z,1649279210.994 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T21:06:53.009Z,1649279213.009 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210652.00,A,3648.33962,N,12147.56017,W,18.603,243.49,060422,,,D*4D
2022-04-06T21:06:53.011Z,1649279213.011 [NAL9602](INFO): GPS fix at 20220406T210652: (36.805660, -121.792670)
2022-04-06T21:06:53.023Z,1649279213.023 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T21:06:53.023Z,1649279213.023 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T21:07:02.002Z,1649279222.002 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220406T164759/Courier0121.lzma
2022-04-06T21:07:03.006Z,1649279223.006 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Courier0121.lzma.bak
2022-04-06T21:07:03.006Z,1649279223.006 [DataOverHttps](INFO): SBD MOMSN=16628970
2022-04-06T21:07:11.593Z,1649279231.593 [NAL9602](INFO): SBD MO Status=0, MOMSN=4579, MT Status=0, MTMSN=0
2022-04-06T21:07:11.593Z,1649279231.593 [NAL9602](INFO): No messages in MT queue
2022-04-06T21:07:20.910Z,1649279240.910 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20220406T164759/Express0122.lzma
2022-04-06T21:07:21.913Z,1649279241.913 [DataOverHttps](INFO): Moved sent file to Logs/20220406T164759/Express0122.lzma.bak
2022-04-06T21:07:21.913Z,1649279241.913 [DataOverHttps](INFO): SBD MOMSN=16628973
2022-04-06T21:07:24.934Z,1649279244.934 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T21:07:24.939Z,1649279244.939 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T21:07:24.939Z,1649279244.939 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T21:07:42.379Z,1649279262.379 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-06T21:12:25.629Z,1649279545.629 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-06T21:12:25.630Z,1649279545.630 [Default:CheckIn:C.Wait] Stopped
2022-04-06T21:12:25.630Z,1649279545.630 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-06T21:12:25.630Z,1649279545.630 [Default:CheckIn:D] Running Loop=1
2022-04-06T21:12:26.050Z,1649279546.050 [Default:CheckIn:D] Stopped
2022-04-06T21:12:26.051Z,1649279546.051 [Default:CheckIn:E] Running Loop=1
2022-04-06T21:12:26.454Z,1649279546.454 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 260.773096 min 
2022-04-06T21:12:26.454Z,1649279546.454 [Default:CheckIn:E] Stopped
2022-04-06T21:12:26.454Z,1649279546.454 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-06T21:12:26.454Z,1649279546.454 [Default:CheckIn] Stopped
2022-04-06T21:12:26.454Z,1649279546.454 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-06T21:12:26.459Z,1649279546.459 [Default:CheckIn](INFO): Running loop #41
2022-04-06T21:12:26.459Z,1649279546.459 [Default:CheckIn] Running Loop=41
2022-04-06T21:12:26.459Z,1649279546.459 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-06T21:12:26.459Z,1649279546.459 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-06T21:12:28.456Z,1649279548.456 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211227.00,A,3647.98115,N,12149.14616,W,0.564,190.61,060422,,,D*7F
2022-04-06T21:12:28.459Z,1649279548.459 [NAL9602](INFO): GPS fix at 20220406T211227: (36.799686, -121.819103)
2022-04-06T21:12:28.470Z,1649279548.470 [Default:CheckIn:Read_GPS] Stopped
2022-04-06T21:12:28.486Z,1649279548.486 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-06T21:12:54.708Z,1649279574.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=4580, MT Status=2, MTMSN=0
2022-04-06T21:12:54.708Z,1649279574.708 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T21:13:00.923Z,1649279580.923 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T21:13:27.027Z,1649279607.027 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239731
2022-04-06T21:13:58.151Z,1649279638.151 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T21:14:33.291Z,1649279673.291 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T21:15:08.431Z,1649279708.431 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T21:15:43.571Z,1649279743.571 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T21:16:09.036Z,1649279769.036 [NAL9602](INFO): SBD MO Status=0, MOMSN=4580, MT Status=0, MTMSN=0
2022-04-06T21:16:09.036Z,1649279769.036 [NAL9602](INFO): No messages in MT queue
2022-04-06T21:16:18.711Z,1649279778.711 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T21:16:28.211Z,1649279788.211 [NAL9602](INFO): SBD MO Status=1, MOMSN=4581, MT Status=0, MTMSN=0
2022-04-06T21:16:28.269Z,1649279788.269 [NAL9602](INFO): Sent 72 bytes from file Logs/20220406T164759/Courier0124.lzma
2022-04-06T21:16:28.269Z,1649279788.269 [NAL9602](INFO): Packets left to send: 0
2022-04-06T21:16:47.329Z,1649279807.329 [NAL9602](INFO): SBD MO Status=2, MOMSN=4582, MT Status=2, MTMSN=0
2022-04-06T21:16:47.329Z,1649279807.329 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-06T21:16:53.859Z,1649279813.859 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-04-06T21:17:17.051Z,1649279837.051 [NAL9602](INFO): SBD MO Status=1, MOMSN=4582, MT Status=0, MTMSN=0
2022-04-06T21:17:17.099Z,1649279837.099 [NAL9602](INFO): Sent 146 bytes from file Logs/20220406T164759/Express0125.lzma
2022-04-06T21:17:17.099Z,1649279837.099 [NAL9602](INFO): Packets left to send: 0
2022-04-06T21:17:26.012Z,1649279846.012 [NAL9602](INFO): SBD MO Status=0, MOMSN=4583, MT Status=0, MTMSN=0
2022-04-06T21:17:26.102Z,1649279846.102 [Default:CheckIn:Read_Iridium] Stopped
2022-04-06T21:17:26.102Z,1649279846.102 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-06T21:17:26.102Z,1649279846.102 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-06T21:17:27.740Z,1649279847.740 [DataOverHttps](IMPORTANT): SBD MTMSN=20220406T211726
2022-04-06T21:17:27.744Z,1649279847.744 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003596