2021-11-10T20:48:44.817Z,1636577324.817 [Supervisor](DEBUG): Initializing supervisor.
2021-11-10T20:48:44.822Z,1636577324.822 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-11-10T20:48:44.822Z,1636577324.822 [SyncHandler](INFO): Protected caller Thread ID is 8554
2021-11-10T20:48:44.823Z,1636577324.823 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-11-10T20:48:44.824Z,1636577324.824 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-11-10T20:48:44.824Z,1636577324.824 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8555
2021-11-10T20:48:44.828Z,1636577324.828 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-11-10T20:48:44.847Z,1636577324.847 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2021-11-10T20:48:44.848Z,1636577324.848 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-11-10T20:48:44.848Z,1636577324.848 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8556
2021-11-10T20:48:44.852Z,1636577324.852 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-11-10T20:48:44.853Z,1636577324.853 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-11-10T20:48:44.854Z,1636577324.854 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8557
2021-11-10T20:48:44.856Z,1636577324.856 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-11-10T20:48:44.857Z,1636577324.857 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2021-11-10T20:48:44.857Z,1636577324.857 [logger ThreadHandler](INFO): Protected caller Thread ID is 8558
2021-11-10T20:48:44.861Z,1636577324.861 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-11-10T20:48:44.861Z,1636577324.861 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-11-10T20:48:44.863Z,1636577324.863 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-11-10T20:48:45.287Z,1636577325.287 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-11-10T20:48:45.287Z,1636577325.287 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-11-10T20:48:45.430Z,1636577325.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-11-10T20:48:45.430Z,1636577325.430 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-11-10T20:48:45.510Z,1636577325.510 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-11-10T20:48:45.719Z,1636577325.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-11-10T20:48:45.719Z,1636577325.719 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-11-10T20:48:45.798Z,1636577325.798 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-11-10T20:48:45.893Z,1636577325.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-11-10T20:48:45.894Z,1636577325.894 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-11-10T20:48:46.235Z,1636577326.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-11-10T20:48:46.236Z,1636577326.236 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-11-10T20:48:46.360Z,1636577326.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-11-10T20:48:46.361Z,1636577326.361 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-11-10T20:48:46.872Z,1636577326.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-11-10T20:48:46.873Z,1636577326.873 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-11-10T20:48:47.056Z,1636577327.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-11-10T20:48:47.057Z,1636577327.057 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-11-10T20:48:47.436Z,1636577327.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-11-10T20:48:47.436Z,1636577327.436 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-11-10T20:48:47.668Z,1636577327.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-11-10T20:48:47.669Z,1636577327.669 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-11-10T20:48:47.776Z,1636577327.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-11-10T20:48:47.777Z,1636577327.777 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-11-10T20:48:48.427Z,1636577328.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-11-10T20:48:48.428Z,1636577328.428 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-11-10T20:48:48.754Z,1636577328.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-11-10T20:48:48.754Z,1636577328.754 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-11-10T20:48:49.033Z,1636577329.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-11-10T20:48:49.035Z,1636577329.035 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2021-11-10T20:48:49.036Z,1636577329.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2021-11-10T20:48:49.353Z,1636577329.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2021-11-10T20:48:49.476Z,1636577329.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2021-11-10T20:48:49.714Z,1636577329.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2021-11-10T20:48:49.838Z,1636577329.838 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2021-11-10T20:48:50.344Z,1636577330.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-11-10T20:48:50.345Z,1636577330.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2021-11-10T20:48:50.448Z,1636577330.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2021-11-10T20:48:50.559Z,1636577330.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2021-11-10T20:48:50.654Z,1636577330.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2021-11-10T20:48:50.798Z,1636577330.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2021-11-10T20:48:50.896Z,1636577330.896 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2021-11-10T20:48:50.988Z,1636577330.988 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-11-10T20:48:51.001Z,1636577331.001 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-11-10T20:48:51.013Z,1636577331.013 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-11-10T20:48:51.013Z,1636577331.013 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-11-10T20:48:51.109Z,1636577331.109 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-11-10T20:48:51.109Z,1636577331.109 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-11-10T20:48:51.162Z,1636577331.162 [VerticalControl](DEBUG): Construct VerticalControl.
2021-11-10T20:48:51.224Z,1636577331.224 [VerticalControl] Loaded
2021-11-10T20:48:51.224Z,1636577331.224 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-11-10T20:48:51.227Z,1636577331.227 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-11-10T20:48:51.266Z,1636577331.266 [HorizontalControl] Loaded
2021-11-10T20:48:51.266Z,1636577331.266 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-11-10T20:48:51.268Z,1636577331.268 [SpeedControl](DEBUG): Construct SpeedControl.
2021-11-10T20:48:51.271Z,1636577331.271 [SpeedControl] Loaded
2021-11-10T20:48:51.272Z,1636577331.272 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-11-10T20:48:51.274Z,1636577331.274 [LoopControl](DEBUG): Construct LoopControl.
2021-11-10T20:48:51.275Z,1636577331.275 [LoopControl] Loaded
2021-11-10T20:48:51.275Z,1636577331.275 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-11-10T20:48:51.275Z,1636577331.275 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-11-10T20:48:51.276Z,1636577331.276 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-11-10T20:48:51.427Z,1636577331.427 [BuoyancyServo] Loaded
2021-11-10T20:48:51.428Z,1636577331.428 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-11-10T20:48:51.446Z,1636577331.446 [ElevatorServo] Loaded
2021-11-10T20:48:51.447Z,1636577331.447 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-11-10T20:48:51.465Z,1636577331.465 [MassServo] Loaded
2021-11-10T20:48:51.465Z,1636577331.465 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-11-10T20:48:51.483Z,1636577331.483 [RudderServo] Loaded
2021-11-10T20:48:51.483Z,1636577331.483 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-11-10T20:48:51.498Z,1636577331.498 [ThrusterHE] Loaded
2021-11-10T20:48:51.498Z,1636577331.498 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2021-11-10T20:48:51.498Z,1636577331.498 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-11-10T20:48:51.499Z,1636577331.499 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-11-10T20:48:51.541Z,1636577331.541 [DepthRateCalculator] Loaded
2021-11-10T20:48:51.542Z,1636577331.542 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-11-10T20:48:51.546Z,1636577331.546 [PitchRateCalculator] Loaded
2021-11-10T20:48:51.547Z,1636577331.547 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-11-10T20:48:51.557Z,1636577331.557 [SpeedCalculator] Loaded
2021-11-10T20:48:51.557Z,1636577331.557 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-11-10T20:48:51.570Z,1636577331.570 [TempGradientCalculator] Loaded
2021-11-10T20:48:51.571Z,1636577331.571 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-11-10T20:48:51.575Z,1636577331.575 [YawRateCalculator] Loaded
2021-11-10T20:48:51.575Z,1636577331.575 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-11-10T20:48:51.592Z,1636577331.592 [ElevatorOffsetCalculator] Loaded
2021-11-10T20:48:51.593Z,1636577331.593 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-11-10T20:48:51.593Z,1636577331.593 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-11-10T20:48:51.594Z,1636577331.594 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-11-10T20:48:51.673Z,1636577331.673 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-11-10T20:48:51.673Z,1636577331.673 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-11-10T20:48:51.686Z,1636577331.686 [NavChart] Loaded
2021-11-10T20:48:51.687Z,1636577331.687 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-11-10T20:48:51.692Z,1636577331.692 [UniversalFixResidualReporter] Loaded
2021-11-10T20:48:51.693Z,1636577331.693 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-11-10T20:48:51.693Z,1636577331.693 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-11-10T20:48:51.694Z,1636577331.694 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-11-10T20:48:52.071Z,1636577332.071 [AHRS_M2] Loaded
2021-11-10T20:48:52.071Z,1636577332.071 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-11-10T20:48:52.104Z,1636577332.104 [BackseatComponent] Loaded
2021-11-10T20:48:52.104Z,1636577332.104 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-11-10T20:48:52.106Z,1636577332.106 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408A64E0
2021-11-10T20:48:52.106Z,1636577332.106 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8640
2021-11-10T20:48:52.109Z,1636577332.109 [LcmUniversalReporter] Loaded
2021-11-10T20:48:52.109Z,1636577332.109 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-11-10T20:48:52.893Z,1636577332.893 [BPC1] Loaded
2021-11-10T20:48:52.893Z,1636577332.893 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-11-10T20:48:52.973Z,1636577332.973 [DataOverHttps] Loaded
2021-11-10T20:48:52.974Z,1636577332.974 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-11-10T20:48:52.975Z,1636577332.975 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D64E0
2021-11-10T20:48:52.975Z,1636577332.975 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8641
2021-11-10T20:48:52.995Z,1636577332.995 [Depth_Keller] Loaded
2021-11-10T20:48:52.995Z,1636577332.995 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-11-10T20:48:52.000Z,1636577333.000 [DropWeight] Loaded
2021-11-10T20:48:52.000Z,1636577333.000 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-11-10T20:48:53.058Z,1636577333.058 [NAL9602] Loaded
2021-11-10T20:48:53.059Z,1636577333.059 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-11-10T20:48:53.087Z,1636577333.087 [Onboard] Loaded
2021-11-10T20:48:53.087Z,1636577333.087 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-11-10T20:48:53.088Z,1636577333.088 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409064E0
2021-11-10T20:48:53.089Z,1636577333.089 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8642
2021-11-10T20:48:53.102Z,1636577333.102 [Power24vConverter] Loaded
2021-11-10T20:48:53.102Z,1636577333.102 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-11-10T20:48:53.115Z,1636577333.115 [Radio_Surface] Loaded
2021-11-10T20:48:53.115Z,1636577333.115 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-11-10T20:48:53.116Z,1636577333.116 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409364E0
2021-11-10T20:48:53.117Z,1636577333.117 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8643
2021-11-10T20:48:53.198Z,1636577333.198 [DAT] Loaded
2021-11-10T20:48:53.198Z,1636577333.198 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-11-10T20:48:53.199Z,1636577333.199 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-11-10T20:48:53.199Z,1636577333.199 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-11-10T20:48:53.257Z,1636577333.257 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-11-10T20:48:53.257Z,1636577333.257 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-11-10T20:48:53.361Z,1636577333.361 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-11-10T20:48:53.362Z,1636577333.362 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-11-10T20:48:53.389Z,1636577333.389 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-11-10T20:48:53.390Z,1636577333.390 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-11-10T20:48:53.565Z,1636577333.565 [CTD_Seabird] Loaded
2021-11-10T20:48:53.565Z,1636577333.565 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-11-10T20:48:53.566Z,1636577333.566 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A564E0
2021-11-10T20:48:53.566Z,1636577333.566 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8644
2021-11-10T20:48:53.586Z,1636577333.586 [PAR_Licor] Loaded
2021-11-10T20:48:53.586Z,1636577333.586 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-11-10T20:48:53.618Z,1636577333.618 [WetLabsBB2FL] Loaded
2021-11-10T20:48:53.618Z,1636577333.618 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-11-10T20:48:53.619Z,1636577333.619 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A864E0
2021-11-10T20:48:53.619Z,1636577333.619 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8645
2021-11-10T20:48:53.620Z,1636577333.620 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-11-10T20:48:53.621Z,1636577333.621 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-11-10T20:48:53.945Z,1636577333.945 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-11-10T20:48:53.945Z,1636577333.945 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-11-10T20:48:54.090Z,1636577334.090 [SBIT](DEBUG): Construct Startup Built In Test.
2021-11-10T20:48:54.100Z,1636577334.100 [SBIT] Loaded
2021-11-10T20:48:54.100Z,1636577334.100 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-11-10T20:48:54.103Z,1636577334.103 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-11-10T20:48:54.116Z,1636577334.116 [IBIT] Loaded
2021-11-10T20:48:54.116Z,1636577334.116 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-11-10T20:48:54.122Z,1636577334.122 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-11-10T20:48:54.223Z,1636577334.223 [CBIT] Loaded
2021-11-10T20:48:54.223Z,1636577334.223 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-11-10T20:48:54.224Z,1636577334.224 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-11-10T20:48:54.239Z,1636577334.239 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-11-10T20:48:54.242Z,1636577334.242 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-11-10T20:48:54.317Z,1636577334.317 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-11-10T20:48:54.318Z,1636577334.318 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B6D4E0
2021-11-10T20:48:54.318Z,1636577334.318 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8646
2021-11-10T20:48:54.331Z,1636577334.331 [Supervisor](INFO): Main Thread ID is 829
2021-11-10T20:48:54.331Z,1636577334.331 [Supervisor](DEBUG): Running supervisor.
2021-11-10T20:48:54.332Z,1636577334.332 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8647
2021-11-10T20:48:54.332Z,1636577334.332 [CommandExec](INFO): Initializing the command executive.
2021-11-10T20:48:54.334Z,1636577334.334 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8648
2021-11-10T20:48:54.336Z,1636577334.336 [logger ThreadHandler](INFO): Handler Thread ID is 8650
2021-11-10T20:48:54.337Z,1636577334.337 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8651
2021-11-10T20:48:54.364Z,1636577334.364 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8652
2021-11-10T20:48:54.365Z,1636577334.365 [Onboard ThreadHandler](INFO): Handler Thread ID is 8653
2021-11-10T20:48:54.367Z,1636577334.367 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-11-10T20:48:54.401Z,1636577334.401 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8654
2021-11-10T20:48:54.403Z,1636577334.403 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8656
2021-11-10T20:48:54.406Z,1636577334.406 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8658
2021-11-10T20:48:54.409Z,1636577334.409 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-11-10T20:48:54.409Z,1636577334.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-11-10T20:48:54.410Z,1636577334.410 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-11-10T20:48:54.410Z,1636577334.410 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-11-10T20:48:54.410Z,1636577334.410 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-11-10T20:48:54.410Z,1636577334.410 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-11-10T20:48:54.410Z,1636577334.410 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-11-10T20:48:54.410Z,1636577334.410 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-11-10T20:48:54.411Z,1636577334.411 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-11-10T20:48:54.412Z,1636577334.412 [WetLabsBB2FL](INFO): Powering up
2021-11-10T20:48:54.415Z,1636577334.415 [controlThread ThreadHandler](INFO): Handler Thread ID is 8649
2021-11-10T20:48:54.416Z,1636577334.416 [controlThread](DEBUG): Initializing ControlThread
2021-11-10T20:48:54.416Z,1636577334.416 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-11-10T20:48:54.418Z,1636577334.418 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-11-10T20:48:54.419Z,1636577334.419 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-11-10T20:48:54.420Z,1636577334.420 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-11-10T20:48:54.421Z,1636577334.421 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-11-10T20:48:54.421Z,1636577334.421 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-11-10T20:48:54.421Z,1636577334.421 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-11-10T20:48:54.422Z,1636577334.422 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-11-10T20:48:54.422Z,1636577334.422 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-11-10T20:48:54.422Z,1636577334.422 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-11-10T20:48:54.424Z,1636577334.424 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-11-10T20:48:54.424Z,1636577334.424 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-11-10T20:48:54.452Z,1636577334.452 [SBIT](INFO): Initialize SBIT Component.
2021-11-10T20:48:54.452Z,1636577334.452 [SBIT](IMPORTANT): git: 2021-11-09
2021-11-10T20:48:54.452Z,1636577334.452 [SBIT](INFO): git hash: 235ad51f9df6aba2b8b5ed692903eef5eaec5ce6
2021-11-10T20:48:54.453Z,1636577334.453 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-11-10T20:48:54.454Z,1636577334.454 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2021-11-10T20:48:54.459Z,1636577334.459 [SBIT](INFO): Beginning SBIT in 61.000000 seconds.
2021-11-10T20:48:54.460Z,1636577334.460 [IBIT](INFO): Initialize IBIT Component.
2021-11-10T20:48:54.461Z,1636577334.461 [CBIT](DEBUG): Initialize CBIT Component.
2021-11-10T20:48:54.462Z,1636577334.462 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8655
2021-11-10T20:48:54.462Z,1636577334.462 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-11-10T20:48:54.475Z,1636577334.475 [CBIT](DEBUG): Initialized mux pins.
2021-11-10T20:48:54.475Z,1636577334.475 [CBIT](DEBUG): Initializing the watchdog timer.
2021-11-10T20:48:54.479Z,1636577334.479 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-11-10T20:48:54.479Z,1636577334.479 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-11-10T20:48:54.480Z,1636577334.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-11-10T20:48:54.480Z,1636577334.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-11-10T20:48:54.480Z,1636577334.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-11-10T20:48:54.480Z,1636577334.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-11-10T20:48:54.480Z,1636577334.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-11-10T20:48:54.499Z,1636577334.499 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-11-10T20:48:54.500Z,1636577334.500 [CBIT](DEBUG): Initializing heartbeat.
2021-11-10T20:48:54.571Z,1636577334.571 [CBIT](DEBUG): Deactivating GF circuits.
2021-11-10T20:48:54.571Z,1636577334.571 [CBIT](DEBUG): Deactivating emergency mode.
2021-11-10T20:48:54.607Z,1636577334.607 [CBIT](DEBUG): Backplane powered.
2021-11-10T20:48:54.608Z,1636577334.608 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-11-10T20:48:54.621Z,1636577334.621 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-10T20:48:54.632Z,1636577334.632 [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>


2021-11-10T20:48:54.633Z,1636577334.633 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-11-10T20:48:54.693Z,1636577334.693 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-11-10T20:48:54.694Z,1636577334.694 [Default:A.Wait](DEBUG): Construct Wait.
2021-11-10T20:48:54.697Z,1636577334.697 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-10T20:48:54.721Z,1636577334.721 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-11-10T20:48:54.724Z,1636577334.724 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-11-10T20:48:54.730Z,1636577334.730 [Default:E.Execute](DEBUG): Construct Execute.
2021-11-10T20:48:54.746Z,1636577334.746 [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>


2021-11-10T20:48:54.752Z,1636577334.752 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-11-10T20:48:54.767Z,1636577334.767 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-11-10T20:48:54.803Z,1636577334.803 [Radio_Surface](INFO): Powering up
2021-11-10T20:48:54.816Z,1636577334.816 [Depth_Keller](ERROR): Pressure reading out of range: 962.881653 decibar
2021-11-10T20:48:54.818Z,1636577334.818 [Power24vConverter](INFO): Powering up.
2021-11-10T20:48:54.818Z,1636577334.818 [DAT](INFO): Powering up
2021-11-10T20:48:54.819Z,1636577334.819 [DAT](DEBUG): Initializing DAT.
2021-11-10T20:48:54.868Z,1636577334.868 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-11-10T20:48:54.890Z,1636577334.890 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-11-10T20:48:54.943Z,1636577334.943 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-11-10T20:48:54.944Z,1636577334.944 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-11-10T20:48:54.980Z,1636577334.980 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-11-10T20:48:54.981Z,1636577334.981 [MassServo](DEBUG): Initializing EZServoServo.
2021-11-10T20:48:54.987Z,1636577334.987 [MassServo](DEBUG): Initializing MassServo.
2021-11-10T20:48:54.988Z,1636577334.988 [RudderServo](DEBUG): Initializing EZServoServo.
2021-11-10T20:48:54.995Z,1636577334.995 [RudderServo](DEBUG): Initializing RudderServo.
2021-11-10T20:48:54.996Z,1636577334.996 [ThrusterHE](DEBUG): Initializing EZServoServo.
2021-11-10T20:48:55.003Z,1636577335.003 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2021-11-10T20:48:55.915Z,1636577335.915 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-11-10T20:48:55.915Z,1636577335.915 [RudderServo](FAULT): Rudder failed to initialize
2021-11-10T20:48:55.915Z,1636577335.915 [RudderServo] Communications Fault, FailCount= 1
2021-11-10T20:48:55.915Z,1636577335.915 [RudderServo](ERROR): Communications Fault
2021-11-10T20:48:55.919Z,1636577335.919 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-11-10T20:48:56.128Z,1636577336.128 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-11-10T20:48:56.128Z,1636577336.128 [RudderServo](INFO): Powering down
2021-11-10T20:48:56.519Z,1636577336.519 [WetLabsBB2FL](INFO): Powering down
2021-11-10T20:48:56.800Z,1636577336.800 [RudderServo](DEBUG): Initializing EZServoServo.
2021-11-10T20:48:56.920Z,1636577336.920 [RudderServo](DEBUG): Initializing RudderServo.
2021-11-10T20:48:56.924Z,1636577336.924 [CBIT](INFO): Clearing failed state for component RudderServo
2021-11-10T20:48:56.924Z,1636577336.924 [RudderServo] No Fault, FailCount= 1
2021-11-10T20:49:02.495Z,1636577342.495 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-11-10T20:49:08.911Z,1636577348.911 [DAT](INFO): commRate: 800
2021-11-10T20:49:10.925Z,1636577350.925 [DAT](INFO): entering command mode
2021-11-10T20:49:11.329Z,1636577351.329 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:11.733Z,1636577351.733 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:12.137Z,1636577352.137 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:12.541Z,1636577352.541 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:12.945Z,1636577352.945 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:13.358Z,1636577353.358 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:13.753Z,1636577353.753 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:14.157Z,1636577354.157 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:14.561Z,1636577354.561 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:14.973Z,1636577354.973 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:15.369Z,1636577355.369 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:15.773Z,1636577355.773 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:16.177Z,1636577356.177 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:16.581Z,1636577356.581 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:16.985Z,1636577356.985 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:17.389Z,1636577357.389 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:17.793Z,1636577357.793 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:18.203Z,1636577358.203 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:18.601Z,1636577358.601 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:19.005Z,1636577359.005 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:19.409Z,1636577359.409 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:19.817Z,1636577359.817 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:20.217Z,1636577360.217 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:20.621Z,1636577360.621 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:21.025Z,1636577361.025 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:21.433Z,1636577361.433 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:21.833Z,1636577361.833 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:22.237Z,1636577362.237 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:22.641Z,1636577362.641 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:23.050Z,1636577363.050 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:23.449Z,1636577363.449 [NAL9602](INFO): Powering up NAL9602
2021-11-10T20:49:23.451Z,1636577363.451 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:23.853Z,1636577363.853 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:24.257Z,1636577364.257 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:24.661Z,1636577364.661 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:25.065Z,1636577365.065 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:25.469Z,1636577365.469 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:25.873Z,1636577365.873 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:26.277Z,1636577366.277 [DAT](DEBUG): checking for command mode acknowledgment
2021-11-10T20:49:26.277Z,1636577366.277 [DAT](FAULT): failed to enter command mode
2021-11-10T20:49:26.681Z,1636577366.681 [DAT](INFO): entering command mode
2021-11-10T20:49:27.086Z,1636577367.086 [DAT](INFO): setting verbose to 3
2021-11-10T20:49:27.490Z,1636577367.490 [DAT](INFO): set verbose to 3
2021-11-10T20:49:27.491Z,1636577367.491 [DAT](INFO): setting DatVerbose to 27440
2021-11-10T20:49:27.894Z,1636577367.894 [DAT](INFO): set DatVerbose to 27440
2021-11-10T20:49:27.895Z,1636577367.895 [DAT](INFO): setting transmit power to 8
2021-11-10T20:49:28.305Z,1636577368.305 [DAT](INFO): set transmit power to 8
2021-11-10T20:49:28.306Z,1636577368.306 [DAT](INFO): setting local address to 5
2021-11-10T20:49:28.702Z,1636577368.702 [DAT](INFO): set local address to 5
2021-11-10T20:49:34.357Z,1636577374.357 [NAL9602](INFO): NAL9602 initialized
2021-11-10T20:49:56.195Z,1636577396.195 [SBIT](IMPORTANT): Beginning Startup BIT
2021-11-10T20:49:56.199Z,1636577396.199 [CBIT](IMPORTANT): Beginning ground fault scan
2021-11-10T20:50:07.192Z,1636577407.192 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006472
CHAN A1 (24V): 0.169256
CHAN A2 (12V): -0.004005
CHAN A3 (5V): -0.002699
CHAN B0 (3.3V): -0.001228
CHAN B1 (3.15aV): -0.001203
CHAN B2 (3.15bV): -0.001013
CHAN B3 (GND): -0.000126
OPEN: 0.004618
Full Scale: +/- 1 mA
2021-11-10T20:50:49.996Z,1636577449.996 [SBIT](IMPORTANT): SBIT PASSED
2021-11-10T20:50:49.996Z,1636577449.996 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-11-10T20:50:49.997Z,1636577449.997 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=32 count;

2021-11-10T20:50:49.997Z,1636577449.997 [SBIT](IMPORTANT): BPC1.batterySamplingInterval=1 hour;

2021-11-10T20:50:49.997Z,1636577449.997 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2021-11-10T20:50:49.998Z,1636577449.998 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-11-10T20:50:49.998Z,1636577449.998 [SBIT](IMPORTANT): CBIT.gf24Offset=-18.106 microampere;

2021-11-10T20:50:49.998Z,1636577449.998 [SBIT](IMPORTANT): CBIT.stopDepth=295 meter;

2021-11-10T20:50:49.998Z,1636577449.998 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2021-11-10T20:50:49.998Z,1636577449.998 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2021-11-10T20:50:49.998Z,1636577449.998 [SBIT](IMPORTANT): HorizontalControl.rudDeadband=0.001 degree;

2021-11-10T20:50:49.998Z,1636577449.998 [SBIT](IMPORTANT): PAR_Licor.parCal=1.38207 none;

2021-11-10T20:50:49.998Z,1636577449.998 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=233.479799 cubic_centimeter;

2021-11-10T20:50:49.999Z,1636577449.999 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.001 degree;

2021-11-10T20:50:49.999Z,1636577449.999 [SBIT](IMPORTANT): VerticalControl.massDefault=10.270247 millimeter;

2021-11-10T20:50:50.346Z,1636577450.346 [MissionManager](IMPORTANT): Started mission Startup
2021-11-10T20:50:50.346Z,1636577450.346 [Startup] Running Loop=1
2021-11-10T20:50:50.346Z,1636577450.346 [Startup](DEBUG): Aggregate::initialize Startup
2021-11-10T20:50:50.346Z,1636577450.346 [Startup:A.GoToSurface] Running Loop=1
2021-11-10T20:50:50.346Z,1636577450.346 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-11-10T20:50:50.347Z,1636577450.347 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-11-10T20:50:50.348Z,1636577450.348 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-11-10T20:50:50.348Z,1636577450.348 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-11-10T20:50:50.348Z,1636577450.348 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-11-10T20:50:50.349Z,1636577450.349 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-11-10T20:50:50.351Z,1636577450.351 [Startup:StartupSatComms] Running Loop=1
2021-11-10T20:50:50.351Z,1636577450.351 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-11-10T20:50:50.352Z,1636577450.352 [Startup:StartupSatComms:A] Running Loop=1
2021-11-10T20:50:50.761Z,1636577450.761 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-11-10T20:51:23.064Z,1636577483.064 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:51:36.377Z,1636577496.377 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:51:49.705Z,1636577509.705 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:51:50.520Z,1636577510.520 [Startup:StartupSatComms:A](INFO): Timed out from 2021-11-10T20:50:50.4Z
2021-11-10T20:51:50.520Z,1636577510.520 [Startup:StartupSatComms:A] Stopped
2021-11-10T20:51:50.520Z,1636577510.520 [Startup:StartupSatComms:B] Running Loop=1
2021-11-10T20:51:50.940Z,1636577510.940 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-11-10T20:51:54.593Z,1636577514.593 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-11-10T20:51:54.593Z,1636577514.593 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T20:51:54.663Z,1636577514.663 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T20:51:55.035Z,1636577515.035 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T20:51:55.036Z,1636577515.036 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-11-10T20:52:03.835Z,1636577523.835 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:52:17.167Z,1636577537.167 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:52:18.294Z,1636577538.294 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004929
2021-11-10T20:52:28.297Z,1636577548.297 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211110T190305/Courier0049.lzma
2021-11-10T20:52:29.299Z,1636577549.299 [DataOverHttps](INFO): Moved sent file to Logs/20211110T190305/Courier0049.lzma.bak
2021-11-10T20:52:29.300Z,1636577549.300 [DataOverHttps](INFO): SBD MOMSN=16231884
2021-11-10T20:52:30.539Z,1636577550.539 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:52:44.273Z,1636577564.273 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:52:45.233Z,1636577565.233 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20211110T204844/Courier0000.lzma
2021-11-10T20:52:46.235Z,1636577566.235 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0000.lzma.bak
2021-11-10T20:52:46.235Z,1636577566.235 [DataOverHttps](INFO): SBD MOMSN=16231886
2021-11-10T20:52:50.779Z,1636577570.779 [Startup:StartupSatComms:B](INFO): Timed out from 2021-11-10T20:51:50.5Z
2021-11-10T20:52:50.779Z,1636577570.779 [Startup:StartupSatComms:B] Stopped
2021-11-10T20:52:50.779Z,1636577570.779 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-11-10T20:52:50.779Z,1636577570.779 [Startup:StartupSatComms] Stopped
2021-11-10T20:52:50.779Z,1636577570.779 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-11-10T20:52:50.780Z,1636577570.780 [Startup](INFO): Completed Startup
2021-11-10T20:52:50.780Z,1636577570.780 [MissionManager](INFO): Startup is completed.
2021-11-10T20:52:50.781Z,1636577570.781 [MissionManager](INFO): Uninitializing Mission Startup
2021-11-10T20:52:50.781Z,1636577570.781 [Startup] Stopped
2021-11-10T20:52:50.781Z,1636577570.781 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-11-10T20:52:50.781Z,1636577570.781 [Startup:A.GoToSurface] Stopped
2021-11-10T20:52:50.785Z,1636577570.785 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-11-10T20:52:51.179Z,1636577571.179 [MissionManager](IMPORTANT): Started mission Default
2021-11-10T20:52:51.179Z,1636577571.179 [Default] Running Loop=1
2021-11-10T20:52:51.179Z,1636577571.179 [Default](DEBUG): Aggregate::initialize Default
2021-11-10T20:52:51.179Z,1636577571.179 [Default:B.GoToSurface] Running Loop=1
2021-11-10T20:52:51.179Z,1636577571.179 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-11-10T20:52:51.180Z,1636577571.180 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-11-10T20:52:51.180Z,1636577571.180 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-11-10T20:52:51.180Z,1636577571.180 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-11-10T20:52:51.181Z,1636577571.181 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-11-10T20:52:51.201Z,1636577571.201 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-11-10T20:52:51.202Z,1636577571.202 [Default:A.Wait] Running Loop=1
2021-11-10T20:52:51.202Z,1636577571.202 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-11-10T20:52:57.648Z,1636577577.648 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:53:02.154Z,1636577582.154 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20211110T190305/Express0050.lzma
2021-11-10T20:53:03.155Z,1636577583.155 [DataOverHttps](INFO): Moved sent file to Logs/20211110T190305/Express0050.lzma.bak
2021-11-10T20:53:03.156Z,1636577583.156 [DataOverHttps](INFO): SBD MOMSN=16231891
2021-11-10T20:53:04.539Z,1636577584.539 [Default:A.Wait](INFO): Done Waiting.
2021-11-10T20:53:04.539Z,1636577584.539 [Default:A.Wait] Stopped
2021-11-10T20:53:04.539Z,1636577584.539 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T20:53:04.947Z,1636577584.947 [Default:CheckIn] Running Loop=1
2021-11-10T20:53:04.947Z,1636577584.947 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T20:53:04.947Z,1636577584.947 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T20:53:05.333Z,1636577585.333 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-11-10T20:53:10.978Z,1636577590.978 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:53:24.309Z,1636577604.309 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:53:37.644Z,1636577617.644 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T20:53:48.960Z,1636577628.960 [BPC1](INFO): Calculating totals. Valid battery stick count: 26. Valid reserve battery stick count: 4.
2021-11-10T20:53:48.964Z,1636577628.964 [BPC1](INFO): Received data from all battery sticks.
2021-11-10T20:54:37.440Z,1636577677.440 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-11-10T20:54:55.686Z,1636577695.686 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-11-10T20:54:55.686Z,1636577695.686 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T20:54:55.790Z,1636577695.790 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T20:54:56.167Z,1636577696.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T20:54:56.167Z,1636577696.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-11-10T20:55:25.553Z,1636577725.553 [DataOverHttps](IMPORTANT): SBD MTMSN=20211110T205524
2021-11-10T20:55:33.158Z,1636577733.158 [DataOverHttps](INFO): Received command: strobe off
2021-11-10T20:55:33.163Z,1636577733.163 [CommandExec](IMPORTANT): got command strobe off
2021-11-10T20:55:33.163Z,1636577733.163 [CommandExec](IMPORTANT): Deactivating strobe
2021-11-10T20:57:56.667Z,1636577876.667 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-11-10T20:57:56.667Z,1636577876.667 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T20:57:56.761Z,1636577876.761 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T20:57:57.154Z,1636577877.154 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T20:57:57.154Z,1636577877.154 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-11-10T20:58:05.180Z,1636577885.180 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-11-10T20:53:04.9Z
2021-11-10T20:58:05.180Z,1636577885.180 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T20:58:05.181Z,1636577885.181 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T20:58:05.589Z,1636577885.589 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-11-10T20:58:12.993Z,1636577892.993 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20211110T204844/Courier0004.lzma
2021-11-10T20:58:13.996Z,1636577893.996 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0004.lzma.bak
2021-11-10T20:58:13.996Z,1636577893.996 [DataOverHttps](INFO): SBD MOMSN=16231959
2021-11-10T20:58:33.007Z,1636577913.007 [DataOverHttps](INFO): Sending 1071 bytes from file Logs/20211110T204844/Express0001.lzma
2021-11-10T20:58:34.007Z,1636577914.007 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0001.lzma.bak
2021-11-10T20:58:34.007Z,1636577914.007 [DataOverHttps](INFO): SBD MOMSN=16231961
2021-11-10T20:58:53.170Z,1636577933.170 [DataOverHttps](INFO): Sending 299 bytes from file Logs/20211110T204844/Express0005.lzma
2021-11-10T20:58:54.171Z,1636577934.171 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0005.lzma.bak
2021-11-10T20:58:54.171Z,1636577934.171 [DataOverHttps](INFO): SBD MOMSN=16231987
2021-11-10T20:59:10.341Z,1636577950.341 [DataOverHttps](INFO): Sending 49 bytes from file Logs/20211110T204844/Express0008.lzma
2021-11-10T20:59:11.343Z,1636577951.343 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0008.lzma.bak
2021-11-10T20:59:11.343Z,1636577951.343 [DataOverHttps](INFO): SBD MOMSN=16231994
2021-11-10T20:59:12.803Z,1636577952.803 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T20:59:12.803Z,1636577952.803 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T20:59:12.803Z,1636577952.803 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T20:59:36.583Z,1636577976.583 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-11-10T20:59:36.583Z,1636577976.583 [NAL9602] Data Fault, FailCount= 1
2021-11-10T20:59:36.583Z,1636577976.583 [NAL9602](ERROR): Data Fault
2021-11-10T20:59:36.671Z,1636577976.671 [CBIT](ERROR): Data Fault in component: NAL9602
2021-11-10T20:59:36.994Z,1636577976.994 [NAL9602](INFO): Powering down
2021-11-10T20:59:37.874Z,1636577977.874 [CBIT](INFO): Clearing failed state for component NAL9602
2021-11-10T20:59:37.874Z,1636577977.874 [NAL9602] No Fault, FailCount= 1
2021-11-10T21:00:07.287Z,1636578007.287 [NAL9602](INFO): Powering up NAL9602
2021-11-10T21:00:18.191Z,1636578018.191 [NAL9602](INFO): NAL9602 initialized
2021-11-10T21:00:57.811Z,1636578057.811 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-11-10T21:00:57.811Z,1636578057.811 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:00:57.913Z,1636578057.913 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:00:58.290Z,1636578058.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:00:58.290Z,1636578058.290 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-11-10T21:03:58.824Z,1636578238.824 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-11-10T21:03:58.824Z,1636578238.824 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:03:58.898Z,1636578238.898 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:03:59.321Z,1636578239.321 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:03:59.322Z,1636578239.322 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-11-10T21:04:13.400Z,1636578253.400 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T21:04:13.400Z,1636578253.400 [Default:CheckIn:C.Wait] Stopped
2021-11-10T21:04:13.400Z,1636578253.400 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T21:04:13.400Z,1636578253.400 [Default:CheckIn:D] Running Loop=1
2021-11-10T21:04:13.768Z,1636578253.768 [Default:CheckIn:D] Stopped
2021-11-10T21:04:13.768Z,1636578253.768 [Default:CheckIn:E] Running Loop=1
2021-11-10T21:04:14.176Z,1636578254.176 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.376485 min 
2021-11-10T21:04:14.177Z,1636578254.177 [Default:CheckIn:E] Stopped
2021-11-10T21:04:14.177Z,1636578254.177 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T21:04:14.177Z,1636578254.177 [Default:CheckIn] Stopped
2021-11-10T21:04:14.177Z,1636578254.177 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T21:04:14.178Z,1636578254.178 [Default:CheckIn](INFO): Running loop #2
2021-11-10T21:04:14.178Z,1636578254.178 [Default:CheckIn] Running Loop=2
2021-11-10T21:04:14.178Z,1636578254.178 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T21:04:14.178Z,1636578254.178 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T21:06:59.839Z,1636578419.839 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-11-10T21:06:59.839Z,1636578419.839 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:06:59.938Z,1636578419.938 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:07:00.337Z,1636578420.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:07:00.338Z,1636578420.338 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-11-10T21:09:14.396Z,1636578554.396 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-11-10T21:04:14.2Z
2021-11-10T21:09:14.396Z,1636578554.396 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T21:09:14.396Z,1636578554.396 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T21:09:21.693Z,1636578561.693 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211110T204844/Courier0010.lzma
2021-11-10T21:09:22.695Z,1636578562.695 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0010.lzma.bak
2021-11-10T21:09:22.695Z,1636578562.695 [DataOverHttps](INFO): SBD MOMSN=16232024
2021-11-10T21:09:38.621Z,1636578578.621 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20211110T204844/Express0011.lzma
2021-11-10T21:09:39.623Z,1636578579.623 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0011.lzma.bak
2021-11-10T21:09:39.623Z,1636578579.623 [DataOverHttps](INFO): SBD MOMSN=16232026
2021-11-10T21:09:41.501Z,1636578581.501 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T21:09:41.501Z,1636578581.501 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T21:09:41.501Z,1636578581.501 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T21:10:00.876Z,1636578600.876 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-11-10T21:10:00.876Z,1636578600.876 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:10:00.966Z,1636578600.966 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:10:01.365Z,1636578601.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:10:01.366Z,1636578601.366 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-11-10T21:10:19.027Z,1636578619.027 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-11-10T21:10:19.027Z,1636578619.027 [NAL9602] Data Fault, FailCount= 2
2021-11-10T21:10:19.027Z,1636578619.027 [NAL9602](ERROR): Data Fault
2021-11-10T21:10:19.158Z,1636578619.158 [CBIT](ERROR): Data Fault in component: NAL9602
2021-11-10T21:10:19.431Z,1636578619.431 [NAL9602](INFO): Powering down
2021-11-10T21:10:20.425Z,1636578620.425 [CBIT](INFO): Clearing failed state for component NAL9602
2021-11-10T21:10:20.425Z,1636578620.425 [NAL9602] No Fault, FailCount= 2
2021-11-10T21:10:49.735Z,1636578649.735 [NAL9602](INFO): Powering up NAL9602
2021-11-10T21:11:00.650Z,1636578660.650 [NAL9602](INFO): NAL9602 initialized
2021-11-10T21:13:01.891Z,1636578781.891 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-11-10T21:13:01.891Z,1636578781.891 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:13:01.902Z,1636578781.902 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:13:02.310Z,1636578782.310 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:13:02.310Z,1636578782.310 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-11-10T21:14:42.092Z,1636578882.092 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T21:14:42.092Z,1636578882.092 [Default:CheckIn:C.Wait] Stopped
2021-11-10T21:14:42.092Z,1636578882.092 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T21:14:42.093Z,1636578882.093 [Default:CheckIn:D] Running Loop=1
2021-11-10T21:14:42.512Z,1636578882.512 [Default:CheckIn:D] Stopped
2021-11-10T21:14:42.512Z,1636578882.512 [Default:CheckIn:E] Running Loop=1
2021-11-10T21:14:42.920Z,1636578882.920 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.855542 min 
2021-11-10T21:14:42.921Z,1636578882.921 [Default:CheckIn:E] Stopped
2021-11-10T21:14:42.921Z,1636578882.921 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T21:14:42.921Z,1636578882.921 [Default:CheckIn] Stopped
2021-11-10T21:14:42.921Z,1636578882.921 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T21:14:42.921Z,1636578882.921 [Default:CheckIn](INFO): Running loop #3
2021-11-10T21:14:42.921Z,1636578882.921 [Default:CheckIn] Running Loop=3
2021-11-10T21:14:42.922Z,1636578882.922 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T21:14:42.922Z,1636578882.922 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T21:16:02.927Z,1636578962.927 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-11-10T21:16:02.927Z,1636578962.927 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:16:02.938Z,1636578962.938 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:16:03.345Z,1636578963.345 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:16:03.346Z,1636578963.346 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-11-10T21:19:04.011Z,1636579144.011 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-11-10T21:19:04.011Z,1636579144.011 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:19:04.022Z,1636579144.022 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:19:04.430Z,1636579144.430 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:19:04.430Z,1636579144.430 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-11-10T21:19:43.216Z,1636579183.216 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-11-10T21:14:42.9Z
2021-11-10T21:19:43.216Z,1636579183.216 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T21:19:43.216Z,1636579183.216 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T21:19:49.661Z,1636579189.661 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211110T204844/Courier0013.lzma
2021-11-10T21:19:50.663Z,1636579190.663 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0013.lzma.bak
2021-11-10T21:19:50.663Z,1636579190.663 [DataOverHttps](INFO): SBD MOMSN=16232092
2021-11-10T21:20:06.266Z,1636579206.266 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20211110T204844/Express0014.lzma
2021-11-10T21:20:07.264Z,1636579207.264 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0014.lzma.bak
2021-11-10T21:20:07.264Z,1636579207.264 [DataOverHttps](INFO): SBD MOMSN=16232109
2021-11-10T21:20:09.040Z,1636579209.040 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T21:20:09.040Z,1636579209.040 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T21:20:09.040Z,1636579209.040 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T21:21:03.179Z,1636579263.179 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-11-10T21:21:03.179Z,1636579263.179 [NAL9602] Data Fault, FailCount= 3
2021-11-10T21:21:03.179Z,1636579263.179 [NAL9602](ERROR): Data Fault
2021-11-10T21:21:03.225Z,1636579263.225 [CBIT](ERROR): Data Fault in component: NAL9602
2021-11-10T21:21:03.583Z,1636579263.583 [NAL9602](INFO): Powering down
2021-11-10T21:21:04.403Z,1636579264.403 [CBIT](INFO): Clearing failed state for component NAL9602
2021-11-10T21:21:04.403Z,1636579264.403 [NAL9602] No Fault, FailCount= 3
2021-11-10T21:21:33.883Z,1636579293.883 [NAL9602](INFO): Powering up NAL9602
2021-11-10T21:21:44.791Z,1636579304.791 [NAL9602](INFO): NAL9602 initialized
2021-11-10T21:22:04.999Z,1636579324.999 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-11-10T21:22:04.999Z,1636579324.999 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:22:05.010Z,1636579325.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:22:05.417Z,1636579325.417 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:22:05.417Z,1636579325.417 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-11-10T21:25:06.098Z,1636579506.098 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2021-11-10T21:25:06.099Z,1636579506.099 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:25:06.119Z,1636579506.119 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:25:06.472Z,1636579506.472 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:25:06.472Z,1636579506.472 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2021-11-10T21:25:09.680Z,1636579509.680 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T21:25:09.680Z,1636579509.680 [Default:CheckIn:C.Wait] Stopped
2021-11-10T21:25:09.680Z,1636579509.680 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T21:25:09.680Z,1636579509.680 [Default:CheckIn:D] Running Loop=1
2021-11-10T21:25:10.121Z,1636579510.121 [Default:CheckIn:D] Stopped
2021-11-10T21:25:10.121Z,1636579510.121 [Default:CheckIn:E] Running Loop=1
2021-11-10T21:25:10.511Z,1636579510.511 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.315688 min 
2021-11-10T21:25:10.512Z,1636579510.512 [Default:CheckIn:E] Stopped
2021-11-10T21:25:10.512Z,1636579510.512 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T21:25:10.512Z,1636579510.512 [Default:CheckIn] Stopped
2021-11-10T21:25:10.512Z,1636579510.512 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T21:25:10.512Z,1636579510.512 [Default:CheckIn](INFO): Running loop #4
2021-11-10T21:25:10.512Z,1636579510.512 [Default:CheckIn] Running Loop=4
2021-11-10T21:25:10.512Z,1636579510.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T21:25:10.512Z,1636579510.512 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T21:28:07.039Z,1636579687.039 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2021-11-10T21:28:07.039Z,1636579687.039 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:28:07.050Z,1636579687.050 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:28:07.473Z,1636579687.473 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:28:07.473Z,1636579687.473 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2021-11-10T21:30:10.677Z,1636579810.677 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-11-10T21:25:10.5Z
2021-11-10T21:30:10.677Z,1636579810.677 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T21:30:10.677Z,1636579810.677 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T21:30:17.249Z,1636579817.249 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211110T204844/Courier0016.lzma
2021-11-10T21:30:18.251Z,1636579818.251 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0016.lzma.bak
2021-11-10T21:30:18.251Z,1636579818.251 [DataOverHttps](INFO): SBD MOMSN=16232153
2021-11-10T21:30:36.261Z,1636579836.261 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20211110T204844/Express0017.lzma
2021-11-10T21:30:37.263Z,1636579837.263 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0017.lzma.bak
2021-11-10T21:30:37.263Z,1636579837.263 [DataOverHttps](INFO): SBD MOMSN=16232156
2021-11-10T21:30:39.014Z,1636579839.014 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T21:30:39.014Z,1636579839.014 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T21:30:39.014Z,1636579839.014 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T21:31:08.129Z,1636579868.129 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2021-11-10T21:31:08.129Z,1636579868.129 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:31:08.140Z,1636579868.140 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:31:08.554Z,1636579868.554 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:31:08.554Z,1636579868.554 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2021-11-10T21:31:46.515Z,1636579906.515 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-11-10T21:31:46.515Z,1636579906.515 [NAL9602] Data Fault, FailCount= 4
2021-11-10T21:31:46.515Z,1636579906.515 [NAL9602](ERROR): Data Fault
2021-11-10T21:31:46.534Z,1636579906.534 [CBIT](ERROR): Data Fault in component: NAL9602
2021-11-10T21:31:46.907Z,1636579906.907 [NAL9602](INFO): Powering down
2021-11-10T21:31:47.732Z,1636579907.732 [CBIT](INFO): Clearing failed state for component NAL9602
2021-11-10T21:31:47.732Z,1636579907.732 [NAL9602] No Fault, FailCount= 4
2021-11-10T21:32:17.204Z,1636579937.204 [NAL9602](INFO): Powering up NAL9602
2021-11-10T21:32:28.111Z,1636579948.111 [NAL9602](INFO): NAL9602 initialized
2021-11-10T21:34:09.175Z,1636580049.175 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2021-11-10T21:34:09.175Z,1636580049.175 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:34:09.186Z,1636580049.186 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:34:09.598Z,1636580049.598 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:34:09.598Z,1636580049.598 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2021-11-10T21:35:39.657Z,1636580139.657 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T21:35:39.657Z,1636580139.657 [Default:CheckIn:C.Wait] Stopped
2021-11-10T21:35:39.657Z,1636580139.657 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T21:35:39.657Z,1636580139.657 [Default:CheckIn:D] Running Loop=1
2021-11-10T21:35:40.063Z,1636580140.063 [Default:CheckIn:D] Stopped
2021-11-10T21:35:40.064Z,1636580140.064 [Default:CheckIn:E] Running Loop=1
2021-11-10T21:35:40.479Z,1636580140.479 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.814738 min 
2021-11-10T21:35:40.480Z,1636580140.480 [Default:CheckIn:E] Stopped
2021-11-10T21:35:40.480Z,1636580140.480 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T21:35:40.480Z,1636580140.480 [Default:CheckIn] Stopped
2021-11-10T21:35:40.480Z,1636580140.480 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T21:35:40.480Z,1636580140.480 [Default:CheckIn](INFO): Running loop #5
2021-11-10T21:35:40.480Z,1636580140.480 [Default:CheckIn] Running Loop=5
2021-11-10T21:35:40.480Z,1636580140.480 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T21:35:40.480Z,1636580140.480 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T21:37:10.197Z,1636580230.197 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2021-11-10T21:37:10.197Z,1636580230.197 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:37:10.266Z,1636580230.266 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:37:10.626Z,1636580230.626 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:37:10.626Z,1636580230.626 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2021-11-10T21:40:11.192Z,1636580411.192 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2021-11-10T21:40:11.192Z,1636580411.192 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:40:11.229Z,1636580411.229 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:40:11.594Z,1636580411.594 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:40:11.594Z,1636580411.594 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2021-11-10T21:40:40.676Z,1636580440.676 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-11-10T21:35:40.5Z
2021-11-10T21:40:40.676Z,1636580440.676 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T21:40:40.676Z,1636580440.676 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T21:40:46.610Z,1636580446.610 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211110T204844/Courier0019.lzma
2021-11-10T21:40:47.607Z,1636580447.607 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0019.lzma.bak
2021-11-10T21:40:47.607Z,1636580447.607 [DataOverHttps](INFO): SBD MOMSN=16232187
2021-11-10T21:41:03.685Z,1636580463.685 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20211110T204844/Express0020.lzma
2021-11-10T21:41:04.687Z,1636580464.687 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0020.lzma.bak
2021-11-10T21:41:04.688Z,1636580464.688 [DataOverHttps](INFO): SBD MOMSN=16232189
2021-11-10T21:41:06.584Z,1636580466.584 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T21:41:06.584Z,1636580466.584 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T21:41:06.584Z,1636580466.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T21:42:30.690Z,1636580550.690 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-11-10T21:42:30.691Z,1636580550.691 [NAL9602] Data Fault, FailCount= 5
2021-11-10T21:42:30.691Z,1636580550.691 [NAL9602](ERROR): Data Fault
2021-11-10T21:42:30.709Z,1636580550.709 [CBIT](ERROR): Data Fault in component: NAL9602
2021-11-10T21:42:30.710Z,1636580550.710 [CBIT](CRITICAL): Data Fault in component: NAL9602
2021-11-10T21:42:31.091Z,1636580551.091 [NAL9602](INFO): Powering down
2021-11-10T21:42:31.124Z,1636580551.124 [CommandExec](FAULT): Scheduling is paused
2021-11-10T21:42:31.124Z,1636580551.124 [CBIT](INFO): Critical error at 20211110T214230
2021-11-10T21:42:31.125Z,1636580551.125 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-11-10T21:43:12.322Z,1636580592.322 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2021-11-10T21:43:12.323Z,1636580592.323 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:43:12.354Z,1636580592.354 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:43:12.735Z,1636580592.735 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:43:12.735Z,1636580592.735 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2021-11-10T21:44:30.713Z,1636580670.713 [CBIT](INFO): Clearing failed state for component NAL9602
2021-11-10T21:44:30.713Z,1636580670.713 [NAL9602] No Fault, FailCount= 5
2021-11-10T21:44:31.091Z,1636580671.091 [NAL9602](INFO): Powering up NAL9602
2021-11-10T21:44:41.999Z,1636580681.999 [NAL9602](INFO): NAL9602 initialized
2021-11-10T21:46:07.280Z,1636580767.280 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T21:46:07.280Z,1636580767.280 [Default:CheckIn:C.Wait] Stopped
2021-11-10T21:46:07.280Z,1636580767.280 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T21:46:07.280Z,1636580767.280 [Default:CheckIn:D] Running Loop=1
2021-11-10T21:46:07.676Z,1636580767.676 [Default:CheckIn:D] Stopped
2021-11-10T21:46:07.676Z,1636580767.676 [Default:CheckIn:E] Running Loop=1
2021-11-10T21:46:08.085Z,1636580768.085 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.274943 min 
2021-11-10T21:46:08.085Z,1636580768.085 [Default:CheckIn:E] Stopped
2021-11-10T21:46:08.086Z,1636580768.086 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T21:46:08.086Z,1636580768.086 [Default:CheckIn] Stopped
2021-11-10T21:46:08.086Z,1636580768.086 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T21:46:08.086Z,1636580768.086 [Default:CheckIn](INFO): Running loop #6
2021-11-10T21:46:08.086Z,1636580768.086 [Default:CheckIn] Running Loop=6
2021-11-10T21:46:08.086Z,1636580768.086 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T21:46:08.086Z,1636580768.086 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T21:46:13.319Z,1636580773.319 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2021-11-10T21:46:13.319Z,1636580773.319 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:46:13.330Z,1636580773.330 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:46:13.739Z,1636580773.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:46:13.739Z,1636580773.739 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2021-11-10T21:49:14.416Z,1636580954.416 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2021-11-10T21:49:14.417Z,1636580954.417 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:49:14.427Z,1636580954.427 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:49:14.809Z,1636580954.809 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:49:14.809Z,1636580954.809 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2021-11-10T21:51:08.333Z,1636581068.333 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-11-10T21:46:08.1Z
2021-11-10T21:51:08.333Z,1636581068.333 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T21:51:08.333Z,1636581068.333 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T21:51:14.977Z,1636581074.977 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20211110T204844/Courier0022.lzma
2021-11-10T21:51:15.979Z,1636581075.979 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0022.lzma.bak
2021-11-10T21:51:15.979Z,1636581075.979 [DataOverHttps](INFO): SBD MOMSN=16232220
2021-11-10T21:51:31.485Z,1636581091.485 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20211110T204844/Express0023.lzma
2021-11-10T21:51:32.487Z,1636581092.487 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0023.lzma.bak
2021-11-10T21:51:32.487Z,1636581092.487 [DataOverHttps](INFO): SBD MOMSN=16232230
2021-11-10T21:51:34.181Z,1636581094.181 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T21:51:34.181Z,1636581094.181 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T21:51:34.181Z,1636581094.181 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T21:52:15.451Z,1636581135.451 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2021-11-10T21:52:15.451Z,1636581135.451 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:52:15.478Z,1636581135.478 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:52:15.909Z,1636581135.909 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:52:15.909Z,1636581135.909 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2021-11-10T21:54:43.243Z,1636581283.243 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-11-10T21:54:43.243Z,1636581283.243 [NAL9602] Data Fault, FailCount= 1
2021-11-10T21:54:43.243Z,1636581283.243 [NAL9602](ERROR): Data Fault
2021-11-10T21:54:43.290Z,1636581283.290 [CBIT](ERROR): Data Fault in component: NAL9602
2021-11-10T21:54:43.641Z,1636581283.641 [NAL9602](INFO): Powering down
2021-11-10T21:54:44.483Z,1636581284.483 [CBIT](INFO): Clearing failed state for component NAL9602
2021-11-10T21:54:44.483Z,1636581284.483 [NAL9602] No Fault, FailCount= 1
2021-11-10T21:55:14.021Z,1636581314.021 [NAL9602](INFO): Powering up NAL9602
2021-11-10T21:55:16.442Z,1636581316.442 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2021-11-10T21:55:16.442Z,1636581316.442 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:55:16.452Z,1636581316.452 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:55:16.863Z,1636581316.863 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:55:16.864Z,1636581316.864 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2021-11-10T21:55:24.919Z,1636581324.919 [NAL9602](INFO): NAL9602 initialized
2021-11-10T21:56:16.811Z,1636581376.811 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:56:30.149Z,1636581390.149 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:56:34.614Z,1636581394.614 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T21:56:34.614Z,1636581394.614 [Default:CheckIn:C.Wait] Stopped
2021-11-10T21:56:34.614Z,1636581394.614 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T21:56:34.614Z,1636581394.614 [Default:CheckIn:D] Running Loop=1
2021-11-10T21:56:35.027Z,1636581395.027 [Default:CheckIn:D] Stopped
2021-11-10T21:56:35.027Z,1636581395.027 [Default:CheckIn:E] Running Loop=1
2021-11-10T21:56:35.409Z,1636581395.409 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.730802 min 
2021-11-10T21:56:35.409Z,1636581395.409 [Default:CheckIn:E] Stopped
2021-11-10T21:56:35.409Z,1636581395.409 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T21:56:35.409Z,1636581395.409 [Default:CheckIn] Stopped
2021-11-10T21:56:35.410Z,1636581395.410 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T21:56:35.410Z,1636581395.410 [Default:CheckIn](INFO): Running loop #7
2021-11-10T21:56:35.410Z,1636581395.410 [Default:CheckIn] Running Loop=7
2021-11-10T21:56:35.410Z,1636581395.410 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T21:56:35.410Z,1636581395.410 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T21:56:43.069Z,1636581403.069 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:56:56.401Z,1636581416.401 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:57:09.329Z,1636581429.329 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:57:23.066Z,1636581443.066 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:57:36.398Z,1636581456.398 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:57:50.133Z,1636581470.133 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:58:03.465Z,1636581483.465 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:58:16.797Z,1636581496.797 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:58:17.716Z,1636581497.716 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2021-11-10T21:58:17.717Z,1636581497.717 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-10T21:58:17.737Z,1636581497.737 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-10T21:58:18.086Z,1636581498.086 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-10T21:58:18.086Z,1636581498.086 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2021-11-10T21:58:30.131Z,1636581510.131 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:58:43.461Z,1636581523.461 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-11-10T21:58:44.680Z,1636581524.680 [BPC1](INFO): Calculating totals. Valid battery stick count: 26. Valid reserve battery stick count: 4.
2021-11-10T21:58:44.684Z,1636581524.684 [BPC1](INFO): Received data from all battery sticks.
2021-11-10T22:00:32.145Z,1636581632.145 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220030.00,A,3648.17711,N,12147.28805,W,0.583,158.85,101121,,,A*7C
2021-11-10T22:00:32.149Z,1636581632.149 [NAL9602](INFO): GPS fix at 20211110T220030: (36.802952, -121.788134)
2021-11-10T22:00:32.160Z,1636581632.160 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T22:00:32.160Z,1636581632.160 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T22:00:41.573Z,1636581641.573 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20211110T204844/Courier0025.lzma
2021-11-10T22:00:42.575Z,1636581642.575 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0025.lzma.bak
2021-11-10T22:00:42.575Z,1636581642.575 [DataOverHttps](INFO): SBD MOMSN=16232287
2021-11-10T22:00:59.445Z,1636581659.445 [DataOverHttps](INFO): Sending 302 bytes from file Logs/20211110T204844/Express0026.lzma
2021-11-10T22:01:00.447Z,1636581660.447 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0026.lzma.bak
2021-11-10T22:01:00.447Z,1636581660.447 [DataOverHttps](INFO): SBD MOMSN=16232295
2021-11-10T22:01:02.058Z,1636581662.058 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T22:01:02.058Z,1636581662.058 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T22:01:02.058Z,1636581662.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T22:01:04.499Z,1636581664.499 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T22:06:02.761Z,1636581962.761 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T22:06:02.762Z,1636581962.762 [Default:CheckIn:C.Wait] Stopped
2021-11-10T22:06:02.762Z,1636581962.762 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T22:06:02.762Z,1636581962.762 [Default:CheckIn:D] Running Loop=1
2021-11-10T22:06:03.171Z,1636581963.171 [Default:CheckIn:D] Stopped
2021-11-10T22:06:03.171Z,1636581963.171 [Default:CheckIn:E] Running Loop=1
2021-11-10T22:06:03.584Z,1636581963.584 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.199862 min 
2021-11-10T22:06:03.584Z,1636581963.584 [Default:CheckIn:E] Stopped
2021-11-10T22:06:03.584Z,1636581963.584 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T22:06:03.584Z,1636581963.584 [Default:CheckIn] Stopped
2021-11-10T22:06:03.584Z,1636581963.584 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T22:06:03.584Z,1636581963.584 [Default:CheckIn](INFO): Running loop #8
2021-11-10T22:06:03.584Z,1636581963.584 [Default:CheckIn] Running Loop=8
2021-11-10T22:06:03.584Z,1636581963.584 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T22:06:03.585Z,1636581963.585 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T22:06:05.585Z,1636581965.585 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220603.00,A,3648.17787,N,12147.28413,W,0.097,60.30,101121,,,D*4F
2021-11-10T22:06:05.587Z,1636581965.587 [NAL9602](INFO): GPS fix at 20211110T220603: (36.802965, -121.788069)
2021-11-10T22:06:05.618Z,1636581965.618 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T22:06:05.618Z,1636581965.618 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T22:06:13.217Z,1636581973.217 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211110T204844/Courier0028.lzma
2021-11-10T22:06:14.219Z,1636581974.219 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0028.lzma.bak
2021-11-10T22:06:14.219Z,1636581974.219 [DataOverHttps](INFO): SBD MOMSN=16232302
2021-11-10T22:06:25.448Z,1636581985.448 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=6787, MT Status=1, MTMSN=436
2021-11-10T22:06:25.448Z,1636581985.448 [NAL9602](INFO): Data available in MT queue
2021-11-10T22:06:26.155Z,1636581986.155 [NAL9602](INFO): Received command: sched asap "load Engineering/sci2_flat_and_level_backseat.xml;set sci2_flat_and_level_backseat.MissionTimeout 4 h;set sci2_flat_and_level_backseat.Depth 20 m;set sci2_flat_and_level_backseat.Lat1 36.797 degree" 24u1y 1 2
2021-11-10T22:06:26.202Z,1636581986.202 [CommandExec](IMPORTANT): got command schedule asap "load Engineering/sci2_flat_and_level_backseat.xml;set sci2_flat_and_level_backseat.MissionTimeout 4 h;set sci2_flat_and_level_backseat.Depth 20 m;set sci2_flat_and_level_backseat.Lat1 36.797 degree" 24u1y 1 2.000000
2021-11-10T22:06:26.203Z,1636581986.203 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=24u1y
2021-11-10T22:06:26.204Z,1636581986.204 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='24u1y'): "load Engineering/sci2_flat_and_level_backseat.xml;set sci2_flat_and_level_backseat.MissionTimeout 4 h;set sci2_flat_and_level_backseat.Depth 20 m;set sci2_flat_and_level_backseat.Lat1 36.797 degree", ASAP
2021-11-10T22:06:34.381Z,1636581994.381 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20211110T204844/Express0029.lzma
2021-11-10T22:06:35.383Z,1636581995.383 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0029.lzma.bak
2021-11-10T22:06:35.383Z,1636581995.383 [DataOverHttps](INFO): SBD MOMSN=16232305
2021-11-10T22:06:37.182Z,1636581997.182 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T22:06:37.182Z,1636581997.182 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T22:06:37.182Z,1636581997.182 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T22:06:56.562Z,1636582016.562 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T22:11:37.908Z,1636582297.908 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T22:11:37.908Z,1636582297.908 [Default:CheckIn:C.Wait] Stopped
2021-11-10T22:11:37.909Z,1636582297.909 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T22:11:37.909Z,1636582297.909 [Default:CheckIn:D] Running Loop=1
2021-11-10T22:11:38.319Z,1636582298.319 [Default:CheckIn:D] Stopped
2021-11-10T22:11:38.319Z,1636582298.319 [Default:CheckIn:E] Running Loop=1
2021-11-10T22:11:38.738Z,1636582298.738 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.785661 min 
2021-11-10T22:11:38.739Z,1636582298.739 [Default:CheckIn:E] Stopped
2021-11-10T22:11:38.739Z,1636582298.739 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T22:11:38.739Z,1636582298.739 [Default:CheckIn] Stopped
2021-11-10T22:11:38.739Z,1636582298.739 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T22:11:38.739Z,1636582298.739 [Default:CheckIn](INFO): Running loop #9
2021-11-10T22:11:38.739Z,1636582298.739 [Default:CheckIn] Running Loop=9
2021-11-10T22:11:38.739Z,1636582298.739 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T22:11:38.739Z,1636582298.739 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T22:11:40.727Z,1636582300.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221139.00,A,3648.17575,N,12147.28632,W,0.019,60.30,101121,,,D*48
2021-11-10T22:11:40.729Z,1636582300.729 [NAL9602](INFO): GPS fix at 20211110T221139: (36.802929, -121.788105)
2021-11-10T22:11:40.741Z,1636582300.741 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T22:11:40.741Z,1636582300.741 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T22:11:48.529Z,1636582308.529 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20211110T204844/Courier0031.lzma
2021-11-10T22:11:49.531Z,1636582309.531 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0031.lzma.bak
2021-11-10T22:11:49.531Z,1636582309.531 [DataOverHttps](INFO): SBD MOMSN=16232323
2021-11-10T22:12:05.502Z,1636582325.502 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20211110T204844/Express0032.lzma
2021-11-10T22:12:06.503Z,1636582326.503 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0032.lzma.bak
2021-11-10T22:12:06.504Z,1636582326.504 [DataOverHttps](INFO): SBD MOMSN=16232326
2021-11-10T22:12:08.224Z,1636582328.224 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T22:12:08.224Z,1636582328.224 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T22:12:08.224Z,1636582328.224 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T22:12:27.592Z,1636582347.592 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=6788, MT Status=1, MTMSN=437
2021-11-10T22:12:27.592Z,1636582347.592 [NAL9602](INFO): Data available in MT queue
2021-11-10T22:12:28.318Z,1636582348.318 [NAL9602](INFO): Received command: sched asap "set sci2_flat_and_level_backseat.Lon1 -121.847 degree;set sci2_flat_and_level_backseat.Speed 0.85 m/s;set sci2_flat_and_level_backseat.ApproachPitchLimit 10 degree;set sci2_flat_and_level_backseat.MaxDepth 50 m;run" 24u1y 2 2
2021-11-10T22:12:28.358Z,1636582348.358 [CommandExec](IMPORTANT): got command schedule asap "set sci2_flat_and_level_backseat.Lon1 -121.847 degree;set sci2_flat_and_level_backseat.Speed 0.85 m/s;set sci2_flat_and_level_backseat.ApproachPitchLimit 10 degree;set sci2_flat_and_level_backseat.MaxDepth 50 m;run" 24u1y 2 2.000000
2021-11-10T22:12:28.358Z,1636582348.358 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=24u1y
2021-11-10T22:12:28.359Z,1636582348.359 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='24u1y'): "set sci2_flat_and_level_backseat.Lon1 -121.847 degree;set sci2_flat_and_level_backseat.Speed 0.85 m/s;set sci2_flat_and_level_backseat.ApproachPitchLimit 10 degree;set sci2_flat_and_level_backseat.MaxDepth 50 m;run", ASAP
2021-11-10T22:12:28.568Z,1636582348.568 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/sci2_flat_and_level_backseat.xml
2021-11-10T22:12:28.569Z,1636582348.569 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/sci2_flat_and_level_backseat.xml
2021-11-10T22:12:28.843Z,1636582348.843 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.MissionTimeout = 2.000000 h
2021-11-10T22:12:28.850Z,1636582348.850 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.NeedCommsTime = 60.000000 min
2021-11-10T22:12:28.858Z,1636582348.858 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Depth = 3.000000 m
2021-11-10T22:12:28.861Z,1636582348.861 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Repeat = 1.000000 count
2021-11-10T22:12:28.868Z,1636582348.868 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lat1 = nan arcdeg
2021-11-10T22:12:28.871Z,1636582348.871 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lon1 = nan arcdeg
2021-11-10T22:12:28.882Z,1636582348.882 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lat2 = nan arcdeg
2021-11-10T22:12:28.935Z,1636582348.935 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lon2 = nan arcdeg
2021-11-10T22:12:28.946Z,1636582348.946 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lat3 = nan arcdeg
2021-11-10T22:12:28.966Z,1636582348.966 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lon3 = nan arcdeg
2021-11-10T22:12:28.969Z,1636582348.969 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lat4 = nan arcdeg
2021-11-10T22:12:28.980Z,1636582348.980 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lon4 = nan arcdeg
2021-11-10T22:12:28.983Z,1636582348.983 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lat5 = nan arcdeg
2021-11-10T22:12:28.987Z,1636582348.987 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lon5 = nan arcdeg
2021-11-10T22:12:29.002Z,1636582349.002 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lat6 = nan arcdeg
2021-11-10T22:12:29.005Z,1636582349.005 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lon6 = nan arcdeg
2021-11-10T22:12:29.019Z,1636582349.019 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lat7 = nan arcdeg
2021-11-10T22:12:29.024Z,1636582349.024 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Lon7 = nan arcdeg
2021-11-10T22:12:29.036Z,1636582349.036 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.Speed = 1.000000 m/s
2021-11-10T22:12:29.040Z,1636582349.040 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.ApproachDepth = 2.000000 m
2021-11-10T22:12:29.052Z,1636582349.052 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.ApproachSpeed = 0.700000 m/s
2021-11-10T22:12:29.066Z,1636582349.066 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.ApproachDepthRate = 0.050000 m/s
2021-11-10T22:12:29.074Z,1636582349.074 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.ApproachPitchLimit = 20.000000 arcdeg
2021-11-10T22:12:29.080Z,1636582349.080 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.ApproachDepthTimeout = 15.000000 min
2021-11-10T22:12:29.083Z,1636582349.083 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.ApproachSettleTimePreDive = 1.000000 min
2021-11-10T22:12:29.087Z,1636582349.087 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.ApproachSettleTimePostDive = 3.000000 min
2021-11-10T22:12:29.102Z,1636582349.102 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.kpDepth = 0.070000 rad/m
2021-11-10T22:12:29.108Z,1636582349.108 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.BuoyancyNeutral = 233.479805 cc
2021-11-10T22:12:29.112Z,1636582349.112 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.MassDefault = 1.027025 cm
2021-11-10T22:12:29.124Z,1636582349.124 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.MinAltitude = 2.000000 m
2021-11-10T22:12:29.128Z,1636582349.128 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.MaxDepth = 15.000000 m
2021-11-10T22:12:29.139Z,1636582349.139 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat.MinOffshore = 2.000000 km
2021-11-10T22:12:29.142Z,1636582349.142 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat.DiveMode = 0.000000 count
2021-11-10T22:12:29.154Z,1636582349.154 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat.DoingComms = 0.000000 count
2021-11-10T22:12:29.161Z,1636582349.161 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat.InitialDive = 1.000000 count
2021-11-10T22:12:29.168Z,1636582349.168 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat.SettleAtDepth = 2.000000 count
2021-11-10T22:12:29.172Z,1636582349.172 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat.MaintainDepth = 3.000000 count
2021-11-10T22:12:29.172Z,1636582349.172 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-11-10T22:12:29.762Z,1636582349.762 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.PeakDetectChlActive = 0 bool
2021-11-10T22:12:29.765Z,1636582349.765 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.TimeWindowPeakReport = nan min
2021-11-10T22:12:29.776Z,1636582349.776 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.HighestChlPeakReportActive = 0 bool
2021-11-10T22:12:29.779Z,1636582349.779 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.HighestSaltPeakReportActive = 0 bool
2021-11-10T22:12:29.790Z,1636582349.790 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.HighestOilPeakReportActive = 0 bool
2021-11-10T22:12:29.795Z,1636582349.795 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.PatchTracking = 0 bool
2021-11-10T22:12:29.798Z,1636582349.798 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.DetectTrough = 0 bool
2021-11-10T22:12:29.801Z,1636582349.801 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.FilterWidthHorizontal = 3.000000 count
2021-11-10T22:12:29.803Z,1636582349.803 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.NumProfilesSlidingwindow = 100.000000 count
2021-11-10T22:12:29.806Z,1636582349.806 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.OffPeakFractionHorizontal = 80.000000 %
2021-11-10T22:12:29.821Z,1636582349.821 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.PeakDetectNO3Active = 0 bool
2021-11-10T22:12:29.824Z,1636582349.824 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.PeakDetectOilActive = 0 bool
2021-11-10T22:12:29.827Z,1636582349.827 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.PeakDetectFDOMActive = 0 bool
2021-11-10T22:12:29.829Z,1636582349.829 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.PeakDetectSalinityActive = 0 bool
2021-11-10T22:12:29.852Z,1636582349.852 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-11-10T22:12:29.856Z,1636582349.856 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.EnabledAanderaaO2 = 0.000000 bool
2021-11-10T22:12:29.895Z,1636582349.895 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.EnabledNeilBrown = 0.000000 bool
2021-11-10T22:12:29.898Z,1636582349.898 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.EnabledSeabird = 1.000000 bool
2021-11-10T22:12:29.910Z,1636582349.910 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-11-10T22:12:29.914Z,1636582349.914 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-11-10T22:12:29.934Z,1636582349.934 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-11-10T22:12:29.937Z,1636582349.937 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.LowPassWindowLength = 20.000000 count
2021-11-10T22:12:29.940Z,1636582349.940 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.MedianFilterLen = 5.000000 count
2021-11-10T22:12:29.953Z,1636582349.953 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.PeakShallowBound = nan m
2021-11-10T22:12:29.956Z,1636582349.956 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.PeakDeepBound = nan m
2021-11-10T22:12:29.987Z,1636582349.987 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2021-11-10T22:12:30.026Z,1636582350.026 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakChl = 0.000000 ug/l
2021-11-10T22:12:30.045Z,1636582350.045 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakChlDepth = 0.000000 m
2021-11-10T22:12:30.048Z,1636582350.048 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakChlTemperature = 0.000000 degC
2021-11-10T22:12:30.052Z,1636582350.052 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakChlLatitude = nan arcdeg
2021-11-10T22:12:30.075Z,1636582350.075 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakChlLongitude = nan arcdeg
2021-11-10T22:12:30.078Z,1636582350.078 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakNO3 = 0.000000 umol/l
2021-11-10T22:12:30.085Z,1636582350.085 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakNO3Depth = 0.000000 m
2021-11-10T22:12:30.104Z,1636582350.104 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakNO3Latitude = nan arcdeg
2021-11-10T22:12:30.107Z,1636582350.107 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakNO3Longitude = nan arcdeg
2021-11-10T22:12:30.131Z,1636582350.131 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakOil = 0.000000 kg/m3
2021-11-10T22:12:30.134Z,1636582350.134 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakOilDepth = 0.000000 m
2021-11-10T22:12:30.137Z,1636582350.137 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakOilLatitude = nan arcdeg
2021-11-10T22:12:30.140Z,1636582350.140 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakOilLongitude = nan arcdeg
2021-11-10T22:12:30.154Z,1636582350.154 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakFDOM = 0.000000 ppb
2021-11-10T22:12:30.157Z,1636582350.157 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakFDOMDepth = 0.000000 m
2021-11-10T22:12:30.173Z,1636582350.173 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakFDOMTemperature = nan degC
2021-11-10T22:12:30.180Z,1636582350.180 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakFDOMLatitude = nan arcdeg
2021-11-10T22:12:30.183Z,1636582350.183 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakFDOMLongitude = nan arcdeg
2021-11-10T22:12:30.195Z,1636582350.195 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchFDOMDistance = nan m
2021-11-10T22:12:30.202Z,1636582350.202 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakSalt = 0.000000 psu
2021-11-10T22:12:30.209Z,1636582350.209 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakSaltDepth = 0.000000 m
2021-11-10T22:12:30.212Z,1636582350.212 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakSaltTemperature = 0.000000 degC
2021-11-10T22:12:30.216Z,1636582350.216 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakSaltLatitude = nan arcdeg
2021-11-10T22:12:30.231Z,1636582350.231 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PeakSaltLongitude = nan arcdeg
2021-11-10T22:12:30.239Z,1636582350.239 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchChl = 0.000000 ug/l
2021-11-10T22:12:30.246Z,1636582350.246 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchChlDepth = 0.000000 m
2021-11-10T22:12:30.259Z,1636582350.259 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchChlLatitude = nan arcdeg
2021-11-10T22:12:30.262Z,1636582350.262 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchChlLongitude = nan arcdeg
2021-11-10T22:12:30.273Z,1636582350.273 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchChlDistance = nan m
2021-11-10T22:12:30.277Z,1636582350.277 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchSalt = 0.000000 psu
2021-11-10T22:12:30.284Z,1636582350.284 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchSaltDepth = 0.000000 m
2021-11-10T22:12:30.289Z,1636582350.289 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchSaltLatitude = nan arcdeg
2021-11-10T22:12:30.300Z,1636582350.300 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchSaltLongitude = nan arcdeg
2021-11-10T22:12:30.308Z,1636582350.308 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchSaltDistance = nan m
2021-11-10T22:12:30.315Z,1636582350.315 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchOil = 0.000000 psu
2021-11-10T22:12:30.323Z,1636582350.323 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchOilDepth = 0.000000 m
2021-11-10T22:12:30.330Z,1636582350.330 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchOilLatitude = nan arcdeg
2021-11-10T22:12:30.338Z,1636582350.338 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchOilLongitude = nan arcdeg
2021-11-10T22:12:30.341Z,1636582350.341 [MissionManager](INFO): DefineOutput sci2_flat_and_level_backseat:Science.PatchOilDistance = nan m
2021-11-10T22:12:30.439Z,1636582350.439 [sci2_flat_and_level_backseat:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-11-10T22:12:30.502Z,1636582350.502 [sci2_flat_and_level_backseat:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-11-10T22:12:30.527Z,1636582350.527 [sci2_flat_and_level_backseat:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-11-10T22:12:30.554Z,1636582350.554 [sci2_flat_and_level_backseat:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-11-10T22:12:30.571Z,1636582350.571 [sci2_flat_and_level_backseat:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-11-10T22:12:30.588Z,1636582350.588 [sci2_flat_and_level_backseat:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-11-10T22:12:30.623Z,1636582350.623 [sci2_flat_and_level_backseat:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-11-10T22:12:30.636Z,1636582350.636 [sci2_flat_and_level_backseat:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-11-10T22:12:30.715Z,1636582350.715 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2021-11-10T22:12:30.764Z,1636582350.764 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:StandardEnvelopes.MinAltitude = 5.000000 m
2021-11-10T22:12:30.767Z,1636582350.767 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:StandardEnvelopes.MaxDepth = 200.000000 m
2021-11-10T22:12:30.786Z,1636582350.786 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:StandardEnvelopes.MinOffshore = 2000.000000 m
2021-11-10T22:12:30.787Z,1636582350.787 [sci2_flat_and_level_backseat:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-11-10T22:12:30.831Z,1636582350.831 [sci2_flat_and_level_backseat:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-11-10T22:12:30.861Z,1636582350.861 [sci2_flat_and_level_backseat:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2021-11-10T22:12:30.960Z,1636582350.960 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2021-11-10T22:12:31.161Z,1636582351.161 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:NeedComms.DiveInterval = 3.000000 h
2021-11-10T22:12:31.164Z,1636582351.164 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:NeedComms.WaitForPitchUp = 10.000000 min
2021-11-10T22:12:31.191Z,1636582351.191 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:NeedComms.SurfacePitch = 20.000000 arcdeg
2021-11-10T22:12:31.194Z,1636582351.194 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:NeedComms.SurfaceDepthRate = nan m/s
2021-11-10T22:12:31.197Z,1636582351.197 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:NeedComms.SurfaceSpeed = 1.000000 m/s
2021-11-10T22:12:31.248Z,1636582351.248 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:NeedComms.GPSTimeout = 7.000000 min
2021-11-10T22:12:31.265Z,1636582351.265 [MissionManager](INFO): DefineArg sci2_flat_and_level_backseat:NeedComms.CommsTimeout = 30.000000 min
2021-11-10T22:12:31.323Z,1636582351.323 [sci2_flat_and_level_backseat:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-10T22:12:31.432Z,1636582351.432 [sci2_flat_and_level_backseat:DiveCmd:InitialDive:dive:C.Mass](DEBUG): Construct.
2021-11-10T22:12:31.439Z,1636582351.439 [sci2_flat_and_level_backseat:DiveCmd:InitialDive:dive:D.Buoyancy](DEBUG): Construct Buoyancy.
2021-11-10T22:12:31.456Z,1636582351.456 [sci2_flat_and_level_backseat:DiveCmd:InitialDive:dive:F.Wait](DEBUG): Construct Wait.
2021-11-10T22:12:31.468Z,1636582351.468 [sci2_flat_and_level_backseat:DiveCmd:InitialDive:dive:ApproachDepth:A.SetSpeed](DEBUG): Construct.
2021-11-10T22:12:31.476Z,1636582351.476 [sci2_flat_and_level_backseat:DiveCmd:InitialDive:dive:ApproachDepth:B.Pitch](DEBUG): Construct.
2021-11-10T22:12:31.514Z,1636582351.514 [sci2_flat_and_level_backseat:DiveCmd:InitialDive:dive:ApproachDepth:B.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2021-11-10T22:12:31.531Z,1636582351.531 [sci2_flat_and_level_backseat:DiveCmd:SettleAtDepth:dive:B.Pitch](DEBUG): Construct.
2021-11-10T22:12:31.554Z,1636582351.554 [sci2_flat_and_level_backseat:DiveCmd:SettleAtDepth:dive:C.Wait](DEBUG): Construct Wait.
2021-11-10T22:12:31.578Z,1636582351.578 [sci2_flat_and_level_backseat:DiveCmd:MaintainDepth:dive:ThrusterDive:A.Mass](DEBUG): Construct.
2021-11-10T22:12:31.581Z,1636582351.581 [sci2_flat_and_level_backseat:DiveCmd:MaintainDepth:dive:ThrusterDive:B.Buoyancy](DEBUG): Construct Buoyancy.
2021-11-10T22:12:31.584Z,1636582351.584 [sci2_flat_and_level_backseat:DiveCmd:MaintainDepth:dive:B.SetSpeed](DEBUG): Construct.
2021-11-10T22:12:31.627Z,1636582351.627 [sci2_flat_and_level_backseat:DiveCmd:MaintainDepth:dive:C.Pitch](DEBUG): Construct.
2021-11-10T22:12:31.668Z,1636582351.668 [sci2_flat_and_level_backseat:M.BackseatDriver](DEBUG): Construct BackseatDriver.
2021-11-10T22:12:31.708Z,1636582351.708 [sci2_flat_and_level_backseat:Lap:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2021-11-10T22:12:31.732Z,1636582351.732 [sci2_flat_and_level_backseat:Lap:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2021-11-10T22:12:31.755Z,1636582351.755 [sci2_flat_and_level_backseat:Lap:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2021-11-10T22:12:31.783Z,1636582351.783 [sci2_flat_and_level_backseat:Lap:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2021-11-10T22:12:31.806Z,1636582351.806 [sci2_flat_and_level_backseat:Lap:Wpt5:A.Waypoint](DEBUG): Construct Waypoint.
2021-11-10T22:12:31.841Z,1636582351.841 [sci2_flat_and_level_backseat:Lap:Wpt6:A.Waypoint](DEBUG): Construct Waypoint.
2021-11-10T22:12:31.848Z,1636582351.848 [sci2_flat_and_level_backseat:Lap:Wpt7:A.Waypoint](DEBUG): Construct Waypoint.
2021-11-10T22:12:31.997Z,1636582351.997 [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:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="sci2_flat_and_level_backseat">

    <Description>
        Vehicle transits to the specified waypoints at the commanded depth
        (flat-and-level) with science turned on.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>2</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        How often to surface for commumications
    </Description><Units:minute/><Value>60</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Depth of flat and level flight during the mission.
    </Description><Units:meter /><Value>3</Value></DefineArg>

    <DefineArg Name="Repeat"><Description>
        Number of times the vehicle should try to cycle through waypoints.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="Lat1"><Description>
        Latitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon1"><Description>
        Longitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat2"><Description>
        Latitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon2"><Description>
        Longitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat3"><Description>
        Latitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon3"><Description>
        Longitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat4"><Description>
        Latitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon4"><Description>
        Longitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat5"><Description>
        Latitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon5"><Description>
        Longitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat6"><Description>
        Latitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon6"><Description>
        Longitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat7"><Description>
        Latitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon7"><Description>
        Longitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Speed while transiting to waypoints.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="ApproachDepth"><Description>
        Depth for initial descent form the surface.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="ApproachSpeed"><Description>
        Speed while descending to target depth.
    </Description><Units:meter_per_second/><Value>0.7</Value></DefineArg>

    <DefineArg Name="ApproachDepthRate"><Description>
        Descent depth rate (Positive depth rate means going down).
    </Description><Units:meter_per_second/><Value>0.05</Value></DefineArg>

    <DefineArg Name="ApproachPitchLimit"><Description>
        Max vehicle pitch (+/-) when decending from the surface.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="ApproachDepthTimeout"><Description>
        Maximum time duration for the vehicle to reach the target depth
    </Description><Units:minute/><Value>15</Value></DefineArg>

    <DefineArg Name="ApproachSettleTimePreDive"><Description>
        Time duration for the vehicle to pump down the VBS *BEFORE* leaving the
        surface.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="ApproachSettleTimePostDive"><Description>
        Time duration for the vehicle to settle *AFTER* reaching the target
        depth.
    </Description><Units:minute/><Value>3</Value></DefineArg>

    <DefineArg Name="kpDepth"><Description>
        Dive loop proportional gain (applied only while the vehicle is
        descending to target depth).
    </Description><Units:radian_per_meter/><Value>0.07</Value></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position during the mission. Defaults to
        buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc
        for active buoyancy.
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission. Set to NaN mm for active
        mass position.
    </Description><Control:VerticalControl.massDefault/></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>15</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

    <DefineOutput Name="DiveMode"><Description>
        Mission variable (don't change). The mission sets this variable to
        switch between dive modes. Initialized to DoingComms.
    </Description><Units:count/><Value>0</Value></DefineOutput>

    <DefineOutput Name="DoingComms"><Description>
        Mission variable (don't change). The mission will run in this to mode
        when doing surface comms.
    </Description><Units:count/><Value>0</Value></DefineOutput>

    <DefineOutput Name="InitialDive"><Description>
        Mission variable (don't change). The mission will run in this mode after
        surface comms are done to dive the vehicle to the approach depth.
    </Description><Units:count/><Value>1</Value></DefineOutput>

    <DefineOutput Name="SettleAtDepth"><Description>
        Mission variable (don't change). The mission will run in this mode to
        settle the vehicle at the target depth.
    </Description><Units:count/><Value>2</Value></DefineOutput>

    <DefineOutput Name="MaintainDepth"><Description>
        Mission variable (don't change). The mission will run in this mode to
        maintain the vehicle at the target depth.
    </Description><Units:count/><Value>3</Value></DefineOutput>

<!-- Mission timeout -->

    <Timeout Duration="MissionTimeout"/>

<!-- Science! -->

    <Insert Filename="Insert/Science.xml">
        <Description>
            Run the science sensors.
        </Description>
    </Insert>

<!-- Safety nets and surfacing behaviors -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="MissionTimeout"/></Assign>

    <Assign><Sequence/><Arg Name="NeedComms:WaitForPitchUp"/><Units:minute/><Value>0</Value></Assign>

    <Aggregate Id="SurfaceComms">

        <When>
            <Called/>
            <Or><Elapsed><Universal:time_fix/></Elapsed><Gt><Arg Name="NeedCommsTime"/></Gt></Or>
        </When>

        <Assign><Sequence/><Arg Name="DiveMode"/><Arg Name="DoingComms"/></Assign>

        <Call RefId="NeedComms"/>

        <Assign><Sequence/><Arg Name="DiveMode"/><Arg Name="InitialDive"/></Assign>

    </Aggregate>

    <Aggregate Id="DiveCmd">

        <Description>
            Called when the vehicle should be underwater. Arbitrates between 3
            dive modes: 1) descend from the surface to initial depth. 2) settle
            at target depth using the VB. 3) cruise at target depth.
        </Description>

        <When>
            <Called/>
        </When>

        <Aggregate Id="InitialDive">

            <Description>
                Descend from the surface to target depth, limit pitch and
                depth-rate.
            </Description>

            <While>
                <Arg Name="DiveMode"/>
                <Eq><Arg Name="InitialDive"/></Eq>
            </While>

            <Aggregate Id="dive">

                <Sequence/>

                <Assign><Parallel/><Control:VerticalControl.pitchLimit/><Arg Name="ApproachPitchLimit"/></Assign>

                <Assign><Parallel/><Control:VerticalControl.kpDepth/><Arg Name="kpDepth"/></Assign>

                <Guidance:Mass>
                    <Parallel/>
                    <Setting><Guidance:Mass.position/><Arg Name="MassDefault"/></Setting>
                </Guidance:Mass>

                <Guidance:Buoyancy>
                    <Parallel/>
                    <Setting><Guidance:Buoyancy.position/><Arg Name="BuoyancyNeutral"/></Setting>
                </Guidance:Buoyancy>

                <Syslog Severity="Info">Waiting for <Arg Name="ApproachSettleTimePreDive"/><Units:minute/>while pumping down to neutral.</Syslog>

                <Guidance:Wait>
                    <Description>
                        Take a few moments to ensure the VBS reaches neutral.
                    </Description>
                    <Sequence/>
                    <Setting><Guidance:Wait.duration/><Arg Name="ApproachSettleTimePreDive"/></Setting>
                </Guidance:Wait>

                <Syslog>Moving to <Arg Name="ApproachDepth"/><Units:meter/></Syslog>

                <Aggregate Id="ApproachDepth">

                    <Sequence/>

                    <Guidance:SetSpeed>
                        <Parallel/>
                        <Setting><Guidance:SetSpeed.speed/><Arg Name="ApproachSpeed"/></Setting>
                    </Guidance:SetSpeed>

                    <Guidance:Pitch>
                        <Sequence/>
                        <Timeout Duration="ApproachDepthTimeout">
                            <Syslog Severity="Important">Timed out while trying to reach target depth at current depth of <Universal:depth/><Units:meter/></Syslog>
                            <Guidance:Execute>
                                <Sequence/>
                                <Setting><Guidance:Execute.command/><String>stop</String></Setting>
                            </Guidance:Execute>
                        </Timeout>
                        <Setting><Guidance:Pitch.depth/><Arg Name="ApproachDepth"/></Setting>
                        <Setting><Guidance:Pitch.depthRate/><Arg Name="ApproachDepthRate"/></Setting>
                    </Guidance:Pitch>

                </Aggregate>

                <Assign><Sequence/><Arg Name="DiveMode"/><Arg Name="SettleAtDepth"/></Assign>

            </Aggregate>

        </Aggregate>

        <Aggregate Id="SettleAtDepth">

            <Description>
                Take a few more moments here to make sure the vechile is settled
                at the target depth.
            </Description>

            <While>
                <Arg Name="DiveMode"/>
                <Eq><Arg Name="SettleAtDepth"/></Eq>
            </While>

            <Aggregate Id="dive">

                <Sequence/>

                <Syslog Severity="Info">Reached <Universal:depth/><Units:meter/>, settling for <Arg Name="ApproachSettleTimePostDive"/><Units:minute/></Syslog>

                <Guidance:Pitch>
                    <Parallel/>
                    <Setting><Guidance:Pitch.depth/><Arg Name="Depth"/></Setting>
                </Guidance:Pitch>

                <Guidance:Wait>
                    <Sequence/>
                    <Setting><Guidance:Wait.duration/><Arg Name="ApproachSettleTimePostDive"/></Setting>
                </Guidance:Wait>

                <Assign><Sequence/><Arg Name="DiveMode"/><Arg Name="MaintainDepth"/></Assign>

            </Aggregate>

        </Aggregate>

        <Aggregate Id="MaintainDepth">

            <Description>
                Cruise at the target depth.
            </Description>

            <While>
                <Arg Name="DiveMode"/>
                <Eq><Arg Name="MaintainDepth"/></Eq>
            </While>

            <Aggregate Id="dive">

                <Parallel/>

                <Aggregate Id="ThrusterDive">

                    <While>
                        <Arg Name="Speed"/>
                        <Gt><Units:meter_per_second/><Value>0.0</Value></Gt>
                    </While>

                    <Guidance:Mass>
                        <Parallel/>
                        <Setting><Guidance:Mass.position/><Arg Name="MassDefault"/></Setting>
                    </Guidance:Mass>

                    <Guidance:Buoyancy>
                        <Parallel/>
                        <Setting><Guidance:Buoyancy.position/><Arg Name="BuoyancyNeutral"/></Setting>
                    </Guidance:Buoyancy>

                </Aggregate>

                <Guidance:SetSpeed>
                    <Parallel/>
                    <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
                </Guidance:SetSpeed>

                <Guidance:Pitch>
                    <Parallel/>
                    <Setting><Guidance:Pitch.depth/><Arg Name="Depth"/></Setting>
                </Guidance:Pitch>

            </Aggregate>

        </Aggregate>

    </Aggregate>

<!-- Start mission sequence -->

    <Call Id="StartingMission" RefId="SurfaceComms"/>


    <Syslog>Powering up backseat...</Syslog>

    <Guidance:BackseatDriver>
        <Parallel/>
    </Guidance:BackseatDriver>

    <Syslog>Backseat powered.</Syslog>


    <Aggregate Id="Lap">

        <Sequence Repeat="Repeat"/>

        <Aggregate Id="Dive">

            <While>
                <Not><Arg Name="DiveMode"/><Eq><Arg Name="DoingComms"/></Eq></Not>
            </While>

            <Call RefId="DiveCmd"/>

        </Aggregate>

        <Aggregate Id="Wpt1">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat1"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon1"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt2">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat2"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon2"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt3">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat3"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon3"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt4">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat4"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon4"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt5">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat5"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon5"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt6">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat6"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon6"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt7">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat7"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon7"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

    </Aggregate>

</Mission>


2021-11-10T22:12:32.058Z,1636582352.058 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/sci2_flat_and_level_backseat.xml
2021-11-10T22:12:42.656Z,1636582362.656 [CommandExec](IMPORTANT): got command set sci2_flat_and_level_backseat.MissionTimeout 4.000000 hour
2021-11-10T22:12:42.658Z,1636582362.658 [CommandExec](IMPORTANT): got command set sci2_flat_and_level_backseat.Depth 20.000000 meter
2021-11-10T22:12:42.658Z,1636582362.658 [CommandExec](IMPORTANT): got command set sci2_flat_and_level_backseat.Lat1 36.797001 degree
2021-11-10T22:12:54.059Z,1636582374.059 [DataOverHttps](IMPORTANT): SBD MTMSN=20211110T221253
2021-11-10T22:12:58.694Z,1636582378.694 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T22:13:01.776Z,1636582381.776 [CommandExec](IMPORTANT): got command set sci2_flat_and_level_backseat.Lon1 -121.847000 degree
2021-11-10T22:13:01.776Z,1636582381.776 [CommandExec](IMPORTANT): got command set sci2_flat_and_level_backseat.Speed 0.850000 meter_per_second
2021-11-10T22:13:01.781Z,1636582381.781 [CommandExec](IMPORTANT): got command set sci2_flat_and_level_backseat.ApproachPitchLimit 10.000000 degree
2021-11-10T22:13:01.782Z,1636582381.782 [CommandExec](IMPORTANT): got command set sci2_flat_and_level_backseat.MaxDepth 50.000000 meter
2021-11-10T22:13:01.783Z,1636582381.783 [CommandExec](IMPORTANT): got command run
2021-11-10T22:13:01.791Z,1636582381.791 [CommandExec](IMPORTANT): Running 
2021-11-10T22:13:01.988Z,1636582381.988 [Default] Stopped
2021-11-10T22:13:01.988Z,1636582381.988 [Default](DEBUG): Aggregate::uninitialize Default
2021-11-10T22:13:01.988Z,1636582381.988 [Default:B.GoToSurface] Stopped
2021-11-10T22:13:01.988Z,1636582381.988 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-11-10T22:13:01.988Z,1636582381.988 [Default:CheckIn] Stopped
2021-11-10T22:13:01.988Z,1636582381.988 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T22:13:01.989Z,1636582381.989 [Default:CheckIn:C.Wait] Stopped
2021-11-10T22:13:01.989Z,1636582381.989 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T22:13:01.989Z,1636582381.989 [MissionManager](IMPORTANT): Started mission sci2_flat_and_level_backseat
2021-11-10T22:13:01.997Z,1636582381.997 [sci2_flat_and_level_backseat] Running Loop=1
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat:Science] Running Loop=1
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat:Science](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:Science
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat:Science:A] Running Loop=1
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat:Science:B] Running Loop=1
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat:Science:C] Running Loop=1
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat:Science:D] Running Loop=1
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat:Science:E] Running Loop=1
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat:Science:F] Running Loop=1
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat:Science:Read_Oil] Running Loop=1
2021-11-10T22:13:01.998Z,1636582381.998 [sci2_flat_and_level_backseat:Science:PeakDetectChl] Running Loop=1
2021-11-10T22:13:01.999Z,1636582381.999 [sci2_flat_and_level_backseat:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:Science:PeakDetectChl
2021-11-10T22:13:01.999Z,1636582381.999 [sci2_flat_and_level_backseat:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-11-10T22:13:01.999Z,1636582381.999 [sci2_flat_and_level_backseat:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-11-10T22:13:02.001Z,1636582382.001 [sci2_flat_and_level_backseat:Science:HighestChlPeakReport] Running Loop=1
2021-11-10T22:13:02.009Z,1636582382.009 [sci2_flat_and_level_backseat:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:Science:HighestChlPeakReport
2021-11-10T22:13:02.010Z,1636582382.010 [sci2_flat_and_level_backseat:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-11-10T22:13:02.010Z,1636582382.010 [sci2_flat_and_level_backseat:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-11-10T22:13:02.010Z,1636582382.010 [sci2_flat_and_level_backseat:Science:PeakDetectNO3] Running Loop=1
2021-11-10T22:13:02.010Z,1636582382.010 [sci2_flat_and_level_backseat:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:Science:PeakDetectNO3
2021-11-10T22:13:02.011Z,1636582382.011 [sci2_flat_and_level_backseat:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-11-10T22:13:02.011Z,1636582382.011 [sci2_flat_and_level_backseat:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-11-10T22:13:02.013Z,1636582382.013 [sci2_flat_and_level_backseat:Science:PeakDetectOil] Running Loop=1
2021-11-10T22:13:02.013Z,1636582382.013 [sci2_flat_and_level_backseat:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:Science:PeakDetectOil
2021-11-10T22:13:02.029Z,1636582382.029 [sci2_flat_and_level_backseat:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-11-10T22:13:02.029Z,1636582382.029 [sci2_flat_and_level_backseat:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-11-10T22:13:02.032Z,1636582382.032 [sci2_flat_and_level_backseat:Science:HighestOilPeakReport] Running Loop=1
2021-11-10T22:13:02.032Z,1636582382.032 [sci2_flat_and_level_backseat:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:Science:HighestOilPeakReport
2021-11-10T22:13:02.032Z,1636582382.032 [sci2_flat_and_level_backseat:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-11-10T22:13:02.032Z,1636582382.032 [sci2_flat_and_level_backseat:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-11-10T22:13:02.032Z,1636582382.032 [sci2_flat_and_level_backseat:Science:PeakDetectFDOM] Running Loop=1
2021-11-10T22:13:02.033Z,1636582382.033 [sci2_flat_and_level_backseat:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:Science:PeakDetectFDOM
2021-11-10T22:13:02.033Z,1636582382.033 [sci2_flat_and_level_backseat:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-11-10T22:13:02.033Z,1636582382.033 [sci2_flat_and_level_backseat:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-11-10T22:13:02.043Z,1636582382.043 [sci2_flat_and_level_backseat:Science:PeakDetectSalinity] Running Loop=1
2021-11-10T22:13:02.043Z,1636582382.043 [sci2_flat_and_level_backseat:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:Science:PeakDetectSalinity
2021-11-10T22:13:02.044Z,1636582382.044 [sci2_flat_and_level_backseat:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2021-11-10T22:13:02.044Z,1636582382.044 [sci2_flat_and_level_backseat:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-11-10T22:13:02.054Z,1636582382.054 [sci2_flat_and_level_backseat:Science:HighestSaltPeakReport] Running Loop=1
2021-11-10T22:13:02.054Z,1636582382.054 [sci2_flat_and_level_backseat:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:Science:HighestSaltPeakReport
2021-11-10T22:13:02.055Z,1636582382.055 [sci2_flat_and_level_backseat:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-11-10T22:13:02.055Z,1636582382.055 [sci2_flat_and_level_backseat:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-11-10T22:13:02.055Z,1636582382.055 [sci2_flat_and_level_backseat:Science:OceanCurrent] Running Loop=1
2021-11-10T22:13:02.055Z,1636582382.055 [sci2_flat_and_level_backseat:Science:OceanCurrent](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:Science:OceanCurrent
2021-11-10T22:13:02.056Z,1636582382.056 [sci2_flat_and_level_backseat:Science:OceanCurrent:A.] Running Loop=1
2021-11-10T22:13:02.056Z,1636582382.056 [sci2_flat_and_level_backseat:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-11-10T22:13:02.056Z,1636582382.056 [sci2_flat_and_level_backseat:StandardEnvelopes] Running Loop=1
2021-11-10T22:13:02.056Z,1636582382.056 [sci2_flat_and_level_backseat:StandardEnvelopes](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:StandardEnvelopes
2021-11-10T22:13:02.056Z,1636582382.056 [sci2_flat_and_level_backseat:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-11-10T22:13:02.056Z,1636582382.056 [sci2_flat_and_level_backseat:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-11-10T22:13:02.056Z,1636582382.056 [sci2_flat_and_level_backseat:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-11-10T22:13:02.057Z,1636582382.057 [sci2_flat_and_level_backseat:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-11-10T22:13:02.057Z,1636582382.057 [sci2_flat_and_level_backseat:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-11-10T22:13:02.057Z,1636582382.057 [sci2_flat_and_level_backseat:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2021-11-10T22:13:02.057Z,1636582382.057 [sci2_flat_and_level_backseat:M.BackseatDriver] Running Loop=1
2021-11-10T22:13:02.073Z,1636582382.073 [sci2_flat_and_level_backseat:M.BackseatDriver](DEBUG): Initialize.
2021-11-10T22:13:02.117Z,1636582382.117 [BackseatComponent](INFO): Powering up
2021-11-10T22:13:02.118Z,1636582382.118 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-11-10T22:13:02.174Z,1636582382.174 [sci2_flat_and_level_backseat:C] Running Loop=1
2021-11-10T22:13:02.175Z,1636582382.175 [sci2_flat_and_level_backseat:M.BackseatDriver] Running Loop=1
2021-11-10T22:13:02.194Z,1636582382.194 [sci2_flat_and_level_backseat:C] Stopped
2021-11-10T22:13:02.194Z,1636582382.194 [sci2_flat_and_level_backseat:D] Running Loop=1
2021-11-10T22:13:02.194Z,1636582382.194 [sci2_flat_and_level_backseat:StandardEnvelopes] Running Loop=1
2021-11-10T22:13:02.194Z,1636582382.194 [sci2_flat_and_level_backseat:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-11-10T22:13:02.227Z,1636582382.227 [sci2_flat_and_level_backseat:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-11-10T22:13:02.227Z,1636582382.227 [sci2_flat_and_level_backseat:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-11-10T22:13:02.228Z,1636582382.228 [sci2_flat_and_level_backseat:Science] Running Loop=1
2021-11-10T22:13:02.229Z,1636582382.229 [sci2_flat_and_level_backseat:Science:HighestSaltPeakReport] Stopped
2021-11-10T22:13:02.229Z,1636582382.229 [sci2_flat_and_level_backseat:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:Science:HighestSaltPeakReport
2021-11-10T22:13:02.229Z,1636582382.229 [sci2_flat_and_level_backseat:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2021-11-10T22:13:02.229Z,1636582382.229 [sci2_flat_and_level_backseat:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-11-10T22:13:02.229Z,1636582382.229 [sci2_flat_and_level_backseat:Science:PeakDetectSalinity] Stopped
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:Science:PeakDetectSalinity
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:PeakDetectFDOM] Stopped
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:Science:PeakDetectFDOM
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:HighestOilPeakReport] Stopped
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:Science:HighestOilPeakReport
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2021-11-10T22:13:02.230Z,1636582382.230 [sci2_flat_and_level_backseat:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-11-10T22:13:02.231Z,1636582382.231 [sci2_flat_and_level_backseat:Science:PeakDetectOil] Stopped
2021-11-10T22:13:02.231Z,1636582382.231 [sci2_flat_and_level_backseat:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:Science:PeakDetectOil
2021-11-10T22:13:02.231Z,1636582382.231 [sci2_flat_and_level_backseat:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-11-10T22:13:02.231Z,1636582382.231 [sci2_flat_and_level_backseat:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-11-10T22:13:02.231Z,1636582382.231 [sci2_flat_and_level_backseat:Science:PeakDetectNO3] Stopped
2021-11-10T22:13:02.231Z,1636582382.231 [sci2_flat_and_level_backseat:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:Science:PeakDetectNO3
2021-11-10T22:13:02.231Z,1636582382.231 [sci2_flat_and_level_backseat:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-11-10T22:13:02.231Z,1636582382.231 [sci2_flat_and_level_backseat:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-11-10T22:13:02.231Z,1636582382.231 [sci2_flat_and_level_backseat:Science:HighestChlPeakReport] Stopped
2021-11-10T22:13:02.231Z,1636582382.231 [sci2_flat_and_level_backseat:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:Science:HighestChlPeakReport
2021-11-10T22:13:02.232Z,1636582382.232 [sci2_flat_and_level_backseat:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-11-10T22:13:02.232Z,1636582382.232 [sci2_flat_and_level_backseat:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-11-10T22:13:02.232Z,1636582382.232 [sci2_flat_and_level_backseat:Science:PeakDetectChl] Stopped
2021-11-10T22:13:02.232Z,1636582382.232 [sci2_flat_and_level_backseat:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:Science:PeakDetectChl
2021-11-10T22:13:02.232Z,1636582382.232 [sci2_flat_and_level_backseat:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-11-10T22:13:02.232Z,1636582382.232 [sci2_flat_and_level_backseat:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-11-10T22:13:02.232Z,1636582382.232 [sci2_flat_and_level_backseat:Science:OceanCurrent:A.] Running Loop=1
2021-11-10T22:13:02.232Z,1636582382.232 [sci2_flat_and_level_backseat:Science:Read_Oil] Stopped
2021-11-10T22:13:02.232Z,1636582382.232 [sci2_flat_and_level_backseat:Science:F] Stopped
2021-11-10T22:13:02.232Z,1636582382.232 [sci2_flat_and_level_backseat:Science:E] Running Loop=1
2021-11-10T22:13:02.257Z,1636582382.257 [sci2_flat_and_level_backseat:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-11-10T22:13:02.257Z,1636582382.257 [sci2_flat_and_level_backseat:Science:D] Stopped
2021-11-10T22:13:02.258Z,1636582382.258 [sci2_flat_and_level_backseat:Science:C] Running Loop=1
2021-11-10T22:13:02.258Z,1636582382.258 [sci2_flat_and_level_backseat:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-11-10T22:13:02.259Z,1636582382.259 [sci2_flat_and_level_backseat:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-11-10T22:13:02.259Z,1636582382.259 [sci2_flat_and_level_backseat:Science:B] Running Loop=1
2021-11-10T22:13:02.260Z,1636582382.260 [sci2_flat_and_level_backseat:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-11-10T22:13:02.261Z,1636582382.261 [sci2_flat_and_level_backseat:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-11-10T22:13:02.261Z,1636582382.261 [sci2_flat_and_level_backseat:Science:A] Stopped
2021-11-10T22:13:02.350Z,1636582382.350 [WetLabsBB2FL](INFO): Powering up
2021-11-10T22:13:02.483Z,1636582382.483 [sci2_flat_and_level_backseat:M.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1864
2021-11-10T22:13:02.484Z,1636582382.484 [sci2_flat_and_level_backseat:D] Stopped
2021-11-10T22:13:02.484Z,1636582382.484 [sci2_flat_and_level_backseat:E] Running Loop=1
2021-11-10T22:13:02.786Z,1636582382.786 [sci2_flat_and_level_backseat:E] Stopped
2021-11-10T22:13:02.786Z,1636582382.786 [sci2_flat_and_level_backseat:G] Running Loop=1
2021-11-10T22:13:03.218Z,1636582383.218 [sci2_flat_and_level_backseat:G] Stopped
2021-11-10T22:13:03.218Z,1636582383.218 [sci2_flat_and_level_backseat:H] Running Loop=1
2021-11-10T22:13:03.686Z,1636582383.686 [sci2_flat_and_level_backseat:H] Stopped
2021-11-10T22:13:03.686Z,1636582383.686 [sci2_flat_and_level_backseat:StartingMission] Running Loop=1
2021-11-10T22:13:03.686Z,1636582383.686 [sci2_flat_and_level_backseat:StartingMission](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:StartingMission
2021-11-10T22:13:03.731Z,1636582383.731 [sci2_flat_and_level_backseat:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2021-11-10T22:13:04.045Z,1636582384.045 [sci2_flat_and_level_backseat:SurfaceComms] Running Loop=1
2021-11-10T22:13:04.046Z,1636582384.046 [sci2_flat_and_level_backseat:SurfaceComms](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:SurfaceComms
2021-11-10T22:13:04.046Z,1636582384.046 [sci2_flat_and_level_backseat:SurfaceComms:A] Running Loop=1
2021-11-10T22:13:04.046Z,1636582384.046 [sci2_flat_and_level_backseat:SurfaceComms:A] Stopped
2021-11-10T22:13:04.046Z,1636582384.046 [sci2_flat_and_level_backseat:SurfaceComms:B] Running Loop=1
2021-11-10T22:13:04.046Z,1636582384.046 [sci2_flat_and_level_backseat:SurfaceComms:B](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:SurfaceComms:B
2021-11-10T22:13:04.454Z,1636582384.454 [sci2_flat_and_level_backseat:NeedComms] Running Loop=1
2021-11-10T22:13:04.454Z,1636582384.454 [sci2_flat_and_level_backseat:NeedComms](DEBUG): Aggregate::initialize sci2_flat_and_level_backseat:NeedComms
2021-11-10T22:13:04.454Z,1636582384.454 [sci2_flat_and_level_backseat:NeedComms:B.GoToSurface] Running Loop=1
2021-11-10T22:13:04.454Z,1636582384.454 [sci2_flat_and_level_backseat:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-11-10T22:13:04.455Z,1636582384.455 [sci2_flat_and_level_backseat:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2021-11-10T22:13:04.455Z,1636582384.455 [sci2_flat_and_level_backseat:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2021-11-10T22:13:04.455Z,1636582384.455 [sci2_flat_and_level_backseat:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2021-11-10T22:13:04.456Z,1636582384.456 [sci2_flat_and_level_backseat:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-11-10T22:13:04.456Z,1636582384.456 [sci2_flat_and_level_backseat:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-11-10T22:13:04.457Z,1636582384.457 [sci2_flat_and_level_backseat:NeedComms:A] Running Loop=1
2021-11-10T22:13:04.471Z,1636582384.471 [sci2_flat_and_level_backseat:NeedComms:A](INFO): last time_fix was: 1636582299.000000 second since 1970/01/01T00:00:00Z 
2021-11-10T22:13:04.471Z,1636582384.471 [sci2_flat_and_level_backseat:NeedComms:A] Stopped
2021-11-10T22:13:04.975Z,1636582384.975 [sci2_flat_and_level_backseat:NeedComms:C] Running Loop=1
2021-11-10T22:13:05.294Z,1636582385.294 [sci2_flat_and_level_backseat:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-11-10T22:13:05.867Z,1636582385.867 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-11-10T22:13:06.257Z,1636582386.257 [DataOverHttps](INFO): Received command: run Maintenance/run_backseat_on_surface.xml
2021-11-10T22:13:06.332Z,1636582386.332 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.xml
2021-11-10T22:13:06.332Z,1636582386.332 [MissionManager](INFO): Uninitializing Mission sci2_flat_and_level_backseat
2021-11-10T22:13:06.332Z,1636582386.332 [sci2_flat_and_level_backseat] Stopped
2021-11-10T22:13:06.332Z,1636582386.332 [sci2_flat_and_level_backseat](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat
2021-11-10T22:13:06.333Z,1636582386.333 [sci2_flat_and_level_backseat:Science] Stopped
2021-11-10T22:13:06.333Z,1636582386.333 [sci2_flat_and_level_backseat:Science](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:Science
2021-11-10T22:13:06.333Z,1636582386.333 [sci2_flat_and_level_backseat:Science:B] Stopped
2021-11-10T22:13:06.341Z,1636582386.341 [sci2_flat_and_level_backseat:Science:C] Stopped
2021-11-10T22:13:06.341Z,1636582386.341 [sci2_flat_and_level_backseat:Science:E] Stopped
2021-11-10T22:13:06.341Z,1636582386.341 [sci2_flat_and_level_backseat:Science:OceanCurrent] Stopped
2021-11-10T22:13:06.342Z,1636582386.342 [sci2_flat_and_level_backseat:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:Science:OceanCurrent
2021-11-10T22:13:06.342Z,1636582386.342 [sci2_flat_and_level_backseat:Science:OceanCurrent:A.] Stopped
2021-11-10T22:13:06.342Z,1636582386.342 [sci2_flat_and_level_backseat:StandardEnvelopes] Stopped
2021-11-10T22:13:06.342Z,1636582386.342 [sci2_flat_and_level_backseat:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:StandardEnvelopes
2021-11-10T22:13:06.342Z,1636582386.342 [sci2_flat_and_level_backseat:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2021-11-10T22:13:06.342Z,1636582386.342 [sci2_flat_and_level_backseat:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-11-10T22:13:06.342Z,1636582386.342 [sci2_flat_and_level_backseat:StandardEnvelopes:B.DepthEnvelope] Stopped
2021-11-10T22:13:06.342Z,1636582386.342 [sci2_flat_and_level_backseat:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2021-11-10T22:13:06.342Z,1636582386.342 [sci2_flat_and_level_backseat:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:NeedComms] Stopped
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:NeedComms](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:NeedComms
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:NeedComms:B.GoToSurface] Stopped
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:NeedComms:C] Stopped
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:SurfaceComms] Stopped
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:SurfaceComms](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:SurfaceComms
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:SurfaceComms:B] Stopped
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:SurfaceComms:B](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:SurfaceComms:B
2021-11-10T22:13:06.343Z,1636582386.343 [sci2_flat_and_level_backseat:StartingMission] Stopped
2021-11-10T22:13:06.344Z,1636582386.344 [sci2_flat_and_level_backseat:StartingMission](DEBUG): Aggregate::uninitialize sci2_flat_and_level_backseat:StartingMission
2021-11-10T22:13:06.344Z,1636582386.344 [sci2_flat_and_level_backseat:M.BackseatDriver] Stopped
2021-11-10T22:13:06.344Z,1636582386.344 [sci2_flat_and_level_backseat:M.BackseatDriver](DEBUG): Uninitialize.
2021-11-10T22:13:06.470Z,1636582386.470 [WetLabsBB2FL](INFO): Powering down
2021-11-10T22:13:06.683Z,1636582386.683 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/run_backseat_on_surface.xml
2021-11-10T22:13:06.780Z,1636582386.780 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min
2021-11-10T22:13:06.780Z,1636582386.780 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-11-10T22:13:06.813Z,1636582386.813 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221306.00,A,3648.17591,N,12147.28562,W,0.019,60.30,101121,,,D*4A
2021-11-10T22:13:06.835Z,1636582386.835 [NAL9602](INFO): GPS fix at 20211110T221306: (36.802932, -121.788094)
2021-11-10T22:13:06.870Z,1636582386.870 [WetLabsBB2FL](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Software Overcurrent.
2021-11-10T22:13:06.870Z,1636582386.870 [WetLabsBB2FL] Hardware Fault, FailCount= 1
2021-11-10T22:13:06.871Z,1636582386.871 [WetLabsBB2FL](ERROR): Hardware Fault
2021-11-10T22:13:07.186Z,1636582387.186 [CBIT](ERROR): Hardware Fault in component: WetLabsBB2FL
2021-11-10T22:13:07.450Z,1636582387.450 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2021-11-10T22:13:07.450Z,1636582387.450 [WetLabsBB2FL] No Fault, FailCount= 1
2021-11-10T22:13:07.687Z,1636582387.687 [WetLabsBB2FL](INFO): Powering up
2021-11-10T22:13:07.934Z,1636582387.934 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectChlActive = 0 bool
2021-11-10T22:13:07.937Z,1636582387.937 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.TimeWindowPeakReport = nan min
2021-11-10T22:13:07.940Z,1636582387.940 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestChlPeakReportActive = 0 bool
2021-11-10T22:13:07.951Z,1636582387.951 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestSaltPeakReportActive = 0 bool
2021-11-10T22:13:07.954Z,1636582387.954 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestOilPeakReportActive = 0 bool
2021-11-10T22:13:07.957Z,1636582387.957 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PatchTracking = 0 bool
2021-11-10T22:13:07.960Z,1636582387.960 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DetectTrough = 0 bool
2021-11-10T22:13:07.963Z,1636582387.963 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.FilterWidthHorizontal = 3.000000 count
2021-11-10T22:13:08.055Z,1636582388.055 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesSlidingwindow = 100.000000 count
2021-11-10T22:13:08.074Z,1636582388.074 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.OffPeakFractionHorizontal = 80.000000 %
2021-11-10T22:13:08.099Z,1636582388.099 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectNO3Active = 0 bool
2021-11-10T22:13:08.118Z,1636582388.118 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectOilActive = 0 bool
2021-11-10T22:13:08.120Z,1636582388.120 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectFDOMActive = 0 bool
2021-11-10T22:13:08.128Z,1636582388.128 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectSalinityActive = 0 bool
2021-11-10T22:13:08.215Z,1636582388.215 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-11-10T22:13:08.228Z,1636582388.228 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledAanderaaO2 = 0.000000 bool
2021-11-10T22:13:08.266Z,1636582388.266 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledNeilBrown = 0.000000 bool
2021-11-10T22:13:08.318Z,1636582388.318 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledSeabird = 1.000000 bool
2021-11-10T22:13:08.338Z,1636582388.338 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-11-10T22:13:08.358Z,1636582388.358 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-11-10T22:13:08.382Z,1636582388.382 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-11-10T22:13:08.385Z,1636582388.385 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.LowPassWindowLength = 20.000000 count
2021-11-10T22:13:08.396Z,1636582388.396 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.MedianFilterLen = 5.000000 count
2021-11-10T22:13:08.440Z,1636582388.440 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakShallowBound = nan m
2021-11-10T22:13:08.467Z,1636582388.467 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDeepBound = nan m
2021-11-10T22:13:08.502Z,1636582388.502 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2021-11-10T22:13:08.516Z,1636582388.516 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChl = 0.000000 ug/l
2021-11-10T22:13:08.552Z,1636582388.552 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlDepth = 0.000000 m
2021-11-10T22:13:08.564Z,1636582388.564 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlTemperature = 0.000000 degC
2021-11-10T22:13:08.679Z,1636582388.679 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLatitude = nan arcdeg
2021-11-10T22:13:08.695Z,1636582388.695 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLongitude = nan arcdeg
2021-11-10T22:13:08.722Z,1636582388.722 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3 = 0.000000 umol/l
2021-11-10T22:13:08.731Z,1636582388.731 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Depth = 0.000000 m
2021-11-10T22:13:08.755Z,1636582388.755 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Latitude = nan arcdeg
2021-11-10T22:13:08.779Z,1636582388.779 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Longitude = nan arcdeg
2021-11-10T22:13:08.794Z,1636582388.794 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOil = 0.000000 kg/m3
2021-11-10T22:13:08.862Z,1636582388.862 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilDepth = 0.000000 m
2021-11-10T22:13:08.886Z,1636582388.886 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLatitude = nan arcdeg
2021-11-10T22:13:08.910Z,1636582388.910 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLongitude = nan arcdeg
2021-11-10T22:13:08.913Z,1636582388.913 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOM = 0.000000 ppb
2021-11-10T22:13:08.949Z,1636582388.949 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMDepth = 0.000000 m
2021-11-10T22:13:08.972Z,1636582388.972 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMTemperature = nan degC
2021-11-10T22:13:09.032Z,1636582389.032 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLatitude = nan arcdeg
2021-11-10T22:13:09.039Z,1636582389.039 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLongitude = nan arcdeg
2021-11-10T22:13:09.055Z,1636582389.055 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchFDOMDistance = nan m
2021-11-10T22:13:09.088Z,1636582389.088 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSalt = 0.000000 psu
2021-11-10T22:13:09.092Z,1636582389.092 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltDepth = 0.000000 m
2021-11-10T22:13:09.108Z,1636582389.108 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltTemperature = 0.000000 degC
2021-11-10T22:13:09.130Z,1636582389.130 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLatitude = nan arcdeg
2021-11-10T22:13:09.154Z,1636582389.154 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLongitude = nan arcdeg
2021-11-10T22:13:09.160Z,1636582389.160 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChl = 0.000000 ug/l
2021-11-10T22:13:09.183Z,1636582389.183 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDepth = 0.000000 m
2021-11-10T22:13:09.188Z,1636582389.188 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLatitude = nan arcdeg
2021-11-10T22:13:09.191Z,1636582389.191 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLongitude = nan arcdeg
2021-11-10T22:13:09.210Z,1636582389.210 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDistance = nan m
2021-11-10T22:13:09.254Z,1636582389.254 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSalt = 0.000000 psu
2021-11-10T22:13:09.279Z,1636582389.279 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDepth = 0.000000 m
2021-11-10T22:13:09.303Z,1636582389.303 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLatitude = nan arcdeg
2021-11-10T22:13:09.318Z,1636582389.318 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLongitude = nan arcdeg
2021-11-10T22:13:09.329Z,1636582389.329 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDistance = nan m
2021-11-10T22:13:09.365Z,1636582389.365 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOil = 0.000000 psu
2021-11-10T22:13:09.369Z,1636582389.369 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDepth = 0.000000 m
2021-11-10T22:13:09.390Z,1636582389.390 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLatitude = nan arcdeg
2021-11-10T22:13:09.398Z,1636582389.398 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLongitude = nan arcdeg
2021-11-10T22:13:09.417Z,1636582389.417 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDistance = nan m
2021-11-10T22:13:09.542Z,1636582389.542 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-11-10T22:13:09.592Z,1636582389.592 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-11-10T22:13:09.688Z,1636582389.688 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-11-10T22:13:09.759Z,1636582389.759 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-11-10T22:13:09.806Z,1636582389.806 [WetLabsBB2FL](INFO): Powering down
2021-11-10T22:13:09.830Z,1636582389.830 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-11-10T22:13:09.857Z,1636582389.857 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-11-10T22:13:09.942Z,1636582389.942 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-11-10T22:13:09.975Z,1636582389.975 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-11-10T22:13:10.086Z,1636582390.086 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Construct BackseatDriver.
2021-11-10T22:13:10.098Z,1636582390.098 [run_backseat_on_surface:C.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-10T22:13:10.147Z,1636582390.147 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-11-10T22:13:10.186Z,1636582390.186 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-11-10T22:13:10.196Z,1636582390.196 [run_backseat_on_surface:F.Execute](DEBUG): Construct Execute.
2021-11-10T22:13:10.330Z,1636582390.330 [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="run_backseat_on_surface">

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

    <!-- Power the science payload. -->

    <Insert Filename="Insert/Science.xml" Id="Science"></Insert>

    <!-- Power the Backseat payload. -->

    <Guidance:BackseatDriver>
        <Parallel/>
    </Guidance:BackseatDriver>

    <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="ElapsedSinceMissionStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

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

    </Aggregate>

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

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

</Mission>


2021-11-10T22:13:10.333Z,1636582390.333 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.xml
2021-11-10T22:13:10.564Z,1636582390.564 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface
2021-11-10T22:13:10.564Z,1636582390.564 [run_backseat_on_surface] Running Loop=1
2021-11-10T22:13:10.564Z,1636582390.564 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface
2021-11-10T22:13:10.565Z,1636582390.565 [run_backseat_on_surface:Science] Running Loop=1
2021-11-10T22:13:10.565Z,1636582390.565 [run_backseat_on_surface:Science](DEBUG): Aggregate::initialize run_backseat_on_surface:Science
2021-11-10T22:13:10.565Z,1636582390.565 [run_backseat_on_surface:Science:A] Running Loop=1
2021-11-10T22:13:10.565Z,1636582390.565 [run_backseat_on_surface:Science:B] Running Loop=1
2021-11-10T22:13:10.565Z,1636582390.565 [run_backseat_on_surface:Science:C] Running Loop=1
2021-11-10T22:13:10.571Z,1636582390.571 [run_backseat_on_surface:Science:D] Running Loop=1
2021-11-10T22:13:10.571Z,1636582390.571 [run_backseat_on_surface:Science:E] Running Loop=1
2021-11-10T22:13:10.571Z,1636582390.571 [run_backseat_on_surface:Science:F] Running Loop=1
2021-11-10T22:13:10.571Z,1636582390.571 [run_backseat_on_surface:Science:Read_Oil] Running Loop=1
2021-11-10T22:13:10.571Z,1636582390.571 [run_backseat_on_surface:Science:PeakDetectChl] Running Loop=1
2021-11-10T22:13:10.571Z,1636582390.571 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectChl
2021-11-10T22:13:10.571Z,1636582390.571 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-11-10T22:13:10.572Z,1636582390.572 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-11-10T22:13:10.574Z,1636582390.574 [run_backseat_on_surface:Science:HighestChlPeakReport] Running Loop=1
2021-11-10T22:13:10.574Z,1636582390.574 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestChlPeakReport
2021-11-10T22:13:10.574Z,1636582390.574 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-11-10T22:13:10.574Z,1636582390.574 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-11-10T22:13:10.575Z,1636582390.575 [run_backseat_on_surface:Science:PeakDetectNO3] Running Loop=1
2021-11-10T22:13:10.575Z,1636582390.575 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectNO3
2021-11-10T22:13:10.575Z,1636582390.575 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-11-10T22:13:10.575Z,1636582390.575 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-11-10T22:13:10.577Z,1636582390.577 [run_backseat_on_surface:Science:PeakDetectOil] Running Loop=1
2021-11-10T22:13:10.577Z,1636582390.577 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectOil
2021-11-10T22:13:10.610Z,1636582390.610 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-11-10T22:13:10.610Z,1636582390.610 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-11-10T22:13:10.611Z,1636582390.611 [run_backseat_on_surface:Science:HighestOilPeakReport] Running Loop=1
2021-11-10T22:13:10.611Z,1636582390.611 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestOilPeakReport
2021-11-10T22:13:10.612Z,1636582390.612 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-11-10T22:13:10.612Z,1636582390.612 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-11-10T22:13:10.612Z,1636582390.612 [run_backseat_on_surface:Science:PeakDetectFDOM] Running Loop=1
2021-11-10T22:13:10.612Z,1636582390.612 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectFDOM
2021-11-10T22:13:10.612Z,1636582390.612 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-11-10T22:13:10.613Z,1636582390.613 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-11-10T22:13:10.614Z,1636582390.614 [run_backseat_on_surface:Science:PeakDetectSalinity] Running Loop=1
2021-11-10T22:13:10.615Z,1636582390.615 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectSalinity
2021-11-10T22:13:10.615Z,1636582390.615 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2021-11-10T22:13:10.615Z,1636582390.615 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-11-10T22:13:10.616Z,1636582390.616 [run_backseat_on_surface:Science:HighestSaltPeakReport] Running Loop=1
2021-11-10T22:13:10.617Z,1636582390.617 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestSaltPeakReport
2021-11-10T22:13:10.617Z,1636582390.617 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-11-10T22:13:10.617Z,1636582390.617 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-11-10T22:13:10.635Z,1636582390.635 [run_backseat_on_surface:Science:OceanCurrent] Running Loop=1
2021-11-10T22:13:10.635Z,1636582390.635 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:OceanCurrent
2021-11-10T22:13:10.635Z,1636582390.635 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2021-11-10T22:13:10.635Z,1636582390.635 [run_backseat_on_surface:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-11-10T22:13:10.635Z,1636582390.635 [run_backseat_on_surface:B.BackseatDriver] Running Loop=1
2021-11-10T22:13:10.635Z,1636582390.635 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Initialize.
2021-11-10T22:13:10.785Z,1636582390.785 [run_backseat_on_surface:C.GoToSurface] Running Loop=1
2021-11-10T22:13:10.786Z,1636582390.786 [run_backseat_on_surface:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-11-10T22:13:10.786Z,1636582390.786 [run_backseat_on_surface:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-11-10T22:13:10.786Z,1636582390.786 [run_backseat_on_surface:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-11-10T22:13:10.786Z,1636582390.786 [run_backseat_on_surface:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-11-10T22:13:10.787Z,1636582390.787 [run_backseat_on_surface:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-11-10T22:13:10.787Z,1636582390.787 [run_backseat_on_surface:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-11-10T22:13:10.788Z,1636582390.788 [run_backseat_on_surface:CheckIn] Running Loop=1
2021-11-10T22:13:10.788Z,1636582390.788 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2021-11-10T22:13:10.789Z,1636582390.789 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2021-11-10T22:13:10.789Z,1636582390.789 [run_backseat_on_surface:B.BackseatDriver] Running Loop=1
2021-11-10T22:13:10.789Z,1636582390.789 [run_backseat_on_surface:Science] Running Loop=1
2021-11-10T22:13:10.791Z,1636582390.791 [run_backseat_on_surface:Science:HighestSaltPeakReport] Stopped
2021-11-10T22:13:10.791Z,1636582390.791 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestSaltPeakReport
2021-11-10T22:13:10.791Z,1636582390.791 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2021-11-10T22:13:10.791Z,1636582390.791 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-11-10T22:13:10.791Z,1636582390.791 [run_backseat_on_surface:Science:PeakDetectSalinity] Stopped
2021-11-10T22:13:10.791Z,1636582390.791 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectSalinity
2021-11-10T22:13:10.791Z,1636582390.791 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2021-11-10T22:13:10.791Z,1636582390.791 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-11-10T22:13:10.791Z,1636582390.791 [run_backseat_on_surface:Science:PeakDetectFDOM] Stopped
2021-11-10T22:13:10.791Z,1636582390.791 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectFDOM
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:HighestOilPeakReport] Stopped
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestOilPeakReport
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:PeakDetectOil] Stopped
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectOil
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-11-10T22:13:10.792Z,1636582390.792 [run_backseat_on_surface:Science:PeakDetectNO3] Stopped
2021-11-10T22:13:10.793Z,1636582390.793 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectNO3
2021-11-10T22:13:10.793Z,1636582390.793 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-11-10T22:13:10.793Z,1636582390.793 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-11-10T22:13:10.793Z,1636582390.793 [run_backseat_on_surface:Science:HighestChlPeakReport] Stopped
2021-11-10T22:13:10.793Z,1636582390.793 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestChlPeakReport
2021-11-10T22:13:10.833Z,1636582390.833 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-11-10T22:13:10.834Z,1636582390.834 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-11-10T22:13:10.834Z,1636582390.834 [run_backseat_on_surface:Science:PeakDetectChl] Stopped
2021-11-10T22:13:10.834Z,1636582390.834 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectChl
2021-11-10T22:13:10.834Z,1636582390.834 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-11-10T22:13:10.834Z,1636582390.834 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-11-10T22:13:10.834Z,1636582390.834 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2021-11-10T22:13:10.834Z,1636582390.834 [run_backseat_on_surface:Science:Read_Oil] Stopped
2021-11-10T22:13:10.834Z,1636582390.834 [run_backseat_on_surface:Science:F] Stopped
2021-11-10T22:13:10.834Z,1636582390.834 [run_backseat_on_surface:Science:E] Running Loop=1
2021-11-10T22:13:10.835Z,1636582390.835 [run_backseat_on_surface:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-11-10T22:13:10.835Z,1636582390.835 [run_backseat_on_surface:Science:D] Stopped
2021-11-10T22:13:10.835Z,1636582390.835 [run_backseat_on_surface:Science:C] Running Loop=1
2021-11-10T22:13:10.836Z,1636582390.836 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-11-10T22:13:10.837Z,1636582390.837 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-11-10T22:13:10.837Z,1636582390.837 [run_backseat_on_surface:Science:B] Running Loop=1
2021-11-10T22:13:10.850Z,1636582390.850 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-11-10T22:13:10.851Z,1636582390.851 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-11-10T22:13:10.851Z,1636582390.851 [run_backseat_on_surface:Science:A] Stopped
2021-11-10T22:13:11.030Z,1636582391.030 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-11-10T22:13:11.219Z,1636582391.219 [WetLabsBB2FL](INFO): Powering up
2021-11-10T22:13:11.461Z,1636582391.461 [run_backseat_on_surface:B.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1864
2021-11-10T22:13:13.914Z,1636582393.914 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-11-10T22:13:15.928Z,1636582395.928 [CTD_Seabird](ERROR): Salinity reading out of range: 0.575050 psu
2021-11-10T22:13:16.932Z,1636582396.932 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574836 psu
2021-11-10T22:13:17.656Z,1636582397.656 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2021-11-10T22:13:17.657Z,1636582397.657 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2021-11-10T22:13:17.660Z,1636582397.660 [BackseatComponent](INFO): Created universal data reader for latitude with code: 23
2021-11-10T22:13:17.661Z,1636582397.661 [BackseatComponent](INFO): Requesting data from latitude (23). Requested size is: 2
2021-11-10T22:13:17.664Z,1636582397.664 [BackseatComponent](INFO): Created universal data reader for longitude with code: 26
2021-11-10T22:13:17.664Z,1636582397.664 [BackseatComponent](INFO): Requesting data from longitude (26). Requested size is: 3
2021-11-10T22:13:17.668Z,1636582397.668 [BackseatComponent](INFO): Created universal data reader for platform_orientation with code: 52
2021-11-10T22:13:17.669Z,1636582397.669 [BackseatComponent](INFO): Requesting data from platform_orientation (52). Requested size is: 4
2021-11-10T22:13:17.707Z,1636582397.707 [BackseatComponent](INFO): Created universal data reader for platform_pitch_angle with code: 54
2021-11-10T22:13:17.707Z,1636582397.707 [BackseatComponent](INFO): Requesting data from platform_pitch_angle (54). Requested size is: 5
2021-11-10T22:13:17.711Z,1636582397.711 [BackseatComponent](INFO): Created universal data reader for platform_roll_angle with code: 59
2021-11-10T22:13:17.711Z,1636582397.711 [BackseatComponent](INFO): Requesting data from platform_roll_angle (59). Requested size is: 6
2021-11-10T22:13:17.730Z,1636582397.730 [BackseatComponent](INFO): Created universal data reader for platform_x_velocity_wrt_sea_water with code: 70
2021-11-10T22:13:17.730Z,1636582397.730 [BackseatComponent](INFO): Requesting data from platform_x_velocity_wrt_sea_water (70). Requested size is: 7
2021-11-10T22:13:17.936Z,1636582397.936 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574738 psu
2021-11-10T22:13:18.939Z,1636582398.939 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574692 psu
2021-11-10T22:13:19.951Z,1636582399.951 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574774 psu
2021-11-10T22:13:20.947Z,1636582400.947 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574949 psu
2021-11-10T22:13:21.968Z,1636582401.968 [CTD_Seabird](ERROR): Salinity reading out of range: 0.575077 psu
2021-11-10T22:13:22.972Z,1636582402.972 [CTD_Seabird](ERROR): Salinity reading out of range: 0.575088 psu
2021-11-10T22:13:23.976Z,1636582403.976 [CTD_Seabird](ERROR): Salinity reading out of range: 0.575114 psu
2021-11-10T22:13:24.979Z,1636582404.979 [CTD_Seabird](ERROR): Salinity reading out of range: 0.575143 psu
2021-11-10T22:13:25.996Z,1636582405.996 [CTD_Seabird](ERROR): Salinity reading out of range: 0.575108 psu
2021-11-10T22:13:26.000Z,1636582407.000 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574986 psu
2021-11-10T22:13:28.003Z,1636582408.003 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574979 psu
2021-11-10T22:13:29.007Z,1636582409.007 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574907 psu
2021-11-10T22:13:30.012Z,1636582410.012 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574817 psu
2021-11-10T22:13:31.016Z,1636582411.016 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574715 psu
2021-11-10T22:13:32.020Z,1636582412.020 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574661 psu
2021-11-10T22:13:33.024Z,1636582413.024 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574664 psu
2021-11-10T22:13:34.027Z,1636582414.027 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574549 psu
2021-11-10T22:13:35.031Z,1636582415.031 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574425 psu
2021-11-10T22:13:36.036Z,1636582416.036 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574483 psu
2021-11-10T22:13:37.040Z,1636582417.040 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574397 psu
2021-11-10T22:13:38.044Z,1636582418.044 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574283 psu
2021-11-10T22:13:39.047Z,1636582419.047 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574179 psu
2021-11-10T22:13:40.052Z,1636582420.052 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573998 psu
2021-11-10T22:13:41.055Z,1636582421.055 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573950 psu
2021-11-10T22:13:42.060Z,1636582422.060 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573857 psu
2021-11-10T22:13:43.063Z,1636582423.063 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573729 psu
2021-11-10T22:13:44.067Z,1636582424.067 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573611 psu
2021-11-10T22:13:45.071Z,1636582425.071 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573635 psu
2021-11-10T22:13:46.075Z,1636582426.075 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573658 psu
2021-11-10T22:13:47.080Z,1636582427.080 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573695 psu
2021-11-10T22:13:48.085Z,1636582428.085 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573927 psu
2021-11-10T22:13:49.088Z,1636582429.088 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574125 psu
2021-11-10T22:13:50.093Z,1636582430.093 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574171 psu
2021-11-10T22:13:51.097Z,1636582431.097 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574214 psu
2021-11-10T22:13:52.100Z,1636582432.100 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574191 psu
2021-11-10T22:13:53.103Z,1636582433.103 [CTD_Seabird](ERROR): Salinity reading out of range: 0.574070 psu
2021-11-10T22:13:54.110Z,1636582434.110 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573942 psu
2021-11-10T22:13:55.118Z,1636582435.118 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573788 psu
2021-11-10T22:13:56.120Z,1636582436.120 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573591 psu
2021-11-10T22:13:57.128Z,1636582437.128 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573400 psu
2021-11-10T22:13:58.136Z,1636582438.136 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573183 psu
2021-11-10T22:13:59.140Z,1636582439.140 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573041 psu
2021-11-10T22:14:00.144Z,1636582440.144 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572910 psu
2021-11-10T22:14:01.148Z,1636582441.148 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572847 psu
2021-11-10T22:14:02.151Z,1636582442.151 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572795 psu
2021-11-10T22:14:03.155Z,1636582443.155 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572836 psu
2021-11-10T22:14:04.160Z,1636582444.160 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572885 psu
2021-11-10T22:14:05.163Z,1636582445.163 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572974 psu
2021-11-10T22:14:06.168Z,1636582446.168 [CTD_Seabird](ERROR): Salinity reading out of range: 0.573036 psu
2021-11-10T22:14:07.171Z,1636582447.171 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572984 psu
2021-11-10T22:14:08.176Z,1636582448.176 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572876 psu
2021-11-10T22:14:09.183Z,1636582449.183 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572818 psu
2021-11-10T22:14:10.188Z,1636582450.188 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572613 psu
2021-11-10T22:14:11.191Z,1636582451.191 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572534 psu
2021-11-10T22:14:12.196Z,1636582452.196 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572437 psu
2021-11-10T22:14:13.199Z,1636582453.199 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572444 psu
2021-11-10T22:14:14.204Z,1636582454.204 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572492 psu
2021-11-10T22:14:15.207Z,1636582455.207 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572476 psu
2021-11-10T22:14:16.212Z,1636582456.212 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572559 psu
2021-11-10T22:14:16.855Z,1636582456.855 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=6789, MT Status=1, MTMSN=438
2021-11-10T22:14:16.855Z,1636582456.855 [NAL9602](INFO): Data available in MT queue
2021-11-10T22:14:17.216Z,1636582457.216 [CTD_Seabird](ERROR): Salinity reading out of range: 0.572518 psu
2021-11-10T22:14:17.322Z,1636582457.322 [NAL9602](INFO): Received command: stop
2021-11-10T22:14:17.347Z,1636582457.347 [CommandExec](IMPORTANT): got command stop
2021-11-10T22:14:17.347Z,1636582457.347 [CommandExec](IMPORTANT): Scheduling is paused
2021-11-10T22:14:17.347Z,1636582457.347 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2021-11-10T22:14:17.356Z,1636582457.356 [MissionManager](INFO): MissionManager is completed.
2021-11-10T22:14:17.356Z,1636582457.356 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface
2021-11-10T22:14:17.356Z,1636582457.356 [run_backseat_on_surface] Stopped
2021-11-10T22:14:17.356Z,1636582457.356 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface
2021-11-10T22:14:17.357Z,1636582457.357 [run_backseat_on_surface:Science] Stopped
2021-11-10T22:14:17.357Z,1636582457.357 [run_backseat_on_surface:Science](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science
2021-11-10T22:14:17.357Z,1636582457.357 [run_backseat_on_surface:Science:B] Stopped
2021-11-10T22:14:17.357Z,1636582457.357 [run_backseat_on_surface:Science:C] Stopped
2021-11-10T22:14:17.357Z,1636582457.357 [run_backseat_on_surface:Science:E] Stopped
2021-11-10T22:14:17.357Z,1636582457.357 [run_backseat_on_surface:Science:OceanCurrent] Stopped
2021-11-10T22:14:17.381Z,1636582457.381 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:OceanCurrent
2021-11-10T22:14:17.382Z,1636582457.382 [run_backseat_on_surface:Science:OceanCurrent:A.] Stopped
2021-11-10T22:14:17.382Z,1636582457.382 [run_backseat_on_surface:B.BackseatDriver] Stopped
2021-11-10T22:14:17.382Z,1636582457.382 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Uninitialize.
2021-11-10T22:14:17.382Z,1636582457.382 [run_backseat_on_surface:C.GoToSurface] Stopped
2021-11-10T22:14:17.382Z,1636582457.382 [run_backseat_on_surface:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-11-10T22:14:17.382Z,1636582457.382 [run_backseat_on_surface:CheckIn] Stopped
2021-11-10T22:14:17.382Z,1636582457.382 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2021-11-10T22:14:17.382Z,1636582457.382 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2021-11-10T22:14:17.805Z,1636582457.805 [MissionManager](IMPORTANT): Started mission Default
2021-11-10T22:14:17.805Z,1636582457.805 [Default] Running Loop=1
2021-11-10T22:14:17.805Z,1636582457.805 [Default](DEBUG): Aggregate::initialize Default
2021-11-10T22:14:17.805Z,1636582457.805 [Default:B.GoToSurface] Running Loop=1
2021-11-10T22:14:17.805Z,1636582457.805 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-11-10T22:14:17.806Z,1636582457.806 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-11-10T22:14:17.806Z,1636582457.806 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-11-10T22:14:17.806Z,1636582457.806 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-11-10T22:14:17.806Z,1636582457.806 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-11-10T22:14:17.807Z,1636582457.807 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-11-10T22:14:17.807Z,1636582457.807 [Default:A.Wait] Running Loop=1
2021-11-10T22:14:17.807Z,1636582457.807 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-11-10T22:14:17.901Z,1636582457.901 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-11-10T22:14:17.926Z,1636582457.926 [WetLabsBB2FL](INFO): Powering down
2021-11-10T22:14:30.824Z,1636582470.824 [Default:A.Wait](INFO): Done Waiting.
2021-11-10T22:14:30.825Z,1636582470.825 [Default:A.Wait] Stopped
2021-11-10T22:14:30.825Z,1636582470.825 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T22:14:31.275Z,1636582471.275 [Default:CheckIn] Running Loop=1
2021-11-10T22:14:31.275Z,1636582471.275 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T22:14:31.275Z,1636582471.275 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T22:14:33.022Z,1636582473.022 [BackseatComponent](INFO): Powering down
2021-11-10T22:14:33.234Z,1636582473.234 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2021-11-10T22:14:33.234Z,1636582473.234 [LcmUniversalReporter](INFO): Deactivating messaging.
2021-11-10T22:14:33.237Z,1636582473.237 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221432.00,A,3648.17679,N,12147.28575,W,0.019,60.30,101121,,,D*49
2021-11-10T22:14:33.240Z,1636582473.240 [NAL9602](INFO): GPS fix at 20211110T221432: (36.802946, -121.788096)
2021-11-10T22:14:33.332Z,1636582473.332 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T22:14:33.333Z,1636582473.333 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T22:14:47.073Z,1636582487.073 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20211110T204844/Courier0034.lzma
2021-11-10T22:14:48.075Z,1636582488.075 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0034.lzma.bak
2021-11-10T22:14:48.076Z,1636582488.076 [DataOverHttps](INFO): SBD MOMSN=16232336
2021-11-10T22:15:05.647Z,1636582505.647 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T22:15:21.015Z,1636582521.015 [DataOverHttps](INFO): Sending 890 bytes from file Logs/20211110T204844/Express0035.lzma
2021-11-10T22:15:22.016Z,1636582522.016 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0035.lzma.bak
2021-11-10T22:15:22.016Z,1636582522.016 [DataOverHttps](INFO): SBD MOMSN=16232339
2021-11-10T22:15:24.387Z,1636582524.387 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T22:15:24.387Z,1636582524.387 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T22:15:24.387Z,1636582524.387 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T22:20:24.984Z,1636582824.984 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T22:20:24.984Z,1636582824.984 [Default:CheckIn:C.Wait] Stopped
2021-11-10T22:20:24.984Z,1636582824.984 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T22:20:24.985Z,1636582824.985 [Default:CheckIn:D] Running Loop=1
2021-11-10T22:20:25.382Z,1636582825.382 [Default:CheckIn:D] Stopped
2021-11-10T22:20:25.382Z,1636582825.382 [Default:CheckIn:E] Running Loop=1
2021-11-10T22:20:25.813Z,1636582825.813 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.126278 min 
2021-11-10T22:20:25.813Z,1636582825.813 [Default:CheckIn:E] Stopped
2021-11-10T22:20:25.814Z,1636582825.814 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T22:20:25.814Z,1636582825.814 [Default:CheckIn] Stopped
2021-11-10T22:20:25.814Z,1636582825.814 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T22:20:25.814Z,1636582825.814 [Default:CheckIn](INFO): Running loop #2
2021-11-10T22:20:25.814Z,1636582825.814 [Default:CheckIn] Running Loop=2
2021-11-10T22:20:25.814Z,1636582825.814 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T22:20:25.814Z,1636582825.814 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T22:20:27.781Z,1636582827.781 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222026.00,A,3648.17662,N,12147.28615,W,0.019,60.30,101121,,,D*44
2021-11-10T22:20:27.783Z,1636582827.783 [NAL9602](INFO): GPS fix at 20211110T222026: (36.802944, -121.788102)
2021-11-10T22:20:27.803Z,1636582827.803 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T22:20:27.803Z,1636582827.803 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T22:20:29.271Z,1636582829.271 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211110T204844/Courier0037.lzma
2021-11-10T22:20:30.043Z,1636582830.043 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Courier0037.lzma.bak
2021-11-10T22:20:30.043Z,1636582830.043 [DataOverHttps](INFO): SBD MOMSN=16232367
2021-11-10T22:20:49.537Z,1636582849.537 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20211110T204844/Express0039.lzma
2021-11-10T22:20:50.540Z,1636582850.540 [DataOverHttps](INFO): Moved sent file to Logs/20211110T204844/Express0039.lzma.bak
2021-11-10T22:20:50.540Z,1636582850.540 [DataOverHttps](INFO): SBD MOMSN=16232370
2021-11-10T22:20:52.095Z,1636582852.095 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T22:20:52.095Z,1636582852.095 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T22:20:52.095Z,1636582852.095 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T22:21:00.556Z,1636582860.556 [NAL9602](INFO): SBD MO Status=2, MOMSN=6790, MT Status=2, MTMSN=0
2021-11-10T22:21:00.556Z,1636582860.556 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-11-10T22:21:29.668Z,1636582889.668 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=6790, MT Status=1, MTMSN=439
2021-11-10T22:21:29.668Z,1636582889.668 [NAL9602](INFO): Data available in MT queue
2021-11-10T22:21:30.150Z,1636582890.150 [NAL9602](INFO): Received command: restart logs