2024-02-05T23:07:03.782Z,1707174423.782 [Supervisor](DEBUG): Initializing supervisor. 2024-02-05T23:07:03.787Z,1707174423.787 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-02-05T23:07:03.787Z,1707174423.787 [SyncHandler](INFO): Protected caller Thread ID is 1008 2024-02-05T23:07:03.788Z,1707174423.788 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-02-05T23:07:03.789Z,1707174423.789 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-02-05T23:07:03.790Z,1707174423.790 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1009 2024-02-05T23:07:03.794Z,1707174423.794 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-02-05T23:07:03.812Z,1707174423.812 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-02-05T23:07:03.813Z,1707174423.813 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-02-05T23:07:03.813Z,1707174423.813 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1010 2024-02-05T23:07:03.817Z,1707174423.817 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-02-05T23:07:03.818Z,1707174423.818 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-02-05T23:07:03.819Z,1707174423.819 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1011 2024-02-05T23:07:03.821Z,1707174423.821 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-02-05T23:07:03.822Z,1707174423.822 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-02-05T23:07:03.822Z,1707174423.822 [logger ThreadHandler](INFO): Protected caller Thread ID is 1012 2024-02-05T23:07:03.826Z,1707174423.826 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-02-05T23:07:03.826Z,1707174423.826 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-02-05T23:07:03.828Z,1707174423.828 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-02-05T23:07:04.165Z,1707174424.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-02-05T23:07:04.166Z,1707174424.166 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-02-05T23:07:04.247Z,1707174424.247 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-02-05T23:07:04.355Z,1707174424.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-02-05T23:07:04.356Z,1707174424.356 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-02-05T23:07:04.486Z,1707174424.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-02-05T23:07:04.486Z,1707174424.486 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-02-05T23:07:04.840Z,1707174424.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-02-05T23:07:04.840Z,1707174424.840 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-02-05T23:07:04.937Z,1707174424.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-02-05T23:07:04.937Z,1707174424.937 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-02-05T23:07:05.348Z,1707174425.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-02-05T23:07:05.348Z,1707174425.348 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-02-05T23:07:05.563Z,1707174425.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-02-05T23:07:05.564Z,1707174425.564 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-02-05T23:07:05.643Z,1707174425.643 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-02-05T23:07:05.885Z,1707174425.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-02-05T23:07:05.886Z,1707174425.886 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-02-05T23:07:06.109Z,1707174426.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-02-05T23:07:06.110Z,1707174426.110 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-02-05T23:07:06.634Z,1707174426.634 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-02-05T23:07:06.635Z,1707174426.635 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-02-05T23:07:06.833Z,1707174426.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-02-05T23:07:06.834Z,1707174426.834 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-02-05T23:07:07.126Z,1707174427.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-02-05T23:07:07.127Z,1707174427.127 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-02-05T23:07:07.953Z,1707174427.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-02-05T23:07:07.954Z,1707174427.954 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-02-05T23:07:08.498Z,1707174428.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-02-05T23:07:08.500Z,1707174428.500 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2024-02-05T23:07:08.501Z,1707174428.501 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2024-02-05T23:07:08.613Z,1707174428.613 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2024-02-05T23:07:08.733Z,1707174428.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2024-02-05T23:07:08.817Z,1707174428.817 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2024-02-05T23:07:08.927Z,1707174428.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2024-02-05T23:07:09.029Z,1707174429.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2024-02-05T23:07:09.291Z,1707174429.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-02-05T23:07:09.292Z,1707174429.292 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2024-02-05T23:07:09.408Z,1707174429.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2024-02-05T23:07:09.503Z,1707174429.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2024-02-05T23:07:09.608Z,1707174429.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2024-02-05T23:07:09.745Z,1707174429.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2024-02-05T23:07:09.832Z,1707174429.832 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/ 2024-02-05T23:07:09.832Z,1707174429.832 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-02-05T23:07:09.839Z,1707174429.839 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-02-05T23:07:09.950Z,1707174429.950 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-02-05T23:07:09.951Z,1707174429.951 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-02-05T23:07:10.003Z,1707174430.003 [VerticalControl](DEBUG): Construct VerticalControl. 2024-02-05T23:07:10.064Z,1707174430.064 [VerticalControl] Loaded 2024-02-05T23:07:10.064Z,1707174430.064 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-02-05T23:07:10.067Z,1707174430.067 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-02-05T23:07:10.109Z,1707174430.109 [HorizontalControl] Loaded 2024-02-05T23:07:10.109Z,1707174430.109 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-02-05T23:07:10.111Z,1707174430.111 [SpeedControl](DEBUG): Construct SpeedControl. 2024-02-05T23:07:10.114Z,1707174430.114 [SpeedControl] Loaded 2024-02-05T23:07:10.115Z,1707174430.115 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-02-05T23:07:10.117Z,1707174430.117 [LoopControl](DEBUG): Construct LoopControl. 2024-02-05T23:07:10.118Z,1707174430.118 [LoopControl] Loaded 2024-02-05T23:07:10.118Z,1707174430.118 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-02-05T23:07:10.118Z,1707174430.118 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-02-05T23:07:10.119Z,1707174430.119 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-02-05T23:07:10.175Z,1707174430.175 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-02-05T23:07:10.176Z,1707174430.176 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-02-05T23:07:10.625Z,1707174430.625 [AHRS_M2] Loaded 2024-02-05T23:07:10.625Z,1707174430.625 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-02-05T23:07:10.660Z,1707174430.660 [BackseatComponent] Loaded 2024-02-05T23:07:10.661Z,1707174430.661 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-02-05T23:07:10.662Z,1707174430.662 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4088A4E0 2024-02-05T23:07:10.662Z,1707174430.662 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1094 2024-02-05T23:07:10.665Z,1707174430.665 [LcmUniversalReporter] Loaded 2024-02-05T23:07:10.665Z,1707174430.665 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-02-05T23:07:11.456Z,1707174431.456 [BPC1] Loaded 2024-02-05T23:07:11.457Z,1707174431.457 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-02-05T23:07:11.529Z,1707174431.529 [DataOverHttps] Loaded 2024-02-05T23:07:11.529Z,1707174431.529 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-02-05T23:07:11.530Z,1707174431.530 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0 2024-02-05T23:07:11.531Z,1707174431.531 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1095 2024-02-05T23:07:11.551Z,1707174431.551 [Depth_Keller] Loaded 2024-02-05T23:07:11.551Z,1707174431.551 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-02-05T23:07:11.556Z,1707174431.556 [DropWeight] Loaded 2024-02-05T23:07:11.556Z,1707174431.556 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-02-05T23:07:11.618Z,1707174431.618 [NAL9602] Loaded 2024-02-05T23:07:11.618Z,1707174431.618 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-02-05T23:07:11.647Z,1707174431.647 [Onboard] Loaded 2024-02-05T23:07:11.648Z,1707174431.648 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-02-05T23:07:11.649Z,1707174431.649 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0 2024-02-05T23:07:11.649Z,1707174431.649 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1096 2024-02-05T23:07:11.661Z,1707174431.661 [Power24vConverter] Loaded 2024-02-05T23:07:11.661Z,1707174431.661 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-02-05T23:07:11.674Z,1707174431.674 [Radio_Surface] Loaded 2024-02-05T23:07:11.675Z,1707174431.675 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-02-05T23:07:11.676Z,1707174431.676 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0 2024-02-05T23:07:11.676Z,1707174431.676 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1097 2024-02-05T23:07:11.690Z,1707174431.690 [Sonardyne_Nano] Loaded 2024-02-05T23:07:11.690Z,1707174431.690 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-02-05T23:07:11.779Z,1707174431.779 [DAT] Loaded 2024-02-05T23:07:11.779Z,1707174431.779 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-02-05T23:07:11.780Z,1707174431.780 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0 2024-02-05T23:07:11.781Z,1707174431.781 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1098 2024-02-05T23:07:11.803Z,1707174431.803 [Waterlinked] Loaded 2024-02-05T23:07:11.804Z,1707174431.804 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2024-02-05T23:07:11.804Z,1707174431.804 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-02-05T23:07:11.804Z,1707174431.804 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-02-05T23:07:11.815Z,1707174431.815 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-02-05T23:07:11.815Z,1707174431.815 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-02-05T23:07:11.841Z,1707174431.841 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-02-05T23:07:11.842Z,1707174431.842 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-02-05T23:07:11.984Z,1707174431.984 [SBIT](DEBUG): Construct Startup Built In Test. 2024-02-05T23:07:11.993Z,1707174431.993 [SBIT] Loaded 2024-02-05T23:07:11.993Z,1707174431.993 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-02-05T23:07:11.996Z,1707174431.996 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-02-05T23:07:12.009Z,1707174432.009 [IBIT] Loaded 2024-02-05T23:07:12.009Z,1707174432.009 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-02-05T23:07:12.015Z,1707174432.015 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-02-05T23:07:12.121Z,1707174432.121 [CBIT] Loaded 2024-02-05T23:07:12.121Z,1707174432.121 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-02-05T23:07:12.121Z,1707174432.121 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-02-05T23:07:12.122Z,1707174432.122 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-02-05T23:07:12.456Z,1707174432.456 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-02-05T23:07:12.456Z,1707174432.456 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-02-05T23:07:12.505Z,1707174432.505 [DepthRateCalculator] Loaded 2024-02-05T23:07:12.505Z,1707174432.505 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-02-05T23:07:12.510Z,1707174432.510 [PitchRateCalculator] Loaded 2024-02-05T23:07:12.510Z,1707174432.510 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-02-05T23:07:12.521Z,1707174432.521 [SpeedCalculator] Loaded 2024-02-05T23:07:12.521Z,1707174432.521 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-02-05T23:07:12.526Z,1707174432.526 [YawRateCalculator] Loaded 2024-02-05T23:07:12.526Z,1707174432.526 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-02-05T23:07:12.544Z,1707174432.544 [ElevatorOffsetCalculator] Loaded 2024-02-05T23:07:12.545Z,1707174432.545 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-02-05T23:07:12.545Z,1707174432.545 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-02-05T23:07:12.546Z,1707174432.546 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-02-05T23:07:12.694Z,1707174432.694 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-02-05T23:07:12.694Z,1707174432.694 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-02-05T23:07:12.805Z,1707174432.805 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-02-05T23:07:12.805Z,1707174432.805 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-02-05T23:07:12.847Z,1707174432.847 [DeadReckonUsingSpeedCalculator] Loaded 2024-02-05T23:07:12.848Z,1707174432.848 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-02-05T23:07:12.890Z,1707174432.890 [DeadReckonWithRespectToSeafloor] Loaded 2024-02-05T23:07:12.890Z,1707174432.890 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2024-02-05T23:07:12.904Z,1707174432.904 [NavChart] Loaded 2024-02-05T23:07:12.904Z,1707174432.904 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-02-05T23:07:12.909Z,1707174432.909 [UniversalFixResidualReporter] Loaded 2024-02-05T23:07:12.910Z,1707174432.910 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-02-05T23:07:12.910Z,1707174432.910 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-02-05T23:07:12.911Z,1707174432.911 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-02-05T23:07:13.040Z,1707174433.040 [BuoyancyServo] Loaded 2024-02-05T23:07:13.040Z,1707174433.040 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-02-05T23:07:13.061Z,1707174433.061 [ElevatorServo] Loaded 2024-02-05T23:07:13.061Z,1707174433.061 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-02-05T23:07:13.081Z,1707174433.081 [MassServo] Loaded 2024-02-05T23:07:13.081Z,1707174433.081 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-02-05T23:07:13.100Z,1707174433.100 [RudderServo] Loaded 2024-02-05T23:07:13.100Z,1707174433.100 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-02-05T23:07:13.115Z,1707174433.115 [ThrusterHE] Loaded 2024-02-05T23:07:13.115Z,1707174433.115 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-02-05T23:07:13.116Z,1707174433.116 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-02-05T23:07:13.116Z,1707174433.116 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-02-05T23:07:13.290Z,1707174433.290 [CTD_Seabird] Loaded 2024-02-05T23:07:13.290Z,1707174433.290 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-02-05T23:07:13.291Z,1707174433.291 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BB14E0 2024-02-05T23:07:13.292Z,1707174433.292 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1099 2024-02-05T23:07:13.325Z,1707174433.325 [ESPComponent] Loaded 2024-02-05T23:07:13.325Z,1707174433.325 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-02-05T23:07:13.345Z,1707174433.345 [PAR_Licor] Loaded 2024-02-05T23:07:13.346Z,1707174433.346 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-02-05T23:07:13.387Z,1707174433.387 [WetLabsBB2FL] Loaded 2024-02-05T23:07:13.388Z,1707174433.388 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-02-05T23:07:13.389Z,1707174433.389 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BE14E0 2024-02-05T23:07:13.389Z,1707174433.389 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1100 2024-02-05T23:07:13.390Z,1707174433.390 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-02-05T23:07:13.396Z,1707174433.396 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-02-05T23:07:13.399Z,1707174433.399 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-02-05T23:07:13.410Z,1707174433.410 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-02-05T23:07:13.411Z,1707174433.411 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C114E0 2024-02-05T23:07:13.411Z,1707174433.411 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1101 2024-02-05T23:07:13.416Z,1707174433.416 [Supervisor](INFO): Main Thread ID is 829 2024-02-05T23:07:13.416Z,1707174433.416 [Supervisor](DEBUG): Running supervisor. 2024-02-05T23:07:13.417Z,1707174433.417 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1102 2024-02-05T23:07:13.417Z,1707174433.417 [CommandExec](INFO): Initializing the command executive. 2024-02-05T23:07:13.418Z,1707174433.418 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1103 2024-02-05T23:07:13.421Z,1707174433.421 [controlThread ThreadHandler](INFO): Handler Thread ID is 1104 2024-02-05T23:07:13.421Z,1707174433.421 [controlThread](DEBUG): Initializing ControlThread 2024-02-05T23:07:13.422Z,1707174433.422 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-02-05T23:07:13.424Z,1707174433.424 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-02-05T23:07:13.425Z,1707174433.425 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-02-05T23:07:13.425Z,1707174433.425 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-02-05T23:07:13.430Z,1707174433.430 [SBIT](INFO): Initialize SBIT Component. 2024-02-05T23:07:13.430Z,1707174433.430 [SBIT](IMPORTANT): git: 2024-02-05_A 2024-02-05T23:07:13.430Z,1707174433.430 [SBIT](INFO): git hash: 89bfde8c3b4737bb79d532c259c4b57f363cbf19 2024-02-05T23:07:13.431Z,1707174433.431 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-02-05T23:07:13.432Z,1707174433.432 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2024-02-05T23:07:13.433Z,1707174433.433 [SBIT](INFO): Beginning SBIT in 99.000000 seconds. 2024-02-05T23:07:13.433Z,1707174433.433 [IBIT](INFO): Initialize IBIT Component. 2024-02-05T23:07:13.434Z,1707174433.434 [CBIT](DEBUG): Initialize CBIT Component. 2024-02-05T23:07:13.435Z,1707174433.435 [logger ThreadHandler](INFO): Handler Thread ID is 1105 2024-02-05T23:07:13.448Z,1707174433.448 [CBIT](DEBUG): Initialized mux pins. 2024-02-05T23:07:13.448Z,1707174433.448 [CBIT](DEBUG): Initializing the watchdog timer. 2024-02-05T23:07:13.456Z,1707174433.456 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1106 2024-02-05T23:07:13.468Z,1707174433.468 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1107 2024-02-05T23:07:13.470Z,1707174433.470 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-02-05T23:07:13.472Z,1707174433.472 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-02-05T23:07:13.472Z,1707174433.472 [CBIT](DEBUG): Initializing heartbeat. 2024-02-05T23:07:13.480Z,1707174433.480 [Onboard ThreadHandler](INFO): Handler Thread ID is 1108 2024-02-05T23:07:13.497Z,1707174433.497 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1109 2024-02-05T23:07:13.521Z,1707174433.521 [DAT ThreadHandler](INFO): Handler Thread ID is 1110 2024-02-05T23:07:13.521Z,1707174433.521 [DAT](INFO): Powering up 2024-02-05T23:07:13.522Z,1707174433.522 [DAT](DEBUG): Initializing DAT. 2024-02-05T23:07:13.525Z,1707174433.525 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1112 2024-02-05T23:07:13.526Z,1707174433.526 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-02-05T23:07:13.529Z,1707174433.529 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1114 2024-02-05T23:07:13.532Z,1707174433.532 [WetLabsBB2FL](INFO): Powering up 2024-02-05T23:07:13.533Z,1707174433.533 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1116 2024-02-05T23:07:13.536Z,1707174433.536 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2024-02-05T23:07:13.537Z,1707174433.537 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2024-02-05T23:07:13.537Z,1707174433.537 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2024-02-05T23:07:13.537Z,1707174433.537 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2024-02-05T23:07:13.537Z,1707174433.537 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-02-05T23:07:13.537Z,1707174433.537 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-02-05T23:07:13.537Z,1707174433.537 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-02-05T23:07:13.538Z,1707174433.538 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-02-05T23:07:13.538Z,1707174433.538 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2024-02-05T23:07:13.538Z,1707174433.538 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2024-02-05T23:07:13.538Z,1707174433.538 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2024-02-05T23:07:13.538Z,1707174433.538 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2024-02-05T23:07:13.544Z,1707174433.544 [CBIT](DEBUG): Deactivating GF circuits. 2024-02-05T23:07:13.544Z,1707174433.544 [CBIT](DEBUG): Deactivating emergency mode. 2024-02-05T23:07:13.584Z,1707174433.584 [CBIT](DEBUG): Backplane powered. 2024-02-05T23:07:13.584Z,1707174433.584 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-02-05T23:07:13.584Z,1707174433.584 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-02-05T23:07:13.585Z,1707174433.585 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-02-05T23:07:13.585Z,1707174433.585 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-02-05T23:07:13.585Z,1707174433.585 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-02-05T23:07:13.586Z,1707174433.586 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-05T23:07:13.587Z,1707174433.587 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-02-05T23:07:13.587Z,1707174433.587 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-02-05T23:07:13.593Z,1707174433.593 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-02-05T23:07:13.593Z,1707174433.593 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-02-05T23:07:13.607Z,1707174433.607 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-05T23:07:13.631Z,1707174433.631 [MissionManager](DEBUG): mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-02-05T23:07:13.631Z,1707174433.631 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-02-05T23:07:13.644Z,1707174433.644 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-02-05T23:07:13.717Z,1707174433.717 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-02-05T23:07:13.719Z,1707174433.719 [Default:A.Wait](DEBUG): Construct Wait. 2024-02-05T23:07:13.729Z,1707174433.729 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-05T23:07:13.761Z,1707174433.761 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-02-05T23:07:13.780Z,1707174433.780 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-02-05T23:07:13.785Z,1707174433.785 [Default:E.Execute](DEBUG): Construct Execute. 2024-02-05T23:07:13.804Z,1707174433.805 [MissionManager](DEBUG): mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-02-05T23:07:13.810Z,1707174433.810 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-02-05T23:07:13.834Z,1707174433.834 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-02-05T23:07:13.905Z,1707174433.905 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2024-02-05T23:07:13.906Z,1707174433.906 [Power24vConverter](INFO): Powering up. 2024-02-05T23:07:13.907Z,1707174433.907 [Sonardyne_Nano](INFO): Initializing. 2024-02-05T23:07:13.912Z,1707174433.912 [Radio_Surface](INFO): Powering up 2024-02-05T23:07:13.942Z,1707174433.942 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-02-05T23:07:13.998Z,1707174433.998 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-02-05T23:07:14.004Z,1707174434.004 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-02-05T23:07:14.005Z,1707174434.005 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-02-05T23:07:14.016Z,1707174434.016 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-02-05T23:07:14.017Z,1707174434.017 [MassServo](DEBUG): Initializing EZServoServo. 2024-02-05T23:07:14.025Z,1707174434.025 [MassServo](DEBUG): Initializing MassServo. 2024-02-05T23:07:14.025Z,1707174434.025 [RudderServo](DEBUG): Initializing EZServoServo. 2024-02-05T23:07:14.032Z,1707174434.032 [RudderServo](DEBUG): Initializing RudderServo. 2024-02-05T23:07:14.033Z,1707174434.033 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-02-05T23:07:14.044Z,1707174434.044 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-02-05T23:07:15.640Z,1707174435.640 [WetLabsBB2FL](INFO): Powering down 2024-02-05T23:07:15.838Z,1707174435.838 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-02-05T23:07:17.124Z,1707174437.124 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2024-02-05T23:07:19.713Z,1707174439.713 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-02-05T23:07:38.153Z,1707174458.153 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2024-02-05T23:07:41.739Z,1707174461.739 [NAL9602](INFO): Powering up NAL9602 2024-02-05T23:07:45.394Z,1707174465.394 [Waterlinked](ERROR): only read 0 of 5 data items 2024-02-05T23:07:52.632Z,1707174472.632 [NAL9602](INFO): NAL9602 initialized 2024-02-05T23:08:01.200Z,1707174481.200 [Waterlinked](INFO): Powering down 2024-02-05T23:08:13.532Z,1707174493.532 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2024-02-05T23:08:13.532Z,1707174493.532 [DAT] Communications Fault, FailCount= 1 2024-02-05T23:08:13.532Z,1707174493.532 [DAT](ERROR): Communications Fault 2024-02-05T23:08:13.675Z,1707174493.675 [CBIT](ERROR): Communications Fault in component: DAT 2024-02-05T23:08:13.936Z,1707174493.936 [DAT](INFO): Powering down 2024-02-05T23:08:14.896Z,1707174494.896 [CBIT](INFO): Clearing failed state for component DAT 2024-02-05T23:08:14.896Z,1707174494.896 [DAT] No Fault, FailCount= 1 2024-02-05T23:08:16.984Z,1707174496.984 [DAT](INFO): Powering up 2024-02-05T23:08:16.984Z,1707174496.984 [DAT](DEBUG): Initializing DAT. 2024-02-05T23:08:52.846Z,1707174532.846 [SBIT](IMPORTANT): Beginning Startup BIT 2024-02-05T23:08:52.850Z,1707174532.850 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-05T23:08:55.264Z,1707174535.264 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-05T23:09:04.008Z,1707174544.008 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000091 CHAN A1 (24V): 0.000489 CHAN A2 (12V): 0.000701 CHAN A3 (5V): -0.001072 CHAN B0 (3.3V): -0.000128 CHAN B1 (3.15aV): -0.000770 CHAN B2 (3.15bV): 0.000084 CHAN B3 (GND): -0.001057 OPEN: 0.007986 Full Scale: +/- 1 mA 2024-02-05T23:09:10.193Z,1707174550.193 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-05T23:09:16.000Z,1707174557.000 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2024-02-05T23:09:17.000Z,1707174557.000 [DAT] Communications Fault, FailCount= 2 2024-02-05T23:09:17.000Z,1707174557.000 [DAT](ERROR): Communications Fault 2024-02-05T23:09:17.122Z,1707174557.122 [CBIT](ERROR): Communications Fault in component: DAT 2024-02-05T23:09:17.404Z,1707174557.404 [DAT](INFO): Powering down 2024-02-05T23:09:18.332Z,1707174558.332 [CBIT](INFO): Clearing failed state for component DAT 2024-02-05T23:09:18.333Z,1707174558.333 [DAT] No Fault, FailCount= 2 2024-02-05T23:09:20.464Z,1707174560.464 [DAT](INFO): Powering up 2024-02-05T23:09:20.464Z,1707174560.464 [DAT](DEBUG): Initializing DAT. 2024-02-05T23:09:25.544Z,1707174565.544 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-05T23:09:41.306Z,1707174581.306 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-05T23:09:46.614Z,1707174586.614 [SBIT](IMPORTANT): SBIT PASSED 2024-02-05T23:09:46.614Z,1707174586.614 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-02-05T23:09:46.615Z,1707174586.615 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count; 2024-02-05T23:09:46.615Z,1707174586.615 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2024-02-05T23:09:46.615Z,1707174586.615 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 10.000000 count_per_second; 2024-02-05T23:09:46.615Z,1707174586.615 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=99.044705 cubic_centimeter; 2024-02-05T23:09:46.615Z,1707174586.615 [SBIT](IMPORTANT): VerticalControl.massDefault=19.870889 millimeter; 2024-02-05T23:09:46.982Z,1707174586.982 [MissionManager](IMPORTANT): Started mission Startup 2024-02-05T23:09:46.982Z,1707174586.982 [Startup] Running Loop=1 2024-02-05T23:09:46.983Z,1707174586.983 [Startup](DEBUG): Aggregate::initialize Startup 2024-02-05T23:09:46.983Z,1707174586.983 [Startup:A.GoToSurface] Running Loop=1 2024-02-05T23:09:46.983Z,1707174586.983 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-05T23:09:46.983Z,1707174586.983 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-05T23:09:46.984Z,1707174586.984 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-05T23:09:46.984Z,1707174586.984 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-05T23:09:46.985Z,1707174586.985 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-05T23:09:46.985Z,1707174586.985 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-05T23:09:46.986Z,1707174586.986 [Startup:StartupSatComms] Running Loop=1 2024-02-05T23:09:46.986Z,1707174586.986 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-02-05T23:09:46.986Z,1707174586.986 [Startup:StartupSatComms:A] Running Loop=1 2024-02-05T23:09:47.396Z,1707174587.396 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-02-05T23:09:56.248Z,1707174596.248 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-05T23:09:56.370Z,1707174596.370 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004855 2024-02-05T23:10:11.602Z,1707174611.602 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-05T23:10:13.667Z,1707174613.667 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-02-05T23:10:13.667Z,1707174613.667 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-05T23:10:13.669Z,1707174613.669 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-02-05T23:10:13.669Z,1707174613.669 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-05T23:10:13.670Z,1707174613.670 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-05T23:10:13.670Z,1707174613.670 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:10:13.713Z,1707174613.713 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-05T23:10:13.714Z,1707174613.714 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-05T23:10:13.714Z,1707174613.714 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:10:14.063Z,1707174614.063 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-05T23:10:14.063Z,1707174614.063 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-02-05T23:10:14.063Z,1707174614.063 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-05T23:10:14.064Z,1707174614.064 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-02-05T23:10:14.064Z,1707174614.064 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:10:14.064Z,1707174614.064 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-05T23:10:14.449Z,1707174614.449 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-05T23:10:20.708Z,1707174620.708 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2024-02-05T23:10:20.709Z,1707174620.709 [DAT] Communications Fault, FailCount= 3 2024-02-05T23:10:20.709Z,1707174620.709 [DAT](ERROR): Communications Fault 2024-02-05T23:10:20.933Z,1707174620.933 [CBIT](ERROR): Communications Fault in component: DAT 2024-02-05T23:10:21.112Z,1707174621.112 [DAT](INFO): Powering down 2024-02-05T23:10:22.180Z,1707174622.180 [CBIT](INFO): Clearing failed state for component DAT 2024-02-05T23:10:22.180Z,1707174622.180 [DAT] No Fault, FailCount= 3 2024-02-05T23:10:24.160Z,1707174624.160 [DAT](INFO): Powering up 2024-02-05T23:10:24.160Z,1707174624.160 [DAT](DEBUG): Initializing DAT. 2024-02-05T23:10:26.960Z,1707174626.960 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-05T23:10:42.305Z,1707174642.305 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-05T23:10:47.181Z,1707174647.181 [Startup:StartupSatComms:A](INFO): Timed out from 2024-02-05T23:09:46.0Z 2024-02-05T23:10:47.181Z,1707174647.181 [Startup:StartupSatComms:A] Stopped 2024-02-05T23:10:47.181Z,1707174647.181 [Startup:StartupSatComms:B] Running Loop=1 2024-02-05T23:10:47.588Z,1707174647.588 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-05T23:10:56.603Z,1707174656.603 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240205T225314/Courier0007.lzma 2024-02-05T23:10:57.606Z,1707174657.606 [DataOverHttps](INFO): Moved sent file to Logs/20240205T225314/Courier0007.lzma.bak 2024-02-05T23:10:57.606Z,1707174657.606 [DataOverHttps](INFO): SBD MOMSN=19266590 2024-02-05T23:10:57.717Z,1707174657.717 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-05T23:11:13.069Z,1707174673.069 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2024-02-05T23:11:14.327Z,1707174674.327 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240205T230703/Courier0000.lzma 2024-02-05T23:11:15.329Z,1707174675.329 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0000.lzma.bak 2024-02-05T23:11:15.330Z,1707174675.330 [DataOverHttps](INFO): SBD MOMSN=19266592 2024-02-05T23:11:24.408Z,1707174684.408 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2024-02-05T23:11:24.408Z,1707174684.408 [DAT] Communications Fault, FailCount= 4 2024-02-05T23:11:24.408Z,1707174684.408 [DAT](ERROR): Communications Fault 2024-02-05T23:11:24.440Z,1707174684.440 [CBIT](ERROR): Communications Fault in component: DAT 2024-02-05T23:11:24.818Z,1707174684.818 [DAT](INFO): Powering down 2024-02-05T23:11:26.033Z,1707174686.033 [CBIT](INFO): Clearing failed state for component DAT 2024-02-05T23:11:26.033Z,1707174686.033 [DAT] No Fault, FailCount= 4 2024-02-05T23:11:27.864Z,1707174687.864 [DAT](INFO): Powering up 2024-02-05T23:11:27.864Z,1707174687.864 [DAT](DEBUG): Initializing DAT. 2024-02-05T23:11:30.919Z,1707174690.919 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240205T225314/Express0008.lzma 2024-02-05T23:11:31.921Z,1707174691.921 [DataOverHttps](INFO): Moved sent file to Logs/20240205T225314/Express0008.lzma.bak 2024-02-05T23:11:31.922Z,1707174691.922 [DataOverHttps](INFO): SBD MOMSN=19266595 2024-02-05T23:11:47.439Z,1707174707.439 [Startup:StartupSatComms:B](INFO): Timed out from 2024-02-05T23:10:47.2Z 2024-02-05T23:11:47.439Z,1707174707.439 [Startup:StartupSatComms:B] Stopped 2024-02-05T23:11:47.439Z,1707174707.439 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-02-05T23:11:47.440Z,1707174707.440 [Startup:StartupSatComms] Stopped 2024-02-05T23:11:47.440Z,1707174707.440 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-02-05T23:11:47.440Z,1707174707.440 [Startup](INFO): Completed Startup 2024-02-05T23:11:47.441Z,1707174707.441 [MissionManager](INFO): Startup is completed. 2024-02-05T23:11:47.441Z,1707174707.441 [MissionManager](INFO): Uninitializing Mission Startup 2024-02-05T23:11:47.441Z,1707174707.441 [Startup] Stopped 2024-02-05T23:11:47.441Z,1707174707.441 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-02-05T23:11:47.441Z,1707174707.441 [Startup:A.GoToSurface] Stopped 2024-02-05T23:11:47.441Z,1707174707.441 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-02-05T23:11:47.569Z,1707174707.569 [DataOverHttps](INFO): Sending 955 bytes from file Logs/20240205T230703/Express0001.lzma 2024-02-05T23:11:47.847Z,1707174707.847 [MissionManager](IMPORTANT): Started mission Default 2024-02-05T23:11:47.848Z,1707174707.848 [Default] Running Loop=1 2024-02-05T23:11:47.848Z,1707174707.848 [Default](DEBUG): Aggregate::initialize Default 2024-02-05T23:11:47.848Z,1707174707.848 [Default:B.GoToSurface] Running Loop=1 2024-02-05T23:11:47.848Z,1707174707.848 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-05T23:11:47.848Z,1707174707.848 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-05T23:11:47.848Z,1707174707.848 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-05T23:11:47.849Z,1707174707.849 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-05T23:11:47.849Z,1707174707.849 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-05T23:11:47.849Z,1707174707.849 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-05T23:11:47.850Z,1707174707.850 [Default:A.Wait] Running Loop=1 2024-02-05T23:11:47.850Z,1707174707.850 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-02-05T23:11:48.569Z,1707174708.569 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0001.lzma.bak 2024-02-05T23:11:48.570Z,1707174708.570 [DataOverHttps](INFO): SBD MOMSN=19266599 2024-02-05T23:12:01.184Z,1707174721.184 [Default:A.Wait](INFO): Done Waiting. 2024-02-05T23:12:01.184Z,1707174721.184 [Default:A.Wait] Stopped 2024-02-05T23:12:01.184Z,1707174721.184 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-02-05T23:12:01.585Z,1707174721.585 [Default:CheckIn] Running Loop=1 2024-02-05T23:12:01.585Z,1707174721.585 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-05T23:12:01.586Z,1707174721.586 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-05T23:12:01.972Z,1707174721.972 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-02-05T23:12:28.096Z,1707174748.096 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2024-02-05T23:12:28.096Z,1707174748.096 [DAT] Communications Fault, FailCount= 5 2024-02-05T23:12:28.096Z,1707174748.096 [DAT](ERROR): Communications Fault 2024-02-05T23:12:28.245Z,1707174748.245 [CBIT](ERROR): Communications Fault in component: DAT 2024-02-05T23:12:28.500Z,1707174748.500 [DAT](INFO): Powering down 2024-02-05T23:12:29.449Z,1707174749.449 [CBIT](INFO): Clearing failed state for component DAT 2024-02-05T23:12:29.449Z,1707174749.449 [DAT] No Fault, FailCount= 5 2024-02-05T23:12:31.544Z,1707174751.544 [DAT](INFO): Powering up 2024-02-05T23:12:31.544Z,1707174751.544 [DAT](DEBUG): Initializing DAT. 2024-02-05T23:12:56.099Z,1707174776.099 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-02-05T23:13:14.689Z,1707174794.689 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-02-05T23:13:14.689Z,1707174794.689 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-05T23:13:14.690Z,1707174794.690 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2024-02-05T23:13:14.690Z,1707174794.690 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-05T23:13:14.691Z,1707174794.691 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-05T23:13:14.691Z,1707174794.691 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:13:14.702Z,1707174794.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-05T23:13:14.702Z,1707174794.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-05T23:13:14.702Z,1707174794.702 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:13:15.123Z,1707174795.123 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-05T23:13:15.123Z,1707174795.123 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-02-05T23:13:15.124Z,1707174795.124 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-05T23:13:15.124Z,1707174795.124 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2024-02-05T23:13:15.124Z,1707174795.124 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:13:15.124Z,1707174795.124 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-05T23:13:15.513Z,1707174795.513 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-05T23:13:31.560Z,1707174811.560 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2024-02-05T23:13:31.560Z,1707174811.560 [DAT] Communications Fault, FailCount= 6 2024-02-05T23:13:31.560Z,1707174811.560 [DAT](ERROR): Communications Fault 2024-02-05T23:13:31.669Z,1707174811.669 [CBIT](ERROR): Communications Fault in component: DAT 2024-02-05T23:13:31.964Z,1707174811.964 [DAT](INFO): Powering down 2024-02-05T23:13:32.884Z,1707174812.884 [CBIT](INFO): Clearing failed state for component DAT 2024-02-05T23:13:32.884Z,1707174812.884 [DAT] No Fault, FailCount= 6 2024-02-05T23:13:35.008Z,1707174815.008 [DAT](INFO): Powering up 2024-02-05T23:13:35.008Z,1707174815.008 [DAT](DEBUG): Initializing DAT. 2024-02-05T23:13:38.542Z,1707174818.542 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 5. 2024-02-05T23:13:38.552Z,1707174818.552 [BPC1](INFO): Received data from all battery sticks. 2024-02-05T23:14:35.016Z,1707174875.016 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2024-02-05T23:14:35.016Z,1707174875.016 [DAT] Communications Fault, FailCount= 7 2024-02-05T23:14:35.016Z,1707174875.016 [DAT](ERROR): Communications Fault 2024-02-05T23:14:35.123Z,1707174875.123 [CBIT](ERROR): Communications Fault in component: DAT 2024-02-05T23:14:35.420Z,1707174875.420 [DAT](INFO): Powering down 2024-02-05T23:14:36.322Z,1707174876.322 [CBIT](INFO): Clearing failed state for component DAT 2024-02-05T23:14:36.322Z,1707174876.322 [DAT] No Fault, FailCount= 7 2024-02-05T23:14:38.476Z,1707174878.476 [DAT](INFO): Powering up 2024-02-05T23:14:38.476Z,1707174878.476 [DAT](DEBUG): Initializing DAT. 2024-02-05T23:15:38.712Z,1707174938.712 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2024-02-05T23:15:38.712Z,1707174938.712 [DAT] Communications Fault, FailCount= 8 2024-02-05T23:15:38.712Z,1707174938.712 [DAT](ERROR): Communications Fault 2024-02-05T23:15:38.963Z,1707174938.963 [CBIT](ERROR): Communications Fault in component: DAT 2024-02-05T23:15:38.963Z,1707174938.963 [CBIT](FAULT): Communications Fault in component: DAT 2024-02-05T23:15:39.116Z,1707174939.116 [DAT](INFO): Powering down 2024-02-05T23:16:15.681Z,1707174975.681 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-02-05T23:16:15.681Z,1707174975.681 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-05T23:16:15.682Z,1707174975.682 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2024-02-05T23:16:15.682Z,1707174975.682 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-05T23:16:15.684Z,1707174975.684 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2024-02-05T23:16:15.684Z,1707174975.684 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:16:15.694Z,1707174975.694 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-05T23:16:15.695Z,1707174975.695 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-05T23:16:15.695Z,1707174975.695 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:16:16.115Z,1707174976.115 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-05T23:16:16.115Z,1707174976.115 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-02-05T23:16:16.116Z,1707174976.116 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-05T23:16:16.116Z,1707174976.116 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2024-02-05T23:16:16.116Z,1707174976.116 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:16:16.116Z,1707174976.116 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2024-02-05T23:16:16.508Z,1707174976.508 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-05T23:17:01.775Z,1707175021.775 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-05T23:12:01.6Z 2024-02-05T23:17:01.775Z,1707175021.775 [Default:CheckIn:Read_GPS] Stopped 2024-02-05T23:17:01.775Z,1707175021.775 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-05T23:17:02.164Z,1707175022.164 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-05T23:17:08.399Z,1707175028.399 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240205T230703/Courier0004.lzma 2024-02-05T23:17:09.401Z,1707175029.401 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0004.lzma.bak 2024-02-05T23:17:09.402Z,1707175029.402 [DataOverHttps](INFO): SBD MOMSN=19266621 2024-02-05T23:17:24.872Z,1707175044.872 [DataOverHttps](INFO): Sending 299 bytes from file Logs/20240205T230703/Express0005.lzma 2024-02-05T23:17:25.876Z,1707175045.876 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0005.lzma.bak 2024-02-05T23:17:25.876Z,1707175045.876 [DataOverHttps](INFO): SBD MOMSN=19266623 2024-02-05T23:17:27.203Z,1707175047.203 [Default:CheckIn:Read_Iridium] Stopped 2024-02-05T23:17:27.203Z,1707175047.203 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-05T23:17:27.203Z,1707175047.203 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-05T23:17:53.853Z,1707175073.853 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-02-05T23:17:53.853Z,1707175073.853 [NAL9602] Data Fault, FailCount= 1 2024-02-05T23:17:53.853Z,1707175073.853 [NAL9602](ERROR): Data Fault 2024-02-05T23:17:53.894Z,1707175073.894 [CBIT](ERROR): Data Fault in component: NAL9602 2024-02-05T23:17:54.253Z,1707175074.253 [NAL9602](INFO): Powering down 2024-02-05T23:17:55.091Z,1707175075.091 [CBIT](INFO): Clearing failed state for component NAL9602 2024-02-05T23:17:55.091Z,1707175075.091 [NAL9602] No Fault, FailCount= 1 2024-02-05T23:18:14.457Z,1707175094.457 [Power24vConverter](INFO): Powering down. 2024-02-05T23:18:24.557Z,1707175104.557 [NAL9602](INFO): Powering up NAL9602 2024-02-05T23:18:35.465Z,1707175115.465 [NAL9602](INFO): NAL9602 initialized 2024-02-05T23:19:16.677Z,1707175156.677 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-02-05T23:19:16.677Z,1707175156.677 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-05T23:19:16.678Z,1707175156.678 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2024-02-05T23:19:16.678Z,1707175156.678 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-05T23:19:16.679Z,1707175156.679 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2024-02-05T23:19:16.680Z,1707175156.680 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:19:16.690Z,1707175156.690 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-05T23:19:16.691Z,1707175156.691 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-05T23:19:16.691Z,1707175156.691 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:19:17.108Z,1707175157.108 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-05T23:19:17.108Z,1707175157.108 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-02-05T23:19:17.109Z,1707175157.109 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-05T23:19:17.109Z,1707175157.109 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2024-02-05T23:19:17.109Z,1707175157.109 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:19:17.109Z,1707175157.109 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2024-02-05T23:19:17.509Z,1707175157.509 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-05T23:20:38.741Z,1707175238.741 [CBIT](INFO): Clearing failed state for component DAT 2024-02-05T23:20:38.741Z,1707175238.741 [DAT] No Fault, FailCount= 8 2024-02-05T23:20:38.956Z,1707175238.956 [DAT](INFO): Powering up 2024-02-05T23:20:38.956Z,1707175238.956 [DAT](DEBUG): Initializing DAT. 2024-02-05T23:20:39.086Z,1707175239.086 [Power24vConverter](INFO): Powering up. 2024-02-05T23:20:51.808Z,1707175251.808 [DAT](INFO): DAT read: 2024-02-05T23:20:51.810Z,1707175251.810 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-02-05T23:20:53.322Z,1707175253.322 [DAT](INFO): DAT read: MF Frequency Band 2024-02-05T23:20:53.573Z,1707175253.573 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-02-05T23:20:53.574Z,1707175253.574 [DAT](INFO): DAT read: Feb 5 2024 23:20:46 2024-02-05T23:20:54.581Z,1707175254.581 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-02-05T23:20:54.582Z,1707175254.582 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 2024-02-05T23:20:54.582Z,1707175254.582 [DAT](INFO): commRate: 800 2024-02-05T23:20:56.660Z,1707175256.660 [DAT](INFO): entering command mode 2024-02-05T23:20:56.860Z,1707175256.860 [DAT](INFO): DAT read: 2024-02-05T23:20:56.861Z,1707175256.861 [DAT](INFO): DAT read: user:1> 2024-02-05T23:20:56.861Z,1707175256.861 [DAT](INFO): setting verbose to 3 2024-02-05T23:20:57.112Z,1707175257.112 [DAT](INFO): DAT read: user:1> 2024-02-05T23:20:57.113Z,1707175257.113 [DAT](INFO): DAT read: Verbose | 3 2024-02-05T23:20:57.114Z,1707175257.114 [DAT](INFO): set verbose to 3 2024-02-05T23:20:57.114Z,1707175257.114 [DAT](INFO): setting DatVerbose to 27440 2024-02-05T23:20:57.364Z,1707175257.364 [DAT](INFO): DAT read: user:2> 2024-02-05T23:20:57.365Z,1707175257.365 [DAT](INFO): DAT read: DatVerbose | 27440 2024-02-05T23:20:57.366Z,1707175257.366 [DAT](INFO): set DatVerbose to 27440 2024-02-05T23:20:57.366Z,1707175257.366 [DAT](INFO): setting transmit power to 8 2024-02-05T23:20:57.616Z,1707175257.616 [DAT](INFO): DAT read: user:3> 2024-02-05T23:20:57.617Z,1707175257.617 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-02-05T23:20:57.618Z,1707175257.618 [DAT](INFO): set transmit power to 8 2024-02-05T23:20:57.618Z,1707175257.618 [DAT](INFO): setting local address to 1 2024-02-05T23:20:57.868Z,1707175257.868 [DAT](INFO): DAT read: user:4> 2024-02-05T23:20:57.869Z,1707175257.869 [DAT](INFO): DAT read: LocalAddr | 1 2024-02-05T23:20:57.870Z,1707175257.870 [DAT](INFO): set local address to 1 2024-02-05T23:20:57.871Z,1707175257.871 [DAT](INFO): Setting time to: 23:20:57 And date to:2/5/2024 2024-02-05T23:20:58.120Z,1707175258.120 [DAT](INFO): DAT read: user:5> 2024-02-05T23:20:58.121Z,1707175258.121 [DAT](INFO): DAT read: Mon Feb 5, 2024 23:20:57 2024-02-05T23:20:58.122Z,1707175258.122 [DAT](INFO): Local DAT time set to Mon Feb 5, 2024 23:20:57 2024-02-05T23:20:58.625Z,1707175258.625 [DAT](FAULT): LCB fault: Current Limiter Activated. 2024-02-05T23:20:58.625Z,1707175258.625 [DAT] Hardware Fault, FailCount= 1 2024-02-05T23:20:58.625Z,1707175258.625 [DAT](ERROR): Hardware Fault 2024-02-05T23:20:58.625Z,1707175258.625 [DAT](INFO): Powering down 2024-02-05T23:20:58.925Z,1707175258.925 [CBIT](ERROR): Hardware Fault in component: DAT 2024-02-05T23:20:59.108Z,1707175259.108 [DAT](INFO): Powering down 2024-02-05T23:20:59.512Z,1707175259.512 [DAT](FAULT): LCB fault: Current Limiter Activated. 2024-02-05T23:21:00.117Z,1707175260.117 [CBIT](INFO): Clearing failed state for component DAT 2024-02-05T23:21:00.117Z,1707175260.117 [DAT] No Fault, FailCount= 1 2024-02-05T23:21:02.336Z,1707175262.336 [DAT](INFO): Powering up 2024-02-05T23:21:02.336Z,1707175262.336 [DAT](DEBUG): Initializing DAT. 2024-02-05T23:21:06.009Z,1707175266.009 [DataOverHttps](IMPORTANT): SBD MTMSN=20240205T232104 2024-02-05T23:21:13.240Z,1707175273.240 [DataOverHttps](INFO): Received command: failc;strobe off 2024-02-05T23:21:13.273Z,1707175273.273 [CommandExec](IMPORTANT): got command failComponent 2024-02-05T23:21:13.273Z,1707175273.273 [CommandExec](IMPORTANT): Failed components: 2024-02-05T23:21:13.274Z,1707175273.274 [CommandExec](IMPORTANT): No failed Components. 2024-02-05T23:21:13.274Z,1707175273.274 [CommandExec](IMPORTANT): got command strobe off 2024-02-05T23:21:13.274Z,1707175273.274 [CommandExec](IMPORTANT): Deactivating strobe 2024-02-05T23:21:14.181Z,1707175274.181 [DAT](INFO): DAT read: 2024-02-05T23:21:14.182Z,1707175274.182 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-02-05T23:21:15.693Z,1707175275.693 [DAT](INFO): DAT read: MF Frequency Band 2024-02-05T23:21:15.694Z,1707175275.694 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-02-05T23:21:15.695Z,1707175275.695 [DAT](INFO): DAT read: Feb 5 2024 23:21:10 2024-02-05T23:21:16.953Z,1707175276.953 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-02-05T23:21:16.954Z,1707175276.954 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 2024-02-05T23:21:16.954Z,1707175276.954 [DAT](INFO): commRate: 800 2024-02-05T23:21:19.036Z,1707175279.036 [DAT](INFO): entering command mode 2024-02-05T23:21:19.236Z,1707175279.236 [DAT](INFO): DAT read: 2024-02-05T23:21:19.237Z,1707175279.237 [DAT](INFO): DAT read: user:1> 2024-02-05T23:21:19.237Z,1707175279.237 [DAT](INFO): setting verbose to 3 2024-02-05T23:21:19.488Z,1707175279.488 [DAT](INFO): DAT read: user:1> 2024-02-05T23:21:19.489Z,1707175279.489 [DAT](INFO): DAT read: Verbose | 3 2024-02-05T23:21:19.490Z,1707175279.490 [DAT](INFO): set verbose to 3 2024-02-05T23:21:19.490Z,1707175279.490 [DAT](INFO): setting DatVerbose to 27440 2024-02-05T23:21:19.740Z,1707175279.740 [DAT](INFO): DAT read: user:2> 2024-02-05T23:21:19.741Z,1707175279.741 [DAT](INFO): DAT read: DatVerbose | 27440 2024-02-05T23:21:19.742Z,1707175279.742 [DAT](INFO): set DatVerbose to 27440 2024-02-05T23:21:19.742Z,1707175279.742 [DAT](INFO): setting transmit power to 8 2024-02-05T23:21:19.992Z,1707175279.992 [DAT](INFO): DAT read: user:3> 2024-02-05T23:21:19.993Z,1707175279.993 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-02-05T23:21:19.994Z,1707175279.994 [DAT](INFO): set transmit power to 8 2024-02-05T23:21:19.994Z,1707175279.994 [DAT](INFO): setting local address to 1 2024-02-05T23:21:20.244Z,1707175280.244 [DAT](INFO): DAT read: user:4> 2024-02-05T23:21:20.245Z,1707175280.245 [DAT](INFO): DAT read: LocalAddr | 1 2024-02-05T23:21:20.246Z,1707175280.246 [DAT](INFO): set local address to 1 2024-02-05T23:21:20.247Z,1707175280.247 [DAT](INFO): Setting time to: 23:21:20 And date to:2/5/2024 2024-02-05T23:21:20.496Z,1707175280.496 [DAT](INFO): DAT read: user:5> 2024-02-05T23:21:20.497Z,1707175280.497 [DAT](INFO): DAT read: Mon Feb 5, 2024 23:21:20 2024-02-05T23:21:20.498Z,1707175280.498 [DAT](INFO): Local DAT time set to Mon Feb 5, 2024 23:21:20 2024-02-05T23:22:17.697Z,1707175337.697 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-02-05T23:22:17.697Z,1707175337.697 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-05T23:22:17.698Z,1707175337.698 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2024-02-05T23:22:17.698Z,1707175337.698 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-05T23:22:17.699Z,1707175337.699 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5 2024-02-05T23:22:17.699Z,1707175337.699 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:22:17.710Z,1707175337.710 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-05T23:22:17.710Z,1707175337.710 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-05T23:22:17.711Z,1707175337.711 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:22:18.095Z,1707175338.095 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-05T23:22:18.095Z,1707175338.095 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-02-05T23:22:18.095Z,1707175338.095 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-05T23:22:18.095Z,1707175338.095 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2024-02-05T23:22:18.120Z,1707175338.120 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:22:18.120Z,1707175338.120 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5 2024-02-05T23:22:18.478Z,1707175338.478 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-05T23:22:27.775Z,1707175347.775 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-05T23:22:27.775Z,1707175347.775 [Default:CheckIn:C.Wait] Stopped 2024-02-05T23:22:27.776Z,1707175347.776 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-05T23:22:27.776Z,1707175347.776 [Default:CheckIn:D] Running Loop=1 2024-02-05T23:22:28.180Z,1707175348.180 [Default:CheckIn:D] Stopped 2024-02-05T23:22:28.180Z,1707175348.180 [Default:CheckIn:E] Running Loop=1 2024-02-05T23:22:28.593Z,1707175348.593 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.672209 min 2024-02-05T23:22:28.593Z,1707175348.593 [Default:CheckIn:E] Stopped 2024-02-05T23:22:28.593Z,1707175348.593 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-05T23:22:28.594Z,1707175348.594 [Default:CheckIn] Stopped 2024-02-05T23:22:28.594Z,1707175348.594 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-05T23:22:28.594Z,1707175348.594 [Default:CheckIn](INFO): Running loop #2 2024-02-05T23:22:28.594Z,1707175348.594 [Default:CheckIn] Running Loop=2 2024-02-05T23:22:28.594Z,1707175348.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-05T23:22:28.594Z,1707175348.594 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-05T23:24:20.560Z,1707175460.560 [DAT](INFO): DAT read: user:6>Lowpower 2024-02-05T23:24:20.561Z,1707175460.561 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:6>Lowpower 2024-02-05T23:25:18.666Z,1707175518.666 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-02-05T23:25:18.666Z,1707175518.666 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-05T23:25:18.668Z,1707175518.668 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2024-02-05T23:25:18.668Z,1707175518.668 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-05T23:25:18.669Z,1707175518.669 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6 2024-02-05T23:25:18.669Z,1707175518.669 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:25:18.712Z,1707175518.712 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-05T23:25:18.712Z,1707175518.712 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-05T23:25:18.713Z,1707175518.713 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:25:19.073Z,1707175519.073 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-05T23:25:19.073Z,1707175519.073 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-02-05T23:25:19.074Z,1707175519.074 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-05T23:25:19.074Z,1707175519.074 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2024-02-05T23:25:19.075Z,1707175519.075 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:25:19.075Z,1707175519.075 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6 2024-02-05T23:25:19.492Z,1707175519.492 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-05T23:27:02.083Z,1707175622.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232700.00,A,3648.16846,N,12147.26409,W,0.661,74.21,050224,,,A*44 2024-02-05T23:27:02.087Z,1707175622.087 [NAL9602](INFO): GPS fix at 20240205T232700: (36.802808, -121.787735) 2024-02-05T23:27:02.129Z,1707175622.129 [Default:CheckIn:Read_GPS] Stopped 2024-02-05T23:27:02.129Z,1707175622.129 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-05T23:27:09.772Z,1707175629.772 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240205T230703/Courier0007.lzma 2024-02-05T23:27:10.773Z,1707175630.773 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0007.lzma.bak 2024-02-05T23:27:10.774Z,1707175630.774 [DataOverHttps](INFO): SBD MOMSN=19266635 2024-02-05T23:27:26.728Z,1707175646.728 [DataOverHttps](INFO): Sending 478 bytes from file Logs/20240205T230703/Express0008.lzma 2024-02-05T23:27:27.729Z,1707175647.729 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0008.lzma.bak 2024-02-05T23:27:27.730Z,1707175647.730 [DataOverHttps](INFO): SBD MOMSN=19266638 2024-02-05T23:27:34.417Z,1707175654.417 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-05T23:27:43.615Z,1707175663.615 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240205T230703/Express0011.lzma 2024-02-05T23:27:44.618Z,1707175664.618 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0011.lzma.bak 2024-02-05T23:27:44.618Z,1707175664.618 [DataOverHttps](INFO): SBD MOMSN=19266650 2024-02-05T23:27:45.742Z,1707175665.742 [Default:CheckIn:Read_Iridium] Stopped 2024-02-05T23:27:45.742Z,1707175665.742 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-05T23:27:45.742Z,1707175665.742 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-05T23:28:19.704Z,1707175699.704 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-05T23:28:19.704Z,1707175699.704 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:28:19.728Z,1707175699.728 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:28:20.127Z,1707175700.127 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:28:20.127Z,1707175700.127 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-05T23:28:57.017Z,1707175737.017 [DataOverHttps](IMPORTANT): SBD MTMSN=20240205T232856 2024-02-05T23:29:04.447Z,1707175744.447 [DataOverHttps](INFO): Received command: ibit 2024-02-05T23:29:04.492Z,1707175744.492 [CommandExec](IMPORTANT): got command ibit 2024-02-05T23:29:04.603Z,1707175744.603 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-02-05T23:29:04.616Z,1707175744.616 [IBIT](IMPORTANT): Beginning control surface checks. 2024-02-05T23:29:04.619Z,1707175744.619 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-05T23:29:06.172Z,1707175746.172 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232904.00,A,3648.13807,N,12147.23894,W,0.719,119.23,050224,,,A*75 2024-02-05T23:29:06.174Z,1707175746.174 [NAL9602](INFO): GPS fix at 20240205T232904: (36.802301, -121.787316) 2024-02-05T23:29:15.591Z,1707175755.591 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000393 CHAN A1 (24V): 0.000731 CHAN A2 (12V): 0.000107 CHAN A3 (5V): -0.001793 CHAN B0 (3.3V): 0.000546 CHAN B1 (3.15aV): -0.000234 CHAN B2 (3.15bV): -0.000089 CHAN B3 (GND): -0.000163 OPEN: 0.007939 Full Scale: +/- 1 mA 2024-02-05T23:29:26.375Z,1707175766.375 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=9457, MT Status=1, MTMSN=59 2024-02-05T23:29:26.375Z,1707175766.375 [NAL9602](INFO): Data available in MT queue 2024-02-05T23:29:26.884Z,1707175766.884 [NAL9602](INFO): Received command: show best depth;get depth 2024-02-05T23:29:27.136Z,1707175767.136 [CommandExec](IMPORTANT): got command show best depth 2024-02-05T23:29:27.137Z,1707175767.137 [CommandExec](IMPORTANT): depth best is Depth_Keller.depth 2024-02-05T23:29:27.137Z,1707175767.137 [CommandExec](IMPORTANT): got command get depth 2024-02-05T23:29:27.137Z,1707175767.137 [CommandExec](IMPORTANT): depth -0.132257 m 2024-02-05T23:29:27.989Z,1707175767.989 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232926.00,A,3648.13668,N,12147.23858,W,0.156,119.23,050224,,,D*7A 2024-02-05T23:29:27.991Z,1707175767.991 [NAL9602](INFO): GPS fix at 20240205T232926: (36.802278, -121.787310) 2024-02-05T23:29:30.819Z,1707175770.819 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232929.00,A,3648.13673,N,12147.23866,W,0.253,119.23,050224,,,D*74 2024-02-05T23:29:30.821Z,1707175770.821 [NAL9602](INFO): GPS fix at 20240205T232929: (36.802279, -121.787311) 2024-02-05T23:29:34.047Z,1707175774.047 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232932.00,A,3648.13658,N,12147.23855,W,0.330,119.23,050224,,,D*73 2024-02-05T23:29:34.049Z,1707175774.049 [NAL9602](INFO): GPS fix at 20240205T232932: (36.802276, -121.787309) 2024-02-05T23:29:36.879Z,1707175776.879 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232935.00,A,3648.13652,N,12147.23892,W,0.058,119.23,050224,,,D*78 2024-02-05T23:29:36.881Z,1707175776.881 [NAL9602](INFO): GPS fix at 20240205T232935: (36.802275, -121.787315) 2024-02-05T23:29:40.106Z,1707175780.106 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232938.00,A,3648.13623,N,12147.23907,W,0.058,119.23,050224,,,D*7E 2024-02-05T23:29:40.108Z,1707175780.108 [NAL9602](INFO): GPS fix at 20240205T232938: (36.802270, -121.787318) 2024-02-05T23:29:42.935Z,1707175782.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232941.00,A,3648.13586,N,12147.23903,W,0.233,119.23,050224,,,D*77 2024-02-05T23:29:42.937Z,1707175782.937 [NAL9602](INFO): GPS fix at 20240205T232941: (36.802264, -121.787317) 2024-02-05T23:29:46.167Z,1707175786.167 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232944.00,A,3648.13577,N,12147.23882,W,0.389,119.23,050224,,,D*74 2024-02-05T23:29:46.169Z,1707175786.169 [NAL9602](INFO): GPS fix at 20240205T232944: (36.802263, -121.787314) 2024-02-05T23:29:48.999Z,1707175788.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232947.00,A,3648.13574,N,12147.23872,W,0.000,119.23,050224,,,D*79 2024-02-05T23:29:49.001Z,1707175789.001 [NAL9602](INFO): GPS fix at 20240205T232947: (36.802262, -121.787312) 2024-02-05T23:29:50.668Z,1707175790.668 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 5 Latitude: 36.802261 Longitude: -121.787315 2024-02-05T23:29:51.033Z,1707175791.033 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.611000 2024-02-05T23:29:51.034Z,1707175791.034 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-02-05T23:29:51.034Z,1707175791.034 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-02-05T23:29:51.441Z,1707175791.441 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-02-05T23:29:51.441Z,1707175791.441 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-02-05T23:29:51.441Z,1707175791.441 [IBIT](IMPORTANT): Pressure:9.801476 PSI 2024-02-05T23:29:51.442Z,1707175791.442 [IBIT](IMPORTANT): Humidity:11.085338 % 2024-02-05T23:29:51.854Z,1707175791.854 [IBIT](IMPORTANT): Vehicle Pitch:0.596432 degrees 2024-02-05T23:29:51.855Z,1707175791.855 [IBIT](IMPORTANT): Vehicle Roll:0.924243 degrees 2024-02-05T23:29:51.855Z,1707175791.855 [IBIT](IMPORTANT): Vehicle Heading:126.408905 degrees 2024-02-05T23:29:52.264Z,1707175792.264 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-02-05T23:29:52.265Z,1707175792.265 [IBIT](IMPORTANT): buoyancyNeutral: 99.044708 cc 2024-02-05T23:29:52.265Z,1707175792.265 [IBIT](IMPORTANT): massDefault: 1.987089 cm 2024-02-05T23:29:52.265Z,1707175792.265 [IBIT](IMPORTANT): stopDepth: 112.000000 m 2024-02-05T23:29:52.265Z,1707175792.265 [IBIT](IMPORTANT): abortDepth: 130.000000 m 2024-02-05T23:29:52.265Z,1707175792.265 [IBIT](IMPORTANT): IBIT PASSED 2024-02-05T23:30:21.317Z,1707175821.317 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-05T23:31:20.721Z,1707175880.721 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-05T23:31:20.721Z,1707175880.721 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:31:20.733Z,1707175880.733 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:31:21.154Z,1707175881.154 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:31:21.154Z,1707175881.154 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-05T23:32:46.361Z,1707175966.361 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-05T23:32:46.361Z,1707175966.361 [Default:CheckIn:C.Wait] Stopped 2024-02-05T23:32:46.361Z,1707175966.361 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-05T23:32:46.361Z,1707175966.361 [Default:CheckIn:D] Running Loop=1 2024-02-05T23:32:46.773Z,1707175966.773 [Default:CheckIn:D] Stopped 2024-02-05T23:32:46.773Z,1707175966.773 [Default:CheckIn:E] Running Loop=1 2024-02-05T23:32:47.185Z,1707175967.185 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.982094 min 2024-02-05T23:32:47.185Z,1707175967.185 [Default:CheckIn:E] Stopped 2024-02-05T23:32:47.185Z,1707175967.185 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-05T23:32:47.186Z,1707175967.186 [Default:CheckIn] Stopped 2024-02-05T23:32:47.186Z,1707175967.186 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-05T23:32:47.186Z,1707175967.186 [Default:CheckIn](INFO): Running loop #3 2024-02-05T23:32:47.186Z,1707175967.186 [Default:CheckIn] Running Loop=3 2024-02-05T23:32:47.186Z,1707175967.186 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-05T23:32:47.186Z,1707175967.186 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-05T23:32:49.179Z,1707175969.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233247.00,A,3648.14069,N,12147.22824,W,0.000,15.48,050224,,,D*4F 2024-02-05T23:32:49.181Z,1707175969.181 [NAL9602](INFO): GPS fix at 20240205T233247: (36.802345, -121.787137) 2024-02-05T23:32:49.205Z,1707175969.205 [Default:CheckIn:Read_GPS] Stopped 2024-02-05T23:32:49.205Z,1707175969.205 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-05T23:32:59.347Z,1707175979.347 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240205T230703/Courier0013.lzma 2024-02-05T23:33:00.349Z,1707175980.349 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0013.lzma.bak 2024-02-05T23:33:00.350Z,1707175980.350 [DataOverHttps](INFO): SBD MOMSN=19266655 2024-02-05T23:33:03.424Z,1707175983.424 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=9458, MT Status=1, MTMSN=60 2024-02-05T23:33:03.424Z,1707175983.424 [NAL9602](INFO): Data available in MT queue 2024-02-05T23:33:03.920Z,1707175983.920 [NAL9602](INFO): Received command: ! echo 1 > /dev/loadB6 2024-02-05T23:33:03.957Z,1707175983.957 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB6 2024-02-05T23:33:16.359Z,1707175996.359 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240205T230703/Courier0016.lzma 2024-02-05T23:33:17.361Z,1707175997.361 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0016.lzma.bak 2024-02-05T23:33:17.362Z,1707175997.362 [DataOverHttps](INFO): SBD MOMSN=19266658 2024-02-05T23:33:33.265Z,1707176013.265 [DataOverHttps](INFO): Sending 914 bytes from file Logs/20240205T230703/Express0014.lzma 2024-02-05T23:33:34.265Z,1707176014.265 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0014.lzma.bak 2024-02-05T23:33:34.266Z,1707176014.266 [DataOverHttps](INFO): SBD MOMSN=19266661 2024-02-05T23:33:34.525Z,1707176014.525 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-05T23:33:50.179Z,1707176030.179 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240205T230703/Express0017.lzma 2024-02-05T23:33:51.182Z,1707176031.182 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0017.lzma.bak 2024-02-05T23:33:51.182Z,1707176031.182 [DataOverHttps](INFO): SBD MOMSN=19266692 2024-02-05T23:33:52.315Z,1707176032.315 [Default:CheckIn:Read_Iridium] Stopped 2024-02-05T23:33:52.315Z,1707176032.315 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-05T23:33:52.315Z,1707176032.315 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-05T23:34:21.797Z,1707176061.797 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-05T23:34:21.797Z,1707176061.797 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:34:21.808Z,1707176061.808 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:34:22.221Z,1707176062.221 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:34:22.221Z,1707176062.221 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-05T23:37:22.804Z,1707176242.804 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-05T23:37:22.804Z,1707176242.804 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:37:22.819Z,1707176242.819 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:37:23.228Z,1707176243.228 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:37:23.228Z,1707176243.228 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-05T23:38:52.900Z,1707176332.900 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-05T23:38:52.900Z,1707176332.900 [Default:CheckIn:C.Wait] Stopped 2024-02-05T23:38:52.900Z,1707176332.900 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-05T23:38:52.901Z,1707176332.901 [Default:CheckIn:D] Running Loop=1 2024-02-05T23:38:53.317Z,1707176333.317 [Default:CheckIn:D] Stopped 2024-02-05T23:38:53.317Z,1707176333.317 [Default:CheckIn:E] Running Loop=1 2024-02-05T23:38:53.703Z,1707176333.703 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.091162 min 2024-02-05T23:38:53.703Z,1707176333.703 [Default:CheckIn:E] Stopped 2024-02-05T23:38:53.703Z,1707176333.703 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-05T23:38:53.703Z,1707176333.703 [Default:CheckIn] Stopped 2024-02-05T23:38:53.703Z,1707176333.703 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-05T23:38:53.704Z,1707176333.704 [Default:CheckIn](INFO): Running loop #4 2024-02-05T23:38:53.704Z,1707176333.704 [Default:CheckIn] Running Loop=4 2024-02-05T23:38:53.704Z,1707176333.704 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-05T23:38:53.704Z,1707176333.704 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-05T23:38:55.703Z,1707176335.703 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233854.00,A,3648.15017,N,12147.22211,W,0.019,38.45,050224,,,A*4C 2024-02-05T23:38:55.705Z,1707176335.705 [NAL9602](INFO): GPS fix at 20240205T233854: (36.802503, -121.787035) 2024-02-05T23:38:55.740Z,1707176335.740 [Default:CheckIn:Read_GPS] Stopped 2024-02-05T23:38:55.740Z,1707176335.740 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-05T23:39:02.723Z,1707176342.723 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240205T230703/Courier0019.lzma 2024-02-05T23:39:03.725Z,1707176343.725 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0019.lzma.bak 2024-02-05T23:39:03.726Z,1707176343.726 [DataOverHttps](INFO): SBD MOMSN=19266694 2024-02-05T23:39:13.962Z,1707176353.962 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=9459, MT Status=1, MTMSN=61 2024-02-05T23:39:13.962Z,1707176353.962 [NAL9602](INFO): Data available in MT queue 2024-02-05T23:39:14.464Z,1707176354.464 [NAL9602](INFO): Received command: strobe off 2024-02-05T23:39:14.565Z,1707176354.565 [CommandExec](IMPORTANT): got command strobe off 2024-02-05T23:39:14.565Z,1707176354.565 [CommandExec](IMPORTANT): Deactivating strobe 2024-02-05T23:39:19.575Z,1707176359.575 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20240205T230703/Express0020.lzma 2024-02-05T23:39:20.578Z,1707176360.578 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0020.lzma.bak 2024-02-05T23:39:20.578Z,1707176360.578 [DataOverHttps](INFO): SBD MOMSN=19266697 2024-02-05T23:39:21.686Z,1707176361.686 [Default:CheckIn:Read_Iridium] Stopped 2024-02-05T23:39:21.686Z,1707176361.686 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-05T23:39:21.686Z,1707176361.686 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-05T23:39:45.136Z,1707176385.136 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-05T23:40:23.886Z,1707176423.886 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-05T23:40:23.886Z,1707176423.886 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:40:23.898Z,1707176423.898 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:40:24.309Z,1707176424.309 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:40:24.309Z,1707176424.309 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-05T23:43:24.876Z,1707176604.876 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-05T23:43:24.876Z,1707176604.876 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:43:24.887Z,1707176604.887 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:43:25.314Z,1707176605.314 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:43:25.314Z,1707176605.314 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-05T23:44:22.250Z,1707176662.250 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-05T23:44:22.250Z,1707176662.250 [Default:CheckIn:C.Wait] Stopped 2024-02-05T23:44:22.250Z,1707176662.250 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-05T23:44:22.250Z,1707176662.250 [Default:CheckIn:D] Running Loop=1 2024-02-05T23:44:22.662Z,1707176662.662 [Default:CheckIn:D] Stopped 2024-02-05T23:44:22.662Z,1707176662.662 [Default:CheckIn:E] Running Loop=1 2024-02-05T23:44:23.065Z,1707176663.065 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.580243 min 2024-02-05T23:44:23.065Z,1707176663.065 [Default:CheckIn:E] Stopped 2024-02-05T23:44:23.065Z,1707176663.065 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-05T23:44:23.065Z,1707176663.065 [Default:CheckIn] Stopped 2024-02-05T23:44:23.065Z,1707176663.065 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-05T23:44:23.065Z,1707176663.065 [Default:CheckIn](INFO): Running loop #5 2024-02-05T23:44:23.065Z,1707176663.065 [Default:CheckIn] Running Loop=5 2024-02-05T23:44:23.065Z,1707176663.065 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-05T23:44:23.065Z,1707176663.065 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-05T23:44:25.061Z,1707176665.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234424.00,A,3648.15037,N,12147.22077,W,0.039,110.84,050224,,,D*71 2024-02-05T23:44:25.064Z,1707176665.064 [NAL9602](INFO): GPS fix at 20240205T234424: (36.802506, -121.787013) 2024-02-05T23:44:25.096Z,1707176665.096 [Default:CheckIn:Read_GPS] Stopped 2024-02-05T23:44:25.096Z,1707176665.096 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-05T23:44:32.355Z,1707176672.355 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240205T230703/Courier0022.lzma 2024-02-05T23:44:33.358Z,1707176673.358 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0022.lzma.bak 2024-02-05T23:44:33.358Z,1707176673.358 [DataOverHttps](INFO): SBD MOMSN=19266703 2024-02-05T23:44:41.222Z,1707176681.222 [NAL9602](INFO): SBD MO Status=0, MOMSN=9460, MT Status=0, MTMSN=0 2024-02-05T23:44:41.223Z,1707176681.223 [NAL9602](INFO): No messages in MT queue 2024-02-05T23:44:50.564Z,1707176690.564 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20240205T230703/Express0023.lzma 2024-02-05T23:44:51.566Z,1707176691.566 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0023.lzma.bak 2024-02-05T23:44:51.566Z,1707176691.566 [DataOverHttps](INFO): SBD MOMSN=19266706 2024-02-05T23:44:52.975Z,1707176692.975 [Default:CheckIn:Read_Iridium] Stopped 2024-02-05T23:44:52.975Z,1707176692.975 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-05T23:44:52.975Z,1707176692.975 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-05T23:45:11.937Z,1707176711.937 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-05T23:46:25.881Z,1707176785.881 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-05T23:46:25.881Z,1707176785.881 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:46:25.893Z,1707176785.893 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:46:26.315Z,1707176786.315 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:46:26.315Z,1707176786.315 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-05T23:49:26.893Z,1707176966.893 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-05T23:49:26.893Z,1707176966.893 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:49:26.916Z,1707176966.916 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:49:27.286Z,1707176967.286 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:49:27.286Z,1707176967.286 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-05T23:49:53.539Z,1707176993.539 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-05T23:49:53.539Z,1707176993.539 [Default:CheckIn:C.Wait] Stopped 2024-02-05T23:49:53.540Z,1707176993.540 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-05T23:49:53.540Z,1707176993.540 [Default:CheckIn:D] Running Loop=1 2024-02-05T23:49:53.950Z,1707176993.950 [Default:CheckIn:D] Stopped 2024-02-05T23:49:53.951Z,1707176993.951 [Default:CheckIn:E] Running Loop=1 2024-02-05T23:49:54.366Z,1707176994.366 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.101717 min 2024-02-05T23:49:54.366Z,1707176994.366 [Default:CheckIn:E] Stopped 2024-02-05T23:49:54.366Z,1707176994.366 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-05T23:49:54.367Z,1707176994.367 [Default:CheckIn] Stopped 2024-02-05T23:49:54.367Z,1707176994.367 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-05T23:49:54.367Z,1707176994.367 [Default:CheckIn](INFO): Running loop #6 2024-02-05T23:49:54.367Z,1707176994.367 [Default:CheckIn] Running Loop=6 2024-02-05T23:49:54.367Z,1707176994.367 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-05T23:49:54.367Z,1707176994.367 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-05T23:49:56.359Z,1707176996.359 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234955.00,A,3648.19874,N,12147.16095,W,4.665,26.36,050224,,,D*44 2024-02-05T23:49:56.361Z,1707176996.361 [NAL9602](INFO): GPS fix at 20240205T234955: (36.803312, -121.786016) 2024-02-05T23:49:56.376Z,1707176996.376 [Default:CheckIn:Read_GPS] Stopped 2024-02-05T23:49:56.376Z,1707176996.376 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-05T23:50:04.007Z,1707177004.007 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240205T230703/Courier0025.lzma 2024-02-05T23:50:05.009Z,1707177005.009 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0025.lzma.bak 2024-02-05T23:50:05.010Z,1707177005.010 [DataOverHttps](INFO): SBD MOMSN=19266713 2024-02-05T23:50:12.518Z,1707177012.518 [NAL9602](INFO): SBD MO Status=0, MOMSN=9461, MT Status=0, MTMSN=0 2024-02-05T23:50:12.518Z,1707177012.518 [NAL9602](INFO): No messages in MT queue 2024-02-05T23:50:20.959Z,1707177020.959 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240205T230703/Express0026.lzma 2024-02-05T23:50:21.962Z,1707177021.962 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0026.lzma.bak 2024-02-05T23:50:21.962Z,1707177021.962 [DataOverHttps](INFO): SBD MOMSN=19266716 2024-02-05T23:50:23.045Z,1707177023.045 [Default:CheckIn:Read_Iridium] Stopped 2024-02-05T23:50:23.045Z,1707177023.045 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-05T23:50:23.045Z,1707177023.045 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-05T23:50:43.217Z,1707177043.217 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-05T23:52:27.896Z,1707177147.896 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-05T23:52:27.896Z,1707177147.896 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:52:27.944Z,1707177147.944 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:52:28.323Z,1707177148.323 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:52:28.323Z,1707177148.323 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-05T23:55:23.637Z,1707177323.637 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-05T23:55:23.637Z,1707177323.637 [Default:CheckIn:C.Wait] Stopped 2024-02-05T23:55:23.637Z,1707177323.637 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-05T23:55:23.637Z,1707177323.637 [Default:CheckIn:D] Running Loop=1 2024-02-05T23:55:24.030Z,1707177324.030 [Default:CheckIn:D] Stopped 2024-02-05T23:55:24.030Z,1707177324.030 [Default:CheckIn:E] Running Loop=1 2024-02-05T23:55:24.438Z,1707177324.438 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.603044 min 2024-02-05T23:55:24.438Z,1707177324.438 [Default:CheckIn:E] Stopped 2024-02-05T23:55:24.438Z,1707177324.438 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-05T23:55:24.439Z,1707177324.439 [Default:CheckIn] Stopped 2024-02-05T23:55:24.439Z,1707177324.439 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-05T23:55:24.439Z,1707177324.439 [Default:CheckIn](INFO): Running loop #7 2024-02-05T23:55:24.439Z,1707177324.439 [Default:CheckIn] Running Loop=7 2024-02-05T23:55:24.439Z,1707177324.439 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-05T23:55:24.439Z,1707177324.439 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-05T23:55:26.451Z,1707177326.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235525.00,A,3648.46415,N,12147.30746,W,8.203,238.35,050224,,,D*74 2024-02-05T23:55:26.453Z,1707177326.453 [NAL9602](INFO): GPS fix at 20240205T235525: (36.807736, -121.788458) 2024-02-05T23:55:26.469Z,1707177326.469 [Default:CheckIn:Read_GPS] Stopped 2024-02-05T23:55:26.469Z,1707177326.469 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-05T23:55:28.876Z,1707177328.876 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-05T23:55:28.876Z,1707177328.876 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:55:28.908Z,1707177328.908 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:55:29.339Z,1707177329.339 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:55:29.339Z,1707177329.339 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-05T23:55:33.467Z,1707177333.467 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240205T230703/Courier0028.lzma 2024-02-05T23:55:34.469Z,1707177334.469 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0028.lzma.bak 2024-02-05T23:55:34.470Z,1707177334.470 [DataOverHttps](INFO): SBD MOMSN=19266720 2024-02-05T23:55:36.950Z,1707177336.950 [NAL9602](INFO): SBD MO Status=2, MOMSN=9462, MT Status=2, MTMSN=0 2024-02-05T23:55:36.950Z,1707177336.950 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-05T23:55:50.303Z,1707177350.303 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240205T230703/Express0029.lzma 2024-02-05T23:55:51.305Z,1707177351.305 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0029.lzma.bak 2024-02-05T23:55:51.306Z,1707177351.306 [DataOverHttps](INFO): SBD MOMSN=19266723 2024-02-05T23:55:52.332Z,1707177352.332 [Default:CheckIn:Read_Iridium] Stopped 2024-02-05T23:55:52.332Z,1707177352.332 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-05T23:55:52.332Z,1707177352.332 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-05T23:55:56.338Z,1707177356.338 [NAL9602](INFO): SBD MO Status=0, MOMSN=9462, MT Status=0, MTMSN=0 2024-02-05T23:55:56.339Z,1707177356.339 [NAL9602](INFO): No messages in MT queue 2024-02-05T23:56:27.041Z,1707177387.041 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-05T23:58:29.889Z,1707177509.889 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-05T23:58:29.889Z,1707177509.889 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-05T23:58:29.944Z,1707177509.944 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-05T23:58:30.311Z,1707177510.311 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-05T23:58:30.311Z,1707177510.311 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-06T00:00:52.893Z,1707177652.893 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-06T00:00:52.894Z,1707177652.894 [Default:CheckIn:C.Wait] Stopped 2024-02-06T00:00:52.894Z,1707177652.894 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-06T00:00:52.894Z,1707177652.894 [Default:CheckIn:D] Running Loop=1 2024-02-06T00:00:53.304Z,1707177653.304 [Default:CheckIn:D] Stopped 2024-02-06T00:00:53.304Z,1707177653.304 [Default:CheckIn:E] Running Loop=1 2024-02-06T00:00:53.721Z,1707177653.721 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.090934 min 2024-02-06T00:00:53.721Z,1707177653.721 [Default:CheckIn:E] Stopped 2024-02-06T00:00:53.721Z,1707177653.721 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-06T00:00:53.721Z,1707177653.721 [Default:CheckIn] Stopped 2024-02-06T00:00:53.721Z,1707177653.721 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-06T00:00:53.722Z,1707177653.722 [Default:CheckIn](INFO): Running loop #8 2024-02-06T00:00:53.722Z,1707177653.722 [Default:CheckIn] Running Loop=8 2024-02-06T00:00:53.722Z,1707177653.722 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-06T00:00:53.722Z,1707177653.722 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-06T00:00:55.703Z,1707177655.703 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000054.00,A,3647.99008,N,12148.43469,W,13.762,260.51,060224,,,D*47 2024-02-06T00:00:55.706Z,1707177655.706 [NAL9602](INFO): GPS fix at 20240206T000054: (36.799835, -121.807245) 2024-02-06T00:00:55.724Z,1707177655.724 [Default:CheckIn:Read_GPS] Stopped 2024-02-06T00:00:55.724Z,1707177655.724 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-06T00:01:03.975Z,1707177663.975 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240205T230703/Courier0031.lzma 2024-02-06T00:01:05.985Z,1707177665.985 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0031.lzma.bak 2024-02-06T00:01:05.986Z,1707177665.986 [DataOverHttps](INFO): SBD MOMSN=19266727 2024-02-06T00:01:09.846Z,1707177669.846 [NAL9602](INFO): SBD MO Status=0, MOMSN=9463, MT Status=0, MTMSN=0 2024-02-06T00:01:09.846Z,1707177669.846 [NAL9602](INFO): No messages in MT queue 2024-02-06T00:01:30.860Z,1707177690.860 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-06T00:01:30.860Z,1707177690.860 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-06T00:01:30.871Z,1707177690.871 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-06T00:01:31.277Z,1707177691.277 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-06T00:01:31.277Z,1707177691.277 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-06T00:01:31.819Z,1707177691.819 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240205T230703/Express0032.lzma 2024-02-06T00:01:32.822Z,1707177692.822 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0032.lzma.bak 2024-02-06T00:01:32.822Z,1707177692.822 [DataOverHttps](INFO): SBD MOMSN=19266730 2024-02-06T00:01:34.115Z,1707177694.115 [Default:CheckIn:Read_Iridium] Stopped 2024-02-06T00:01:34.115Z,1707177694.115 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-06T00:01:34.115Z,1707177694.115 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-06T00:01:40.549Z,1707177700.549 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-06T00:04:31.852Z,1707177871.852 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-06T00:04:31.852Z,1707177871.852 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-06T00:04:31.863Z,1707177871.863 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-06T00:04:32.267Z,1707177872.267 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-06T00:04:32.268Z,1707177872.268 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-06T00:06:07.704Z,1707177967.704 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-06T00:06:34.735Z,1707177994.735 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-06T00:06:34.736Z,1707177994.736 [Default:CheckIn:C.Wait] Stopped 2024-02-06T00:06:34.736Z,1707177994.736 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-06T00:06:34.736Z,1707177994.736 [Default:CheckIn:D] Running Loop=1 2024-02-06T00:06:35.151Z,1707177995.151 [Default:CheckIn:D] Stopped 2024-02-06T00:06:35.151Z,1707177995.151 [Default:CheckIn:E] Running Loop=1 2024-02-06T00:06:35.535Z,1707177995.535 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.788387 min 2024-02-06T00:06:35.535Z,1707177995.535 [Default:CheckIn:E] Stopped 2024-02-06T00:06:35.535Z,1707177995.535 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-06T00:06:35.535Z,1707177995.535 [Default:CheckIn] Stopped 2024-02-06T00:06:35.535Z,1707177995.535 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-06T00:06:35.535Z,1707177995.535 [Default:CheckIn](INFO): Running loop #9 2024-02-06T00:06:35.535Z,1707177995.535 [Default:CheckIn] Running Loop=9 2024-02-06T00:06:35.536Z,1707177995.536 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-06T00:06:35.536Z,1707177995.536 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-06T00:06:37.551Z,1707177997.551 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000636.00,A,3647.93678,N,12148.50935,W,0.583,116.95,060224,,,D*7D 2024-02-06T00:06:37.553Z,1707177997.553 [NAL9602](INFO): GPS fix at 20240206T000636: (36.798946, -121.808489) 2024-02-06T00:06:37.568Z,1707177997.568 [Default:CheckIn:Read_GPS] Stopped 2024-02-06T00:06:37.568Z,1707177997.568 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-06T00:06:38.834Z,1707177998.834 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-06T00:06:53.302Z,1707178013.302 [NAL9602](INFO): SBD MO Status=0, MOMSN=9464, MT Status=0, MTMSN=0 2024-02-06T00:06:53.302Z,1707178013.302 [NAL9602](INFO): No messages in MT queue 2024-02-06T00:07:09.952Z,1707178029.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-06T00:07:24.005Z,1707178044.005 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-06T00:07:32.921Z,1707178052.921 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-06T00:07:32.921Z,1707178052.921 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-06T00:07:32.941Z,1707178052.941 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-06T00:07:33.318Z,1707178053.318 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-06T00:07:33.318Z,1707178053.318 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-06T00:07:41.094Z,1707178061.094 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-06T00:07:57.479Z,1707178077.479 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240205T230703/Courier0034.lzma 2024-02-06T00:07:58.482Z,1707178078.482 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Courier0034.lzma.bak 2024-02-06T00:07:58.482Z,1707178078.482 [DataOverHttps](INFO): SBD MOMSN=19266735 2024-02-06T00:08:15.348Z,1707178095.348 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240205T230703/Express0035.lzma 2024-02-06T00:08:16.349Z,1707178096.349 [DataOverHttps](INFO): Moved sent file to Logs/20240205T230703/Express0035.lzma.bak 2024-02-06T00:08:16.350Z,1707178096.350 [DataOverHttps](INFO): SBD MOMSN=19266738 2024-02-06T00:08:17.750Z,1707178097.750 [Default:CheckIn:Read_Iridium] Stopped 2024-02-06T00:08:17.750Z,1707178097.750 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-06T00:08:17.751Z,1707178097.751 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-06T00:10:26.549Z,1707178226.549 [DataOverHttps](IMPORTANT): SBD MTMSN=20240206T001025 2024-02-06T00:10:33.936Z,1707178233.936 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-06T00:10:33.936Z,1707178233.936 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-06T00:10:33.944Z,1707178233.944 [DataOverHttps](INFO): Received command: restart logs 2024-02-06T00:10:33.952Z,1707178233.952 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor