2023-04-03T15:20:50.525Z,1680535250.525 [Supervisor](DEBUG): Initializing supervisor.
2023-04-03T15:20:50.530Z,1680535250.530 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-04-03T15:20:50.531Z,1680535250.531 [SyncHandler](INFO): Protected caller Thread ID is 4587
2023-04-03T15:20:50.531Z,1680535250.531 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-04-03T15:20:50.532Z,1680535250.532 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-04-03T15:20:50.533Z,1680535250.533 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4588
2023-04-03T15:20:50.537Z,1680535250.537 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-04-03T15:20:50.554Z,1680535250.554 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-04-03T15:20:50.555Z,1680535250.555 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-04-03T15:20:50.556Z,1680535250.556 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4589
2023-04-03T15:20:50.560Z,1680535250.560 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-04-03T15:20:50.561Z,1680535250.561 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-04-03T15:20:50.561Z,1680535250.561 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4590
2023-04-03T15:20:50.563Z,1680535250.563 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-04-03T15:20:50.564Z,1680535250.564 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-04-03T15:20:50.565Z,1680535250.565 [logger ThreadHandler](INFO): Protected caller Thread ID is 4591
2023-04-03T15:20:50.568Z,1680535250.568 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-04-03T15:20:50.569Z,1680535250.569 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-04-03T15:20:50.570Z,1680535250.570 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-04-03T15:20:50.826Z,1680535250.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-04-03T15:20:50.827Z,1680535250.827 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-04-03T15:20:50.904Z,1680535250.904 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-04-03T15:20:51.354Z,1680535251.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-04-03T15:20:51.355Z,1680535251.355 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-04-03T15:20:51.660Z,1680535251.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-04-03T15:20:51.660Z,1680535251.660 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-04-03T15:20:51.753Z,1680535251.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-04-03T15:20:51.754Z,1680535251.754 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-04-03T15:20:52.127Z,1680535252.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-04-03T15:20:52.127Z,1680535252.127 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-04-03T15:20:52.325Z,1680535252.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-04-03T15:20:52.326Z,1680535252.326 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-04-03T15:20:52.810Z,1680535252.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-04-03T15:20:52.811Z,1680535252.811 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-04-03T15:20:52.943Z,1680535252.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-04-03T15:20:52.944Z,1680535252.944 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-04-03T15:20:53.020Z,1680535253.020 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-04-03T15:20:53.731Z,1680535253.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-04-03T15:20:53.732Z,1680535253.732 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-04-03T15:20:54.083Z,1680535254.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-04-03T15:20:54.083Z,1680535254.083 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-04-03T15:20:54.259Z,1680535254.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-04-03T15:20:54.259Z,1680535254.259 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-04-03T15:20:54.366Z,1680535254.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-04-03T15:20:54.366Z,1680535254.366 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-04-03T15:20:54.579Z,1680535254.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-04-03T15:20:54.579Z,1680535254.579 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-04-03T15:20:54.806Z,1680535254.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-04-03T15:20:54.808Z,1680535254.808 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-04-03T15:20:54.809Z,1680535254.809 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-04-03T15:20:54.896Z,1680535254.896 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-04-03T15:20:54.978Z,1680535254.978 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-04-03T15:20:55.074Z,1680535255.074 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-04-03T15:20:55.154Z,1680535255.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-04-03T15:20:55.258Z,1680535255.258 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-04-03T15:20:55.503Z,1680535255.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-04-03T15:20:55.817Z,1680535255.817 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-04-03T15:20:56.018Z,1680535256.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-04-03T15:20:56.215Z,1680535256.215 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-04-03T15:20:56.546Z,1680535256.546 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-04-03T15:20:56.649Z,1680535256.649 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-04-03T15:20:56.910Z,1680535256.910 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-04-03T15:20:56.911Z,1680535256.911 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-04-03T15:20:56.929Z,1680535256.929 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-04-03T15:20:56.982Z,1680535256.982 [VerticalControl](DEBUG): Construct VerticalControl.
2023-04-03T15:20:57.041Z,1680535257.041 [VerticalControl] Loaded
2023-04-03T15:20:57.041Z,1680535257.041 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-04-03T15:20:57.044Z,1680535257.044 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-04-03T15:20:57.085Z,1680535257.085 [HorizontalControl] Loaded
2023-04-03T15:20:57.085Z,1680535257.085 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-04-03T15:20:57.087Z,1680535257.087 [SpeedControl](DEBUG): Construct SpeedControl.
2023-04-03T15:20:57.090Z,1680535257.090 [SpeedControl] Loaded
2023-04-03T15:20:57.090Z,1680535257.090 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-04-03T15:20:57.093Z,1680535257.093 [LoopControl](DEBUG): Construct LoopControl.
2023-04-03T15:20:57.094Z,1680535257.094 [LoopControl] Loaded
2023-04-03T15:20:57.094Z,1680535257.094 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-04-03T15:20:57.094Z,1680535257.094 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-04-03T15:20:57.095Z,1680535257.095 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-04-03T15:20:57.105Z,1680535257.105 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-04-03T15:20:57.106Z,1680535257.106 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-04-03T15:20:57.257Z,1680535257.257 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-04-03T15:20:57.258Z,1680535257.258 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-04-03T15:20:57.389Z,1680535257.389 [BuoyancyServo] Loaded
2023-04-03T15:20:57.390Z,1680535257.390 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-04-03T15:20:57.409Z,1680535257.409 [ElevatorServo] Loaded
2023-04-03T15:20:57.410Z,1680535257.410 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-04-03T15:20:57.429Z,1680535257.429 [MassServo] Loaded
2023-04-03T15:20:57.429Z,1680535257.429 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-04-03T15:20:57.447Z,1680535257.447 [RudderServo] Loaded
2023-04-03T15:20:57.448Z,1680535257.448 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-04-03T15:20:57.465Z,1680535257.465 [ThrusterServo] Loaded
2023-04-03T15:20:57.465Z,1680535257.465 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-04-03T15:20:57.466Z,1680535257.466 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-04-03T15:20:57.466Z,1680535257.466 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-04-03T15:20:57.579Z,1680535257.579 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-04-03T15:20:57.579Z,1680535257.579 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-04-03T15:20:57.593Z,1680535257.593 [NavChart] Loaded
2023-04-03T15:20:57.593Z,1680535257.593 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-04-03T15:20:57.599Z,1680535257.599 [UniversalFixResidualReporter] Loaded
2023-04-03T15:20:57.599Z,1680535257.599 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-04-03T15:20:57.600Z,1680535257.600 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-04-03T15:20:57.600Z,1680535257.600 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-04-03T15:20:57.658Z,1680535257.658 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-04-03T15:20:57.659Z,1680535257.659 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-04-03T15:20:58.170Z,1680535258.170 [AHRS_M2] Loaded
2023-04-03T15:20:58.170Z,1680535258.170 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-04-03T15:20:58.992Z,1680535258.992 [BPC1] Loaded
2023-04-03T15:20:58.993Z,1680535258.993 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-04-03T15:20:59.065Z,1680535259.065 [DataOverHttps] Loaded
2023-04-03T15:20:59.065Z,1680535259.065 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-04-03T15:20:59.066Z,1680535259.066 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-04-03T15:20:59.067Z,1680535259.067 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4677
2023-04-03T15:20:59.087Z,1680535259.087 [Depth_Keller] Loaded
2023-04-03T15:20:59.088Z,1680535259.088 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-04-03T15:20:59.093Z,1680535259.093 [DropWeight] Loaded
2023-04-03T15:20:59.093Z,1680535259.093 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-04-03T15:20:59.154Z,1680535259.154 [NAL9602] Loaded
2023-04-03T15:20:59.155Z,1680535259.155 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-04-03T15:20:59.184Z,1680535259.184 [Onboard] Loaded
2023-04-03T15:20:59.184Z,1680535259.184 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-04-03T15:20:59.185Z,1680535259.185 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2023-04-03T15:20:59.186Z,1680535259.186 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4678
2023-04-03T15:20:59.199Z,1680535259.199 [Power24vConverter] Loaded
2023-04-03T15:20:59.199Z,1680535259.199 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-04-03T15:20:59.212Z,1680535259.212 [Radio_Surface] Loaded
2023-04-03T15:20:59.213Z,1680535259.213 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-04-03T15:20:59.214Z,1680535259.214 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2023-04-03T15:20:59.214Z,1680535259.214 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4679
2023-04-03T15:20:59.215Z,1680535259.215 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-04-03T15:20:59.216Z,1680535259.216 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-04-03T15:20:59.270Z,1680535259.270 [DepthRateCalculator] Loaded
2023-04-03T15:20:59.270Z,1680535259.270 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-04-03T15:20:59.275Z,1680535259.275 [PitchRateCalculator] Loaded
2023-04-03T15:20:59.275Z,1680535259.275 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-04-03T15:20:59.284Z,1680535259.284 [SpeedCalculator] Loaded
2023-04-03T15:20:59.284Z,1680535259.284 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-04-03T15:20:59.300Z,1680535259.300 [VerticalHomogeneityIndexCalculator] Loaded
2023-04-03T15:20:59.300Z,1680535259.300 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2023-04-03T15:20:59.304Z,1680535259.304 [YawRateCalculator] Loaded
2023-04-03T15:20:59.304Z,1680535259.304 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-04-03T15:20:59.323Z,1680535259.323 [ElevatorOffsetCalculator] Loaded
2023-04-03T15:20:59.323Z,1680535259.323 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-04-03T15:20:59.339Z,1680535259.339 [ShortestDistanceToPolygonSidesCalculator] Loaded
2023-04-03T15:20:59.339Z,1680535259.339 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2023-04-03T15:20:59.340Z,1680535259.340 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-04-03T15:20:59.340Z,1680535259.340 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-04-03T15:20:59.473Z,1680535259.473 [CANONSampler] Loaded
2023-04-03T15:20:59.473Z,1680535259.473 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-04-03T15:20:59.531Z,1680535259.531 [CTD_Seabird] Loaded
2023-04-03T15:20:59.532Z,1680535259.532 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-04-03T15:20:59.534Z,1680535259.534 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2023-04-03T15:20:59.534Z,1680535259.534 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4680
2023-04-03T15:20:59.554Z,1680535259.554 [PAR_Licor] Loaded
2023-04-03T15:20:59.554Z,1680535259.554 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-04-03T15:20:59.585Z,1680535259.585 [WetLabsBB2FL] Loaded
2023-04-03T15:20:59.585Z,1680535259.585 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-04-03T15:20:59.586Z,1680535259.586 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2023-04-03T15:20:59.587Z,1680535259.587 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4681
2023-04-03T15:20:59.587Z,1680535259.587 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-04-03T15:20:59.588Z,1680535259.588 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-04-03T15:20:59.616Z,1680535259.616 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-04-03T15:20:59.616Z,1680535259.616 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-04-03T15:20:59.930Z,1680535259.930 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-04-03T15:20:59.930Z,1680535259.930 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-04-03T15:21:00.070Z,1680535260.070 [SBIT](DEBUG): Construct Startup Built In Test.
2023-04-03T15:21:00.079Z,1680535260.079 [SBIT] Loaded
2023-04-03T15:21:00.079Z,1680535260.079 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-04-03T15:21:00.082Z,1680535260.082 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-04-03T15:21:00.095Z,1680535260.095 [IBIT] Loaded
2023-04-03T15:21:00.096Z,1680535260.096 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-04-03T15:21:00.101Z,1680535260.101 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-04-03T15:21:00.204Z,1680535260.204 [CBIT] Loaded
2023-04-03T15:21:00.204Z,1680535260.204 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-04-03T15:21:00.205Z,1680535260.205 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-04-03T15:21:00.205Z,1680535260.205 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-04-03T15:21:00.307Z,1680535260.307 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-04-03T15:21:00.424Z,1680535260.424 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-04-03T15:21:00.427Z,1680535260.427 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-04-03T15:21:00.476Z,1680535260.476 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-04-03T15:21:00.477Z,1680535260.477 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B7E4E0
2023-04-03T15:21:00.477Z,1680535260.477 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4682
2023-04-03T15:21:00.482Z,1680535260.482 [Supervisor](INFO): Main Thread ID is 4586
2023-04-03T15:21:00.482Z,1680535260.482 [Supervisor](DEBUG): Running supervisor.
2023-04-03T15:21:00.483Z,1680535260.483 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4683
2023-04-03T15:21:00.484Z,1680535260.484 [CommandExec](INFO): Initializing the command executive.
2023-04-03T15:21:00.510Z,1680535260.510 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4684
2023-04-03T15:21:00.521Z,1680535260.521 [controlThread ThreadHandler](INFO): Handler Thread ID is 4685
2023-04-03T15:21:00.521Z,1680535260.521 [controlThread](DEBUG): Initializing ControlThread
2023-04-03T15:21:00.522Z,1680535260.522 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-04-03T15:21:00.523Z,1680535260.523 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-04-03T15:21:00.524Z,1680535260.524 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-04-03T15:21:00.525Z,1680535260.525 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-04-03T15:21:00.527Z,1680535260.527 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-04-03T15:21:00.527Z,1680535260.527 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-04-03T15:21:00.534Z,1680535260.534 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-04-03T15:21:00.534Z,1680535260.534 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-04-03T15:21:00.535Z,1680535260.535 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-04-03T15:21:00.535Z,1680535260.535 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2023-04-03T15:21:00.536Z,1680535260.536 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-04-03T15:21:00.536Z,1680535260.536 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-04-03T15:21:00.537Z,1680535260.537 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2023-04-03T15:21:00.541Z,1680535260.541 [SBIT](INFO): Initialize SBIT Component.
2023-04-03T15:21:00.541Z,1680535260.541 [SBIT](IMPORTANT): git: 2023-03-31_A
2023-04-03T15:21:00.541Z,1680535260.541 [SBIT](INFO): git hash: af64d0505c79dd7b718d6df4bbafdde730718da9
2023-04-03T15:21:00.541Z,1680535260.541 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-04-03T15:21:00.543Z,1680535260.543 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-04-03T15:21:00.544Z,1680535260.544 [SBIT](INFO): Beginning SBIT in 76.000000 seconds.
2023-04-03T15:21:00.545Z,1680535260.545 [IBIT](INFO): Initialize IBIT Component.
2023-04-03T15:21:00.546Z,1680535260.546 [CBIT](DEBUG): Initialize CBIT Component.
2023-04-03T15:21:00.547Z,1680535260.547 [logger ThreadHandler](INFO): Handler Thread ID is 4686
2023-04-03T15:21:00.547Z,1680535260.547 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4687
2023-04-03T15:21:00.626Z,1680535260.626 [CBIT](DEBUG): Initialized mux pins.
2023-04-03T15:21:00.627Z,1680535260.627 [CBIT](DEBUG): Initializing the watchdog timer.
2023-04-03T15:21:00.627Z,1680535260.627 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-04-03T15:21:00.652Z,1680535260.652 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-04-03T15:21:00.653Z,1680535260.653 [CBIT](DEBUG): Initializing heartbeat.
2023-04-03T15:21:00.653Z,1680535260.653 [Onboard ThreadHandler](INFO): Handler Thread ID is 4688
2023-04-03T15:21:00.685Z,1680535260.685 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4689
2023-04-03T15:21:00.693Z,1680535260.693 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4690
2023-04-03T15:21:00.694Z,1680535260.694 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-04-03T15:21:00.698Z,1680535260.698 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4692
2023-04-03T15:21:00.701Z,1680535260.701 [WetLabsBB2FL](INFO): Powering up
2023-04-03T15:21:00.709Z,1680535260.709 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4694
2023-04-03T15:21:00.712Z,1680535260.712 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-04-03T15:21:00.712Z,1680535260.712 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-04-03T15:21:00.713Z,1680535260.713 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-04-03T15:21:00.713Z,1680535260.713 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-04-03T15:21:00.713Z,1680535260.713 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-04-03T15:21:00.713Z,1680535260.713 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-04-03T15:21:00.713Z,1680535260.713 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-04-03T15:21:00.713Z,1680535260.713 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-04-03T15:21:00.724Z,1680535260.724 [CBIT](DEBUG): Deactivating GF circuits.
2023-04-03T15:21:00.724Z,1680535260.724 [CBIT](DEBUG): Deactivating emergency mode.
2023-04-03T15:21:00.764Z,1680535260.764 [CBIT](DEBUG): Backplane powered.
2023-04-03T15:21:00.765Z,1680535260.765 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-04-03T15:21:00.779Z,1680535260.779 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-03T15:21:00.807Z,1680535260.807 [MissionManager](DEBUG):
2023-04-03T15:21:00.807Z,1680535260.807 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-04-03T15:21:00.894Z,1680535260.894 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-04-03T15:21:00.895Z,1680535260.895 [Default:A.Wait](DEBUG): Construct Wait.
2023-04-03T15:21:00.897Z,1680535260.897 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-03T15:21:00.941Z,1680535260.941 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-04-03T15:21:00.943Z,1680535260.943 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-04-03T15:21:00.965Z,1680535260.965 [Default:E.Execute](DEBUG): Construct Execute.
2023-04-03T15:21:00.969Z,1680535260.969 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2023-04-03T15:21:00.982Z,1680535260.982 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T15:21:01.009Z,1680535261.009 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-04-03T15:21:01.080Z,1680535261.080 [Power24vConverter](INFO): Powering up.
2023-04-03T15:21:01.081Z,1680535261.081 [CANONSampler](INFO): Powering down
2023-04-03T15:21:01.088Z,1680535261.088 [Radio_Surface](INFO): Powering up
2023-04-03T15:21:01.153Z,1680535261.153 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-04-03T15:21:01.160Z,1680535261.160 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-04-03T15:21:01.162Z,1680535261.162 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-04-03T15:21:01.168Z,1680535261.168 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-04-03T15:21:01.169Z,1680535261.169 [MassServo](DEBUG): Initializing EZServoServo.
2023-04-03T15:21:01.176Z,1680535261.176 [MassServo](DEBUG): Initializing MassServo.
2023-04-03T15:21:01.177Z,1680535261.177 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-03T15:21:01.188Z,1680535261.188 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-03T15:21:01.189Z,1680535261.189 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-04-03T15:21:01.196Z,1680535261.196 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-04-03T15:21:03.312Z,1680535263.312 [WetLabsBB2FL](INFO): Powering down
2023-04-03T15:21:07.748Z,1680535267.748 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-04-03T15:21:14.955Z,1680535274.955 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004669
2023-04-03T15:21:29.274Z,1680535289.274 [NAL9602](INFO): Powering up NAL9602
2023-04-03T15:21:40.182Z,1680535300.182 [NAL9602](INFO): NAL9602 initialized
2023-04-03T15:22:17.033Z,1680535337.033 [SBIT](IMPORTANT): Beginning Startup BIT
2023-04-03T15:22:17.046Z,1680535337.046 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-03T15:22:27.891Z,1680535347.891 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-04-03T15:22:28.280Z,1680535348.280 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-03T15:22:39.452Z,1680535359.452 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-04-03T15:22:39.673Z,1680535359.673 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-03T15:22:50.554Z,1680535370.554 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-04-03T15:22:50.555Z,1680535370.555 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-04-03T15:22:54.174Z,1680535374.174 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-04-03T15:23:11.099Z,1680535391.099 [SBIT](IMPORTANT): SBIT PASSED
2023-04-03T15:23:11.100Z,1680535391.100 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-04-03T15:23:11.101Z,1680535391.101 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;
2023-04-03T15:23:11.101Z,1680535391.101 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count;
2023-04-03T15:23:11.101Z,1680535391.101 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;
2023-04-03T15:23:11.101Z,1680535391.101 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;
2023-04-03T15:23:11.101Z,1680535391.101 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2023-04-03T15:23:11.101Z,1680535391.101 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.100000 celsius;
2023-04-03T15:23:11.102Z,1680535391.102 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1=36.81 degree;
2023-04-03T15:23:11.102Z,1680535391.102 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2=36.81 degree;
2023-04-03T15:23:11.102Z,1680535391.102 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3=36.78 degree;
2023-04-03T15:23:11.102Z,1680535391.102 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4=36.78 degree;
2023-04-03T15:23:11.102Z,1680535391.102 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;
2023-04-03T15:23:11.102Z,1680535391.102 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1=-121.87 degree;
2023-04-03T15:23:11.102Z,1680535391.102 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2=-121.83 degree;
2023-04-03T15:23:11.102Z,1680535391.102 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3=-121.83 degree;
2023-04-03T15:23:11.102Z,1680535391.102 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4=-121.87 degree;
2023-04-03T15:23:11.103Z,1680535391.103 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=336.456139 cubic_centimeter;
2023-04-03T15:23:11.103Z,1680535391.103 [SBIT](IMPORTANT): VerticalControl.massDefault=-14.157167 millimeter;
2023-04-03T15:23:11.103Z,1680535391.103 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=15 meter;
2023-04-03T15:23:11.103Z,1680535391.103 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=20 meter;
2023-04-03T15:23:11.103Z,1680535391.103 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;
2023-04-03T15:23:11.523Z,1680535391.523 [MissionManager](IMPORTANT): Started mission Startup
2023-04-03T15:23:11.523Z,1680535391.523 [Startup] Running Loop=1
2023-04-03T15:23:11.523Z,1680535391.523 [Startup](DEBUG): Aggregate::initialize Startup
2023-04-03T15:23:11.523Z,1680535391.523 [Startup:A.GoToSurface] Running Loop=1
2023-04-03T15:23:11.523Z,1680535391.523 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-03T15:23:11.540Z,1680535391.540 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-03T15:23:11.541Z,1680535391.541 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-03T15:23:11.541Z,1680535391.541 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-03T15:23:11.541Z,1680535391.541 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-03T15:23:11.542Z,1680535391.542 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-03T15:23:11.543Z,1680535391.543 [Startup:StartupSatComms] Running Loop=1
2023-04-03T15:23:11.543Z,1680535391.543 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-04-03T15:23:11.543Z,1680535391.543 [Startup:StartupSatComms:A] Running Loop=1
2023-04-03T15:23:11.909Z,1680535391.909 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-04-03T15:24:00.792Z,1680535440.792 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-04-03T15:24:00.792Z,1680535440.792 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-03T15:24:00.877Z,1680535440.877 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-03T15:24:01.195Z,1680535441.195 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-03T15:24:01.195Z,1680535441.195 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-04-03T15:24:08.933Z,1680535448.933 [CommandExec](IMPORTANT): got command failComponent
2023-04-03T15:24:08.933Z,1680535448.933 [CommandExec](IMPORTANT): Failed components:
2023-04-03T15:24:08.933Z,1680535448.933 [CommandExec](IMPORTANT): No failed Components.
2023-04-03T15:24:11.807Z,1680535451.807 [Startup:StartupSatComms:A](INFO): Timed out from 2023-04-03T15:23:11.5Z
2023-04-03T15:24:11.807Z,1680535451.807 [Startup:StartupSatComms:A] Stopped
2023-04-03T15:24:11.807Z,1680535451.807 [Startup:StartupSatComms:B] Running Loop=1
2023-04-03T15:24:12.171Z,1680535452.171 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-04-03T15:24:13.057Z,1680535453.057 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230330T190033/Courier0049.lzma
2023-04-03T15:24:13.915Z,1680535453.915 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0049.lzma.bak
2023-04-03T15:24:13.915Z,1680535453.915 [DataOverHttps](INFO): SBD MOMSN=18010160
2023-04-03T15:24:29.405Z,1680535469.405 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230330T203528/Courier0000.lzma
2023-04-03T15:24:30.407Z,1680535470.407 [DataOverHttps](INFO): Moved sent file to Logs/20230330T203528/Courier0000.lzma.bak
2023-04-03T15:24:30.407Z,1680535470.407 [DataOverHttps](INFO): SBD MOMSN=18010162
2023-04-03T15:24:36.789Z,1680535476.789 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2023-04-03T15:24:36.791Z,1680535476.791 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2023-04-03T15:24:45.800Z,1680535485.800 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230403T152050/Courier0000.lzma
2023-04-03T15:24:46.802Z,1680535486.802 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0000.lzma.bak
2023-04-03T15:24:46.802Z,1680535486.802 [DataOverHttps](INFO): SBD MOMSN=18010168
2023-04-03T15:25:02.345Z,1680535502.345 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20230330T190033/Express0050.lzma
2023-04-03T15:25:03.346Z,1680535503.346 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0050.lzma.bak
2023-04-03T15:25:03.347Z,1680535503.347 [DataOverHttps](INFO): SBD MOMSN=18010174
2023-04-03T15:25:11.979Z,1680535511.979 [Startup:StartupSatComms:B](INFO): Timed out from 2023-04-03T15:24:11.8Z
2023-04-03T15:25:11.979Z,1680535511.979 [Startup:StartupSatComms:B] Stopped
2023-04-03T15:25:11.979Z,1680535511.979 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-04-03T15:25:11.979Z,1680535511.979 [Startup:StartupSatComms] Stopped
2023-04-03T15:25:11.979Z,1680535511.979 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-04-03T15:25:11.980Z,1680535511.980 [Startup](INFO): Completed Startup
2023-04-03T15:25:11.980Z,1680535511.980 [MissionManager](INFO): Startup is completed.
2023-04-03T15:25:11.980Z,1680535511.980 [MissionManager](INFO): Uninitializing Mission Startup
2023-04-03T15:25:11.980Z,1680535511.980 [Startup] Stopped
2023-04-03T15:25:11.980Z,1680535511.980 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-04-03T15:25:11.980Z,1680535511.980 [Startup:A.GoToSurface] Stopped
2023-04-03T15:25:11.981Z,1680535511.981 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-03T15:25:12.380Z,1680535512.380 [MissionManager](IMPORTANT): Started mission Default
2023-04-03T15:25:12.380Z,1680535512.380 [Default] Running Loop=1
2023-04-03T15:25:12.380Z,1680535512.380 [Default](DEBUG): Aggregate::initialize Default
2023-04-03T15:25:12.380Z,1680535512.380 [Default:B.GoToSurface] Running Loop=1
2023-04-03T15:25:12.380Z,1680535512.380 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-03T15:25:12.381Z,1680535512.381 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-03T15:25:12.381Z,1680535512.381 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-03T15:25:12.381Z,1680535512.381 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-03T15:25:12.382Z,1680535512.382 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-03T15:25:12.382Z,1680535512.382 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-03T15:25:12.382Z,1680535512.382 [Default:A.Wait] Running Loop=1
2023-04-03T15:25:12.382Z,1680535512.382 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-04-03T15:25:18.697Z,1680535518.697 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20230330T203528/Express0001.lzma
2023-04-03T15:25:19.698Z,1680535519.698 [DataOverHttps](INFO): Moved sent file to Logs/20230330T203528/Express0001.lzma.bak
2023-04-03T15:25:19.698Z,1680535519.698 [DataOverHttps](INFO): SBD MOMSN=18010182
2023-04-03T15:25:25.695Z,1680535525.695 [Default:A.Wait](INFO): Done Waiting.
2023-04-03T15:25:25.695Z,1680535525.695 [Default:A.Wait] Stopped
2023-04-03T15:25:25.695Z,1680535525.695 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T15:25:26.103Z,1680535526.103 [Default:CheckIn] Running Loop=1
2023-04-03T15:25:26.103Z,1680535526.103 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T15:25:26.103Z,1680535526.103 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T15:25:26.495Z,1680535526.495 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-04-03T15:26:43.654Z,1680535603.654 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-03T15:27:01.841Z,1680535621.841 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-04-03T15:27:01.841Z,1680535621.841 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-03T15:27:01.851Z,1680535621.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-03T15:27:02.256Z,1680535622.256 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-03T15:27:02.256Z,1680535622.256 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-04-03T15:29:09.949Z,1680535749.949 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-04-03T15:30:02.835Z,1680535802.835 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-04-03T15:30:02.835Z,1680535802.835 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-03T15:30:02.846Z,1680535802.846 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-03T15:30:03.264Z,1680535803.264 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-03T15:30:03.264Z,1680535803.264 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-04-03T15:30:26.280Z,1680535826.280 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-03T15:25:26.1Z
2023-04-03T15:30:26.280Z,1680535826.280 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T15:30:26.280Z,1680535826.280 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T15:30:26.688Z,1680535826.688 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-04-03T15:30:36.256Z,1680535836.256 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230403T152050/Courier0004.lzma
2023-04-03T15:30:37.262Z,1680535837.262 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0004.lzma.bak
2023-04-03T15:30:37.262Z,1680535837.262 [DataOverHttps](INFO): SBD MOMSN=18010655
2023-04-03T15:30:57.105Z,1680535857.105 [DataOverHttps](INFO): Sending 951 bytes from file Logs/20230403T152050/Express0001.lzma
2023-04-03T15:30:58.106Z,1680535858.106 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0001.lzma.bak
2023-04-03T15:30:58.106Z,1680535858.106 [DataOverHttps](INFO): SBD MOMSN=18010657
2023-04-03T15:31:01.577Z,1680535861.577 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-04-03T15:31:01.923Z,1680535861.923 [Power24vConverter](INFO): Powering down.
2023-04-03T15:31:12.540Z,1680535872.540 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230403T152050/Express0005.lzma
2023-04-03T15:31:13.542Z,1680535873.542 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0005.lzma.bak
2023-04-03T15:31:13.542Z,1680535873.542 [DataOverHttps](INFO): SBD MOMSN=18010695
2023-04-03T15:31:14.859Z,1680535874.859 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T15:31:14.859Z,1680535874.859 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T15:31:14.859Z,1680535874.859 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T15:31:41.506Z,1680535901.506 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-03T15:31:41.506Z,1680535901.506 [NAL9602] Data Fault, FailCount= 1
2023-04-03T15:31:41.506Z,1680535901.506 [NAL9602](ERROR): Data Fault
2023-04-03T15:31:41.556Z,1680535901.556 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-03T15:31:41.910Z,1680535901.910 [NAL9602](INFO): Powering down
2023-04-03T15:31:42.740Z,1680535902.740 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-03T15:31:42.740Z,1680535902.740 [NAL9602] No Fault, FailCount= 1
2023-04-03T15:32:01.356Z,1680535921.356 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-04-03T15:32:12.210Z,1680535932.210 [NAL9602](INFO): Powering up NAL9602
2023-04-03T15:32:23.118Z,1680535943.118 [NAL9602](INFO): NAL9602 initialized
2023-04-03T15:33:03.980Z,1680535983.980 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-04-03T15:33:03.980Z,1680535983.980 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-03T15:33:03.991Z,1680535983.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-03T15:33:04.371Z,1680535984.371 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-03T15:33:04.371Z,1680535984.371 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-04-03T15:35:05.130Z,1680536105.130 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-04-03T15:36:04.946Z,1680536164.946 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-04-03T15:36:04.946Z,1680536164.946 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-03T15:36:04.957Z,1680536164.957 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-03T15:36:05.367Z,1680536165.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-03T15:36:05.368Z,1680536165.368 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-04-03T15:36:15.439Z,1680536175.439 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T15:36:15.439Z,1680536175.439 [Default:CheckIn:C.Wait] Stopped
2023-04-03T15:36:15.439Z,1680536175.439 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T15:36:15.440Z,1680536175.440 [Default:CheckIn:D] Running Loop=1
2023-04-03T15:36:15.852Z,1680536175.852 [Default:CheckIn:D] Stopped
2023-04-03T15:36:15.852Z,1680536175.852 [Default:CheckIn:E] Running Loop=1
2023-04-03T15:36:16.249Z,1680536176.249 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.057860 min
2023-04-03T15:36:16.249Z,1680536176.249 [Default:CheckIn:E] Stopped
2023-04-03T15:36:16.249Z,1680536176.249 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T15:36:16.250Z,1680536176.250 [Default:CheckIn] Stopped
2023-04-03T15:36:16.250Z,1680536176.250 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T15:36:16.250Z,1680536176.250 [Default:CheckIn](INFO): Running loop #2
2023-04-03T15:36:16.250Z,1680536176.250 [Default:CheckIn] Running Loop=2
2023-04-03T15:36:16.250Z,1680536176.250 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T15:36:16.250Z,1680536176.250 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T15:36:16.997Z,1680536176.997 [CommandExec](IMPORTANT): got command gfscan
2023-04-03T15:36:17.108Z,1680536177.108 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-03T15:36:19.948Z,1680536179.948 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-04-03T15:36:27.968Z,1680536187.968 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001883
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.001944
CHAN A3 (5V): -0.001636
CHAN B0 (3.3V): 0.000296
CHAN B1 (3.15aV): -0.000408
CHAN B2 (3.15bV): -0.000307
CHAN B3 (GND): -0.000147
OPEN: -0.000238
Full Scale: +/- 1 mA
2023-04-03T15:39:05.955Z,1680536345.955 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-04-03T15:39:05.956Z,1680536345.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-03T15:39:05.966Z,1680536345.966 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-03T15:39:06.380Z,1680536346.380 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-03T15:39:06.380Z,1680536346.380 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-04-03T15:41:16.490Z,1680536476.490 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-03T15:36:16.3Z
2023-04-03T15:41:16.491Z,1680536476.491 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T15:41:16.491Z,1680536476.491 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T15:41:23.479Z,1680536483.479 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230403T152050/Courier0007.lzma
2023-04-03T15:41:24.482Z,1680536484.482 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0007.lzma.bak
2023-04-03T15:41:24.482Z,1680536484.482 [DataOverHttps](INFO): SBD MOMSN=18011072
2023-04-03T15:41:39.828Z,1680536499.828 [DataOverHttps](INFO): Sending 384 bytes from file Logs/20230403T152050/Express0008.lzma
2023-04-03T15:41:40.830Z,1680536500.830 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0008.lzma.bak
2023-04-03T15:41:40.830Z,1680536500.830 [DataOverHttps](INFO): SBD MOMSN=18011076
2023-04-03T15:41:41.955Z,1680536501.955 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T15:41:41.955Z,1680536501.955 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T15:41:41.955Z,1680536501.955 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T15:42:06.999Z,1680536526.999 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-04-03T15:42:06.999Z,1680536526.999 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-03T15:42:07.010Z,1680536527.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-03T15:42:07.424Z,1680536527.424 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-03T15:42:07.424Z,1680536527.424 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-04-03T15:42:24.770Z,1680536544.770 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-03T15:42:24.770Z,1680536544.770 [NAL9602] Data Fault, FailCount= 2
2023-04-03T15:42:24.770Z,1680536544.770 [NAL9602](ERROR): Data Fault
2023-04-03T15:42:24.828Z,1680536544.828 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-03T15:42:25.166Z,1680536545.166 [NAL9602](INFO): Powering down
2023-04-03T15:42:25.987Z,1680536545.987 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-03T15:42:25.988Z,1680536545.988 [NAL9602] No Fault, FailCount= 2
2023-04-03T15:42:55.470Z,1680536575.470 [NAL9602](INFO): Powering up NAL9602
2023-04-03T15:43:06.398Z,1680536586.398 [NAL9602](INFO): NAL9602 initialized
2023-04-03T15:45:08.091Z,1680536708.091 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-04-03T15:45:08.091Z,1680536708.091 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-03T15:45:08.101Z,1680536708.101 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-03T15:45:08.508Z,1680536708.508 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-03T15:45:08.508Z,1680536708.508 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-04-03T15:46:25.680Z,1680536785.680 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154623.00,A,3648.18287,N,12147.28338,W,0.253,349.87,030423,,,A*76
2023-04-03T15:46:25.697Z,1680536785.697 [NAL9602](INFO): GPS fix at 20230403T154623: (36.803048, -121.788056)
2023-04-03T15:46:42.666Z,1680536802.666 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T15:46:42.666Z,1680536802.666 [Default:CheckIn:C.Wait] Stopped
2023-04-03T15:46:42.667Z,1680536802.667 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T15:46:42.667Z,1680536802.667 [Default:CheckIn:D] Running Loop=1
2023-04-03T15:46:43.064Z,1680536803.064 [Default:CheckIn:D] Stopped
2023-04-03T15:46:43.080Z,1680536803.080 [Default:CheckIn:E] Running Loop=1
2023-04-03T15:46:43.473Z,1680536803.473 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.511397 min
2023-04-03T15:46:43.473Z,1680536803.473 [Default:CheckIn:E] Stopped
2023-04-03T15:46:43.473Z,1680536803.473 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T15:46:43.473Z,1680536803.473 [Default:CheckIn] Stopped
2023-04-03T15:46:43.473Z,1680536803.473 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T15:46:43.473Z,1680536803.473 [Default:CheckIn](INFO): Running loop #3
2023-04-03T15:46:43.473Z,1680536803.473 [Default:CheckIn] Running Loop=3
2023-04-03T15:46:43.474Z,1680536803.474 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T15:46:43.474Z,1680536803.474 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T15:46:45.478Z,1680536805.478 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154643.00,A,3648.18091,N,12147.28203,W,0.408,349.87,030423,,,A*74
2023-04-03T15:46:45.481Z,1680536805.481 [NAL9602](INFO): GPS fix at 20230403T154643: (36.803015, -121.788034)
2023-04-03T15:46:45.493Z,1680536805.493 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T15:46:45.493Z,1680536805.493 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T15:46:55.360Z,1680536815.360 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230403T152050/Courier0010.lzma
2023-04-03T15:46:56.362Z,1680536816.362 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0010.lzma.bak
2023-04-03T15:46:56.362Z,1680536816.362 [DataOverHttps](INFO): SBD MOMSN=18011093
2023-04-03T15:47:12.153Z,1680536832.153 [DataOverHttps](INFO): Sending 303 bytes from file Logs/20230403T152050/Express0011.lzma
2023-04-03T15:47:13.154Z,1680536833.154 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0011.lzma.bak
2023-04-03T15:47:13.154Z,1680536833.154 [DataOverHttps](INFO): SBD MOMSN=18011096
2023-04-03T15:47:14.260Z,1680536834.260 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T15:47:14.260Z,1680536834.260 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T15:47:14.260Z,1680536834.260 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T15:47:17.458Z,1680536837.458 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T15:47:41.698Z,1680536861.698 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-03T15:52:01.285Z,1680537121.285 [BPC1](INFO): Calculating totals. Valid battery stick count: 21. Valid reserve battery stick count: 2.
2023-04-03T15:52:01.288Z,1680537121.288 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2023-04-03T15:52:15.032Z,1680537135.032 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T15:52:15.032Z,1680537135.032 [Default:CheckIn:C.Wait] Stopped
2023-04-03T15:52:15.032Z,1680537135.032 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T15:52:15.032Z,1680537135.032 [Default:CheckIn:D] Running Loop=1
2023-04-03T15:52:15.435Z,1680537135.435 [Default:CheckIn:D] Stopped
2023-04-03T15:52:15.435Z,1680537135.435 [Default:CheckIn:E] Running Loop=1
2023-04-03T15:52:15.846Z,1680537135.846 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.050918 min
2023-04-03T15:52:15.846Z,1680537135.846 [Default:CheckIn:E] Stopped
2023-04-03T15:52:15.846Z,1680537135.846 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T15:52:15.846Z,1680537135.846 [Default:CheckIn] Stopped
2023-04-03T15:52:15.846Z,1680537135.846 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T15:52:15.846Z,1680537135.846 [Default:CheckIn](INFO): Running loop #4
2023-04-03T15:52:15.846Z,1680537135.846 [Default:CheckIn] Running Loop=4
2023-04-03T15:52:15.847Z,1680537135.847 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T15:52:15.847Z,1680537135.847 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T15:52:17.851Z,1680537137.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155216.00,A,3648.15996,N,12147.28290,W,0.272,349.87,030423,,,A*73
2023-04-03T15:52:17.853Z,1680537137.853 [NAL9602](INFO): GPS fix at 20230403T155216: (36.802666, -121.788048)
2023-04-03T15:52:17.873Z,1680537137.873 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T15:52:17.873Z,1680537137.873 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T15:52:28.020Z,1680537148.020 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20230403T152050/Courier0013.lzma
2023-04-03T15:52:29.022Z,1680537149.022 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0013.lzma.bak
2023-04-03T15:52:29.022Z,1680537149.022 [DataOverHttps](INFO): SBD MOMSN=18011479
2023-04-03T15:52:44.902Z,1680537164.902 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20230403T152050/Express0014.lzma
2023-04-03T15:52:45.902Z,1680537165.902 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0014.lzma.bak
2023-04-03T15:52:45.903Z,1680537165.903 [DataOverHttps](INFO): SBD MOMSN=18011482
2023-04-03T15:52:47.035Z,1680537167.035 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T15:52:47.035Z,1680537167.035 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T15:52:47.035Z,1680537167.035 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T15:53:34.727Z,1680537214.727 [NAL9602](INFO): SBD MO Status=2, MOMSN=39566, MT Status=2, MTMSN=0
2023-04-03T15:53:34.727Z,1680537214.727 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-03T15:55:44.915Z,1680537344.915 [NAL9602](INFO): SBD MO Status=2, MOMSN=39566, MT Status=2, MTMSN=0
2023-04-03T15:55:44.915Z,1680537344.915 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-03T15:56:36.687Z,1680537396.687 [NAL9602](INFO): SBD MO Status=2, MOMSN=39566, MT Status=2, MTMSN=0
2023-04-03T15:56:36.687Z,1680537396.687 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-03T15:57:20.337Z,1680537440.337 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-03T15:57:47.415Z,1680537467.415 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T15:57:47.415Z,1680537467.415 [Default:CheckIn:C.Wait] Stopped
2023-04-03T15:57:47.415Z,1680537467.415 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T15:57:47.415Z,1680537467.415 [Default:CheckIn:D] Running Loop=1
2023-04-03T15:57:47.825Z,1680537467.825 [Default:CheckIn:D] Stopped
2023-04-03T15:57:47.825Z,1680537467.825 [Default:CheckIn:E] Running Loop=1
2023-04-03T15:57:48.234Z,1680537468.234 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.590743 min
2023-04-03T15:57:48.235Z,1680537468.235 [Default:CheckIn:E] Stopped
2023-04-03T15:57:48.235Z,1680537468.235 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T15:57:48.235Z,1680537468.235 [Default:CheckIn] Stopped
2023-04-03T15:57:48.235Z,1680537468.235 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T15:57:48.235Z,1680537468.235 [Default:CheckIn](INFO): Running loop #5
2023-04-03T15:57:48.235Z,1680537468.235 [Default:CheckIn] Running Loop=5
2023-04-03T15:57:48.235Z,1680537468.235 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T15:57:48.235Z,1680537468.235 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T15:57:50.235Z,1680537470.235 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155749.00,A,3648.16408,N,12147.28089,W,0.233,349.87,030423,,,A*7A
2023-04-03T15:57:50.238Z,1680537470.238 [NAL9602](INFO): GPS fix at 20230403T155749: (36.802735, -121.788015)
2023-04-03T15:57:50.275Z,1680537470.275 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T15:57:50.275Z,1680537470.275 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T15:57:57.624Z,1680537477.624 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230403T152050/Courier0016.lzma
2023-04-03T15:57:58.626Z,1680537478.626 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0016.lzma.bak
2023-04-03T15:57:58.626Z,1680537478.626 [DataOverHttps](INFO): SBD MOMSN=18011491
2023-04-03T15:58:14.532Z,1680537494.532 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230403T152050/Express0017.lzma
2023-04-03T15:58:15.534Z,1680537495.534 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0017.lzma.bak
2023-04-03T15:58:15.534Z,1680537495.534 [DataOverHttps](INFO): SBD MOMSN=18011494
2023-04-03T15:58:16.931Z,1680537496.931 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T15:58:16.931Z,1680537496.931 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T15:58:16.931Z,1680537496.931 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T15:58:22.597Z,1680537502.597 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:03:17.357Z,1680537797.357 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T16:03:17.357Z,1680537797.357 [Default:CheckIn:C.Wait] Stopped
2023-04-03T16:03:17.357Z,1680537797.357 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T16:03:17.357Z,1680537797.357 [Default:CheckIn:D] Running Loop=1
2023-04-03T16:03:17.761Z,1680537797.761 [Default:CheckIn:D] Stopped
2023-04-03T16:03:17.761Z,1680537797.761 [Default:CheckIn:E] Running Loop=1
2023-04-03T16:03:18.181Z,1680537798.181 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.089681 min
2023-04-03T16:03:18.181Z,1680537798.181 [Default:CheckIn:E] Stopped
2023-04-03T16:03:18.181Z,1680537798.181 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T16:03:18.181Z,1680537798.181 [Default:CheckIn] Stopped
2023-04-03T16:03:18.181Z,1680537798.181 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T16:03:18.182Z,1680537798.182 [Default:CheckIn](INFO): Running loop #6
2023-04-03T16:03:18.182Z,1680537798.182 [Default:CheckIn] Running Loop=6
2023-04-03T16:03:18.182Z,1680537798.182 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T16:03:18.182Z,1680537798.182 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T16:03:20.179Z,1680537800.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160319.00,A,3648.17936,N,12147.27490,W,0.097,25.56,030423,,,A*46
2023-04-03T16:03:20.185Z,1680537800.185 [NAL9602](INFO): GPS fix at 20230403T160319: (36.802989, -121.787915)
2023-04-03T16:03:20.223Z,1680537800.223 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T16:03:20.223Z,1680537800.223 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T16:03:27.991Z,1680537807.991 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230403T152050/Courier0019.lzma
2023-04-03T16:03:28.994Z,1680537808.994 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0019.lzma.bak
2023-04-03T16:03:28.994Z,1680537808.994 [DataOverHttps](INFO): SBD MOMSN=18011858
2023-04-03T16:03:44.840Z,1680537824.840 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20230403T152050/Express0020.lzma
2023-04-03T16:03:45.842Z,1680537825.842 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0020.lzma.bak
2023-04-03T16:03:45.842Z,1680537825.842 [DataOverHttps](INFO): SBD MOMSN=18011861
2023-04-03T16:03:47.257Z,1680537827.257 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T16:03:47.257Z,1680537827.257 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T16:03:47.257Z,1680537827.257 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T16:03:50.877Z,1680537830.877 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-03T16:03:50.952Z,1680537830.952 [NAL9602](ERROR): received:
+CSQ:0
OK566, 2, 0, 0, 0
OK
2023-04-03T16:05:46.575Z,1680537946.575 [NAL9602](INFO): SBD MO Status=2, MOMSN=39566, MT Status=2, MTMSN=0
2023-04-03T16:05:46.575Z,1680537946.575 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-03T16:06:09.599Z,1680537969.599 [NAL9602](INFO): SBD MO Status=2, MOMSN=39566, MT Status=2, MTMSN=0
2023-04-03T16:06:09.599Z,1680537969.599 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-03T16:06:22.551Z,1680537982.551 [NAL9602](INFO): SBD MO Status=2, MOMSN=39566, MT Status=2, MTMSN=0
2023-04-03T16:06:22.551Z,1680537982.551 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-03T16:07:01.010Z,1680538021.010 [NAL9602](INFO): SBD MO Status=2, MOMSN=39566, MT Status=2, MTMSN=0
2023-04-03T16:07:01.010Z,1680538021.010 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-03T16:07:10.711Z,1680538030.711 [NAL9602](INFO): SBD MO Status=0, MOMSN=39566, MT Status=0, MTMSN=0
2023-04-03T16:07:10.711Z,1680538030.711 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:07:41.410Z,1680538061.410 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:08:47.719Z,1680538127.719 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T16:08:47.719Z,1680538127.719 [Default:CheckIn:C.Wait] Stopped
2023-04-03T16:08:47.719Z,1680538127.719 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T16:08:47.719Z,1680538127.719 [Default:CheckIn:D] Running Loop=1
2023-04-03T16:08:48.182Z,1680538128.182 [Default:CheckIn:D] Stopped
2023-04-03T16:08:48.182Z,1680538128.182 [Default:CheckIn:E] Running Loop=1
2023-04-03T16:08:48.539Z,1680538128.539 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.596700 min
2023-04-03T16:08:48.539Z,1680538128.539 [Default:CheckIn:E] Stopped
2023-04-03T16:08:48.539Z,1680538128.539 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T16:08:48.539Z,1680538128.539 [Default:CheckIn] Stopped
2023-04-03T16:08:48.539Z,1680538128.539 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T16:08:48.540Z,1680538128.540 [Default:CheckIn](INFO): Running loop #7
2023-04-03T16:08:48.540Z,1680538128.540 [Default:CheckIn] Running Loop=7
2023-04-03T16:08:48.540Z,1680538128.540 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T16:08:48.540Z,1680538128.540 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T16:08:50.527Z,1680538130.527 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160849.00,A,3648.17360,N,12147.27776,W,0.039,193.98,030423,,,A*70
2023-04-03T16:08:50.530Z,1680538130.530 [NAL9602](INFO): GPS fix at 20230403T160849: (36.802893, -121.787963)
2023-04-03T16:08:50.580Z,1680538130.580 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T16:08:50.581Z,1680538130.581 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T16:09:00.387Z,1680538140.387 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230403T152050/Courier0022.lzma
2023-04-03T16:09:01.390Z,1680538141.390 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0022.lzma.bak
2023-04-03T16:09:01.390Z,1680538141.390 [DataOverHttps](INFO): SBD MOMSN=18011870
2023-04-03T16:09:06.719Z,1680538146.719 [NAL9602](INFO): SBD MO Status=0, MOMSN=39567, MT Status=0, MTMSN=0
2023-04-03T16:09:06.719Z,1680538146.719 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:09:17.164Z,1680538157.164 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230403T152050/Express0023.lzma
2023-04-03T16:09:18.166Z,1680538158.166 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0023.lzma.bak
2023-04-03T16:09:18.166Z,1680538158.166 [DataOverHttps](INFO): SBD MOMSN=18011873
2023-04-03T16:09:19.240Z,1680538159.240 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T16:09:19.240Z,1680538159.240 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T16:09:19.240Z,1680538159.240 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T16:09:37.402Z,1680538177.402 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:14:19.927Z,1680538459.927 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T16:14:19.927Z,1680538459.927 [Default:CheckIn:C.Wait] Stopped
2023-04-03T16:14:19.927Z,1680538459.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T16:14:19.927Z,1680538459.927 [Default:CheckIn:D] Running Loop=1
2023-04-03T16:14:20.327Z,1680538460.327 [Default:CheckIn:D] Stopped
2023-04-03T16:14:20.327Z,1680538460.327 [Default:CheckIn:E] Running Loop=1
2023-04-03T16:14:20.724Z,1680538460.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.132442 min
2023-04-03T16:14:20.724Z,1680538460.724 [Default:CheckIn:E] Stopped
2023-04-03T16:14:20.724Z,1680538460.724 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T16:14:20.724Z,1680538460.724 [Default:CheckIn] Stopped
2023-04-03T16:14:20.724Z,1680538460.724 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T16:14:20.725Z,1680538460.725 [Default:CheckIn](INFO): Running loop #8
2023-04-03T16:14:20.725Z,1680538460.725 [Default:CheckIn] Running Loop=8
2023-04-03T16:14:20.725Z,1680538460.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T16:14:20.725Z,1680538460.725 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T16:14:22.739Z,1680538462.739 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161422.00,A,3648.17444,N,12147.27606,W,0.097,193.98,030423,,,A*73
2023-04-03T16:14:22.741Z,1680538462.741 [NAL9602](INFO): GPS fix at 20230403T161422: (36.802907, -121.787934)
2023-04-03T16:14:22.809Z,1680538462.809 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T16:14:22.809Z,1680538462.809 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T16:14:30.451Z,1680538470.451 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230403T152050/Courier0025.lzma
2023-04-03T16:14:31.454Z,1680538471.454 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0025.lzma.bak
2023-04-03T16:14:31.454Z,1680538471.454 [DataOverHttps](INFO): SBD MOMSN=18012263
2023-04-03T16:14:47.220Z,1680538487.220 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230403T152050/Express0026.lzma
2023-04-03T16:14:48.222Z,1680538488.222 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0026.lzma.bak
2023-04-03T16:14:48.222Z,1680538488.222 [DataOverHttps](INFO): SBD MOMSN=18012266
2023-04-03T16:14:49.615Z,1680538489.615 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T16:14:49.615Z,1680538489.615 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T16:14:49.615Z,1680538489.615 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T16:14:50.809Z,1680538490.809 [NAL9602](INFO): SBD MO Status=2, MOMSN=39568, MT Status=2, MTMSN=0
2023-04-03T16:14:50.809Z,1680538490.809 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-03T16:15:00.158Z,1680538500.158 [NAL9602](INFO): SBD MO Status=0, MOMSN=39568, MT Status=0, MTMSN=0
2023-04-03T16:15:00.158Z,1680538500.158 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:15:30.865Z,1680538530.865 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:15:57.297Z,1680538557.297 [DataOverHttps](IMPORTANT): SBD MTMSN=20230403T161556
2023-04-03T16:16:04.841Z,1680538564.841 [DataOverHttps](INFO): Received command: ibit
2023-04-03T16:16:04.842Z,1680538564.842 [CommandExec](IMPORTANT): got command ibit
2023-04-03T16:16:05.220Z,1680538565.220 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-04-03T16:16:05.220Z,1680538565.220 [IBIT](IMPORTANT): Beginning control surface checks.
2023-04-03T16:16:05.223Z,1680538565.223 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-03T16:16:06.825Z,1680538566.825 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161606.00,A,3648.17270,N,12147.27588,W,0.194,193.98,030423,,,A*71
2023-04-03T16:16:06.827Z,1680538566.827 [NAL9602](INFO): GPS fix at 20230403T161606: (36.802878, -121.787931)
2023-04-03T16:16:08.125Z,1680538568.125 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-04-03T16:16:16.145Z,1680538576.145 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-04-03T16:16:16.563Z,1680538576.563 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-03T16:16:19.394Z,1680538579.394 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-04-03T16:16:27.501Z,1680538587.501 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-04-03T16:16:27.965Z,1680538587.965 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-03T16:16:30.749Z,1680538590.749 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-04-03T16:16:35.990Z,1680538595.990 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.762486
2023-04-03T16:16:35.990Z,1680538595.990 [IBIT](FAULT): Control surface position failure.
2023-04-03T16:16:38.813Z,1680538598.813 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-04-03T16:16:38.813Z,1680538598.813 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-04-03T16:16:40.755Z,1680538600.755 [NAL9602](INFO): SBD MO Status=0, MOMSN=39569, MT Status=0, MTMSN=0
2023-04-03T16:16:40.755Z,1680538600.755 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:16:41.967Z,1680538601.967 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161641.00,A,3648.17204,N,12147.27822,W,0.156,193.98,030423,,,A*72
2023-04-03T16:16:41.969Z,1680538601.969 [NAL9602](INFO): GPS fix at 20230403T161641: (36.802867, -121.787970)
2023-04-03T16:16:44.799Z,1680538604.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161644.00,A,3648.17214,N,12147.27837,W,0.292,193.98,030423,,,A*79
2023-04-03T16:16:44.802Z,1680538604.802 [NAL9602](INFO): GPS fix at 20230403T161644: (36.802869, -121.787973)
2023-04-03T16:16:48.041Z,1680538608.041 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161647.00,A,3648.17221,N,12147.27847,W,0.350,193.98,030423,,,A*74
2023-04-03T16:16:48.043Z,1680538608.043 [NAL9602](INFO): GPS fix at 20230403T161647: (36.802870, -121.787975)
2023-04-03T16:16:50.855Z,1680538610.855 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161650.00,A,3648.17246,N,12147.27823,W,0.156,193.98,030423,,,A*75
2023-04-03T16:16:50.858Z,1680538610.858 [NAL9602](INFO): GPS fix at 20230403T161650: (36.802874, -121.787971)
2023-04-03T16:16:51.688Z,1680538611.688 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802876 Longitude: -121.787971
2023-04-03T16:16:52.098Z,1680538612.098 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-04-03T16:16:52.098Z,1680538612.098 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-04-03T16:16:52.099Z,1680538612.099 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-04-03T16:16:52.523Z,1680538612.523 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-04-03T16:16:52.524Z,1680538612.524 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-04-03T16:16:52.537Z,1680538612.537 [IBIT](IMPORTANT): Pressure:9.881642 PSI
2023-04-03T16:16:52.537Z,1680538612.537 [IBIT](IMPORTANT): Humidity:0.988890 %
2023-04-03T16:16:52.938Z,1680538612.938 [IBIT](IMPORTANT): Vehicle Pitch:-0.706096 degrees
2023-04-03T16:16:52.939Z,1680538612.939 [IBIT](IMPORTANT): Vehicle Roll:-2.467996 degrees
2023-04-03T16:16:52.939Z,1680538612.939 [IBIT](IMPORTANT): Vehicle Heading:38.563694 degrees
2023-04-03T16:16:53.302Z,1680538613.302 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-04-03T16:16:53.302Z,1680538613.302 [IBIT](IMPORTANT): buoyancyNeutral: 336.456146 cc
2023-04-03T16:16:53.303Z,1680538613.303 [IBIT](IMPORTANT): massDefault: -1.415717 cm
2023-04-03T16:16:53.303Z,1680538613.303 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-04-03T16:16:53.303Z,1680538613.303 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-04-03T16:16:53.303Z,1680538613.303 [IBIT](IMPORTANT): IBIT FAILED
2023-04-03T16:17:23.585Z,1680538643.585 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:19:50.249Z,1680538790.249 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T16:19:50.249Z,1680538790.249 [Default:CheckIn:C.Wait] Stopped
2023-04-03T16:19:50.249Z,1680538790.249 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T16:19:50.250Z,1680538790.250 [Default:CheckIn:D] Running Loop=1
2023-04-03T16:19:50.643Z,1680538790.643 [Default:CheckIn:D] Stopped
2023-04-03T16:19:50.643Z,1680538790.643 [Default:CheckIn:E] Running Loop=1
2023-04-03T16:19:51.051Z,1680538791.051 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.637712 min
2023-04-03T16:19:51.051Z,1680538791.051 [Default:CheckIn:E] Stopped
2023-04-03T16:19:51.051Z,1680538791.051 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T16:19:51.051Z,1680538791.051 [Default:CheckIn] Stopped
2023-04-03T16:19:51.051Z,1680538791.051 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T16:19:51.051Z,1680538791.051 [Default:CheckIn](INFO): Running loop #9
2023-04-03T16:19:51.051Z,1680538791.051 [Default:CheckIn] Running Loop=9
2023-04-03T16:19:51.052Z,1680538791.052 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T16:19:51.052Z,1680538791.052 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T16:19:53.060Z,1680538793.060 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161952.00,A,3648.17110,N,12147.27905,W,0.058,193.98,030423,,,A*72
2023-04-03T16:19:53.062Z,1680538793.062 [NAL9602](INFO): GPS fix at 20230403T161952: (36.802852, -121.787984)
2023-04-03T16:19:53.074Z,1680538793.074 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T16:19:53.074Z,1680538793.074 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T16:19:59.919Z,1680538799.919 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230403T152050/Courier0028.lzma
2023-04-03T16:20:00.922Z,1680538800.922 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0028.lzma.bak
2023-04-03T16:20:00.922Z,1680538800.922 [DataOverHttps](INFO): SBD MOMSN=18012280
2023-04-03T16:20:09.282Z,1680538809.282 [DataOverHttps](IMPORTANT): SBD MTMSN=20230403T162008
2023-04-03T16:20:10.035Z,1680538810.035 [NAL9602](INFO): SBD MO Status=0, MOMSN=39570, MT Status=0, MTMSN=0
2023-04-03T16:20:10.035Z,1680538810.035 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:20:16.773Z,1680538816.773 [DataOverHttps](INFO): Sending 817 bytes from file Logs/20230403T152050/Express0029.lzma
2023-04-03T16:20:16.777Z,1680538816.777 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.80125 degree;set transit.Longitude -121.79578 degree;set transit.Depth 1 m;set transit.MaxDepth 2 m" 2lupj 1 2
2023-04-03T16:20:16.849Z,1680538816.849 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.80125 degree;set transit.Longitude -121.79578 degree;set transit.Depth 1 m;set transit.MaxDepth 2 m" 2lupj 1 2.000000
2023-04-03T16:20:16.849Z,1680538816.849 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=2lupj
2023-04-03T16:20:16.851Z,1680538816.851 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='2lupj'): "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.80125 degree;set transit.Longitude -121.79578 degree;set transit.Depth 1 m;set transit.MaxDepth 2 m", ASAP
2023-04-03T16:20:18.047Z,1680538818.047 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0029.lzma.bak
2023-04-03T16:20:18.047Z,1680538818.047 [DataOverHttps](IMPORTANT): SBD MOMSN=18012284, MTMSN=20230403T162016
2023-04-03T16:20:27.740Z,1680538827.740 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run" 2lupj 2 2
2023-04-03T16:20:27.778Z,1680538827.778 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run" 2lupj 2 2.000000
2023-04-03T16:20:27.778Z,1680538827.778 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=2lupj
2023-04-03T16:20:27.779Z,1680538827.779 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='2lupj'): "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run", ASAP
2023-04-03T16:20:27.900Z,1680538827.900 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-04-03T16:20:27.901Z,1680538827.901 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-04-03T16:20:27.901Z,1680538827.901 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-04-03T16:20:28.110Z,1680538828.110 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-04-03T16:20:28.113Z,1680538828.113 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-04-03T16:20:28.115Z,1680538828.115 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-04-03T16:20:28.130Z,1680538828.130 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-04-03T16:20:28.133Z,1680538828.133 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-04-03T16:20:28.135Z,1680538828.135 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-04-03T16:20:28.138Z,1680538828.138 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-04-03T16:20:28.185Z,1680538828.185 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-04-03T16:20:28.187Z,1680538828.187 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-04-03T16:20:28.190Z,1680538828.190 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-04-03T16:20:28.215Z,1680538828.215 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-04-03T16:20:28.217Z,1680538828.217 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-04-03T16:20:28.220Z,1680538828.220 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-04-03T16:20:28.281Z,1680538828.281 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml
2023-04-03T16:20:28.464Z,1680538828.464 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 3.000000 h
2023-04-03T16:20:28.475Z,1680538828.475 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-04-03T16:20:28.509Z,1680538828.509 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-04-03T16:20:28.516Z,1680538828.516 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-04-03T16:20:28.537Z,1680538828.537 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-04-03T16:20:28.553Z,1680538828.553 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-04-03T16:20:28.556Z,1680538828.556 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-04-03T16:20:28.567Z,1680538828.567 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-04-03T16:20:28.599Z,1680538828.599 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-03T16:20:28.715Z,1680538828.715 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml
2023-04-03T16:20:28.847Z,1680538828.847 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-04-03T16:20:28.874Z,1680538828.874 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-04-03T16:20:28.877Z,1680538828.877 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-04-03T16:20:28.878Z,1680538828.878 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-04-03T16:20:28.903Z,1680538828.903 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-04-03T16:20:28.985Z,1680538828.985 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-04-03T16:20:29.047Z,1680538829.047 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-04-03T16:20:29.051Z,1680538829.051 [transit:MassHold.Pitch](DEBUG): Construct.
2023-04-03T16:20:29.144Z,1680538829.144 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-04-03T16:20:29.170Z,1680538829.170 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-04-03T16:20:29.173Z,1680538829.173 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-04-03T16:20:29.251Z,1680538829.251 [MissionManager](DEBUG):
mission transit {
"""
Vehicle transits to desired waypoint and can be commanded to use/hold
mass or buoyancy.
"""
arguments {
MissionTimeout = 60 minute
"""
Maximum duration of mission
"""
NeedCommsTime = 30 minute
"""
How often to surface for commumications
"""
Latitude = NaN degree
"""
Latitude of waypoint to seek. If set to NaN, uses latitude at mission
initialization.
"""
Longitude = NaN degree
"""
Longitude of waypoint to seek. If set to NaN, uses longitude at mission
initialization.
"""
Northings = 0 meter
"""
Northward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Eastings = 0 meter
"""
Eastward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Depth = 10 meter
"""
Depth of flat and level flight during the mission.
"""
Speed = 1 meter_per_second
"""
Speed of vehicle (relative to water) during the mission.
"""
MaxDepth = 20 meter
"""
Maximum depth for the entire mission.
"""
MinOffshore = 1 kilometer
"""
Minimum distance offshore for the entire mission.
"""
MinAltitude = 7 meter
"""
Minimum height above the sea floor for the entire mission.
"""
MassHold = true
"""
Set to True in order to hold mass at default position, False to allow
mass to run on its own.
"""
BuoyancyHold = true
"""
Set to True in order to hold buoyancy at neutral position, False to
allow buoyancy to run on its own.
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.xml
assign in sequence NeedComms:DiveInterval = NeedCommsTime
assign in sequence NeedComms:WaitForPitchUp = 1 second
insert Insert/StandardEnvelopes.xml
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
behavior Guidance:Buoyancy id="BuoyancyHold" {
run while ( BuoyancyHold )
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch id="MassHold" {
run while ( MassHold )
set massPosition = Control:VerticalControl.massDefault
}
aggregate Transit {
run in sequence
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
set northingsDelta = Northings
set eastingsDelta = Eastings
}
call id="PhoneHome" refId="NeedComms"
}
}
2023-04-03T16:20:29.252Z,1680538829.252 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-04-03T16:20:39.648Z,1680538839.648 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230403T152050/Courier0031.lzma
2023-04-03T16:20:40.650Z,1680538840.650 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0031.lzma.bak
2023-04-03T16:20:40.650Z,1680538840.650 [DataOverHttps](INFO): SBD MOMSN=18012322
2023-04-03T16:20:40.789Z,1680538840.789 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:20:41.774Z,1680538841.774 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute
2023-04-03T16:20:41.775Z,1680538841.775 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 12 minute
2023-04-03T16:20:41.776Z,1680538841.776 [CommandExec](IMPORTANT): got command set transit.Latitude 36.80125 degree
2023-04-03T16:20:41.777Z,1680538841.777 [CommandExec](IMPORTANT): got command set transit.Longitude -121.79578 degree
2023-04-03T16:20:41.778Z,1680538841.778 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-04-03T16:20:41.778Z,1680538841.778 [CommandExec](IMPORTANT): got command set transit.MaxDepth 2 meter
2023-04-03T16:20:46.129Z,1680538846.129 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.0001 kilometer
2023-04-03T16:20:46.129Z,1680538846.129 [CommandExec](IMPORTANT): got command set transit.MinAltitude 1 meter
2023-04-03T16:20:46.130Z,1680538846.130 [CommandExec](IMPORTANT): got command run
2023-04-03T16:20:46.144Z,1680538846.144 [CommandExec](IMPORTANT): Running
2023-04-03T16:20:46.508Z,1680538846.508 [Default] Stopped
2023-04-03T16:20:46.508Z,1680538846.508 [Default](DEBUG): Aggregate::uninitialize Default
2023-04-03T16:20:46.508Z,1680538846.508 [Default:B.GoToSurface] Stopped
2023-04-03T16:20:46.508Z,1680538846.508 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-03T16:20:46.508Z,1680538846.508 [Default:CheckIn] Stopped
2023-04-03T16:20:46.509Z,1680538846.509 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T16:20:46.509Z,1680538846.509 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T16:20:46.509Z,1680538846.509 [MissionManager](IMPORTANT): Started mission transit
2023-04-03T16:20:46.509Z,1680538846.509 [transit] Running Loop=1
2023-04-03T16:20:46.509Z,1680538846.509 [transit](DEBUG): Aggregate::initialize transit
2023-04-03T16:20:46.509Z,1680538846.509 [transit:StandardEnvelopes] Running Loop=1
2023-04-03T16:20:46.509Z,1680538846.509 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-04-03T16:20:46.509Z,1680538846.509 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-04-03T16:20:46.510Z,1680538846.510 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-04-03T16:20:46.510Z,1680538846.510 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-04-03T16:20:46.510Z,1680538846.510 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-04-03T16:20:46.510Z,1680538846.510 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-04-03T16:20:46.510Z,1680538846.510 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-04-03T16:20:46.510Z,1680538846.510 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-04-03T16:20:46.510Z,1680538846.510 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-04-03T16:20:46.510Z,1680538846.510 [transit:MassHold.Pitch] Running Loop=1
2023-04-03T16:20:46.510Z,1680538846.510 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-04-03T16:20:46.511Z,1680538846.511 [transit:B] Running Loop=1
2023-04-03T16:20:46.511Z,1680538846.511 [transit:MassHold.Pitch] Running Loop=1
2023-04-03T16:20:46.512Z,1680538846.512 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-04-03T16:20:46.512Z,1680538846.512 [transit:StandardEnvelopes] Running Loop=1
2023-04-03T16:20:46.512Z,1680538846.512 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-04-03T16:20:46.578Z,1680538846.578 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-04-03T16:20:46.579Z,1680538846.579 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-04-03T16:20:46.580Z,1680538846.580 [transit:B] Stopped
2023-04-03T16:20:46.580Z,1680538846.580 [transit:C] Running Loop=1
2023-04-03T16:20:46.915Z,1680538846.915 [transit:C] Stopped
2023-04-03T16:20:46.915Z,1680538846.915 [transit:E] Running Loop=1
2023-04-03T16:20:47.285Z,1680538847.285 [transit:E] Stopped
2023-04-03T16:20:47.285Z,1680538847.285 [transit:F] Running Loop=1
2023-04-03T16:20:47.714Z,1680538847.714 [transit:F] Stopped
2023-04-03T16:20:47.714Z,1680538847.714 [transit:G] Running Loop=1
2023-04-03T16:20:48.143Z,1680538848.143 [transit:G] Stopped
2023-04-03T16:20:48.143Z,1680538848.143 [transit:Transit] Running Loop=1
2023-04-03T16:20:48.143Z,1680538848.143 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2023-04-03T16:20:48.143Z,1680538848.143 [transit:Transit:A.Pitch] Running Loop=1
2023-04-03T16:20:48.143Z,1680538848.143 [transit:Transit:A.Pitch](DEBUG): Initialize.
2023-04-03T16:20:48.144Z,1680538848.144 [transit:Transit:B.SetSpeed] Running Loop=1
2023-04-03T16:20:48.144Z,1680538848.144 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2023-04-03T16:20:48.157Z,1680538848.157 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2023-04-03T16:20:48.158Z,1680538848.158 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2023-04-03T16:20:48.278Z,1680538848.278 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.801250,-121.795776
2023-04-03T16:20:48.279Z,1680538848.279 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2023-04-03T16:20:48.530Z,1680538848.530 [transit:Transit:B.SetSpeed] Running Loop=1
2023-04-03T16:20:48.530Z,1680538848.530 [transit:Transit:A.Pitch] Running Loop=1
2023-04-03T16:20:52.590Z,1680538852.590 [DataOverHttps](IMPORTANT): SBD MTMSN=20230403T162051
2023-04-03T16:21:03.379Z,1680538863.379 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230403T152050/Courier0037.lzma
2023-04-03T16:21:03.382Z,1680538863.382 [DataOverHttps](INFO): Received command: stop
2023-04-03T16:21:03.425Z,1680538863.425 [CommandExec](IMPORTANT): got command stop
2023-04-03T16:21:03.425Z,1680538863.425 [CommandExec](IMPORTANT): Scheduling is paused
2023-04-03T16:21:03.425Z,1680538863.425 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-04-03T16:21:03.518Z,1680538863.518 [MissionManager](INFO): MissionManager is completed.
2023-04-03T16:21:03.518Z,1680538863.518 [MissionManager](INFO): Uninitializing Mission transit
2023-04-03T16:21:03.518Z,1680538863.518 [transit] Stopped
2023-04-03T16:21:03.518Z,1680538863.518 [transit](DEBUG): Aggregate::uninitialize transit
2023-04-03T16:21:03.518Z,1680538863.518 [transit:StandardEnvelopes] Stopped
2023-04-03T16:21:03.518Z,1680538863.518 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2023-04-03T16:21:03.518Z,1680538863.518 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2023-04-03T16:21:03.518Z,1680538863.518 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2023-04-03T16:21:03.518Z,1680538863.518 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2023-04-03T16:21:03.518Z,1680538863.518 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2023-04-03T16:21:03.518Z,1680538863.518 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2023-04-03T16:21:03.519Z,1680538863.519 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2023-04-03T16:21:03.519Z,1680538863.519 [transit:BuoyancyHold.Buoyancy] Stopped
2023-04-03T16:21:03.519Z,1680538863.519 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-04-03T16:21:03.519Z,1680538863.519 [transit:MassHold.Pitch] Stopped
2023-04-03T16:21:03.519Z,1680538863.519 [transit:Transit] Stopped
2023-04-03T16:21:03.519Z,1680538863.519 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2023-04-03T16:21:03.519Z,1680538863.519 [transit:Transit:A.Pitch] Stopped
2023-04-03T16:21:03.519Z,1680538863.519 [transit:Transit:B.SetSpeed] Stopped
2023-04-03T16:21:03.519Z,1680538863.519 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2023-04-03T16:21:03.519Z,1680538863.519 [transit:Transit:Wpt1.Waypoint] Stopped
2023-04-03T16:21:03.519Z,1680538863.519 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2023-04-03T16:21:03.885Z,1680538863.885 [MissionManager](IMPORTANT): Started mission Default
2023-04-03T16:21:03.885Z,1680538863.885 [Default] Running Loop=1
2023-04-03T16:21:03.885Z,1680538863.885 [Default](DEBUG): Aggregate::initialize Default
2023-04-03T16:21:03.885Z,1680538863.885 [Default:B.GoToSurface] Running Loop=1
2023-04-03T16:21:03.885Z,1680538863.885 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-03T16:21:03.885Z,1680538863.885 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-03T16:21:03.886Z,1680538863.886 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-03T16:21:03.886Z,1680538863.886 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-03T16:21:03.886Z,1680538863.886 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-03T16:21:03.887Z,1680538863.887 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-03T16:21:03.887Z,1680538863.887 [Default:A.Wait] Running Loop=1
2023-04-03T16:21:03.887Z,1680538863.887 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-04-03T16:21:04.384Z,1680538864.384 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0037.lzma.bak
2023-04-03T16:21:04.384Z,1680538864.384 [DataOverHttps](INFO): SBD MOMSN=18012325
2023-04-03T16:21:17.217Z,1680538877.217 [Default:A.Wait](INFO): Done Waiting.
2023-04-03T16:21:17.217Z,1680538877.217 [Default:A.Wait] Stopped
2023-04-03T16:21:17.217Z,1680538877.217 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T16:21:17.625Z,1680538877.625 [Default:CheckIn] Running Loop=1
2023-04-03T16:21:17.625Z,1680538877.625 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T16:21:17.625Z,1680538877.625 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T16:21:19.626Z,1680538879.626 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162118.00,A,3648.17798,N,12147.27420,W,0.039,193.98,030423,,,A*7C
2023-04-03T16:21:19.629Z,1680538879.629 [NAL9602](INFO): GPS fix at 20230403T162118: (36.802966, -121.787903)
2023-04-03T16:21:19.661Z,1680538879.661 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T16:21:19.662Z,1680538879.662 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T16:21:35.320Z,1680538895.320 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230403T152050/Courier0040.lzma
2023-04-03T16:21:36.322Z,1680538896.322 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0040.lzma.bak
2023-04-03T16:21:36.323Z,1680538896.323 [DataOverHttps](IMPORTANT): SBD MOMSN=18012329, MTMSN=20230403T162135
2023-04-03T16:21:43.832Z,1680538903.832 [DataOverHttps](INFO): Received command: ibit
2023-04-03T16:21:43.909Z,1680538903.909 [CommandExec](IMPORTANT): got command ibit
2023-04-03T16:21:44.291Z,1680538904.291 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-04-03T16:21:44.291Z,1680538904.291 [IBIT](IMPORTANT): Beginning control surface checks.
2023-04-03T16:21:44.294Z,1680538904.294 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-03T16:21:47.185Z,1680538907.185 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-04-03T16:21:52.557Z,1680538912.557 [DataOverHttps](INFO): Sending 327 bytes from file Logs/20230403T152050/Express0033.lzma
2023-04-03T16:21:53.585Z,1680538913.585 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0033.lzma.bak
2023-04-03T16:21:53.585Z,1680538913.585 [DataOverHttps](INFO): SBD MOMSN=18012333
2023-04-03T16:21:55.257Z,1680538915.257 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-04-03T16:21:55.701Z,1680538915.701 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-03T16:21:58.517Z,1680538918.517 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-04-03T16:22:06.624Z,1680538926.624 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002560
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002073
CHAN A3 (5V): -0.001441
CHAN B0 (3.3V): 0.000152
CHAN B1 (3.15aV): 0.000037
CHAN B2 (3.15bV): -0.000135
CHAN B3 (GND): 0.000047
OPEN: -0.000479
Full Scale: +/- 1 mA
2023-04-03T16:22:10.023Z,1680538930.023 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230403T152050/Courier0046.lzma
2023-04-03T16:22:11.027Z,1680538931.027 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0046.lzma.bak
2023-04-03T16:22:11.027Z,1680538931.027 [DataOverHttps](INFO): SBD MOMSN=18012342
2023-04-03T16:22:14.683Z,1680538934.683 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.797676
2023-04-03T16:22:14.683Z,1680538934.683 [IBIT](FAULT): Control surface position failure.
2023-04-03T16:22:30.272Z,1680538950.272 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20230403T152050/Express0038.lzma
2023-04-03T16:22:31.274Z,1680538951.274 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0038.lzma.bak
2023-04-03T16:22:31.274Z,1680538951.274 [DataOverHttps](INFO): SBD MOMSN=18012348
2023-04-03T16:22:47.089Z,1680538967.089 [DataOverHttps](INFO): Sending 460 bytes from file Logs/20230403T152050/Express0041.lzma
2023-04-03T16:22:48.090Z,1680538968.090 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0041.lzma.bak
2023-04-03T16:22:48.091Z,1680538968.091 [DataOverHttps](INFO): SBD MOMSN=18012357
2023-04-03T16:22:51.017Z,1680538971.017 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802967 Longitude: -121.787903
2023-04-03T16:22:51.487Z,1680538971.487 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-04-03T16:22:51.488Z,1680538971.488 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-04-03T16:22:51.504Z,1680538971.504 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-04-03T16:22:51.872Z,1680538971.872 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-04-03T16:22:51.889Z,1680538971.889 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-04-03T16:22:51.889Z,1680538971.889 [IBIT](IMPORTANT): Pressure:9.829593 PSI
2023-04-03T16:22:51.890Z,1680538971.890 [IBIT](IMPORTANT): Humidity:0.958369 %
2023-04-03T16:22:52.303Z,1680538972.303 [IBIT](IMPORTANT): Vehicle Pitch:-0.722725 degrees
2023-04-03T16:22:52.304Z,1680538972.304 [IBIT](IMPORTANT): Vehicle Roll:-2.466406 degrees
2023-04-03T16:22:52.321Z,1680538972.321 [IBIT](IMPORTANT): Vehicle Heading:38.559093 degrees
2023-04-03T16:22:52.691Z,1680538972.691 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-04-03T16:22:52.691Z,1680538972.691 [IBIT](IMPORTANT): buoyancyNeutral: 336.456146 cc
2023-04-03T16:22:52.691Z,1680538972.691 [IBIT](IMPORTANT): massDefault: -1.415717 cm
2023-04-03T16:22:52.692Z,1680538972.692 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-04-03T16:22:52.704Z,1680538972.704 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-04-03T16:22:52.704Z,1680538972.704 [IBIT](IMPORTANT): IBIT FAILED
2023-04-03T16:23:02.343Z,1680538982.343 [NAL9602](INFO): SBD MO Status=0, MOMSN=39571, MT Status=0, MTMSN=0
2023-04-03T16:23:02.343Z,1680538982.343 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:23:06.864Z,1680538986.864 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230403T152050/Express0047.lzma
2023-04-03T16:23:07.866Z,1680538987.866 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0047.lzma.bak
2023-04-03T16:23:07.866Z,1680538987.866 [DataOverHttps](INFO): SBD MOMSN=18012376
2023-04-03T16:23:09.226Z,1680538989.226 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T16:23:09.227Z,1680538989.227 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T16:23:09.227Z,1680538989.227 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T16:23:33.041Z,1680539013.041 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:28:09.828Z,1680539289.828 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T16:28:09.828Z,1680539289.828 [Default:CheckIn:C.Wait] Stopped
2023-04-03T16:28:09.828Z,1680539289.828 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T16:28:09.829Z,1680539289.829 [Default:CheckIn:D] Running Loop=1
2023-04-03T16:28:10.242Z,1680539290.242 [Default:CheckIn:D] Stopped
2023-04-03T16:28:10.243Z,1680539290.243 [Default:CheckIn:E] Running Loop=1
2023-04-03T16:28:10.631Z,1680539290.631 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.105959 min
2023-04-03T16:28:10.631Z,1680539290.631 [Default:CheckIn:E] Stopped
2023-04-03T16:28:10.631Z,1680539290.631 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T16:28:10.631Z,1680539290.631 [Default:CheckIn] Stopped
2023-04-03T16:28:10.632Z,1680539290.632 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T16:28:10.632Z,1680539290.632 [Default:CheckIn](INFO): Running loop #2
2023-04-03T16:28:10.632Z,1680539290.632 [Default:CheckIn] Running Loop=2
2023-04-03T16:28:10.632Z,1680539290.632 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T16:28:10.632Z,1680539290.632 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T16:28:12.645Z,1680539292.645 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162811.00,A,3648.17767,N,12147.27728,W,0.078,193.98,030423,,,A*72
2023-04-03T16:28:12.647Z,1680539292.647 [NAL9602](INFO): GPS fix at 20230403T162811: (36.802961, -121.787955)
2023-04-03T16:28:12.659Z,1680539292.659 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T16:28:12.659Z,1680539292.659 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T16:28:21.564Z,1680539301.564 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230403T152050/Courier0049.lzma
2023-04-03T16:28:22.570Z,1680539302.570 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0049.lzma.bak
2023-04-03T16:28:22.570Z,1680539302.570 [DataOverHttps](INFO): SBD MOMSN=18012754
2023-04-03T16:28:39.981Z,1680539319.981 [DataOverHttps](INFO): Sending 767 bytes from file Logs/20230403T152050/Express0050.lzma
2023-04-03T16:28:40.982Z,1680539320.982 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0050.lzma.bak
2023-04-03T16:28:40.982Z,1680539320.982 [DataOverHttps](INFO): SBD MOMSN=18012757
2023-04-03T16:28:42.223Z,1680539322.223 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T16:28:42.223Z,1680539322.223 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T16:28:42.223Z,1680539322.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T16:28:46.235Z,1680539326.235 [NAL9602](INFO): SBD MO Status=0, MOMSN=39572, MT Status=0, MTMSN=0
2023-04-03T16:28:46.235Z,1680539326.235 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:29:16.933Z,1680539356.933 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:33:42.949Z,1680539622.949 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T16:33:42.949Z,1680539622.949 [Default:CheckIn:C.Wait] Stopped
2023-04-03T16:33:42.950Z,1680539622.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T16:33:42.950Z,1680539622.950 [Default:CheckIn:D] Running Loop=1
2023-04-03T16:33:43.346Z,1680539623.346 [Default:CheckIn:D] Stopped
2023-04-03T16:33:43.346Z,1680539623.346 [Default:CheckIn:E] Running Loop=1
2023-04-03T16:33:43.749Z,1680539623.749 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.657685 min
2023-04-03T16:33:43.749Z,1680539623.749 [Default:CheckIn:E] Stopped
2023-04-03T16:33:43.749Z,1680539623.749 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T16:33:43.749Z,1680539623.749 [Default:CheckIn] Stopped
2023-04-03T16:33:43.749Z,1680539623.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T16:33:43.750Z,1680539623.750 [Default:CheckIn](INFO): Running loop #3
2023-04-03T16:33:43.750Z,1680539623.750 [Default:CheckIn] Running Loop=3
2023-04-03T16:33:43.750Z,1680539623.750 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T16:33:43.750Z,1680539623.750 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T16:33:45.761Z,1680539625.761 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163345.00,A,3648.17328,N,12147.27660,W,0.078,0.00,030423,,,A*71
2023-04-03T16:33:45.763Z,1680539625.763 [NAL9602](INFO): GPS fix at 20230403T163345: (36.802888, -121.787943)
2023-04-03T16:33:45.775Z,1680539625.775 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T16:33:45.775Z,1680539625.775 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T16:33:53.360Z,1680539633.360 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230403T152050/Courier0052.lzma
2023-04-03T16:33:54.362Z,1680539634.362 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0052.lzma.bak
2023-04-03T16:33:54.363Z,1680539634.363 [DataOverHttps](INFO): SBD MOMSN=18012801
2023-04-03T16:34:00.703Z,1680539640.703 [NAL9602](INFO): SBD MO Status=0, MOMSN=39573, MT Status=0, MTMSN=0
2023-04-03T16:34:00.703Z,1680539640.703 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:34:10.280Z,1680539650.280 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230403T152050/Express0053.lzma
2023-04-03T16:34:11.282Z,1680539651.282 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0053.lzma.bak
2023-04-03T16:34:11.282Z,1680539651.282 [DataOverHttps](INFO): SBD MOMSN=18012809
2023-04-03T16:34:12.441Z,1680539652.441 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T16:34:12.441Z,1680539652.441 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T16:34:12.441Z,1680539652.441 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T16:34:31.413Z,1680539671.413 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:34:51.554Z,1680539691.554 [DataOverHttps](IMPORTANT): SBD MTMSN=20230403T163450
2023-04-03T16:35:01.036Z,1680539701.036 [DataOverHttps](INFO): Received command: get rudderservo.limitlo
2023-04-03T16:35:01.698Z,1680539701.698 [CommandExec](IMPORTANT): got command get RudderServo.limitLo
2023-04-03T16:35:01.698Z,1680539701.698 [CommandExec](IMPORTANT): RudderServo.limitLo 0 count
2023-04-03T16:36:03.574Z,1680539763.574 [CommandExec](IMPORTANT): got command show variable rudderServo.offset
2023-04-03T16:36:03.743Z,1680539763.743 [CommandExec](IMPORTANT): RudderServo.offsetAngle (degree)
2023-04-03T16:36:13.654Z,1680539773.654 [CommandExec](IMPORTANT): got command get RudderServo.offsetAngle
2023-04-03T16:36:13.655Z,1680539773.655 [CommandExec](IMPORTANT): RudderServo.offsetAngle 0.000000 arcdeg
2023-04-03T16:36:25.626Z,1680539785.626 [CommandExec](IMPORTANT): got command report mod platform_rudder_angle
2023-04-03T16:36:25.905Z,1680539785.905 [Reporter](INFO): platform_rudder_angle 0.000307 rad
2023-04-03T16:36:39.692Z,1680539799.692 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2023-04-03T16:36:39.695Z,1680539799.695 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2023-04-03T16:36:39.999Z,1680539799.999 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:36:41.641Z,1680539801.641 [Reporter](INFO): platform_rudder_angle 0.002111 rad
2023-04-03T16:36:42.018Z,1680539802.018 [Reporter](INFO): platform_rudder_angle 0.016315 rad
2023-04-03T16:36:42.437Z,1680539802.437 [Reporter](INFO): platform_rudder_angle 0.061842 rad
2023-04-03T16:36:42.848Z,1680539802.848 [Reporter](INFO): platform_rudder_angle 0.111131 rad
2023-04-03T16:36:43.218Z,1680539803.218 [Reporter](INFO): platform_rudder_angle 0.156427 rad
2023-04-03T16:36:43.638Z,1680539803.638 [Reporter](INFO): platform_rudder_angle 0.201225 rad
2023-04-03T16:36:44.061Z,1680539804.061 [Reporter](INFO): platform_rudder_angle 0.247213 rad
2023-04-03T16:36:44.433Z,1680539804.433 [Reporter](INFO): platform_rudder_angle 0.261301 rad
2023-04-03T16:36:57.235Z,1680539817.235 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2023-04-03T16:36:58.673Z,1680539818.673 [Reporter](INFO): platform_rudder_angle 0.260725 rad
2023-04-03T16:36:59.070Z,1680539819.070 [Reporter](INFO): platform_rudder_angle 0.244103 rad
2023-04-03T16:36:59.441Z,1680539819.441 [Reporter](INFO): platform_rudder_angle 0.202952 rad
2023-04-03T16:36:59.891Z,1680539819.891 [Reporter](INFO): platform_rudder_angle 0.150516 rad
2023-04-03T16:37:00.273Z,1680539820.273 [Reporter](INFO): platform_rudder_angle 0.105257 rad
2023-04-03T16:37:00.662Z,1680539820.662 [Reporter](INFO): platform_rudder_angle 0.058847 rad
2023-04-03T16:37:01.058Z,1680539821.058 [Reporter](INFO): platform_rudder_angle 0.015009 rad
2023-04-03T16:37:01.429Z,1680539821.429 [Reporter](INFO): platform_rudder_angle -0.029827 rad
2023-04-03T16:37:01.838Z,1680539821.838 [Reporter](INFO): platform_rudder_angle -0.078348 rad
2023-04-03T16:37:02.265Z,1680539822.265 [Reporter](INFO): platform_rudder_angle -0.124144 rad
2023-04-03T16:37:02.721Z,1680539822.721 [Reporter](INFO): platform_rudder_angle -0.180035 rad
2023-04-03T16:37:03.065Z,1680539823.065 [Reporter](INFO): platform_rudder_angle -0.219574 rad
2023-04-03T16:37:03.457Z,1680539823.457 [Reporter](INFO): platform_rudder_angle -0.252510 rad
2023-04-03T16:37:03.897Z,1680539823.897 [Reporter](INFO): platform_rudder_angle -0.257654 rad
2023-04-03T16:37:04.297Z,1680539824.297 [Reporter](INFO): platform_rudder_angle -0.257692 rad
2023-04-03T16:37:05.085Z,1680539825.085 [Reporter](INFO): platform_rudder_angle -0.257654 rad
2023-04-03T16:37:06.309Z,1680539826.309 [Reporter](INFO): platform_rudder_angle -0.257692 rad
2023-04-03T16:37:06.717Z,1680539826.717 [Reporter](INFO): platform_rudder_angle -0.257654 rad
2023-04-03T16:37:07.521Z,1680539827.521 [Reporter](INFO): platform_rudder_angle -0.257692 rad
2023-04-03T16:37:07.866Z,1680539827.866 [CommandExec](IMPORTANT): got command maintain clear
2023-04-03T16:37:07.954Z,1680539827.954 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:37:08.341Z,1680539828.341 [Reporter](INFO): platform_rudder_angle -0.257654 rad
2023-04-03T16:37:08.702Z,1680539828.702 [Reporter](INFO): platform_rudder_angle -0.250591 rad
2023-04-03T16:37:09.121Z,1680539829.121 [Reporter](INFO): platform_rudder_angle -0.206254 rad
2023-04-03T16:37:09.569Z,1680539829.569 [Reporter](INFO): platform_rudder_angle -0.152243 rad
2023-04-03T16:37:09.910Z,1680539829.910 [Reporter](INFO): platform_rudder_angle -0.113434 rad
2023-04-03T16:37:10.322Z,1680539830.322 [Reporter](INFO): platform_rudder_angle -0.064222 rad
2023-04-03T16:37:10.753Z,1680539830.753 [Reporter](INFO): platform_rudder_angle -0.013819 rad
2023-04-03T16:37:11.173Z,1680539831.173 [Reporter](INFO): platform_rudder_angle 0.000883 rad
2023-04-03T16:37:11.562Z,1680539831.562 [Reporter](INFO): platform_rudder_angle 0.000307 rad
2023-04-03T16:37:28.367Z,1680539848.367 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle 1 degree persist
2023-04-03T16:37:31.428Z,1680539851.428 [CommandExec](IMPORTANT): got command failComponent hardware RudderServo
2023-04-03T16:37:31.428Z,1680539851.428 [RudderServo] Hardware Fault, FailCount= 1
2023-04-03T16:37:31.428Z,1680539851.428 [RudderServo](ERROR): Hardware Fault
2023-04-03T16:37:31.428Z,1680539851.428 [CommandExec](IMPORTANT): RudderServo failureMode is Hardware Fault
2023-04-03T16:37:31.726Z,1680539851.726 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-03T16:37:31.726Z,1680539851.726 [RudderServo](INFO): Powering down
2023-04-03T16:37:31.735Z,1680539851.735 [CBIT](ERROR): Hardware Fault in component: RudderServo
2023-04-03T16:37:32.517Z,1680539852.517 [CBIT](INFO): Clearing failed state for component RudderServo
2023-04-03T16:37:32.518Z,1680539852.518 [RudderServo] No Fault, FailCount= 1
2023-04-03T16:37:32.933Z,1680539852.933 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-03T16:37:32.934Z,1680539852.934 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-03T16:37:36.601Z,1680539856.601 [Reporter](INFO): platform_rudder_angle -0.019027 rad
2023-04-03T16:37:37.002Z,1680539857.002 [Reporter](INFO): platform_rudder_angle -0.003058 rad
2023-04-03T16:37:37.441Z,1680539857.441 [Reporter](INFO): platform_rudder_angle 0.000473 rad
2023-04-03T16:37:52.834Z,1680539872.834 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2023-04-03T16:37:52.836Z,1680539872.836 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2023-04-03T16:37:53.136Z,1680539873.136 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:37:54.801Z,1680539874.801 [Reporter](INFO): platform_rudder_angle 0.000128 rad
2023-04-03T16:37:55.182Z,1680539875.182 [Reporter](INFO): platform_rudder_angle -0.014728 rad
2023-04-03T16:37:55.596Z,1680539875.596 [Reporter](INFO): platform_rudder_angle -0.060754 rad
2023-04-03T16:37:55.989Z,1680539875.989 [Reporter](INFO): platform_rudder_angle -0.104477 rad
2023-04-03T16:37:56.393Z,1680539876.393 [Reporter](INFO): platform_rudder_angle -0.157796 rad
2023-04-03T16:37:56.868Z,1680539876.868 [Reporter](INFO): platform_rudder_angle -0.207546 rad
2023-04-03T16:37:57.185Z,1680539877.185 [Reporter](INFO): platform_rudder_angle -0.240098 rad
2023-04-03T16:37:57.657Z,1680539877.657 [Reporter](INFO): platform_rudder_angle -0.254839 rad
2023-04-03T16:37:58.037Z,1680539878.037 [Reporter](INFO): platform_rudder_angle -0.254916 rad
2023-04-03T16:37:58.471Z,1680539878.471 [Reporter](INFO): platform_rudder_angle -0.254877 rad
2023-04-03T16:37:59.723Z,1680539879.723 [Reporter](INFO): platform_rudder_angle -0.254916 rad
2023-04-03T16:38:00.069Z,1680539880.069 [Reporter](INFO): platform_rudder_angle -0.254877 rad
2023-04-03T16:38:00.457Z,1680539880.457 [Reporter](INFO): platform_rudder_angle -0.254839 rad
2023-04-03T16:38:00.849Z,1680539880.849 [Reporter](INFO): platform_rudder_angle -0.254916 rad
2023-04-03T16:38:01.261Z,1680539881.261 [Reporter](INFO): platform_rudder_angle -0.254877 rad
2023-04-03T16:38:02.069Z,1680539882.069 [Reporter](INFO): platform_rudder_angle -0.254916 rad
2023-04-03T16:38:03.271Z,1680539883.271 [Reporter](INFO): platform_rudder_angle -0.254877 rad
2023-04-03T16:38:04.073Z,1680539884.073 [Reporter](INFO): platform_rudder_angle -0.254839 rad
2023-04-03T16:38:05.293Z,1680539885.293 [Reporter](INFO): platform_rudder_angle -0.254877 rad
2023-04-03T16:38:06.109Z,1680539886.109 [Reporter](INFO): platform_rudder_angle -0.254801 rad
2023-04-03T16:38:06.529Z,1680539886.529 [Reporter](INFO): platform_rudder_angle -0.254839 rad
2023-04-03T16:38:06.917Z,1680539886.917 [Reporter](INFO): platform_rudder_angle -0.254877 rad
2023-04-03T16:38:07.234Z,1680539887.234 [CommandExec](IMPORTANT): got command maintain clear
2023-04-03T16:38:07.350Z,1680539887.350 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:38:07.721Z,1680539887.721 [Reporter](INFO): platform_rudder_angle -0.254839 rad
2023-04-03T16:38:08.121Z,1680539888.121 [Reporter](INFO): platform_rudder_angle -0.245664 rad
2023-04-03T16:38:08.494Z,1680539888.494 [Reporter](INFO): platform_rudder_angle -0.192230 rad
2023-04-03T16:38:08.931Z,1680539888.931 [Reporter](INFO): platform_rudder_angle -0.156261 rad
2023-04-03T16:38:09.322Z,1680539889.322 [Reporter](INFO): platform_rudder_angle -0.108507 rad
2023-04-03T16:38:09.713Z,1680539889.713 [Reporter](INFO): platform_rudder_angle -0.061061 rad
2023-04-03T16:38:10.130Z,1680539890.130 [Reporter](INFO): platform_rudder_angle -0.015342 rad
2023-04-03T16:38:10.537Z,1680539890.537 [Reporter](INFO): platform_rudder_angle 0.000013 rad
2023-04-03T16:38:33.185Z,1680539913.185 [CommandExec](IMPORTANT): got command get RudderServo.offsetAngle degree
2023-04-03T16:38:33.185Z,1680539913.185 [CommandExec](IMPORTANT): RudderServo.offsetAngle 1.000000 arcdeg
2023-04-03T16:38:46.745Z,1680539926.745 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle -1 degree persist
2023-04-03T16:38:51.372Z,1680539931.372 [CommandExec](IMPORTANT): got command failComponent hardware RudderServo
2023-04-03T16:38:51.372Z,1680539931.372 [RudderServo] Hardware Fault, FailCount= 1
2023-04-03T16:38:51.372Z,1680539931.372 [RudderServo](ERROR): Hardware Fault
2023-04-03T16:38:51.372Z,1680539931.372 [CommandExec](IMPORTANT): RudderServo failureMode is Hardware Fault
2023-04-03T16:38:51.742Z,1680539931.742 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-03T16:38:51.742Z,1680539931.742 [RudderServo](INFO): Powering down
2023-04-03T16:38:51.750Z,1680539931.750 [CBIT](ERROR): Hardware Fault in component: RudderServo
2023-04-03T16:38:52.562Z,1680539932.562 [CBIT](INFO): Clearing failed state for component RudderServo
2023-04-03T16:38:52.562Z,1680539932.562 [RudderServo] No Fault, FailCount= 1
2023-04-03T16:38:52.954Z,1680539932.954 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-03T16:38:52.957Z,1680539932.957 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-03T16:38:56.633Z,1680539936.633 [Reporter](INFO): platform_rudder_angle 0.034881 rad
2023-04-03T16:38:57.030Z,1680539937.030 [Reporter](INFO): platform_rudder_angle 0.017492 rad
2023-04-03T16:38:57.457Z,1680539937.457 [Reporter](INFO): platform_rudder_angle -0.000090 rad
2023-04-03T16:39:08.981Z,1680539948.981 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2023-04-03T16:39:08.983Z,1680539948.983 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2023-04-03T16:39:09.130Z,1680539949.130 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:39:10.749Z,1680539950.749 [Reporter](INFO): platform_rudder_angle -0.000051 rad
2023-04-03T16:39:11.149Z,1680539951.149 [Reporter](INFO): platform_rudder_angle -0.016135 rad
2023-04-03T16:39:11.554Z,1680539951.554 [Reporter](INFO): platform_rudder_angle -0.060895 rad
2023-04-03T16:39:11.966Z,1680539951.966 [Reporter](INFO): platform_rudder_angle -0.106499 rad
2023-04-03T16:39:12.385Z,1680539952.385 [Reporter](INFO): platform_rudder_angle -0.153523 rad
2023-04-03T16:39:12.766Z,1680539952.766 [Reporter](INFO): platform_rudder_angle -0.201583 rad
2023-04-03T16:39:13.125Z,1680539953.125 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T16:39:13.125Z,1680539953.125 [Default:CheckIn:C.Wait] Stopped
2023-04-03T16:39:13.125Z,1680539953.125 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T16:39:13.125Z,1680539953.125 [Default:CheckIn:D] Running Loop=1
2023-04-03T16:39:13.174Z,1680539953.174 [Reporter](INFO): platform_rudder_angle -0.243924 rad
2023-04-03T16:39:13.537Z,1680539953.537 [Default:CheckIn:D] Stopped
2023-04-03T16:39:13.537Z,1680539953.537 [Default:CheckIn:E] Running Loop=1
2023-04-03T16:39:13.623Z,1680539953.623 [Reporter](INFO): platform_rudder_angle -0.258665 rad
2023-04-03T16:39:13.954Z,1680539953.954 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.160864 min
2023-04-03T16:39:13.954Z,1680539953.954 [Default:CheckIn:E] Stopped
2023-04-03T16:39:13.954Z,1680539953.954 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T16:39:13.954Z,1680539953.954 [Default:CheckIn] Stopped
2023-04-03T16:39:13.954Z,1680539953.954 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T16:39:13.955Z,1680539953.955 [Default:CheckIn](INFO): Running loop #4
2023-04-03T16:39:13.955Z,1680539953.955 [Default:CheckIn] Running Loop=4
2023-04-03T16:39:13.955Z,1680539953.955 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T16:39:13.955Z,1680539953.955 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T16:39:14.033Z,1680539954.033 [Reporter](INFO): platform_rudder_angle -0.257398 rad
2023-04-03T16:39:14.403Z,1680539954.403 [Reporter](INFO): platform_rudder_angle -0.256822 rad
2023-04-03T16:39:14.830Z,1680539954.830 [Reporter](INFO): platform_rudder_angle -0.257436 rad
2023-04-03T16:39:15.225Z,1680539955.225 [Reporter](INFO): platform_rudder_angle -0.257782 rad
2023-04-03T16:39:15.633Z,1680539955.633 [Reporter](INFO): platform_rudder_angle -0.258012 rad
2023-04-03T16:39:15.939Z,1680539955.939 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163915.00,A,3648.16954,N,12147.28022,W,0.194,0.00,030423,,,A*72
2023-04-03T16:39:15.941Z,1680539955.941 [NAL9602](INFO): GPS fix at 20230403T163915: (36.802826, -121.788004)
2023-04-03T16:39:15.953Z,1680539955.953 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T16:39:15.953Z,1680539955.953 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T16:39:16.025Z,1680539956.025 [Reporter](INFO): platform_rudder_angle -0.258051 rad
2023-04-03T16:39:16.443Z,1680539956.443 [Reporter](INFO): platform_rudder_angle -0.258012 rad
2023-04-03T16:39:16.842Z,1680539956.842 [Reporter](INFO): platform_rudder_angle -0.257974 rad
2023-04-03T16:39:17.274Z,1680539957.274 [Reporter](INFO): platform_rudder_angle -0.258012 rad
2023-04-03T16:39:18.045Z,1680539958.045 [Reporter](INFO): platform_rudder_angle -0.258089 rad
2023-04-03T16:39:18.469Z,1680539958.469 [Reporter](INFO): platform_rudder_angle -0.258012 rad
2023-04-03T16:39:18.869Z,1680539958.869 [Reporter](INFO): platform_rudder_angle -0.257974 rad
2023-04-03T16:39:19.281Z,1680539959.281 [Reporter](INFO): platform_rudder_angle -0.258051 rad
2023-04-03T16:39:19.684Z,1680539959.684 [Reporter](INFO): platform_rudder_angle -0.257628 rad
2023-04-03T16:39:20.085Z,1680539960.085 [Reporter](INFO): platform_rudder_angle -0.258012 rad
2023-04-03T16:39:20.861Z,1680539960.861 [Reporter](INFO): platform_rudder_angle -0.258051 rad
2023-04-03T16:39:21.290Z,1680539961.290 [Reporter](INFO): platform_rudder_angle -0.258012 rad
2023-04-03T16:39:21.701Z,1680539961.701 [Reporter](INFO): platform_rudder_angle -0.258243 rad
2023-04-03T16:39:22.121Z,1680539962.121 [Reporter](INFO): platform_rudder_angle -0.258089 rad
2023-04-03T16:39:22.546Z,1680539962.546 [Reporter](INFO): platform_rudder_angle -0.258358 rad
2023-04-03T16:39:22.650Z,1680539962.650 [CommandExec](IMPORTANT): got command maintain clear
2023-04-03T16:39:22.926Z,1680539962.926 [Reporter](INFO): platform_rudder_angle -0.258089 rad
2023-04-03T16:39:22.930Z,1680539962.930 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:39:23.333Z,1680539963.333 [Reporter](INFO): platform_rudder_angle -0.258626 rad
2023-04-03T16:39:23.681Z,1680539963.681 [Reporter](INFO): platform_rudder_angle -0.253905 rad
2023-04-03T16:39:24.085Z,1680539964.085 [Reporter](INFO): platform_rudder_angle -0.213867 rad
2023-04-03T16:39:24.493Z,1680539964.493 [Reporter](INFO): platform_rudder_angle -0.159895 rad
2023-04-03T16:39:24.555Z,1680539964.555 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230403T152050/Courier0055.lzma
2023-04-03T16:39:24.870Z,1680539964.870 [Reporter](INFO): platform_rudder_angle -0.115328 rad
2023-04-03T16:39:25.278Z,1680539965.278 [Reporter](INFO): platform_rudder_angle -0.063812 rad
2023-04-03T16:39:25.558Z,1680539965.558 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0055.lzma.bak
2023-04-03T16:39:25.558Z,1680539965.558 [DataOverHttps](INFO): SBD MOMSN=18013175
2023-04-03T16:39:25.693Z,1680539965.693 [Reporter](INFO): platform_rudder_angle -0.019590 rad
2023-04-03T16:39:26.133Z,1680539966.133 [Reporter](INFO): platform_rudder_angle 0.001139 rad
2023-04-03T16:39:26.515Z,1680539966.515 [Reporter](INFO): platform_rudder_angle -0.000051 rad
2023-04-03T16:39:39.335Z,1680539979.335 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle -2 degree persist
2023-04-03T16:39:42.780Z,1680539982.780 [CommandExec](IMPORTANT): got command failComponent hardware RudderServo
2023-04-03T16:39:42.780Z,1680539982.780 [RudderServo] Hardware Fault, FailCount= 1
2023-04-03T16:39:42.780Z,1680539982.780 [RudderServo](ERROR): Hardware Fault
2023-04-03T16:39:42.780Z,1680539982.780 [CommandExec](IMPORTANT): RudderServo failureMode is Hardware Fault
2023-04-03T16:39:43.035Z,1680539983.035 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-03T16:39:43.035Z,1680539983.035 [RudderServo](INFO): Powering down
2023-04-03T16:39:43.041Z,1680539983.041 [DataOverHttps](INFO): Sending 512 bytes from file Logs/20230403T152050/Express0056.lzma
2023-04-03T16:39:43.047Z,1680539983.047 [CBIT](ERROR): Hardware Fault in component: RudderServo
2023-04-03T16:39:43.839Z,1680539983.839 [CBIT](INFO): Clearing failed state for component RudderServo
2023-04-03T16:39:43.839Z,1680539983.839 [RudderServo] No Fault, FailCount= 1
2023-04-03T16:39:44.042Z,1680539984.042 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0056.lzma.bak
2023-04-03T16:39:44.042Z,1680539984.042 [DataOverHttps](INFO): SBD MOMSN=18013178
2023-04-03T16:39:44.234Z,1680539984.234 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-03T16:39:44.235Z,1680539984.235 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-03T16:39:47.541Z,1680539987.541 [Reporter](INFO): platform_rudder_angle 0.017402 rad
2023-04-03T16:39:47.930Z,1680539987.930 [Reporter](INFO): platform_rudder_angle 0.004427 rad
2023-04-03T16:39:48.361Z,1680539988.361 [Reporter](INFO): platform_rudder_angle 0.000205 rad
2023-04-03T16:39:55.798Z,1680539995.798 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2023-04-03T16:39:55.800Z,1680539995.800 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2023-04-03T16:39:55.931Z,1680539995.931 [NAL9602](INFO): SBD MO Status=2, MOMSN=39574, MT Status=2, MTMSN=0
2023-04-03T16:39:55.931Z,1680539995.931 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-03T16:39:56.053Z,1680539996.053 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:39:57.653Z,1680539997.653 [Reporter](INFO): platform_rudder_angle 0.000166 rad
2023-04-03T16:39:58.009Z,1680539998.009 [Reporter](INFO): platform_rudder_angle -0.011465 rad
2023-04-03T16:39:58.433Z,1680539998.433 [Reporter](INFO): platform_rudder_angle -0.057568 rad
2023-04-03T16:39:58.846Z,1680539998.846 [Reporter](INFO): platform_rudder_angle -0.111041 rad
2023-04-03T16:39:59.248Z,1680539999.248 [Reporter](INFO): platform_rudder_angle -0.150349 rad
2023-04-03T16:39:59.657Z,1680539999.657 [Reporter](INFO): platform_rudder_angle -0.196798 rad
2023-04-03T16:40:00.022Z,1680540000.022 [Reporter](INFO): platform_rudder_angle -0.235377 rad
2023-04-03T16:40:00.452Z,1680540000.452 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20230403T152050/Express0059.lzma
2023-04-03T16:40:00.482Z,1680540000.482 [Reporter](INFO): platform_rudder_angle -0.252497 rad
2023-04-03T16:40:00.873Z,1680540000.873 [Reporter](INFO): platform_rudder_angle -0.252766 rad
2023-04-03T16:40:01.253Z,1680540001.253 [Reporter](INFO): platform_rudder_angle -0.252843 rad
2023-04-03T16:40:01.454Z,1680540001.454 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0059.lzma.bak
2023-04-03T16:40:01.454Z,1680540001.454 [DataOverHttps](INFO): SBD MOMSN=18013203
2023-04-03T16:40:02.817Z,1680540002.817 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T16:40:02.818Z,1680540002.818 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T16:40:02.818Z,1680540002.818 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T16:40:03.305Z,1680540003.305 [Reporter](INFO): platform_rudder_angle -0.252766 rad
2023-04-03T16:40:03.677Z,1680540003.677 [Reporter](INFO): platform_rudder_angle -0.252804 rad
2023-04-03T16:40:04.101Z,1680540004.101 [Reporter](INFO): platform_rudder_angle -0.252843 rad
2023-04-03T16:40:04.533Z,1680540004.533 [Reporter](INFO): platform_rudder_angle -0.252804 rad
2023-04-03T16:40:04.930Z,1680540004.930 [Reporter](INFO): platform_rudder_angle -0.252728 rad
2023-04-03T16:40:05.293Z,1680540005.293 [Reporter](INFO): platform_rudder_angle -0.252804 rad
2023-04-03T16:40:05.761Z,1680540005.761 [Reporter](INFO): platform_rudder_angle -0.252843 rad
2023-04-03T16:40:06.541Z,1680540006.541 [Reporter](INFO): platform_rudder_angle -0.252804 rad
2023-04-03T16:40:06.971Z,1680540006.971 [Reporter](INFO): platform_rudder_angle -0.252305 rad
2023-04-03T16:40:07.346Z,1680540007.346 [CommandExec](IMPORTANT): got command maintain clear
2023-04-03T16:40:07.370Z,1680540007.370 [Reporter](INFO): platform_rudder_angle -0.252843 rad
2023-04-03T16:40:07.374Z,1680540007.374 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:40:07.753Z,1680540007.753 [Reporter](INFO): platform_rudder_angle -0.252689 rad
2023-04-03T16:40:08.149Z,1680540008.149 [Reporter](INFO): platform_rudder_angle -0.241442 rad
2023-04-03T16:40:08.510Z,1680540008.510 [Reporter](INFO): platform_rudder_angle -0.206817 rad
2023-04-03T16:40:08.929Z,1680540008.929 [Reporter](INFO): platform_rudder_angle -0.161904 rad
2023-04-03T16:40:09.345Z,1680540009.345 [Reporter](INFO): platform_rudder_angle -0.109122 rad
2023-04-03T16:40:09.750Z,1680540009.750 [Reporter](INFO): platform_rudder_angle -0.057798 rad
2023-04-03T16:40:10.126Z,1680540010.126 [Reporter](INFO): platform_rudder_angle -0.017991 rad
2023-04-03T16:40:10.573Z,1680540010.573 [Reporter](INFO): platform_rudder_angle -0.000448 rad
2023-04-03T16:40:23.340Z,1680540023.340 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle 2 degree persist
2023-04-03T16:40:26.550Z,1680540026.550 [CommandExec](IMPORTANT): got command failComponent hardware RudderServo
2023-04-03T16:40:26.550Z,1680540026.550 [RudderServo] Hardware Fault, FailCount= 1
2023-04-03T16:40:26.550Z,1680540026.550 [RudderServo](ERROR): Hardware Fault
2023-04-03T16:40:26.550Z,1680540026.550 [CommandExec](IMPORTANT): RudderServo failureMode is Hardware Fault
2023-04-03T16:40:26.687Z,1680540026.687 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-03T16:40:26.688Z,1680540026.688 [RudderServo](INFO): Powering down
2023-04-03T16:40:26.692Z,1680540026.692 [CBIT](ERROR): Hardware Fault in component: RudderServo
2023-04-03T16:40:27.491Z,1680540027.491 [CBIT](INFO): Clearing failed state for component RudderServo
2023-04-03T16:40:27.491Z,1680540027.491 [RudderServo] No Fault, FailCount= 1
2023-04-03T16:40:27.862Z,1680540027.862 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-03T16:40:27.863Z,1680540027.863 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-03T16:40:31.153Z,1680540031.153 [Reporter](INFO): platform_rudder_angle -0.070184 rad
2023-04-03T16:40:31.561Z,1680540031.561 [Reporter](INFO): platform_rudder_angle -0.054868 rad
2023-04-03T16:40:31.953Z,1680540031.953 [Reporter](INFO): platform_rudder_angle -0.010646 rad
2023-04-03T16:40:32.357Z,1680540032.357 [Reporter](INFO): platform_rudder_angle 0.000410 rad
2023-04-03T16:40:41.814Z,1680540041.814 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2023-04-03T16:40:41.815Z,1680540041.815 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2023-04-03T16:40:42.031Z,1680540042.031 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:40:43.693Z,1680540043.693 [Reporter](INFO): platform_rudder_angle -0.001663 rad
2023-04-03T16:40:44.083Z,1680540044.083 [Reporter](INFO): platform_rudder_angle -0.018055 rad
2023-04-03T16:40:44.462Z,1680540044.462 [Reporter](INFO): platform_rudder_angle -0.060664 rad
2023-04-03T16:40:44.916Z,1680540044.916 [RudderServo](ERROR): getPosition uart error serial timeout
2023-04-03T16:40:44.916Z,1680540044.916 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2023-04-03T16:40:44.916Z,1680540044.916 [RudderServo] Communications Fault, FailCount= 1
2023-04-03T16:40:44.917Z,1680540044.917 [RudderServo](ERROR): Communications Fault
2023-04-03T16:40:44.946Z,1680540044.946 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-04-03T16:40:45.244Z,1680540045.244 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-03T16:40:45.244Z,1680540045.244 [RudderServo](INFO): Powering down
2023-04-03T16:40:46.043Z,1680540046.043 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-03T16:40:46.162Z,1680540046.162 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-03T16:40:46.172Z,1680540046.172 [CBIT](INFO): Clearing failed state for component RudderServo
2023-04-03T16:40:46.172Z,1680540046.172 [RudderServo] No Fault, FailCount= 1
2023-04-03T16:40:49.782Z,1680540049.782 [Reporter](INFO): platform_rudder_angle -0.158666 rad
2023-04-03T16:40:50.130Z,1680540050.130 [Reporter](INFO): platform_rudder_angle -0.156402 rad
2023-04-03T16:40:50.534Z,1680540050.534 [Reporter](INFO): platform_rudder_angle -0.210259 rad
2023-04-03T16:40:50.961Z,1680540050.961 [Reporter](INFO): platform_rudder_angle -0.251026 rad
2023-04-03T16:40:51.390Z,1680540051.390 [Reporter](INFO): platform_rudder_angle -0.257513 rad
2023-04-03T16:40:51.791Z,1680540051.791 [Reporter](INFO): platform_rudder_angle -0.257552 rad
2023-04-03T16:40:52.214Z,1680540052.214 [Reporter](INFO): platform_rudder_angle -0.257590 rad
2023-04-03T16:40:52.566Z,1680540052.566 [Reporter](INFO): platform_rudder_angle -0.257552 rad
2023-04-03T16:40:53.001Z,1680540053.001 [Reporter](INFO): platform_rudder_angle -0.257590 rad
2023-04-03T16:40:53.423Z,1680540053.423 [Reporter](INFO): platform_rudder_angle -0.257552 rad
2023-04-03T16:40:53.793Z,1680540053.793 [Reporter](INFO): platform_rudder_angle -0.257590 rad
2023-04-03T16:40:54.197Z,1680540054.197 [Reporter](INFO): platform_rudder_angle -0.257552 rad
2023-04-03T16:40:55.457Z,1680540055.457 [Reporter](INFO): platform_rudder_angle -0.257590 rad
2023-04-03T16:40:55.825Z,1680540055.825 [Reporter](INFO): platform_rudder_angle -0.257552 rad
2023-04-03T16:40:56.217Z,1680540056.217 [Reporter](INFO): platform_rudder_angle -0.257513 rad
2023-04-03T16:40:56.609Z,1680540056.609 [Reporter](INFO): platform_rudder_angle -0.257590 rad
2023-04-03T16:40:57.029Z,1680540057.029 [Reporter](INFO): platform_rudder_angle -0.257552 rad
2023-04-03T16:40:58.229Z,1680540058.229 [Reporter](INFO): platform_rudder_angle -0.257590 rad
2023-04-03T16:40:59.034Z,1680540059.034 [Reporter](INFO): platform_rudder_angle -0.257552 rad
2023-04-03T16:40:59.437Z,1680540059.437 [Reporter](INFO): platform_rudder_angle -0.257590 rad
2023-04-03T16:41:01.073Z,1680540061.073 [Reporter](INFO): platform_rudder_angle -0.257552 rad
2023-04-03T16:41:01.438Z,1680540061.438 [CommandExec](IMPORTANT): got command maintain clear
2023-04-03T16:41:01.477Z,1680540061.477 [Reporter](INFO): platform_rudder_angle -0.257590 rad
2023-04-03T16:41:01.482Z,1680540061.482 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:41:02.258Z,1680540062.258 [Reporter](INFO): platform_rudder_angle -0.245344 rad
2023-04-03T16:41:02.685Z,1680540062.685 [Reporter](INFO): platform_rudder_angle -0.204194 rad
2023-04-03T16:41:03.054Z,1680540063.054 [Reporter](INFO): platform_rudder_angle -0.148878 rad
2023-04-03T16:41:03.466Z,1680540063.466 [Reporter](INFO): platform_rudder_angle -0.105232 rad
2023-04-03T16:41:03.885Z,1680540063.885 [Reporter](INFO): platform_rudder_angle -0.055751 rad
2023-04-03T16:41:04.258Z,1680540064.258 [Reporter](INFO): platform_rudder_angle -0.015214 rad
2023-04-03T16:41:04.689Z,1680540064.689 [Reporter](INFO): platform_rudder_angle 0.000486 rad
2023-04-03T16:41:28.043Z,1680540088.043 [NAL9602](INFO): SBD MO Status=0, MOMSN=39574, MT Status=0, MTMSN=0
2023-04-03T16:41:28.043Z,1680540088.043 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:41:34.379Z,1680540094.379 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle 0 degree persist
2023-04-03T16:41:39.906Z,1680540099.906 [CommandExec](IMPORTANT): got command show variable limitLo
2023-04-03T16:41:39.940Z,1680540099.940 [CommandExec](IMPORTANT): VerticalControl.buoyancyLimitLoCC (cubic_centimeter)
2023-04-03T16:41:39.948Z,1680540099.948 [CommandExec](IMPORTANT): BuoyancyServo.limitLo (count)
2023-04-03T16:41:39.950Z,1680540099.950 [CommandExec](IMPORTANT): DockingServo.limitLo (count)
2023-04-03T16:41:39.951Z,1680540099.951 [CommandExec](IMPORTANT): ElevatorServo.limitLo (count)
2023-04-03T16:41:39.965Z,1680540099.965 [CommandExec](IMPORTANT): MassServo.limitLo (count)
2023-04-03T16:41:39.966Z,1680540099.966 [CommandExec](IMPORTANT): RudderServo.limitLo (count)
2023-04-03T16:41:49.158Z,1680540109.158 [CommandExec](IMPORTANT): got command get RudderServo.limitLo
2023-04-03T16:41:49.159Z,1680540109.159 [CommandExec](IMPORTANT): RudderServo.limitLo 0 count
2023-04-03T16:41:54.802Z,1680540114.802 [CommandExec](IMPORTANT): got command show variable rudderServo
2023-04-03T16:41:54.872Z,1680540114.872 [CommandExec](IMPORTANT): RudderServo.loadAtStartup (bool)
2023-04-03T16:41:54.873Z,1680540114.873 [CommandExec](IMPORTANT): RudderServo.simulateHardware (bool)
2023-04-03T16:41:54.873Z,1680540114.873 [CommandExec](IMPORTANT): RudderServo.countsPerDeg (count_per_angular_degree)
2023-04-03T16:41:54.873Z,1680540114.873 [CommandExec](IMPORTANT): RudderServo.currLimit (percent)
2023-04-03T16:41:54.874Z,1680540114.874 [CommandExec](IMPORTANT): RudderServo.deviationAngle (angular_degree)
2023-04-03T16:41:54.874Z,1680540114.874 [CommandExec](IMPORTANT): RudderServo.limitHi (count)
2023-04-03T16:41:54.874Z,1680540114.874 [CommandExec](IMPORTANT): RudderServo.limitLo (count)
2023-04-03T16:41:54.875Z,1680540114.875 [CommandExec](IMPORTANT): RudderServo.mtrCenter (count)
2023-04-03T16:41:54.875Z,1680540114.875 [CommandExec](IMPORTANT): RudderServo.offsetAngle (degree)
2023-04-03T16:41:54.876Z,1680540114.876 [CommandExec](IMPORTANT): RudderServo.pidW (count)
2023-04-03T16:41:54.876Z,1680540114.876 [CommandExec](IMPORTANT): RudderServo.pidX (count)
2023-04-03T16:41:54.876Z,1680540114.876 [CommandExec](IMPORTANT): RudderServo.pidY (count)
2023-04-03T16:41:54.877Z,1680540114.877 [CommandExec](IMPORTANT): RudderServo.powerOnTimeout (second)
2023-04-03T16:41:54.955Z,1680540114.955 [CommandExec](IMPORTANT): RudderServo.loadControl (none)
2023-04-03T16:41:54.955Z,1680540114.955 [CommandExec](IMPORTANT): RudderServo.uart (none)
2023-04-03T16:41:54.956Z,1680540114.956 [CommandExec](IMPORTANT): RudderServo.baud (bit_per_second)
2023-04-03T16:41:55.204Z,1680540115.204 [CommandExec](IMPORTANT): RudderServo.enableBroadcast (bool)
2023-04-03T16:41:55.205Z,1680540115.205 [CommandExec](IMPORTANT): RudderServo.component_voltage (volt)
2023-04-03T16:41:55.205Z,1680540115.205 [CommandExec](IMPORTANT): RudderServo.component_avgVoltage (volt)
2023-04-03T16:41:55.205Z,1680540115.205 [CommandExec](IMPORTANT): RudderServo.component_current (milliampere)
2023-04-03T16:41:55.206Z,1680540115.206 [CommandExec](IMPORTANT): RudderServo.component_avgCurrent (milliampere)
2023-04-03T16:41:55.206Z,1680540115.206 [CommandExec](IMPORTANT): RudderServo.platform_rudder_angle (radian)
2023-04-03T16:41:58.744Z,1680540118.744 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:42:24.243Z,1680540144.243 [CommandExec](IMPORTANT): got command get RudderServo.mtrCenter
2023-04-03T16:42:24.243Z,1680540144.243 [CommandExec](IMPORTANT): RudderServo.mtrCenter 8184 count
2023-04-03T16:42:44.659Z,1680540164.659 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle 5 degree persist
2023-04-03T16:42:47.498Z,1680540167.498 [CommandExec](IMPORTANT): got command failComponent hardware RudderServo
2023-04-03T16:42:47.498Z,1680540167.498 [RudderServo] Hardware Fault, FailCount= 1
2023-04-03T16:42:47.498Z,1680540167.498 [RudderServo](ERROR): Hardware Fault
2023-04-03T16:42:47.499Z,1680540167.499 [CommandExec](IMPORTANT): RudderServo failureMode is Hardware Fault
2023-04-03T16:42:47.667Z,1680540167.667 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-03T16:42:47.667Z,1680540167.667 [RudderServo](INFO): Powering down
2023-04-03T16:42:47.672Z,1680540167.672 [CBIT](ERROR): Hardware Fault in component: RudderServo
2023-04-03T16:42:48.473Z,1680540168.473 [CBIT](INFO): Clearing failed state for component RudderServo
2023-04-03T16:42:48.473Z,1680540168.473 [RudderServo] No Fault, FailCount= 1
2023-04-03T16:42:48.890Z,1680540168.890 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-03T16:42:48.891Z,1680540168.891 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-03T16:42:52.597Z,1680540172.597 [Reporter](INFO): platform_rudder_angle -0.051989 rad
2023-04-03T16:42:52.978Z,1680540172.978 [Reporter](INFO): platform_rudder_angle -0.037210 rad
2023-04-03T16:42:53.393Z,1680540173.393 [Reporter](INFO): platform_rudder_angle -0.003429 rad
2023-04-03T16:42:53.873Z,1680540173.873 [Reporter](INFO): platform_rudder_angle 0.000832 rad
2023-04-03T16:43:31.530Z,1680540211.530 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2023-04-03T16:43:31.531Z,1680540211.531 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2023-04-03T16:43:31.709Z,1680540211.709 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:43:33.377Z,1680540213.377 [Reporter](INFO): platform_rudder_angle 0.000218 rad
2023-04-03T16:43:33.767Z,1680540213.767 [Reporter](INFO): platform_rudder_angle -0.017172 rad
2023-04-03T16:43:34.153Z,1680540214.153 [Reporter](INFO): platform_rudder_angle -0.058783 rad
2023-04-03T16:43:34.569Z,1680540214.569 [Reporter](INFO): platform_rudder_angle -0.105424 rad
2023-04-03T16:43:34.989Z,1680540214.989 [Reporter](INFO): platform_rudder_angle -0.153676 rad
2023-04-03T16:43:35.365Z,1680540215.365 [Reporter](INFO): platform_rudder_angle -0.195403 rad
2023-04-03T16:43:35.762Z,1680540215.762 [Reporter](INFO): platform_rudder_angle -0.244692 rad
2023-04-03T16:43:36.189Z,1680540216.189 [Reporter](INFO): platform_rudder_angle -0.258396 rad
2023-04-03T16:43:36.609Z,1680540216.609 [Reporter](INFO): platform_rudder_angle -0.258358 rad
2023-04-03T16:43:37.421Z,1680540217.421 [Reporter](INFO): platform_rudder_angle -0.258396 rad
2023-04-03T16:43:37.821Z,1680540217.821 [Reporter](INFO): platform_rudder_angle -0.258358 rad
2023-04-03T16:43:38.657Z,1680540218.657 [Reporter](INFO): platform_rudder_angle -0.258434 rad
2023-04-03T16:43:39.039Z,1680540219.039 [Reporter](INFO): platform_rudder_angle -0.258473 rad
2023-04-03T16:43:39.453Z,1680540219.453 [Reporter](INFO): platform_rudder_angle -0.258396 rad
2023-04-03T16:43:39.869Z,1680540219.869 [Reporter](INFO): platform_rudder_angle -0.258358 rad
2023-04-03T16:43:40.285Z,1680540220.285 [Reporter](INFO): platform_rudder_angle -0.258511 rad
2023-04-03T16:43:40.673Z,1680540220.673 [Reporter](INFO): platform_rudder_angle -0.258473 rad
2023-04-03T16:43:41.075Z,1680540221.075 [Reporter](INFO): platform_rudder_angle -0.258319 rad
2023-04-03T16:43:41.465Z,1680540221.465 [Reporter](INFO): platform_rudder_angle -0.258396 rad
2023-04-03T16:43:42.317Z,1680540222.317 [Reporter](INFO): platform_rudder_angle -0.258473 rad
2023-04-03T16:43:42.661Z,1680540222.661 [Reporter](INFO): platform_rudder_angle -0.258396 rad
2023-04-03T16:43:43.086Z,1680540223.086 [Reporter](INFO): platform_rudder_angle -0.258511 rad
2023-04-03T16:43:43.471Z,1680540223.471 [Reporter](INFO): platform_rudder_angle -0.258319 rad
2023-04-03T16:43:43.881Z,1680540223.881 [Reporter](INFO): platform_rudder_angle -0.258396 rad
2023-04-03T16:43:44.701Z,1680540224.701 [Reporter](INFO): platform_rudder_angle -0.258358 rad
2023-04-03T16:43:45.083Z,1680540225.083 [Reporter](INFO): platform_rudder_angle -0.258319 rad
2023-04-03T16:43:45.318Z,1680540225.318 [CommandExec](IMPORTANT): got command maintain clear
2023-04-03T16:43:45.509Z,1680540225.509 [Reporter](INFO): platform_rudder_angle -0.258358 rad
2023-04-03T16:43:45.515Z,1680540225.515 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:43:45.889Z,1680540225.889 [Reporter](INFO): platform_rudder_angle -0.258396 rad
2023-04-03T16:43:46.289Z,1680540226.289 [Reporter](INFO): platform_rudder_angle -0.247724 rad
2023-04-03T16:43:46.685Z,1680540226.685 [Reporter](INFO): platform_rudder_angle -0.204577 rad
2023-04-03T16:43:47.110Z,1680540227.110 [Reporter](INFO): platform_rudder_angle -0.152371 rad
2023-04-03T16:43:47.477Z,1680540227.477 [Reporter](INFO): platform_rudder_angle -0.108111 rad
2023-04-03T16:43:47.890Z,1680540227.890 [Reporter](INFO): platform_rudder_angle -0.059973 rad
2023-04-03T16:43:48.306Z,1680540228.306 [Reporter](INFO): platform_rudder_angle -0.013909 rad
2023-04-03T16:43:48.733Z,1680540228.733 [Reporter](INFO): platform_rudder_angle 0.000218 rad
2023-04-03T16:45:03.408Z,1680540303.408 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-03T16:45:03.409Z,1680540303.409 [Default:CheckIn:C.Wait] Stopped
2023-04-03T16:45:03.409Z,1680540303.409 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T16:45:03.409Z,1680540303.409 [Default:CheckIn:D] Running Loop=1
2023-04-03T16:45:03.853Z,1680540303.853 [Default:CheckIn:D] Stopped
2023-04-03T16:45:03.853Z,1680540303.853 [Default:CheckIn:E] Running Loop=1
2023-04-03T16:45:04.222Z,1680540304.222 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.999463 min
2023-04-03T16:45:04.222Z,1680540304.222 [Default:CheckIn:E] Stopped
2023-04-03T16:45:04.222Z,1680540304.222 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-03T16:45:04.222Z,1680540304.222 [Default:CheckIn] Stopped
2023-04-03T16:45:04.222Z,1680540304.222 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T16:45:04.222Z,1680540304.222 [Default:CheckIn](INFO): Running loop #5
2023-04-03T16:45:04.223Z,1680540304.223 [Default:CheckIn] Running Loop=5
2023-04-03T16:45:04.223Z,1680540304.223 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-03T16:45:04.223Z,1680540304.223 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-03T16:45:06.228Z,1680540306.228 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164505.00,A,3648.16400,N,12147.28523,W,0.175,208.55,030423,,,A*75
2023-04-03T16:45:06.230Z,1680540306.230 [NAL9602](INFO): GPS fix at 20230403T164505: (36.802733, -121.788087)
2023-04-03T16:45:06.241Z,1680540306.241 [Default:CheckIn:Read_GPS] Stopped
2023-04-03T16:45:06.241Z,1680540306.241 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-03T16:45:13.628Z,1680540313.628 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230403T152050/Courier0061.lzma
2023-04-03T16:45:14.630Z,1680540314.630 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Courier0061.lzma.bak
2023-04-03T16:45:14.630Z,1680540314.630 [DataOverHttps](INFO): SBD MOMSN=18013218
2023-04-03T16:45:15.523Z,1680540315.523 [NAL9602](INFO): SBD MO Status=0, MOMSN=39575, MT Status=0, MTMSN=0
2023-04-03T16:45:15.523Z,1680540315.523 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:45:30.417Z,1680540330.417 [DataOverHttps](INFO): Sending 907 bytes from file Logs/20230403T152050/Express0062.lzma
2023-04-03T16:45:31.418Z,1680540331.418 [DataOverHttps](INFO): Moved sent file to Logs/20230403T152050/Express0062.lzma.bak
2023-04-03T16:45:31.418Z,1680540331.418 [DataOverHttps](INFO): SBD MOMSN=18013221
2023-04-03T16:45:32.515Z,1680540332.515 [Default:CheckIn:Read_Iridium] Stopped
2023-04-03T16:45:32.515Z,1680540332.515 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-03T16:45:32.515Z,1680540332.515 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-03T16:45:46.222Z,1680540346.222 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:45:46.685Z,1680540346.685 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle -5 degree persist
2023-04-03T16:45:58.092Z,1680540358.092 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2023-04-03T16:45:58.094Z,1680540358.094 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2023-04-03T16:45:58.365Z,1680540358.365 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:46:00.069Z,1680540360.069 [Reporter](INFO): platform_rudder_angle 0.174827 rad
2023-04-03T16:46:00.434Z,1680540360.434 [Reporter](INFO): platform_rudder_angle 0.162467 rad
2023-04-03T16:46:00.845Z,1680540360.845 [Reporter](INFO): platform_rudder_angle 0.116940 rad
2023-04-03T16:46:01.214Z,1680540361.214 [Reporter](INFO): platform_rudder_angle 0.072065 rad
2023-04-03T16:46:01.625Z,1680540361.625 [Reporter](INFO): platform_rudder_angle 0.024926 rad
2023-04-03T16:46:02.045Z,1680540362.045 [Reporter](INFO): platform_rudder_angle -0.022290 rad
2023-04-03T16:46:02.453Z,1680540362.453 [Reporter](INFO): platform_rudder_angle -0.064247 rad
2023-04-03T16:46:02.858Z,1680540362.858 [Reporter](INFO): platform_rudder_angle -0.116300 rad
2023-04-03T16:46:03.245Z,1680540363.245 [Reporter](INFO): platform_rudder_angle -0.161405 rad
2023-04-03T16:46:03.650Z,1680540363.650 [Reporter](INFO): platform_rudder_angle -0.202287 rad
2023-04-03T16:46:04.093Z,1680540364.093 [Reporter](INFO): platform_rudder_angle -0.206663 rad
2023-04-03T16:46:04.868Z,1680540364.868 [RudderServo](FAULT): Overload Error
2023-04-03T16:46:04.868Z,1680540364.868 [RudderServo] Hardware Fault, FailCount= 1
2023-04-03T16:46:04.868Z,1680540364.868 [RudderServo](ERROR): Hardware Fault
2023-04-03T16:46:04.873Z,1680540364.873 [CBIT](ERROR): Hardware Fault in component: RudderServo
2023-04-03T16:46:05.226Z,1680540365.226 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-03T16:46:05.226Z,1680540365.226 [RudderServo](INFO): Powering down
2023-04-03T16:46:06.072Z,1680540366.072 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-03T16:46:06.194Z,1680540366.194 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-03T16:46:06.198Z,1680540366.198 [CBIT](INFO): Clearing failed state for component RudderServo
2023-04-03T16:46:06.198Z,1680540366.198 [RudderServo] No Fault, FailCount= 1
2023-04-03T16:46:09.725Z,1680540369.725 [Reporter](INFO): platform_rudder_angle -0.198679 rad
2023-04-03T16:46:10.126Z,1680540370.126 [Reporter](INFO): platform_rudder_angle -0.204821 rad
2023-04-03T16:46:10.214Z,1680540370.214 [CommandExec](IMPORTANT): got command maintain clear
2023-04-03T16:46:10.549Z,1680540370.549 [Reporter](INFO): platform_rudder_angle -0.206663 rad
2023-04-03T16:46:10.554Z,1680540370.554 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:46:11.735Z,1680540371.735 [Reporter](INFO): platform_rudder_angle -0.169236 rad
2023-04-03T16:46:12.145Z,1680540372.145 [Reporter](INFO): platform_rudder_angle -0.120139 rad
2023-04-03T16:46:12.573Z,1680540372.573 [Reporter](INFO): platform_rudder_angle -0.074689 rad
2023-04-03T16:46:12.994Z,1680540372.994 [Reporter](INFO): platform_rudder_angle -0.015841 rad
2023-04-03T16:46:13.365Z,1680540373.365 [Reporter](INFO): platform_rudder_angle -0.001600 rad
2023-04-03T16:46:13.773Z,1680540373.773 [Reporter](INFO): platform_rudder_angle -0.000486 rad
2023-04-03T16:46:21.392Z,1680540381.392 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle 5 degree persist
2023-04-03T16:46:30.939Z,1680540390.939 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2023-04-03T16:46:30.940Z,1680540390.940 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2023-04-03T16:46:31.117Z,1680540391.117 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:46:32.777Z,1680540392.777 [Reporter](INFO): platform_rudder_angle -0.175250 rad
2023-04-03T16:46:33.134Z,1680540393.134 [Reporter](INFO): platform_rudder_angle -0.188340 rad
2023-04-03T16:46:33.570Z,1680540393.570 [Reporter](INFO): platform_rudder_angle -0.233713 rad
2023-04-03T16:46:33.978Z,1680540393.978 [Reporter](INFO): platform_rudder_angle -0.258473 rad
2023-04-03T16:46:34.383Z,1680540394.383 [Reporter](INFO): platform_rudder_angle -0.258550 rad
2023-04-03T16:46:34.797Z,1680540394.797 [Reporter](INFO): platform_rudder_angle -0.258473 rad
2023-04-03T16:46:35.613Z,1680540395.613 [Reporter](INFO): platform_rudder_angle -0.258396 rad
2023-04-03T16:46:36.085Z,1680540396.085 [Reporter](INFO): platform_rudder_angle -0.258550 rad
2023-04-03T16:46:36.421Z,1680540396.421 [Reporter](INFO): platform_rudder_angle -0.258703 rad
2023-04-03T16:46:36.817Z,1680540396.817 [Reporter](INFO): platform_rudder_angle -0.258780 rad
2023-04-03T16:46:37.257Z,1680540397.257 [Reporter](INFO): platform_rudder_angle -0.258434 rad
2023-04-03T16:46:38.081Z,1680540398.081 [Reporter](INFO): platform_rudder_angle -0.258319 rad
2023-04-03T16:46:38.453Z,1680540398.453 [Reporter](INFO): platform_rudder_angle -0.258473 rad
2023-04-03T16:46:38.867Z,1680540398.867 [Reporter](INFO): platform_rudder_angle -0.258780 rad
2023-04-03T16:46:39.273Z,1680540399.273 [Reporter](INFO): platform_rudder_angle -0.258857 rad
2023-04-03T16:46:39.669Z,1680540399.669 [Reporter](INFO): platform_rudder_angle -0.258665 rad
2023-04-03T16:46:39.910Z,1680540399.910 [CommandExec](IMPORTANT): got command maintain clear
2023-04-03T16:46:40.079Z,1680540400.079 [Reporter](INFO): platform_rudder_angle -0.258703 rad
2023-04-03T16:46:40.083Z,1680540400.083 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-03T16:46:40.433Z,1680540400.433 [Reporter](INFO): platform_rudder_angle -0.258550 rad
2023-04-03T16:46:40.836Z,1680540400.836 [Reporter](INFO): platform_rudder_angle -0.245344 rad
2023-04-03T16:46:41.233Z,1680540401.233 [Reporter](INFO): platform_rudder_angle -0.202543 rad
2023-04-03T16:46:41.646Z,1680540401.646 [Reporter](INFO): platform_rudder_angle -0.151642 rad
2023-04-03T16:46:42.037Z,1680540402.037 [Reporter](INFO): platform_rudder_angle -0.108187 rad
2023-04-03T16:46:42.438Z,1680540402.438 [Reporter](INFO): platform_rudder_angle -0.059282 rad
2023-04-03T16:46:42.858Z,1680540402.858 [Reporter](INFO): platform_rudder_angle -0.012680 rad
2023-04-03T16:46:43.293Z,1680540403.293 [Reporter](INFO): platform_rudder_angle 0.000218 rad
2023-04-03T16:46:51.650Z,1680540411.650 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle 0 degree persist
2023-04-03T16:46:53.489Z,1680540413.489 [CommandExec](IMPORTANT): got command ibit
2023-04-03T16:46:53.709Z,1680540413.709 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-04-03T16:46:53.709Z,1680540413.709 [IBIT](IMPORTANT): Beginning control surface checks.
2023-04-03T16:46:53.721Z,1680540413.721 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-03T16:46:55.313Z,1680540415.313 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164654.00,A,3648.17426,N,12147.27986,W,0.272,206.52,030423,,,A*76
2023-04-03T16:46:55.316Z,1680540415.316 [NAL9602](INFO): GPS fix at 20230403T164654: (36.802904, -121.787998)
2023-04-03T16:46:55.866Z,1680540415.866 [Reporter](INFO): platform_rudder_angle 0.087484 rad
2023-04-03T16:46:56.195Z,1680540416.195 [Reporter](INFO): platform_rudder_angle 0.098079 rad
2023-04-03T16:46:56.625Z,1680540416.625 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-04-03T16:46:56.626Z,1680540416.626 [Reporter](INFO): platform_rudder_angle 0.143184 rad
2023-04-03T16:46:57.018Z,1680540417.018 [Reporter](INFO): platform_rudder_angle 0.190784 rad
2023-04-03T16:46:57.431Z,1680540417.431 [Reporter](INFO): platform_rudder_angle 0.238614 rad
2023-04-03T16:46:57.885Z,1680540417.885 [Reporter](INFO): platform_rudder_angle 0.260687 rad
2023-04-03T16:46:58.242Z,1680540418.242 [Reporter](INFO): platform_rudder_angle 0.261915 rad
2023-04-03T16:47:04.625Z,1680540424.625 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002688
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002074
CHAN A3 (5V): -0.002181
CHAN B0 (3.3V): -0.000078
CHAN B1 (3.15aV): -0.000334
CHAN B2 (3.15bV): -0.000036
CHAN B3 (GND): 0.000112
OPEN: -0.000476
Full Scale: +/- 1 mA
2023-04-03T16:47:06.219Z,1680540426.219 [NAL9602](INFO): SBD MO Status=0, MOMSN=39576, MT Status=0, MTMSN=0
2023-04-03T16:47:06.219Z,1680540426.219 [NAL9602](INFO): No messages in MT queue
2023-04-03T16:47:07.427Z,1680540427.427 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164706.00,A,3648.17401,N,12147.27981,W,0.292,206.52,030423,,,A*7C
2023-04-03T16:47:07.429Z,1680540427.429 [NAL9602](INFO): GPS fix at 20230403T164706: (36.802900, -121.787997)
2023-04-03T16:47:09.851Z,1680540429.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164709.00,A,3648.17398,N,12147.27978,W,0.214,206.52,030423,,,A*7C
2023-04-03T16:47:09.853Z,1680540429.853 [NAL9602](INFO): GPS fix at 20230403T164709: (36.802900, -121.787996)
2023-04-03T16:47:10.797Z,1680540430.797 [Reporter](INFO): platform_rudder_angle 0.262529 rad
2023-04-03T16:47:11.154Z,1680540431.154 [Reporter](INFO): platform_rudder_angle 0.248710 rad
2023-04-03T16:47:11.562Z,1680540431.562 [Reporter](INFO): platform_rudder_angle 0.204450 rad
2023-04-03T16:47:11.974Z,1680540431.974 [Reporter](INFO): platform_rudder_angle 0.156274 rad
2023-04-03T16:47:12.354Z,1680540432.354 [Reporter](INFO): platform_rudder_angle 0.112090 rad
2023-04-03T16:47:12.786Z,1680540432.786 [Reporter](INFO): platform_rudder_angle 0.061074 rad
2023-04-03T16:47:13.087Z,1680540433.087 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164712.00,A,3648.17401,N,12147.27969,W,0.156,206.52,030423,,,A*74
2023-04-03T16:47:13.098Z,1680540433.098 [NAL9602](INFO): GPS fix at 20230403T164712: (36.802900, -121.787995)
2023-04-03T16:47:13.190Z,1680540433.190 [Reporter](INFO): platform_rudder_angle 0.012553 rad
2023-04-03T16:47:13.590Z,1680540433.590 [Reporter](INFO): platform_rudder_angle -0.031708 rad
2023-04-03T16:47:13.966Z,1680540433.966 [Reporter](INFO): platform_rudder_angle -0.077120 rad
2023-04-03T16:47:14.393Z,1680540434.393 [Reporter](INFO): platform_rudder_angle -0.117580 rad
2023-04-03T16:47:14.798Z,1680540434.798 [Reporter](INFO): platform_rudder_angle -0.170707 rad
2023-04-03T16:47:15.202Z,1680540435.202 [Reporter](INFO): platform_rudder_angle -0.219420 rad
2023-04-03T16:47:15.618Z,1680540435.618 [Reporter](INFO): platform_rudder_angle -0.256502 rad
2023-04-03T16:47:15.913Z,1680540435.913 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164715.00,A,3648.17404,N,12147.27964,W,0.136,206.52,030423,,,A*7D
2023-04-03T16:47:15.915Z,1680540435.915 [NAL9602](INFO): GPS fix at 20230403T164715: (36.802901, -121.787994)
2023-04-03T16:47:16.050Z,1680540436.050 [Reporter](INFO): platform_rudder_angle -0.258345 rad
2023-04-03T16:47:16.514Z,1680540436.514 [Reporter](INFO): platform_rudder_angle -0.258882 rad
2023-04-03T16:47:16.818Z,1680540436.818 [Reporter](INFO): platform_rudder_angle -0.258690 rad
2023-04-03T16:47:17.249Z,1680540437.249 [Reporter](INFO): platform_rudder_angle -0.258806 rad
2023-04-03T16:47:17.622Z,1680540437.622 [Reporter](INFO): platform_rudder_angle -0.258575 rad
2023-04-03T16:47:18.037Z,1680540438.037 [Reporter](INFO): platform_rudder_angle -0.258882 rad
2023-04-03T16:47:18.402Z,1680540438.402 [Reporter](INFO): platform_rudder_angle -0.258307 rad
2023-04-03T16:47:18.862Z,1680540438.862 [Reporter](INFO): platform_rudder_angle -0.258422 rad
2023-04-03T16:47:19.149Z,1680540439.149 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164718.00,A,3648.17391,N,12147.27966,W,0.175,206.52,030423,,,A*7E
2023-04-03T16:47:19.151Z,1680540439.151 [NAL9602](INFO): GPS fix at 20230403T164718: (36.802898, -121.787994)
2023-04-03T16:47:19.246Z,1680540439.246 [Reporter](INFO): platform_rudder_angle -0.258460 rad
2023-04-03T16:47:19.642Z,1680540439.642 [Reporter](INFO): platform_rudder_angle -0.258498 rad
2023-04-03T16:47:20.022Z,1680540440.022 [Reporter](INFO): platform_rudder_angle -0.258307 rad
2023-04-03T16:47:20.461Z,1680540440.461 [Reporter](INFO): platform_rudder_angle -0.258460 rad
2023-04-03T16:47:20.869Z,1680540440.869 [Reporter](INFO): platform_rudder_angle -0.258422 rad
2023-04-03T16:47:21.242Z,1680540441.242 [Reporter](INFO): platform_rudder_angle -0.258460 rad
2023-04-03T16:47:21.675Z,1680540441.675 [Reporter](INFO): platform_rudder_angle -0.258844 rad
2023-04-03T16:47:21.974Z,1680540441.974 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164721.00,A,3648.17389,N,12147.27963,W,0.117,206.52,030423,,,A*7C
2023-04-03T16:47:21.976Z,1680540441.976 [NAL9602](INFO): GPS fix at 20230403T164721: (36.802898, -121.787994)
2023-04-03T16:47:22.074Z,1680540442.074 [Reporter](INFO): platform_rudder_angle -0.258882 rad
2023-04-03T16:47:22.478Z,1680540442.478 [Reporter](INFO): platform_rudder_angle -0.258614 rad
2023-04-03T16:47:22.853Z,1680540442.853 [Reporter](INFO): platform_rudder_angle -0.258882 rad
2023-04-03T16:47:23.310Z,1680540443.310 [Reporter](INFO): platform_rudder_angle -0.258767 rad
2023-04-03T16:47:23.682Z,1680540443.682 [Reporter](INFO): platform_rudder_angle -0.258844 rad
2023-04-03T16:47:24.082Z,1680540444.082 [Reporter](INFO): platform_rudder_angle -0.258806 rad
2023-04-03T16:47:24.501Z,1680540444.501 [Reporter](INFO): platform_rudder_angle -0.258882 rad
2023-04-03T16:47:24.803Z,1680540444.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164724.00,A,3648.17365,N,12147.27974,W,0.214,206.52,030423,,,A*7D
2023-04-03T16:47:24.806Z,1680540444.806 [NAL9602](INFO): GPS fix at 20230403T164724: (36.802894, -121.787996)
2023-04-03T16:47:24.882Z,1680540444.882 [Reporter](INFO): platform_rudder_angle -0.258844 rad
2023-04-03T16:47:25.270Z,1680540445.270 [Reporter](INFO): platform_rudder_angle -0.245370 rad
2023-04-03T16:47:25.686Z,1680540445.686 [Reporter](INFO): platform_rudder_angle -0.201071 rad
2023-04-03T16:47:26.150Z,1680540446.150 [Reporter](INFO): platform_rudder_angle -0.143184 rad
2023-04-03T16:47:26.494Z,1680540446.494 [Reporter](INFO): platform_rudder_angle -0.104797 rad
2023-04-03T16:47:26.910Z,1680540446.910 [Reporter](INFO): platform_rudder_angle -0.057120 rad
2023-04-03T16:47:27.310Z,1680540447.310 [Reporter](INFO): platform_rudder_angle -0.012207 rad
2023-04-03T16:47:27.774Z,1680540447.774 [Reporter](INFO): platform_rudder_angle 0.000307 rad
2023-04-03T16:47:28.035Z,1680540448.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164727.00,A,3648.17342,N,12147.27989,W,0.058,206.52,030423,,,A*73
2023-04-03T16:47:28.037Z,1680540448.037 [NAL9602](INFO): GPS fix at 20230403T164727: (36.802890, -121.787998)
2023-04-03T16:47:30.871Z,1680540450.871 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164730.00,A,3648.17322,N,12147.28004,W,0.136,206.52,030423,,,A*79
2023-04-03T16:47:30.884Z,1680540450.884 [NAL9602](INFO): GPS fix at 20230403T164730: (36.802887, -121.788001)
2023-04-03T16:47:34.099Z,1680540454.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164733.00,A,3648.17294,N,12147.28024,W,0.350,206.52,030423,,,A*76
2023-04-03T16:47:34.101Z,1680540454.101 [NAL9602](INFO): GPS fix at 20230403T164733: (36.802882, -121.788004)
2023-04-03T16:47:35.117Z,1680540455.117 [CommandExec](IMPORTANT): got command report clear
2023-04-03T16:47:36.919Z,1680540456.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164736.00,A,3648.17265,N,12147.28038,W,0.078,206.52,030423,,,A*79
2023-04-03T16:47:36.922Z,1680540456.922 [NAL9602](INFO): GPS fix at 20230403T164736: (36.802878, -121.788006)
2023-04-03T16:47:39.793Z,1680540459.793 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802879 Longitude: -121.788010
2023-04-03T16:47:40.151Z,1680540460.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164739.00,A,3648.17243,N,12147.28059,W,0.078,206.52,030423,,,A*75
2023-04-03T16:47:40.153Z,1680540460.153 [NAL9602](INFO): GPS fix at 20230403T164739: (36.802874, -121.788010)
2023-04-03T16:47:40.227Z,1680540460.227 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-04-03T16:47:40.228Z,1680540460.228 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-04-03T16:47:40.240Z,1680540460.240 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-04-03T16:47:40.588Z,1680540460.588 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-04-03T16:47:40.589Z,1680540460.589 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-04-03T16:47:40.589Z,1680540460.589 [IBIT](IMPORTANT): Pressure:9.859320 PSI
2023-04-03T16:47:40.589Z,1680540460.589 [IBIT](IMPORTANT): Humidity:1.013307 %
2023-04-03T16:47:41.001Z,1680540461.001 [IBIT](IMPORTANT): Vehicle Pitch:-0.763736 degrees
2023-04-03T16:47:41.001Z,1680540461.001 [IBIT](IMPORTANT): Vehicle Roll:-2.464410 degrees
2023-04-03T16:47:41.001Z,1680540461.001 [IBIT](IMPORTANT): Vehicle Heading:38.568882 degrees
2023-04-03T16:47:41.444Z,1680540461.444 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-04-03T16:47:41.445Z,1680540461.445 [IBIT](IMPORTANT): buoyancyNeutral: 336.456146 cc
2023-04-03T16:47:41.445Z,1680540461.445 [IBIT](IMPORTANT): massDefault: -1.415717 cm
2023-04-03T16:47:41.445Z,1680540461.445 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-04-03T16:47:41.445Z,1680540461.445 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-04-03T16:47:41.445Z,1680540461.445 [IBIT](IMPORTANT): IBIT PASSED
2023-04-03T16:48:12.470Z,1680540492.470 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-03T16:48:12.965Z,1680540492.965 [DataOverHttps](IMPORTANT): SBD MTMSN=20230403T164812
2023-04-03T16:48:22.572Z,1680540502.572 [DataOverHttps](INFO): Received command: restart app
2023-04-03T16:48:22.661Z,1680540502.661 [CommandExec](IMPORTANT): got command restart application
2023-04-03T16:48:23.664Z,1680540503.664 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-03T16:48:23.665Z,1680540503.665 [CommandExec](INFO): Uninitializing the command executive.
2023-04-03T16:48:23.665Z,1680540503.665 [CommandExec](INFO): Uninitializing the command scheduler.
2023-04-03T16:48:23.665Z,1680540503.665 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:23.832Z,1680540503.832 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2023-04-03T16:48:23.832Z,1680540503.832 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-04-03T16:48:23.833Z,1680540503.833 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:23.833Z,1680540503.833 [NavChartDb](INFO): Join timeout helper Thread ID is 5112
2023-04-03T16:48:24.129Z,1680540504.129 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-03T16:48:24.129Z,1680540504.129 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:24.144Z,1680540504.144 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-04-03T16:48:24.144Z,1680540504.144 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:24.145Z,1680540504.145 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5113
2023-04-03T16:48:24.197Z,1680540504.197 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-03T16:48:24.197Z,1680540504.197 [WetLabsBB2FL](INFO): Powering down
2023-04-03T16:48:24.198Z,1680540504.198 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:24.219Z,1680540504.219 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-04-03T16:48:24.219Z,1680540504.219 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:24.219Z,1680540504.219 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5114
2023-04-03T16:48:24.620Z,1680540504.620 [CTD_Seabird](INFO): Powering down
2023-04-03T16:48:24.632Z,1680540504.632 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-03T16:48:24.633Z,1680540504.633 [CTD_Seabird](INFO): Powering down
2023-04-03T16:48:24.648Z,1680540504.648 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:24.649Z,1680540504.649 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-04-03T16:48:24.649Z,1680540504.649 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:24.649Z,1680540504.649 [Radio_Surface](INFO): Join timeout helper Thread ID is 5115
2023-04-03T16:48:24.912Z,1680540504.912 [Radio_Surface](INFO): Powering down
2023-04-03T16:48:24.913Z,1680540504.913 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-03T16:48:24.914Z,1680540504.914 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:24.928Z,1680540504.928 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-04-03T16:48:24.928Z,1680540504.928 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:24.929Z,1680540504.929 [Onboard](INFO): Join timeout helper Thread ID is 5116
2023-04-03T16:48:25.080Z,1680540505.080 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-03T16:48:25.081Z,1680540505.081 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:25.105Z,1680540505.105 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-04-03T16:48:25.105Z,1680540505.105 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:25.106Z,1680540505.106 [DataOverHttps](INFO): Join timeout helper Thread ID is 5117
2023-04-03T16:48:25.581Z,1680540505.581 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-03T16:48:25.581Z,1680540505.581 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:25.585Z,1680540505.585 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-04-03T16:48:25.585Z,1680540505.585 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:25.586Z,1680540505.586 [logger](INFO): Join timeout helper Thread ID is 5118
2023-04-03T16:48:25.592Z,1680540505.592 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-03T16:48:25.593Z,1680540505.593 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:25.605Z,1680540505.605 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-04-03T16:48:25.605Z,1680540505.605 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:25.606Z,1680540505.606 [CommandLine](INFO): Join timeout helper Thread ID is 5119
2023-04-03T16:48:25.668Z,1680540505.668 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-03T16:48:25.669Z,1680540505.669 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:25.685Z,1680540505.685 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-04-03T16:48:25.685Z,1680540505.685 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:25.685Z,1680540505.685 [CommandExec](INFO): Join timeout helper Thread ID is 5120
2023-04-03T16:48:25.686Z,1680540505.686 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-04-03T16:48:25.687Z,1680540505.687 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:25.687Z,1680540505.687 [controlThread](INFO): Join timeout helper Thread ID is 5121
2023-04-03T16:48:25.820Z,1680540505.820 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-03T16:48:25.821Z,1680540505.821 [controlThread](DEBUG): Uninitializing ControlThread
2023-04-03T16:48:25.821Z,1680540505.821 [AHRS_M2](INFO): Powering down
2023-04-03T16:48:25.893Z,1680540505.893 [NAL9602](INFO): Powering down
2023-04-03T16:48:25.896Z,1680540505.896 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-04-03T16:48:25.897Z,1680540505.897 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-04-03T16:48:25.897Z,1680540505.897 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-04-03T16:48:25.898Z,1680540505.898 [MissionManager](INFO): Uninitializing Mission Default
2023-04-03T16:48:25.898Z,1680540505.898 [Default] Stopped
2023-04-03T16:48:25.898Z,1680540505.898 [Default](DEBUG): Aggregate::uninitialize Default
2023-04-03T16:48:25.898Z,1680540505.898 [Default:B.GoToSurface] Stopped
2023-04-03T16:48:25.898Z,1680540505.898 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-03T16:48:25.898Z,1680540505.898 [Default:CheckIn] Stopped
2023-04-03T16:48:25.899Z,1680540505.899 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-03T16:48:25.899Z,1680540505.899 [Default:CheckIn:C.Wait] Stopped
2023-04-03T16:48:25.899Z,1680540505.899 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-03T16:48:25.902Z,1680540505.902 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-04-03T16:48:25.902Z,1680540505.902 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-04-03T16:48:25.902Z,1680540505.902 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-04-03T16:48:25.902Z,1680540505.902 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-04-03T16:48:25.903Z,1680540505.903 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-04-03T16:48:25.903Z,1680540505.903 [BuoyancyServo](INFO): Powering down
2023-04-03T16:48:25.916Z,1680540505.916 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-04-03T16:48:25.917Z,1680540505.917 [ElevatorServo](INFO): Powering down
2023-04-03T16:48:25.917Z,1680540505.917 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-04-03T16:48:25.918Z,1680540505.918 [MassServo](INFO): Powering down
2023-04-03T16:48:25.918Z,1680540505.918 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-03T16:48:25.919Z,1680540505.919 [RudderServo](INFO): Powering down
2023-04-03T16:48:25.919Z,1680540505.919 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-04-03T16:48:25.919Z,1680540505.919 [ThrusterServo](INFO): Powering down
2023-04-03T16:48:25.921Z,1680540505.921 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-04-03T16:48:25.921Z,1680540505.921 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-04-03T16:48:25.921Z,1680540505.921 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-04-03T16:48:25.921Z,1680540505.921 [CBIT](DEBUG): Powering off loads.
2023-04-03T16:48:25.932Z,1680540505.932 [CBIT](DEBUG): Disabling WDT.
2023-04-03T16:48:25.944Z,1680540505.944 [CBIT](DEBUG): Opening all GF detection circuits.
2023-04-03T16:48:25.945Z,1680540505.945 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:26.002Z,1680540506.002 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:26.011Z,1680540506.011 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:26.017Z,1680540506.017 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:26.121Z,1680540506.121 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:26.131Z,1680540506.131 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:26.187Z,1680540506.187 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-03T16:48:26.290Z,1680540506.290 [logger ThreadHandler](INFO): Thread cancelled.