2020-10-07T15:31:22.089Z,1602084682.089 [Supervisor](DEBUG): Initializing supervisor.
2020-10-07T15:31:22.093Z,1602084682.093 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-10-07T15:31:22.094Z,1602084682.094 [SyncHandler](INFO): Protected caller Thread ID is 3160
2020-10-07T15:31:22.094Z,1602084682.094 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-10-07T15:31:22.095Z,1602084682.095 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-10-07T15:31:22.096Z,1602084682.096 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3161
2020-10-07T15:31:22.099Z,1602084682.099 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-10-07T15:31:22.115Z,1602084682.115 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-10-07T15:31:22.116Z,1602084682.116 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-10-07T15:31:22.116Z,1602084682.116 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3162
2020-10-07T15:31:22.118Z,1602084682.118 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-10-07T15:31:22.119Z,1602084682.119 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-10-07T15:31:22.119Z,1602084682.119 [logger ThreadHandler](INFO): Protected caller Thread ID is 3163
2020-10-07T15:31:22.122Z,1602084682.122 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-10-07T15:31:22.123Z,1602084682.123 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-10-07T15:31:22.124Z,1602084682.124 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-10-07T15:31:22.219Z,1602084682.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-10-07T15:31:22.219Z,1602084682.219 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-10-07T15:31:22.419Z,1602084682.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-10-07T15:31:22.419Z,1602084682.419 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-10-07T15:31:22.624Z,1602084682.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-10-07T15:31:22.625Z,1602084682.625 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-10-07T15:31:22.723Z,1602084682.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-10-07T15:31:22.724Z,1602084682.724 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-10-07T15:31:23.296Z,1602084683.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-10-07T15:31:23.297Z,1602084683.297 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-10-07T15:31:23.695Z,1602084683.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-10-07T15:31:23.696Z,1602084683.696 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-10-07T15:31:24.163Z,1602084684.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-10-07T15:31:24.163Z,1602084684.163 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-10-07T15:31:24.538Z,1602084684.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-10-07T15:31:24.539Z,1602084684.539 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-10-07T15:31:24.862Z,1602084684.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-10-07T15:31:24.863Z,1602084684.863 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-10-07T15:31:25.204Z,1602084685.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-10-07T15:31:25.205Z,1602084685.205 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-10-07T15:31:25.351Z,1602084685.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-10-07T15:31:25.352Z,1602084685.352 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-10-07T15:31:25.455Z,1602084685.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-10-07T15:31:25.456Z,1602084685.456 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-10-07T15:31:25.535Z,1602084685.535 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-10-07T15:31:25.613Z,1602084685.613 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-10-07T15:31:25.798Z,1602084685.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-10-07T15:31:25.799Z,1602084685.799 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-10-07T15:31:26.002Z,1602084686.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-10-07T15:31:26.004Z,1602084686.004 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2020-10-07T15:31:26.005Z,1602084686.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2020-10-07T15:31:26.087Z,1602084686.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2020-10-07T15:31:26.312Z,1602084686.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-10-07T15:31:26.312Z,1602084686.312 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2020-10-07T15:31:26.406Z,1602084686.406 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2020-10-07T15:31:26.549Z,1602084686.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2020-10-07T15:31:26.731Z,1602084686.731 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2020-10-07T15:31:26.827Z,1602084686.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2020-10-07T15:31:27.175Z,1602084687.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2020-10-07T15:31:27.301Z,1602084687.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2020-10-07T15:31:27.622Z,1602084687.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2020-10-07T15:31:27.728Z,1602084687.728 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2020-10-07T15:31:27.825Z,1602084687.825 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2020-10-07T15:31:27.826Z,1602084687.826 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-10-07T15:31:27.834Z,1602084687.834 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-10-07T15:31:27.934Z,1602084687.934 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-10-07T15:31:27.935Z,1602084687.935 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-10-07T15:31:28.068Z,1602084688.068 [BuoyancyServo] Loaded
2020-10-07T15:31:28.069Z,1602084688.069 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-10-07T15:31:28.105Z,1602084688.105 [ElevatorServo] Loaded
2020-10-07T15:31:28.105Z,1602084688.105 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-10-07T15:31:28.141Z,1602084688.141 [MassServo] Loaded
2020-10-07T15:31:28.141Z,1602084688.141 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-10-07T15:31:28.176Z,1602084688.176 [RudderServo] Loaded
2020-10-07T15:31:28.176Z,1602084688.176 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-10-07T15:31:28.211Z,1602084688.211 [ThrusterServo] Loaded
2020-10-07T15:31:28.211Z,1602084688.211 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-10-07T15:31:28.212Z,1602084688.212 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-10-07T15:31:28.212Z,1602084688.212 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-10-07T15:31:28.352Z,1602084688.352 [SBIT](DEBUG): Construct Startup Built In Test.
2020-10-07T15:31:28.380Z,1602084688.380 [SBIT] Loaded
2020-10-07T15:31:28.380Z,1602084688.380 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-10-07T15:31:28.385Z,1602084688.385 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-10-07T15:31:28.415Z,1602084688.415 [IBIT] Loaded
2020-10-07T15:31:28.415Z,1602084688.415 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-10-07T15:31:28.423Z,1602084688.423 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-10-07T15:31:28.808Z,1602084688.808 [CBIT] Loaded
2020-10-07T15:31:28.808Z,1602084688.808 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-10-07T15:31:28.808Z,1602084688.808 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-10-07T15:31:28.809Z,1602084688.809 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-10-07T15:31:28.954Z,1602084688.954 [CANONSampler] Loaded
2020-10-07T15:31:28.954Z,1602084688.954 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2020-10-07T15:31:29.055Z,1602084689.055 [CTD_Seabird] Loaded
2020-10-07T15:31:29.055Z,1602084689.055 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-10-07T15:31:29.056Z,1602084689.056 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4061B4E0
2020-10-07T15:31:29.057Z,1602084689.057 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3246
2020-10-07T15:31:29.111Z,1602084689.111 [ESPComponent] Loaded
2020-10-07T15:31:29.111Z,1602084689.111 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-10-07T15:31:29.146Z,1602084689.146 [PAR_Licor] Loaded
2020-10-07T15:31:29.146Z,1602084689.146 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-10-07T15:31:29.213Z,1602084689.213 [WetLabsBB2FL] Loaded
2020-10-07T15:31:29.214Z,1602084689.214 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-10-07T15:31:29.215Z,1602084689.215 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4064B4E0
2020-10-07T15:31:29.215Z,1602084689.215 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3247
2020-10-07T15:31:29.216Z,1602084689.216 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-10-07T15:31:29.216Z,1602084689.216 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-10-07T15:31:29.262Z,1602084689.262 [DepthRateCalculator] Loaded
2020-10-07T15:31:29.262Z,1602084689.262 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-10-07T15:31:29.272Z,1602084689.272 [PitchRateCalculator] Loaded
2020-10-07T15:31:29.272Z,1602084689.272 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-10-07T15:31:29.292Z,1602084689.292 [SpeedCalculator] Loaded
2020-10-07T15:31:29.292Z,1602084689.292 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-10-07T15:31:29.317Z,1602084689.317 [TempGradientCalculator] Loaded
2020-10-07T15:31:29.318Z,1602084689.318 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-10-07T15:31:29.327Z,1602084689.327 [YawRateCalculator] Loaded
2020-10-07T15:31:29.327Z,1602084689.327 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-10-07T15:31:29.364Z,1602084689.364 [ElevatorOffsetCalculator] Loaded
2020-10-07T15:31:29.364Z,1602084689.364 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-10-07T15:31:29.365Z,1602084689.365 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-10-07T15:31:29.365Z,1602084689.365 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-10-07T15:31:29.445Z,1602084689.445 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-10-07T15:31:29.445Z,1602084689.445 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-10-07T15:31:29.472Z,1602084689.472 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-10-07T15:31:29.473Z,1602084689.473 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-10-07T15:31:29.592Z,1602084689.592 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-10-07T15:31:29.592Z,1602084689.592 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-10-07T15:31:29.618Z,1602084689.618 [NavChart] Loaded
2020-10-07T15:31:29.618Z,1602084689.618 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-10-07T15:31:29.626Z,1602084689.626 [UniversalFixResidualReporter] Loaded
2020-10-07T15:31:29.626Z,1602084689.626 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-10-07T15:31:29.627Z,1602084689.627 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-10-07T15:31:29.627Z,1602084689.627 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-10-07T15:31:29.998Z,1602084689.998 [AHRS_M2] Loaded
2020-10-07T15:31:29.998Z,1602084689.998 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-10-07T15:31:31.273Z,1602084691.273 [BPC1] Loaded
2020-10-07T15:31:31.273Z,1602084691.273 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-10-07T15:31:31.359Z,1602084691.359 [DataOverHttps] Loaded
2020-10-07T15:31:31.359Z,1602084691.359 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-10-07T15:31:31.360Z,1602084691.360 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409854E0
2020-10-07T15:31:31.360Z,1602084691.360 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3248
2020-10-07T15:31:31.395Z,1602084691.395 [Depth_Keller] Loaded
2020-10-07T15:31:31.395Z,1602084691.395 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-10-07T15:31:31.404Z,1602084691.404 [DropWeight] Loaded
2020-10-07T15:31:31.405Z,1602084691.405 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-10-07T15:31:31.517Z,1602084691.517 [NAL9602] Loaded
2020-10-07T15:31:31.517Z,1602084691.517 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-10-07T15:31:31.561Z,1602084691.561 [Onboard] Loaded
2020-10-07T15:31:31.561Z,1602084691.561 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-10-07T15:31:31.562Z,1602084691.562 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409B54E0
2020-10-07T15:31:31.563Z,1602084691.563 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3249
2020-10-07T15:31:31.587Z,1602084691.587 [Radio_Surface] Loaded
2020-10-07T15:31:31.587Z,1602084691.587 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-10-07T15:31:31.588Z,1602084691.588 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0
2020-10-07T15:31:31.589Z,1602084691.589 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3250
2020-10-07T15:31:31.590Z,1602084691.590 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-10-07T15:31:31.590Z,1602084691.590 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-10-07T15:31:31.645Z,1602084691.645 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-10-07T15:31:31.645Z,1602084691.645 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-10-07T15:31:31.698Z,1602084691.698 [VerticalControl](DEBUG): Construct VerticalControl.
2020-10-07T15:31:31.782Z,1602084691.782 [VerticalControl] Loaded
2020-10-07T15:31:31.782Z,1602084691.782 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-10-07T15:31:31.787Z,1602084691.787 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-10-07T15:31:31.857Z,1602084691.857 [HorizontalControl] Loaded
2020-10-07T15:31:31.857Z,1602084691.857 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-10-07T15:31:31.862Z,1602084691.862 [SpeedControl](DEBUG): Construct SpeedControl.
2020-10-07T15:31:31.863Z,1602084691.863 [SpeedControl] Loaded
2020-10-07T15:31:31.864Z,1602084691.864 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-10-07T15:31:31.868Z,1602084691.868 [LoopControl](DEBUG): Construct LoopControl.
2020-10-07T15:31:31.869Z,1602084691.869 [LoopControl] Loaded
2020-10-07T15:31:31.869Z,1602084691.869 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-10-07T15:31:31.870Z,1602084691.870 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-10-07T15:31:31.870Z,1602084691.870 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-10-07T15:31:31.941Z,1602084691.941 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-10-07T15:31:31.941Z,1602084691.941 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-10-07T15:31:32.337Z,1602084692.337 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-10-07T15:31:32.342Z,1602084692.342 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-10-07T15:31:32.344Z,1602084692.344 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-10-07T15:31:32.353Z,1602084692.353 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-10-07T15:31:32.354Z,1602084692.354 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AE74E0
2020-10-07T15:31:32.354Z,1602084692.354 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3251
2020-10-07T15:31:32.359Z,1602084692.359 [Supervisor](INFO): Main Thread ID is 3159
2020-10-07T15:31:32.359Z,1602084692.359 [Supervisor](DEBUG): Running supervisor.
2020-10-07T15:31:32.359Z,1602084692.359 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3252
2020-10-07T15:31:32.362Z,1602084692.362 [controlThread ThreadHandler](INFO): Handler Thread ID is 3253
2020-10-07T15:31:32.362Z,1602084692.362 [controlThread](DEBUG): Initializing ControlThread
2020-10-07T15:31:32.364Z,1602084692.364 [SBIT](INFO): Initialize SBIT Component.
2020-10-07T15:31:32.364Z,1602084692.364 [SBIT](IMPORTANT): git: 2020-09-23a
2020-10-07T15:31:32.365Z,1602084692.365 [SBIT](INFO): git hash: e308fd45a6ed36fdf930f760c966ab830e167584
2020-10-07T15:31:32.365Z,1602084692.365 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-10-07T15:31:32.366Z,1602084692.366 [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 Nov 13 12:38:10 PST 2019
2020-10-07T15:31:32.368Z,1602084692.368 [SBIT](INFO): Beginning SBIT in 61.000000 seconds.
2020-10-07T15:31:32.368Z,1602084692.368 [IBIT](INFO): Initialize IBIT Component.
2020-10-07T15:31:32.369Z,1602084692.369 [CBIT](DEBUG): Initialize CBIT Component.
2020-10-07T15:31:32.370Z,1602084692.370 [logger ThreadHandler](INFO): Handler Thread ID is 3254
2020-10-07T15:31:32.381Z,1602084692.381 [CBIT](DEBUG): Initialized mux pins.
2020-10-07T15:31:32.381Z,1602084692.381 [CBIT](DEBUG): Initializing the watchdog timer.
2020-10-07T15:31:32.389Z,1602084692.389 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3255
2020-10-07T15:31:32.390Z,1602084692.390 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-07T15:31:32.394Z,1602084692.394 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3257
2020-10-07T15:31:32.397Z,1602084692.397 [WetLabsBB2FL](INFO): Powering up
2020-10-07T15:31:32.399Z,1602084692.399 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3259
2020-10-07T15:31:32.400Z,1602084692.400 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-10-07T15:31:32.405Z,1602084692.405 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-10-07T15:31:32.405Z,1602084692.405 [CBIT](DEBUG): Initializing heartbeat.
2020-10-07T15:31:32.417Z,1602084692.417 [Onboard ThreadHandler](INFO): Handler Thread ID is 3260
2020-10-07T15:31:32.434Z,1602084692.434 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3261
2020-10-07T15:31:32.457Z,1602084692.457 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3262
2020-10-07T15:31:32.460Z,1602084692.460 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-10-07T15:31:32.460Z,1602084692.460 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-10-07T15:31:32.461Z,1602084692.461 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-10-07T15:31:32.461Z,1602084692.461 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-10-07T15:31:32.461Z,1602084692.461 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-10-07T15:31:32.461Z,1602084692.461 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-10-07T15:31:32.461Z,1602084692.461 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-10-07T15:31:32.462Z,1602084692.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-10-07T15:31:32.462Z,1602084692.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-10-07T15:31:32.462Z,1602084692.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-10-07T15:31:32.462Z,1602084692.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-10-07T15:31:32.462Z,1602084692.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-10-07T15:31:32.462Z,1602084692.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-10-07T15:31:32.463Z,1602084692.463 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-10-07T15:31:32.463Z,1602084692.463 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-10-07T15:31:32.463Z,1602084692.463 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-10-07T15:31:32.477Z,1602084692.477 [CBIT](DEBUG): Deactivating GF circuits.
2020-10-07T15:31:32.477Z,1602084692.477 [CBIT](DEBUG): Deactivating emergency mode.
2020-10-07T15:31:32.513Z,1602084692.513 [CBIT](DEBUG): Backplane powered.
2020-10-07T15:31:32.517Z,1602084692.517 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-10-07T15:31:32.517Z,1602084692.517 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-10-07T15:31:32.518Z,1602084692.518 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-10-07T15:31:32.518Z,1602084692.518 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-10-07T15:31:32.519Z,1602084692.519 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-10-07T15:31:32.519Z,1602084692.519 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-10-07T15:31:32.520Z,1602084692.520 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-10-07T15:31:32.521Z,1602084692.521 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-10-07T15:31:32.534Z,1602084692.534 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-10-07T15:31:32.536Z,1602084692.536 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-10-07T15:31:32.541Z,1602084692.541 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-10-07T15:31:32.541Z,1602084692.541 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-10-07T15:31:32.542Z,1602084692.542 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-10-07T15:31:32.580Z,1602084692.580 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-07T15:31:32.620Z,1602084692.620 [MissionManager](DEBUG):
2020-10-07T15:31:32.621Z,1602084692.621 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-10-07T15:31:32.688Z,1602084692.688 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-10-07T15:31:32.690Z,1602084692.690 [Default:A.Wait](DEBUG): Construct Wait.
2020-10-07T15:31:32.692Z,1602084692.692 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-07T15:31:32.769Z,1602084692.769 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-10-07T15:31:32.771Z,1602084692.771 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-10-07T15:31:32.793Z,1602084692.793 [Default:E.Execute](DEBUG): Construct Execute.
2020-10-07T15:31:32.796Z,1602084692.796 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2020-10-07T15:31:32.809Z,1602084692.809 [controlThread](DEBUG): Component order: CycleStarter,CANONSampler,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-10-07T15:31:32.821Z,1602084692.821 [CANONSampler](INFO): Powering down
2020-10-07T15:31:32.824Z,1602084692.824 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-07T15:31:32.849Z,1602084692.849 [Radio_Surface](INFO): Powering up
2020-10-07T15:31:33.051Z,1602084693.051 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-10-07T15:31:33.057Z,1602084693.057 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-10-07T15:31:33.058Z,1602084693.058 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-10-07T15:31:33.069Z,1602084693.069 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-10-07T15:31:33.070Z,1602084693.070 [MassServo](DEBUG): Initializing EZServoServo.
2020-10-07T15:31:33.077Z,1602084693.077 [MassServo](DEBUG): Initializing MassServo.
2020-10-07T15:31:33.078Z,1602084693.078 [RudderServo](DEBUG): Initializing EZServoServo.
2020-10-07T15:31:33.085Z,1602084693.085 [RudderServo](DEBUG): Initializing RudderServo.
2020-10-07T15:31:33.086Z,1602084693.086 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-07T15:31:33.093Z,1602084693.093 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-07T15:31:33.957Z,1602084693.957 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-10-07T15:31:33.957Z,1602084693.957 [RudderServo](FAULT): Rudder failed to initialize
2020-10-07T15:31:33.957Z,1602084693.957 [RudderServo] Communications Fault, FailCount= 1
2020-10-07T15:31:33.957Z,1602084693.957 [RudderServo](ERROR): Communications Fault
2020-10-07T15:31:34.068Z,1602084694.068 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-10-07T15:31:34.230Z,1602084694.230 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-10-07T15:31:34.230Z,1602084694.230 [RudderServo](INFO): Powering down
2020-10-07T15:31:34.505Z,1602084694.505 [WetLabsBB2FL](INFO): Powering down
2020-10-07T15:31:34.905Z,1602084694.905 [RudderServo](DEBUG): Initializing EZServoServo.
2020-10-07T15:31:35.026Z,1602084695.026 [RudderServo](DEBUG): Initializing RudderServo.
2020-10-07T15:31:35.030Z,1602084695.030 [CBIT](INFO): Clearing failed state for component RudderServo
2020-10-07T15:31:35.030Z,1602084695.030 [RudderServo] No Fault, FailCount= 1
2020-10-07T15:32:01.563Z,1602084721.563 [NAL9602](INFO): Powering up NAL9602
2020-10-07T15:32:07.398Z,1602084727.398 [CommandLine](IMPORTANT): got command strobe off
2020-10-07T15:32:07.398Z,1602084727.398 [CommandLine](IMPORTANT): Deactivating strobe
2020-10-07T15:32:12.530Z,1602084732.530 [NAL9602](INFO): NAL9602 initialized
2020-10-07T15:32:32.520Z,1602084752.520 [CommandLine](IMPORTANT): got command strobe off
2020-10-07T15:32:32.521Z,1602084752.521 [CommandLine](IMPORTANT): Deactivating strobe
2020-10-07T15:32:33.915Z,1602084753.915 [SBIT](IMPORTANT): Beginning Startup BIT
2020-10-07T15:32:33.919Z,1602084753.919 [CBIT](IMPORTANT): Beginning ground fault scan
2020-10-07T15:32:44.854Z,1602084764.854 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.004435
CHAN A1 (24V): -0.175194
CHAN A2 (12V): -0.005088
CHAN A3 (5V): -0.002149
CHAN B0 (3.3V): -0.001134
CHAN B1 (3.15aV): -0.001505
CHAN B2 (3.15bV): -0.001078
CHAN B3 (GND): 0.000009
OPEN: 0.004883
Full Scale Calc: 4.765 mA, -1.589 mA
2020-10-07T15:32:46.058Z,1602084766.058 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:32:46.120Z,1602084766.120 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:33:12.727Z,1602084792.727 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:33:21.352Z,1602084801.352 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005359
2020-10-07T15:33:28.027Z,1602084808.027 [SBIT](IMPORTANT): SBIT PASSED
2020-10-07T15:33:28.027Z,1602084808.027 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-10-07T15:33:28.028Z,1602084808.028 [SBIT](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;
2020-10-07T15:33:28.028Z,1602084808.028 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;
2020-10-07T15:33:28.028Z,1602084808.028 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;
2020-10-07T15:33:28.028Z,1602084808.028 [SBIT](IMPORTANT): CANONSampler.rotateOnly=1 bool;
2020-10-07T15:33:28.045Z,1602084808.045 [SBIT](IMPORTANT): CBIT.gf24Offset=148.5 microampere;
2020-10-07T15:33:28.045Z,1602084808.045 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;
2020-10-07T15:33:28.045Z,1602084808.045 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=230 cubic_centimeter;
2020-10-07T15:33:28.045Z,1602084808.045 [SBIT](IMPORTANT): VerticalControl.massDefault=10.3 millimeter;
2020-10-07T15:33:28.458Z,1602084808.458 [MissionManager](IMPORTANT): Started mission Startup
2020-10-07T15:33:28.458Z,1602084808.458 [Startup] Running Loop=1
2020-10-07T15:33:28.458Z,1602084808.458 [Startup](DEBUG): Aggregate::initialize Startup
2020-10-07T15:33:28.458Z,1602084808.458 [Startup:A.GoToSurface] Running Loop=1
2020-10-07T15:33:28.459Z,1602084808.459 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-07T15:33:28.459Z,1602084808.459 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-07T15:33:28.460Z,1602084808.460 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-07T15:33:28.460Z,1602084808.460 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-07T15:33:28.460Z,1602084808.460 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-07T15:33:28.461Z,1602084808.461 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-07T15:33:28.462Z,1602084808.462 [Startup:StartupSatComms] Running Loop=1
2020-10-07T15:33:28.462Z,1602084808.462 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-10-07T15:33:28.462Z,1602084808.462 [Startup:StartupSatComms:A] Running Loop=1
2020-10-07T15:33:28.851Z,1602084808.851 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-10-07T15:34:22.633Z,1602084862.633 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:34:28.622Z,1602084868.622 [Startup:StartupSatComms:A](INFO): Timed out from 2020-10-07T15:33:28.5Z
2020-10-07T15:34:28.622Z,1602084868.622 [Startup:StartupSatComms:A] Stopped
2020-10-07T15:34:28.622Z,1602084868.622 [Startup:StartupSatComms:B] Running Loop=1
2020-10-07T15:34:29.030Z,1602084869.030 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-10-07T15:34:32.685Z,1602084872.685 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-10-07T15:34:32.685Z,1602084872.685 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T15:34:32.723Z,1602084872.723 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T15:34:33.125Z,1602084873.125 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T15:34:33.125Z,1602084873.125 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-10-07T15:34:35.261Z,1602084875.261 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20201006T195237/Courier0049.lzma
2020-10-07T15:34:36.263Z,1602084876.263 [DataOverHttps](INFO): Moved sent file to Logs/20201006T195237/Courier0049.lzma.bak
2020-10-07T15:34:36.263Z,1602084876.263 [DataOverHttps](INFO): SBD MOMSN=12808856
2020-10-07T15:34:51.645Z,1602084891.645 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20201007T152740/Courier0000.lzma
2020-10-07T15:34:52.646Z,1602084892.646 [DataOverHttps](INFO): Moved sent file to Logs/20201007T152740/Courier0000.lzma.bak
2020-10-07T15:34:52.647Z,1602084892.647 [DataOverHttps](INFO): SBD MOMSN=12808859
2020-10-07T15:35:08.665Z,1602084908.665 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20201007T153122/Courier0000.lzma
2020-10-07T15:35:09.666Z,1602084909.666 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Courier0000.lzma.bak
2020-10-07T15:35:09.667Z,1602084909.667 [DataOverHttps](INFO): SBD MOMSN=12808862
2020-10-07T15:35:25.117Z,1602084925.117 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20201006T195237/Express0050.lzma
2020-10-07T15:35:26.119Z,1602084926.119 [DataOverHttps](INFO): Moved sent file to Logs/20201006T195237/Express0050.lzma.bak
2020-10-07T15:35:26.119Z,1602084926.119 [DataOverHttps](INFO): SBD MOMSN=12808865
2020-10-07T15:35:28.851Z,1602084928.851 [Startup:StartupSatComms:B](INFO): Timed out from 2020-10-07T15:34:28.6Z
2020-10-07T15:35:28.851Z,1602084928.851 [Startup:StartupSatComms:B] Stopped
2020-10-07T15:35:28.851Z,1602084928.851 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-10-07T15:35:28.851Z,1602084928.851 [Startup:StartupSatComms] Stopped
2020-10-07T15:35:28.852Z,1602084928.852 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-10-07T15:35:28.852Z,1602084928.852 [Startup](INFO): Completed Startup
2020-10-07T15:35:28.852Z,1602084928.852 [MissionManager](INFO): Startup is completed.
2020-10-07T15:35:28.853Z,1602084928.853 [MissionManager](INFO): Uninitializing Mission Startup
2020-10-07T15:35:28.853Z,1602084928.853 [Startup] Stopped
2020-10-07T15:35:28.853Z,1602084928.853 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-10-07T15:35:28.853Z,1602084928.853 [Startup:A.GoToSurface] Stopped
2020-10-07T15:35:28.853Z,1602084928.853 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-07T15:35:29.382Z,1602084929.382 [MissionManager](IMPORTANT): Started mission Default
2020-10-07T15:35:29.383Z,1602084929.383 [Default] Running Loop=1
2020-10-07T15:35:29.383Z,1602084929.383 [Default](DEBUG): Aggregate::initialize Default
2020-10-07T15:35:29.383Z,1602084929.383 [Default:B.GoToSurface] Running Loop=1
2020-10-07T15:35:29.383Z,1602084929.383 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-07T15:35:29.383Z,1602084929.383 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-07T15:35:29.383Z,1602084929.383 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-07T15:35:29.384Z,1602084929.384 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-07T15:35:29.384Z,1602084929.384 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-07T15:35:29.384Z,1602084929.384 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-07T15:35:29.389Z,1602084929.389 [Default:A.Wait] Running Loop=1
2020-10-07T15:35:29.389Z,1602084929.389 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-10-07T15:35:42.462Z,1602084942.462 [DataOverHttps](INFO): Sending 514 bytes from file Logs/20201007T152740/Express0001.lzma
2020-10-07T15:35:42.634Z,1602084942.634 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:35:42.644Z,1602084942.644 [Default:A.Wait](INFO): Done Waiting.
2020-10-07T15:35:42.644Z,1602084942.644 [Default:A.Wait] Stopped
2020-10-07T15:35:42.644Z,1602084942.644 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-10-07T15:35:43.006Z,1602084943.006 [Default:CheckIn] Running Loop=1
2020-10-07T15:35:43.006Z,1602084943.006 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-07T15:35:43.006Z,1602084943.006 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-07T15:35:43.406Z,1602084943.406 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-10-07T15:35:43.463Z,1602084943.463 [DataOverHttps](INFO): Moved sent file to Logs/20201007T152740/Express0001.lzma.bak
2020-10-07T15:35:43.463Z,1602084943.463 [DataOverHttps](INFO): SBD MOMSN=12808872
2020-10-07T15:36:08.931Z,1602084968.931 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:36:21.021Z,1602084981.021 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:36:31.929Z,1602084991.929 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:36:42.834Z,1602085002.834 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:36:52.462Z,1602085012.462 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2020-10-07T15:36:52.464Z,1602085012.464 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2020-10-07T15:37:03.845Z,1602085023.845 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:37:14.755Z,1602085034.755 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:37:15.896Z,1602085035.896 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-10-07T15:37:25.660Z,1602085045.660 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:37:33.683Z,1602085053.683 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-10-07T15:37:33.683Z,1602085053.683 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T15:37:33.694Z,1602085053.694 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T15:37:34.115Z,1602085054.115 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T15:37:34.115Z,1602085054.115 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-10-07T15:37:35.287Z,1602085055.287 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2020-10-07T15:37:35.287Z,1602085055.287 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2020-10-07T15:37:41.341Z,1602085061.341 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2020-10-07T15:37:41.345Z,1602085061.345 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2020-10-07T15:37:44.177Z,1602085064.177 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:37:46.267Z,1602085066.267 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:37:47.405Z,1602085067.405 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:37:50.653Z,1602085070.653 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:37:53.467Z,1602085073.467 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:37:56.702Z,1602085076.702 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:37:57.575Z,1602085077.575 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:37:59.929Z,1602085079.929 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:38:02.364Z,1602085082.364 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:38:05.185Z,1602085085.185 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-10-07T15:38:08.483Z,1602085088.483 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:38:19.394Z,1602085099.394 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:38:30.298Z,1602085110.298 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:38:40.522Z,1602085120.522 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-10-07T15:38:54.897Z,1602085134.897 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2020-10-07T15:38:54.898Z,1602085134.898 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2020-10-07T15:38:54.898Z,1602085134.898 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2020-10-07T15:38:54.906Z,1602085134.906 [BPC1](INFO): Received data from all battery sticks.
2020-10-07T15:40:34.668Z,1602085234.668 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-10-07T15:40:34.668Z,1602085234.668 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T15:40:34.679Z,1602085234.679 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T15:40:35.086Z,1602085235.086 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T15:40:35.086Z,1602085235.086 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-10-07T15:40:43.175Z,1602085243.175 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-07T15:35:43.0Z
2020-10-07T15:40:43.175Z,1602085243.175 [Default:CheckIn:Read_GPS] Stopped
2020-10-07T15:40:43.175Z,1602085243.175 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-07T15:40:43.557Z,1602085243.557 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-10-07T15:40:44.567Z,1602085244.567 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20201007T153122/Courier0004.lzma
2020-10-07T15:40:45.391Z,1602085245.391 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Courier0004.lzma.bak
2020-10-07T15:40:45.391Z,1602085245.391 [DataOverHttps](INFO): SBD MOMSN=12808899
2020-10-07T15:40:54.806Z,1602085254.806 [CommandLine](IMPORTANT): got command failComponent
2020-10-07T15:40:54.807Z,1602085254.807 [CommandLine](IMPORTANT): Failed components:
2020-10-07T15:40:54.807Z,1602085254.807 [CommandLine](IMPORTANT): No failed Components.
2020-10-07T15:41:00.635Z,1602085260.635 [DataOverHttps](INFO): Sending 895 bytes from file Logs/20201007T153122/Express0001.lzma
2020-10-07T15:41:01.063Z,1602085261.063 [CommandLine](IMPORTANT): got command burn on
2020-10-07T15:41:01.063Z,1602085261.063 [CommandLine](IMPORTANT): Activating dropweight wire
2020-10-07T15:41:01.635Z,1602085261.635 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Express0001.lzma.bak
2020-10-07T15:41:01.635Z,1602085261.635 [DataOverHttps](INFO): SBD MOMSN=12808901
2020-10-07T15:41:12.967Z,1602085272.967 [CommandLine](IMPORTANT): got command burn off
2020-10-07T15:41:12.967Z,1602085272.967 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-10-07T15:41:16.925Z,1602085276.925 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20201007T153122/Express0005.lzma
2020-10-07T15:41:17.928Z,1602085277.928 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Express0005.lzma.bak
2020-10-07T15:41:17.928Z,1602085277.928 [DataOverHttps](INFO): SBD MOMSN=12808925
2020-10-07T15:41:19.534Z,1602085279.534 [Default:CheckIn:Read_Iridium] Stopped
2020-10-07T15:41:19.534Z,1602085279.534 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-07T15:41:19.535Z,1602085279.535 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-07T15:42:14.054Z,1602085334.054 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-07T15:42:14.054Z,1602085334.054 [NAL9602] Data Fault, FailCount= 1
2020-10-07T15:42:14.054Z,1602085334.054 [NAL9602](ERROR): Data Fault
2020-10-07T15:42:14.070Z,1602085334.070 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-07T15:42:14.454Z,1602085334.454 [NAL9602](INFO): Powering down
2020-10-07T15:42:15.280Z,1602085335.280 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-07T15:42:15.280Z,1602085335.280 [NAL9602] No Fault, FailCount= 1
2020-10-07T15:42:44.761Z,1602085364.761 [NAL9602](INFO): Powering up NAL9602
2020-10-07T15:42:55.658Z,1602085375.658 [NAL9602](INFO): NAL9602 initialized
2020-10-07T15:43:35.663Z,1602085415.663 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-10-07T15:43:35.663Z,1602085415.663 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T15:43:35.701Z,1602085415.701 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T15:43:36.088Z,1602085416.088 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T15:43:36.088Z,1602085416.088 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-10-07T15:46:20.093Z,1602085580.093 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-07T15:46:20.094Z,1602085580.094 [Default:CheckIn:C.Wait] Stopped
2020-10-07T15:46:20.094Z,1602085580.094 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-07T15:46:20.094Z,1602085580.094 [Default:CheckIn:D] Running Loop=1
2020-10-07T15:46:20.502Z,1602085580.502 [Default:CheckIn:D] Stopped
2020-10-07T15:46:20.502Z,1602085580.502 [Default:CheckIn:E] Running Loop=1
2020-10-07T15:46:20.905Z,1602085580.905 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.851980 min
2020-10-07T15:46:20.905Z,1602085580.905 [Default:CheckIn:E] Stopped
2020-10-07T15:46:20.905Z,1602085580.905 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-07T15:46:20.906Z,1602085580.906 [Default:CheckIn] Stopped
2020-10-07T15:46:20.906Z,1602085580.906 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-07T15:46:20.906Z,1602085580.906 [Default:CheckIn](INFO): Running loop #2
2020-10-07T15:46:20.906Z,1602085580.906 [Default:CheckIn] Running Loop=2
2020-10-07T15:46:20.906Z,1602085580.906 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-07T15:46:20.906Z,1602085580.906 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-07T15:46:36.664Z,1602085596.664 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-10-07T15:46:36.664Z,1602085596.664 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T15:46:36.675Z,1602085596.675 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T15:46:37.098Z,1602085597.098 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T15:46:37.099Z,1602085597.099 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-10-07T15:49:37.676Z,1602085777.676 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-10-07T15:49:37.676Z,1602085777.676 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T15:49:37.686Z,1602085777.686 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T15:49:38.080Z,1602085778.080 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T15:49:38.080Z,1602085778.080 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-10-07T15:51:21.098Z,1602085881.098 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-07T15:46:20.9Z
2020-10-07T15:51:21.098Z,1602085881.098 [Default:CheckIn:Read_GPS] Stopped
2020-10-07T15:51:21.098Z,1602085881.098 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-07T15:51:27.992Z,1602085887.992 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201007T153122/Courier0007.lzma
2020-10-07T15:51:28.994Z,1602085888.994 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Courier0007.lzma.bak
2020-10-07T15:51:28.995Z,1602085888.995 [DataOverHttps](INFO): SBD MOMSN=12809010
2020-10-07T15:51:44.217Z,1602085904.217 [DataOverHttps](INFO): Sending 290 bytes from file Logs/20201007T153122/Express0008.lzma
2020-10-07T15:51:45.671Z,1602085905.671 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Express0008.lzma.bak
2020-10-07T15:51:45.671Z,1602085905.671 [DataOverHttps](INFO): SBD MOMSN=12809012
2020-10-07T15:51:46.932Z,1602085906.932 [Default:CheckIn:Read_Iridium] Stopped
2020-10-07T15:51:46.932Z,1602085906.932 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-07T15:51:46.932Z,1602085906.932 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-07T15:52:38.654Z,1602085958.654 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-10-07T15:52:38.654Z,1602085958.654 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T15:52:38.664Z,1602085958.664 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T15:52:39.079Z,1602085959.079 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T15:52:39.079Z,1602085959.079 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-10-07T15:52:59.239Z,1602085979.239 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-07T15:52:59.239Z,1602085979.239 [NAL9602] Data Fault, FailCount= 2
2020-10-07T15:52:59.239Z,1602085979.239 [NAL9602](ERROR): Data Fault
2020-10-07T15:52:59.286Z,1602085979.286 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-07T15:52:59.635Z,1602085979.635 [NAL9602](INFO): Powering down
2020-10-07T15:53:00.473Z,1602085980.473 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-07T15:53:00.473Z,1602085980.473 [NAL9602] No Fault, FailCount= 2
2020-10-07T15:53:25.690Z,1602086005.690 [CommandLine](IMPORTANT): got command failComponent
2020-10-07T15:53:25.690Z,1602086005.690 [CommandLine](IMPORTANT): Failed components:
2020-10-07T15:53:25.691Z,1602086005.691 [CommandLine](IMPORTANT): No failed Components.
2020-10-07T15:53:29.943Z,1602086009.943 [NAL9602](INFO): Powering up NAL9602
2020-10-07T15:53:40.874Z,1602086020.874 [NAL9602](INFO): NAL9602 initialized
2020-10-07T15:55:39.640Z,1602086139.640 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-10-07T15:55:39.640Z,1602086139.640 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T15:55:39.651Z,1602086139.651 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T15:55:40.050Z,1602086140.050 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T15:55:40.050Z,1602086140.050 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-10-07T15:56:47.513Z,1602086207.513 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-07T15:56:47.513Z,1602086207.513 [Default:CheckIn:C.Wait] Stopped
2020-10-07T15:56:47.513Z,1602086207.513 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-07T15:56:47.513Z,1602086207.513 [Default:CheckIn:D] Running Loop=1
2020-10-07T15:56:47.915Z,1602086207.915 [Default:CheckIn:D] Stopped
2020-10-07T15:56:47.915Z,1602086207.915 [Default:CheckIn:E] Running Loop=1
2020-10-07T15:56:48.331Z,1602086208.331 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.308872 min
2020-10-07T15:56:48.331Z,1602086208.331 [Default:CheckIn:E] Stopped
2020-10-07T15:56:48.331Z,1602086208.331 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-07T15:56:48.331Z,1602086208.331 [Default:CheckIn] Stopped
2020-10-07T15:56:48.331Z,1602086208.331 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-07T15:56:48.332Z,1602086208.332 [Default:CheckIn](INFO): Running loop #3
2020-10-07T15:56:48.332Z,1602086208.332 [Default:CheckIn] Running Loop=3
2020-10-07T15:56:48.332Z,1602086208.332 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-07T15:56:48.332Z,1602086208.332 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-07T15:58:40.652Z,1602086320.652 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-10-07T15:58:40.652Z,1602086320.652 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T15:58:40.692Z,1602086320.692 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T15:58:41.084Z,1602086321.084 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T15:58:41.084Z,1602086321.084 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-10-07T16:01:41.630Z,1602086501.630 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-10-07T16:01:41.630Z,1602086501.630 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T16:01:41.640Z,1602086501.640 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T16:01:42.030Z,1602086502.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T16:01:42.030Z,1602086502.030 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-10-07T16:01:48.515Z,1602086508.515 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-07T15:56:48.3Z
2020-10-07T16:01:48.515Z,1602086508.515 [Default:CheckIn:Read_GPS] Stopped
2020-10-07T16:01:48.515Z,1602086508.515 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-07T16:01:55.476Z,1602086515.476 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201007T153122/Courier0010.lzma
2020-10-07T16:01:56.478Z,1602086516.478 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Courier0010.lzma.bak
2020-10-07T16:01:56.479Z,1602086516.479 [DataOverHttps](INFO): SBD MOMSN=12809092
2020-10-07T16:02:11.694Z,1602086531.694 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20201007T153122/Express0011.lzma
2020-10-07T16:02:12.695Z,1602086532.695 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Express0011.lzma.bak
2020-10-07T16:02:12.695Z,1602086532.695 [DataOverHttps](INFO): SBD MOMSN=12809094
2020-10-07T16:02:14.361Z,1602086534.361 [Default:CheckIn:Read_Iridium] Stopped
2020-10-07T16:02:14.361Z,1602086534.361 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-07T16:02:14.361Z,1602086534.361 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-07T16:03:42.415Z,1602086622.415 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-07T16:03:42.415Z,1602086622.415 [NAL9602] Data Fault, FailCount= 3
2020-10-07T16:03:42.415Z,1602086622.415 [NAL9602](ERROR): Data Fault
2020-10-07T16:03:42.463Z,1602086622.463 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-07T16:03:42.822Z,1602086622.822 [NAL9602](INFO): Powering down
2020-10-07T16:03:43.644Z,1602086623.644 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-07T16:03:43.644Z,1602086623.644 [NAL9602] No Fault, FailCount= 3
2020-10-07T16:04:13.119Z,1602086653.119 [NAL9602](INFO): Powering up NAL9602
2020-10-07T16:04:24.027Z,1602086664.027 [NAL9602](INFO): NAL9602 initialized
2020-10-07T16:04:42.617Z,1602086682.617 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-10-07T16:04:42.617Z,1602086682.617 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T16:04:42.627Z,1602086682.627 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T16:04:43.030Z,1602086683.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T16:04:43.030Z,1602086683.030 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-10-07T16:07:14.941Z,1602086834.941 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-07T16:07:14.941Z,1602086834.941 [Default:CheckIn:C.Wait] Stopped
2020-10-07T16:07:14.941Z,1602086834.941 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-07T16:07:14.941Z,1602086834.941 [Default:CheckIn:D] Running Loop=1
2020-10-07T16:07:15.358Z,1602086835.358 [Default:CheckIn:D] Stopped
2020-10-07T16:07:15.358Z,1602086835.358 [Default:CheckIn:E] Running Loop=1
2020-10-07T16:07:15.759Z,1602086835.759 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.766256 min
2020-10-07T16:07:15.759Z,1602086835.759 [Default:CheckIn:E] Stopped
2020-10-07T16:07:15.759Z,1602086835.759 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-07T16:07:15.759Z,1602086835.759 [Default:CheckIn] Stopped
2020-10-07T16:07:15.759Z,1602086835.759 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-07T16:07:15.760Z,1602086835.760 [Default:CheckIn](INFO): Running loop #4
2020-10-07T16:07:15.760Z,1602086835.760 [Default:CheckIn] Running Loop=4
2020-10-07T16:07:15.760Z,1602086835.760 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-07T16:07:15.760Z,1602086835.760 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-07T16:07:43.619Z,1602086863.619 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-10-07T16:07:43.619Z,1602086863.619 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T16:07:43.658Z,1602086863.658 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T16:07:44.032Z,1602086864.032 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T16:07:44.032Z,1602086864.032 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-10-07T16:10:44.605Z,1602087044.605 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-10-07T16:10:44.605Z,1602087044.605 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T16:10:44.616Z,1602087044.616 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T16:10:45.027Z,1602087045.027 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T16:10:45.027Z,1602087045.027 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-10-07T16:12:15.911Z,1602087135.911 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-07T16:07:15.8Z
2020-10-07T16:12:15.911Z,1602087135.911 [Default:CheckIn:Read_GPS] Stopped
2020-10-07T16:12:15.911Z,1602087135.911 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-07T16:12:23.120Z,1602087143.120 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201007T153122/Courier0013.lzma
2020-10-07T16:12:24.122Z,1602087144.122 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Courier0013.lzma.bak
2020-10-07T16:12:24.123Z,1602087144.123 [DataOverHttps](INFO): SBD MOMSN=12809191
2020-10-07T16:12:39.833Z,1602087159.833 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20201007T153122/Express0014.lzma
2020-10-07T16:12:40.835Z,1602087160.835 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Express0014.lzma.bak
2020-10-07T16:12:40.835Z,1602087160.835 [DataOverHttps](INFO): SBD MOMSN=12809193
2020-10-07T16:12:42.577Z,1602087162.577 [Default:CheckIn:Read_Iridium] Stopped
2020-10-07T16:12:42.577Z,1602087162.577 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-07T16:12:42.577Z,1602087162.577 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-07T16:13:45.646Z,1602087225.646 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-10-07T16:13:45.646Z,1602087225.646 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T16:13:45.656Z,1602087225.656 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T16:13:46.047Z,1602087226.047 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T16:13:46.047Z,1602087226.047 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-10-07T16:14:25.607Z,1602087265.607 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-07T16:14:25.607Z,1602087265.607 [NAL9602] Data Fault, FailCount= 4
2020-10-07T16:14:25.607Z,1602087265.607 [NAL9602](ERROR): Data Fault
2020-10-07T16:14:25.623Z,1602087265.623 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-07T16:14:26.002Z,1602087266.002 [NAL9602](INFO): Powering down
2020-10-07T16:14:26.827Z,1602087266.827 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-07T16:14:26.827Z,1602087266.827 [NAL9602] No Fault, FailCount= 4
2020-10-07T16:14:56.299Z,1602087296.299 [NAL9602](INFO): Powering up NAL9602
2020-10-07T16:15:07.213Z,1602087307.213 [NAL9602](INFO): NAL9602 initialized
2020-10-07T16:16:46.618Z,1602087406.618 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-10-07T16:16:46.618Z,1602087406.618 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T16:16:46.651Z,1602087406.651 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T16:16:47.010Z,1602087407.010 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T16:16:47.010Z,1602087407.010 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-10-07T16:17:43.161Z,1602087463.161 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-07T16:17:43.161Z,1602087463.161 [Default:CheckIn:C.Wait] Stopped
2020-10-07T16:17:43.161Z,1602087463.161 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-07T16:17:43.161Z,1602087463.161 [Default:CheckIn:D] Running Loop=1
2020-10-07T16:17:43.566Z,1602087463.566 [Default:CheckIn:D] Stopped
2020-10-07T16:17:43.566Z,1602087463.566 [Default:CheckIn:E] Running Loop=1
2020-10-07T16:17:43.978Z,1602087463.978 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.236381 min
2020-10-07T16:17:43.978Z,1602087463.978 [Default:CheckIn:E] Stopped
2020-10-07T16:17:43.978Z,1602087463.978 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-07T16:17:43.978Z,1602087463.978 [Default:CheckIn] Stopped
2020-10-07T16:17:43.978Z,1602087463.978 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-07T16:17:43.978Z,1602087463.978 [Default:CheckIn](INFO): Running loop #5
2020-10-07T16:17:43.978Z,1602087463.978 [Default:CheckIn] Running Loop=5
2020-10-07T16:17:43.978Z,1602087463.978 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-07T16:17:43.979Z,1602087463.979 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-07T16:19:47.607Z,1602087587.607 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-10-07T16:19:47.607Z,1602087587.607 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T16:19:47.618Z,1602087587.618 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T16:19:48.005Z,1602087588.005 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T16:19:48.005Z,1602087588.005 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-10-07T16:22:44.167Z,1602087764.167 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-07T16:17:43.0Z
2020-10-07T16:22:44.167Z,1602087764.167 [Default:CheckIn:Read_GPS] Stopped
2020-10-07T16:22:44.167Z,1602087764.167 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-07T16:22:48.625Z,1602087768.625 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-10-07T16:22:48.626Z,1602087768.626 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T16:22:48.651Z,1602087768.651 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T16:22:49.044Z,1602087769.044 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T16:22:49.044Z,1602087769.044 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-10-07T16:22:50.928Z,1602087770.928 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201007T153122/Courier0016.lzma
2020-10-07T16:22:51.930Z,1602087771.930 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Courier0016.lzma.bak
2020-10-07T16:22:51.931Z,1602087771.931 [DataOverHttps](INFO): SBD MOMSN=12809289
2020-10-07T16:23:07.573Z,1602087787.573 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20201007T153122/Express0017.lzma
2020-10-07T16:23:08.575Z,1602087788.575 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Express0017.lzma.bak
2020-10-07T16:23:08.575Z,1602087788.575 [DataOverHttps](INFO): SBD MOMSN=12809291
2020-10-07T16:23:10.049Z,1602087790.049 [Default:CheckIn:Read_Iridium] Stopped
2020-10-07T16:23:10.049Z,1602087790.049 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-07T16:23:10.049Z,1602087790.049 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-07T16:25:08.387Z,1602087908.387 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-07T16:25:08.387Z,1602087908.387 [NAL9602] Data Fault, FailCount= 5
2020-10-07T16:25:08.387Z,1602087908.387 [NAL9602](ERROR): Data Fault
2020-10-07T16:25:08.430Z,1602087908.430 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-07T16:25:08.431Z,1602087908.431 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-10-07T16:25:08.794Z,1602087908.794 [NAL9602](INFO): Powering down
2020-10-07T16:25:08.808Z,1602087908.808 [CommandLine](FAULT): Scheduling is paused
2020-10-07T16:25:08.838Z,1602087908.838 [CBIT](INFO): Critical error at 20201007T162508
2020-10-07T16:25:08.838Z,1602087908.838 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-10-07T16:25:49.621Z,1602087949.621 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-10-07T16:25:49.621Z,1602087949.621 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T16:25:49.632Z,1602087949.632 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T16:25:50.021Z,1602087950.021 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T16:25:50.021Z,1602087950.021 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-10-07T16:27:08.411Z,1602088028.411 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-07T16:27:08.412Z,1602088028.412 [NAL9602] No Fault, FailCount= 5
2020-10-07T16:27:08.802Z,1602088028.802 [NAL9602](INFO): Powering up NAL9602
2020-10-07T16:27:19.711Z,1602088039.711 [NAL9602](INFO): NAL9602 initialized
2020-10-07T16:28:10.622Z,1602088090.622 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-07T16:28:10.622Z,1602088090.622 [Default:CheckIn:C.Wait] Stopped
2020-10-07T16:28:10.622Z,1602088090.622 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-07T16:28:10.622Z,1602088090.622 [Default:CheckIn:D] Running Loop=1
2020-10-07T16:28:11.028Z,1602088091.028 [Default:CheckIn:D] Stopped
2020-10-07T16:28:11.028Z,1602088091.028 [Default:CheckIn:E] Running Loop=1
2020-10-07T16:28:11.432Z,1602088091.432 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.694092 min
2020-10-07T16:28:11.432Z,1602088091.432 [Default:CheckIn:E] Stopped
2020-10-07T16:28:11.433Z,1602088091.433 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-07T16:28:11.433Z,1602088091.433 [Default:CheckIn] Stopped
2020-10-07T16:28:11.433Z,1602088091.433 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-07T16:28:11.433Z,1602088091.433 [Default:CheckIn](INFO): Running loop #6
2020-10-07T16:28:11.433Z,1602088091.433 [Default:CheckIn] Running Loop=6
2020-10-07T16:28:11.433Z,1602088091.433 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-07T16:28:11.433Z,1602088091.433 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-07T16:28:50.621Z,1602088130.621 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-10-07T16:28:50.621Z,1602088130.621 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-07T16:28:50.631Z,1602088130.631 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-07T16:28:51.038Z,1602088131.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-07T16:28:51.038Z,1602088131.038 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-10-07T16:31:37.061Z,1602088297.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162318.00,A,3648.16559,N,12147.28859,W,0.194,0.00,071020,,,A*73
2020-10-07T16:31:37.064Z,1602088297.064 [NAL9602](INFO): GPS fix at 20201007T162318: (36.802760, -121.788143)
2020-10-07T16:31:37.073Z,1602088297.073 [Default:CheckIn:Read_GPS] Stopped
2020-10-07T16:31:37.073Z,1602088297.073 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-07T16:31:46.413Z,1602088306.413 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20201007T153122/Courier0019.lzma
2020-10-07T16:31:47.415Z,1602088307.415 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Courier0019.lzma.bak
2020-10-07T16:31:47.415Z,1602088307.415 [DataOverHttps](INFO): SBD MOMSN=12809386
2020-10-07T16:32:04.873Z,1602088324.873 [DataOverHttps](INFO): Sending 315 bytes from file Logs/20201007T153122/Express0020.lzma
2020-10-07T16:32:05.874Z,1602088325.874 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Express0020.lzma.bak
2020-10-07T16:32:05.875Z,1602088325.875 [DataOverHttps](INFO): SBD MOMSN=12809390
2020-10-07T16:32:07.380Z,1602088327.380 [Default:CheckIn:Read_Iridium] Stopped
2020-10-07T16:32:07.380Z,1602088327.380 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-07T16:32:07.380Z,1602088327.380 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-07T16:32:09.398Z,1602088329.398 [NAL9602](INFO): Not Powering down - fast GPS
2020-10-07T16:37:07.964Z,1602088627.964 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-07T16:37:07.964Z,1602088627.964 [Default:CheckIn:C.Wait] Stopped
2020-10-07T16:37:07.964Z,1602088627.964 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-07T16:37:07.964Z,1602088627.964 [Default:CheckIn:D] Running Loop=1
2020-10-07T16:37:08.357Z,1602088628.357 [Default:CheckIn:D] Stopped
2020-10-07T16:37:08.357Z,1602088628.357 [Default:CheckIn:E] Running Loop=1
2020-10-07T16:37:08.766Z,1602088628.766 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.649565 min
2020-10-07T16:37:08.766Z,1602088628.766 [Default:CheckIn:E] Stopped
2020-10-07T16:37:08.766Z,1602088628.766 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-07T16:37:08.766Z,1602088628.766 [Default:CheckIn] Stopped
2020-10-07T16:37:08.766Z,1602088628.766 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-07T16:37:08.767Z,1602088628.767 [Default:CheckIn](INFO): Running loop #7
2020-10-07T16:37:08.767Z,1602088628.767 [Default:CheckIn] Running Loop=7
2020-10-07T16:37:08.767Z,1602088628.767 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-07T16:37:08.767Z,1602088628.767 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-07T16:37:10.777Z,1602088630.777 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162853.00,A,3648.16318,N,12147.28328,W,1.050,0.00,071020,,,A*71
2020-10-07T16:37:10.779Z,1602088630.779 [NAL9602](INFO): GPS fix at 20201007T162853: (36.802720, -121.788055)
2020-10-07T16:37:10.788Z,1602088630.788 [Default:CheckIn:Read_GPS] Stopped
2020-10-07T16:37:10.788Z,1602088630.788 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-07T16:37:18.376Z,1602088638.376 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201007T153122/Courier0022.lzma
2020-10-07T16:37:19.378Z,1602088639.378 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Courier0022.lzma.bak
2020-10-07T16:37:19.379Z,1602088639.379 [DataOverHttps](INFO): SBD MOMSN=12809464
2020-10-07T16:37:35.368Z,1602088655.368 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201007T153122/Express0023.lzma
2020-10-07T16:37:36.371Z,1602088656.371 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Express0023.lzma.bak
2020-10-07T16:37:36.371Z,1602088656.371 [DataOverHttps](INFO): SBD MOMSN=12809467
2020-10-07T16:37:37.864Z,1602088657.864 [Default:CheckIn:Read_Iridium] Stopped
2020-10-07T16:37:37.864Z,1602088657.864 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-07T16:37:37.864Z,1602088657.864 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-07T16:40:01.675Z,1602088801.675 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2020-10-07T16:40:12.989Z,1602088812.989 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2020-10-07T16:40:24.296Z,1602088824.296 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2020-10-07T16:40:36.015Z,1602088836.015 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2020-10-07T16:40:47.334Z,1602088847.334 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2020-10-07T16:40:58.644Z,1602088858.644 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2020-10-07T16:41:10.021Z,1602088870.021 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2020-10-07T16:41:21.269Z,1602088881.269 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2020-10-07T16:42:13.457Z,1602088933.457 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-10-07T16:42:38.441Z,1602088958.441 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-07T16:42:38.441Z,1602088958.441 [Default:CheckIn:C.Wait] Stopped
2020-10-07T16:42:38.441Z,1602088958.441 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-07T16:42:38.441Z,1602088958.441 [Default:CheckIn:D] Running Loop=1
2020-10-07T16:42:38.850Z,1602088958.850 [Default:CheckIn:D] Stopped
2020-10-07T16:42:38.850Z,1602088958.850 [Default:CheckIn:E] Running Loop=1
2020-10-07T16:42:39.255Z,1602088959.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.157788 min
2020-10-07T16:42:39.255Z,1602088959.255 [Default:CheckIn:E] Stopped
2020-10-07T16:42:39.255Z,1602088959.255 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-07T16:42:39.255Z,1602088959.255 [Default:CheckIn] Stopped
2020-10-07T16:42:39.255Z,1602088959.255 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-07T16:42:39.255Z,1602088959.255 [Default:CheckIn](INFO): Running loop #8
2020-10-07T16:42:39.256Z,1602088959.256 [Default:CheckIn] Running Loop=8
2020-10-07T16:42:39.256Z,1602088959.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-07T16:42:39.256Z,1602088959.256 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-07T16:46:33.186Z,1602089193.186 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2020-10-07T16:46:33.187Z,1602089193.187 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2020-10-07T16:46:33.188Z,1602089193.188 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2020-10-07T16:46:33.190Z,1602089193.190 [BPC1](INFO): Received data from all battery sticks.
2020-10-07T16:47:39.445Z,1602089259.445 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-07T16:42:39.3Z
2020-10-07T16:47:39.445Z,1602089259.445 [Default:CheckIn:Read_GPS] Stopped
2020-10-07T16:47:39.445Z,1602089259.445 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-07T16:47:46.813Z,1602089266.813 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20201007T153122/Courier0025.lzma
2020-10-07T16:47:47.814Z,1602089267.814 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Courier0025.lzma.bak
2020-10-07T16:47:47.815Z,1602089267.815 [DataOverHttps](INFO): SBD MOMSN=12809554
2020-10-07T16:48:03.741Z,1602089283.741 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20201007T153122/Express0026.lzma
2020-10-07T16:48:04.742Z,1602089284.742 [DataOverHttps](INFO): Moved sent file to Logs/20201007T153122/Express0026.lzma.bak
2020-10-07T16:48:04.743Z,1602089284.743 [DataOverHttps](INFO): SBD MOMSN=12809556
2020-10-07T16:48:06.117Z,1602089286.117 [Default:CheckIn:Read_Iridium] Stopped
2020-10-07T16:48:06.117Z,1602089286.117 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-07T16:48:06.117Z,1602089286.117 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-07T16:48:10.542Z,1602089290.542 [NAL9602](INFO): Not Powering down - fast GPS
2020-10-07T16:51:15.918Z,1602089475.918 [DataOverHttps](IMPORTANT): SBD MTMSN=20201007T164257
2020-10-07T16:51:23.349Z,1602089483.349 [DataOverHttps](INFO): Received command:restart sys
2020-10-07T16:51:23.366Z,1602089483.366 [CommandLine](IMPORTANT): got command restart system
2020-10-07T16:51:25.421Z,1602089485.421 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-07T16:51:25.421Z,1602089485.421 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:25.502Z,1602089485.502 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2020-10-07T16:51:25.502Z,1602089485.502 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:25.503Z,1602089485.503 [CommandLine](INFO): Join timeout helper Thread ID is 3436
2020-10-07T16:51:25.503Z,1602089485.503 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-10-07T16:51:25.503Z,1602089485.503 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:25.504Z,1602089485.504 [NavChartDb](INFO): Join timeout helper Thread ID is 3437
2020-10-07T16:51:25.657Z,1602089485.657 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-07T16:51:25.657Z,1602089485.657 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:25.665Z,1602089485.665 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-10-07T16:51:25.665Z,1602089485.665 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:25.665Z,1602089485.665 [Radio_Surface](INFO): Join timeout helper Thread ID is 3438
2020-10-07T16:51:25.841Z,1602089485.841 [Radio_Surface](INFO): Powering down
2020-10-07T16:51:25.842Z,1602089485.842 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-07T16:51:25.842Z,1602089485.842 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:25.845Z,1602089485.845 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-10-07T16:51:25.845Z,1602089485.845 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:25.845Z,1602089485.845 [Onboard](INFO): Join timeout helper Thread ID is 3439
2020-10-07T16:51:26.357Z,1602089486.357 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-10-07T16:51:26.397Z,1602089486.397 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-07T16:51:26.397Z,1602089486.397 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:26.413Z,1602089486.413 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-10-07T16:51:26.413Z,1602089486.413 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:26.413Z,1602089486.413 [DataOverHttps](INFO): Join timeout helper Thread ID is 3440
2020-10-07T16:51:27.361Z,1602089487.361 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-07T16:51:27.361Z,1602089487.361 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.378Z,1602089487.378 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-10-07T16:51:27.378Z,1602089487.378 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.378Z,1602089487.378 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3441
2020-10-07T16:51:27.469Z,1602089487.469 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-07T16:51:27.469Z,1602089487.469 [WetLabsBB2FL](INFO): Powering down
2020-10-07T16:51:27.470Z,1602089487.470 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.485Z,1602089487.485 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-10-07T16:51:27.486Z,1602089487.486 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.486Z,1602089487.486 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3442
2020-10-07T16:51:27.585Z,1602089487.585 [CTD_Seabird](INFO): Powering down
2020-10-07T16:51:27.597Z,1602089487.597 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-07T16:51:27.597Z,1602089487.597 [CTD_Seabird](INFO): Powering down
2020-10-07T16:51:27.609Z,1602089487.609 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.627Z,1602089487.627 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-10-07T16:51:27.627Z,1602089487.627 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.627Z,1602089487.627 [logger](INFO): Join timeout helper Thread ID is 3443
2020-10-07T16:51:27.628Z,1602089487.628 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-07T16:51:27.628Z,1602089487.628 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.629Z,1602089487.629 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-10-07T16:51:27.629Z,1602089487.629 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.629Z,1602089487.629 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-10-07T16:51:27.629Z,1602089487.629 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.630Z,1602089487.630 [controlThread](INFO): Join timeout helper Thread ID is 3444
2020-10-07T16:51:27.697Z,1602089487.697 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-07T16:51:27.697Z,1602089487.697 [controlThread](DEBUG): Uninitializing ControlThread
2020-10-07T16:51:27.703Z,1602089487.703 [AHRS_M2](INFO): Powering down
2020-10-07T16:51:27.774Z,1602089487.774 [NAL9602](INFO): Powering down
2020-10-07T16:51:27.775Z,1602089487.775 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-10-07T16:51:27.776Z,1602089487.776 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-10-07T16:51:27.777Z,1602089487.777 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-10-07T16:51:27.777Z,1602089487.777 [MissionManager](INFO): Uninitializing Mission Default
2020-10-07T16:51:27.777Z,1602089487.777 [Default] Stopped
2020-10-07T16:51:27.778Z,1602089487.778 [Default](DEBUG): Aggregate::uninitialize Default
2020-10-07T16:51:27.778Z,1602089487.778 [Default:B.GoToSurface] Stopped
2020-10-07T16:51:27.778Z,1602089487.778 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-07T16:51:27.778Z,1602089487.778 [Default:CheckIn] Stopped
2020-10-07T16:51:27.778Z,1602089487.778 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-07T16:51:27.778Z,1602089487.778 [Default:CheckIn:C.Wait] Stopped
2020-10-07T16:51:27.778Z,1602089487.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-07T16:51:27.781Z,1602089487.781 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-10-07T16:51:27.782Z,1602089487.782 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-10-07T16:51:27.782Z,1602089487.782 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-10-07T16:51:27.782Z,1602089487.782 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-10-07T16:51:27.783Z,1602089487.783 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-10-07T16:51:27.783Z,1602089487.783 [BuoyancyServo](INFO): Powering down
2020-10-07T16:51:27.797Z,1602089487.797 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-10-07T16:51:27.797Z,1602089487.797 [ElevatorServo](INFO): Powering down
2020-10-07T16:51:27.798Z,1602089487.798 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-10-07T16:51:27.798Z,1602089487.798 [MassServo](INFO): Powering down
2020-10-07T16:51:27.799Z,1602089487.799 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-10-07T16:51:27.799Z,1602089487.799 [RudderServo](INFO): Powering down
2020-10-07T16:51:27.800Z,1602089487.800 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-07T16:51:27.800Z,1602089487.800 [ThrusterServo](INFO): Powering down
2020-10-07T16:51:27.801Z,1602089487.801 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-10-07T16:51:27.801Z,1602089487.801 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-10-07T16:51:27.802Z,1602089487.802 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-10-07T16:51:27.802Z,1602089487.802 [CBIT](DEBUG): Powering off loads.
2020-10-07T16:51:27.813Z,1602089487.813 [CBIT](DEBUG): Disabling WDT.
2020-10-07T16:51:27.825Z,1602089487.825 [CBIT](DEBUG): Opening all GF detection circuits.
2020-10-07T16:51:27.826Z,1602089487.826 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.868Z,1602089487.868 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.874Z,1602089487.874 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.915Z,1602089487.915 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.916Z,1602089487.916 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:27.923Z,1602089487.923 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:28.029Z,1602089488.029 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-07T16:51:28.109Z,1602089488.109 [logger ThreadHandler](INFO): Thread cancelled.