1970-01-01T00:05:27.510Z,327.510 [Supervisor](DEBUG): Initializing supervisor.
1970-01-01T00:05:27.514Z,327.514 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
1970-01-01T00:05:27.515Z,327.515 [SyncHandler](INFO): Protected caller Thread ID is 834
1970-01-01T00:05:27.515Z,327.515 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
1970-01-01T00:05:27.516Z,327.516 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
1970-01-01T00:05:27.516Z,327.516 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 835
1970-01-01T00:05:27.520Z,327.520 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
1970-01-01T00:05:27.541Z,327.541 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
1970-01-01T00:05:27.542Z,327.542 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
1970-01-01T00:05:27.542Z,327.542 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 836
1970-01-01T00:05:27.546Z,327.546 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
1970-01-01T00:05:27.547Z,327.547 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
1970-01-01T00:05:27.548Z,327.548 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837
1970-01-01T00:05:27.550Z,327.550 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
1970-01-01T00:05:27.551Z,327.551 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
1970-01-01T00:05:27.551Z,327.551 [logger ThreadHandler](INFO): Protected caller Thread ID is 838
1970-01-01T00:05:27.555Z,327.555 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
1970-01-01T00:05:27.556Z,327.556 [Supervisor](INFO): Looking for Config files in directory: Config/
1970-01-01T00:05:27.560Z,327.560 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
1970-01-01T00:05:27.929Z,327.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
1970-01-01T00:05:27.930Z,327.930 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
1970-01-01T00:05:28.205Z,328.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
1970-01-01T00:05:28.207Z,328.207 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
1970-01-01T00:05:28.735Z,328.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
1970-01-01T00:05:28.737Z,328.737 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
1970-01-01T00:05:28.984Z,328.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
1970-01-01T00:05:28.985Z,328.985 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
1970-01-01T00:05:29.219Z,329.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
1970-01-01T00:05:29.221Z,329.221 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
1970-01-01T00:05:30.052Z,330.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
1970-01-01T00:05:30.052Z,330.052 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
1970-01-01T00:05:30.409Z,330.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
1970-01-01T00:05:30.411Z,330.411 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
1970-01-01T00:05:30.530Z,330.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
1970-01-01T00:05:30.531Z,330.531 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
1970-01-01T00:05:30.621Z,330.621 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
1970-01-01T00:05:30.842Z,330.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
1970-01-01T00:05:30.842Z,330.842 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
1970-01-01T00:05:31.631Z,331.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
1970-01-01T00:05:31.700Z,331.700 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
1970-01-01T00:05:31.935Z,331.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
1970-01-01T00:05:31.936Z,331.936 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
1970-01-01T00:05:32.087Z,332.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
1970-01-01T00:05:32.089Z,332.089 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
1970-01-01T00:05:32.191Z,332.191 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
1970-01-01T00:05:32.192Z,332.192 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
1970-01-01T00:05:32.649Z,332.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
1970-01-01T00:05:32.650Z,332.650 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
1970-01-01T00:05:33.241Z,333.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
1970-01-01T00:05:33.243Z,333.243 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/
1970-01-01T00:05:33.245Z,333.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg
1970-01-01T00:05:33.359Z,333.359 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg
1970-01-01T00:05:33.647Z,333.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
1970-01-01T00:05:33.648Z,333.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg
1970-01-01T00:05:33.759Z,333.759 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg
1970-01-01T00:05:33.864Z,333.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg
1970-01-01T00:05:33.983Z,333.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg
1970-01-01T00:05:34.128Z,334.128 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg
1970-01-01T00:05:34.236Z,334.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg
1970-01-01T00:05:34.354Z,334.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg
1970-01-01T00:05:34.448Z,334.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg
1970-01-01T00:05:34.570Z,334.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg
1970-01-01T00:05:34.665Z,334.665 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
1970-01-01T00:05:34.678Z,334.678 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
1970-01-01T00:05:34.694Z,334.694 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
1970-01-01T00:05:34.695Z,334.695 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
1970-01-01T00:05:34.841Z,334.841 [DeadReckonUsingMultipleVelocitySources] Loaded
1970-01-01T00:05:34.842Z,334.842 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
1970-01-01T00:05:34.884Z,334.884 [DeadReckonWithRespectToSeafloor] Loaded
1970-01-01T00:05:34.884Z,334.884 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
1970-01-01T00:05:34.898Z,334.898 [NavChart] Loaded
1970-01-01T00:05:34.898Z,334.898 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
1970-01-01T00:05:34.904Z,334.904 [UniversalFixResidualReporter] Loaded
1970-01-01T00:05:34.904Z,334.904 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
1970-01-01T00:05:34.905Z,334.905 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
1970-01-01T00:05:34.906Z,334.906 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
1970-01-01T00:05:35.782Z,335.782 [AHRS_M2] Loaded
1970-01-01T00:05:35.782Z,335.782 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
1970-01-01T00:05:36.036Z,336.036 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC3
1970-01-01T00:05:36.037Z,336.037 [BackseatComponent] Loaded
1970-01-01T00:05:36.037Z,336.037 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
1970-01-01T00:05:36.038Z,336.038 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4085E4E0
1970-01-01T00:05:36.039Z,336.039 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 920
1970-01-01T00:05:36.041Z,336.041 [LcmUniversalReporter] Loaded
1970-01-01T00:05:36.042Z,336.042 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
1970-01-01T00:05:37.426Z,337.426 [BPC1] Loaded
1970-01-01T00:05:37.426Z,337.426 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
1970-01-01T00:05:37.527Z,337.527 [DAT] Loaded
1970-01-01T00:05:37.527Z,337.527 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
1970-01-01T00:05:37.528Z,337.528 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4088E4E0
1970-01-01T00:05:37.529Z,337.529 [DAT ThreadHandler](INFO): Protected caller Thread ID is 921
1970-01-01T00:05:37.695Z,337.695 [DataOverHttps] Loaded
1970-01-01T00:05:37.696Z,337.696 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
1970-01-01T00:05:37.697Z,337.697 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BE4E0
1970-01-01T00:05:37.697Z,337.697 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
1970-01-01T00:05:37.720Z,337.720 [DepthKeller33X] Loaded
1970-01-01T00:05:37.721Z,337.721 [ComponentRegistry](DEBUG): SyncComponent "DepthKeller33X" handled in the control thread.
1970-01-01T00:05:37.726Z,337.726 [DropWeight] Loaded
1970-01-01T00:05:37.726Z,337.726 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
1970-01-01T00:05:37.792Z,337.792 [NAL9602] Loaded
1970-01-01T00:05:37.792Z,337.792 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
1970-01-01T00:05:37.823Z,337.823 [Onboard] Loaded
1970-01-01T00:05:37.823Z,337.823 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
1970-01-01T00:05:37.824Z,337.824 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408EE4E0
1970-01-01T00:05:37.825Z,337.825 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
1970-01-01T00:05:37.839Z,337.839 [Power24vConverter] Loaded
1970-01-01T00:05:37.839Z,337.839 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
1970-01-01T00:05:37.857Z,337.857 [Radio_Surface] Loaded
1970-01-01T00:05:37.857Z,337.857 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
1970-01-01T00:05:37.858Z,337.858 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091E4E0
1970-01-01T00:05:37.859Z,337.859 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
1970-01-01T00:05:37.907Z,337.907 [RDI_Pathfinder] Loaded
1970-01-01T00:05:37.907Z,337.907 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
1970-01-01T00:05:37.923Z,337.923 [Sonardyne_Nano] Loaded
1970-01-01T00:05:37.923Z,337.923 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
1970-01-01T00:05:37.923Z,337.923 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
1970-01-01T00:05:37.924Z,337.924 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
1970-01-01T00:05:38.000Z,338.000 [DepthRateCalculator] Loaded
1970-01-01T00:05:38.001Z,338.001 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
1970-01-01T00:05:38.007Z,338.007 [PitchRateCalculator] Loaded
1970-01-01T00:05:38.007Z,338.007 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
1970-01-01T00:05:38.017Z,338.017 [SpeedCalculator] Loaded
1970-01-01T00:05:38.017Z,338.017 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
1970-01-01T00:05:38.022Z,338.022 [YawRateCalculator] Loaded
1970-01-01T00:05:38.022Z,338.022 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
1970-01-01T00:05:38.046Z,338.046 [ElevatorOffsetCalculator] Loaded
1970-01-01T00:05:38.046Z,338.046 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
1970-01-01T00:05:38.046Z,338.046 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
1970-01-01T00:05:38.047Z,338.047 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
1970-01-01T00:05:38.139Z,338.139 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
1970-01-01T00:05:38.139Z,338.139 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
1970-01-01T00:05:38.317Z,338.317 [BuoyancyServo] Loaded
1970-01-01T00:05:38.318Z,338.318 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
1970-01-01T00:05:38.343Z,338.343 [ElevatorServo] Loaded
1970-01-01T00:05:38.344Z,338.344 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
1970-01-01T00:05:38.367Z,338.367 [MassServo] Loaded
1970-01-01T00:05:38.368Z,338.368 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
1970-01-01T00:05:38.392Z,338.392 [RudderServo] Loaded
1970-01-01T00:05:38.392Z,338.392 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
1970-01-01T00:05:38.411Z,338.411 [ThrusterHE] Loaded
1970-01-01T00:05:38.411Z,338.411 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
1970-01-01T00:05:38.412Z,338.412 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
1970-01-01T00:05:38.412Z,338.412 [Module Loader](DEBUG): Loading Module at Modules/Science.so
1970-01-01T00:05:38.645Z,338.645 [CTD_Seabird] Loaded
1970-01-01T00:05:38.646Z,338.646 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
1970-01-01T00:05:38.647Z,338.647 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0
1970-01-01T00:05:38.647Z,338.647 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 925
1970-01-01T00:05:38.669Z,338.669 [RBRTridente] Loaded
1970-01-01T00:05:38.669Z,338.669 [ComponentRegistry](DEBUG): Component "RBRTridente" handled in its own thread.
1970-01-01T00:05:38.670Z,338.670 [RBRTridente ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0
1970-01-01T00:05:38.671Z,338.671 [RBRTridente ThreadHandler](INFO): Protected caller Thread ID is 926
1970-01-01T00:05:38.672Z,338.672 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
1970-01-01T00:05:38.672Z,338.672 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
1970-01-01T00:05:38.851Z,338.851 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
1970-01-01T00:05:38.851Z,338.851 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
1970-01-01T00:05:39.083Z,339.083 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
1970-01-01T00:05:39.083Z,339.083 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
1970-01-01T00:05:39.514Z,339.514 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
1970-01-01T00:05:39.515Z,339.515 [Module Loader](DEBUG): Loading Module at Modules/Control.so
1970-01-01T00:05:39.592Z,339.592 [VerticalControl](DEBUG): Construct VerticalControl.
1970-01-01T00:05:39.648Z,339.648 [VerticalControl] Loaded
1970-01-01T00:05:39.648Z,339.648 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
1970-01-01T00:05:39.651Z,339.651 [HorizontalControl](DEBUG): Construct HorizontalControl.
1970-01-01T00:05:39.701Z,339.701 [HorizontalControl] Loaded
1970-01-01T00:05:39.701Z,339.701 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
1970-01-01T00:05:39.704Z,339.704 [SpeedControl](DEBUG): Construct SpeedControl.
1970-01-01T00:05:39.706Z,339.706 [SpeedControl] Loaded
1970-01-01T00:05:39.706Z,339.706 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
1970-01-01T00:05:39.709Z,339.709 [LoopControl](DEBUG): Construct LoopControl.
1970-01-01T00:05:39.710Z,339.710 [LoopControl] Loaded
1970-01-01T00:05:39.710Z,339.710 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
1970-01-01T00:05:39.710Z,339.710 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
1970-01-01T00:05:39.711Z,339.711 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
1970-01-01T00:05:39.918Z,339.918 [SBIT](DEBUG): Construct Startup Built In Test.
1970-01-01T00:05:39.928Z,339.928 [SBIT] Loaded
1970-01-01T00:05:39.928Z,339.928 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
1970-01-01T00:05:39.931Z,339.931 [IBIT](DEBUG): Construct Initiated Built In Test.
1970-01-01T00:05:39.944Z,339.944 [IBIT] Loaded
1970-01-01T00:05:39.945Z,339.945 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
1970-01-01T00:05:39.950Z,339.950 [CBIT](DEBUG): Construct Continuous Built In Test.
1970-01-01T00:05:40.037Z,340.037 [CBIT] Loaded
1970-01-01T00:05:40.037Z,340.037 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
1970-01-01T00:05:40.071Z,340.071 [GFScanner] Loaded
1970-01-01T00:05:40.072Z,340.072 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
1970-01-01T00:05:40.072Z,340.072 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
1970-01-01T00:05:40.073Z,340.073 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
1970-01-01T00:05:40.123Z,340.123 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
1970-01-01T00:05:40.130Z,340.130 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
1970-01-01T00:05:40.133Z,340.133 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
1970-01-01T00:05:40.144Z,340.144 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
1970-01-01T00:05:40.145Z,340.145 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
1970-01-01T00:05:40.146Z,340.146 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 927
1970-01-01T00:05:40.150Z,340.150 [Supervisor](INFO): Main Thread ID is 825
1970-01-01T00:05:40.150Z,340.150 [Supervisor](DEBUG): Running supervisor.
1970-01-01T00:05:40.151Z,340.151 [CommandExec ThreadHandler](INFO): Handler Thread ID is 928
1970-01-01T00:05:40.151Z,340.151 [CommandExec](INFO): Initializing the command executive.
1970-01-01T00:05:40.153Z,340.153 [CommandLine ThreadHandler](INFO): Handler Thread ID is 929
1970-01-01T00:05:40.155Z,340.155 [controlThread ThreadHandler](INFO): Handler Thread ID is 930
1970-01-01T00:05:40.155Z,340.155 [controlThread](DEBUG): Initializing ControlThread
1970-01-01T00:05:40.157Z,340.157 [NavChart](DEBUG): Initialize NavChart Navigation.
1970-01-01T00:05:40.158Z,340.158 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
1970-01-01T00:05:40.161Z,340.161 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
1970-01-01T00:05:40.161Z,340.161 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
1970-01-01T00:05:40.162Z,340.162 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
1970-01-01T00:05:40.162Z,340.162 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
1970-01-01T00:05:40.162Z,340.162 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
1970-01-01T00:05:40.164Z,340.164 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
1970-01-01T00:05:40.166Z,340.166 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
1970-01-01T00:05:40.166Z,340.166 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
1970-01-01T00:05:40.167Z,340.167 [LoopControl](DEBUG): Initialize LoopControlComponent.
1970-01-01T00:05:40.167Z,340.167 [SBIT](INFO): Initialize SBIT Component.
1970-01-01T00:05:40.168Z,340.168 [SBIT](IMPORTANT): git: 2025-09-11
1970-01-01T00:05:40.168Z,340.168 [SBIT](INFO): git hash: 809a1152453d4a7797fa86b43c86e80bc3a54567
1970-01-01T00:05:40.168Z,340.168 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
1970-01-01T00:05:40.169Z,340.169 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Mon Nov 27 01:04:18 PST 2023
1970-01-01T00:05:40.170Z,340.170 [SBIT](INFO): Beginning SBIT in 95.000000 seconds.
1970-01-01T00:05:40.171Z,340.171 [IBIT](INFO): Initialize IBIT Component.
1970-01-01T00:05:40.172Z,340.172 [CBIT](DEBUG): Initialize CBIT Component.
1970-01-01T00:05:40.173Z,340.173 [logger ThreadHandler](INFO): Handler Thread ID is 931
1970-01-01T00:05:40.185Z,340.185 [CBIT](DEBUG): Initialized mux pins.
1970-01-01T00:05:40.185Z,340.185 [CBIT](DEBUG): Initializing the watchdog timer.
1970-01-01T00:05:40.193Z,340.193 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 932
1970-01-01T00:05:40.209Z,340.209 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
1970-01-01T00:05:40.209Z,340.209 [CBIT](DEBUG): Initializing heartbeat.
1970-01-01T00:05:40.210Z,340.210 [DAT ThreadHandler](INFO): Handler Thread ID is 933
1970-01-01T00:05:40.211Z,340.211 [DAT](INFO): Powering up
1970-01-01T00:05:40.211Z,340.211 [DAT](DEBUG): Initializing DAT.
1970-01-01T00:05:40.214Z,340.214 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
1970-01-01T00:05:40.216Z,340.216 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
1970-01-01T00:05:40.237Z,340.237 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
1970-01-01T00:05:40.255Z,340.255 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
1970-01-01T00:05:40.273Z,340.273 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938
1970-01-01T00:05:40.274Z,340.274 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
1970-01-01T00:05:40.278Z,340.278 [RBRTridente ThreadHandler](INFO): Handler Thread ID is 940
1970-01-01T00:05:40.279Z,340.279 [RBRTridente](INFO): Start
1970-01-01T00:05:40.281Z,340.281 [CBIT](DEBUG): Deactivating emergency mode.
1970-01-01T00:05:40.282Z,340.282 [RBRTridente](INFO): Powering up
1970-01-01T00:05:40.283Z,340.283 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942
1970-01-01T00:05:40.291Z,340.291 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
1970-01-01T00:05:40.291Z,340.291 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
1970-01-01T00:05:40.291Z,340.291 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
1970-01-01T00:05:40.291Z,340.291 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
1970-01-01T00:05:40.291Z,340.291 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
1970-01-01T00:05:40.292Z,340.292 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
1970-01-01T00:05:40.292Z,340.292 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
1970-01-01T00:05:40.292Z,340.292 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
1970-01-01T00:05:40.321Z,340.321 [CBIT](DEBUG): Backplane powered.
1970-01-01T00:05:40.322Z,340.322 [GFScanner](DEBUG): Initializing GFScanner
1970-01-01T00:05:40.322Z,340.322 [GFScanner](DEBUG): Deactivating GF circuits.
1970-01-01T00:05:40.326Z,340.326 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
1970-01-01T00:05:40.327Z,340.327 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
1970-01-01T00:05:40.327Z,340.327 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
1970-01-01T00:05:40.332Z,340.332 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
1970-01-01T00:05:40.423Z,340.423 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

1970-01-01T00:05:40.427Z,340.427 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
1970-01-01T00:05:40.505Z,340.505 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Startup {
  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate StartupSatComms {
    run in sequence

    readDatum {
      timeout duration=P1M

      Universal:latitude_fix
    }

    readDatum {
      timeout duration=P1M

      Universal:platform_communications
    }
  }
}


1970-01-01T00:05:40.506Z,340.506 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
1970-01-01T00:05:40.506Z,340.506 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
1970-01-01T00:05:40.509Z,340.509 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
1970-01-01T00:05:40.661Z,340.661 [Radio_Surface](INFO): Powering up
1970-01-01T00:05:40.681Z,340.681 [RBRTridente](INFO): Starting
1970-01-01T00:05:40.862Z,340.862 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

1970-01-01T00:05:40.868Z,340.868 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
1970-01-01T00:05:40.878Z,340.878 [Default:A.Wait](DEBUG): Construct Wait.
1970-01-01T00:05:40.880Z,340.880 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
1970-01-01T00:05:40.923Z,340.923 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
1970-01-01T00:05:40.946Z,340.946 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
1970-01-01T00:05:40.952Z,340.952 [Default:E.Execute](DEBUG): Construct Execute.
1970-01-01T00:05:40.968Z,340.968 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Default {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute
  }

  behavior Guidance:Wait {
    """
    Wait a moment to see if the scheduler starts a new mission before
    starting to actually run Default.
    """

    run in sequence

    set duration = 13 second
  }

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

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

          set command = "Burn on"
        }

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

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )

    syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
  }

  syslog important "Restarting logs and Default mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


1970-01-01T00:05:40.985Z,340.985 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DepthKeller33X,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
1970-01-01T00:05:41.007Z,341.007 [AHRS_M2](DEBUG): Initializing AHRS_M2.
1970-01-01T00:05:41.085Z,341.085 [RBRTridente](INFO): Stop
1970-01-01T00:05:41.145Z,341.145 [DepthKeller33X](INFO): Initializing.
1970-01-01T00:05:41.157Z,341.157 [RBRTridente](INFO): Powering down
1970-01-01T00:05:41.166Z,341.166 [Power24vConverter](INFO): Powering up.
1970-01-01T00:05:41.166Z,341.166 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
1970-01-01T00:05:41.173Z,341.173 [Sonardyne_Nano](INFO): Initializing.
1970-01-01T00:05:41.193Z,341.193 [DepthRateCalculator](ERROR): Depth measurement is not active
1970-01-01T00:05:41.204Z,341.204 [BuoyancyServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:05:41.205Z,341.205 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
1970-01-01T00:05:41.206Z,341.206 [ElevatorServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:05:41.214Z,341.214 [ElevatorServo](DEBUG): Initializing ElevatorServo.
1970-01-01T00:05:41.215Z,341.215 [MassServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:05:41.221Z,341.221 [MassServo](DEBUG): Initializing MassServo.
1970-01-01T00:05:41.222Z,341.222 [RudderServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:05:41.229Z,341.229 [RudderServo](DEBUG): Initializing RudderServo.
1970-01-01T00:05:41.230Z,341.230 [ThrusterHE](DEBUG): Initializing EZServoServo.
1970-01-01T00:05:41.237Z,341.237 [ThrusterHE](DEBUG): Initializing ThrusterHE.
1970-01-01T00:05:41.240Z,341.240 [CBIT](FAULT): Main Battery Failure
1970-01-01T00:05:41.451Z,341.451 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

1970-01-01T00:05:41.451Z,341.451 [DropWeight] Hardware Fault, FailCount= 1
1970-01-01T00:05:41.451Z,341.451 [DropWeight](ERROR): Hardware Fault
1970-01-01T00:05:41.453Z,341.453 [DepthRateCalculator](ERROR): Depth measurement is not active
1970-01-01T00:05:41.476Z,341.476 [CommandExec](FAULT): Scheduling is paused
1970-01-01T00:05:41.485Z,341.485 [CBIT](INFO): Critical error at 19700101T000541
1970-01-01T00:05:41.485Z,341.485 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
1970-01-01T00:05:41.488Z,341.488 [CBIT](ERROR): Hardware Fault in component: DropWeight
1970-01-01T00:05:41.488Z,341.488 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
1970-01-01T00:05:41.489Z,341.489 [RBRTridente](INFO): Stopping
1970-01-01T00:05:41.801Z,341.801 [DepthRateCalculator](ERROR): Depth measurement is not active
1970-01-01T00:05:41.810Z,341.810 [CBIT](INFO): Critical error at 19700101T000541
1970-01-01T00:05:42.208Z,342.208 [DepthRateCalculator](ERROR): Depth measurement is not active
1970-01-01T00:05:42.609Z,342.609 [DepthRateCalculator](ERROR): Depth measurement is not active
1970-01-01T00:05:43.035Z,343.035 [DepthRateCalculator](ERROR): Depth measurement is not active
1970-01-01T00:05:43.448Z,343.448 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
1970-01-01T00:05:44.326Z,344.326 [Power24vConverter](FAULT): LCB fault:  Current Limiter Activated.
1970-01-01T00:05:44.327Z,344.327 [Power24vConverter] Hardware Fault, FailCount= 1
1970-01-01T00:05:44.327Z,344.327 [Power24vConverter](ERROR): Hardware Fault
1970-01-01T00:05:44.327Z,344.327 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:44.328Z,344.328 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
1970-01-01T00:05:44.343Z,344.343 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
1970-01-01T00:05:44.694Z,344.694 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:44.697Z,344.697 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
1970-01-01T00:05:44.736Z,344.736 [BuoyancyServo](FAULT): Reading outside of valid range:3089.000000

1970-01-01T00:05:44.736Z,344.736 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
1970-01-01T00:05:44.736Z,344.736 [BuoyancyServo] Hardware Fault, FailCount= 1
1970-01-01T00:05:44.736Z,344.736 [BuoyancyServo](ERROR): Hardware Fault
1970-01-01T00:05:44.901Z,344.901 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
1970-01-01T00:05:45.062Z,345.062 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:45.540Z,345.540 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:45.885Z,345.885 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:46.292Z,346.292 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:46.353Z,346.353 [RudderServo](FAULT): Overload Error
1970-01-01T00:05:46.354Z,346.354 [RudderServo] Hardware Fault, FailCount= 1
1970-01-01T00:05:46.354Z,346.354 [RudderServo](ERROR): Hardware Fault
1970-01-01T00:05:46.476Z,346.476 [CBIT](ERROR): Hardware Fault in component: RudderServo
1970-01-01T00:05:46.706Z,346.706 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:46.715Z,346.715 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
1970-01-01T00:05:46.715Z,346.715 [BuoyancyServo](INFO): Powering down
1970-01-01T00:05:46.870Z,346.870 [RudderServo](DEBUG): Uninitialize Rudder Servo.
1970-01-01T00:05:46.870Z,346.870 [RudderServo](INFO): Powering down
1970-01-01T00:05:47.122Z,347.122 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:47.497Z,347.497 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:47.534Z,347.534 [RudderServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:05:47.654Z,347.654 [RudderServo](DEBUG): Initializing RudderServo.
1970-01-01T00:05:47.658Z,347.658 [CBIT](INFO): Clearing failed state for component BuoyancyServo
1970-01-01T00:05:47.658Z,347.658 [BuoyancyServo] No Fault, FailCount= 1
1970-01-01T00:05:47.658Z,347.658 [CBIT](INFO): Clearing failed state for component RudderServo
1970-01-01T00:05:47.658Z,347.658 [RudderServo] No Fault, FailCount= 1
1970-01-01T00:05:47.905Z,347.905 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:47.915Z,347.915 [BuoyancyServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:05:47.921Z,347.921 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
1970-01-01T00:05:48.312Z,348.312 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:48.726Z,348.726 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:49.150Z,349.150 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:49.522Z,349.522 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:49.922Z,349.922 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:50.301Z,350.301 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:50.698Z,350.698 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:51.110Z,351.110 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:51.150Z,351.150 [BuoyancyServo](FAULT): Reading outside of valid range:3089.000000

1970-01-01T00:05:51.150Z,351.150 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
1970-01-01T00:05:51.150Z,351.150 [BuoyancyServo] Hardware Fault, FailCount= 2
1970-01-01T00:05:51.150Z,351.150 [BuoyancyServo](ERROR): Hardware Fault
1970-01-01T00:05:51.325Z,351.325 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
1970-01-01T00:05:51.530Z,351.530 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:51.906Z,351.906 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:52.314Z,352.314 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:52.733Z,352.733 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:52.797Z,352.797 [RudderServo](FAULT): Overload Error
1970-01-01T00:05:52.798Z,352.798 [RudderServo] Hardware Fault, FailCount= 2
1970-01-01T00:05:52.798Z,352.798 [RudderServo](ERROR): Hardware Fault
1970-01-01T00:05:52.909Z,352.909 [CBIT](ERROR): Hardware Fault in component: RudderServo
1970-01-01T00:05:53.136Z,353.136 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:53.151Z,353.151 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
1970-01-01T00:05:53.151Z,353.151 [BuoyancyServo](INFO): Powering down
1970-01-01T00:05:53.450Z,353.450 [RudderServo](DEBUG): Uninitialize Rudder Servo.
1970-01-01T00:05:53.450Z,353.450 [RudderServo](INFO): Powering down
1970-01-01T00:05:53.558Z,353.558 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:53.927Z,353.927 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:53.970Z,353.970 [RudderServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:05:54.090Z,354.090 [RudderServo](DEBUG): Initializing RudderServo.
1970-01-01T00:05:54.096Z,354.096 [CBIT](INFO): Clearing failed state for component BuoyancyServo
1970-01-01T00:05:54.096Z,354.096 [BuoyancyServo] No Fault, FailCount= 2
1970-01-01T00:05:54.096Z,354.096 [CBIT](INFO): Clearing failed state for component RudderServo
1970-01-01T00:05:54.096Z,354.096 [RudderServo] No Fault, FailCount= 2
1970-01-01T00:05:54.334Z,354.334 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:54.352Z,354.352 [BuoyancyServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:05:54.354Z,354.354 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
1970-01-01T00:05:55.321Z,355.321 [CBIT](INFO): Clearing failed state for component Power24vConverter
1970-01-01T00:05:55.321Z,355.321 [Power24vConverter] No Fault, FailCount= 1
1970-01-01T00:05:55.570Z,355.570 [Power24vConverter](INFO): Powering up.
1970-01-01T00:05:56.760Z,356.760 [Power24vConverter](FAULT): LCB fault:  Current Limiter Activated.
1970-01-01T00:05:56.761Z,356.761 [Power24vConverter] Hardware Fault, FailCount= 1
1970-01-01T00:05:56.761Z,356.761 [Power24vConverter](ERROR): Hardware Fault
1970-01-01T00:05:56.762Z,356.762 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:56.764Z,356.764 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
1970-01-01T00:05:56.859Z,356.859 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
1970-01-01T00:05:57.172Z,357.172 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:57.590Z,357.590 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:05:57.630Z,357.630 [BuoyancyServo](FAULT): Reading outside of valid range:3089.000000

1970-01-01T00:05:57.630Z,357.630 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
1970-01-01T00:05:57.630Z,357.630 [BuoyancyServo] Hardware Fault, FailCount= 3
1970-01-01T00:05:57.630Z,357.630 [BuoyancyServo](ERROR): Hardware Fault
1970-01-01T00:05:57.801Z,357.801 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
1970-01-01T00:05:57.967Z,357.967 [Power24vConverter](INFO): Powering down.
1970-01-01T00:05:58.867Z,358.867 [CBIT](INFO): Clearing failed state for component Power24vConverter
1970-01-01T00:05:58.867Z,358.867 [Power24vConverter] No Fault, FailCount= 1
1970-01-01T00:05:59.190Z,359.190 [Power24vConverter](INFO): Powering up.
1970-01-01T00:05:59.253Z,359.253 [RudderServo](FAULT): Overload Error
1970-01-01T00:05:59.253Z,359.253 [RudderServo] Hardware Fault, FailCount= 3
1970-01-01T00:05:59.253Z,359.253 [RudderServo](ERROR): Hardware Fault
1970-01-01T00:05:59.366Z,359.366 [CBIT](ERROR): Hardware Fault in component: RudderServo
1970-01-01T00:05:59.366Z,359.366 [CBIT](CRITICAL): Hardware Fault in component: RudderServo
1970-01-01T00:05:59.626Z,359.626 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
1970-01-01T00:05:59.626Z,359.626 [BuoyancyServo](INFO): Powering down
1970-01-01T00:05:59.787Z,359.787 [RudderServo](DEBUG): Uninitialize Rudder Servo.
1970-01-01T00:05:59.787Z,359.787 [RudderServo](INFO): Powering down
1970-01-01T00:05:59.790Z,359.790 [CBIT](INFO): Critical error at 19700101T000559
1970-01-01T00:06:00.406Z,360.406 [Power24vConverter](FAULT): LCB fault:  Current Limiter Activated.
1970-01-01T00:06:00.406Z,360.406 [Power24vConverter] Hardware Fault, FailCount= 1
1970-01-01T00:06:00.406Z,360.406 [Power24vConverter](ERROR): Hardware Fault
1970-01-01T00:06:00.407Z,360.407 [Power24vConverter](INFO): Powering down.
1970-01-01T00:06:00.408Z,360.408 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
1970-01-01T00:06:00.446Z,360.446 [RudderServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:06:00.565Z,360.565 [RudderServo](DEBUG): Initializing RudderServo.
1970-01-01T00:06:00.570Z,360.570 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
1970-01-01T00:06:00.570Z,360.570 [CBIT](INFO): Clearing failed state for component BuoyancyServo
1970-01-01T00:06:00.570Z,360.570 [BuoyancyServo] No Fault, FailCount= 3
1970-01-01T00:06:00.799Z,360.799 [Power24vConverter](INFO): Powering down.
1970-01-01T00:06:00.810Z,360.810 [BuoyancyServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:06:00.817Z,360.817 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
1970-01-01T00:06:01.218Z,361.218 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:06:01.638Z,361.638 [Power24vConverter](INFO): Powering down.
1970-01-01T00:06:02.050Z,362.050 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:06:02.417Z,362.417 [Power24vConverter](INFO): Powering down.
1970-01-01T00:06:02.822Z,362.822 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:06:03.238Z,363.238 [Power24vConverter](INFO): Powering down.
1970-01-01T00:06:03.651Z,363.651 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
1970-01-01T00:06:03.652Z,363.652 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
1970-01-01T00:06:03.652Z,363.652 [RDI_Pathfinder] Communications Fault, FailCount= 1
1970-01-01T00:06:03.652Z,363.652 [RDI_Pathfinder](ERROR): Communications Fault
1970-01-01T00:06:03.722Z,363.722 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
1970-01-01T00:06:04.058Z,364.058 [Power24vConverter](INFO): Powering down.
1970-01-01T00:06:04.129Z,364.129 [RDI_Pathfinder](INFO): Powering down
1970-01-01T00:06:04.173Z,364.173 [BuoyancyServo](FAULT): Reading outside of valid range:3091.000000

1970-01-01T00:06:04.173Z,364.173 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
1970-01-01T00:06:04.173Z,364.173 [BuoyancyServo] Hardware Fault, FailCount= 4
1970-01-01T00:06:04.173Z,364.173 [BuoyancyServo](ERROR): Hardware Fault
1970-01-01T00:06:04.210Z,364.210 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
1970-01-01T00:06:04.882Z,364.882 [CBIT](INFO): Clearing failed state for component Power24vConverter
1970-01-01T00:06:04.882Z,364.882 [Power24vConverter] No Fault, FailCount= 1
1970-01-01T00:06:04.883Z,364.883 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
1970-01-01T00:06:04.883Z,364.883 [RDI_Pathfinder] No Fault, FailCount= 1
1970-01-01T00:06:05.265Z,365.265 [Power24vConverter](INFO): Powering up.
1970-01-01T00:06:05.270Z,365.270 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
1970-01-01T00:06:06.095Z,366.095 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
1970-01-01T00:06:06.095Z,366.095 [BuoyancyServo](INFO): Powering down
1970-01-01T00:06:06.922Z,366.922 [CBIT](INFO): Clearing failed state for component BuoyancyServo
1970-01-01T00:06:06.922Z,366.922 [BuoyancyServo] No Fault, FailCount= 4
1970-01-01T00:06:07.283Z,367.283 [BuoyancyServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:06:07.284Z,367.284 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
1970-01-01T00:06:08.068Z,368.068 [NAL9602](INFO): Powering up NAL9602
1970-01-01T00:06:10.933Z,370.933 [BuoyancyServo](FAULT): Reading outside of valid range:3089.000000

1970-01-01T00:06:10.933Z,370.933 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
1970-01-01T00:06:10.933Z,370.933 [BuoyancyServo] Hardware Fault, FailCount= 5
1970-01-01T00:06:10.933Z,370.933 [BuoyancyServo](ERROR): Hardware Fault
1970-01-01T00:06:10.967Z,370.967 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
1970-01-01T00:06:11.078Z,371.078 [CommandExec](IMPORTANT): got command quit
1970-01-01T00:06:12.082Z,372.082 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:12.083Z,372.083 [CommandExec](INFO): Uninitializing the command executive.
1970-01-01T00:06:12.084Z,372.084 [CommandExec](INFO): Uninitializing the command scheduler.
1970-01-01T00:06:12.085Z,372.085 [CommandExec ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:12.169Z,372.169 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
1970-01-01T00:06:12.169Z,372.169 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
1970-01-01T00:06:12.169Z,372.169 [NavChartDb ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:12.170Z,372.170 [NavChartDb](INFO): Join timeout helper Thread ID is 962
1970-01-01T00:06:12.258Z,372.258 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:12.258Z,372.258 [NavChartDb ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:12.265Z,372.265 [ComponentRegistry](INFO): Shutting down RBRTridente ThreadHandler
1970-01-01T00:06:12.265Z,372.265 [RBRTridente ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:12.265Z,372.265 [RBRTridente](INFO): Join timeout helper Thread ID is 963
1970-01-01T00:06:12.618Z,372.618 [RBRTridente ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:12.619Z,372.619 [RBRTridente](INFO): Powering down
1970-01-01T00:06:12.619Z,372.619 [RBRTridente ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:12.633Z,372.633 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
1970-01-01T00:06:12.633Z,372.633 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:12.634Z,372.634 [CTD_Seabird](INFO): Join timeout helper Thread ID is 964
1970-01-01T00:06:12.945Z,372.945 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
1970-01-01T00:06:12.945Z,372.945 [BuoyancyServo](INFO): Powering down
1970-01-01T00:06:13.425Z,373.425 [CTD_Seabird](INFO): Powering down
1970-01-01T00:06:13.437Z,373.437 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:13.437Z,373.437 [CTD_Seabird](INFO): Powering down
1970-01-01T00:06:13.457Z,373.457 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:13.473Z,373.473 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
1970-01-01T00:06:13.473Z,373.473 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:13.473Z,373.473 [Radio_Surface](INFO): Join timeout helper Thread ID is 965
1970-01-01T00:06:13.797Z,373.797 [Radio_Surface](INFO): Powering down
1970-01-01T00:06:13.798Z,373.798 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:13.799Z,373.799 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:13.809Z,373.809 [CBIT](INFO): Clearing failed state for component BuoyancyServo
1970-01-01T00:06:13.809Z,373.809 [BuoyancyServo] No Fault, FailCount= 5
1970-01-01T00:06:13.810Z,373.810 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
1970-01-01T00:06:13.810Z,373.810 [Onboard ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:13.810Z,373.810 [Onboard](INFO): Join timeout helper Thread ID is 966
1970-01-01T00:06:14.168Z,374.168 [BuoyancyServo](DEBUG): Initializing EZServoServo.
1970-01-01T00:06:14.169Z,374.169 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
1970-01-01T00:06:15.265Z,375.265 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:15.265Z,375.265 [Onboard ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.276Z,375.276 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
1970-01-01T00:06:15.276Z,375.276 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.276Z,375.276 [DataOverHttps](INFO): Join timeout helper Thread ID is 968
1970-01-01T00:06:15.353Z,375.353 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:15.354Z,375.354 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.360Z,375.360 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
1970-01-01T00:06:15.360Z,375.360 [DAT ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.361Z,375.361 [DAT](INFO): Join timeout helper Thread ID is 969
1970-01-01T00:06:15.497Z,375.497 [DAT](INFO): Powering down
1970-01-01T00:06:15.569Z,375.569 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:15.569Z,375.569 [DAT](INFO): Powering down
1970-01-01T00:06:15.570Z,375.570 [DAT ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.573Z,375.573 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
1970-01-01T00:06:15.574Z,375.574 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.574Z,375.574 [BackseatComponent](INFO): Join timeout helper Thread ID is 970
1970-01-01T00:06:15.721Z,375.721 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:15.721Z,375.721 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.746Z,375.746 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
1970-01-01T00:06:15.746Z,375.746 [logger ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.746Z,375.746 [logger](INFO): Join timeout helper Thread ID is 971
1970-01-01T00:06:15.802Z,375.802 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:15.802Z,375.802 [logger ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.822Z,375.822 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
1970-01-01T00:06:15.822Z,375.822 [CommandLine ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.822Z,375.822 [CommandLine](INFO): Join timeout helper Thread ID is 972
1970-01-01T00:06:15.825Z,375.825 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:15.825Z,375.825 [CommandLine ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.827Z,375.827 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
1970-01-01T00:06:15.827Z,375.827 [CommandExec ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.827Z,375.827 [CommandExec](INFO): Join timeout helper Thread ID is 973
1970-01-01T00:06:15.828Z,375.828 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
1970-01-01T00:06:15.828Z,375.828 [controlThread ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:15.829Z,375.829 [controlThread](INFO): Join timeout helper Thread ID is 974
1970-01-01T00:06:16.133Z,376.133 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
1970-01-01T00:06:16.133Z,376.133 [controlThread](DEBUG): Uninitializing ControlThread
1970-01-01T00:06:16.134Z,376.134 [AHRS_M2](INFO): Powering down
1970-01-01T00:06:16.206Z,376.206 [DepthKeller33X](INFO): Powering down
1970-01-01T00:06:16.301Z,376.301 [NAL9602](INFO): Powering down
1970-01-01T00:06:16.373Z,376.373 [RDI_Pathfinder](INFO): Powering down
1970-01-01T00:06:16.374Z,376.374 [Sonardyne_Nano](INFO): Powering down
1970-01-01T00:06:16.493Z,376.493 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
1970-01-01T00:06:16.494Z,376.494 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
1970-01-01T00:06:16.495Z,376.495 [NavChart](DEBUG): Uninitialize NavChart Navigation.
1970-01-01T00:06:16.495Z,376.495 [MissionManager](INFO): Uninitializing Mission Default
1970-01-01T00:06:16.498Z,376.498 [MissionManager](INFO): Uninitializing Mission Startup
1970-01-01T00:06:16.500Z,376.500 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
1970-01-01T00:06:16.500Z,376.500 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
1970-01-01T00:06:16.500Z,376.500 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
1970-01-01T00:06:16.501Z,376.501 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
1970-01-01T00:06:16.501Z,376.501 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
1970-01-01T00:06:16.501Z,376.501 [BuoyancyServo](INFO): Powering down
1970-01-01T00:06:16.633Z,376.633 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
1970-01-01T00:06:16.633Z,376.633 [ElevatorServo](INFO): Powering down
1970-01-01T00:06:16.634Z,376.634 [MassServo](DEBUG): Uninitialize Mass Servo.
1970-01-01T00:06:16.634Z,376.634 [MassServo](INFO): Powering down
1970-01-01T00:06:16.635Z,376.635 [RudderServo](DEBUG): Uninitialize Rudder Servo.
1970-01-01T00:06:16.635Z,376.635 [RudderServo](INFO): Powering down
1970-01-01T00:06:16.636Z,376.636 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
1970-01-01T00:06:16.636Z,376.636 [ThrusterHE](INFO): Powering down
1970-01-01T00:06:16.637Z,376.637 [SBIT](DEBUG): Uninitialize SBIT Component.
1970-01-01T00:06:16.637Z,376.637 [IBIT](DEBUG): Uninitialize IBIT Component.
1970-01-01T00:06:16.637Z,376.637 [CBIT](DEBUG): Uninitialize CBIT Component.
1970-01-01T00:06:16.638Z,376.638 [CBIT](DEBUG): Powering off loads.
1970-01-01T00:06:16.649Z,376.649 [CBIT](DEBUG): Disabling WDT.
1970-01-01T00:06:16.661Z,376.661 [GFScanner](DEBUG): Uninitialize GFScanner component.
1970-01-01T00:06:16.661Z,376.661 [GFScanner](DEBUG): Opening all GF detection circuits.
1970-01-01T00:06:16.662Z,376.662 [controlThread ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:16.689Z,376.689 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:16.692Z,376.692 [Onboard ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:16.698Z,376.698 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:16.704Z,376.704 [DAT ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:16.745Z,376.745 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:17.148Z,377.148 [RBRTridente ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:17.149Z,377.149 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:17.189Z,377.189 [GFScanner](DEBUG): Uninitialize GFScanner component.
1970-01-01T00:06:17.190Z,377.190 [GFScanner](DEBUG): Opening all GF detection circuits.
1970-01-01T00:06:17.208Z,377.208 [NavChartDb ThreadHandler](INFO): Thread cancelled.
1970-01-01T00:06:17.272Z,377.272 [logger ThreadHandler](INFO): Thread cancelled.