2023-06-29T01:59:49.378Z,1688003989.378 [Supervisor](DEBUG): Initializing supervisor.
2023-06-29T01:59:49.382Z,1688003989.382 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-06-29T01:59:49.383Z,1688003989.383 [SyncHandler](INFO): Protected caller Thread ID is 837
2023-06-29T01:59:49.383Z,1688003989.383 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-06-29T01:59:49.384Z,1688003989.384 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-06-29T01:59:49.385Z,1688003989.385 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2023-06-29T01:59:49.389Z,1688003989.389 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-06-29T01:59:49.406Z,1688003989.406 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-06-29T01:59:49.407Z,1688003989.407 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-06-29T01:59:49.407Z,1688003989.407 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2023-06-29T01:59:49.411Z,1688003989.411 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-06-29T01:59:49.412Z,1688003989.412 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-06-29T01:59:49.413Z,1688003989.413 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2023-06-29T01:59:49.414Z,1688003989.414 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-06-29T01:59:49.415Z,1688003989.415 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-06-29T01:59:49.416Z,1688003989.416 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2023-06-29T01:59:49.419Z,1688003989.419 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-06-29T01:59:49.420Z,1688003989.420 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-06-29T01:59:49.424Z,1688003989.424 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-06-29T01:59:49.886Z,1688003989.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-06-29T01:59:49.888Z,1688003989.888 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-06-29T01:59:50.091Z,1688003990.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-06-29T01:59:50.092Z,1688003990.092 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-06-29T01:59:50.172Z,1688003990.172 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-06-29T01:59:50.385Z,1688003990.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-06-29T01:59:50.387Z,1688003990.387 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-06-29T01:59:50.467Z,1688003990.467 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-06-29T01:59:50.566Z,1688003990.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-06-29T01:59:50.568Z,1688003990.568 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-06-29T01:59:50.908Z,1688003990.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-06-29T01:59:50.910Z,1688003990.910 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-06-29T01:59:51.043Z,1688003991.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-06-29T01:59:51.045Z,1688003991.045 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-06-29T01:59:51.520Z,1688003991.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-06-29T01:59:51.521Z,1688003991.521 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-06-29T01:59:51.700Z,1688003991.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-06-29T01:59:51.700Z,1688003991.700 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-06-29T01:59:52.088Z,1688003992.088 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-06-29T01:59:52.090Z,1688003992.090 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-06-29T01:59:52.374Z,1688003992.374 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-06-29T01:59:52.376Z,1688003992.376 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-06-29T01:59:52.484Z,1688003992.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-06-29T01:59:52.485Z,1688003992.485 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-06-29T01:59:53.618Z,1688003993.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-06-29T01:59:53.619Z,1688003993.619 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-06-29T01:59:54.025Z,1688003994.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-06-29T01:59:54.025Z,1688003994.025 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-06-29T01:59:54.247Z,1688003994.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-06-29T01:59:54.249Z,1688003994.249 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-06-29T01:59:54.251Z,1688003994.251 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-06-29T01:59:54.373Z,1688003994.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-06-29T01:59:54.457Z,1688003994.457 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-06-29T01:59:54.596Z,1688003994.596 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-06-29T01:59:54.681Z,1688003994.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-06-29T01:59:54.948Z,1688003994.948 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-06-29T01:59:54.949Z,1688003994.949 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-06-29T01:59:55.052Z,1688003995.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-06-29T01:59:55.160Z,1688003995.160 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-06-29T01:59:55.272Z,1688003995.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-06-29T01:59:55.418Z,1688003995.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-06-29T01:59:56.044Z,1688003996.044 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-06-29T01:59:56.142Z,1688003996.142 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-06-29T01:59:56.157Z,1688003996.157 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-06-29T01:59:56.173Z,1688003996.173 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-06-29T01:59:56.175Z,1688003996.175 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-06-29T01:59:56.301Z,1688003996.301 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-06-29T01:59:56.303Z,1688003996.303 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-06-29T01:59:56.372Z,1688003996.372 [VerticalControl](DEBUG): Construct VerticalControl.
2023-06-29T01:59:56.431Z,1688003996.431 [VerticalControl] Loaded
2023-06-29T01:59:56.431Z,1688003996.431 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-06-29T01:59:56.434Z,1688003996.434 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-06-29T01:59:56.474Z,1688003996.474 [HorizontalControl] Loaded
2023-06-29T01:59:56.475Z,1688003996.475 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-06-29T01:59:56.477Z,1688003996.477 [SpeedControl](DEBUG): Construct SpeedControl.
2023-06-29T01:59:56.480Z,1688003996.480 [SpeedControl] Loaded
2023-06-29T01:59:56.481Z,1688003996.480 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-06-29T01:59:56.483Z,1688003996.483 [LoopControl](DEBUG): Construct LoopControl.
2023-06-29T01:59:56.483Z,1688003996.483 [LoopControl] Loaded
2023-06-29T01:59:56.484Z,1688003996.484 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-06-29T01:59:56.484Z,1688003996.484 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-06-29T01:59:56.486Z,1688003996.486 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-06-29T01:59:56.671Z,1688003996.671 [BuoyancyServo] Loaded
2023-06-29T01:59:56.672Z,1688003996.672 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-06-29T01:59:56.691Z,1688003996.691 [ElevatorServo] Loaded
2023-06-29T01:59:56.692Z,1688003996.692 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-06-29T01:59:56.711Z,1688003996.711 [MassServo] Loaded
2023-06-29T01:59:56.711Z,1688003996.711 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-06-29T01:59:56.729Z,1688003996.729 [RudderServo] Loaded
2023-06-29T01:59:56.729Z,1688003996.729 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-06-29T01:59:56.744Z,1688003996.744 [ThrusterHE] Loaded
2023-06-29T01:59:56.744Z,1688003996.744 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-06-29T01:59:56.745Z,1688003996.745 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-06-29T01:59:56.745Z,1688003996.745 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-06-29T01:59:56.815Z,1688003996.815 [DepthRateCalculator] Loaded
2023-06-29T01:59:56.816Z,1688003996.816 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-06-29T01:59:56.821Z,1688003996.821 [PitchRateCalculator] Loaded
2023-06-29T01:59:56.821Z,1688003996.821 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-06-29T01:59:56.830Z,1688003996.830 [SpeedCalculator] Loaded
2023-06-29T01:59:56.831Z,1688003996.831 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-06-29T01:59:56.835Z,1688003996.835 [YawRateCalculator] Loaded
2023-06-29T01:59:56.835Z,1688003996.835 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-06-29T01:59:56.853Z,1688003996.853 [ElevatorOffsetCalculator] Loaded
2023-06-29T01:59:56.853Z,1688003996.853 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-06-29T01:59:56.854Z,1688003996.854 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-06-29T01:59:56.856Z,1688003996.856 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-06-29T01:59:56.996Z,1688003996.996 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-06-29T01:59:56.996Z,1688003996.996 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-06-29T01:59:57.009Z,1688003997.009 [NavChart] Loaded
2023-06-29T01:59:57.010Z,1688003997.010 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-06-29T01:59:57.015Z,1688003997.015 [UniversalFixResidualReporter] Loaded
2023-06-29T01:59:57.015Z,1688003997.015 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-06-29T01:59:57.016Z,1688003997.016 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-06-29T01:59:57.018Z,1688003997.018 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-06-29T01:59:57.764Z,1688003997.764 [AHRS_M2] Loaded
2023-06-29T01:59:57.764Z,1688003997.764 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-06-29T01:59:58.013Z,1688003998.013 [BackseatComponent] Loaded
2023-06-29T01:59:58.014Z,1688003998.014 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-06-29T01:59:58.015Z,1688003998.015 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E54E0
2023-06-29T01:59:58.015Z,1688003998.015 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2023-06-29T01:59:58.018Z,1688003998.018 [LcmUniversalReporter] Loaded
2023-06-29T01:59:58.018Z,1688003998.018 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-06-29T01:59:58.024Z,1688003998.024 [BioacousticsDataBridge] Loaded
2023-06-29T01:59:58.025Z,1688003998.025 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2023-06-29T01:59:58.873Z,1688003998.873 [BPC1] Loaded
2023-06-29T01:59:58.873Z,1688003998.873 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-06-29T01:59:59.016Z,1688003999.016 [DataOverHttps] Loaded
2023-06-29T01:59:59.017Z,1688003999.017 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-06-29T01:59:59.018Z,1688003999.018 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409154E0
2023-06-29T01:59:59.018Z,1688003999.018 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2023-06-29T01:59:59.111Z,1688003999.111 [Depth_Keller] Loaded
2023-06-29T01:59:59.111Z,1688003999.111 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-06-29T01:59:59.116Z,1688003999.116 [DropWeight] Loaded
2023-06-29T01:59:59.116Z,1688003999.116 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-06-29T01:59:59.179Z,1688003999.179 [NAL9602] Loaded
2023-06-29T01:59:59.179Z,1688003999.179 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-06-29T01:59:59.209Z,1688003999.209 [Onboard] Loaded
2023-06-29T01:59:59.209Z,1688003999.209 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-06-29T01:59:59.210Z,1688003999.210 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409454E0
2023-06-29T01:59:59.211Z,1688003999.211 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2023-06-29T01:59:59.223Z,1688003999.223 [Power24vConverter] Loaded
2023-06-29T01:59:59.223Z,1688003999.223 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-06-29T01:59:59.269Z,1688003999.269 [Radio_Surface] Loaded
2023-06-29T01:59:59.270Z,1688003999.270 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-06-29T01:59:59.271Z,1688003999.271 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409754E0
2023-06-29T01:59:59.271Z,1688003999.271 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2023-06-29T01:59:59.322Z,1688003999.322 [Sonardyne_Nano] Loaded
2023-06-29T01:59:59.322Z,1688003999.322 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-06-29T01:59:59.323Z,1688003999.323 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-06-29T01:59:59.323Z,1688003999.323 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-06-29T01:59:59.955Z,1688003999.955 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-06-29T01:59:59.956Z,1688003999.956 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-06-29T02:00:00.145Z,1688004000.145 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-06-29T02:00:00.146Z,1688004000.146 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-06-29T02:00:00.189Z,1688004000.189 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-06-29T02:00:00.190Z,1688004000.190 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-06-29T02:00:00.406Z,1688004000.406 [CTD_Seabird] Loaded
2023-06-29T02:00:00.406Z,1688004000.406 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-06-29T02:00:00.407Z,1688004000.407 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0
2023-06-29T02:00:00.407Z,1688004000.407 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2023-06-29T02:00:00.428Z,1688004000.428 [PAR_Licor] Loaded
2023-06-29T02:00:00.428Z,1688004000.428 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-06-29T02:00:00.459Z,1688004000.459 [WetLabsBB2FL] Loaded
2023-06-29T02:00:00.459Z,1688004000.459 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-06-29T02:00:00.460Z,1688004000.460 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AD94E0
2023-06-29T02:00:00.460Z,1688004000.460 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2023-06-29T02:00:00.461Z,1688004000.461 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-06-29T02:00:00.462Z,1688004000.462 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-06-29T02:00:00.822Z,1688004000.822 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-06-29T02:00:00.822Z,1688004000.822 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-06-29T02:00:00.995Z,1688004000.995 [SBIT](DEBUG): Construct Startup Built In Test.
2023-06-29T02:00:01.004Z,1688004001.004 [SBIT] Loaded
2023-06-29T02:00:01.004Z,1688004001.004 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-06-29T02:00:01.007Z,1688004001.007 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-06-29T02:00:01.021Z,1688004001.021 [IBIT] Loaded
2023-06-29T02:00:01.021Z,1688004001.021 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-06-29T02:00:01.027Z,1688004001.027 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-06-29T02:00:01.131Z,1688004001.131 [CBIT] Loaded
2023-06-29T02:00:01.131Z,1688004001.131 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-06-29T02:00:01.132Z,1688004001.132 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-06-29T02:00:01.138Z,1688004001.138 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-06-29T02:00:01.141Z,1688004001.141 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-06-29T02:00:01.152Z,1688004001.152 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-06-29T02:00:01.153Z,1688004001.153 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC44E0
2023-06-29T02:00:01.153Z,1688004001.153 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2023-06-29T02:00:01.157Z,1688004001.157 [Supervisor](INFO): Main Thread ID is 829
2023-06-29T02:00:01.158Z,1688004001.158 [Supervisor](DEBUG): Running supervisor.
2023-06-29T02:00:01.158Z,1688004001.158 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2023-06-29T02:00:01.159Z,1688004001.159 [CommandExec](INFO): Initializing the command executive.
2023-06-29T02:00:01.160Z,1688004001.160 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2023-06-29T02:00:01.162Z,1688004001.162 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2023-06-29T02:00:01.163Z,1688004001.163 [controlThread](DEBUG): Initializing ControlThread
2023-06-29T02:00:01.163Z,1688004001.163 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-06-29T02:00:01.165Z,1688004001.165 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-06-29T02:00:01.166Z,1688004001.166 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-06-29T02:00:01.166Z,1688004001.166 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-06-29T02:00:01.168Z,1688004001.168 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-06-29T02:00:01.168Z,1688004001.168 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-06-29T02:00:01.169Z,1688004001.169 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-06-29T02:00:01.169Z,1688004001.169 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-06-29T02:00:01.169Z,1688004001.169 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-06-29T02:00:01.171Z,1688004001.171 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-06-29T02:00:01.171Z,1688004001.171 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-06-29T02:00:01.180Z,1688004001.180 [SBIT](INFO): Initialize SBIT Component.
2023-06-29T02:00:01.181Z,1688004001.181 [SBIT](IMPORTANT): git: 2023-06-27_B
2023-06-29T02:00:01.181Z,1688004001.181 [SBIT](INFO): git hash: ff84f56c2b48606b9e34bb544c9bbe31deaa129e
2023-06-29T02:00:01.181Z,1688004001.181 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-06-29T02:00:01.182Z,1688004001.182 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-06-29T02:00:01.183Z,1688004001.183 [SBIT](INFO): Beginning SBIT in 131.000000 seconds.
2023-06-29T02:00:01.184Z,1688004001.184 [IBIT](INFO): Initialize IBIT Component.
2023-06-29T02:00:01.185Z,1688004001.185 [CBIT](DEBUG): Initialize CBIT Component.
2023-06-29T02:00:01.186Z,1688004001.186 [logger ThreadHandler](INFO): Handler Thread ID is 933
2023-06-29T02:00:01.196Z,1688004001.196 [CBIT](DEBUG): Initialized mux pins.
2023-06-29T02:00:01.196Z,1688004001.196 [CBIT](DEBUG): Initializing the watchdog timer.
2023-06-29T02:00:01.204Z,1688004001.204 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2023-06-29T02:00:01.216Z,1688004001.216 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
2023-06-29T02:00:01.217Z,1688004001.217 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-06-29T02:00:01.220Z,1688004001.220 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-06-29T02:00:01.220Z,1688004001.220 [CBIT](DEBUG): Initializing heartbeat.
2023-06-29T02:00:01.228Z,1688004001.228 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
2023-06-29T02:00:01.246Z,1688004001.246 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
2023-06-29T02:00:01.268Z,1688004001.268 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938
2023-06-29T02:00:01.269Z,1688004001.269 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-06-29T02:00:01.273Z,1688004001.273 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940
2023-06-29T02:00:01.276Z,1688004001.276 [WetLabsBB2FL](INFO): Powering up
2023-06-29T02:00:01.277Z,1688004001.277 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942
2023-06-29T02:00:01.289Z,1688004001.289 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-06-29T02:00:01.289Z,1688004001.289 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-06-29T02:00:01.289Z,1688004001.289 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-06-29T02:00:01.290Z,1688004001.290 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-06-29T02:00:01.290Z,1688004001.290 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-06-29T02:00:01.290Z,1688004001.290 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-06-29T02:00:01.290Z,1688004001.290 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-06-29T02:00:01.290Z,1688004001.290 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-06-29T02:00:01.290Z,1688004001.290 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-06-29T02:00:01.291Z,1688004001.291 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-06-29T02:00:01.291Z,1688004001.291 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-06-29T02:00:01.291Z,1688004001.291 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-06-29T02:00:01.291Z,1688004001.291 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2023-06-29T02:00:01.291Z,1688004001.291 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2023-06-29T02:00:01.291Z,1688004001.291 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DD.000
2023-06-29T02:00:01.292Z,1688004001.292 [CBIT](DEBUG): Deactivating GF circuits.
2023-06-29T02:00:01.292Z,1688004001.292 [CBIT](DEBUG): Deactivating emergency mode.
2023-06-29T02:00:01.292Z,1688004001.292 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DD.000
2023-06-29T02:00:01.292Z,1688004001.292 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DE.000
2023-06-29T02:00:01.293Z,1688004001.293 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DE.000
2023-06-29T02:00:01.293Z,1688004001.293 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DF.000
2023-06-29T02:00:01.293Z,1688004001.293 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DF.000
2023-06-29T02:00:01.293Z,1688004001.293 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DG.000
2023-06-29T02:00:01.293Z,1688004001.293 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DG.000
2023-06-29T02:00:01.293Z,1688004001.293 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DH.000
2023-06-29T02:00:01.294Z,1688004001.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DH.000
2023-06-29T02:00:01.294Z,1688004001.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CC.000
2023-06-29T02:00:01.294Z,1688004001.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CC.000
2023-06-29T02:00:01.294Z,1688004001.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CD.000
2023-06-29T02:00:01.294Z,1688004001.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CD.000
2023-06-29T02:00:01.294Z,1688004001.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CE.000
2023-06-29T02:00:01.295Z,1688004001.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CE.000
2023-06-29T02:00:01.295Z,1688004001.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QE.000
2023-06-29T02:00:01.295Z,1688004001.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QE.000
2023-06-29T02:00:01.295Z,1688004001.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QF.000
2023-06-29T02:00:01.295Z,1688004001.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QF.000
2023-06-29T02:00:01.296Z,1688004001.296 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QG.000
2023-06-29T02:00:01.296Z,1688004001.296 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QG.000
2023-06-29T02:00:01.296Z,1688004001.296 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PB.000
2023-06-29T02:00:01.296Z,1688004001.296 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PB.000
2023-06-29T02:00:01.296Z,1688004001.296 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PC.000
2023-06-29T02:00:01.297Z,1688004001.297 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PC.000
2023-06-29T02:00:01.297Z,1688004001.297 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PD.000
2023-06-29T02:00:01.297Z,1688004001.297 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PD.000
2023-06-29T02:00:01.297Z,1688004001.297 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PE.000
2023-06-29T02:00:01.297Z,1688004001.297 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PE.000
2023-06-29T02:00:01.298Z,1688004001.298 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PF.000
2023-06-29T02:00:01.298Z,1688004001.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PF.000
2023-06-29T02:00:01.298Z,1688004001.298 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PG.000
2023-06-29T02:00:01.298Z,1688004001.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PG.000
2023-06-29T02:00:01.298Z,1688004001.298 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5WI22M.000
2023-06-29T02:00:01.298Z,1688004001.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5WI22M.000
2023-06-29T02:00:01.332Z,1688004001.332 [CBIT](DEBUG): Backplane powered.
2023-06-29T02:00:01.333Z,1688004001.333 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-06-29T02:00:01.360Z,1688004001.360 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-06-29T02:00:01.392Z,1688004001.392 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-06-29T02:00:01.392Z,1688004001.392 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-06-29T02:00:01.467Z,1688004001.467 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-06-29T02:00:01.469Z,1688004001.469 [Default:A.Wait](DEBUG): Construct Wait.
2023-06-29T02:00:01.471Z,1688004001.471 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-06-29T02:00:01.507Z,1688004001.507 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-06-29T02:00:01.522Z,1688004001.522 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-06-29T02:00:01.544Z,1688004001.544 [Default:E.Execute](DEBUG): Construct Execute.
2023-06-29T02:00:01.547Z,1688004001.547 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

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

    </Aggregate>

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

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

</Mission>


2023-06-29T02:00:01.552Z,1688004001.552 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-06-29T02:00:01.568Z,1688004001.568 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-06-29T02:00:01.652Z,1688004001.652 [Radio_Surface](INFO): Powering up
2023-06-29T02:00:01.754Z,1688004001.754 [Power24vConverter](INFO): Powering up.
2023-06-29T02:00:01.755Z,1688004001.755 [Sonardyne_Nano](INFO): Initializing.
2023-06-29T02:00:01.814Z,1688004001.814 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-06-29T02:00:01.820Z,1688004001.820 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-06-29T02:00:01.821Z,1688004001.821 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-06-29T02:00:01.828Z,1688004001.828 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-06-29T02:00:01.829Z,1688004001.829 [MassServo](DEBUG): Initializing EZServoServo.
2023-06-29T02:00:01.836Z,1688004001.836 [MassServo](DEBUG): Initializing MassServo.
2023-06-29T02:00:01.837Z,1688004001.837 [RudderServo](DEBUG): Initializing EZServoServo.
2023-06-29T02:00:01.844Z,1688004001.844 [RudderServo](DEBUG): Initializing RudderServo.
2023-06-29T02:00:01.845Z,1688004001.845 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-06-29T02:00:01.852Z,1688004001.852 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-06-29T02:00:03.384Z,1688004003.384 [Wet