2024-06-06T12:48:18.549Z,1717678098.549 [Supervisor](DEBUG): Initializing supervisor.
2024-06-06T12:48:18.554Z,1717678098.554 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-06-06T12:48:18.554Z,1717678098.554 [SyncHandler](INFO): Protected caller Thread ID is 1513
2024-06-06T12:48:18.555Z,1717678098.555 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-06-06T12:48:18.556Z,1717678098.556 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-06-06T12:48:18.556Z,1717678098.556 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1514
2024-06-06T12:48:18.560Z,1717678098.560 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-06-06T12:48:18.579Z,1717678098.579 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-06-06T12:48:18.580Z,1717678098.580 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-06-06T12:48:18.581Z,1717678098.581 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1515
2024-06-06T12:48:18.585Z,1717678098.585 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-06-06T12:48:18.586Z,1717678098.586 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-06-06T12:48:18.586Z,1717678098.586 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1516
2024-06-06T12:48:18.588Z,1717678098.588 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-06-06T12:48:18.589Z,1717678098.589 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-06-06T12:48:18.590Z,1717678098.590 [logger ThreadHandler](INFO): Protected caller Thread ID is 1517
2024-06-06T12:48:18.594Z,1717678098.594 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-06-06T12:48:18.595Z,1717678098.595 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-06-06T12:48:18.596Z,1717678098.596 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-06-06T12:48:18.974Z,1717678098.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-06-06T12:48:18.975Z,1717678098.975 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-06-06T12:48:19.812Z,1717678099.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-06-06T12:48:19.813Z,1717678099.813 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-06-06T12:48:19.938Z,1717678099.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-06-06T12:48:19.938Z,1717678099.938 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-06-06T12:48:20.560Z,1717678100.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-06-06T12:48:20.561Z,1717678100.561 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-06-06T12:48:20.674Z,1717678100.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-06-06T12:48:20.674Z,1717678100.674 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-06-06T12:48:20.769Z,1717678100.769 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-06-06T12:48:21.237Z,1717678101.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-06-06T12:48:21.237Z,1717678101.237 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-06-06T12:48:21.665Z,1717678101.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-06-06T12:48:21.665Z,1717678101.665 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-06-06T12:48:21.945Z,1717678101.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-06-06T12:48:21.946Z,1717678101.946 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-06-06T12:48:22.261Z,1717678102.261 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-06-06T12:48:22.261Z,1717678102.261 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-06-06T12:48:22.868Z,1717678102.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-06-06T12:48:22.869Z,1717678102.869 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-06-06T12:48:23.300Z,1717678103.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-06-06T12:48:23.300Z,1717678103.300 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-06-06T12:48:23.394Z,1717678103.394 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-06-06T12:48:23.617Z,1717678103.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-06-06T12:48:23.618Z,1717678103.618 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-06-06T12:48:23.879Z,1717678103.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-06-06T12:48:23.880Z,1717678103.880 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-06-06T12:48:24.217Z,1717678104.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-06-06T12:48:24.219Z,1717678104.219 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2024-06-06T12:48:24.220Z,1717678104.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2024-06-06T12:48:24.345Z,1717678104.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2024-06-06T12:48:24.520Z,1717678104.520 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2024-06-06T12:48:24.620Z,1717678104.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2024-06-06T12:48:24.718Z,1717678104.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2024-06-06T12:48:24.845Z,1717678104.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg
2024-06-06T12:48:24.944Z,1717678104.944 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2024-06-06T12:48:25.068Z,1717678105.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2024-06-06T12:48:25.351Z,1717678105.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-06-06T12:48:25.352Z,1717678105.352 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2024-06-06T12:48:25.508Z,1717678105.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2024-06-06T12:48:25.618Z,1717678105.618 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2024-06-06T12:48:25.725Z,1717678105.725 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2024-06-06T12:48:25.847Z,1717678105.847 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-06-06T12:48:25.858Z,1717678105.858 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-06-06T12:48:25.973Z,1717678105.973 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-06-06T12:48:25.974Z,1717678105.974 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-06-06T12:48:25.984Z,1717678105.984 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-06-06T12:48:25.984Z,1717678105.984 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-06-06T12:48:26.035Z,1717678106.035 [DepthRateCalculator] Loaded
2024-06-06T12:48:26.035Z,1717678106.035 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-06-06T12:48:26.041Z,1717678106.041 [PitchRateCalculator] Loaded
2024-06-06T12:48:26.041Z,1717678106.041 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-06-06T12:48:26.052Z,1717678106.052 [SpeedCalculator] Loaded
2024-06-06T12:48:26.052Z,1717678106.052 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-06-06T12:48:26.057Z,1717678106.057 [YawRateCalculator] Loaded
2024-06-06T12:48:26.058Z,1717678106.058 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-06-06T12:48:26.079Z,1717678106.079 [ElevatorOffsetCalculator] Loaded
2024-06-06T12:48:26.080Z,1717678106.080 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-06-06T12:48:26.080Z,1717678106.080 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-06-06T12:48:26.081Z,1717678106.081 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-06-06T12:48:26.133Z,1717678106.133 [VerticalControl](DEBUG): Construct VerticalControl.
2024-06-06T12:48:26.192Z,1717678106.192 [VerticalControl] Loaded
2024-06-06T12:48:26.192Z,1717678106.192 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-06-06T12:48:26.195Z,1717678106.195 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-06-06T12:48:26.243Z,1717678106.243 [HorizontalControl] Loaded
2024-06-06T12:48:26.243Z,1717678106.243 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-06-06T12:48:26.246Z,1717678106.246 [SpeedControl](DEBUG): Construct SpeedControl.
2024-06-06T12:48:26.249Z,1717678106.249 [SpeedControl] Loaded
2024-06-06T12:48:26.249Z,1717678106.249 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-06-06T12:48:26.252Z,1717678106.252 [LoopControl](DEBUG): Construct LoopControl.
2024-06-06T12:48:26.252Z,1717678106.252 [LoopControl] Loaded
2024-06-06T12:48:26.252Z,1717678106.252 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-06-06T12:48:26.253Z,1717678106.253 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-06-06T12:48:26.253Z,1717678106.253 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-06-06T12:48:26.647Z,1717678106.647 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-06-06T12:48:26.647Z,1717678106.647 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-06-06T12:48:26.697Z,1717678106.697 [NavChart] Loaded
2024-06-06T12:48:26.697Z,1717678106.697 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-06-06T12:48:26.706Z,1717678106.706 [UniversalFixResidualReporter] Loaded
2024-06-06T12:48:26.706Z,1717678106.706 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-06-06T12:48:26.706Z,1717678106.706 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-06-06T12:48:26.707Z,1717678106.707 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-06-06T12:48:26.889Z,1717678106.889 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-06-06T12:48:26.890Z,1717678106.890 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-06-06T12:48:27.358Z,1717678107.358 [AHRS_M2] Loaded
2024-06-06T12:48:27.358Z,1717678107.358 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-06-06T12:48:27.397Z,1717678107.397 [BackseatComponent] Loaded
2024-06-06T12:48:27.397Z,1717678107.397 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-06-06T12:48:27.398Z,1717678107.398 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409214E0
2024-06-06T12:48:27.399Z,1717678107.399 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1602
2024-06-06T12:48:27.402Z,1717678107.402 [LcmUniversalReporter] Loaded
2024-06-06T12:48:27.402Z,1717678107.402 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-06-06T12:48:28.251Z,1717678108.251 [BPC1] Loaded
2024-06-06T12:48:28.251Z,1717678108.251 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-06-06T12:48:28.324Z,1717678108.324 [DataOverHttps] Loaded
2024-06-06T12:48:28.324Z,1717678108.324 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-06-06T12:48:28.325Z,1717678108.325 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409514E0
2024-06-06T12:48:28.325Z,1717678108.325 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1603
2024-06-06T12:48:28.348Z,1717678108.348 [Depth_Keller] Loaded
2024-06-06T12:48:28.349Z,1717678108.349 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-06-06T12:48:28.354Z,1717678108.354 [DropWeight] Loaded
2024-06-06T12:48:28.354Z,1717678108.354 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-06-06T12:48:28.419Z,1717678108.419 [NAL9602] Loaded
2024-06-06T12:48:28.419Z,1717678108.419 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-06-06T12:48:28.449Z,1717678108.449 [Onboard] Loaded
2024-06-06T12:48:28.450Z,1717678108.450 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-06-06T12:48:28.451Z,1717678108.451 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409814E0
2024-06-06T12:48:28.451Z,1717678108.451 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1604
2024-06-06T12:48:28.465Z,1717678108.465 [Power24vConverter] Loaded
2024-06-06T12:48:28.466Z,1717678108.466 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-06-06T12:48:28.481Z,1717678108.481 [Sonardyne_Nano] Loaded
2024-06-06T12:48:28.482Z,1717678108.482 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-06-06T12:48:28.482Z,1717678108.482 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-06-06T12:48:28.483Z,1717678108.483 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-06-06T12:48:28.618Z,1717678108.618 [BuoyancyServo] Loaded
2024-06-06T12:48:28.618Z,1717678108.618 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-06-06T12:48:28.641Z,1717678108.641 [ElevatorServo] Loaded
2024-06-06T12:48:28.642Z,1717678108.642 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-06-06T12:48:28.664Z,1717678108.664 [MassServo] Loaded
2024-06-06T12:48:28.664Z,1717678108.664 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-06-06T12:48:28.685Z,1717678108.685 [RudderServo] Loaded
2024-06-06T12:48:28.685Z,1717678108.685 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-06-06T12:48:28.701Z,1717678108.701 [ThrusterHE] Loaded
2024-06-06T12:48:28.702Z,1717678108.702 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-06-06T12:48:28.702Z,1717678108.702 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-06-06T12:48:28.703Z,1717678108.703 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-06-06T12:48:28.759Z,1717678108.759 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-06-06T12:48:28.760Z,1717678108.760 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-06-06T12:48:28.786Z,1717678108.786 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-06-06T12:48:28.787Z,1717678108.787 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-06-06T12:48:29.131Z,1717678109.131 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-06-06T12:48:29.132Z,1717678109.132 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-06-06T12:48:29.309Z,1717678109.309 [CTD_Seabird] Loaded
2024-06-06T12:48:29.309Z,1717678109.309 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-06-06T12:48:29.310Z,1717678109.310 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B384E0
2024-06-06T12:48:29.311Z,1717678109.311 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1605
2024-06-06T12:48:29.347Z,1717678109.347 [ESPComponent] Loaded
2024-06-06T12:48:29.348Z,1717678109.348 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-06-06T12:48:29.370Z,1717678109.370 [PAR_Licor] Loaded
2024-06-06T12:48:29.370Z,1717678109.370 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-06-06T12:48:29.417Z,1717678109.417 [WetLabsBB2FL] Loaded
2024-06-06T12:48:29.417Z,1717678109.417 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-06-06T12:48:29.419Z,1717678109.419 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B684E0
2024-06-06T12:48:29.419Z,1717678109.419 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1606
2024-06-06T12:48:29.420Z,1717678109.420 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-06-06T12:48:29.420Z,1717678109.420 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-06-06T12:48:29.564Z,1717678109.564 [SBIT](DEBUG): Construct Startup Built In Test.
2024-06-06T12:48:29.574Z,1717678109.574 [SBIT] Loaded
2024-06-06T12:48:29.574Z,1717678109.574 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-06-06T12:48:29.577Z,1717678109.577 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-06-06T12:48:29.590Z,1717678109.590 [IBIT] Loaded
2024-06-06T12:48:29.590Z,1717678109.590 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-06-06T12:48:29.596Z,1717678109.596 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-06-06T12:48:29.705Z,1717678109.705 [CBIT] Loaded
2024-06-06T12:48:29.705Z,1717678109.705 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-06-06T12:48:29.705Z,1717678109.705 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-06-06T12:48:29.712Z,1717678109.712 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-06-06T12:48:29.715Z,1717678109.715 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-06-06T12:48:29.727Z,1717678109.727 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-06-06T12:48:29.728Z,1717678109.728 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2024-06-06T12:48:29.728Z,1717678109.728 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1607
2024-06-06T12:48:29.733Z,1717678109.733 [Supervisor](INFO): Main Thread ID is 827
2024-06-06T12:48:29.733Z,1717678109.733 [Supervisor](DEBUG): Running supervisor.
2024-06-06T12:48:29.734Z,1717678109.734 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1608
2024-06-06T12:48:29.734Z,1717678109.734 [CommandExec](INFO): Initializing the command executive.
2024-06-06T12:48:29.736Z,1717678109.736 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1609
2024-06-06T12:48:29.738Z,1717678109.738 [controlThread ThreadHandler](INFO): Handler Thread ID is 1610
2024-06-06T12:48:29.738Z,1717678109.738 [controlThread](DEBUG): Initializing ControlThread
2024-06-06T12:48:29.739Z,1717678109.739 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-06-06T12:48:29.740Z,1717678109.740 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-06-06T12:48:29.740Z,1717678109.740 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-06-06T12:48:29.740Z,1717678109.740 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-06-06T12:48:29.740Z,1717678109.740 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-06-06T12:48:29.741Z,1717678109.741 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-06-06T12:48:29.743Z,1717678109.743 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-06-06T12:48:29.743Z,1717678109.743 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-06-06T12:48:29.744Z,1717678109.744 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-06-06T12:48:29.744Z,1717678109.744 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-06-06T12:48:29.745Z,1717678109.745 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-06-06T12:48:29.752Z,1717678109.752 [SBIT](INFO): Initialize SBIT Component.
2024-06-06T12:48:29.752Z,1717678109.752 [SBIT](IMPORTANT): git: 2024-06-04
2024-06-06T12:48:29.752Z,1717678109.752 [SBIT](INFO): git hash: 665aaa51948338ae26aa7901209e8336b196d4d1
2024-06-06T12:48:29.753Z,1717678109.753 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-06-06T12:48:29.754Z,1717678109.754 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-06-06T12:48:29.755Z,1717678109.755 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2024-06-06T12:48:29.755Z,1717678109.755 [IBIT](INFO): Initialize IBIT Component.
2024-06-06T12:48:29.756Z,1717678109.756 [CBIT](DEBUG): Initialize CBIT Component.
2024-06-06T12:48:29.757Z,1717678109.757 [logger ThreadHandler](INFO): Handler Thread ID is 1611
2024-06-06T12:48:29.767Z,1717678109.767 [CBIT](DEBUG): Initialized mux pins.
2024-06-06T12:48:29.767Z,1717678109.767 [CBIT](DEBUG): Initializing the watchdog timer.
2024-06-06T12:48:29.776Z,1717678109.776 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1612
2024-06-06T12:48:29.788Z,1717678109.788 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1613
2024-06-06T12:48:29.789Z,1717678109.789 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-06-06T12:48:29.791Z,1717678109.791 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-06-06T12:48:29.792Z,1717678109.792 [CBIT](DEBUG): Initializing heartbeat.
2024-06-06T12:48:29.800Z,1717678109.800 [Onboard ThreadHandler](INFO): Handler Thread ID is 1614
2024-06-06T12:48:29.830Z,1717678109.830 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1615
2024-06-06T12:48:29.831Z,1717678109.831 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-06-06T12:48:29.837Z,1717678109.837 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1617
2024-06-06T12:48:29.844Z,1717678109.844 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1619
2024-06-06T12:48:29.846Z,1717678109.846 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000
2024-06-06T12:48:29.846Z,1717678109.846 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000
2024-06-06T12:48:29.846Z,1717678109.846 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000
2024-06-06T12:48:29.846Z,1717678109.846 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000
2024-06-06T12:48:29.847Z,1717678109.847 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000
2024-06-06T12:48:29.847Z,1717678109.847 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000
2024-06-06T12:48:29.863Z,1717678109.863 [CBIT](DEBUG): Deactivating GF circuits.
2024-06-06T12:48:29.863Z,1717678109.863 [CBIT](DEBUG): Deactivating emergency mode.
2024-06-06T12:48:29.872Z,1717678109.872 [WetLabsBB2FL](INFO): Powering up
2024-06-06T12:48:29.903Z,1717678109.903 [CBIT](DEBUG): Backplane powered.
2024-06-06T12:48:29.904Z,1717678109.904 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-06-06T12:48:29.905Z,1717678109.905 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-06-06T12:48:29.905Z,1717678109.905 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-06-06T12:48:29.906Z,1717678109.906 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-06-06T12:48:29.941Z,1717678109.941 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-06-06T12:48:29.945Z,1717678109.945 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-06T12:48:29.990Z,1717678109.990 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-06-06T12:48:29.990Z,1717678109.990 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-06-06T12:48:29.991Z,1717678109.991 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-06-06T12:48:30.012Z,1717678110.012 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-06-06T12:48:30.281Z,1717678110.281 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-06-06T12:48:30.286Z,1717678110.286 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-06-06T12:48:30.300Z,1717678110.300 [Default:A.Wait](DEBUG): Construct Wait.
2024-06-06T12:48:30.302Z,1717678110.302 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-06T12:48:30.334Z,1717678110.334 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-06-06T12:48:30.353Z,1717678110.353 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-06-06T12:48:30.378Z,1717678110.378 [Default:E.Execute](DEBUG): Construct Execute.
2024-06-06T12:48:30.381Z,1717678110.381 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
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=P4H {
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 ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-06-06T12:48:30.392Z,1717678110.392 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,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,
2024-06-06T12:48:30.407Z,1717678110.407 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-06-06T12:48:30.482Z,1717678110.482 [Power24vConverter](INFO): Powering up.
2024-06-06T12:48:30.482Z,1717678110.482 [Sonardyne_Nano](INFO): Initializing.
2024-06-06T12:48:30.533Z,1717678110.533 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-06-06T12:48:30.540Z,1717678110.540 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-06-06T12:48:30.540Z,1717678110.540 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-06T12:48:30.548Z,1717678110.548 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-06T12:48:30.548Z,1717678110.548 [MassServo](DEBUG): Initializing EZServoServo.
2024-06-06T12:48:30.556Z,1717678110.556 [MassServo](DEBUG): Initializing MassServo.
2024-06-06T12:48:30.556Z,1717678110.556 [RudderServo](DEBUG): Initializing EZServoServo.
2024-06-06T12:48:30.564Z,1717678110.564 [RudderServo](DEBUG): Initializing RudderServo.
2024-06-06T12:48:30.565Z,1717678110.565 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-06-06T12:48:30.572Z,1717678110.572 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-06-06T12:48:32.422Z,1717678112.422 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-06-06T12:48:32.452Z,1717678112.452 [WetLabsBB2FL](INFO): Powering down
2024-06-06T12:48:33.708Z,1717678113.708 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207
2024-06-06T12:48:36.165Z,1717678116.165 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-06-06T12:48:58.693Z,1717678138.693 [NAL9602](INFO): Powering up NAL9602
2024-06-06T12:49:00.911Z,1717678140.911 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-06-06T12:49:09.601Z,1717678149.601 [NAL9602](INFO): NAL9602 initialized
2024-06-06T12:49:29.418Z,1717678169.418 [SBIT](IMPORTANT): Beginning Startup BIT
2024-06-06T12:49:29.422Z,1717678169.422 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-06T12:49:40.859Z,1717678180.859 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003460
CHAN A1 (24V): -0.000094
CHAN A2 (12V): 0.000975
CHAN A3 (5V): 0.000334
CHAN B0 (3.3V): 0.000496
CHAN B1 (3.15aV): 0.000783
CHAN B2 (3.15bV): 0.000012
CHAN B3 (GND): 0.000040
OPEN: 0.004618
Full Scale: +/- 1 mA
2024-06-06T12:49:45.548Z,1717678185.548 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:49:51.205Z,1717678191.205 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:50:06.152Z,1717678206.152 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:50:21.100Z,1717678221.100 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:50:23.549Z,1717678223.549 [SBIT](IMPORTANT): SBIT PASSED
2024-06-06T12:50:23.549Z,1717678223.549 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-06-06T12:50:23.550Z,1717678223.550 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-06-06T12:50:23.550Z,1717678223.550 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;
2024-06-06T12:50:23.550Z,1717678223.550 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar;
2024-06-06T12:50:23.550Z,1717678223.550 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;
2024-06-06T12:50:23.551Z,1717678223.551 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-06-06T12:50:23.551Z,1717678223.551 [SBIT](IMPORTANT): Express linearApproximation CurrentEstimator.current_direction_navigation_frame 25.000000 degree;
2024-06-06T12:50:23.551Z,1717678223.551 [SBIT](IMPORTANT): Express linearApproximation CurrentEstimator.current_speed_navigation_frame 0.500000 meter_per_second;
2024-06-06T12:50:23.551Z,1717678223.551 [SBIT](IMPORTANT): Radio_Surface.loadAtStartup=0 bool;
2024-06-06T12:50:23.551Z,1717678223.551 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=70.614532 cubic_centimeter;
2024-06-06T12:50:23.551Z,1717678223.551 [SBIT](IMPORTANT): VerticalControl.massDefault=1 centimeter;
2024-06-06T12:50:23.959Z,1717678223.959 [MissionManager](IMPORTANT): Started mission Startup
2024-06-06T12:50:23.960Z,1717678223.960 [Startup] Running Loop=1
2024-06-06T12:50:23.960Z,1717678223.960 [Startup](DEBUG): Aggregate::initialize Startup
2024-06-06T12:50:23.960Z,1717678223.960 [Startup:A.GoToSurface] Running Loop=1
2024-06-06T12:50:23.960Z,1717678223.960 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-06T12:50:23.961Z,1717678223.961 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-06T12:50:23.961Z,1717678223.961 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-06T12:50:23.961Z,1717678223.961 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-06T12:50:23.962Z,1717678223.962 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-06T12:50:23.962Z,1717678223.962 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-06T12:50:23.962Z,1717678223.962 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-06T12:50:23.964Z,1717678223.964 [Startup:StartupSatComms] Running Loop=1
2024-06-06T12:50:23.964Z,1717678223.964 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-06-06T12:50:23.964Z,1717678223.964 [Startup:StartupSatComms:A] Running Loop=1
2024-06-06T12:50:24.350Z,1717678224.350 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-06-06T12:50:36.048Z,1717678236.048 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:50:50.592Z,1717678250.592 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:51:05.136Z,1717678265.136 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:51:20.084Z,1717678280.084 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:51:24.145Z,1717678284.145 [Startup:StartupSatComms:A](INFO): Timed out from 2024-06-06T12:50:23.0Z
2024-06-06T12:51:24.145Z,1717678284.145 [Startup:StartupSatComms:A] Stopped
2024-06-06T12:51:24.145Z,1717678284.145 [Startup:StartupSatComms:B] Running Loop=1
2024-06-06T12:51:24.553Z,1717678284.553 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-06T12:51:29.802Z,1717678289.802 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-06-06T12:51:29.802Z,1717678289.802 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-06T12:51:29.813Z,1717678289.813 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-06T12:51:30.224Z,1717678290.224 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-06T12:51:30.224Z,1717678290.224 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-06-06T12:51:34.628Z,1717678294.628 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:51:49.177Z,1717678309.177 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:51:49.190Z,1717678309.190 [NAL9602](INFO): SBD MO Status=2, MOMSN=19969, MT Status=2, MTMSN=0
2024-06-06T12:51:49.190Z,1717678309.190 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T12:51:59.695Z,1717678319.695 [NAL9602](INFO): SBD MO Status=0, MOMSN=19969, MT Status=0, MTMSN=0
2024-06-06T12:51:59.696Z,1717678319.696 [NAL9602](INFO): No messages in MT queue
2024-06-06T12:52:00.903Z,1717678320.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125156.00,A,5600.07595,N,00807.43740,E,0.019,0.00,060624,,,D*67
2024-06-06T12:52:00.906Z,1717678320.906 [NAL9602](INFO): GPS fix at 20240606T125156: (56.001266, 8.123957)
2024-06-06T12:52:04.129Z,1717678324.129 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:52:16.194Z,1717678336.194 [NAL9602](INFO): SBD MO Status=1, MOMSN=19970, MT Status=0, MTMSN=0
2024-06-06T12:52:16.244Z,1717678336.244 [NAL9602](INFO): Sent 39 bytes from file Logs/20240606T072436/Courier0172.lzma
2024-06-06T12:52:16.244Z,1717678336.244 [NAL9602](INFO): Packets left to send: 0
2024-06-06T12:52:20.519Z,1717678340.519 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:52:24.171Z,1717678344.171 [Startup:StartupSatComms:B](INFO): Timed out from 2024-06-06T12:51:24.1Z
2024-06-06T12:52:24.172Z,1717678344.172 [Startup:StartupSatComms:B] Stopped
2024-06-06T12:52:24.172Z,1717678344.172 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-06-06T12:52:24.172Z,1717678344.172 [Startup:StartupSatComms] Stopped
2024-06-06T12:52:24.172Z,1717678344.172 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-06-06T12:52:24.173Z,1717678344.173 [Startup](INFO): Completed Startup
2024-06-06T12:52:24.173Z,1717678344.173 [MissionManager](INFO): Startup is completed.
2024-06-06T12:52:24.173Z,1717678344.173 [MissionManager](INFO): Uninitializing Mission Startup
2024-06-06T12:52:24.173Z,1717678344.173 [Startup] Stopped
2024-06-06T12:52:24.173Z,1717678344.173 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-06-06T12:52:24.173Z,1717678344.173 [Startup:A.GoToSurface] Stopped
2024-06-06T12:52:24.173Z,1717678344.173 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-06T12:52:24.580Z,1717678344.580 [MissionManager](IMPORTANT): Started mission Default
2024-06-06T12:52:24.580Z,1717678344.580 [Default] Running Loop=1
2024-06-06T12:52:24.580Z,1717678344.580 [Default](DEBUG): Aggregate::initialize Default
2024-06-06T12:52:24.580Z,1717678344.580 [Default:B.GoToSurface] Running Loop=1
2024-06-06T12:52:24.580Z,1717678344.580 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-06T12:52:24.581Z,1717678344.581 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-06T12:52:24.581Z,1717678344.581 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-06T12:52:24.581Z,1717678344.581 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-06T12:52:24.581Z,1717678344.581 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-06T12:52:24.582Z,1717678344.582 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-06T12:52:24.582Z,1717678344.582 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-06T12:52:24.582Z,1717678344.582 [Default:A.Wait] Running Loop=1
2024-06-06T12:52:24.582Z,1717678344.582 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-06-06T12:52:26.183Z,1717678346.183 [NAL9602](INFO): SBD MO Status=1, MOMSN=19971, MT Status=0, MTMSN=0
2024-06-06T12:52:26.244Z,1717678346.244 [NAL9602](INFO): Sent 39 bytes from file Logs/20240606T072436/Courier0175.lzma
2024-06-06T12:52:26.244Z,1717678346.244 [NAL9602](INFO): Packets left to send: 0
2024-06-06T12:52:34.657Z,1717678354.657 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:52:37.914Z,1717678357.914 [Default:A.Wait](INFO): Done Waiting.
2024-06-06T12:52:37.914Z,1717678357.914 [Default:A.Wait] Stopped
2024-06-06T12:52:37.914Z,1717678357.914 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-06-06T12:52:38.311Z,1717678358.311 [Default:CheckIn] Running Loop=1
2024-06-06T12:52:38.311Z,1717678358.311 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-06T12:52:38.311Z,1717678358.311 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-06T12:52:38.710Z,1717678358.710 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-06-06T12:52:40.318Z,1717678360.318 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125236.00,A,5600.07710,N,00807.43551,E,0.019,0.00,060624,,,D*6F
2024-06-06T12:52:40.321Z,1717678360.321 [NAL9602](INFO): GPS fix at 20240606T125236: (56.001285, 8.123925)
2024-06-06T12:52:40.331Z,1717678360.331 [Default:CheckIn:Read_GPS] Stopped
2024-06-06T12:52:40.331Z,1717678360.331 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-06T12:52:40.752Z,1717678360.752 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-06T12:52:48.395Z,1717678368.395 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:53:02.582Z,1717678382.582 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:53:17.154Z,1717678397.154 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:53:30.889Z,1717678410.889 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:53:30.898Z,1717678410.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=19972, MT Status=2, MTMSN=0
2024-06-06T12:53:30.898Z,1717678410.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T12:53:44.946Z,1717678424.946 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:53:58.286Z,1717678438.286 [NAL9602](INFO): SBD MO Status=2, MOMSN=19972, MT Status=2, MTMSN=0
2024-06-06T12:53:58.286Z,1717678438.286 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T12:53:59.087Z,1717678439.087 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:54:13.091Z,1717678453.091 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:54:26.819Z,1717678466.819 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:54:28.038Z,1717678468.038 [NAL9602](INFO): SBD MO Status=1, MOMSN=19972, MT Status=0, MTMSN=0
2024-06-06T12:54:28.088Z,1717678468.088 [NAL9602](INFO): Sent 151 bytes from file Logs/20240606T124818/Courier0000.lzma
2024-06-06T12:54:28.088Z,1717678468.088 [NAL9602](INFO): Packets left to send: 0
2024-06-06T12:54:39.693Z,1717678479.693 [NAL9602](INFO): SBD MO Status=1, MOMSN=19973, MT Status=0, MTMSN=0
2024-06-06T12:54:39.747Z,1717678479.747 [NAL9602](INFO): Sent 222 bytes from file Logs/20240606T124818/Courier0004.lzma
2024-06-06T12:54:39.748Z,1717678479.748 [NAL9602](INFO): Packets left to send: 0
2024-06-06T12:54:43.280Z,1717678483.280 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:54:58.706Z,1717678498.706 [NAL9602](INFO): SBD MO Status=2, MOMSN=19974, MT Status=2, MTMSN=0
2024-06-06T12:54:58.706Z,1717678498.706 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T12:55:26.918Z,1717678526.918 [NAL9602](INFO): SBD MO Status=2, MOMSN=19974, MT Status=2, MTMSN=0
2024-06-06T12:55:26.918Z,1717678526.918 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T12:55:54.675Z,1717678554.675 [NAL9602](INFO): SBD MO Status=1, MOMSN=19974, MT Status=0, MTMSN=0
2024-06-06T12:55:54.732Z,1717678554.732 [NAL9602](INFO): Sent 332 bytes from file Logs/20240606T072436/Express0170.lzma
2024-06-06T12:55:54.732Z,1717678554.732 [NAL9602](INFO): Packets left to send: 2
2024-06-06T12:56:10.850Z,1717678570.850 [NAL9602](INFO): SBD MO Status=1, MOMSN=19975, MT Status=0, MTMSN=0
2024-06-06T12:56:10.904Z,1717678570.904 [NAL9602](INFO): Sent 332 bytes from file Logs/20240606T072436/Express0170.lzma
2024-06-06T12:56:10.904Z,1717678570.904 [NAL9602](INFO): Packets left to send: 1
2024-06-06T12:56:27.350Z,1717678587.350 [NAL9602](INFO): SBD MO Status=1, MOMSN=19976, MT Status=0, MTMSN=0
2024-06-06T12:56:27.400Z,1717678587.400 [NAL9602](INFO): Sent 13 bytes from file Logs/20240606T072436/Express0170.lzma
2024-06-06T12:56:27.401Z,1717678587.401 [NAL9602](INFO): Packets left to send: 0
2024-06-06T12:56:53.254Z,1717678613.254 [NAL9602](INFO): SBD MO Status=1, MOMSN=19977, MT Status=0, MTMSN=0
2024-06-06T12:56:53.304Z,1717678613.304 [NAL9602](INFO): Sent 106 bytes from file Logs/20240606T072436/Express0173.lzma
2024-06-06T12:56:53.304Z,1717678613.304 [NAL9602](INFO): Packets left to send: 0
2024-06-06T12:57:08.886Z,1717678628.886 [NAL9602](INFO): SBD MO Status=1, MOMSN=19978, MT Status=0, MTMSN=0
2024-06-06T12:57:08.944Z,1717678628.944 [NAL9602](INFO): Sent 192 bytes from file Logs/20240606T072436/Express0174.lzma
2024-06-06T12:57:08.944Z,1717678628.944 [NAL9602](INFO): Packets left to send: 0
2024-06-06T12:57:17.761Z,1717678637.761 [NAL9602](INFO): SBD MO Status=1, MOMSN=19979, MT Status=0, MTMSN=0
2024-06-06T12:57:17.812Z,1717678637.812 [NAL9602](INFO): Sent 61 bytes from file Logs/20240606T072436/Express0176.lzma
2024-06-06T12:57:17.812Z,1717678637.812 [NAL9602](INFO): Packets left to send: 0
2024-06-06T12:57:37.907Z,1717678657.907 [NAL9602](INFO): SBD MO Status=1, MOMSN=19980, MT Status=0, MTMSN=0
2024-06-06T12:57:37.956Z,1717678657.956 [NAL9602](INFO): Sent 332 bytes from file Logs/20240606T124818/Express0001.lzma
2024-06-06T12:57:37.957Z,1717678657.957 [NAL9602](INFO): Packets left to send: 2
2024-06-06T12:57:55.251Z,1717678675.251 [NAL9602](INFO): SBD MO Status=1, MOMSN=19981, MT Status=0, MTMSN=0
2024-06-06T12:57:55.304Z,1717678675.304 [NAL9602](INFO): Sent 332 bytes from file Logs/20240606T124818/Express0001.lzma
2024-06-06T12:57:55.304Z,1717678675.304 [NAL9602](INFO): Packets left to send: 1
2024-06-06T12:58:31.249Z,1717678711.249 [Power24vConverter](INFO): Powering down.
2024-06-06T12:58:40.542Z,1717678720.542 [NAL9602](INFO): SBD MO Status=2, MOMSN=19982, MT Status=0, MTMSN=0
2024-06-06T12:58:40.542Z,1717678720.542 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T12:59:39.442Z,1717678779.442 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:59:56.421Z,1717678796.421 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T12:59:59.658Z,1717678799.658 [NAL9602](INFO): SBD MO Status=2, MOMSN=19982, MT Status=2, MTMSN=0
2024-06-06T12:59:59.658Z,1717678799.658 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:00:11.308Z,1717678811.308 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:00:26.653Z,1717678826.653 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:00:27.878Z,1717678827.878 [NAL9602](INFO): SBD MO Status=2, MOMSN=19982, MT Status=2, MTMSN=0
2024-06-06T13:00:27.878Z,1717678827.878 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:00:41.555Z,1717678841.555 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:00:48.834Z,1717678848.834 [NAL9602](INFO): SBD MO Status=1, MOMSN=19982, MT Status=0, MTMSN=0
2024-06-06T13:00:48.895Z,1717678848.895 [NAL9602](INFO): Sent 262 bytes from file Logs/20240606T124818/Express0001.lzma
2024-06-06T13:00:48.896Z,1717678848.896 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:00:56.749Z,1717678856.749 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:01:05.234Z,1717678865.234 [NAL9602](INFO): SBD MO Status=1, MOMSN=19983, MT Status=0, MTMSN=0
2024-06-06T13:01:05.283Z,1717678865.283 [NAL9602](INFO): Sent 234 bytes from file Logs/20240606T124818/Express0005.lzma
2024-06-06T13:01:05.284Z,1717678865.284 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:01:10.286Z,1717678870.286 [NAL9602](INFO): SBD MO Status=2, MOMSN=19984, MT Status=2, MTMSN=0
2024-06-06T13:01:10.286Z,1717678870.286 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:01:11.494Z,1717678871.494 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:01:26.770Z,1717678886.770 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:01:30.410Z,1717678890.410 [NAL9602](INFO): SBD MO Status=2, MOMSN=19984, MT Status=2, MTMSN=0
2024-06-06T13:01:30.410Z,1717678890.410 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:01:41.597Z,1717678901.597 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:01:56.142Z,1717678916.142 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:01:57.363Z,1717678917.363 [NAL9602](INFO): SBD MO Status=0, MOMSN=19984, MT Status=0, MTMSN=0
2024-06-06T13:01:57.435Z,1717678917.435 [Default:CheckIn:Read_Iridium] Stopped
2024-06-06T13:01:57.435Z,1717678917.435 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-06T13:01:57.435Z,1717678917.435 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-06T13:02:10.685Z,1717678930.685 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:02:24.421Z,1717678944.421 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:02:28.059Z,1717678948.059 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-06T13:02:38.162Z,1717678958.162 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:02:51.895Z,1717678971.895 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:03:06.037Z,1717678986.037 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:03:19.769Z,1717678999.769 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:03:33.506Z,1717679013.506 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:03:47.241Z,1717679027.241 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:04:01.381Z,1717679041.381 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:04:15.117Z,1717679055.117 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:04:29.259Z,1717679069.259 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:06:57.946Z,1717679217.946 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-06T13:06:57.946Z,1717679217.946 [Default:CheckIn:C.Wait] Stopped
2024-06-06T13:06:57.946Z,1717679217.946 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-06T13:06:57.946Z,1717679217.946 [Default:CheckIn:D] Running Loop=1
2024-06-06T13:06:58.347Z,1717679218.347 [Default:CheckIn:D] Stopped
2024-06-06T13:06:58.347Z,1717679218.347 [Default:CheckIn:E] Running Loop=1
2024-06-06T13:06:58.818Z,1717679218.818 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.562783 min
2024-06-06T13:06:58.818Z,1717679218.818 [Default:CheckIn:E] Stopped
2024-06-06T13:06:58.818Z,1717679218.818 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-06T13:06:58.818Z,1717679218.818 [Default:CheckIn] Stopped
2024-06-06T13:06:58.818Z,1717679218.818 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-06T13:06:58.819Z,1717679218.819 [Default:CheckIn](INFO): Running loop #2
2024-06-06T13:06:58.819Z,1717679218.819 [Default:CheckIn] Running Loop=2
2024-06-06T13:06:58.819Z,1717679218.819 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-06T13:06:58.819Z,1717679218.819 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-06T13:07:00.766Z,1717679220.766 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,130659.00,A,5600.07330,N,00807.44074,E,0.000,0.00,060624,,,D*6D
2024-06-06T13:07:00.768Z,1717679220.768 [NAL9602](INFO): GPS fix at 20240606T130659: (56.001222, 8.124012)
2024-06-06T13:07:00.779Z,1717679220.779 [Default:CheckIn:Read_GPS] Stopped
2024-06-06T13:07:00.779Z,1717679220.779 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-06T13:07:22.806Z,1717679242.806 [NAL9602](INFO): SBD MO Status=2, MOMSN=19985, MT Status=2, MTMSN=0
2024-06-06T13:07:22.806Z,1717679242.806 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:08:10.646Z,1717679290.646 [NAL9602](INFO): SBD MO Status=2, MOMSN=19985, MT Status=2, MTMSN=0
2024-06-06T13:08:10.646Z,1717679290.646 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:09:31.305Z,1717679371.305 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:09:46.150Z,1717679386.150 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:10:01.463Z,1717679401.463 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:10:16.734Z,1717679416.734 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:10:31.925Z,1717679431.925 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:10:47.226Z,1717679447.226 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:11:03.097Z,1717679463.097 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:11:14.014Z,1717679474.014 [NAL9602](INFO): SBD MO Status=2, MOMSN=19985, MT Status=2, MTMSN=0
2024-06-06T13:11:14.014Z,1717679474.014 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:11:18.099Z,1717679478.099 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:11:32.933Z,1717679492.933 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:11:48.630Z,1717679508.630 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:11:49.850Z,1717679509.850 [NAL9602](INFO): SBD MO Status=1, MOMSN=19985, MT Status=0, MTMSN=0
2024-06-06T13:11:49.920Z,1717679509.920 [NAL9602](INFO): Sent 71 bytes from file Logs/20240606T124818/Courier0007.lzma
2024-06-06T13:11:49.920Z,1717679509.920 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:12:03.352Z,1717679523.352 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:12:12.237Z,1717679532.237 [NAL9602](INFO): SBD MO Status=1, MOMSN=19986, MT Status=0, MTMSN=0
2024-06-06T13:12:12.288Z,1717679532.288 [NAL9602](INFO): Sent 160 bytes from file Logs/20240606T124818/Express0008.lzma
2024-06-06T13:12:12.288Z,1717679532.288 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:12:17.239Z,1717679537.239 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:12:31.390Z,1717679551.390 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:12:33.001Z,1717679553.001 [NAL9602](INFO): SBD MO Status=2, MOMSN=19987, MT Status=2, MTMSN=0
2024-06-06T13:12:33.001Z,1717679553.001 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:12:40.581Z,1717679560.581 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:12:45.491Z,1717679565.491 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:12:59.373Z,1717679579.373 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:13:06.655Z,1717679586.655 [NAL9602](INFO): SBD MO Status=0, MOMSN=19987, MT Status=0, MTMSN=0
2024-06-06T13:13:06.731Z,1717679586.731 [Default:CheckIn:Read_Iridium] Stopped
2024-06-06T13:13:06.731Z,1717679586.731 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-06T13:13:06.731Z,1717679586.731 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-06T13:13:13.109Z,1717679593.109 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:13:27.249Z,1717679607.249 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:13:37.351Z,1717679617.351 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-06T13:13:40.985Z,1717679620.985 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:13:55.126Z,1717679635.126 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:14:08.862Z,1717679648.862 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:14:22.598Z,1717679662.598 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-06T13:18:07.242Z,1717679887.242 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-06T13:18:07.242Z,1717679887.242 [Default:CheckIn:C.Wait] Stopped
2024-06-06T13:18:07.242Z,1717679887.242 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-06T13:18:07.242Z,1717679887.242 [Default:CheckIn:D] Running Loop=1
2024-06-06T13:18:07.650Z,1717679887.650 [Default:CheckIn:D] Stopped
2024-06-06T13:18:07.650Z,1717679887.650 [Default:CheckIn:E] Running Loop=1
2024-06-06T13:18:08.041Z,1717679888.041 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.717784 min
2024-06-06T13:18:08.042Z,1717679888.042 [Default:CheckIn:E] Stopped
2024-06-06T13:18:08.042Z,1717679888.042 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-06T13:18:08.042Z,1717679888.042 [Default:CheckIn] Stopped
2024-06-06T13:18:08.042Z,1717679888.042 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-06T13:18:08.042Z,1717679888.042 [Default:CheckIn](INFO): Running loop #3
2024-06-06T13:18:08.042Z,1717679888.042 [Default:CheckIn] Running Loop=3
2024-06-06T13:18:08.042Z,1717679888.042 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-06T13:18:08.042Z,1717679888.042 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-06T13:18:10.058Z,1717679890.058 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131808.00,A,5600.07413,N,00807.43543,E,0.039,0.00,060624,,,D*6C
2024-06-06T13:18:10.060Z,1717679890.060 [NAL9602](INFO): GPS fix at 20240606T131808: (56.001235, 8.123924)
2024-06-06T13:18:10.071Z,1717679890.071 [Default:CheckIn:Read_GPS] Stopped
2024-06-06T13:18:10.071Z,1717679890.071 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-06T13:18:58.507Z,1717679938.507 [NAL9602](INFO): SBD MO Status=2, MOMSN=19988, MT Status=2, MTMSN=0
2024-06-06T13:18:58.507Z,1717679938.507 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:19:30.927Z,1717679970.927 [BPC1](INFO): Calculating totals. Valid battery stick count: 25. Valid reserve battery stick count: 3.
2024-06-06T13:19:30.935Z,1717679970.935 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-06-06T13:20:34.502Z,1717680034.502 [NAL9602](INFO): SBD MO Status=2, MOMSN=19988, MT Status=2, MTMSN=0
2024-06-06T13:20:34.502Z,1717680034.502 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:20:56.117Z,1717680056.117 [NAL9602](INFO): SBD MO Status=2, MOMSN=19988, MT Status=2, MTMSN=0
2024-06-06T13:20:56.117Z,1717680056.117 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:21:25.163Z,1717680085.163 [NAL9602](INFO): SBD MO Status=1, MOMSN=19988, MT Status=0, MTMSN=0
2024-06-06T13:21:25.212Z,1717680085.212 [NAL9602](INFO): Sent 71 bytes from file Logs/20240606T124818/Courier0010.lzma
2024-06-06T13:21:25.212Z,1717680085.212 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:21:47.266Z,1717680107.266 [NAL9602](INFO): SBD MO Status=2, MOMSN=19989, MT Status=2, MTMSN=0
2024-06-06T13:21:47.266Z,1717680107.266 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:22:12.973Z,1717680132.973 [NAL9602](INFO): SBD MO Status=2, MOMSN=19989, MT Status=2, MTMSN=0
2024-06-06T13:22:12.973Z,1717680132.973 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:22:33.982Z,1717680153.982 [NAL9602](INFO): SBD MO Status=1, MOMSN=19989, MT Status=0, MTMSN=0
2024-06-06T13:22:34.044Z,1717680154.044 [NAL9602](INFO): Sent 128 bytes from file Logs/20240606T124818/Express0011.lzma
2024-06-06T13:22:34.044Z,1717680154.044 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:22:40.206Z,1717680160.206 [NAL9602](INFO): SBD MO Status=0, MOMSN=19990, MT Status=0, MTMSN=0
2024-06-06T13:22:40.295Z,1717680160.295 [Default:CheckIn:Read_Iridium] Stopped
2024-06-06T13:22:40.295Z,1717680160.295 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-06T13:22:40.295Z,1717680160.295 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-06T13:23:10.908Z,1717680190.908 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-06T13:27:40.786Z,1717680460.786 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-06T13:27:40.786Z,1717680460.786 [Default:CheckIn:C.Wait] Stopped
2024-06-06T13:27:40.786Z,1717680460.786 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-06T13:27:40.786Z,1717680460.786 [Default:CheckIn:D] Running Loop=1
2024-06-06T13:27:41.202Z,1717680461.202 [Default:CheckIn:D] Stopped
2024-06-06T13:27:41.202Z,1717680461.202 [Default:CheckIn:E] Running Loop=1
2024-06-06T13:27:41.593Z,1717680461.593 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.277035 min
2024-06-06T13:27:41.593Z,1717680461.593 [Default:CheckIn:E] Stopped
2024-06-06T13:27:41.593Z,1717680461.593 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-06T13:27:41.593Z,1717680461.593 [Default:CheckIn] Stopped
2024-06-06T13:27:41.594Z,1717680461.594 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-06T13:27:41.594Z,1717680461.594 [Default:CheckIn](INFO): Running loop #4
2024-06-06T13:27:41.594Z,1717680461.594 [Default:CheckIn] Running Loop=4
2024-06-06T13:27:41.594Z,1717680461.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-06T13:27:41.594Z,1717680461.594 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-06T13:27:43.610Z,1717680463.610 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132741.00,A,5600.07308,N,00807.43393,E,0.019,0.00,060624,,,D*69
2024-06-06T13:27:43.612Z,1717680463.612 [NAL9602](INFO): GPS fix at 20240606T132741: (56.001218, 8.123899)
2024-06-06T13:27:43.623Z,1717680463.623 [Default:CheckIn:Read_GPS] Stopped
2024-06-06T13:27:43.623Z,1717680463.623 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-06T13:29:09.774Z,1717680549.774 [NAL9602](INFO): SBD MO Status=2, MOMSN=19991, MT Status=2, MTMSN=0
2024-06-06T13:29:09.774Z,1717680549.774 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:30:08.289Z,1717680608.289 [NAL9602](INFO): SBD MO Status=2, MOMSN=19991, MT Status=2, MTMSN=0
2024-06-06T13:30:08.289Z,1717680608.289 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:30:34.546Z,1717680634.546 [NAL9602](INFO): SBD MO Status=2, MOMSN=19991, MT Status=2, MTMSN=0
2024-06-06T13:30:34.546Z,1717680634.546 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:30:57.431Z,1717680657.431 [NAL9602](INFO): SBD MO Status=2, MOMSN=19991, MT Status=2, MTMSN=0
2024-06-06T13:30:57.431Z,1717680657.431 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:31:19.877Z,1717680679.877 [NAL9602](INFO): SBD MO Status=1, MOMSN=19991, MT Status=0, MTMSN=0
2024-06-06T13:31:19.932Z,1717680679.932 [NAL9602](INFO): Sent 202 bytes from file Logs/20240606T124818/Courier0013.lzma
2024-06-06T13:31:19.932Z,1717680679.932 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:31:35.174Z,1717680695.174 [NAL9602](INFO): SBD MO Status=1, MOMSN=19992, MT Status=0, MTMSN=0
2024-06-06T13:31:35.223Z,1717680695.223 [NAL9602](INFO): Sent 127 bytes from file Logs/20240606T124818/Express0014.lzma
2024-06-06T13:31:35.224Z,1717680695.224 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:31:51.526Z,1717680711.526 [NAL9602](INFO): SBD MO Status=0, MOMSN=19993, MT Status=0, MTMSN=0
2024-06-06T13:31:51.616Z,1717680711.616 [Default:CheckIn:Read_Iridium] Stopped
2024-06-06T13:31:51.616Z,1717680711.616 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-06T13:31:51.616Z,1717680711.616 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-06T13:32:22.224Z,1717680742.224 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-06T13:36:52.105Z,1717681012.105 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-06T13:36:52.105Z,1717681012.105 [Default:CheckIn:C.Wait] Stopped
2024-06-06T13:36:52.105Z,1717681012.105 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-06T13:36:52.106Z,1717681012.106 [Default:CheckIn:D] Running Loop=1
2024-06-06T13:36:52.511Z,1717681012.511 [Default:CheckIn:D] Stopped
2024-06-06T13:36:52.511Z,1717681012.511 [Default:CheckIn:E] Running Loop=1
2024-06-06T13:36:52.925Z,1717681012.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.465507 min
2024-06-06T13:36:52.925Z,1717681012.925 [Default:CheckIn:E] Stopped
2024-06-06T13:36:52.925Z,1717681012.925 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-06T13:36:52.925Z,1717681012.925 [Default:CheckIn] Stopped
2024-06-06T13:36:52.925Z,1717681012.925 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-06T13:36:52.926Z,1717681012.926 [Default:CheckIn](INFO): Running loop #5
2024-06-06T13:36:52.926Z,1717681012.926 [Default:CheckIn] Running Loop=5
2024-06-06T13:36:52.926Z,1717681012.926 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-06T13:36:52.926Z,1717681012.926 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-06T13:36:54.925Z,1717681014.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133652.00,A,5600.07332,N,00807.43378,E,0.039,0.00,060624,,,D*65
2024-06-06T13:36:54.927Z,1717681014.927 [NAL9602](INFO): GPS fix at 20240606T133652: (56.001222, 8.123896)
2024-06-06T13:36:54.938Z,1717681014.938 [Default:CheckIn:Read_GPS] Stopped
2024-06-06T13:36:54.938Z,1717681014.938 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-06T13:38:09.186Z,1717681089.186 [NAL9602](INFO): SBD MO Status=2, MOMSN=19994, MT Status=2, MTMSN=0
2024-06-06T13:38:09.186Z,1717681089.186 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:38:55.918Z,1717681135.918 [NAL9602](INFO): SBD MO Status=2, MOMSN=19994, MT Status=2, MTMSN=0
2024-06-06T13:38:55.918Z,1717681135.918 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:39:17.525Z,1717681157.525 [NAL9602](INFO): SBD MO Status=2, MOMSN=19994, MT Status=2, MTMSN=0
2024-06-06T13:39:17.525Z,1717681157.525 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:39:44.402Z,1717681184.402 [NAL9602](INFO): SBD MO Status=2, MOMSN=19994, MT Status=2, MTMSN=0
2024-06-06T13:39:44.402Z,1717681184.402 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:40:15.169Z,1717681215.169 [NAL9602](INFO): SBD MO Status=2, MOMSN=19994, MT Status=2, MTMSN=0
2024-06-06T13:40:15.169Z,1717681215.169 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:40:43.810Z,1717681243.810 [NAL9602](INFO): SBD MO Status=1, MOMSN=19994, MT Status=0, MTMSN=0
2024-06-06T13:40:43.863Z,1717681243.863 [NAL9602](INFO): Sent 71 bytes from file Logs/20240606T124818/Courier0016.lzma
2024-06-06T13:40:43.864Z,1717681243.864 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:41:06.770Z,1717681266.770 [NAL9602](INFO): SBD MO Status=2, MOMSN=19995, MT Status=2, MTMSN=0
2024-06-06T13:41:06.770Z,1717681266.770 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:41:32.890Z,1717681292.890 [NAL9602](INFO): SBD MO Status=2, MOMSN=19995, MT Status=2, MTMSN=0
2024-06-06T13:41:32.890Z,1717681292.890 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:42:14.718Z,1717681334.718 [NAL9602](INFO): SBD MO Status=1, MOMSN=19995, MT Status=0, MTMSN=0
2024-06-06T13:42:14.780Z,1717681334.780 [NAL9602](INFO): Sent 128 bytes from file Logs/20240606T124818/Express0017.lzma
2024-06-06T13:42:14.780Z,1717681334.780 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:42:33.190Z,1717681353.190 [NAL9602](INFO): SBD MO Status=2, MOMSN=19996, MT Status=2, MTMSN=0
2024-06-06T13:42:33.190Z,1717681353.190 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:42:40.600Z,1717681360.600 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:02.698Z,1717681382.698 [NAL9602](INFO): SBD MO Status=2, MOMSN=19996, MT Status=2, MTMSN=0
2024-06-06T13:43:02.698Z,1717681382.698 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:43:10.514Z,1717681390.514 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:14.780Z,1717681394.780 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:19.103Z,1717681399.103 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:23.344Z,1717681403.344 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:27.662Z,1717681407.662 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:32.030Z,1717681412.030 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:36.198Z,1717681416.198 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:40.450Z,1717681420.450 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:44.902Z,1717681424.902 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:49.281Z,1717681429.281 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:43:54.897Z,1717681434.897 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:44:03.548Z,1717681443.548 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:44:07.829Z,1717681447.829 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:44:12.031Z,1717681452.031 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:44:24.550Z,1717681464.550 [NAL9602](INFO): SBD MO Status=0, MOMSN=19996, MT Status=0, MTMSN=0
2024-06-06T13:44:24.631Z,1717681464.631 [Default:CheckIn:Read_Iridium] Stopped
2024-06-06T13:44:24.631Z,1717681464.631 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-06T13:44:24.631Z,1717681464.631 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-06T13:44:55.248Z,1717681495.248 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-06T13:49:25.137Z,1717681765.137 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-06T13:49:25.137Z,1717681765.137 [Default:CheckIn:C.Wait] Stopped
2024-06-06T13:49:25.137Z,1717681765.137 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-06T13:49:25.137Z,1717681765.137 [Default:CheckIn:D] Running Loop=1
2024-06-06T13:49:25.538Z,1717681765.538 [Default:CheckIn:D] Stopped
2024-06-06T13:49:25.538Z,1717681765.538 [Default:CheckIn:E] Running Loop=1
2024-06-06T13:49:25.937Z,1717681765.937 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.015963 min
2024-06-06T13:49:25.937Z,1717681765.937 [Default:CheckIn:E] Stopped
2024-06-06T13:49:25.938Z,1717681765.938 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-06T13:49:25.938Z,1717681765.938 [Default:CheckIn] Stopped
2024-06-06T13:49:25.938Z,1717681765.938 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-06T13:49:25.938Z,1717681765.938 [Default:CheckIn](INFO): Running loop #6
2024-06-06T13:49:25.938Z,1717681765.938 [Default:CheckIn] Running Loop=6
2024-06-06T13:49:25.938Z,1717681765.938 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-06T13:49:25.938Z,1717681765.938 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-06T13:49:27.950Z,1717681767.950 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,134925.00,A,5600.07399,N,00807.43047,E,0.058,0.00,060624,,,D*64
2024-06-06T13:49:27.952Z,1717681767.952 [NAL9602](INFO): GPS fix at 20240606T134925: (56.001233, 8.123841)
2024-06-06T13:49:27.963Z,1717681767.963 [Default:CheckIn:Read_GPS] Stopped
2024-06-06T13:49:27.963Z,1717681767.963 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-06T13:49:58.893Z,1717681798.893 [NAL9602](INFO): SBD MO Status=2, MOMSN=19997, MT Status=2, MTMSN=0
2024-06-06T13:49:58.893Z,1717681798.893 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:50:35.248Z,1717681835.248 [NAL9602](INFO): SBD MO Status=2, MOMSN=19997, MT Status=2, MTMSN=0
2024-06-06T13:50:35.248Z,1717681835.248 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:51:15.117Z,1717681875.117 [NAL9602](INFO): SBD MO Status=1, MOMSN=19997, MT Status=0, MTMSN=0
2024-06-06T13:51:15.172Z,1717681875.172 [NAL9602](INFO): Sent 71 bytes from file Logs/20240606T124818/Courier0019.lzma
2024-06-06T13:51:15.172Z,1717681875.172 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:51:25.921Z,1717681885.921 [NAL9602](INFO): SBD MO Status=1, MOMSN=19998, MT Status=0, MTMSN=0
2024-06-06T13:51:25.972Z,1717681885.972 [NAL9602](INFO): Sent 128 bytes from file Logs/20240606T124818/Express0020.lzma
2024-06-06T13:51:25.972Z,1717681885.972 [NAL9602](INFO): Packets left to send: 0
2024-06-06T13:51:39.886Z,1717681899.886 [NAL9602](INFO): SBD MO Status=2, MOMSN=19999, MT Status=2, MTMSN=0
2024-06-06T13:51:39.886Z,1717681899.886 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:51:48.873Z,1717681908.873 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:51:58.828Z,1717681918.828 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:52:03.168Z,1717681923.168 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:52:19.140Z,1717681939.140 [NAL9602](INFO): SBD MO Status=2, MOMSN=19999, MT Status=2, MTMSN=0
2024-06-06T13:52:19.141Z,1717681939.141 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:52:37.330Z,1717681957.330 [NAL9602](INFO): SBD MO Status=2, MOMSN=19999, MT Status=2, MTMSN=0
2024-06-06T13:52:37.330Z,1717681957.330 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:52:44.814Z,1717681964.814 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:52:49.056Z,1717681969.056 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:52:53.419Z,1717681973.419 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:52:57.938Z,1717681977.938 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:53:02.328Z,1717681982.328 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:53:08.288Z,1717681988.288 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:53:16.596Z,1717681996.596 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:53:21.313Z,1717682001.313 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:53:25.597Z,1717682005.597 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:53:29.921Z,1717682009.921 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:53:34.393Z,1717682014.393 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:53:42.226Z,1717682022.226 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:54:07.287Z,1717682047.287 [NAL9602](INFO): SBD MO Status=2, MOMSN=19999, MT Status=2, MTMSN=0
2024-06-06T13:54:07.287Z,1717682047.287 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T13:54:15.477Z,1717682055.477 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:54:19.802Z,1717682059.802 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:54:24.135Z,1717682064.135 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2024-06-06T13:54:41.554Z,1717682081.554 [NAL9602](INFO): SBD MO Status=0, MOMSN=19999, MT Status=0, MTMSN=0
2024-06-06T13:54:41.644Z,1717682081.644 [Default:CheckIn:Read_Iridium] Stopped
2024-06-06T13:54:41.644Z,1717682081.644 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-06T13:54:41.644Z,1717682081.644 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-06T13:55:12.256Z,1717682112.256 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-06T13:59:42.140Z,1717682382.140 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-06T13:59:42.140Z,1717682382.140 [Default:CheckIn:C.Wait] Stopped
2024-06-06T13:59:42.140Z,1717682382.140 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-06T13:59:42.140Z,1717682382.140 [Default:CheckIn:D] Running Loop=1
2024-06-06T13:59:42.546Z,1717682382.546 [Default:CheckIn:D] Stopped
2024-06-06T13:59:42.546Z,1717682382.546 [Default:CheckIn:E] Running Loop=1
2024-06-06T13:59:42.959Z,1717682382.959 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.299434 min
2024-06-06T13:59:42.959Z,1717682382.959 [Default:CheckIn:E] Stopped
2024-06-06T13:59:42.960Z,1717682382.960 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-06T13:59:42.960Z,1717682382.960 [Default:CheckIn] Stopped
2024-06-06T13:59:42.960Z,1717682382.960 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-06T13:59:42.960Z,1717682382.960 [Default:CheckIn](INFO): Running loop #7
2024-06-06T13:59:42.960Z,1717682382.960 [Default:CheckIn] Running Loop=7
2024-06-06T13:59:42.960Z,1717682382.960 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-06T13:59:42.960Z,1717682382.960 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-06T13:59:44.958Z,1717682384.958 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135941.00,A,5600.07261,N,00807.43476,E,0.097,0.00,060624,,,D*64
2024-06-06T13:59:44.960Z,1717682384.960 [NAL9602](INFO): GPS fix at 20240606T135941: (56.001210, 8.123913)
2024-06-06T13:59:44.985Z,1717682384.985 [Default:CheckIn:Read_GPS] Stopped
2024-06-06T13:59:44.985Z,1717682384.985 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-06T14:00:45.202Z,1717682445.202 [NAL9602](INFO): SBD MO Status=2, MOMSN=20000, MT Status=2, MTMSN=0
2024-06-06T14:00:45.202Z,1717682445.202 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T14:01:11.730Z,1717682471.730 [NAL9602](INFO): SBD MO Status=2, MOMSN=20000, MT Status=2, MTMSN=0
2024-06-06T14:01:11.730Z,1717682471.730 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T14:03:40.306Z,1717682620.306 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=20000, MT Status=1, MTMSN=1361
2024-06-06T14:03:40.353Z,1717682620.353 [NAL9602](INFO): Sent 72 bytes from file Logs/20240606T124818/Courier0022.lzma
2024-06-06T14:03:40.354Z,1717682620.354 [NAL9602](INFO): Packets left to send: 0
2024-06-06T14:03:40.788Z,1717682620.788 [NAL9602](INFO): Received command: strobe on
2024-06-06T14:03:40.829Z,1717682620.829 [CommandExec](IMPORTANT): got command strobe on
2024-06-06T14:03:40.829Z,1717682620.829 [CommandExec](IMPORTANT): Activating strobe
2024-06-06T14:04:06.836Z,1717682646.836 [NAL9602](INFO): SBD MO Status=2, MOMSN=20001, MT Status=2, MTMSN=0
2024-06-06T14:04:06.836Z,1717682646.836 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T14:04:35.082Z,1717682675.082 [NAL9602](INFO): SBD MO Status=2, MOMSN=20001, MT Status=2, MTMSN=0
2024-06-06T14:04:35.082Z,1717682675.082 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T14:05:14.350Z,1717682714.350 [NAL9602](INFO): SBD MO Status=2, MOMSN=20001, MT Status=2, MTMSN=0
2024-06-06T14:05:14.351Z,1717682714.351 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T14:05:38.990Z,1717682738.990 [NAL9602](INFO): SBD MO Status=2, MOMSN=20001, MT Status=2, MTMSN=0
2024-06-06T14:05:38.990Z,1717682738.990 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T14:06:49.578Z,1717682809.578 [NAL9602](INFO): SBD MO Status=2, MOMSN=20001, MT Status=2, MTMSN=0
2024-06-06T14:06:49.578Z,1717682809.578 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T14:08:16.778Z,1717682896.778 [NAL9602](INFO): SBD MO Status=2, MOMSN=20001, MT Status=2, MTMSN=0
2024-06-06T14:08:16.778Z,1717682896.778 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-06T14:08:38.871Z,1717682918.871 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=20001, MT Status=1, MTMSN=1362
2024-06-06T14:08:38.925Z,1717682918.925 [NAL9602](INFO): Sent 128 bytes from file Logs/20240606T124818/Express0023.lzma
2024-06-06T14:08:38.926Z,1717682918.926 [NAL9602](INFO): Packets left to send: 0
2024-06-06T14:08:39.420Z,1717682919.420 [NAL9602](INFO): Received command: configSet Radio_Surface.loadAtStartup 1 bool persist;restart app
2024-06-06T14:08:39.950Z,1717682919.950 [CommandExec](IMPORTANT): got command configSet Radio_Surface.loadAtStartup 1 bool persist
2024-06-06T14:08:39.950Z,1717682919.950 [CommandExec](IMPORTANT): configSet Radio_Surface.loadAtStartup requires a restart to take effect.
2024-06-06T14:08:39.953Z,1717682919.953 [CommandExec](IMPORTANT): got command restart application
2024-06-06T14:08:40.955Z,1717682920.955 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-06-06T14:08:40.956Z,1717682920.956 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-06T14:08:40.956Z,1717682920.956 [CommandExec](INFO): Uninitializing the command executive.
2024-06-06T14:08:40.956Z,1717682920.956 [CommandExec](INFO): Uninitializing the command scheduler.
2024-06-06T14:08:40.956Z,1717682920.956 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:41.011Z,1717682921.011 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-06-06T14:08:41.011Z,1717682921.011 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-06-06T14:08:41.011Z,1717682921.011 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:41.012Z,1717682921.012 [NavChartDb](INFO): Join timeout helper Thread ID is 1637
2024-06-06T14:08:41.175Z,1717682921.175 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-06T14:08:41.176Z,1717682921.176 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:41.191Z,1717682921.191 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-06-06T14:08:41.191Z,1717682921.191 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:41.192Z,1717682921.192 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1638
2024-06-06T14:08:41.340Z,1717682921.340 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-06T14:08:41.340Z,1717682921.340 [WetLabsBB2FL](INFO): Powering down
2024-06-06T14:08:41.340Z,1717682921.340 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:41.359Z,1717682921.359 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-06-06T14:08:41.359Z,1717682921.359 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:41.360Z,1717682921.360 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1639
2024-06-06T14:08:41.807Z,1717682921.807 [CTD_Seabird](INFO): Powering down
2024-06-06T14:08:41.819Z,1717682921.819 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-06T14:08:41.819Z,1717682921.819 [CTD_Seabird](INFO): Powering down
2024-06-06T14:08:41.834Z,1717682921.834 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:41.843Z,1717682921.843 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-06-06T14:08:41.844Z,1717682921.844 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:41.844Z,1717682921.844 [Onboard](INFO): Join timeout helper Thread ID is 1640
2024-06-06T14:08:43.783Z,1717682923.783 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-06T14:08:43.784Z,1717682923.784 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:43.795Z,1717682923.795 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-06-06T14:08:43.795Z,1717682923.795 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:43.796Z,1717682923.796 [DataOverHttps](INFO): Join timeout helper Thread ID is 1641
2024-06-06T14:08:43.927Z,1717682923.927 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-06T14:08:43.928Z,1717682923.928 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:43.948Z,1717682923.948 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-06-06T14:08:43.948Z,1717682923.948 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:43.949Z,1717682923.949 [BackseatComponent](INFO): Join timeout helper Thread ID is 1642
2024-06-06T14:08:44.047Z,1717682924.047 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-06T14:08:44.048Z,1717682924.048 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:44.060Z,1717682924.060 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-06-06T14:08:44.060Z,1717682924.060 [logger ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:44.061Z,1717682924.061 [logger](INFO): Join timeout helper Thread ID is 1643
2024-06-06T14:08:44.087Z,1717682924.087 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-06T14:08:44.088Z,1717682924.088 [logger ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:44.100Z,1717682924.100 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-06-06T14:08:44.100Z,1717682924.100 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:44.101Z,1717682924.101 [CommandLine](INFO): Join timeout helper Thread ID is 1644
2024-06-06T14:08:44.175Z,1717682924.175 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-06T14:08:44.176Z,1717682924.176 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:44.192Z,1717682924.192 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-06-06T14:08:44.192Z,1717682924.192 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:44.193Z,1717682924.193 [CommandExec](INFO): Join timeout helper Thread ID is 1645
2024-06-06T14:08:44.204Z,1717682924.204 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-06-06T14:08:44.204Z,1717682924.204 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:44.204Z,1717682924.204 [controlThread](INFO): Join timeout helper Thread ID is 1646
2024-06-06T14:08:46.341Z,1717682926.341 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-06T14:08:46.341Z,1717682926.341 [controlThread](DEBUG): Uninitializing ControlThread
2024-06-06T14:08:46.341Z,1717682926.341 [AHRS_M2](INFO): Powering down
2024-06-06T14:08:46.412Z,1717682926.412 [NAL9602](INFO): Powering down
2024-06-06T14:08:46.413Z,1717682926.413 [Sonardyne_Nano](INFO): Powering down
2024-06-06T14:08:46.533Z,1717682926.533 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-06-06T14:08:46.534Z,1717682926.534 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-06-06T14:08:46.534Z,1717682926.534 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-06-06T14:08:46.535Z,1717682926.535 [MissionManager](INFO): Uninitializing Mission Default
2024-06-06T14:08:46.535Z,1717682926.535 [Default] Stopped
2024-06-06T14:08:46.535Z,1717682926.535 [Default](DEBUG): Aggregate::uninitialize Default
2024-06-06T14:08:46.535Z,1717682926.535 [Default:B.GoToSurface] Stopped
2024-06-06T14:08:46.535Z,1717682926.535 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-06T14:08:46.535Z,1717682926.535 [Default:CheckIn] Stopped
2024-06-06T14:08:46.536Z,1717682926.536 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-06T14:08:46.536Z,1717682926.536 [Default:CheckIn:Read_Iridium] Stopped
2024-06-06T14:08:46.538Z,1717682926.538 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-06-06T14:08:46.539Z,1717682926.539 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-06-06T14:08:46.539Z,1717682926.539 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-06-06T14:08:46.540Z,1717682926.540 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-06-06T14:08:46.540Z,1717682926.540 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-06-06T14:08:46.540Z,1717682926.540 [BuoyancyServo](INFO): Powering down
2024-06-06T14:08:46.551Z,1717682926.551 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-06T14:08:46.552Z,1717682926.552 [ElevatorServo](INFO): Powering down
2024-06-06T14:08:46.552Z,1717682926.552 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-06-06T14:08:46.553Z,1717682926.553 [MassServo](INFO): Powering down
2024-06-06T14:08:46.553Z,1717682926.553 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-06-06T14:08:46.553Z,1717682926.553 [RudderServo](INFO): Powering down
2024-06-06T14:08:46.554Z,1717682926.554 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-06-06T14:08:46.554Z,1717682926.554 [ThrusterHE](INFO): Powering down
2024-06-06T14:08:46.555Z,1717682926.555 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-06-06T14:08:46.556Z,1717682926.556 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-06-06T14:08:46.556Z,1717682926.556 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-06-06T14:08:46.556Z,1717682926.556 [CBIT](DEBUG): Powering off loads.
2024-06-06T14:08:46.567Z,1717682926.567 [CBIT](DEBUG): Disabling WDT.
2024-06-06T14:08:46.579Z,1717682926.579 [CBIT](DEBUG): Opening all GF detection circuits.
2024-06-06T14:08:46.580Z,1717682926.580 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:46.635Z,1717682926.635 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:46.642Z,1717682926.642 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:46.687Z,1717682926.687 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:46.739Z,1717682926.739 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:46.746Z,1717682926.746 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:46.768Z,1717682926.768 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-06T14:08:46.836Z,1717682926.836 [logger ThreadHandler](INFO): Thread cancelled.