2023-09-08T11:17:55.335Z,1694171875.335 [Supervisor](DEBUG): Initializing supervisor.
2023-09-08T11:17:55.340Z,1694171875.340 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-08T11:17:55.340Z,1694171875.340 [SyncHandler](INFO): Protected caller Thread ID is 843
2023-09-08T11:17:55.341Z,1694171875.341 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-08T11:17:55.342Z,1694171875.342 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-08T11:17:55.342Z,1694171875.342 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 844
2023-09-08T11:17:55.346Z,1694171875.346 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-08T11:17:55.364Z,1694171875.364 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-08T11:17:55.365Z,1694171875.365 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-08T11:17:55.366Z,1694171875.366 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 845
2023-09-08T11:17:55.370Z,1694171875.370 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-08T11:17:55.371Z,1694171875.371 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-08T11:17:55.371Z,1694171875.371 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 846
2023-09-08T11:17:55.373Z,1694171875.373 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-08T11:17:55.374Z,1694171875.374 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-08T11:17:55.374Z,1694171875.374 [logger ThreadHandler](INFO): Protected caller Thread ID is 847
2023-09-08T11:17:55.379Z,1694171875.379 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-08T11:17:55.379Z,1694171875.379 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-08T11:17:55.383Z,1694171875.383 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-08T11:17:55.894Z,1694171875.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-08T11:17:55.896Z,1694171875.896 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-08T11:17:56.108Z,1694171876.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-08T11:17:56.132Z,1694171876.132 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-08T11:17:56.214Z,1694171876.214 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-08T11:17:56.438Z,1694171876.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-08T11:17:56.439Z,1694171876.439 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-08T11:17:56.519Z,1694171876.519 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-08T11:17:56.617Z,1694171876.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-08T11:17:56.619Z,1694171876.619 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-08T11:17:56.982Z,1694171876.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-08T11:17:56.983Z,1694171876.983 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-08T11:17:57.116Z,1694171877.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-08T11:17:57.117Z,1694171877.117 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-08T11:17:57.617Z,1694171877.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-08T11:17:57.618Z,1694171877.618 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-08T11:17:57.818Z,1694171877.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-08T11:17:57.820Z,1694171877.820 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-08T11:17:58.514Z,1694171878.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-08T11:17:58.622Z,1694171878.622 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-08T11:17:58.926Z,1694171878.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-08T11:17:58.928Z,1694171878.928 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-08T11:17:59.041Z,1694171879.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-08T11:17:59.043Z,1694171879.043 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-08T11:17:59.844Z,1694171879.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-08T11:17:59.845Z,1694171879.845 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-08T11:18:00.185Z,1694171880.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-08T11:18:00.187Z,1694171880.187 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-08T11:18:00.425Z,1694171880.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-08T11:18:00.427Z,1694171880.427 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-09-08T11:18:00.429Z,1694171880.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-09-08T11:18:00.553Z,1694171880.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-09-08T11:18:00.637Z,1694171880.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-09-08T11:18:00.775Z,1694171880.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-09-08T11:18:00.862Z,1694171880.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-09-08T11:18:01.130Z,1694171881.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-08T11:18:01.130Z,1694171881.130 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-09-08T11:18:01.236Z,1694171881.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-09-08T11:18:01.345Z,1694171881.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-09-08T11:18:01.466Z,1694171881.466 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-09-08T11:18:01.617Z,1694171881.617 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-09-08T11:18:01.717Z,1694171881.717 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-09-08T11:18:01.817Z,1694171881.817 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-09-08T11:18:01.835Z,1694171881.835 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-08T11:18:01.852Z,1694171881.852 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-08T11:18:01.853Z,1694171881.853 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-08T11:18:01.978Z,1694171881.978 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-08T11:18:01.979Z,1694171881.979 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-08T11:18:02.049Z,1694171882.049 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-08T11:18:02.111Z,1694171882.111 [VerticalControl] Loaded
2023-09-08T11:18:02.111Z,1694171882.111 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-08T11:18:02.114Z,1694171882.114 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-08T11:18:02.157Z,1694171882.157 [HorizontalControl] Loaded
2023-09-08T11:18:02.157Z,1694171882.157 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-08T11:18:02.159Z,1694171882.159 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-08T11:18:02.162Z,1694171882.162 [SpeedControl] Loaded
2023-09-08T11:18:02.163Z,1694171882.163 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-08T11:18:02.165Z,1694171882.165 [LoopControl](DEBUG): Construct LoopControl.
2023-09-08T11:18:02.166Z,1694171882.166 [LoopControl] Loaded
2023-09-08T11:18:02.166Z,1694171882.166 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-08T11:18:02.167Z,1694171882.167 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-08T11:18:02.167Z,1694171882.167 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-08T11:18:02.349Z,1694171882.349 [BuoyancyServo] Loaded
2023-09-08T11:18:02.349Z,1694171882.349 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-08T11:18:02.369Z,1694171882.369 [ElevatorServo] Loaded
2023-09-08T11:18:02.369Z,1694171882.369 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-08T11:18:02.389Z,1694171882.389 [MassServo] Loaded
2023-09-08T11:18:02.390Z,1694171882.390 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-08T11:18:02.408Z,1694171882.408 [RudderServo] Loaded
2023-09-08T11:18:02.409Z,1694171882.409 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-08T11:18:02.423Z,1694171882.423 [ThrusterHE] Loaded
2023-09-08T11:18:02.424Z,1694171882.424 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-08T11:18:02.424Z,1694171882.424 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-08T11:18:02.425Z,1694171882.425 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-08T11:18:02.493Z,1694171882.493 [DepthRateCalculator] Loaded
2023-09-08T11:18:02.494Z,1694171882.494 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-08T11:18:02.499Z,1694171882.499 [PitchRateCalculator] Loaded
2023-09-08T11:18:02.499Z,1694171882.499 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-08T11:18:02.509Z,1694171882.509 [SpeedCalculator] Loaded
2023-09-08T11:18:02.509Z,1694171882.509 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-08T11:18:02.513Z,1694171882.513 [YawRateCalculator] Loaded
2023-09-08T11:18:02.514Z,1694171882.514 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-08T11:18:02.532Z,1694171882.532 [ElevatorOffsetCalculator] Loaded
2023-09-08T11:18:02.533Z,1694171882.533 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-08T11:18:02.533Z,1694171882.533 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-08T11:18:02.534Z,1694171882.534 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-08T11:18:02.673Z,1694171882.673 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-09-08T11:18:02.673Z,1694171882.673 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-09-08T11:18:02.687Z,1694171882.687 [NavChart] Loaded
2023-09-08T11:18:02.688Z,1694171882.688 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-08T11:18:02.694Z,1694171882.694 [UniversalFixResidualReporter] Loaded
2023-09-08T11:18:02.694Z,1694171882.694 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-08T11:18:02.694Z,1694171882.694 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-08T11:18:02.695Z,1694171882.695 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-08T11:18:03.659Z,1694171883.659 [AHRS_M2] Loaded
2023-09-08T11:18:03.659Z,1694171883.659 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-08T11:18:03.909Z,1694171883.909 [BackseatComponent] Loaded
2023-09-08T11:18:03.909Z,1694171883.909 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-08T11:18:03.910Z,1694171883.910 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E84E0
2023-09-08T11:18:03.910Z,1694171883.910 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 930
2023-09-08T11:18:03.913Z,1694171883.913 [LcmUniversalReporter] Loaded
2023-09-08T11:18:03.914Z,1694171883.914 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-08T11:18:03.920Z,1694171883.920 [BioacousticsDataBridge] Loaded
2023-09-08T11:18:03.920Z,1694171883.920 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2023-09-08T11:18:05.015Z,1694171885.015 [BPC1] Loaded
2023-09-08T11:18:05.015Z,1694171885.015 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-08T11:18:05.346Z,1694171885.346 [DataOverHttps] Loaded
2023-09-08T11:18:05.346Z,1694171885.346 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-08T11:18:05.347Z,1694171885.347 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409184E0
2023-09-08T11:18:05.348Z,1694171885.348 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 931
2023-09-08T11:18:05.369Z,1694171885.369 [Depth_Keller] Loaded
2023-09-08T11:18:05.369Z,1694171885.369 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-08T11:18:05.374Z,1694171885.374 [DropWeight] Loaded
2023-09-08T11:18:05.375Z,1694171885.375 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-09-08T11:18:05.438Z,1694171885.438 [NAL9602] Loaded
2023-09-08T11:18:05.438Z,1694171885.438 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-08T11:18:05.469Z,1694171885.469 [Onboard] Loaded
2023-09-08T11:18:05.469Z,1694171885.469 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-08T11:18:05.470Z,1694171885.470 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409484E0
2023-09-08T11:18:05.470Z,1694171885.470 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 932
2023-09-08T11:18:05.483Z,1694171885.483 [Power24vConverter] Loaded
2023-09-08T11:18:05.483Z,1694171885.483 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-08T11:18:05.497Z,1694171885.497 [Radio_Surface] Loaded
2023-09-08T11:18:05.497Z,1694171885.497 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-08T11:18:05.498Z,1694171885.498 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409784E0
2023-09-08T11:18:05.499Z,1694171885.499 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 933
2023-09-08T11:18:05.512Z,1694171885.512 [Sonardyne_Nano] Loaded
2023-09-08T11:18:05.512Z,1694171885.512 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-09-08T11:18:05.513Z,1694171885.513 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-08T11:18:05.514Z,1694171885.514 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-08T11:18:05.596Z,1694171885.596 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-08T11:18:05.596Z,1694171885.596 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-08T11:18:05.776Z,1694171885.776 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-08T11:18:05.777Z,1694171885.777 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-08T11:18:05.820Z,1694171885.820 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-08T11:18:05.821Z,1694171885.821 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-08T11:18:06.042Z,1694171886.042 [CTD_Seabird] Loaded
2023-09-08T11:18:06.043Z,1694171886.043 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-08T11:18:06.044Z,1694171886.044 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2023-09-08T11:18:06.044Z,1694171886.044 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 934
2023-09-08T11:18:06.064Z,1694171886.064 [PAR_Licor] Loaded
2023-09-08T11:18:06.065Z,1694171886.065 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-08T11:18:06.106Z,1694171886.106 [WetLabsBB2FL] Loaded
2023-09-08T11:18:06.106Z,1694171886.106 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-08T11:18:06.107Z,1694171886.107 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2023-09-08T11:18:06.107Z,1694171886.107 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 935
2023-09-08T11:18:06.108Z,1694171886.108 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-08T11:18:06.109Z,1694171886.109 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-08T11:18:06.467Z,1694171886.467 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-08T11:18:06.467Z,1694171886.467 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-08T11:18:06.633Z,1694171886.633 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-08T11:18:06.642Z,1694171886.642 [SBIT] Loaded
2023-09-08T11:18:06.643Z,1694171886.643 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-08T11:18:06.646Z,1694171886.646 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-08T11:18:06.659Z,1694171886.659 [IBIT] Loaded
2023-09-08T11:18:06.660Z,1694171886.660 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-08T11:18:06.666Z,1694171886.666 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-08T11:18:06.772Z,1694171886.772 [CBIT] Loaded
2023-09-08T11:18:06.772Z,1694171886.772 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-08T11:18:06.773Z,1694171886.773 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-08T11:18:06.779Z,1694171886.779 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-08T11:18:06.782Z,1694171886.782 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-08T11:18:06.793Z,1694171886.793 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-08T11:18:06.794Z,1694171886.794 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0
2023-09-08T11:18:06.795Z,1694171886.795 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 936
2023-09-08T11:18:06.799Z,1694171886.799 [Supervisor](INFO): Main Thread ID is 836
2023-09-08T11:18:06.799Z,1694171886.799 [Supervisor](DEBUG): Running supervisor.
2023-09-08T11:18:06.800Z,1694171886.800 [CommandExec ThreadHandler](INFO): Handler Thread ID is 937
2023-09-08T11:18:06.800Z,1694171886.800 [CommandExec](INFO): Initializing the command executive.
2023-09-08T11:18:06.802Z,1694171886.802 [CommandLine ThreadHandler](INFO): Handler Thread ID is 938
2023-09-08T11:18:06.804Z,1694171886.804 [controlThread ThreadHandler](INFO): Handler Thread ID is 939
2023-09-08T11:18:06.804Z,1694171886.804 [controlThread](DEBUG): Initializing ControlThread
2023-09-08T11:18:06.805Z,1694171886.805 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-08T11:18:06.807Z,1694171886.807 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-08T11:18:06.808Z,1694171886.808 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-08T11:18:06.808Z,1694171886.808 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-08T11:18:06.810Z,1694171886.810 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-08T11:18:06.810Z,1694171886.810 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-08T11:18:06.810Z,1694171886.810 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-08T11:18:06.811Z,1694171886.811 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-08T11:18:06.811Z,1694171886.811 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-08T11:18:06.812Z,1694171886.812 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-08T11:18:06.813Z,1694171886.813 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-08T11:18:06.821Z,1694171886.821 [SBIT](INFO): Initialize SBIT Component.
2023-09-08T11:18:06.822Z,1694171886.822 [SBIT](IMPORTANT): git: 2023-09-07
2023-09-08T11:18:06.822Z,1694171886.822 [SBIT](INFO): git hash: de73b65bba9e058a7fe4bf2bc7b950ee8b764c06
2023-09-08T11:18:06.822Z,1694171886.822 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-08T11:18:06.823Z,1694171886.823 [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
2023-09-08T11:18:06.824Z,1694171886.824 [SBIT](INFO): Beginning SBIT in 122.000000 seconds.
2023-09-08T11:18:06.825Z,1694171886.825 [IBIT](INFO): Initialize IBIT Component.
2023-09-08T11:18:06.826Z,1694171886.826 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-08T11:18:06.827Z,1694171886.827 [logger ThreadHandler](INFO): Handler Thread ID is 940
2023-09-08T11:18:06.837Z,1694171886.837 [CBIT](DEBUG): Initialized mux pins.
2023-09-08T11:18:06.837Z,1694171886.837 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-08T11:18:06.845Z,1694171886.845 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 941
2023-09-08T11:18:06.857Z,1694171886.857 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 942
2023-09-08T11:18:06.858Z,1694171886.858 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-08T11:18:06.861Z,1694171886.861 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-08T11:18:06.861Z,1694171886.861 [CBIT](DEBUG): Initializing heartbeat.
2023-09-08T11:18:06.869Z,1694171886.869 [Onboard ThreadHandler](INFO): Handler Thread ID is 943
2023-09-08T11:18:06.887Z,1694171886.887 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 944
2023-09-08T11:18:06.906Z,1694171886.906 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 945
2023-09-08T11:18:06.906Z,1694171886.906 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-08T11:18:06.910Z,1694171886.910 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 947
2023-09-08T11:18:06.913Z,1694171886.913 [WetLabsBB2FL](INFO): Powering up
2023-09-08T11:18:06.914Z,1694171886.914 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 949
2023-09-08T11:18:06.923Z,1694171886.923 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-08T11:18:06.923Z,1694171886.923 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-09-08T11:18:06.923Z,1694171886.923 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-08T11:18:06.924Z,1694171886.924 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-09-08T11:18:06.924Z,1694171886.924 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-08T11:18:06.924Z,1694171886.924 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-09-08T11:18:06.924Z,1694171886.924 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-08T11:18:06.924Z,1694171886.924 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-09-08T11:18:06.925Z,1694171886.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-08T11:18:06.925Z,1694171886.925 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-09-08T11:18:06.925Z,1694171886.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-08T11:18:06.925Z,1694171886.925 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-09-08T11:18:06.925Z,1694171886.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-08T11:18:06.926Z,1694171886.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000
2023-09-08T11:18:06.926Z,1694171886.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-08T11:18:06.926Z,1694171886.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000
2023-09-08T11:18:06.926Z,1694171886.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-08T11:18:06.926Z,1694171886.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000
2023-09-08T11:18:06.926Z,1694171886.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T11:18:06.927Z,1694171886.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000
2023-09-08T11:18:06.927Z,1694171886.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-08T11:18:06.927Z,1694171886.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000
2023-09-08T11:18:06.927Z,1694171886.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T11:18:06.927Z,1694171886.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000
2023-09-08T11:18:06.927Z,1694171886.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-08T11:18:06.927Z,1694171886.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000
2023-09-08T11:18:06.933Z,1694171886.933 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-08T11:18:06.933Z,1694171886.933 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-08T11:18:06.973Z,1694171886.973 [CBIT](DEBUG): Backplane powered.
2023-09-08T11:18:06.974Z,1694171886.974 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-08T11:18:06.999Z,1694171886.999 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T11:18:07.043Z,1694171887.043 [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>


2023-09-08T11:18:07.043Z,1694171887.043 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-08T11:18:07.105Z,1694171887.105 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-08T11:18:07.107Z,1694171887.107 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-08T11:18:07.121Z,1694171887.121 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T11:18:07.145Z,1694171887.145 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-08T11:18:07.147Z,1694171887.147 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-08T11:18:07.177Z,1694171887.177 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-08T11:18:07.180Z,1694171887.180 [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="P4H">
                <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>


2023-09-08T11:18:07.201Z,1694171887.201 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-09-08T11:18:07.208Z,1694171887.208 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-08T11:18:07.286Z,1694171887.286 [Depth_Keller](ERROR): Pressure or depth reading out of range: 963.111633 decibar, 0.000000 m
2023-09-08T11:18:07.287Z,1694171887.287 [Power24vConverter](INFO): Powering up.
2023-09-08T11:18:07.287Z,1694171887.287 [Sonardyne_Nano](INFO): Initializing.
2023-09-08T11:18:07.293Z,1694171887.293 [Radio_Surface](INFO): Powering up
2023-09-08T11:18:07.322Z,1694171887.322 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-09-08T11:18:07.388Z,1694171887.388 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-08T11:18:07.397Z,1694171887.397 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-08T11:18:07.398Z,1694171887.398 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-08T11:18:07.409Z,1694171887.409 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-08T11:18:07.410Z,1694171887.410 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-08T11:18:07.417Z,1694171887.417 [MassServo](DEBUG): Initializing MassServo.
2023-09-08T11:18:07.418Z,1694171887.418 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-08T11:18:07.426Z,1694171887.426 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-08T11:18:07.426Z,1694171887.426 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-08T11:18:07.433Z,1694171887.433 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-08T11:18:09.021Z,1694171889.021 [WetLabsBB2FL](INFO): Powering down
2023-09-08T11:18:09.231Z,1694171889.231 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-09-08T11:18:10.518Z,1694171890.518 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2201
2023-09-08T11:18:13.102Z,1694171893.102 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-08T11:18:13.973Z,1694171893.973 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-09-08T11:18:35.500Z,1694171915.500 [NAL9602](INFO): Powering up NAL9602
2023-09-08T11:18:46.406Z,1694171926.406 [NAL9602](INFO): NAL9602 initialized
2023-09-08T11:19:23.980Z,1694171963.980 [NAL9602](INFO): SBD MO Status=0, MOMSN=3459, MT Status=0, MTMSN=0
2023-09-08T11:19:23.980Z,1694171963.980 [NAL9602](INFO): No messages in MT queue
2023-09-08T11:19:25.196Z,1694171965.196 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,111923.00,A,4145.52945,N,08116.65077,W,0.019,0.00,080923,,,A*7B
2023-09-08T11:19:25.199Z,1694171965.199 [NAL9602](INFO): GPS fix at 20230908T111923: (41.758824, -81.277513)
2023-09-08T11:19:57.514Z,1694171997.514 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T11:20:09.293Z,1694172009.293 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-08T11:20:09.301Z,1694172009.301 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-08T11:20:20.184Z,1694172020.184 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006413
CHAN A1 (24V): -0.007568
CHAN A2 (12V): -0.004377
CHAN A3 (5V): -0.002801
CHAN B0 (3.3V): -0.000742
CHAN B1 (3.15aV): -0.000908
CHAN B2 (3.15bV): -0.000669
CHAN B3 (GND): -0.000481
OPEN: -0.004762
Full Scale: +/- 1 mA
2023-09-08T11:21:03.397Z,1694172063.397 [SBIT](IMPORTANT): SBIT PASSED
2023-09-08T11:21:03.397Z,1694172063.397 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-09-08T11:21:03.398Z,1694172063.398 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-09-08T11:21:03.398Z,1694172063.398 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-09-08T11:21:03.398Z,1694172063.398 [SBIT](IMPORTANT): Express linearApproximation BackseatComponent.handled_message 1.000000 bool;

2023-09-08T11:21:03.398Z,1694172063.398 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_mean_sea_water_temperature 5.000000 celsius;

2023-09-08T11:21:03.398Z,1694172063.398 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-09-08T11:21:03.398Z,1694172063.398 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-09-08T11:21:03.398Z,1694172063.398 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 50.000000 meter;

2023-09-08T11:21:03.399Z,1694172063.399 [SBIT](IMPORTANT): Express none platform_roll_angle;

2023-09-08T11:21:03.399Z,1694172063.399 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2023-09-08T11:21:03.399Z,1694172063.399 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=5 minute;

2023-09-08T11:21:03.399Z,1694172063.399 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-09-08T11:21:03.399Z,1694172063.399 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=391.543020 cubic_centimeter;

2023-09-08T11:21:03.399Z,1694172063.399 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-09-08T11:21:03.399Z,1694172063.399 [SBIT](IMPORTANT): VerticalControl.massDefault=25.724938 millimeter;

2023-09-08T11:21:03.787Z,1694172063.787 [MissionManager](IMPORTANT): Started mission Startup
2023-09-08T11:21:03.788Z,1694172063.788 [Startup] Running Loop=1
2023-09-08T11:21:03.788Z,1694172063.788 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-08T11:21:03.788Z,1694172063.788 [Startup:A.GoToSurface] Running Loop=1
2023-09-08T11:21:03.788Z,1694172063.788 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T11:21:03.789Z,1694172063.789 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T11:21:03.789Z,1694172063.789 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T11:21:03.789Z,1694172063.789 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T11:21:03.790Z,1694172063.790 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T11:21:03.790Z,1694172063.790 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T11:21:03.791Z,1694172063.791 [Startup:StartupSatComms] Running Loop=1
2023-09-08T11:21:03.792Z,1694172063.792 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-08T11:21:03.792Z,1694172063.792 [Startup:StartupSatComms:A] Running Loop=1
2023-09-08T11:21:04.188Z,1694172064.188 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-08T11:21:05.792Z,1694172065.792 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112104.00,A,4145.52932,N,08116.65139,W,0.019,0.00,080923,,,A*7E
2023-09-08T11:21:05.794Z,1694172065.794 [NAL9602](INFO): GPS fix at 20230908T112104: (41.758822, -81.277523)
2023-09-08T11:21:05.804Z,1694172065.804 [Startup:StartupSatComms:A] Stopped
2023-09-08T11:21:05.805Z,1694172065.805 [Startup:StartupSatComms:B] Running Loop=1
2023-09-08T11:21:06.223Z,1694172066.223 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T11:21:30.011Z,1694172090.011 [NAL9602](INFO): SBD MO Status=1, MOMSN=3460, MT Status=0, MTMSN=0
2023-09-08T11:21:30.061Z,1694172090.061 [NAL9602](INFO): Sent 39 bytes from file Logs/20230908T004914/Courier0208.lzma
2023-09-08T11:21:30.061Z,1694172090.061 [NAL9602](INFO): Packets left to send: 0
2023-09-08T11:21:41.595Z,1694172101.595 [NAL9602](INFO): SBD MO Status=1, MOMSN=3461, MT Status=0, MTMSN=0
2023-09-08T11:21:41.653Z,1694172101.653 [NAL9602](INFO): Sent 267 bytes from file Logs/20230908T111755/Courier0000.lzma
2023-09-08T11:21:41.653Z,1694172101.653 [NAL9602](INFO): Packets left to send: 0
2023-09-08T11:21:51.359Z,1694172111.359 [NAL9602](INFO): SBD MO Status=1, MOMSN=3462, MT Status=0, MTMSN=0
2023-09-08T11:21:51.417Z,1694172111.417 [NAL9602](INFO): Sent 191 bytes from file Logs/20230908T004914/Express0209.lzma
2023-09-08T11:21:51.417Z,1694172111.417 [NAL9602](INFO): Packets left to send: 0
2023-09-08T11:22:06.170Z,1694172126.170 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-08T11:21:05.8Z
2023-09-08T11:22:06.170Z,1694172126.170 [Startup:StartupSatComms:B] Stopped
2023-09-08T11:22:06.170Z,1694172126.170 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-09-08T11:22:06.170Z,1694172126.170 [Startup:StartupSatComms] Stopped
2023-09-08T11:22:06.170Z,1694172126.170 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-08T11:22:06.171Z,1694172126.171 [Startup](INFO): Completed Startup
2023-09-08T11:22:06.171Z,1694172126.171 [MissionManager](INFO): Startup is completed.
2023-09-08T11:22:06.171Z,1694172126.171 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-08T11:22:06.171Z,1694172126.171 [Startup] Stopped
2023-09-08T11:22:06.171Z,1694172126.171 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-08T11:22:06.172Z,1694172126.172 [Startup:A.GoToSurface] Stopped
2023-09-08T11:22:06.172Z,1694172126.172 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T11:22:06.585Z,1694172126.585 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T11:22:06.585Z,1694172126.585 [Default] Running Loop=1
2023-09-08T11:22:06.585Z,1694172126.585 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T11:22:06.585Z,1694172126.585 [Default:B.GoToSurface] Running Loop=1
2023-09-08T11:22:06.585Z,1694172126.585 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T11:22:06.585Z,1694172126.585 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T11:22:06.586Z,1694172126.586 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T11:22:06.586Z,1694172126.586 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T11:22:06.586Z,1694172126.586 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T11:22:06.587Z,1694172126.587 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T11:22:06.587Z,1694172126.587 [Default:A.Wait] Running Loop=1
2023-09-08T11:22:06.587Z,1694172126.587 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:22:08.983Z,1694172128.983 [NAL9602](INFO): SBD MO Status=1, MOMSN=3463, MT Status=0, MTMSN=0
2023-09-08T11:22:09.045Z,1694172129.045 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T111755/Express0001.lzma
2023-09-08T11:22:09.045Z,1694172129.045 [NAL9602](INFO): Packets left to send: 3
2023-09-08T11:22:19.960Z,1694172139.960 [Default:A.Wait](INFO): Done Waiting.
2023-09-08T11:22:19.960Z,1694172139.960 [Default:A.Wait] Stopped
2023-09-08T11:22:19.960Z,1694172139.960 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T11:22:20.307Z,1694172140.307 [Default:CheckIn] Running Loop=1
2023-09-08T11:22:20.308Z,1694172140.308 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T11:22:20.308Z,1694172140.308 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T11:22:20.721Z,1694172140.721 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-08T11:22:22.316Z,1694172142.316 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112220.00,A,4145.52976,N,08116.65098,W,0.019,0.00,080923,,,A*71
2023-09-08T11:22:22.332Z,1694172142.332 [NAL9602](INFO): GPS fix at 20230908T112220: (41.758829, -81.277516)
2023-09-08T11:22:22.342Z,1694172142.342 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T11:22:22.342Z,1694172142.342 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T11:22:22.756Z,1694172142.756 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T11:22:55.591Z,1694172175.591 [NAL9602](INFO): SBD MO Status=2, MOMSN=3464, MT Status=2, MTMSN=0
2023-09-08T11:22:55.591Z,1694172175.591 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T11:23:21.617Z,1694172201.617 [NAL9602](INFO): SBD MO Status=2, MOMSN=3464, MT Status=2, MTMSN=0
2023-09-08T11:23:21.617Z,1694172201.617 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T11:23:50.191Z,1694172230.191 [NAL9602](INFO): SBD MO Status=1, MOMSN=3464, MT Status=0, MTMSN=0
2023-09-08T11:23:50.249Z,1694172230.249 [NAL9602](INFO): Sent 72 bytes from file Logs/20230908T111755/Courier0004.lzma
2023-09-08T11:23:50.249Z,1694172230.249 [NAL9602](INFO): Packets left to send: 0
2023-09-08T11:24:04.067Z,1694172244.067 [NAL9602](INFO): SBD MO Status=1, MOMSN=3465, MT Status=0, MTMSN=0
2023-09-08T11:24:04.129Z,1694172244.129 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T111755/Express0001.lzma
2023-09-08T11:24:04.129Z,1694172244.129 [NAL9602](INFO): Packets left to send: 2
2023-09-08T11:24:14.276Z,1694172254.276 [NAL9602](INFO): SBD MO Status=1, MOMSN=3466, MT Status=0, MTMSN=0
2023-09-08T11:24:14.333Z,1694172254.333 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T111755/Express0001.lzma
2023-09-08T11:24:14.333Z,1694172254.333 [NAL9602](INFO): Packets left to send: 1
2023-09-08T11:24:28.469Z,1694172268.469 [NAL9602](INFO): SBD MO Status=1, MOMSN=3467, MT Status=0, MTMSN=0
2023-09-08T11:24:28.529Z,1694172268.529 [NAL9602](INFO): Sent 133 bytes from file Logs/20230908T111755/Express0001.lzma
2023-09-08T11:24:28.529Z,1694172268.529 [NAL9602](INFO): Packets left to send: 0
2023-09-08T11:24:39.520Z,1694172279.520 [NAL9602](INFO): SBD MO Status=1, MOMSN=3468, MT Status=0, MTMSN=0
2023-09-08T11:24:39.569Z,1694172279.569 [NAL9602](INFO): Sent 105 bytes from file Logs/20230908T111755/Express0005.lzma
2023-09-08T11:24:39.569Z,1694172279.569 [NAL9602](INFO): Packets left to send: 0
2023-09-08T11:24:57.904Z,1694172297.904 [NAL9602](INFO): SBD MO Status=2, MOMSN=3469, MT Status=2, MTMSN=0
2023-09-08T11:24:57.905Z,1694172297.905 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T11:25:04.531Z,1694172304.531 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-09-08T11:25:08.404Z,1694172308.404 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-09-08T11:25:12.719Z,1694172312.719 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-09-08T11:25:16.655Z,1694172316.655 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-09-08T11:25:24.628Z,1694172324.628 [NAL9602](INFO): SBD MO Status=0, MOMSN=3469, MT Status=0, MTMSN=0
2023-09-08T11:25:24.717Z,1694172324.717 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T11:25:24.717Z,1694172324.717 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T11:25:24.717Z,1694172324.717 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:25:55.402Z,1694172355.402 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T11:26:09.961Z,1694172369.961 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2023-09-08T11:26:09.969Z,1694172369.969 [BPC1](INFO): Received data from all battery sticks.
2023-09-08T11:26:57.400Z,1694172417.400 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005532
2023-09-08T11:28:08.329Z,1694172488.329 [Power24vConverter](INFO): Powering down.
2023-09-08T11:30:25.391Z,1694172625.391 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T11:30:25.391Z,1694172625.391 [Default:CheckIn:C.Wait] Stopped
2023-09-08T11:30:25.391Z,1694172625.391 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T11:30:25.391Z,1694172625.391 [Default:CheckIn:D] Running Loop=1
2023-09-08T11:30:25.760Z,1694172625.760 [Default:CheckIn:D] Stopped
2023-09-08T11:30:25.760Z,1694172625.760 [Default:CheckIn:E] Running Loop=1
2023-09-08T11:30:26.176Z,1694172626.176 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.319582 min 
2023-09-08T11:30:26.176Z,1694172626.176 [Default:CheckIn:E] Stopped
2023-09-08T11:30:26.176Z,1694172626.176 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T11:30:26.185Z,1694172626.185 [Default:CheckIn] Stopped
2023-09-08T11:30:26.185Z,1694172626.185 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T11:30:26.185Z,1694172626.185 [Default:CheckIn](INFO): Running loop #2
2023-09-08T11:30:26.185Z,1694172626.185 [Default:CheckIn] Running Loop=2
2023-09-08T11:30:26.185Z,1694172626.185 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T11:30:26.185Z,1694172626.185 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T11:30:28.184Z,1694172628.184 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113027.00,A,4145.52928,N,08116.65129,W,0.019,0.00,080923,,,D*70
2023-09-08T11:30:28.202Z,1694172628.202 [NAL9602](INFO): GPS fix at 20230908T113027: (41.758821, -81.277522)
2023-09-08T11:30:28.261Z,1694172628.261 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T11:30:28.261Z,1694172628.261 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T11:30:34.127Z,1694172634.127 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230908T111755/Courier0007.lzma
2023-09-08T11:30:35.139Z,1694172635.139 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0007.lzma.bak
2023-09-08T11:30:35.139Z,1694172635.139 [DataOverHttps](INFO): SBD MOMSN=18630401
2023-09-08T11:30:42.039Z,1694172642.039 [NAL9602](INFO): SBD MO Status=0, MOMSN=3470, MT Status=0, MTMSN=0
2023-09-08T11:30:42.039Z,1694172642.039 [NAL9602](INFO): No messages in MT queue
2023-09-08T11:30:50.742Z,1694172650.742 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T113049
2023-09-08T11:30:58.072Z,1694172658.072 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20230908T111755/Express0008.lzma
2023-09-08T11:30:58.076Z,1694172658.076 [DataOverHttps](INFO): Received command: ibit; failc
2023-09-08T11:30:58.106Z,1694172658.106 [CommandExec](IMPORTANT): got command ibit
2023-09-08T11:30:58.106Z,1694172658.106 [CommandExec](IMPORTANT): got command failComponent
2023-09-08T11:30:58.106Z,1694172658.106 [CommandExec](IMPORTANT): Failed components:
2023-09-08T11:30:58.107Z,1694172658.107 [CommandExec](IMPORTANT): No failed Components.
2023-09-08T11:30:58.284Z,1694172658.284 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-09-08T11:30:58.284Z,1694172658.284 [IBIT](IMPORTANT): Beginning control surface checks.
2023-09-08T11:30:58.288Z,1694172658.288 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-08T11:30:59.299Z,1694172659.299 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0008.lzma.bak
2023-09-08T11:30:59.299Z,1694172659.299 [DataOverHttps](INFO): SBD MOMSN=18630406
2023-09-08T11:30:59.881Z,1694172659.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113059.00,A,4145.52961,N,08116.65119,W,0.058,0.00,080923,,,D*72
2023-09-08T11:30:59.884Z,1694172659.884 [NAL9602](INFO): GPS fix at 20230908T113059: (41.758827, -81.277520)
2023-09-08T11:31:01.186Z,1694172661.186 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-09-08T11:31:03.116Z,1694172663.116 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113102.00,A,4145.52955,N,08116.65121,W,0.019,0.00,080923,,,D*74
2023-09-08T11:31:03.126Z,1694172663.126 [NAL9602](INFO): GPS fix at 20230908T113102: (41.758826, -81.277520)
2023-09-08T11:31:05.958Z,1694172665.958 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113105.00,A,4145.52955,N,08116.65119,W,0.019,0.00,080923,,,D*78
2023-09-08T11:31:05.960Z,1694172665.960 [NAL9602](INFO): GPS fix at 20230908T113105: (41.758826, -81.277520)
2023-09-08T11:31:09.181Z,1694172669.181 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113108.00,A,4145.52963,N,08116.65122,W,0.000,0.00,080923,,,D*70
2023-09-08T11:31:09.183Z,1694172669.183 [NAL9602](INFO): GPS fix at 20230908T113108: (41.758827, -81.277520)
2023-09-08T11:31:09.213Z,1694172669.213 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007489
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.004181
CHAN A3 (5V): -0.002128
CHAN B0 (3.3V): -0.000993
CHAN B1 (3.15aV): -0.001038
CHAN B2 (3.15bV): -0.000800
CHAN B3 (GND): -0.000837
OPEN: 0.003275
Full Scale: +/- 1 mA
2023-09-08T11:31:12.008Z,1694172672.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113111.00,A,4145.52971,N,08116.65124,W,0.019,0.00,080923,,,D*75
2023-09-08T11:31:12.010Z,1694172672.010 [NAL9602](INFO): GPS fix at 20230908T113111: (41.758828, -81.277521)
2023-09-08T11:31:14.832Z,1694172674.832 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113114.00,A,4145.52976,N,08116.65128,W,0.000,0.00,080923,,,D*73
2023-09-08T11:31:14.838Z,1694172674.838 [NAL9602](INFO): GPS fix at 20230908T113114: (41.758829, -81.277521)
2023-09-08T11:31:18.063Z,1694172678.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113117.00,A,4145.52975,N,08116.65138,W,0.019,0.00,080923,,,D*7A
2023-09-08T11:31:18.065Z,1694172678.065 [NAL9602](INFO): GPS fix at 20230908T113117: (41.758829, -81.277523)
2023-09-08T11:31:18.521Z,1694172678.521 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230908T111755/Courier0010.lzma
2023-09-08T11:31:19.522Z,1694172679.522 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0010.lzma.bak
2023-09-08T11:31:19.523Z,1694172679.523 [DataOverHttps](INFO): SBD MOMSN=18630410
2023-09-08T11:31:20.902Z,1694172680.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113120.00,A,4145.52979,N,08116.65143,W,0.019,0.00,080923,,,D*7E
2023-09-08T11:31:20.904Z,1694172680.904 [NAL9602](INFO): GPS fix at 20230908T113120: (41.758830, -81.277524)
2023-09-08T11:31:24.132Z,1694172684.132 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113123.00,A,4145.52972,N,08116.65149,W,0.019,0.00,080923,,,D*7C
2023-09-08T11:31:24.134Z,1694172684.134 [NAL9602](INFO): GPS fix at 20230908T113123: (41.758829, -81.277525)
2023-09-08T11:31:26.952Z,1694172686.952 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113126.00,A,4145.52959,N,08116.65154,W,0.039,0.00,080923,,,D*7E
2023-09-08T11:31:26.954Z,1694172686.954 [NAL9602](INFO): GPS fix at 20230908T113126: (41.758826, -81.277526)
2023-09-08T11:31:30.188Z,1694172690.188 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113129.00,A,4145.52946,N,08116.65157,W,0.019,0.00,080923,,,D*7E
2023-09-08T11:31:30.190Z,1694172690.190 [NAL9602](INFO): GPS fix at 20230908T113129: (41.758824, -81.277526)
2023-09-08T11:31:33.011Z,1694172693.011 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113132.00,A,4145.52943,N,08116.65146,W,0.039,0.00,080923,,,D*73
2023-09-08T11:31:33.013Z,1694172693.013 [NAL9602](INFO): GPS fix at 20230908T113132: (41.758824, -81.277524)
2023-09-08T11:31:35.844Z,1694172695.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113135.00,A,4145.52937,N,08116.65140,W,0.019,0.00,080923,,,D*73
2023-09-08T11:31:35.846Z,1694172695.846 [NAL9602](INFO): GPS fix at 20230908T113135: (41.758823, -81.277523)
2023-09-08T11:31:38.029Z,1694172698.029 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20230908T111755/Express0011.lzma
2023-09-08T11:31:39.030Z,1694172699.030 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0011.lzma.bak
2023-09-08T11:31:39.031Z,1694172699.031 [DataOverHttps](INFO): SBD MOMSN=18630412
2023-09-08T11:31:39.080Z,1694172699.080 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113138.00,A,4145.52925,N,08116.65142,W,0.058,0.00,080923,,,D*7A
2023-09-08T11:31:39.083Z,1694172699.083 [NAL9602](INFO): GPS fix at 20230908T113138: (41.758821, -81.277524)
2023-09-08T11:31:41.900Z,1694172701.900 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113141.00,A,4145.52914,N,08116.65133,W,0.019,0.00,080923,,,D*75
2023-09-08T11:31:41.902Z,1694172701.902 [NAL9602](INFO): GPS fix at 20230908T113141: (41.758819, -81.277522)
2023-09-08T11:31:44.800Z,1694172704.800 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 41.758820 Longitude: -81.277519
2023-09-08T11:31:45.141Z,1694172705.141 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113144.00,A,4145.52909,N,08116.65135,W,0.019,0.00,080923,,,D*7A
2023-09-08T11:31:45.143Z,1694172705.143 [NAL9602](INFO): GPS fix at 20230908T113144: (41.758818, -81.277523)
2023-09-08T11:31:45.175Z,1694172705.175 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.770000
2023-09-08T11:31:45.176Z,1694172705.176 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-09-08T11:31:45.176Z,1694172705.176 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-09-08T11:31:45.586Z,1694172705.586 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-09-08T11:31:45.586Z,1694172705.586 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-09-08T11:31:45.586Z,1694172705.586 [IBIT](IMPORTANT): Pressure:10.167402 PSI
2023-09-08T11:31:45.587Z,1694172705.587 [IBIT](IMPORTANT): Humidity:5.200830 %
2023-09-08T11:31:46.008Z,1694172706.008 [IBIT](IMPORTANT): Vehicle Pitch:-1.427046 degrees
2023-09-08T11:31:46.009Z,1694172706.009 [IBIT](IMPORTANT): Vehicle  Roll:-2.663651 degrees
2023-09-08T11:31:46.009Z,1694172706.009 [IBIT](IMPORTANT): Vehicle  Heading:299.277496 degrees
2023-09-08T11:31:46.378Z,1694172706.378 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-09-08T11:31:46.379Z,1694172706.379 [IBIT](IMPORTANT): buoyancyNeutral: 391.543030 cc
2023-09-08T11:31:46.379Z,1694172706.379 [IBIT](IMPORTANT): massDefault: 2.572494 cm
2023-09-08T11:31:46.379Z,1694172706.379 [IBIT](IMPORTANT): stopDepth: 100.000000 m
2023-09-08T11:31:46.379Z,1694172706.379 [IBIT](IMPORTANT): abortDepth: 120.000000 m
2023-09-08T11:31:46.379Z,1694172706.379 [IBIT](IMPORTANT): IBIT PASSED
2023-09-08T11:31:46.785Z,1694172706.785 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T11:31:46.785Z,1694172706.785 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T11:31:46.785Z,1694172706.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:31:53.442Z,1694172713.442 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T113152
2023-09-08T11:32:00.756Z,1694172720.756 [DataOverHttps](INFO): Received command: get depth; get distance_from_shore
2023-09-08T11:32:01.548Z,1694172721.548 [CommandExec](IMPORTANT): got command get depth
2023-09-08T11:32:01.549Z,1694172721.549 [CommandExec](IMPORTANT): depth -0.044754 m
2023-09-08T11:32:01.549Z,1694172721.549 [CommandExec](IMPORTANT): got command get distance_from_shore
2023-09-08T11:32:01.549Z,1694172721.549 [CommandExec](FAULT): Element has no value
2023-09-08T11:32:17.454Z,1694172737.454 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T11:36:47.418Z,1694173007.418 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T11:36:47.419Z,1694173007.419 [Default:CheckIn:C.Wait] Stopped
2023-09-08T11:36:47.419Z,1694173007.419 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T11:36:47.419Z,1694173007.419 [Default:CheckIn:D] Running Loop=1
2023-09-08T11:36:47.865Z,1694173007.865 [Default:CheckIn:D] Stopped
2023-09-08T11:36:47.865Z,1694173007.865 [Default:CheckIn:E] Running Loop=1
2023-09-08T11:36:48.228Z,1694173008.228 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.687658 min 
2023-09-08T11:36:48.228Z,1694173008.228 [Default:CheckIn:E] Stopped
2023-09-08T11:36:48.228Z,1694173008.228 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T11:36:48.228Z,1694173008.228 [Default:CheckIn] Stopped
2023-09-08T11:36:48.228Z,1694173008.228 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T11:36:48.228Z,1694173008.228 [Default:CheckIn](INFO): Running loop #3
2023-09-08T11:36:48.229Z,1694173008.229 [Default:CheckIn] Running Loop=3
2023-09-08T11:36:48.229Z,1694173008.229 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T11:36:48.229Z,1694173008.229 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T11:36:50.240Z,1694173010.240 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113649.00,A,4145.52879,N,08116.65026,W,0.019,0.00,080923,,,D*75
2023-09-08T11:36:50.242Z,1694173010.242 [NAL9602](INFO): GPS fix at 20230908T113649: (41.758813, -81.277504)
2023-09-08T11:36:50.296Z,1694173010.296 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T11:36:50.296Z,1694173010.296 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T11:36:55.959Z,1694173015.959 [NAL9602](INFO): SBD MO Status=0, MOMSN=3471, MT Status=0, MTMSN=0
2023-09-08T11:36:55.959Z,1694173015.959 [NAL9602](INFO): No messages in MT queue
2023-09-08T11:37:00.760Z,1694173020.760 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0013.lzma
2023-09-08T11:37:01.763Z,1694173021.763 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0013.lzma.bak
2023-09-08T11:37:01.763Z,1694173021.763 [DataOverHttps](INFO): SBD MOMSN=18630490
2023-09-08T11:37:20.073Z,1694173040.073 [DataOverHttps](INFO): Sending 788 bytes from file Logs/20230908T111755/Express0014.lzma
2023-09-08T11:37:21.075Z,1694173041.075 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0014.lzma.bak
2023-09-08T11:37:21.075Z,1694173041.075 [DataOverHttps](INFO): SBD MOMSN=18630497
2023-09-08T11:37:25.057Z,1694173045.057 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T11:37:25.057Z,1694173045.057 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T11:37:25.057Z,1694173045.057 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:37:26.654Z,1694173046.654 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T11:40:03.926Z,1694173203.926 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T114003
2023-09-08T11:40:11.220Z,1694173211.220 [DataOverHttps](INFO): Received command: get navdb.charts
2023-09-08T11:40:11.779Z,1694173211.779 [CommandExec](IMPORTANT): got command get 
2023-09-08T11:40:11.779Z,1694173211.779 [CommandExec](FAULT): Incomplete syntax. Try: help get
2023-09-08T11:42:25.708Z,1694173345.708 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T11:42:25.708Z,1694173345.708 [Default:CheckIn:C.Wait] Stopped
2023-09-08T11:42:25.708Z,1694173345.708 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T11:42:25.708Z,1694173345.708 [Default:CheckIn:D] Running Loop=1
2023-09-08T11:42:26.120Z,1694173346.120 [Default:CheckIn:D] Stopped
2023-09-08T11:42:26.120Z,1694173346.120 [Default:CheckIn:E] Running Loop=1
2023-09-08T11:42:26.515Z,1694173346.515 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.325580 min 
2023-09-08T11:42:26.515Z,1694173346.515 [Default:CheckIn:E] Stopped
2023-09-08T11:42:26.515Z,1694173346.515 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T11:42:26.515Z,1694173346.515 [Default:CheckIn] Stopped
2023-09-08T11:42:26.515Z,1694173346.515 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T11:42:26.515Z,1694173346.515 [Default:CheckIn](INFO): Running loop #4
2023-09-08T11:42:26.515Z,1694173346.515 [Default:CheckIn] Running Loop=4
2023-09-08T11:42:26.515Z,1694173346.515 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T11:42:26.515Z,1694173346.515 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T11:42:28.527Z,1694173348.527 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,114227.00,A,4145.52809,N,08116.64969,W,0.019,0.00,080923,,,D*7A
2023-09-08T11:42:28.529Z,1694173348.529 [NAL9602](INFO): GPS fix at 20230908T114227: (41.758801, -81.277495)
2023-09-08T11:42:28.539Z,1694173348.539 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T11:42:28.539Z,1694173348.539 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T11:42:39.312Z,1694173359.312 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0016.lzma
2023-09-08T11:42:40.315Z,1694173360.315 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0016.lzma.bak
2023-09-08T11:42:40.315Z,1694173360.315 [DataOverHttps](INFO): SBD MOMSN=18630537
2023-09-08T11:42:41.995Z,1694173361.995 [NAL9602](INFO): SBD MO Status=0, MOMSN=3472, MT Status=0, MTMSN=0
2023-09-08T11:42:41.996Z,1694173361.996 [NAL9602](INFO): No messages in MT queue
2023-09-08T11:43:04.604Z,1694173384.604 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20230908T111755/Express0018.lzma
2023-09-08T11:43:05.607Z,1694173385.607 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0018.lzma.bak
2023-09-08T11:43:05.607Z,1694173385.607 [DataOverHttps](INFO): SBD MOMSN=18630543
2023-09-08T11:43:09.580Z,1694173389.580 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T11:43:09.580Z,1694173389.580 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T11:43:09.580Z,1694173389.580 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:43:12.785Z,1694173392.785 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T11:47:33.786Z,1694173653.786 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T114732
2023-09-08T11:47:41.072Z,1694173661.072 [DataOverHttps](INFO): Received command: run Maintenance/run_backseat_on_surface.tl
2023-09-08T11:47:41.178Z,1694173661.178 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-08T11:47:41.178Z,1694173661.178 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-08T11:47:41.179Z,1694173661.179 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/run_backseat_on_surface.tx
2023-09-08T11:47:41.226Z,1694173661.226 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min
2023-09-08T11:47:41.227Z,1694173661.227 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2023-09-08T11:47:41.227Z,1694173661.227 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx
2023-09-08T11:47:41.506Z,1694173661.506 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectChlActive = 0 bool
2023-09-08T11:47:41.513Z,1694173661.513 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.TimeWindowPeakReport = nan min
2023-09-08T11:47:41.516Z,1694173661.516 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestChlPeakReportActive = 0 bool
2023-09-08T11:47:41.519Z,1694173661.519 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestSaltPeakReportActive = 0 bool
2023-09-08T11:47:41.521Z,1694173661.521 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestOilPeakReportActive = 0 bool
2023-09-08T11:47:41.530Z,1694173661.530 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PatchTracking = 0 bool
2023-09-08T11:47:41.532Z,1694173661.532 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DetectTrough = 0 bool
2023-09-08T11:47:41.535Z,1694173661.535 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.FilterWidthHorizontal = 3.000000 count
2023-09-08T11:47:41.547Z,1694173661.547 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesSlidingwindow = 100.000000 count
2023-09-08T11:47:41.592Z,1694173661.592 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.OffPeakFractionHorizontal = 80.000000 %
2023-09-08T11:47:41.595Z,1694173661.595 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectNO3Active = 0 bool
2023-09-08T11:47:41.609Z,1694173661.609 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectOilActive = 0 bool
2023-09-08T11:47:41.612Z,1694173661.612 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectFDOMActive = 0 bool
2023-09-08T11:47:41.615Z,1694173661.615 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectSalinityActive = 0 bool
2023-09-08T11:47:41.626Z,1694173661.626 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-09-08T11:47:41.630Z,1694173661.630 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledAanderaaO2 = 0 bool
2023-09-08T11:47:41.633Z,1694173661.633 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledNeilBrown = 0 bool
2023-09-08T11:47:41.636Z,1694173661.636 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledSeabird = 1 bool
2023-09-08T11:47:41.652Z,1694173661.652 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsBB2FL = 1 bool
2023-09-08T11:47:41.655Z,1694173661.655 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-09-08T11:47:41.659Z,1694173661.659 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsUBAT = 0 bool
2023-09-08T11:47:41.685Z,1694173661.685 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.LowPassWindowLength = 20.000000 count
2023-09-08T11:47:41.688Z,1694173661.688 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.MedianFilterLen = 5.000000 count
2023-09-08T11:47:41.691Z,1694173661.691 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakShallowBound = nan m
2023-09-08T11:47:41.694Z,1694173661.694 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDeepBound = nan m
2023-09-08T11:47:41.706Z,1694173661.706 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-09-08T11:47:41.709Z,1694173661.709 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlThreshTriggerActive = 0 bool
2023-09-08T11:47:41.711Z,1694173661.711 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlLowerThresh = nan ug/l
2023-09-08T11:47:41.714Z,1694173661.714 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlUpperThresh = nan ug/l
2023-09-08T11:47:41.738Z,1694173661.738 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesThresh = 5.000000 count
2023-09-08T11:47:41.740Z,1694173661.740 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChl = nan ug/l
2023-09-08T11:47:41.743Z,1694173661.743 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlDepth = nan m
2023-09-08T11:47:41.746Z,1694173661.746 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlTemperature = nan degC
2023-09-08T11:47:41.759Z,1694173661.759 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLatitude = nan arcdeg
2023-09-08T11:47:41.762Z,1694173661.762 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLongitude = nan arcdeg
2023-09-08T11:47:41.765Z,1694173661.765 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3 = nan umol/l
2023-09-08T11:47:41.768Z,1694173661.768 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Depth = nan m
2023-09-08T11:47:41.790Z,1694173661.790 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Latitude = nan arcdeg
2023-09-08T11:47:41.793Z,1694173661.793 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Longitude = nan arcdeg
2023-09-08T11:47:41.796Z,1694173661.796 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOil = nan kg/m3
2023-09-08T11:47:41.810Z,1694173661.810 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilDepth = nan m
2023-09-08T11:47:41.813Z,1694173661.813 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLatitude = nan arcdeg
2023-09-08T11:47:41.815Z,1694173661.815 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLongitude = nan arcdeg
2023-09-08T11:47:41.840Z,1694173661.840 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOM = nan ppb
2023-09-08T11:47:41.843Z,1694173661.843 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMDepth = nan m
2023-09-08T11:47:41.846Z,1694173661.846 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMTemperature = nan degC
2023-09-08T11:47:41.860Z,1694173661.860 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLatitude = nan arcdeg
2023-09-08T11:47:41.863Z,1694173661.863 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLongitude = nan arcdeg
2023-09-08T11:47:41.866Z,1694173661.866 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchFDOMDistance = nan m
2023-09-08T11:47:41.923Z,1694173661.923 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSalt = nan psu
2023-09-08T11:47:41.939Z,1694173661.939 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltDepth = nan m
2023-09-08T11:47:41.957Z,1694173661.957 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltTemperature = nan degC
2023-09-08T11:47:41.960Z,1694173661.960 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLatitude = nan arcdeg
2023-09-08T11:47:41.971Z,1694173661.971 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLongitude = nan arcdeg
2023-09-08T11:47:41.976Z,1694173661.976 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChl = nan ug/l
2023-09-08T11:47:41.987Z,1694173661.987 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDepth = nan m
2023-09-08T11:47:41.994Z,1694173661.994 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLatitude = nan arcdeg
2023-09-08T11:47:42.005Z,1694173662.005 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLongitude = nan arcdeg
2023-09-08T11:47:42.008Z,1694173662.008 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDistance = nan m
2023-09-08T11:47:42.015Z,1694173662.015 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSalt = nan psu
2023-09-08T11:47:42.018Z,1694173662.018 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDepth = nan m
2023-09-08T11:47:42.029Z,1694173662.029 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLatitude = nan arcdeg
2023-09-08T11:47:42.032Z,1694173662.032 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLongitude = nan arcdeg
2023-09-08T11:47:42.039Z,1694173662.039 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDistance = nan m
2023-09-08T11:47:42.043Z,1694173662.043 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOil = nan psu
2023-09-08T11:47:42.054Z,1694173662.054 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDepth = nan m
2023-09-08T11:47:42.061Z,1694173662.061 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLatitude = nan arcdeg
2023-09-08T11:47:42.064Z,1694173662.064 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLongitude = nan arcdeg
2023-09-08T11:47:42.071Z,1694173662.071 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDistance = nan m
2023-09-08T11:47:42.090Z,1694173662.090 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.TriggeredOnChl = 0 bool
2023-09-08T11:47:42.097Z,1694173662.097 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.ChlTriggering = nan ug/l
2023-09-08T11:47:42.173Z,1694173662.173 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T11:47:42.191Z,1694173662.191 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-08T11:47:42.225Z,1694173662.225 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T11:47:42.243Z,1694173662.243 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T11:47:42.270Z,1694173662.270 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-08T11:47:42.340Z,1694173662.340 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T11:47:42.371Z,1694173662.371 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T11:47:42.402Z,1694173662.402 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-08T11:47:42.428Z,1694173662.428 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2023-09-08T11:47:42.447Z,1694173662.447 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-08T11:47:42.447Z,1694173662.447 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-08T11:47:42.467Z,1694173662.467 [MissionManager](INFO): DefineArg run_backseat_on_surface:BackseatDriver.EnableBackseat = 0 bool
2023-09-08T11:47:42.470Z,1694173662.470 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-08T11:47:42.475Z,1694173662.475 [run_backseat_on_surface:D.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T11:47:42.527Z,1694173662.527 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-08T11:47:42.530Z,1694173662.530 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-08T11:47:42.535Z,1694173662.535 [run_backseat_on_surface:G.Execute](DEBUG): Construct Execute.
2023-09-08T11:47:42.592Z,1694173662.592 [MissionManager](DEBUG): 
mission run_backseat_on_surface {
  arguments {
    ElapsedSinceMissionStarted = 0 minute
  }

  # Power the science payload.

  insert id="Science" Insert/Science.tl

  # Power the Backseat payload.

  insert Insert/BackseatDriver.tl

  assign in sequence BackseatDriver:EnableBackseat = true

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

    readDatum id="Read_Iridium" {
      timeout duration=P2H {
        behavior Guidance:Execute {
          run in sequence

          set command = "Burn on"
        }

        syslog critical "Dropped weight due to communications timeout."
      }

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceMissionStarted = elapsed ( Universal:mission_started )

    syslog important "run_backseat_on_surface mission has been running for "
         + ElapsedSinceMissionStarted~minute
  }

  syslog important "Restarting logs and run_backseat_on_surface mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2023-09-08T11:47:42.596Z,1694173662.596 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-08T11:47:42.714Z,1694173662.714 [Default] Stopped
2023-09-08T11:47:42.714Z,1694173662.714 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-08T11:47:42.714Z,1694173662.714 [Default:B.GoToSurface] Stopped
2023-09-08T11:47:42.714Z,1694173662.714 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T11:47:42.715Z,1694173662.715 [Default:CheckIn] Stopped
2023-09-08T11:47:42.715Z,1694173662.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T11:47:42.715Z,1694173662.715 [Default:CheckIn:C.Wait] Stopped
2023-09-08T11:47:42.715Z,1694173662.715 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T11:47:42.715Z,1694173662.715 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface
2023-09-08T11:47:42.715Z,1694173662.715 [run_backseat_on_surface] Running Loop=1
2023-09-08T11:47:42.715Z,1694173662.715 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface
2023-09-08T11:47:42.715Z,1694173662.715 [run_backseat_on_surface:Science] Running Loop=1
2023-09-08T11:47:42.715Z,1694173662.715 [run_backseat_on_surface:Science](DEBUG): Aggregate::initialize run_backseat_on_surface:Science
2023-09-08T11:47:42.715Z,1694173662.715 [run_backseat_on_surface:Science:A] Running Loop=1
2023-09-08T11:47:42.716Z,1694173662.716 [run_backseat_on_surface:Science:B] Running Loop=1
2023-09-08T11:47:42.716Z,1694173662.716 [run_backseat_on_surface:Science:C] Running Loop=1
2023-09-08T11:47:42.716Z,1694173662.716 [run_backseat_on_surface:Science:D] Running Loop=1
2023-09-08T11:47:42.716Z,1694173662.716 [run_backseat_on_surface:Science:E] Running Loop=1
2023-09-08T11:47:42.716Z,1694173662.716 [run_backseat_on_surface:Science:F] Running Loop=1
2023-09-08T11:47:42.716Z,1694173662.716 [run_backseat_on_surface:Science:Read_Oil] Running Loop=1
2023-09-08T11:47:42.716Z,1694173662.716 [run_backseat_on_surface:Science:PeakDetectChl] Running Loop=1
2023-09-08T11:47:42.716Z,1694173662.716 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectChl
2023-09-08T11:47:42.716Z,1694173662.716 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T11:47:42.716Z,1694173662.716 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T11:47:42.721Z,1694173662.721 [run_backseat_on_surface:Science:HighestChlPeakReport] Running Loop=1
2023-09-08T11:47:42.722Z,1694173662.722 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestChlPeakReport
2023-09-08T11:47:42.723Z,1694173662.723 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-08T11:47:42.723Z,1694173662.723 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-08T11:47:42.724Z,1694173662.724 [run_backseat_on_surface:Science:PeakDetectNO3] Running Loop=1
2023-09-08T11:47:42.724Z,1694173662.724 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectNO3
2023-09-08T11:47:42.724Z,1694173662.724 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T11:47:42.724Z,1694173662.724 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T11:47:42.726Z,1694173662.726 [run_backseat_on_surface:Science:PeakDetectOil] Running Loop=1
2023-09-08T11:47:42.726Z,1694173662.726 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectOil
2023-09-08T11:47:42.726Z,1694173662.726 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T11:47:42.726Z,1694173662.726 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T11:47:42.728Z,1694173662.728 [run_backseat_on_surface:Science:HighestOilPeakReport] Running Loop=1
2023-09-08T11:47:42.728Z,1694173662.728 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestOilPeakReport
2023-09-08T11:47:42.728Z,1694173662.728 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-08T11:47:42.729Z,1694173662.729 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-08T11:47:42.729Z,1694173662.729 [run_backseat_on_surface:Science:PeakDetectFDOM] Running Loop=1
2023-09-08T11:47:42.729Z,1694173662.729 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectFDOM
2023-09-08T11:47:42.729Z,1694173662.729 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T11:47:42.729Z,1694173662.729 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T11:47:42.731Z,1694173662.731 [run_backseat_on_surface:Science:PeakDetectSalinity] Running Loop=1
2023-09-08T11:47:42.731Z,1694173662.731 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectSalinity
2023-09-08T11:47:42.731Z,1694173662.731 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T11:47:42.731Z,1694173662.731 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T11:47:42.732Z,1694173662.732 [run_backseat_on_surface:Science:HighestSaltPeakReport] Running Loop=1
2023-09-08T11:47:42.733Z,1694173662.733 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestSaltPeakReport
2023-09-08T11:47:42.733Z,1694173662.733 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-08T11:47:42.733Z,1694173662.733 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-08T11:47:42.733Z,1694173662.733 [run_backseat_on_surface:Science:ChlThreshTrigger] Running Loop=1
2023-09-08T11:47:42.733Z,1694173662.733 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:ChlThreshTrigger
2023-09-08T11:47:42.734Z,1694173662.734 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2023-09-08T11:47:42.734Z,1694173662.734 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2023-09-08T11:47:42.734Z,1694173662.734 [run_backseat_on_surface:Science:OceanCurrent] Running Loop=1
2023-09-08T11:47:42.734Z,1694173662.734 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:OceanCurrent
2023-09-08T11:47:42.734Z,1694173662.734 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2023-09-08T11:47:42.734Z,1694173662.734 [run_backseat_on_surface:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-09-08T11:47:42.734Z,1694173662.734 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2023-09-08T11:47:42.735Z,1694173662.735 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::initialize run_backseat_on_surface:BackseatDriver
2023-09-08T11:47:42.735Z,1694173662.735 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-08T11:47:42.735Z,1694173662.735 [run_backseat_on_surface:D.GoToSurface] Running Loop=1
2023-09-08T11:47:42.735Z,1694173662.735 [run_backseat_on_surface:D.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T11:47:42.735Z,1694173662.735 [run_backseat_on_surface:D.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T11:47:42.735Z,1694173662.735 [run_backseat_on_surface:D.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T11:47:42.736Z,1694173662.736 [run_backseat_on_surface:D.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T11:47:42.736Z,1694173662.736 [run_backseat_on_surface:D.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T11:47:42.736Z,1694173662.736 [run_backseat_on_surface:D.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T11:47:42.749Z,1694173662.749 [run_backseat_on_surface:C] Running Loop=1
2023-09-08T11:47:42.750Z,1694173662.750 [run_backseat_on_surface:C] Stopped
2023-09-08T11:47:42.750Z,1694173662.750 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2023-09-08T11:47:42.750Z,1694173662.750 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2023-09-08T11:47:42.849Z,1694173662.849 [run_backseat_on_surface:Science] Running Loop=1
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:ChlThreshTrigger] Stopped
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:ChlThreshTrigger
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:HighestSaltPeakReport] Stopped
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestSaltPeakReport
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:PeakDetectSalinity] Stopped
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectSalinity
2023-09-08T11:47:42.850Z,1694173662.850 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:PeakDetectFDOM] Stopped
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectFDOM
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:HighestOilPeakReport] Stopped
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestOilPeakReport
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:PeakDetectOil] Stopped
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectOil
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-09-08T11:47:42.851Z,1694173662.851 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:PeakDetectNO3] Stopped
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectNO3
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:HighestChlPeakReport] Stopped
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestChlPeakReport
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:PeakDetectChl] Stopped
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectChl
2023-09-08T11:47:42.852Z,1694173662.852 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-09-08T11:47:42.853Z,1694173662.853 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T11:47:42.853Z,1694173662.853 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2023-09-08T11:47:42.853Z,1694173662.853 [run_backseat_on_surface:Science:Read_Oil] Stopped
2023-09-08T11:47:42.853Z,1694173662.853 [run_backseat_on_surface:Science:F] Stopped
2023-09-08T11:47:42.853Z,1694173662.853 [run_backseat_on_surface:Science:E] Running Loop=1
2023-09-08T11:47:42.862Z,1694173662.862 [run_backseat_on_surface:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-09-08T11:47:42.862Z,1694173662.862 [run_backseat_on_surface:Science:D] Stopped
2023-09-08T11:47:42.862Z,1694173662.862 [run_backseat_on_surface:Science:C] Running Loop=1
2023-09-08T11:47:42.863Z,1694173662.863 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-09-08T11:47:42.863Z,1694173662.863 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-09-08T11:47:42.863Z,1694173662.863 [run_backseat_on_surface:Science:B] Running Loop=1
2023-09-08T11:47:42.864Z,1694173662.864 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-09-08T11:47:42.865Z,1694173662.865 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-09-08T11:47:42.865Z,1694173662.865 [run_backseat_on_surface:Science:A] Stopped
2023-09-08T11:47:42.945Z,1694173662.945 [BackseatComponent](INFO): Powering up
2023-09-08T11:47:42.945Z,1694173662.945 [BackseatComponent](INFO): Subscribing to LCM channels.
2023-09-08T11:47:43.013Z,1694173663.013 [WetLabsBB2FL](INFO): Powering up
2023-09-08T11:47:43.110Z,1694173663.110 [Power24vConverter](INFO): Powering up.
2023-09-08T11:47:43.130Z,1694173663.130 [run_backseat_on_surface:CheckIn] Running Loop=1
2023-09-08T11:47:43.130Z,1694173663.130 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2023-09-08T11:47:43.130Z,1694173663.130 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2023-09-08T11:47:43.548Z,1694173663.548 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-08T11:47:45.132Z,1694173665.132 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,114744.00,A,4145.52924,N,08116.64968,W,0.039,0.00,080923,,,D*77
2023-09-08T11:47:45.134Z,1694173665.134 [NAL9602](INFO): GPS fix at 20230908T114744: (41.758821, -81.277495)
2023-09-08T11:47:45.145Z,1694173665.145 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2023-09-08T11:47:45.145Z,1694173665.145 [run_backseat_on_surface:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T11:47:45.581Z,1694173665.581 [run_backseat_on_surface:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T11:47:46.558Z,1694173666.558 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2023-09-08T11:47:54.316Z,1694173674.316 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T111755/Courier0022.lzma
2023-09-08T11:47:55.319Z,1694173675.319 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0022.lzma.bak
2023-09-08T11:47:55.319Z,1694173675.319 [DataOverHttps](INFO): SBD MOMSN=18630556
2023-09-08T11:48:02.107Z,1694173682.107 [NAL9602](INFO): SBD MO Status=0, MOMSN=3473, MT Status=0, MTMSN=0
2023-09-08T11:48:02.108Z,1694173682.108 [NAL9602](INFO): No messages in MT queue
2023-09-08T11:48:14.269Z,1694173694.269 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20230908T111755/Express0024.lzma
2023-09-08T11:48:15.271Z,1694173695.271 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0024.lzma.bak
2023-09-08T11:48:15.271Z,1694173695.271 [DataOverHttps](INFO): SBD MOMSN=18630562
2023-09-08T11:48:32.854Z,1694173712.854 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T11:48:39.460Z,1694173719.460 [DataOverHttps](INFO): Sending 60 bytes from file Logs/20230908T111755/Express0029.lzma
2023-09-08T11:48:40.463Z,1694173720.463 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0029.lzma.bak
2023-09-08T11:48:40.464Z,1694173720.464 [DataOverHttps](IMPORTANT): SBD MOMSN=18630578, MTMSN=20230908T114839
2023-09-08T11:48:47.146Z,1694173727.146 [run_backseat_on_surface:CheckIn:Read_Iridium] Stopped
2023-09-08T11:48:47.146Z,1694173727.146 [run_backseat_on_surface:CheckIn:C.Wait] Running Loop=1
2023-09-08T11:48:47.146Z,1694173727.146 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:48:47.836Z,1694173727.836 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20230908T114722
2023-09-08T11:48:50.548Z,1694173730.548 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2023-09-08T11:48:50.548Z,1694173730.548 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2023-09-08T11:48:50.551Z,1694173730.551 [BackseatComponent](INFO): Created universal data reader for latitude with code: 23
2023-09-08T11:48:50.551Z,1694173730.551 [BackseatComponent](INFO): Requesting data from latitude (23). Requested size is: 2
2023-09-08T11:48:50.554Z,1694173730.554 [BackseatComponent](INFO): Created universal data reader for longitude with code: 26
2023-09-08T11:48:50.555Z,1694173730.555 [BackseatComponent](INFO): Requesting data from longitude (26). Requested size is: 3
2023-09-08T11:48:50.557Z,1694173730.557 [BackseatComponent](INFO): Created universal data reader for platform_orientation with code: 52
2023-09-08T11:48:50.558Z,1694173730.558 [BackseatComponent](INFO): Requesting data from platform_orientation (52). Requested size is: 4
2023-09-08T11:48:50.569Z,1694173730.569 [BackseatComponent](INFO): Created universal data reader for platform_pitch_angle with code: 54
2023-09-08T11:48:50.569Z,1694173730.569 [BackseatComponent](INFO): Requesting data from platform_pitch_angle (54). Requested size is: 5
2023-09-08T11:48:50.572Z,1694173730.572 [BackseatComponent](INFO): Created universal data reader for platform_roll_angle with code: 59
2023-09-08T11:48:50.572Z,1694173730.572 [BackseatComponent](INFO): Requesting data from platform_roll_angle (59). Requested size is: 6
2023-09-08T11:48:50.575Z,1694173730.575 [BackseatComponent](INFO): Created universal data reader for platform_x_velocity_wrt_sea_water with code: 70
2023-09-08T11:48:50.575Z,1694173730.575 [BackseatComponent](INFO): Requesting data from platform_x_velocity_wrt_sea_water (70). Requested size is: 7
2023-09-08T11:48:59.704Z,1694173739.704 [DataOverHttps](INFO): Received command: sched 20230908T1158 "stop"
2023-09-08T11:48:59.749Z,1694173739.749 [CommandExec](IMPORTANT): got command schedule 20230908T115800 "stop"
2023-09-08T11:48:59.750Z,1694173739.750 [CommandExec](IMPORTANT): Scheduled #1: "stop", AT TIME: 2023-09-08T11:58:00Z
2023-09-08T11:49:33.220Z,1694173773.220 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1920
2023-09-08T11:53:47.818Z,1694174027.818 [run_backseat_on_surface:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T11:53:47.818Z,1694174027.818 [run_backseat_on_surface:CheckIn:C.Wait] Stopped
2023-09-08T11:53:47.818Z,1694174027.818 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T11:53:47.818Z,1694174027.818 [run_backseat_on_surface:CheckIn:D] Running Loop=1
2023-09-08T11:53:48.221Z,1694174028.221 [run_backseat_on_surface:CheckIn:D] Stopped
2023-09-08T11:53:48.221Z,1694174028.221 [run_backseat_on_surface:CheckIn:E] Running Loop=1
2023-09-08T11:53:48.225Z,1694174028.225 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.simrad_data_logging_rate with code: 1921
2023-09-08T11:53:48.624Z,1694174028.624 [run_backseat_on_surface:CheckIn:E](IMPORTANT): run_backseat_on_surface mission has been running for 6.091765 min 
2023-09-08T11:53:48.624Z,1694174028.624 [run_backseat_on_surface:CheckIn:E] Stopped
2023-09-08T11:53:48.624Z,1694174028.624 [run_backseat_on_surface:CheckIn](INFO): Completed run_backseat_on_surface:CheckIn
2023-09-08T11:53:48.624Z,1694174028.624 [run_backseat_on_surface:CheckIn] Stopped
2023-09-08T11:53:48.625Z,1694174028.625 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2023-09-08T11:53:48.625Z,1694174028.625 [run_backseat_on_surface:CheckIn](INFO): Running loop #2
2023-09-08T11:53:48.625Z,1694174028.625 [run_backseat_on_surface:CheckIn] Running Loop=2
2023-09-08T11:53:48.625Z,1694174028.625 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2023-09-08T11:53:48.625Z,1694174028.625 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2023-09-08T11:53:50.636Z,1694174030.636 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,115350.00,A,4145.52918,N,08116.65058,W,0.039,0.00,080923,,,D*73
2023-09-08T11:53:50.638Z,1694174030.638 [NAL9602](INFO): GPS fix at 20230908T115350: (41.758820, -81.277510)
2023-09-08T11:53:50.680Z,1694174030.680 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2023-09-08T11:53:50.680Z,1694174030.680 [run_backseat_on_surface:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T11:53:54.415Z,1694174034.415 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0031.lzma
2023-09-08T11:53:55.430Z,1694174035.430 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0031.lzma.bak
2023-09-08T11:53:55.431Z,1694174035.431 [DataOverHttps](INFO): SBD MOMSN=18630592
2023-09-08T11:54:04.891Z,1694174044.891 [NAL9602](INFO): SBD MO Status=0, MOMSN=3474, MT Status=0, MTMSN=0
2023-09-08T11:54:04.892Z,1694174044.892 [NAL9602](INFO): No messages in MT queue
2023-09-08T11:54:14.902Z,1694174054.902 [DataOverHttps](INFO): Sending 733 bytes from file Logs/20230908T111755/Express0032.lzma
2023-09-08T11:54:15.903Z,1694174055.903 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0032.lzma.bak
2023-09-08T11:54:15.903Z,1694174055.903 [DataOverHttps](INFO): SBD MOMSN=18630598
2023-09-08T11:54:35.666Z,1694174075.666 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T11:54:40.296Z,1694174080.296 [DataOverHttps](INFO): Sending 68 bytes from file Logs/20230908T111755/Express0035.lzma
2023-09-08T11:54:41.299Z,1694174081.299 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0035.lzma.bak
2023-09-08T11:54:41.299Z,1694174081.299 [DataOverHttps](INFO): SBD MOMSN=18630608
2023-09-08T11:54:47.505Z,1694174087.505 [run_backseat_on_surface:CheckIn:Read_Iridium] Stopped
2023-09-08T11:54:47.505Z,1694174087.505 [run_backseat_on_surface:CheckIn:C.Wait] Running Loop=1
2023-09-08T11:54:47.505Z,1694174087.505 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:58:00.070Z,1694174280.070 [CommandExec](IMPORTANT): got command stop
2023-09-08T11:58:00.070Z,1694174280.070 [CommandExec](IMPORTANT): Scheduling is paused
2023-09-08T11:58:00.070Z,1694174280.070 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-09-08T11:58:00.302Z,1694174280.302 [MissionManager](INFO): MissionManager is completed.
2023-09-08T11:58:00.302Z,1694174280.302 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface
2023-09-08T11:58:00.302Z,1694174280.302 [run_backseat_on_surface] Stopped
2023-09-08T11:58:00.302Z,1694174280.302 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface
2023-09-08T11:58:00.302Z,1694174280.302 [run_backseat_on_surface:Science] Stopped
2023-09-08T11:58:00.302Z,1694174280.302 [run_backseat_on_surface:Science](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science
2023-09-08T11:58:00.302Z,1694174280.302 [run_backseat_on_surface:Science:B] Stopped
2023-09-08T11:58:00.302Z,1694174280.302 [run_backseat_on_surface:Science:C] Stopped
2023-09-08T11:58:00.302Z,1694174280.302 [run_backseat_on_surface:Science:E] Stopped
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:Science:OceanCurrent] Stopped
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:OceanCurrent
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:Science:OceanCurrent:A.] Stopped
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:BackseatDriver] Stopped
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::uninitialize run_backseat_on_surface:BackseatDriver
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Stopped
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:D.GoToSurface] Stopped
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:D.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:CheckIn] Stopped
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2023-09-08T11:58:00.303Z,1694174280.303 [run_backseat_on_surface:CheckIn:C.Wait] Stopped
2023-09-08T11:58:00.304Z,1694174280.304 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T11:58:00.457Z,1694174280.457 [WetLabsBB2FL](INFO): Powering down
2023-09-08T11:58:00.679Z,1694174280.679 [BioacousticsDataBridge](INFO): No valid data from altitude reader, stopping
2023-09-08T11:58:00.691Z,1694174280.691 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T11:58:00.691Z,1694174280.691 [Default] Running Loop=1
2023-09-08T11:58:00.692Z,1694174280.692 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T11:58:00.692Z,1694174280.692 [Default:B.GoToSurface] Running Loop=1
2023-09-08T11:58:00.692Z,1694174280.692 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T11:58:00.692Z,1694174280.692 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T11:58:00.692Z,1694174280.692 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T11:58:00.709Z,1694174280.709 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T11:58:00.709Z,1694174280.709 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T11:58:00.710Z,1694174280.710 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T11:58:00.710Z,1694174280.710 [Default:A.Wait] Running Loop=1
2023-09-08T11:58:00.710Z,1694174280.710 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:58:00.868Z,1694174280.868 [BackseatComponent](INFO): Sent LCM shutdown request.
2023-09-08T11:58:00.869Z,1694174280.869 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2023-09-08T11:58:14.019Z,1694174294.019 [Default:A.Wait](INFO): Done Waiting.
2023-09-08T11:58:14.019Z,1694174294.019 [Default:A.Wait] Stopped
2023-09-08T11:58:14.019Z,1694174294.019 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T11:58:14.428Z,1694174294.428 [Default:CheckIn] Running Loop=1
2023-09-08T11:58:14.428Z,1694174294.428 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T11:58:14.428Z,1694174294.428 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T11:58:16.435Z,1694174296.435 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,115815.00,A,4145.52919,N,08116.65047,W,0.019,0.00,080923,,,D*74
2023-09-08T11:58:16.438Z,1694174296.438 [NAL9602](INFO): GPS fix at 20230908T115815: (41.758820, -81.277508)
2023-09-08T11:58:16.448Z,1694174296.448 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T11:58:16.448Z,1694174296.448 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T11:58:19.065Z,1694174299.065 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2023-09-08T11:58:19.066Z,1694174299.066 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2023-09-08T11:58:19.066Z,1694174299.066 [LcmUniversalReporter](INFO): Deactivating messaging.
2023-09-08T11:58:25.192Z,1694174305.192 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0037.lzma
2023-09-08T11:58:26.195Z,1694174306.195 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0037.lzma.bak
2023-09-08T11:58:26.195Z,1694174306.195 [DataOverHttps](INFO): SBD MOMSN=18630615
2023-09-08T11:58:54.413Z,1694174334.413 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20230908T111755/Express0038.lzma
2023-09-08T11:58:55.415Z,1694174335.415 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0038.lzma.bak
2023-09-08T11:58:55.415Z,1694174335.415 [DataOverHttps](INFO): SBD MOMSN=18630618
2023-09-08T11:58:59.445Z,1694174339.445 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T11:58:59.445Z,1694174339.445 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T11:58:59.445Z,1694174339.445 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:58:59.815Z,1694174339.815 [NAL9602](INFO): SBD MO Status=0, MOMSN=3475, MT Status=0, MTMSN=0
2023-09-08T11:58:59.815Z,1694174339.815 [NAL9602](INFO): No messages in MT queue
2023-09-08T11:59:30.505Z,1694174370.505 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:04:00.056Z,1694174640.056 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T12:04:00.056Z,1694174640.056 [Default:CheckIn:C.Wait] Stopped
2023-09-08T12:04:00.057Z,1694174640.057 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:04:00.057Z,1694174640.057 [Default:CheckIn:D] Running Loop=1
2023-09-08T12:04:00.463Z,1694174640.463 [Default:CheckIn:D] Stopped
2023-09-08T12:04:00.464Z,1694174640.464 [Default:CheckIn:E] Running Loop=1
2023-09-08T12:04:00.872Z,1694174640.872 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.996198 min 
2023-09-08T12:04:00.872Z,1694174640.872 [Default:CheckIn:E] Stopped
2023-09-08T12:04:00.872Z,1694174640.872 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T12:04:00.872Z,1694174640.872 [Default:CheckIn] Stopped
2023-09-08T12:04:00.872Z,1694174640.872 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T12:04:00.872Z,1694174640.872 [Default:CheckIn](INFO): Running loop #2
2023-09-08T12:04:00.872Z,1694174640.872 [Default:CheckIn] Running Loop=2
2023-09-08T12:04:00.873Z,1694174640.873 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T12:04:00.873Z,1694174640.873 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:04:02.876Z,1694174642.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,120402.00,A,4145.52972,N,08116.65007,W,0.019,0.00,080923,,,D*71
2023-09-08T12:04:02.878Z,1694174642.878 [NAL9602](INFO): GPS fix at 20230908T120402: (41.758829, -81.277501)
2023-09-08T12:04:02.908Z,1694174642.908 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T12:04:02.917Z,1694174642.917 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:04:11.848Z,1694174651.848 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0040.lzma
2023-09-08T12:04:12.850Z,1694174652.850 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0040.lzma.bak
2023-09-08T12:04:12.851Z,1694174652.851 [DataOverHttps](INFO): SBD MOMSN=18630644
2023-09-08T12:04:14.587Z,1694174654.587 [NAL9602](INFO): SBD MO Status=0, MOMSN=3476, MT Status=0, MTMSN=0
2023-09-08T12:04:14.587Z,1694174654.587 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:04:34.337Z,1694174674.337 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20230908T111755/Express0041.lzma
2023-09-08T12:04:35.339Z,1694174675.339 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0041.lzma.bak
2023-09-08T12:04:35.339Z,1694174675.339 [DataOverHttps](INFO): SBD MOMSN=18630647
2023-09-08T12:04:41.398Z,1694174681.398 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T12:04:41.398Z,1694174681.398 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:04:41.398Z,1694174681.398 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:04:45.038Z,1694174685.038 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:08:20.078Z,1694174900.078 [Power24vConverter](INFO): Powering down.
2023-09-08T12:09:42.179Z,1694174982.179 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T12:09:42.179Z,1694174982.179 [Default:CheckIn:C.Wait] Stopped
2023-09-08T12:09:42.179Z,1694174982.179 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:09:42.179Z,1694174982.179 [Default:CheckIn:D] Running Loop=1
2023-09-08T12:09:42.635Z,1694174982.635 [Default:CheckIn:D] Stopped
2023-09-08T12:09:42.635Z,1694174982.635 [Default:CheckIn:E] Running Loop=1
2023-09-08T12:09:42.000Z,1694174983.000 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.699052 min 
2023-09-08T12:09:42.000Z,1694174983.000 [Default:CheckIn:E] Stopped
2023-09-08T12:09:42.000Z,1694174983.000 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T12:09:42.000Z,1694174983.000 [Default:CheckIn] Stopped
2023-09-08T12:09:43.000Z,1694174983.000 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T12:09:43.000Z,1694174983.000 [Default:CheckIn](INFO): Running loop #3
2023-09-08T12:09:43.000Z,1694174983.000 [Default:CheckIn] Running Loop=3
2023-09-08T12:09:43.005Z,1694174983.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T12:09:43.005Z,1694174983.005 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:09:44.996Z,1694174984.996 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,120944.00,A,4145.52982,N,08116.65056,W,0.019,0.00,080923,,,D*75
2023-09-08T12:09:44.998Z,1694174984.998 [NAL9602](INFO): GPS fix at 20230908T120944: (41.758830, -81.277509)
2023-09-08T12:09:45.059Z,1694174985.059 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T12:09:45.059Z,1694174985.059 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:09:47.336Z,1694174987.336 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T111755/Courier0043.lzma
2023-09-08T12:09:48.343Z,1694174988.343 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0043.lzma.bak
2023-09-08T12:09:48.343Z,1694174988.343 [DataOverHttps](INFO): SBD MOMSN=18630659
2023-09-08T12:10:06.728Z,1694175006.728 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230908T111755/Express0044.lzma
2023-09-08T12:10:07.731Z,1694175007.731 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0044.lzma.bak
2023-09-08T12:10:07.731Z,1694175007.731 [DataOverHttps](INFO): SBD MOMSN=18630662
2023-09-08T12:10:09.683Z,1694175009.683 [NAL9602](INFO): SBD MO Status=2, MOMSN=3477, MT Status=2, MTMSN=0
2023-09-08T12:10:09.684Z,1694175009.684 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T12:10:11.764Z,1694175011.764 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T12:10:11.764Z,1694175011.764 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:10:11.764Z,1694175011.764 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:10:20.191Z,1694175020.191 [NAL9602](INFO): SBD MO Status=0, MOMSN=3477, MT Status=0, MTMSN=0
2023-09-08T12:10:20.192Z,1694175020.192 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:10:50.890Z,1694175050.890 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:15:12.363Z,1694175312.363 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T12:15:12.363Z,1694175312.363 [Default:CheckIn:C.Wait] Stopped
2023-09-08T12:15:12.363Z,1694175312.363 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:15:12.363Z,1694175312.363 [Default:CheckIn:D] Running Loop=1
2023-09-08T12:15:12.770Z,1694175312.770 [Default:CheckIn:D] Stopped
2023-09-08T12:15:12.770Z,1694175312.770 [Default:CheckIn:E] Running Loop=1
2023-09-08T12:15:13.167Z,1694175313.167 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.201312 min 
2023-09-08T12:15:13.167Z,1694175313.167 [Default:CheckIn:E] Stopped
2023-09-08T12:15:13.168Z,1694175313.168 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T12:15:13.168Z,1694175313.168 [Default:CheckIn] Stopped
2023-09-08T12:15:13.168Z,1694175313.168 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T12:15:13.168Z,1694175313.168 [Default:CheckIn](INFO): Running loop #4
2023-09-08T12:15:13.168Z,1694175313.168 [Default:CheckIn] Running Loop=4
2023-09-08T12:15:13.168Z,1694175313.168 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T12:15:13.168Z,1694175313.168 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:15:15.180Z,1694175315.180 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,121514.00,A,4145.52936,N,08116.65030,W,0.019,0.00,080923,,,D*72
2023-09-08T12:15:15.182Z,1694175315.182 [NAL9602](INFO): GPS fix at 20230908T121514: (41.758823, -81.277505)
2023-09-08T12:15:15.192Z,1694175315.192 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T12:15:15.192Z,1694175315.192 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:15:23.992Z,1694175323.992 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0046.lzma
2023-09-08T12:15:24.995Z,1694175324.995 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0046.lzma.bak
2023-09-08T12:15:24.995Z,1694175324.995 [DataOverHttps](INFO): SBD MOMSN=18630680
2023-09-08T12:15:28.523Z,1694175328.523 [NAL9602](INFO): SBD MO Status=0, MOMSN=3478, MT Status=0, MTMSN=0
2023-09-08T12:15:28.524Z,1694175328.524 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:15:43.224Z,1694175343.224 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230908T111755/Express0047.lzma
2023-09-08T12:15:44.227Z,1694175344.227 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0047.lzma.bak
2023-09-08T12:15:44.227Z,1694175344.227 [DataOverHttps](INFO): SBD MOMSN=18630684
2023-09-08T12:15:47.923Z,1694175347.923 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T12:15:47.924Z,1694175347.924 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:15:47.924Z,1694175347.924 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:15:59.209Z,1694175359.209 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:20:48.659Z,1694175648.659 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T12:20:48.659Z,1694175648.659 [Default:CheckIn:C.Wait] Stopped
2023-09-08T12:20:48.659Z,1694175648.659 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:20:48.660Z,1694175648.660 [Default:CheckIn:D] Running Loop=1
2023-09-08T12:20:49.060Z,1694175649.060 [Default:CheckIn:D] Stopped
2023-09-08T12:20:49.065Z,1694175649.065 [Default:CheckIn:E] Running Loop=1
2023-09-08T12:20:49.496Z,1694175649.496 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.806144 min 
2023-09-08T12:20:49.496Z,1694175649.496 [Default:CheckIn:E] Stopped
2023-09-08T12:20:49.496Z,1694175649.496 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T12:20:49.496Z,1694175649.496 [Default:CheckIn] Stopped
2023-09-08T12:20:49.496Z,1694175649.496 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T12:20:49.496Z,1694175649.496 [Default:CheckIn](INFO): Running loop #5
2023-09-08T12:20:49.496Z,1694175649.496 [Default:CheckIn] Running Loop=5
2023-09-08T12:20:49.497Z,1694175649.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T12:20:49.497Z,1694175649.497 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:20:51.459Z,1694175651.459 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,122050.00,A,4145.52879,N,08116.65067,W,0.039,0.00,080923,,,D*7E
2023-09-08T12:20:51.461Z,1694175651.461 [NAL9602](INFO): GPS fix at 20230908T122050: (41.758813, -81.277511)
2023-09-08T12:20:51.471Z,1694175651.471 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T12:20:51.471Z,1694175651.471 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:21:00.412Z,1694175660.412 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0049.lzma
2023-09-08T12:21:01.415Z,1694175661.415 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0049.lzma.bak
2023-09-08T12:21:01.415Z,1694175661.415 [DataOverHttps](INFO): SBD MOMSN=18630697
2023-09-08T12:21:06.819Z,1694175666.819 [NAL9602](INFO): SBD MO Status=0, MOMSN=3479, MT Status=0, MTMSN=0
2023-09-08T12:21:06.819Z,1694175666.819 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:21:19.669Z,1694175679.669 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T111755/Express0050.lzma
2023-09-08T12:21:20.671Z,1694175680.671 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0050.lzma.bak
2023-09-08T12:21:20.671Z,1694175680.671 [DataOverHttps](INFO): SBD MOMSN=18630700
2023-09-08T12:21:24.638Z,1694175684.638 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T12:21:24.638Z,1694175684.638 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:21:24.638Z,1694175684.638 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:21:37.523Z,1694175697.523 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:26:25.231Z,1694175985.231 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T12:26:25.231Z,1694175985.231 [Default:CheckIn:C.Wait] Stopped
2023-09-08T12:26:25.231Z,1694175985.231 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:26:25.231Z,1694175985.231 [Default:CheckIn:D] Running Loop=1
2023-09-08T12:26:25.635Z,1694175985.635 [Default:CheckIn:D] Stopped
2023-09-08T12:26:25.635Z,1694175985.635 [Default:CheckIn:E] Running Loop=1
2023-09-08T12:26:26.046Z,1694175986.046 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.415725 min 
2023-09-08T12:26:26.047Z,1694175986.047 [Default:CheckIn:E] Stopped
2023-09-08T12:26:26.047Z,1694175986.047 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T12:26:26.047Z,1694175986.047 [Default:CheckIn] Stopped
2023-09-08T12:26:26.047Z,1694175986.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T12:26:26.047Z,1694175986.047 [Default:CheckIn](INFO): Running loop #6
2023-09-08T12:26:26.047Z,1694175986.047 [Default:CheckIn] Running Loop=6
2023-09-08T12:26:26.047Z,1694175986.047 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T12:26:26.047Z,1694175986.047 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:26:28.052Z,1694175988.052 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,122627.00,A,4145.52879,N,08116.65049,W,0.019,0.00,080923,,,D*76
2023-09-08T12:26:28.054Z,1694175988.054 [NAL9602](INFO): GPS fix at 20230908T122627: (41.758813, -81.277508)
2023-09-08T12:26:28.101Z,1694175988.101 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T12:26:28.101Z,1694175988.101 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:26:44.652Z,1694176004.652 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0052.lzma
2023-09-08T12:26:45.655Z,1694176005.655 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0052.lzma.bak
2023-09-08T12:26:45.655Z,1694176005.655 [DataOverHttps](INFO): SBD MOMSN=18630720
2023-09-08T12:26:57.103Z,1694176017.103 [NAL9602](INFO): SBD MO Status=2, MOMSN=3480, MT Status=2, MTMSN=0
2023-09-08T12:26:57.103Z,1694176017.103 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T12:27:03.567Z,1694176023.567 [NAL9602](INFO): SBD MO Status=0, MOMSN=3480, MT Status=0, MTMSN=0
2023-09-08T12:27:03.568Z,1694176023.568 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:27:07.278Z,1694176027.278 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230908T111755/Express0053.lzma
2023-09-08T12:27:08.279Z,1694176028.279 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0053.lzma.bak
2023-09-08T12:27:08.279Z,1694176028.279 [DataOverHttps](INFO): SBD MOMSN=18630723
2023-09-08T12:27:12.072Z,1694176032.072 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T12:27:12.072Z,1694176032.072 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:27:12.072Z,1694176032.072 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:27:34.279Z,1694176054.279 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:32:12.715Z,1694176332.715 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T12:32:12.715Z,1694176332.715 [Default:CheckIn:C.Wait] Stopped
2023-09-08T12:32:12.715Z,1694176332.715 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:32:12.716Z,1694176332.716 [Default:CheckIn:D] Running Loop=1
2023-09-08T12:32:13.120Z,1694176333.120 [Default:CheckIn:D] Stopped
2023-09-08T12:32:13.120Z,1694176333.120 [Default:CheckIn:E] Running Loop=1
2023-09-08T12:32:13.521Z,1694176333.521 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.207129 min 
2023-09-08T12:32:13.521Z,1694176333.521 [Default:CheckIn:E] Stopped
2023-09-08T12:32:13.521Z,1694176333.521 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T12:32:13.521Z,1694176333.521 [Default:CheckIn] Stopped
2023-09-08T12:32:13.521Z,1694176333.521 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T12:32:13.521Z,1694176333.521 [Default:CheckIn](INFO): Running loop #7
2023-09-08T12:32:13.522Z,1694176333.522 [Default:CheckIn] Running Loop=7
2023-09-08T12:32:13.522Z,1694176333.522 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T12:32:13.522Z,1694176333.522 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:32:15.564Z,1694176335.564 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,123214.00,A,4145.52928,N,08116.65068,W,0.039,0.00,080923,,,D*77
2023-09-08T12:32:15.566Z,1694176335.566 [NAL9602](INFO): GPS fix at 20230908T123214: (41.758821, -81.277511)
2023-09-08T12:32:15.576Z,1694176335.576 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T12:32:15.576Z,1694176335.576 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:32:23.972Z,1694176343.972 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T111755/Courier0055.lzma
2023-09-08T12:32:24.975Z,1694176344.975 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0055.lzma.bak
2023-09-08T12:32:24.975Z,1694176344.975 [DataOverHttps](INFO): SBD MOMSN=18630738
2023-09-08T12:32:30.911Z,1694176350.911 [NAL9602](INFO): SBD MO Status=0, MOMSN=3481, MT Status=0, MTMSN=0
2023-09-08T12:32:30.911Z,1694176350.911 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:32:53.672Z,1694176373.672 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230908T111755/Express0056.lzma
2023-09-08T12:32:54.675Z,1694176374.675 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0056.lzma.bak
2023-09-08T12:32:54.675Z,1694176374.675 [DataOverHttps](INFO): SBD MOMSN=18630745
2023-09-08T12:32:58.524Z,1694176378.524 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T12:32:58.529Z,1694176378.529 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:32:58.529Z,1694176378.529 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:33:01.722Z,1694176381.722 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:33:35.366Z,1694176415.366 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-08T12:33:35.369Z,1694176415.369 [BPC1](INFO): Received data from all battery sticks.
2023-09-08T12:37:44.382Z,1694176664.382 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-08T12:37:44.383Z,1694176664.383 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-08T12:37:44.384Z,1694176664.384 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/run_backseat_on_surface.tx
2023-09-08T12:37:44.432Z,1694176664.432 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min
2023-09-08T12:37:44.433Z,1694176664.433 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2023-09-08T12:37:44.433Z,1694176664.433 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx
2023-09-08T12:37:44.755Z,1694176664.755 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectChlActive = 0 bool
2023-09-08T12:37:44.757Z,1694176664.757 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.TimeWindowPeakReport = nan min
2023-09-08T12:37:44.759Z,1694176664.759 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestChlPeakReportActive = 0 bool
2023-09-08T12:37:44.801Z,1694176664.801 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestSaltPeakReportActive = 0 bool
2023-09-08T12:37:44.809Z,1694176664.809 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestOilPeakReportActive = 0 bool
2023-09-08T12:37:44.811Z,1694176664.811 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PatchTracking = 0 bool
2023-09-08T12:37:44.813Z,1694176664.813 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DetectTrough = 0 bool
2023-09-08T12:37:44.815Z,1694176664.815 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.FilterWidthHorizontal = 3.000000 count
2023-09-08T12:37:44.847Z,1694176664.847 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesSlidingwindow = 100.000000 count
2023-09-08T12:37:44.849Z,1694176664.849 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.OffPeakFractionHorizontal = 80.000000 %
2023-09-08T12:37:44.851Z,1694176664.851 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectNO3Active = 0 bool
2023-09-08T12:37:44.853Z,1694176664.853 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectOilActive = 0 bool
2023-09-08T12:37:44.854Z,1694176664.854 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectFDOMActive = 0 bool
2023-09-08T12:37:44.856Z,1694176664.856 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectSalinityActive = 0 bool
2023-09-08T12:37:44.858Z,1694176664.858 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-09-08T12:37:44.860Z,1694176664.860 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledAanderaaO2 = 0 bool
2023-09-08T12:37:44.879Z,1694176664.879 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledNeilBrown = 0 bool
2023-09-08T12:37:44.881Z,1694176664.881 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledSeabird = 1 bool
2023-09-08T12:37:44.884Z,1694176664.884 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsBB2FL = 1 bool
2023-09-08T12:37:44.904Z,1694176664.904 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-09-08T12:37:44.906Z,1694176664.906 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsUBAT = 0 bool
2023-09-08T12:37:44.908Z,1694176664.908 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.LowPassWindowLength = 20.000000 count
2023-09-08T12:37:44.910Z,1694176664.910 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.MedianFilterLen = 5.000000 count
2023-09-08T12:37:44.912Z,1694176664.912 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakShallowBound = nan m
2023-09-08T12:37:44.940Z,1694176664.940 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDeepBound = nan m
2023-09-08T12:37:44.948Z,1694176664.948 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-09-08T12:37:44.958Z,1694176664.958 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlThreshTriggerActive = 0 bool
2023-09-08T12:37:44.960Z,1694176664.960 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlLowerThresh = nan ug/l
2023-09-08T12:37:44.990Z,1694176664.990 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlUpperThresh = nan ug/l
2023-09-08T12:37:45.005Z,1694176665.005 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesThresh = 5.000000 count
2023-09-08T12:37:45.007Z,1694176665.007 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChl = nan ug/l
2023-09-08T12:37:45.021Z,1694176665.021 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlDepth = nan m
2023-09-08T12:37:45.042Z,1694176665.042 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlTemperature = nan degC
2023-09-08T12:37:45.044Z,1694176665.044 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLatitude = nan arcdeg
2023-09-08T12:37:45.059Z,1694176665.059 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLongitude = nan arcdeg
2023-09-08T12:37:45.065Z,1694176665.065 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3 = nan umol/l
2023-09-08T12:37:45.067Z,1694176665.067 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Depth = nan m
2023-09-08T12:37:45.078Z,1694176665.078 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Latitude = nan arcdeg
2023-09-08T12:37:45.080Z,1694176665.080 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Longitude = nan arcdeg
2023-09-08T12:37:45.090Z,1694176665.090 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOil = nan kg/m3
2023-09-08T12:37:45.101Z,1694176665.101 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilDepth = nan m
2023-09-08T12:37:45.103Z,1694176665.103 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLatitude = nan arcdeg
2023-09-08T12:37:45.111Z,1694176665.111 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLongitude = nan arcdeg
2023-09-08T12:37:45.127Z,1694176665.127 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOM = nan ppb
2023-09-08T12:37:45.137Z,1694176665.137 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMDepth = nan m
2023-09-08T12:37:45.140Z,1694176665.140 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMTemperature = nan degC
2023-09-08T12:37:45.150Z,1694176665.150 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLatitude = nan arcdeg
2023-09-08T12:37:45.152Z,1694176665.152 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLongitude = nan arcdeg
2023-09-08T12:37:45.186Z,1694176665.186 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchFDOMDistance = nan m
2023-09-08T12:37:45.188Z,1694176665.188 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSalt = nan psu
2023-09-08T12:37:45.198Z,1694176665.198 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltDepth = nan m
2023-09-08T12:37:45.200Z,1694176665.200 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltTemperature = nan degC
2023-09-08T12:37:45.211Z,1694176665.211 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLatitude = nan arcdeg
2023-09-08T12:37:45.217Z,1694176665.217 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLongitude = nan arcdeg
2023-09-08T12:37:45.219Z,1694176665.219 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChl = nan ug/l
2023-09-08T12:37:45.231Z,1694176665.231 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDepth = nan m
2023-09-08T12:37:45.262Z,1694176665.262 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLatitude = nan arcdeg
2023-09-08T12:37:45.264Z,1694176665.264 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLongitude = nan arcdeg
2023-09-08T12:37:45.276Z,1694176665.276 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDistance = nan m
2023-09-08T12:37:45.283Z,1694176665.283 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSalt = nan psu
2023-09-08T12:37:45.297Z,1694176665.297 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDepth = nan m
2023-09-08T12:37:45.299Z,1694176665.299 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLatitude = nan arcdeg
2023-09-08T12:37:45.310Z,1694176665.310 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLongitude = nan arcdeg
2023-09-08T12:37:45.312Z,1694176665.312 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDistance = nan m
2023-09-08T12:37:45.323Z,1694176665.323 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOil = nan psu
2023-09-08T12:37:45.337Z,1694176665.337 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDepth = nan m
2023-09-08T12:37:45.339Z,1694176665.339 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLatitude = nan arcdeg
2023-09-08T12:37:45.360Z,1694176665.360 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLongitude = nan arcdeg
2023-09-08T12:37:45.362Z,1694176665.362 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDistance = nan m
2023-09-08T12:37:45.364Z,1694176665.364 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.TriggeredOnChl = 0 bool
2023-09-08T12:37:45.407Z,1694176665.407 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.ChlTriggering = nan ug/l
2023-09-08T12:37:45.479Z,1694176665.479 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T12:37:45.510Z,1694176665.510 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-08T12:37:45.570Z,1694176665.570 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T12:37:45.596Z,1694176665.596 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T12:37:45.637Z,1694176665.637 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-08T12:37:45.688Z,1694176665.688 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T12:37:45.725Z,1694176665.725 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T12:37:45.748Z,1694176665.748 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-08T12:37:45.759Z,1694176665.759 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2023-09-08T12:37:45.809Z,1694176665.809 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-08T12:37:45.810Z,1694176665.810 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-08T12:37:45.835Z,1694176665.835 [MissionManager](INFO): DefineArg run_backseat_on_surface:BackseatDriver.EnableBackseat = 0 bool
2023-09-08T12:37:45.841Z,1694176665.841 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-08T12:37:45.850Z,1694176665.850 [run_backseat_on_surface:D.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T12:37:45.884Z,1694176665.884 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-08T12:37:45.898Z,1694176665.898 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-08T12:37:45.907Z,1694176665.907 [run_backseat_on_surface:G.Execute](DEBUG): Construct Execute.
2023-09-08T12:37:45.929Z,1694176665.929 [MissionManager](DEBUG): 
mission run_backseat_on_surface {
  arguments {
    ElapsedSinceMissionStarted = 0 minute
  }

  # Power the science payload.

  insert id="Science" Insert/Science.tl

  # Power the Backseat payload.

  insert Insert/BackseatDriver.tl

  assign in sequence BackseatDriver:EnableBackseat = true

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

    readDatum id="Read_Iridium" {
      timeout duration=P2H {
        behavior Guidance:Execute {
          run in sequence

          set command = "Burn on"
        }

        syslog critical "Dropped weight due to communications timeout."
      }

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceMissionStarted = elapsed ( Universal:mission_started )

    syslog important "run_backseat_on_surface mission has been running for "
         + ElapsedSinceMissionStarted~minute
  }

  syslog important "Restarting logs and run_backseat_on_surface mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2023-09-08T12:37:45.931Z,1694176665.931 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-08T12:37:46.216Z,1694176666.216 [Default] Stopped
2023-09-08T12:37:46.216Z,1694176666.216 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-08T12:37:46.216Z,1694176666.216 [Default:B.GoToSurface] Stopped
2023-09-08T12:37:46.216Z,1694176666.216 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T12:37:46.216Z,1694176666.216 [Default:CheckIn] Stopped
2023-09-08T12:37:46.216Z,1694176666.216 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T12:37:46.216Z,1694176666.216 [Default:CheckIn:C.Wait] Stopped
2023-09-08T12:37:46.216Z,1694176666.216 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:37:46.217Z,1694176666.217 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface
2023-09-08T12:37:46.217Z,1694176666.217 [run_backseat_on_surface] Running Loop=1
2023-09-08T12:37:46.217Z,1694176666.217 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface
2023-09-08T12:37:46.217Z,1694176666.217 [run_backseat_on_surface:Science] Running Loop=1
2023-09-08T12:37:46.217Z,1694176666.217 [run_backseat_on_surface:Science](DEBUG): Aggregate::initialize run_backseat_on_surface:Science
2023-09-08T12:37:46.217Z,1694176666.217 [run_backseat_on_surface:Science:A] Running Loop=1
2023-09-08T12:37:46.217Z,1694176666.217 [run_backseat_on_surface:Science:B] Running Loop=1
2023-09-08T12:37:46.217Z,1694176666.217 [run_backseat_on_surface:Science:C] Running Loop=1
2023-09-08T12:37:46.217Z,1694176666.217 [run_backseat_on_surface:Science:D] Running Loop=1
2023-09-08T12:37:46.217Z,1694176666.217 [run_backseat_on_surface:Science:E] Running Loop=1
2023-09-08T12:37:46.218Z,1694176666.218 [run_backseat_on_surface:Science:F] Running Loop=1
2023-09-08T12:37:46.218Z,1694176666.218 [run_backseat_on_surface:Science:Read_Oil] Running Loop=1
2023-09-08T12:37:46.218Z,1694176666.218 [run_backseat_on_surface:Science:PeakDetectChl] Running Loop=1
2023-09-08T12:37:46.218Z,1694176666.218 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectChl
2023-09-08T12:37:46.218Z,1694176666.218 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T12:37:46.218Z,1694176666.218 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T12:37:46.219Z,1694176666.219 [run_backseat_on_surface:Science:HighestChlPeakReport] Running Loop=1
2023-09-08T12:37:46.219Z,1694176666.219 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestChlPeakReport
2023-09-08T12:37:46.219Z,1694176666.219 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-08T12:37:46.220Z,1694176666.220 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-08T12:37:46.220Z,1694176666.220 [run_backseat_on_surface:Science:PeakDetectNO3] Running Loop=1
2023-09-08T12:37:46.220Z,1694176666.220 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectNO3
2023-09-08T12:37:46.220Z,1694176666.220 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T12:37:46.220Z,1694176666.220 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T12:37:46.222Z,1694176666.222 [run_backseat_on_surface:Science:PeakDetectOil] Running Loop=1
2023-09-08T12:37:46.222Z,1694176666.222 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectOil
2023-09-08T12:37:46.222Z,1694176666.222 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T12:37:46.222Z,1694176666.222 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T12:37:46.223Z,1694176666.223 [run_backseat_on_surface:Science:HighestOilPeakReport] Running Loop=1
2023-09-08T12:37:46.223Z,1694176666.223 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestOilPeakReport
2023-09-08T12:37:46.223Z,1694176666.223 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-08T12:37:46.223Z,1694176666.223 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-08T12:37:46.224Z,1694176666.224 [run_backseat_on_surface:Science:PeakDetectFDOM] Running Loop=1
2023-09-08T12:37:46.224Z,1694176666.224 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectFDOM
2023-09-08T12:37:46.224Z,1694176666.224 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T12:37:46.224Z,1694176666.224 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T12:37:46.225Z,1694176666.225 [run_backseat_on_surface:Science:PeakDetectSalinity] Running Loop=1
2023-09-08T12:37:46.225Z,1694176666.225 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectSalinity
2023-09-08T12:37:46.225Z,1694176666.225 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T12:37:46.226Z,1694176666.226 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T12:37:46.227Z,1694176666.227 [run_backseat_on_surface:Science:HighestSaltPeakReport] Running Loop=1
2023-09-08T12:37:46.227Z,1694176666.227 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestSaltPeakReport
2023-09-08T12:37:46.227Z,1694176666.227 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-08T12:37:46.227Z,1694176666.227 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-08T12:37:46.227Z,1694176666.227 [run_backseat_on_surface:Science:ChlThreshTrigger] Running Loop=1
2023-09-08T12:37:46.227Z,1694176666.227 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:ChlThreshTrigger
2023-09-08T12:37:46.228Z,1694176666.228 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2023-09-08T12:37:46.228Z,1694176666.228 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2023-09-08T12:37:46.228Z,1694176666.228 [run_backseat_on_surface:Science:OceanCurrent] Running Loop=1
2023-09-08T12:37:46.228Z,1694176666.228 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:OceanCurrent
2023-09-08T12:37:46.228Z,1694176666.228 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2023-09-08T12:37:46.228Z,1694176666.228 [run_backseat_on_surface:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-09-08T12:37:46.229Z,1694176666.229 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2023-09-08T12:37:46.229Z,1694176666.229 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::initialize run_backseat_on_surface:BackseatDriver
2023-09-08T12:37:46.229Z,1694176666.229 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-08T12:37:46.229Z,1694176666.229 [run_backseat_on_surface:D.GoToSurface] Running Loop=1
2023-09-08T12:37:46.229Z,1694176666.229 [run_backseat_on_surface:D.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T12:37:46.230Z,1694176666.230 [run_backseat_on_surface:D.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T12:37:46.230Z,1694176666.230 [run_backseat_on_surface:D.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T12:37:46.230Z,1694176666.230 [run_backseat_on_surface:D.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T12:37:46.230Z,1694176666.230 [run_backseat_on_surface:D.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T12:37:46.231Z,1694176666.231 [run_backseat_on_surface:D.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T12:37:46.231Z,1694176666.231 [run_backseat_on_surface:C] Running Loop=1
2023-09-08T12:37:46.232Z,1694176666.232 [run_backseat_on_surface:C] Stopped
2023-09-08T12:37:46.232Z,1694176666.232 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2023-09-08T12:37:46.232Z,1694176666.232 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2023-09-08T12:37:46.275Z,1694176666.275 [run_backseat_on_surface:Science] Running Loop=1
2023-09-08T12:37:46.276Z,1694176666.276 [run_backseat_on_surface:Science:ChlThreshTrigger] Stopped
2023-09-08T12:37:46.276Z,1694176666.276 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:ChlThreshTrigger
2023-09-08T12:37:46.276Z,1694176666.276 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2023-09-08T12:37:46.276Z,1694176666.276 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2023-09-08T12:37:46.276Z,1694176666.276 [run_backseat_on_surface:Science:HighestSaltPeakReport] Stopped
2023-09-08T12:37:46.276Z,1694176666.276 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestSaltPeakReport
2023-09-08T12:37:46.276Z,1694176666.276 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-08T12:37:46.276Z,1694176666.276 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-08T12:37:46.276Z,1694176666.276 [run_backseat_on_surface:Science:PeakDetectSalinity] Stopped
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectSalinity
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:PeakDetectFDOM] Stopped
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectFDOM
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:HighestOilPeakReport] Stopped
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestOilPeakReport
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-08T12:37:46.277Z,1694176666.277 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:PeakDetectOil] Stopped
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectOil
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:PeakDetectNO3] Stopped
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectNO3
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:HighestChlPeakReport] Stopped
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestChlPeakReport
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-08T12:37:46.278Z,1694176666.278 [run_backseat_on_surface:Science:PeakDetectChl] Stopped
2023-09-08T12:37:46.279Z,1694176666.279 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectChl
2023-09-08T12:37:46.279Z,1694176666.279 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-09-08T12:37:46.279Z,1694176666.279 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T12:37:46.279Z,1694176666.279 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2023-09-08T12:37:46.279Z,1694176666.279 [run_backseat_on_surface:Science:Read_Oil] Stopped
2023-09-08T12:37:46.279Z,1694176666.279 [run_backseat_on_surface:Science:F] Stopped
2023-09-08T12:37:46.279Z,1694176666.279 [run_backseat_on_surface:Science:E] Running Loop=1
2023-09-08T12:37:46.280Z,1694176666.280 [run_backseat_on_surface:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-09-08T12:37:46.280Z,1694176666.280 [run_backseat_on_surface:Science:D] Stopped
2023-09-08T12:37:46.280Z,1694176666.280 [run_backseat_on_surface:Science:C] Running Loop=1
2023-09-08T12:37:46.280Z,1694176666.280 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-09-08T12:37:46.290Z,1694176666.290 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-09-08T12:37:46.290Z,1694176666.290 [run_backseat_on_surface:Science:B] Running Loop=1
2023-09-08T12:37:46.291Z,1694176666.291 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-09-08T12:37:46.291Z,1694176666.291 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-09-08T12:37:46.291Z,1694176666.291 [run_backseat_on_surface:Science:A] Stopped
2023-09-08T12:37:46.413Z,1694176666.413 [BackseatComponent](INFO): Powering up
2023-09-08T12:37:46.414Z,1694176666.414 [BackseatComponent](INFO): Subscribing to LCM channels.
2023-09-08T12:37:46.594Z,1694176666.594 [Power24vConverter](INFO): Powering up.
2023-09-08T12:37:46.613Z,1694176666.613 [run_backseat_on_surface:CheckIn] Running Loop=1
2023-09-08T12:37:46.613Z,1694176666.613 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2023-09-08T12:37:46.613Z,1694176666.613 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:37:46.770Z,1694176666.770 [WetLabsBB2FL](INFO): Powering up
2023-09-08T12:37:47.021Z,1694176667.021 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-08T12:37:48.620Z,1694176668.620 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,123747.00,A,4145.52946,N,08116.65056,W,0.000,0.00,080923,,,D*7B
2023-09-08T12:37:48.622Z,1694176668.622 [NAL9602](INFO): GPS fix at 20230908T123747: (41.758824, -81.277509)
2023-09-08T12:37:48.632Z,1694176668.632 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2023-09-08T12:37:48.632Z,1694176668.632 [run_backseat_on_surface:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:37:49.062Z,1694176669.062 [run_backseat_on_surface:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T12:37:49.894Z,1694176669.894 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2023-09-08T12:37:57.384Z,1694176677.384 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230908T111755/Courier0058.lzma
2023-09-08T12:37:58.387Z,1694176678.387 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0058.lzma.bak
2023-09-08T12:37:58.387Z,1694176678.387 [DataOverHttps](INFO): SBD MOMSN=18630757
2023-09-08T12:38:00.748Z,1694176680.748 [NAL9602](INFO): SBD MO Status=0, MOMSN=3482, MT Status=0, MTMSN=0
2023-09-08T12:38:00.748Z,1694176680.748 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:38:19.065Z,1694176699.065 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20230908T111755/Express0059.lzma
2023-09-08T12:38:20.067Z,1694176700.067 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0059.lzma.bak
2023-09-08T12:38:20.067Z,1694176700.067 [DataOverHttps](INFO): SBD MOMSN=18630760
2023-09-08T12:38:24.221Z,1694176704.221 [run_backseat_on_surface:CheckIn:Read_Iridium] Stopped
2023-09-08T12:38:24.221Z,1694176704.221 [run_backseat_on_surface:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:38:24.221Z,1694176704.221 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:38:31.486Z,1694176711.486 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:38:53.152Z,1694176733.152 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20230908T123727
2023-09-08T12:40:12.147Z,1694176812.147 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1920
2023-09-08T12:40:12.519Z,1694176812.519 [BioacousticsDataBridge](INFO): Received valid altitude data, resuming
2023-09-08T12:40:46.565Z,1694176846.565 [CommandExec](IMPORTANT): got command stop
2023-09-08T12:40:46.566Z,1694176846.566 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-09-08T12:40:46.898Z,1694176846.898 [MissionManager](INFO): MissionManager is completed.
2023-09-08T12:40:46.898Z,1694176846.898 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface
2023-09-08T12:40:46.898Z,1694176846.898 [run_backseat_on_surface] Stopped
2023-09-08T12:40:46.898Z,1694176846.898 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:Science] Stopped
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:Science](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:Science:B] Stopped
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:Science:C] Stopped
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:Science:E] Stopped
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:Science:OceanCurrent] Stopped
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:OceanCurrent
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:Science:OceanCurrent:A.] Stopped
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:BackseatDriver] Stopped
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::uninitialize run_backseat_on_surface:BackseatDriver
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Stopped
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:D.GoToSurface] Stopped
2023-09-08T12:40:46.899Z,1694176846.899 [run_backseat_on_surface:D.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T12:40:46.900Z,1694176846.900 [run_backseat_on_surface:CheckIn] Stopped
2023-09-08T12:40:46.900Z,1694176846.900 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2023-09-08T12:40:46.900Z,1694176846.900 [run_backseat_on_surface:CheckIn:C.Wait] Stopped
2023-09-08T12:40:46.900Z,1694176846.900 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:40:47.263Z,1694176847.263 [BioacousticsDataBridge](INFO): No valid data from altitude reader, stopping
2023-09-08T12:40:47.298Z,1694176847.298 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T12:40:47.298Z,1694176847.298 [Default] Running Loop=1
2023-09-08T12:40:47.298Z,1694176847.298 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T12:40:47.298Z,1694176847.298 [Default:B.GoToSurface] Running Loop=1
2023-09-08T12:40:47.298Z,1694176847.298 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T12:40:47.298Z,1694176847.298 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T12:40:47.299Z,1694176847.299 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T12:40:47.299Z,1694176847.299 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T12:40:47.299Z,1694176847.299 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T12:40:47.300Z,1694176847.300 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T12:40:47.300Z,1694176847.300 [Default:A.Wait] Running Loop=1
2023-09-08T12:40:47.300Z,1694176847.300 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:40:47.409Z,1694176847.409 [WetLabsBB2FL](INFO): Powering down
2023-09-08T12:40:48.606Z,1694176848.606 [BackseatComponent](INFO): Sent LCM shutdown request.
2023-09-08T12:40:48.606Z,1694176848.606 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2023-09-08T12:41:00.612Z,1694176860.612 [Default:A.Wait](INFO): Done Waiting.
2023-09-08T12:41:00.612Z,1694176860.612 [Default:A.Wait] Stopped
2023-09-08T12:41:00.612Z,1694176860.612 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:41:01.016Z,1694176861.016 [Default:CheckIn] Running Loop=1
2023-09-08T12:41:01.016Z,1694176861.016 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T12:41:01.016Z,1694176861.016 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:41:03.056Z,1694176863.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,124102.00,A,4145.52905,N,08116.65072,W,0.019,0.00,080923,,,D*72
2023-09-08T12:41:03.058Z,1694176863.058 [NAL9602](INFO): GPS fix at 20230908T124102: (41.758817, -81.277512)
2023-09-08T12:41:03.094Z,1694176863.094 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T12:41:03.094Z,1694176863.094 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:41:06.809Z,1694176866.809 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2023-09-08T12:41:06.810Z,1694176866.810 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2023-09-08T12:41:06.810Z,1694176866.810 [LcmUniversalReporter](INFO): Deactivating messaging.
2023-09-08T12:41:13.445Z,1694176873.445 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0061.lzma
2023-09-08T12:41:14.451Z,1694176874.451 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0061.lzma.bak
2023-09-08T12:41:14.451Z,1694176874.451 [DataOverHttps](INFO): SBD MOMSN=18630777
2023-09-08T12:41:26.879Z,1694176886.879 [NAL9602](INFO): SBD MO Status=0, MOMSN=3483, MT Status=0, MTMSN=0
2023-09-08T12:41:26.879Z,1694176886.879 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:41:33.745Z,1694176893.745 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20230908T111755/Express0062.lzma
2023-09-08T12:41:34.747Z,1694176894.747 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0062.lzma.bak
2023-09-08T12:41:34.747Z,1694176894.747 [DataOverHttps](INFO): SBD MOMSN=18630785
2023-09-08T12:41:38.607Z,1694176898.607 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T12:41:38.608Z,1694176898.608 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:41:38.608Z,1694176898.608 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:41:57.576Z,1694176917.576 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:46:39.268Z,1694177199.268 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T12:46:39.268Z,1694177199.268 [Default:CheckIn:C.Wait] Stopped
2023-09-08T12:46:39.268Z,1694177199.268 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:46:39.277Z,1694177199.277 [Default:CheckIn:D] Running Loop=1
2023-09-08T12:46:39.700Z,1694177199.700 [Default:CheckIn:D] Stopped
2023-09-08T12:46:39.700Z,1694177199.700 [Default:CheckIn:E] Running Loop=1
2023-09-08T12:46:40.094Z,1694177200.094 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.873371 min 
2023-09-08T12:46:40.094Z,1694177200.094 [Default:CheckIn:E] Stopped
2023-09-08T12:46:40.094Z,1694177200.094 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T12:46:40.094Z,1694177200.094 [Default:CheckIn] Stopped
2023-09-08T12:46:40.094Z,1694177200.094 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T12:46:40.094Z,1694177200.094 [Default:CheckIn](INFO): Running loop #2
2023-09-08T12:46:40.094Z,1694177200.094 [Default:CheckIn] Running Loop=2
2023-09-08T12:46:40.094Z,1694177200.094 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T12:46:40.094Z,1694177200.094 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:46:42.083Z,1694177202.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,124641.00,A,4145.52904,N,08116.65107,W,0.019,0.00,080923,,,D*70
2023-09-08T12:46:42.086Z,1694177202.086 [NAL9602](INFO): GPS fix at 20230908T124641: (41.758817, -81.277518)
2023-09-08T12:46:42.100Z,1694177202.100 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T12:46:42.100Z,1694177202.100 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:46:45.240Z,1694177205.240 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0064.lzma
2023-09-08T12:46:46.251Z,1694177206.251 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0064.lzma.bak
2023-09-08T12:46:46.251Z,1694177206.251 [DataOverHttps](INFO): SBD MOMSN=18630853
2023-09-08T12:47:03.247Z,1694177223.247 [NAL9602](INFO): SBD MO Status=0, MOMSN=3484, MT Status=0, MTMSN=0
2023-09-08T12:47:03.247Z,1694177223.247 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:47:08.084Z,1694177228.084 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20230908T111755/Express0065.lzma
2023-09-08T12:47:09.087Z,1694177229.087 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0065.lzma.bak
2023-09-08T12:47:09.087Z,1694177229.087 [DataOverHttps](INFO): SBD MOMSN=18630856
2023-09-08T12:47:12.964Z,1694177232.964 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T12:47:12.964Z,1694177232.964 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:47:12.964Z,1694177232.964 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:47:33.954Z,1694177253.954 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:51:07.682Z,1694177467.682 [Power24vConverter](INFO): Powering down.
2023-09-08T12:52:13.546Z,1694177533.546 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T12:52:13.546Z,1694177533.546 [Default:CheckIn:C.Wait] Stopped
2023-09-08T12:52:13.546Z,1694177533.546 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:52:13.546Z,1694177533.546 [Default:CheckIn:D] Running Loop=1
2023-09-08T12:52:13.961Z,1694177533.961 [Default:CheckIn:D] Stopped
2023-09-08T12:52:13.961Z,1694177533.961 [Default:CheckIn:E] Running Loop=1
2023-09-08T12:52:14.372Z,1694177534.372 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.444383 min 
2023-09-08T12:52:14.373Z,1694177534.373 [Default:CheckIn:E] Stopped
2023-09-08T12:52:14.373Z,1694177534.373 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T12:52:14.373Z,1694177534.373 [Default:CheckIn] Stopped
2023-09-08T12:52:14.373Z,1694177534.373 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T12:52:14.373Z,1694177534.373 [Default:CheckIn](INFO): Running loop #3
2023-09-08T12:52:14.373Z,1694177534.373 [Default:CheckIn] Running Loop=3
2023-09-08T12:52:14.373Z,1694177534.373 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T12:52:14.373Z,1694177534.373 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:52:16.372Z,1694177536.372 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125215.00,A,4145.52955,N,08116.65105,W,0.039,0.00,080923,,,D*70
2023-09-08T12:52:16.374Z,1694177536.374 [NAL9602](INFO): GPS fix at 20230908T125215: (41.758826, -81.277518)
2023-09-08T12:52:16.384Z,1694177536.384 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T12:52:16.413Z,1694177536.413 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:52:25.096Z,1694177545.096 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0067.lzma
2023-09-08T12:52:26.099Z,1694177546.099 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0067.lzma.bak
2023-09-08T12:52:26.099Z,1694177546.099 [DataOverHttps](INFO): SBD MOMSN=18630904
2023-09-08T12:52:55.755Z,1694177575.755 [NAL9602](INFO): SBD MO Status=0, MOMSN=3485, MT Status=0, MTMSN=0
2023-09-08T12:52:55.755Z,1694177575.755 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:52:57.444Z,1694177577.444 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T111755/Express0068.lzma
2023-09-08T12:52:58.447Z,1694177578.447 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0068.lzma.bak
2023-09-08T12:52:58.447Z,1694177578.447 [DataOverHttps](INFO): SBD MOMSN=18630919
2023-09-08T12:53:02.216Z,1694177582.216 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T12:53:02.221Z,1694177582.221 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:53:02.221Z,1694177582.221 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:53:26.442Z,1694177606.442 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T12:58:02.803Z,1694177882.803 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T12:58:02.803Z,1694177882.803 [Default:CheckIn:C.Wait] Stopped
2023-09-08T12:58:02.803Z,1694177882.803 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T12:58:02.804Z,1694177882.804 [Default:CheckIn:D] Running Loop=1
2023-09-08T12:58:03.219Z,1694177883.219 [Default:CheckIn:D] Stopped
2023-09-08T12:58:03.219Z,1694177883.219 [Default:CheckIn:E] Running Loop=1
2023-09-08T12:58:03.617Z,1694177883.617 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.265350 min 
2023-09-08T12:58:03.617Z,1694177883.617 [Default:CheckIn:E] Stopped
2023-09-08T12:58:03.617Z,1694177883.617 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T12:58:03.618Z,1694177883.618 [Default:CheckIn] Stopped
2023-09-08T12:58:03.618Z,1694177883.618 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T12:58:03.618Z,1694177883.618 [Default:CheckIn](INFO): Running loop #4
2023-09-08T12:58:03.618Z,1694177883.618 [Default:CheckIn] Running Loop=4
2023-09-08T12:58:03.618Z,1694177883.618 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T12:58:03.618Z,1694177883.618 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T12:58:05.628Z,1694177885.628 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125805.00,A,4145.52932,N,08116.65065,W,0.019,0.00,080923,,,D*7F
2023-09-08T12:58:05.630Z,1694177885.630 [NAL9602](INFO): GPS fix at 20230908T125805: (41.758822, -81.277511)
2023-09-08T12:58:05.640Z,1694177885.640 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T12:58:05.640Z,1694177885.640 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T12:58:14.568Z,1694177894.568 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230908T111755/Courier0070.lzma
2023-09-08T12:58:15.571Z,1694177895.571 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0070.lzma.bak
2023-09-08T12:58:15.571Z,1694177895.571 [DataOverHttps](INFO): SBD MOMSN=18630930
2023-09-08T12:58:25.423Z,1694177905.423 [NAL9602](INFO): SBD MO Status=2, MOMSN=3486, MT Status=2, MTMSN=0
2023-09-08T12:58:25.424Z,1694177905.424 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T12:58:37.874Z,1694177917.874 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T111755/Express0071.lzma
2023-09-08T12:58:38.843Z,1694177918.843 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0071.lzma.bak
2023-09-08T12:58:38.843Z,1694177918.843 [DataOverHttps](INFO): SBD MOMSN=18630933
2023-09-08T12:58:44.108Z,1694177924.108 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T12:58:44.108Z,1694177924.108 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T12:58:44.108Z,1694177924.108 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T12:58:44.907Z,1694177924.907 [NAL9602](INFO): SBD MO Status=0, MOMSN=3486, MT Status=0, MTMSN=0
2023-09-08T12:58:44.907Z,1694177924.907 [NAL9602](INFO): No messages in MT queue
2023-09-08T12:59:15.611Z,1694177955.611 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:01:10.297Z,1694178070.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:01:12.305Z,1694178072.305 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239204
2023-09-08T13:01:45.469Z,1694178105.469 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:02:20.609Z,1694178140.609 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:02:55.757Z,1694178175.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:03:30.897Z,1694178210.897 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:03:44.687Z,1694178224.687 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T13:03:44.687Z,1694178224.687 [Default:CheckIn:C.Wait] Stopped
2023-09-08T13:03:44.687Z,1694178224.687 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:03:44.688Z,1694178224.688 [Default:CheckIn:D] Running Loop=1
2023-09-08T13:03:45.103Z,1694178225.103 [Default:CheckIn:D] Stopped
2023-09-08T13:03:45.103Z,1694178225.103 [Default:CheckIn:E] Running Loop=1
2023-09-08T13:03:45.499Z,1694178225.499 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.963418 min 
2023-09-08T13:03:45.499Z,1694178225.499 [Default:CheckIn:E] Stopped
2023-09-08T13:03:45.499Z,1694178225.499 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T13:03:45.499Z,1694178225.499 [Default:CheckIn] Stopped
2023-09-08T13:03:45.499Z,1694178225.499 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T13:03:45.500Z,1694178225.500 [Default:CheckIn](INFO): Running loop #5
2023-09-08T13:03:45.500Z,1694178225.500 [Default:CheckIn] Running Loop=5
2023-09-08T13:03:45.500Z,1694178225.500 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T13:03:45.500Z,1694178225.500 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T13:03:47.516Z,1694178227.516 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,130346.00,A,4145.53026,N,08116.65199,W,0.019,0.00,080923,,,D*78
2023-09-08T13:03:47.518Z,1694178227.518 [NAL9602](INFO): GPS fix at 20230908T130346: (41.758838, -81.277533)
2023-09-08T13:03:47.528Z,1694178227.528 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T13:03:47.528Z,1694178227.528 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T13:04:06.038Z,1694178246.038 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:04:19.652Z,1694178259.652 [NAL9602](INFO): SBD MO Status=1, MOMSN=3487, MT Status=0, MTMSN=0
2023-09-08T13:04:19.713Z,1694178259.713 [NAL9602](INFO): Sent 72 bytes from file Logs/20230908T111755/Courier0073.lzma
2023-09-08T13:04:19.713Z,1694178259.713 [NAL9602](INFO): Packets left to send: 0
2023-09-08T13:04:28.623Z,1694178268.623 [NAL9602](INFO): SBD MO Status=1, MOMSN=3488, MT Status=0, MTMSN=0
2023-09-08T13:04:28.675Z,1694178268.675 [NAL9602](INFO): Sent 131 bytes from file Logs/20230908T111755/Express0074.lzma
2023-09-08T13:04:28.675Z,1694178268.675 [NAL9602](INFO): Packets left to send: 0
2023-09-08T13:04:37.587Z,1694178277.587 [NAL9602](INFO): SBD MO Status=0, MOMSN=3489, MT Status=0, MTMSN=0
2023-09-08T13:04:37.654Z,1694178277.654 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T13:04:37.654Z,1694178277.654 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T13:04:37.654Z,1694178277.654 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:04:41.177Z,1694178281.177 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:05:08.290Z,1694178308.290 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:05:16.317Z,1694178316.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:05:51.457Z,1694178351.457 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:06:26.597Z,1694178386.597 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:07:01.737Z,1694178421.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:07:36.877Z,1694178456.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:08:12.017Z,1694178492.017 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:08:47.165Z,1694178527.165 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:09:22.305Z,1694178562.305 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:09:37.149Z,1694178577.149 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002794
2023-09-08T13:09:38.240Z,1694178578.240 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T13:09:38.240Z,1694178578.240 [Default:CheckIn:C.Wait] Stopped
2023-09-08T13:09:38.240Z,1694178578.240 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:09:38.245Z,1694178578.245 [Default:CheckIn:D] Running Loop=1
2023-09-08T13:09:38.637Z,1694178578.637 [Default:CheckIn:D] Stopped
2023-09-08T13:09:38.637Z,1694178578.637 [Default:CheckIn:E] Running Loop=1
2023-09-08T13:09:39.039Z,1694178579.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.855650 min 
2023-09-08T13:09:39.039Z,1694178579.039 [Default:CheckIn:E] Stopped
2023-09-08T13:09:39.039Z,1694178579.039 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T13:09:39.039Z,1694178579.039 [Default:CheckIn] Stopped
2023-09-08T13:09:39.039Z,1694178579.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T13:09:39.039Z,1694178579.039 [Default:CheckIn](INFO): Running loop #6
2023-09-08T13:09:39.040Z,1694178579.040 [Default:CheckIn] Running Loop=6
2023-09-08T13:09:39.040Z,1694178579.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T13:09:39.040Z,1694178579.040 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T13:09:41.052Z,1694178581.052 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,130940.00,A,4145.52968,N,08116.65080,W,0.019,0.00,080923,,,D*7F
2023-09-08T13:09:41.054Z,1694178581.054 [NAL9602](INFO): GPS fix at 20230908T130940: (41.758828, -81.277513)
2023-09-08T13:09:41.064Z,1694178581.064 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T13:09:41.064Z,1694178581.064 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T13:09:49.952Z,1694178589.952 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T111755/Courier0076.lzma
2023-09-08T13:09:50.955Z,1694178590.955 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0076.lzma.bak
2023-09-08T13:09:50.955Z,1694178590.955 [DataOverHttps](INFO): SBD MOMSN=18630937
2023-09-08T13:09:55.599Z,1694178595.599 [NAL9602](INFO): SBD MO Status=0, MOMSN=3490, MT Status=0, MTMSN=0
2023-09-08T13:09:55.599Z,1694178595.599 [NAL9602](INFO): No messages in MT queue
2023-09-08T13:10:13.729Z,1694178613.729 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230908T111755/Express0077.lzma
2023-09-08T13:10:14.731Z,1694178614.731 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0077.lzma.bak
2023-09-08T13:10:14.731Z,1694178614.731 [DataOverHttps](INFO): SBD MOMSN=18630949
2023-09-08T13:10:20.747Z,1694178620.747 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T13:10:20.747Z,1694178620.747 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T13:10:20.747Z,1694178620.747 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:10:26.022Z,1694178626.022 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:15:21.489Z,1694178921.489 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T13:15:21.490Z,1694178921.490 [Default:CheckIn:C.Wait] Stopped
2023-09-08T13:15:21.490Z,1694178921.490 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:15:21.490Z,1694178921.490 [Default:CheckIn:D] Running Loop=1
2023-09-08T13:15:21.875Z,1694178921.875 [Default:CheckIn:D] Stopped
2023-09-08T13:15:21.876Z,1694178921.876 [Default:CheckIn:E] Running Loop=1
2023-09-08T13:15:22.271Z,1694178922.271 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.576294 min 
2023-09-08T13:15:22.271Z,1694178922.271 [Default:CheckIn:E] Stopped
2023-09-08T13:15:22.272Z,1694178922.272 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T13:15:22.272Z,1694178922.272 [Default:CheckIn] Stopped
2023-09-08T13:15:22.272Z,1694178922.272 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T13:15:22.272Z,1694178922.272 [Default:CheckIn](INFO): Running loop #7
2023-09-08T13:15:22.272Z,1694178922.272 [Default:CheckIn] Running Loop=7
2023-09-08T13:15:22.272Z,1694178922.272 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T13:15:22.272Z,1694178922.272 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T13:15:24.308Z,1694178924.308 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131523.00,A,4145.52984,N,08116.65111,W,0.117,0.00,080923,,,D*73
2023-09-08T13:15:24.315Z,1694178924.315 [NAL9602](INFO): GPS fix at 20230908T131523: (41.758831, -81.277518)
2023-09-08T13:15:24.365Z,1694178924.365 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T13:15:24.365Z,1694178924.365 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T13:15:36.552Z,1694178936.552 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0079.lzma
2023-09-08T13:15:37.555Z,1694178937.555 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0079.lzma.bak
2023-09-08T13:15:37.555Z,1694178937.555 [DataOverHttps](INFO): SBD MOMSN=18630976
2023-09-08T13:15:52.696Z,1694178952.696 [NAL9602](INFO): SBD MO Status=0, MOMSN=3491, MT Status=0, MTMSN=0
2023-09-08T13:15:52.696Z,1694178952.696 [NAL9602](INFO): No messages in MT queue
2023-09-08T13:15:57.124Z,1694178957.124 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T111755/Express0080.lzma
2023-09-08T13:15:58.127Z,1694178958.127 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0080.lzma.bak
2023-09-08T13:15:58.127Z,1694178958.127 [DataOverHttps](INFO): SBD MOMSN=18630979
2023-09-08T13:16:02.007Z,1694178962.007 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T13:16:02.008Z,1694178962.008 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T13:16:02.008Z,1694178962.008 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:16:23.402Z,1694178983.402 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:21:02.600Z,1694179262.600 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T13:21:02.601Z,1694179262.601 [Default:CheckIn:C.Wait] Stopped
2023-09-08T13:21:02.601Z,1694179262.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:21:02.602Z,1694179262.602 [Default:CheckIn:D] Running Loop=1
2023-09-08T13:21:03.009Z,1694179263.009 [Default:CheckIn:D] Stopped
2023-09-08T13:21:03.009Z,1694179263.009 [Default:CheckIn:E] Running Loop=1
2023-09-08T13:21:03.414Z,1694179263.414 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.261857 min 
2023-09-08T13:21:03.414Z,1694179263.414 [Default:CheckIn:E] Stopped
2023-09-08T13:21:03.414Z,1694179263.414 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T13:21:03.414Z,1694179263.414 [Default:CheckIn] Stopped
2023-09-08T13:21:03.414Z,1694179263.414 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T13:21:03.414Z,1694179263.414 [Default:CheckIn](INFO): Running loop #8
2023-09-08T13:21:03.415Z,1694179263.415 [Default:CheckIn] Running Loop=8
2023-09-08T13:21:03.415Z,1694179263.415 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T13:21:03.415Z,1694179263.415 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T13:21:05.418Z,1694179265.418 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132104.00,A,4145.52979,N,08116.65103,W,0.019,0.00,080923,,,D*7F
2023-09-08T13:21:05.420Z,1694179265.420 [NAL9602](INFO): GPS fix at 20230908T132104: (41.758830, -81.277517)
2023-09-08T13:21:05.468Z,1694179265.468 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T13:21:05.468Z,1694179265.468 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T13:21:16.592Z,1694179276.592 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T111755/Courier0082.lzma
2023-09-08T13:21:17.595Z,1694179277.595 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0082.lzma.bak
2023-09-08T13:21:17.595Z,1694179277.595 [DataOverHttps](INFO): SBD MOMSN=18637074
2023-09-08T13:21:18.370Z,1694179278.370 [NAL9602](INFO): SBD MO Status=0, MOMSN=3492, MT Status=0, MTMSN=0
2023-09-08T13:21:18.371Z,1694179278.371 [NAL9602](INFO): No messages in MT queue
2023-09-08T13:21:36.908Z,1694179296.908 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T111755/Express0083.lzma
2023-09-08T13:21:37.911Z,1694179297.911 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0083.lzma.bak
2023-09-08T13:21:37.911Z,1694179297.911 [DataOverHttps](INFO): SBD MOMSN=18637077
2023-09-08T13:21:41.842Z,1694179301.842 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T13:21:41.842Z,1694179301.842 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T13:21:41.842Z,1694179301.842 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:21:49.078Z,1694179309.078 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:26:42.568Z,1694179602.568 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T13:26:42.568Z,1694179602.568 [Default:CheckIn:C.Wait] Stopped
2023-09-08T13:26:42.568Z,1694179602.568 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:26:42.568Z,1694179602.568 [Default:CheckIn:D] Running Loop=1
2023-09-08T13:26:42.980Z,1694179602.980 [Default:CheckIn:D] Stopped
2023-09-08T13:26:42.980Z,1694179602.980 [Default:CheckIn:E] Running Loop=1
2023-09-08T13:26:43.380Z,1694179603.380 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.928031 min 
2023-09-08T13:26:43.380Z,1694179603.380 [Default:CheckIn:E] Stopped
2023-09-08T13:26:43.385Z,1694179603.385 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T13:26:43.385Z,1694179603.385 [Default:CheckIn] Stopped
2023-09-08T13:26:43.385Z,1694179603.385 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T13:26:43.385Z,1694179603.385 [Default:CheckIn](INFO): Running loop #9
2023-09-08T13:26:43.385Z,1694179603.385 [Default:CheckIn] Running Loop=9
2023-09-08T13:26:43.385Z,1694179603.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T13:26:43.386Z,1694179603.386 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T13:26:45.393Z,1694179605.393 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132644.00,A,4145.53024,N,08116.65124,W,0.039,0.00,080923,,,D*7B
2023-09-08T13:26:45.395Z,1694179605.395 [NAL9602](INFO): GPS fix at 20230908T132644: (41.758837, -81.277521)
2023-09-08T13:26:45.406Z,1694179605.406 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T13:26:45.406Z,1694179605.406 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T13:26:57.136Z,1694179617.136 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T111755/Courier0085.lzma
2023-09-08T13:26:58.139Z,1694179618.139 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0085.lzma.bak
2023-09-08T13:26:58.139Z,1694179618.139 [DataOverHttps](INFO): SBD MOMSN=18637086
2023-09-08T13:27:03.292Z,1694179623.292 [NAL9602](INFO): SBD MO Status=2, MOMSN=3493, MT Status=2, MTMSN=0
2023-09-08T13:27:03.292Z,1694179623.292 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T13:27:18.576Z,1694179638.576 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T111755/Express0086.lzma
2023-09-08T13:27:19.043Z,1694179639.043 [NAL9602](INFO): SBD MO Status=2, MOMSN=3493, MT Status=2, MTMSN=0
2023-09-08T13:27:19.044Z,1694179639.044 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T13:27:19.579Z,1694179639.579 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0086.lzma.bak
2023-09-08T13:27:19.579Z,1694179639.579 [DataOverHttps](INFO): SBD MOMSN=18637096
2023-09-08T13:27:25.545Z,1694179645.545 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T13:27:25.546Z,1694179645.546 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T13:27:25.546Z,1694179645.546 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:27:36.818Z,1694179656.818 [NAL9602](INFO): SBD MO Status=2, MOMSN=3493, MT Status=2, MTMSN=0
2023-09-08T13:27:36.819Z,1694179656.819 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T13:27:46.516Z,1694179666.516 [NAL9602](INFO): SBD MO Status=0, MOMSN=3493, MT Status=0, MTMSN=0
2023-09-08T13:27:46.516Z,1694179666.516 [NAL9602](INFO): No messages in MT queue
2023-09-08T13:28:17.222Z,1694179697.222 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:32:26.112Z,1694179946.112 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T13:32:26.112Z,1694179946.112 [Default:CheckIn:C.Wait] Stopped
2023-09-08T13:32:26.112Z,1694179946.112 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:32:26.113Z,1694179946.113 [Default:CheckIn:D] Running Loop=1
2023-09-08T13:32:26.527Z,1694179946.527 [Default:CheckIn:D] Stopped
2023-09-08T13:32:26.527Z,1694179946.527 [Default:CheckIn:E] Running Loop=1
2023-09-08T13:32:26.919Z,1694179946.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.653813 min 
2023-09-08T13:32:26.919Z,1694179946.919 [Default:CheckIn:E] Stopped
2023-09-08T13:32:26.919Z,1694179946.919 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T13:32:26.919Z,1694179946.919 [Default:CheckIn] Stopped
2023-09-08T13:32:26.920Z,1694179946.920 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T13:32:26.920Z,1694179946.920 [Default:CheckIn](INFO): Running loop #10
2023-09-08T13:32:26.920Z,1694179946.920 [Default:CheckIn] Running Loop=10
2023-09-08T13:32:26.920Z,1694179946.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T13:32:26.920Z,1694179946.920 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T13:32:28.948Z,1694179948.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133228.00,A,4145.52862,N,08116.65101,W,0.039,0.00,080923,,,D*78
2023-09-08T13:32:28.950Z,1694179948.950 [NAL9602](INFO): GPS fix at 20230908T133228: (41.758810, -81.277517)
2023-09-08T13:32:28.961Z,1694179948.961 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T13:32:28.961Z,1694179948.961 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T13:32:41.484Z,1694179961.484 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20230908T111755/Courier0088.lzma
2023-09-08T13:32:42.487Z,1694179962.487 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0088.lzma.bak
2023-09-08T13:32:42.487Z,1694179962.487 [DataOverHttps](INFO): SBD MOMSN=18637112
2023-09-08T13:33:03.755Z,1694179983.755 [NAL9602](INFO): SBD MO Status=0, MOMSN=3494, MT Status=0, MTMSN=0
2023-09-08T13:33:03.755Z,1694179983.755 [NAL9602](INFO): No messages in MT queue
2023-09-08T13:33:04.670Z,1694179984.670 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T111755/Express0089.lzma
2023-09-08T13:33:05.659Z,1694179985.659 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0089.lzma.bak
2023-09-08T13:33:05.659Z,1694179985.659 [DataOverHttps](INFO): SBD MOMSN=18637115
2023-09-08T13:33:11.536Z,1694179991.536 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T13:33:11.549Z,1694179991.549 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T13:33:11.549Z,1694179991.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:33:34.535Z,1694180014.535 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:37:38.345Z,1694180258.345 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-09-08T13:38:12.304Z,1694180292.304 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T13:38:12.304Z,1694180292.304 [Default:CheckIn:C.Wait] Stopped
2023-09-08T13:38:12.309Z,1694180292.309 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:38:12.309Z,1694180292.309 [Default:CheckIn:D] Running Loop=1
2023-09-08T13:38:12.727Z,1694180292.727 [Default:CheckIn:D] Stopped
2023-09-08T13:38:12.727Z,1694180292.727 [Default:CheckIn:E] Running Loop=1
2023-09-08T13:38:13.104Z,1694180293.104 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.423824 min 
2023-09-08T13:38:13.105Z,1694180293.105 [Default:CheckIn:E] Stopped
2023-09-08T13:38:13.105Z,1694180293.105 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T13:38:13.105Z,1694180293.105 [Default:CheckIn] Stopped
2023-09-08T13:38:13.105Z,1694180293.105 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T13:38:13.105Z,1694180293.105 [Default:CheckIn](INFO): Running loop #11
2023-09-08T13:38:13.105Z,1694180293.105 [Default:CheckIn] Running Loop=11
2023-09-08T13:38:13.105Z,1694180293.105 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T13:38:13.105Z,1694180293.105 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T13:38:15.110Z,1694180295.110 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133814.00,A,4145.52936,N,08116.65162,W,0.039,0.00,080923,,,D*78
2023-09-08T13:38:15.112Z,1694180295.112 [NAL9602](INFO): GPS fix at 20230908T133814: (41.758823, -81.277527)
2023-09-08T13:38:15.122Z,1694180295.122 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T13:38:15.122Z,1694180295.122 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T13:38:23.668Z,1694180303.668 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T111755/Courier0091.lzma
2023-09-08T13:38:24.671Z,1694180304.671 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0091.lzma.bak
2023-09-08T13:38:24.671Z,1694180304.671 [DataOverHttps](INFO): SBD MOMSN=18637135
2023-09-08T13:38:28.487Z,1694180308.487 [NAL9602](INFO): SBD MO Status=0, MOMSN=3495, MT Status=0, MTMSN=0
2023-09-08T13:38:28.487Z,1694180308.487 [NAL9602](INFO): No messages in MT queue
2023-09-08T13:38:49.536Z,1694180329.536 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T111755/Express0092.lzma
2023-09-08T13:38:50.539Z,1694180330.539 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0092.lzma.bak
2023-09-08T13:38:50.539Z,1694180330.539 [DataOverHttps](INFO): SBD MOMSN=18637138
2023-09-08T13:38:54.441Z,1694180334.441 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T13:38:54.441Z,1694180334.441 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T13:38:54.441Z,1694180334.441 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:38:58.978Z,1694180338.978 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:41:01.338Z,1694180461.338 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-08T13:41:01.342Z,1694180461.342 [BPC1](INFO): Received data from all battery sticks.
2023-09-08T13:43:55.111Z,1694180635.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T13:43:55.112Z,1694180635.112 [Default:CheckIn:C.Wait] Stopped
2023-09-08T13:43:55.112Z,1694180635.112 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:43:55.112Z,1694180635.112 [Default:CheckIn:D] Running Loop=1
2023-09-08T13:43:55.517Z,1694180635.517 [Default:CheckIn:D] Stopped
2023-09-08T13:43:55.517Z,1694180635.517 [Default:CheckIn:E] Running Loop=1
2023-09-08T13:43:55.932Z,1694180635.932 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.136979 min 
2023-09-08T13:43:55.932Z,1694180635.932 [Default:CheckIn:E] Stopped
2023-09-08T13:43:55.932Z,1694180635.932 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T13:43:55.932Z,1694180635.932 [Default:CheckIn] Stopped
2023-09-08T13:43:55.932Z,1694180635.932 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T13:43:55.933Z,1694180635.933 [Default:CheckIn](INFO): Running loop #12
2023-09-08T13:43:55.933Z,1694180635.933 [Default:CheckIn] Running Loop=12
2023-09-08T13:43:55.933Z,1694180635.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T13:43:55.933Z,1694180635.933 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T13:43:57.932Z,1694180637.932 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,134357.00,A,4145.52872,N,08116.65073,W,0.078,0.00,080923,,,D*76
2023-09-08T13:43:57.934Z,1694180637.934 [NAL9602](INFO): GPS fix at 20230908T134357: (41.758812, -81.277512)
2023-09-08T13:43:57.944Z,1694180637.944 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T13:43:57.965Z,1694180637.965 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T13:44:07.044Z,1694180647.044 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230908T111755/Courier0094.lzma
2023-09-08T13:44:08.055Z,1694180648.055 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0094.lzma.bak
2023-09-08T13:44:08.055Z,1694180648.055 [DataOverHttps](INFO): SBD MOMSN=18637160
2023-09-08T13:44:27.879Z,1694180667.879 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T111755/Express0095.lzma
2023-09-08T13:44:29.007Z,1694180669.007 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0095.lzma.bak
2023-09-08T13:44:29.007Z,1694180669.007 [DataOverHttps](INFO): SBD MOMSN=18637163
2023-09-08T13:44:35.642Z,1694180675.642 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T13:44:35.642Z,1694180675.642 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T13:44:35.642Z,1694180675.642 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:44:49.376Z,1694180689.376 [NAL9602](INFO): SBD MO Status=0, MOMSN=3496, MT Status=0, MTMSN=0
2023-09-08T13:44:49.376Z,1694180689.376 [NAL9602](INFO): No messages in MT queue
2023-09-08T13:45:20.095Z,1694180720.095 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:48:52.010Z,1694180932.010 [CommandExec](IMPORTANT): got command restart application
2023-09-08T13:48:53.013Z,1694180933.013 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:48:53.013Z,1694180933.013 [CommandExec](INFO): Uninitializing the command executive.
2023-09-08T13:48:53.013Z,1694180933.013 [CommandExec](INFO): Uninitializing the command scheduler.
2023-09-08T13:48:53.013Z,1694180933.013 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:53.137Z,1694180933.137 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-09-08T13:48:53.137Z,1694180933.137 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-09-08T13:48:53.137Z,1694180933.137 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:53.137Z,1694180933.137 [NavChartDb](INFO): Join timeout helper Thread ID is 1602
2023-09-08T13:48:53.246Z,1694180933.246 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:48:53.247Z,1694180933.247 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:53.249Z,1694180933.249 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-09-08T13:48:53.250Z,1694180933.250 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:53.250Z,1694180933.250 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1603
2023-09-08T13:48:53.593Z,1694180933.593 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:48:53.593Z,1694180933.593 [WetLabsBB2FL](INFO): Powering down
2023-09-08T13:48:53.594Z,1694180933.594 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:53.597Z,1694180933.597 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-09-08T13:48:53.597Z,1694180933.597 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:53.597Z,1694180933.597 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1604
2023-09-08T13:48:54.537Z,1694180934.537 [CTD_Seabird](INFO): Powering down
2023-09-08T13:48:54.554Z,1694180934.554 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:48:54.554Z,1694180934.554 [CTD_Seabird](INFO): Powering down
2023-09-08T13:48:54.569Z,1694180934.569 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:54.577Z,1694180934.577 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-09-08T13:48:54.577Z,1694180934.577 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:54.577Z,1694180934.577 [Radio_Surface](INFO): Join timeout helper Thread ID is 1605
2023-09-08T13:48:54.893Z,1694180934.893 [Radio_Surface](INFO): Powering down
2023-09-08T13:48:54.894Z,1694180934.894 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:48:54.894Z,1694180934.894 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:54.901Z,1694180934.901 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-09-08T13:48:54.901Z,1694180934.901 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:54.901Z,1694180934.901 [Onboard](INFO): Join timeout helper Thread ID is 1606
2023-09-08T13:48:55.441Z,1694180935.441 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-09-08T13:48:59.641Z,1694180939.641 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:48:59.641Z,1694180939.641 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:59.646Z,1694180939.646 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-09-08T13:48:59.646Z,1694180939.646 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:48:59.646Z,1694180939.646 [DataOverHttps](INFO): Join timeout helper Thread ID is 1607
2023-09-08T13:49:00.461Z,1694180940.461 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:49:00.461Z,1694180940.461 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:00.486Z,1694180940.486 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-09-08T13:49:00.486Z,1694180940.486 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:00.486Z,1694180940.486 [BackseatComponent](INFO): Join timeout helper Thread ID is 1608
2023-09-08T13:49:00.517Z,1694180940.517 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:49:00.517Z,1694180940.517 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:00.529Z,1694180940.529 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-09-08T13:49:00.529Z,1694180940.529 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:00.530Z,1694180940.530 [logger](INFO): Join timeout helper Thread ID is 1609
2023-09-08T13:49:00.559Z,1694180940.559 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:49:00.559Z,1694180940.559 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:00.569Z,1694180940.569 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-09-08T13:49:00.570Z,1694180940.570 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:00.570Z,1694180940.570 [CommandLine](INFO): Join timeout helper Thread ID is 1610
2023-09-08T13:49:00.589Z,1694180940.589 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:49:00.589Z,1694180940.589 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:00.609Z,1694180940.609 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-09-08T13:49:00.609Z,1694180940.609 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:00.610Z,1694180940.610 [CommandExec](INFO): Join timeout helper Thread ID is 1611
2023-09-08T13:49:00.611Z,1694180940.611 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-09-08T13:49:00.611Z,1694180940.611 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:00.612Z,1694180940.612 [controlThread](INFO): Join timeout helper Thread ID is 1612
2023-09-08T13:49:00.781Z,1694180940.781 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T13:49:00.781Z,1694180940.781 [controlThread](DEBUG): Uninitializing ControlThread
2023-09-08T13:49:00.781Z,1694180940.781 [AHRS_M2](INFO): Powering down
2023-09-08T13:49:00.854Z,1694180940.854 [NAL9602](INFO): Powering down
2023-09-08T13:49:00.855Z,1694180940.855 [Sonardyne_Nano](INFO): Powering down
2023-09-08T13:49:00.974Z,1694180940.974 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-09-08T13:49:00.974Z,1694180940.974 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-09-08T13:49:00.975Z,1694180940.975 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-09-08T13:49:00.975Z,1694180940.975 [MissionManager](INFO): Uninitializing Mission Default
2023-09-08T13:49:00.975Z,1694180940.975 [Default] Stopped
2023-09-08T13:49:00.975Z,1694180940.975 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-08T13:49:00.976Z,1694180940.976 [Default:B.GoToSurface] Stopped
2023-09-08T13:49:00.976Z,1694180940.976 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T13:49:00.976Z,1694180940.976 [Default:CheckIn] Stopped
2023-09-08T13:49:00.976Z,1694180940.976 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T13:49:00.976Z,1694180940.976 [Default:CheckIn:C.Wait] Stopped
2023-09-08T13:49:00.976Z,1694180940.976 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:49:00.979Z,1694180940.979 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-09-08T13:49:00.979Z,1694180940.979 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-09-08T13:49:00.979Z,1694180940.979 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-09-08T13:49:00.980Z,1694180940.980 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-09-08T13:49:00.980Z,1694180940.980 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-09-08T13:49:00.980Z,1694180940.980 [BuoyancyServo](INFO): Powering down
2023-09-08T13:49:00.993Z,1694180940.993 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-09-08T13:49:00.993Z,1694180940.993 [ElevatorServo](INFO): Powering down
2023-09-08T13:49:00.994Z,1694180940.994 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-09-08T13:49:00.994Z,1694180940.994 [MassServo](INFO): Powering down
2023-09-08T13:49:00.995Z,1694180940.995 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-09-08T13:49:00.995Z,1694180940.995 [RudderServo](INFO): Powering down
2023-09-08T13:49:00.996Z,1694180940.996 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-08T13:49:00.996Z,1694180940.996 [ThrusterHE](INFO): Powering down
2023-09-08T13:49:00.997Z,1694180940.997 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-09-08T13:49:00.997Z,1694180940.997 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-09-08T13:49:00.997Z,1694180940.997 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-09-08T13:49:00.997Z,1694180940.997 [CBIT](DEBUG): Powering off loads.
2023-09-08T13:49:01.009Z,1694180941.009 [CBIT](DEBUG): Disabling WDT.
2023-09-08T13:49:01.021Z,1694180941.021 [CBIT](DEBUG): Opening all GF detection circuits.
2023-09-08T13:49:01.022Z,1694180941.022 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:01.077Z,1694180941.077 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:01.080Z,1694180941.080 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:01.086Z,1694180941.086 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:01.130Z,1694180941.130 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:01.168Z,1694180941.168 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:01.181Z,1694180941.181 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:01.213Z,1694180941.213 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-08T13:49:01.262Z,1694180941.262 [logger ThreadHandler](INFO): Thread cancelled.