2021-02-15T18:38:45.540Z,1613414325.540 [Supervisor](DEBUG): Initializing supervisor.
2021-02-15T18:38:45.545Z,1613414325.545 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-02-15T18:38:45.545Z,1613414325.545 [SyncHandler](INFO): Protected caller Thread ID is 6682
2021-02-15T18:38:45.545Z,1613414325.545 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-02-15T18:38:45.546Z,1613414325.546 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-02-15T18:38:45.547Z,1613414325.547 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6683
2021-02-15T18:38:45.551Z,1613414325.551 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-02-15T18:38:45.569Z,1613414325.569 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-02-15T18:38:45.570Z,1613414325.570 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-02-15T18:38:45.570Z,1613414325.570 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6684
2021-02-15T18:38:45.573Z,1613414325.573 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-02-15T18:38:45.574Z,1613414325.574 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-02-15T18:38:45.574Z,1613414325.574 [logger ThreadHandler](INFO): Protected caller Thread ID is 6685
2021-02-15T18:38:45.578Z,1613414325.578 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-02-15T18:38:45.578Z,1613414325.578 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-02-15T18:38:45.580Z,1613414325.580 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-02-15T18:38:45.967Z,1613414325.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-02-15T18:38:45.967Z,1613414325.967 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-02-15T18:38:46.046Z,1613414326.046 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-02-15T18:38:46.358Z,1613414326.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-02-15T18:38:46.358Z,1613414326.358 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-02-15T18:38:46.458Z,1613414326.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-02-15T18:38:46.459Z,1613414326.459 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-02-15T18:38:46.650Z,1613414326.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-02-15T18:38:46.650Z,1613414326.650 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-02-15T18:38:47.150Z,1613414327.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-02-15T18:38:47.151Z,1613414327.151 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-02-15T18:38:47.377Z,1613414327.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-02-15T18:38:47.377Z,1613414327.377 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-02-15T18:38:47.521Z,1613414327.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-02-15T18:38:47.522Z,1613414327.522 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-02-15T18:38:47.705Z,1613414327.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-02-15T18:38:47.706Z,1613414327.706 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-02-15T18:38:47.801Z,1613414327.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-02-15T18:38:47.802Z,1613414327.802 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-02-15T18:38:48.011Z,1613414328.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-02-15T18:38:48.012Z,1613414328.012 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-02-15T18:38:48.310Z,1613414328.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-02-15T18:38:48.311Z,1613414328.311 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-02-15T18:38:48.392Z,1613414328.392 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-02-15T18:38:48.515Z,1613414328.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-02-15T18:38:48.515Z,1613414328.515 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-02-15T18:38:49.142Z,1613414329.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-02-15T18:38:49.142Z,1613414329.142 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-02-15T18:38:49.489Z,1613414329.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-02-15T18:38:49.491Z,1613414329.491 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2021-02-15T18:38:49.492Z,1613414329.492 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2021-02-15T18:38:49.880Z,1613414329.880 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2021-02-15T18:38:50.101Z,1613414330.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2021-02-15T18:38:50.461Z,1613414330.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2021-02-15T18:38:50.790Z,1613414330.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-02-15T18:38:50.790Z,1613414330.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2021-02-15T18:38:50.876Z,1613414330.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2021-02-15T18:38:50.971Z,1613414330.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2021-02-15T18:38:51.084Z,1613414331.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2021-02-15T18:38:51.170Z,1613414331.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Dock.cfg
2021-02-15T18:38:51.256Z,1613414331.256 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2021-02-15T18:38:51.363Z,1613414331.363 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2021-02-15T18:38:51.461Z,1613414331.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2021-02-15T18:38:51.643Z,1613414331.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2021-02-15T18:38:51.777Z,1613414331.777 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-02-15T18:38:51.783Z,1613414331.783 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-02-15T18:38:52.131Z,1613414332.131 [AHRS_M2] Loaded
2021-02-15T18:38:52.131Z,1613414332.131 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-02-15T18:38:52.863Z,1613414332.863 [BPC1] Loaded
2021-02-15T18:38:52.863Z,1613414332.863 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-02-15T18:38:52.935Z,1613414332.935 [DataOverHttps] Loaded
2021-02-15T18:38:52.936Z,1613414332.936 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-02-15T18:38:52.937Z,1613414332.937 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407934E0
2021-02-15T18:38:52.937Z,1613414332.937 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6773
2021-02-15T18:38:52.958Z,1613414332.958 [DDM] Loaded
2021-02-15T18:38:52.958Z,1613414332.958 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2021-02-15T18:38:52.976Z,1613414332.976 [Depth_Keller] Loaded
2021-02-15T18:38:52.976Z,1613414332.976 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-02-15T18:38:52.981Z,1613414332.981 [DropWeight] Loaded
2021-02-15T18:38:52.981Z,1613414332.981 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-02-15T18:38:53.022Z,1613414333.022 [DUSBL_Hydroid] Loaded
2021-02-15T18:38:53.022Z,1613414333.022 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2021-02-15T18:38:53.056Z,1613414333.056 [Micromodem] Loaded
2021-02-15T18:38:53.057Z,1613414333.057 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2021-02-15T18:38:53.113Z,1613414333.113 [NAL9602] Loaded
2021-02-15T18:38:53.113Z,1613414333.113 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-02-15T18:38:53.140Z,1613414333.140 [Onboard] Loaded
2021-02-15T18:38:53.141Z,1613414333.141 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-02-15T18:38:53.142Z,1613414333.142 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2021-02-15T18:38:53.142Z,1613414333.142 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6774
2021-02-15T18:38:53.155Z,1613414333.155 [Radio_Surface] Loaded
2021-02-15T18:38:53.155Z,1613414333.155 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-02-15T18:38:53.156Z,1613414333.156 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407F34E0
2021-02-15T18:38:53.157Z,1613414333.157 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6775
2021-02-15T18:38:53.186Z,1613414333.186 [RDI_Pathfinder] Loaded
2021-02-15T18:38:53.186Z,1613414333.186 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2021-02-15T18:38:53.210Z,1613414333.210 [RDI_PathfinderUp] Loaded
2021-02-15T18:38:53.211Z,1613414333.211 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2021-02-15T18:38:53.211Z,1613414333.211 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-02-15T18:38:53.212Z,1613414333.212 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-02-15T18:38:53.223Z,1613414333.223 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-02-15T18:38:53.223Z,1613414333.223 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-02-15T18:38:53.300Z,1613414333.300 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-02-15T18:38:53.300Z,1613414333.300 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-02-15T18:38:53.312Z,1613414333.312 [NavChart] Loaded
2021-02-15T18:38:53.313Z,1613414333.313 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-02-15T18:38:53.318Z,1613414333.318 [UniversalFixResidualReporter] Loaded
2021-02-15T18:38:53.318Z,1613414333.318 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-02-15T18:38:53.328Z,1613414333.328 [WorkSite] Loaded
2021-02-15T18:38:53.328Z,1613414333.328 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2021-02-15T18:38:53.329Z,1613414333.329 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-02-15T18:38:53.329Z,1613414333.329 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-02-15T18:38:53.413Z,1613414333.413 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-02-15T18:38:53.414Z,1613414333.414 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-02-15T18:38:53.529Z,1613414333.529 [BuoyancyServo] Loaded
2021-02-15T18:38:53.529Z,1613414333.529 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-02-15T18:38:53.549Z,1613414333.549 [ElevatorServo] Loaded
2021-02-15T18:38:53.549Z,1613414333.549 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-02-15T18:38:53.569Z,1613414333.569 [MassServo] Loaded
2021-02-15T18:38:53.569Z,1613414333.569 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-02-15T18:38:53.588Z,1613414333.588 [RudderServo] Loaded
2021-02-15T18:38:53.588Z,1613414333.588 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-02-15T18:38:53.606Z,1613414333.606 [ThrusterServo] Loaded
2021-02-15T18:38:53.606Z,1613414333.606 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-02-15T18:38:53.606Z,1613414333.606 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-02-15T18:38:53.607Z,1613414333.607 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-02-15T18:38:53.773Z,1613414333.773 [CTD_Seabird] Loaded
2021-02-15T18:38:53.773Z,1613414333.773 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-02-15T18:38:53.775Z,1613414333.775 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409164E0
2021-02-15T18:38:53.775Z,1613414333.775 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6776
2021-02-15T18:38:53.793Z,1613414333.793 [PAR_Licor] Loaded
2021-02-15T18:38:53.794Z,1613414333.794 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-02-15T18:38:53.824Z,1613414333.824 [WetLabsSeaOWL_UV_A] Loaded
2021-02-15T18:38:53.825Z,1613414333.825 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2021-02-15T18:38:53.826Z,1613414333.826 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409464E0
2021-02-15T18:38:53.826Z,1613414333.826 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 6777
2021-02-15T18:38:53.826Z,1613414333.826 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-02-15T18:38:53.827Z,1613414333.827 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-02-15T18:38:54.129Z,1613414334.129 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-02-15T18:38:54.129Z,1613414334.129 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-02-15T18:38:54.170Z,1613414334.170 [DepthRateCalculator] Loaded
2021-02-15T18:38:54.170Z,1613414334.170 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-02-15T18:38:54.175Z,1613414334.175 [PitchRateCalculator] Loaded
2021-02-15T18:38:54.175Z,1613414334.175 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-02-15T18:38:54.185Z,1613414334.185 [SpeedCalculator] Loaded
2021-02-15T18:38:54.185Z,1613414334.185 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-02-15T18:38:54.197Z,1613414334.197 [TempGradientCalculator] Loaded
2021-02-15T18:38:54.198Z,1613414334.198 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-02-15T18:38:54.202Z,1613414334.202 [YawRateCalculator] Loaded
2021-02-15T18:38:54.202Z,1613414334.202 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-02-15T18:38:54.223Z,1613414334.223 [ElevatorOffsetCalculator] Loaded
2021-02-15T18:38:54.223Z,1613414334.223 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-02-15T18:38:54.223Z,1613414334.223 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-02-15T18:38:54.224Z,1613414334.224 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-02-15T18:38:54.362Z,1613414334.362 [SBIT](DEBUG): Construct Startup Built In Test.
2021-02-15T18:38:54.373Z,1613414334.373 [SBIT] Loaded
2021-02-15T18:38:54.374Z,1613414334.374 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-02-15T18:38:54.376Z,1613414334.376 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-02-15T18:38:54.388Z,1613414334.388 [IBIT] Loaded
2021-02-15T18:38:54.389Z,1613414334.389 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-02-15T18:38:54.394Z,1613414334.394 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-02-15T18:38:54.490Z,1613414334.490 [CBIT] Loaded
2021-02-15T18:38:54.490Z,1613414334.490 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-02-15T18:38:54.490Z,1613414334.490 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-02-15T18:38:54.491Z,1613414334.491 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-02-15T18:38:54.544Z,1613414334.544 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-02-15T18:38:54.545Z,1613414334.545 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-02-15T18:38:54.644Z,1613414334.644 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-02-15T18:38:54.645Z,1613414334.645 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-02-15T18:38:54.694Z,1613414334.694 [VerticalControl](DEBUG): Construct VerticalControl.
2021-02-15T18:38:54.744Z,1613414334.744 [VerticalControl] Loaded
2021-02-15T18:38:54.745Z,1613414334.745 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-02-15T18:38:54.748Z,1613414334.748 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-02-15T18:38:54.782Z,1613414334.782 [HorizontalControl] Loaded
2021-02-15T18:38:54.782Z,1613414334.782 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-02-15T18:38:54.785Z,1613414334.785 [SpeedControl](DEBUG): Construct SpeedControl.
2021-02-15T18:38:54.786Z,1613414334.786 [SpeedControl] Loaded
2021-02-15T18:38:54.786Z,1613414334.786 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-02-15T18:38:54.789Z,1613414334.789 [LoopControl](DEBUG): Construct LoopControl.
2021-02-15T18:38:54.790Z,1613414334.790 [LoopControl] Loaded
2021-02-15T18:38:54.790Z,1613414334.790 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-02-15T18:38:54.790Z,1613414334.790 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-02-15T18:38:54.791Z,1613414334.791 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-02-15T18:38:54.819Z,1613414334.819 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-02-15T18:38:54.825Z,1613414334.825 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-02-15T18:38:54.827Z,1613414334.827 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-02-15T18:38:54.838Z,1613414334.838 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-02-15T18:38:54.839Z,1613414334.839 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AEF4E0
2021-02-15T18:38:54.839Z,1613414334.839 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6778
2021-02-15T18:38:54.843Z,1613414334.843 [Supervisor](INFO): Main Thread ID is 5669
2021-02-15T18:38:54.843Z,1613414334.843 [Supervisor](DEBUG): Running supervisor.
2021-02-15T18:38:54.844Z,1613414334.844 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6779
2021-02-15T18:38:54.846Z,1613414334.846 [controlThread ThreadHandler](INFO): Handler Thread ID is 6780
2021-02-15T18:38:54.847Z,1613414334.847 [controlThread](DEBUG): Initializing ControlThread
2021-02-15T18:38:54.854Z,1613414334.854 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-02-15T18:38:54.854Z,1613414334.854 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-02-15T18:38:54.854Z,1613414334.854 [WorkSite](DEBUG): Initializing WorkSite component.
2021-02-15T18:38:54.859Z,1613414334.859 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-02-15T18:38:54.860Z,1613414334.860 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-02-15T18:38:54.860Z,1613414334.860 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-02-15T18:38:54.860Z,1613414334.860 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-02-15T18:38:54.861Z,1613414334.861 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-02-15T18:38:54.861Z,1613414334.861 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-02-15T18:38:54.862Z,1613414334.862 [SBIT](INFO): Initialize SBIT Component.
2021-02-15T18:38:54.862Z,1613414334.862 [SBIT](IMPORTANT): git: 2021-02-15
2021-02-15T18:38:54.863Z,1613414334.863 [SBIT](INFO): git hash: 2cd124a0e0845b789d2aa46a02e42482c8a7267f
2021-02-15T18:38:54.863Z,1613414334.863 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-02-15T18:38:54.864Z,1613414334.864 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2021-02-15T18:38:54.865Z,1613414334.865 [SBIT](INFO): Beginning SBIT in 36.000000 seconds.
2021-02-15T18:38:54.866Z,1613414334.866 [IBIT](INFO): Initialize IBIT Component.
2021-02-15T18:38:54.867Z,1613414334.867 [CBIT](DEBUG): Initialize CBIT Component.
2021-02-15T18:38:54.868Z,1613414334.868 [logger ThreadHandler](INFO): Handler Thread ID is 6781
2021-02-15T18:38:54.879Z,1613414334.879 [CBIT](DEBUG): Initialized mux pins.
2021-02-15T18:38:54.879Z,1613414334.879 [CBIT](DEBUG): Initializing the watchdog timer.
2021-02-15T18:38:54.887Z,1613414334.887 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6782
2021-02-15T18:38:54.889Z,1613414334.889 [DataOverHttps](DEBUG): dashIP=128.128.181.155 starts with a digit so assuming it is a numeric IP
2021-02-15T18:38:54.899Z,1613414334.899 [Onboard ThreadHandler](INFO): Handler Thread ID is 6783
2021-02-15T18:38:54.904Z,1613414334.904 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-02-15T18:38:54.904Z,1613414334.904 [CBIT](DEBUG): Initializing heartbeat.
2021-02-15T18:38:54.915Z,1613414334.915 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6784
2021-02-15T18:38:54.935Z,1613414334.935 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6785
2021-02-15T18:38:54.936Z,1613414334.936 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-02-15T18:38:54.941Z,1613414334.941 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 6787
2021-02-15T18:38:54.941Z,1613414334.941 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-02-15T18:38:54.952Z,1613414334.952 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6788
2021-02-15T18:38:54.955Z,1613414334.955 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-02-15T18:38:54.956Z,1613414334.956 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-02-15T18:38:54.956Z,1613414334.956 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-02-15T18:38:54.957Z,1613414334.957 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-02-15T18:38:54.957Z,1613414334.957 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-02-15T18:38:54.957Z,1613414334.957 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-02-15T18:38:54.957Z,1613414334.957 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-02-15T18:38:54.957Z,1613414334.957 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-02-15T18:38:54.957Z,1613414334.957 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-02-15T18:38:54.958Z,1613414334.958 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-02-15T18:38:54.958Z,1613414334.958 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-02-15T18:38:54.958Z,1613414334.958 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-02-15T18:38:54.958Z,1613414334.958 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-02-15T18:38:54.958Z,1613414334.958 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-02-15T18:38:54.958Z,1613414334.958 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-02-15T18:38:54.959Z,1613414334.959 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-02-15T18:38:54.975Z,1613414334.975 [CBIT](DEBUG): Deactivating GF circuits.
2021-02-15T18:38:54.975Z,1613414334.975 [CBIT](DEBUG): Deactivating emergency mode.
2021-02-15T18:38:55.011Z,1613414335.011 [CBIT](DEBUG): Backplane powered.
2021-02-15T18:38:55.011Z,1613414335.011 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-02-15T18:38:55.013Z,1613414335.013 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-02-15T18:38:55.014Z,1613414335.014 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-02-15T18:38:55.014Z,1613414335.014 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-02-15T18:38:55.015Z,1613414335.015 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-02-15T18:38:55.028Z,1613414335.028 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-15T18:38:55.056Z,1613414335.056 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2021-02-15T18:38:55.057Z,1613414335.057 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultWithUndock.xml
2021-02-15T18:38:55.323Z,1613414335.323 [Radio_Surface](INFO): Powering up
2021-02-15T18:38:55.423Z,1613414335.423 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2021-02-15T18:38:55.450Z,1613414335.450 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2021-02-15T18:38:55.478Z,1613414335.478 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 2.000000 count
2021-02-15T18:38:55.494Z,1613414335.494 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 2.500000 s
2021-02-15T18:38:55.509Z,1613414335.509 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2021-02-15T18:38:55.521Z,1613414335.521 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachModeDDM = 2.000000 count
2021-02-15T18:38:55.522Z,1613414335.522 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2021-02-15T18:38:55.711Z,1613414335.711 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2021-02-15T18:38:55.712Z,1613414335.712 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2021-02-15T18:38:55.766Z,1613414335.766 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-15T18:38:55.797Z,1613414335.797 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-02-15T18:38:55.820Z,1613414335.820 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-02-15T18:38:55.825Z,1613414335.825 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2021-02-15T18:38:55.847Z,1613414335.847 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Control="Tethys/Control"
         xmlns:Dock="Tethys/Dock"
         xmlns:Estimation="Tethys/Estimation"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Sensor="Tethys/Sensor"
         xmlns:Units="Tethys/Units"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Dock http://okeanids.mbari.org/tethys/Xml/Dock.xsd
                             Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="DefaultWithUndock">

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

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time duration for the vehicle to detach form the dock.
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Dock transponder address.
    </Description><Dock:Dock.transponderCode/></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        Time duration between acoustic range queries while the
        mission is detaching from the dock.
    </Description><Units:second/><Value>2.5</Value></DefineArg>

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="DetachModeDDM"><Description>
        DDM DETACH mode enumeration (whiskers retracted, latch open).
    </Description><Units:count/><Value>2</Value></DefineArg>
        
    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>30</Value></Setting>
    </Guidance:Wait>

    <Aggregate Id="LeaveDock">
        <Sequence/>

        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">Timed out while trying to detach from dock at current depth of <Universal:depth/><Units:meter/>.</Syslog>
        </Timeout>

        <!-- Acoustic tracking directives -->
        <Estimation:TrackAcousticContact>
            <Parallel/>
            <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
            <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
            <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
        </Estimation:TrackAcousticContact>

        <!-- Leave the dock -->
        <Dock:Undock>
            <Sequence/>
        </Dock:Undock>

    </Aggregate>


    <Aggregate Id="SurfaceDefault">

        <Sequence/>

        <Aggregate Id="HoldLatchOpen">

            <While>
                <Universal:depth/>
                <Gt><Control:VerticalControl.surfaceThreshold/></Gt>
            </While>

            <!-- Power the DDM -->
            <ReadData>
                <Sensor:DDM.DDMMode/>
            </ReadData>

            <!-- Hold the DDM in DETACH mode (whiskers retracted, latch open) -->
            <Assign><Parallel/><Sensor:DDM.DDMModeCmd/><Arg Name="DetachModeDDM"/></Assign>

        </Aggregate>

        <!-- This mission assumes open water so from here on it's the same old default that's always used -->
        <Guidance:GoToSurface>
            <Progression/>
        </Guidance:GoToSurface>

        <Aggregate Id="CheckIn">

            <Sequence Repeat="288"/>

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

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

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

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

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

        </Aggregate>

    </Aggregate>

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

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

</Mission>


2021-02-15T18:38:55.856Z,1613414335.856 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,RDI_Pathfinder,RDI_PathfinderUp,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-02-15T18:38:55.875Z,1613414335.875 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-15T18:38:55.940Z,1613414335.940 [DDM](INFO): Powering up
2021-02-15T18:38:55.940Z,1613414335.940 [DDM](DEBUG): Initializing DDM.
2021-02-15T18:38:55.964Z,1613414335.964 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2021-02-15T18:38:55.964Z,1613414335.964 [DUSBL_Hydroid](INFO): Powering up
2021-02-15T18:38:55.965Z,1613414335.965 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2021-02-15T18:38:56.008Z,1613414336.008 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-02-15T18:38:56.049Z,1613414336.049 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-02-15T18:38:56.055Z,1613414336.055 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-02-15T18:38:56.057Z,1613414336.057 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-02-15T18:38:56.063Z,1613414336.063 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-02-15T18:38:56.064Z,1613414336.064 [MassServo](DEBUG): Initializing EZServoServo.
2021-02-15T18:38:56.071Z,1613414336.071 [MassServo](DEBUG): Initializing MassServo.
2021-02-15T18:38:56.072Z,1613414336.072 [RudderServo](DEBUG): Initializing EZServoServo.
2021-02-15T18:38:56.079Z,1613414336.079 [RudderServo](DEBUG): Initializing RudderServo.
2021-02-15T18:38:56.080Z,1613414336.080 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-02-15T18:38:56.087Z,1613414336.087 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-02-15T18:38:56.283Z,1613414336.283 [Micromodem](INFO): Powering up
2021-02-15T18:38:56.284Z,1613414336.284 [Micromodem](DEBUG): Initializing Micromodem.
2021-02-15T18:38:57.023Z,1613414337.023 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-02-15T18:38:57.023Z,1613414337.023 [RudderServo](FAULT): Rudder failed to initialize
2021-02-15T18:38:57.023Z,1613414337.023 [RudderServo] Communications Fault, FailCount= 1
2021-02-15T18:38:57.023Z,1613414337.023 [RudderServo](ERROR): Communications Fault
2021-02-15T18:38:57.123Z,1613414337.123 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-02-15T18:38:57.288Z,1613414337.288 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-02-15T18:38:57.288Z,1613414337.288 [RudderServo](INFO): Powering down
2021-02-15T18:38:57.960Z,1613414337.960 [RudderServo](DEBUG): Initializing EZServoServo.
2021-02-15T18:38:58.080Z,1613414338.080 [RudderServo](DEBUG): Initializing RudderServo.
2021-02-15T18:38:58.084Z,1613414338.084 [CBIT](INFO): Clearing failed state for component RudderServo
2021-02-15T18:38:58.084Z,1613414338.084 [RudderServo] No Fault, FailCount= 1
2021-02-15T18:39:01.215Z,1613414341.215 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2021-02-15T18:39:01.618Z,1613414341.618 [Micromodem](INFO): Nmea in: $CATMG,2021-02-15T18:39:00.697816Z,RTC,RTC*53

2021-02-15T18:39:01.618Z,1613414341.618 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2021-02-15T18:39:00.697816Z,RTC,RTC*53

2021-02-15T18:39:02.023Z,1613414342.023 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2021-02-15T18:39:02.024Z,1613414342.024 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2021-02-15T18:39:02.429Z,1613414342.429 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2021-02-15T18:39:02.429Z,1613414342.429 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2021-02-15T18:39:02.830Z,1613414342.830 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2021-02-15T18:39:02.830Z,1613414342.830 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2021-02-15T18:39:03.233Z,1613414343.233 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2021-02-15T18:39:03.234Z,1613414343.234 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2021-02-15T18:39:03.637Z,1613414343.637 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2021-02-15T18:39:03.637Z,1613414343.637 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2021-02-15T18:39:04.039Z,1613414344.039 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2021-02-15T18:39:04.039Z,1613414344.039 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2021-02-15T18:39:04.433Z,1613414344.433 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2021-02-15T18:39:04.434Z,1613414344.434 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2021-02-15T18:39:04.826Z,1613414344.826 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2021-02-15T18:39:04.827Z,1613414344.827 [Micromodem](INFO): Nmea out: $CCCLK,2021,02,15,18,39,05*45

2021-02-15T18:39:05.217Z,1613414345.217 [Micromodem](INFO): Nmea in: $CACLK,2021,2,15,18,39,5*47

2021-02-15T18:39:05.625Z,1613414345.625 [Micromodem](INFO): Nmea in: $CATMS,0,2021-02-15T18:39:06Z*74

2021-02-15T18:39:05.627Z,1613414345.627 [Micromodem](INFO): Nmea in: $CATMG,2021-02-15T18:39:06.026102Z,USER_CMD,RTC*14

2021-02-15T18:39:11.676Z,1613414351.676 [DDM](INFO): Dynamic Docking Module:
2021-02-15T18:39:14.121Z,1613414354.121 [DUSBL_Hydroid](INFO): DUSBL Version:O
2021-02-15T18:39:23.406Z,1613414363.406 [NAL9602](INFO): Powering up NAL9602
2021-02-15T18:39:31.519Z,1613414371.519 [SBIT](IMPORTANT): Beginning Startup BIT
2021-02-15T18:39:31.547Z,1613414371.547 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-15T18:39:34.305Z,1613414374.305 [NAL9602](INFO): NAL9602 initialized
2021-02-15T18:39:42.591Z,1613414382.591 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010285
CHAN A1 (24V): -0.028953
CHAN A2 (12V): -0.007644
CHAN A3 (5V): -0.002196
CHAN B0 (3.3V): 0.000429
CHAN B1 (3.15aV): 0.000027
CHAN B2 (3.15bV): 0.000233
CHAN B3 (GND): 0.002072
OPEN: 0.004986
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-15T18:39:51.679Z,1613414391.679 [NAL9602](INFO): SBD MO Status=0, MOMSN=13291, MT Status=0, MTMSN=0
2021-02-15T18:39:51.679Z,1613414391.679 [NAL9602](INFO): No messages in MT queue
2021-02-15T18:40:02.191Z,1613414402.191 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184002.00,A,4131.46011,N,07040.31539,W,0.894,323.40,150221,,,A*7E
2021-02-15T18:40:02.194Z,1613414402.194 [NAL9602](INFO): GPS fix at 20210215T184002: (41.524335, -70.671923)
2021-02-15T18:40:25.653Z,1613414425.653 [SBIT](IMPORTANT): SBIT PASSED
2021-02-15T18:40:25.653Z,1613414425.653 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-02-15T18:40:25.654Z,1613414425.654 [SBIT](IMPORTANT): AHRS_M2.verbosity=0 count;

2021-02-15T18:40:25.654Z,1613414425.654 [SBIT](IMPORTANT): Courier mostRecent acoustic_contact_range;

2021-02-15T18:40:25.654Z,1613414425.654 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2021-02-15T18:40:25.654Z,1613414425.654 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;

2021-02-15T18:40:25.654Z,1613414425.654 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;

2021-02-15T18:40:26.034Z,1613414426.034 [WorkSite](INFO): Already have valid position. Ignoring workSite position from Data/workSite.cfg
2021-02-15T18:40:26.039Z,1613414426.039 [MissionManager](IMPORTANT): Started mission Startup
2021-02-15T18:40:26.039Z,1613414426.039 [Startup] Running Loop=1
2021-02-15T18:40:26.039Z,1613414426.039 [Startup](DEBUG): Aggregate::initialize Startup
2021-02-15T18:40:26.039Z,1613414426.039 [Startup:A.GoToSurface] Running Loop=1
2021-02-15T18:40:26.039Z,1613414426.039 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-15T18:40:26.040Z,1613414426.040 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-02-15T18:40:26.040Z,1613414426.040 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-02-15T18:40:26.041Z,1613414426.041 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-02-15T18:40:26.041Z,1613414426.041 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-15T18:40:26.042Z,1613414426.042 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-15T18:40:26.043Z,1613414426.043 [Startup:StartupSatComms] Running Loop=1
2021-02-15T18:40:26.043Z,1613414426.043 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-02-15T18:40:26.043Z,1613414426.043 [Startup:StartupSatComms:A] Running Loop=1
2021-02-15T18:40:26.463Z,1613414426.463 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-02-15T18:40:28.051Z,1613414428.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184028.00,A,4131.45439,N,07040.31134,W,0.564,321.88,150221,,,A*76
2021-02-15T18:40:28.053Z,1613414428.053 [NAL9602](INFO): GPS fix at 20210215T184028: (41.524240, -70.671856)
2021-02-15T18:40:28.064Z,1613414428.064 [Startup:StartupSatComms:A] Stopped
2021-02-15T18:40:28.064Z,1613414428.064 [Startup:StartupSatComms:B] Running Loop=1
2021-02-15T18:40:28.537Z,1613414428.537 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-02-15T18:41:20.274Z,1613414480.274 [NAL9602](INFO): SBD MO Status=1, MOMSN=13292, MT Status=0, MTMSN=0
2021-02-15T18:41:20.327Z,1613414480.327 [NAL9602](INFO): Sent 52 bytes from file Logs/20210215T182832/Courier0010.lzma
2021-02-15T18:41:20.327Z,1613414480.327 [NAL9602](INFO): Packets left to send: 0
2021-02-15T18:41:27.304Z,1613414487.304 [NAL9602](INFO): SBD MO Status=1, MOMSN=13293, MT Status=0, MTMSN=0
2021-02-15T18:41:27.354Z,1613414487.354 [NAL9602](INFO): Sent 184 bytes from file Logs/20210215T183845/Courier0000.lzma
2021-02-15T18:41:27.354Z,1613414487.354 [NAL9602](INFO): Packets left to send: 1
2021-02-15T18:41:30.247Z,1613414490.247 [Startup:StartupSatComms:B](INFO): Timed out from 2021-02-15T18:40:28.1Z
2021-02-15T18:41:30.247Z,1613414490.247 [Startup:StartupSatComms:B] Stopped
2021-02-15T18:41:30.248Z,1613414490.248 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-02-15T18:41:30.248Z,1613414490.248 [Startup:StartupSatComms] Stopped
2021-02-15T18:41:30.248Z,1613414490.248 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-02-15T18:41:30.248Z,1613414490.248 [Startup](INFO): Completed Startup
2021-02-15T18:41:30.249Z,1613414490.249 [MissionManager](INFO): Startup is completed.
2021-02-15T18:41:30.249Z,1613414490.249 [MissionManager](INFO): Uninitializing Mission Startup
2021-02-15T18:41:30.249Z,1613414490.249 [Startup] Stopped
2021-02-15T18:41:30.249Z,1613414490.249 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-02-15T18:41:30.249Z,1613414490.249 [Startup:A.GoToSurface] Stopped
2021-02-15T18:41:30.249Z,1613414490.249 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-15T18:41:30.334Z,1613414490.334 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2021-02-15T18:41:30.334Z,1613414490.334 [DefaultWithUndock] Running Loop=1
2021-02-15T18:41:30.334Z,1613414490.334 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2021-02-15T18:41:30.334Z,1613414490.334 [DefaultWithUndock:A.Wait] Running Loop=1
2021-02-15T18:41:30.334Z,1613414490.334 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2021-02-15T18:41:35.944Z,1613414495.944 [NAL9602](INFO): SBD MO Status=1, MOMSN=13294, MT Status=0, MTMSN=0
2021-02-15T18:41:36.003Z,1613414496.003 [NAL9602](INFO): Sent 68 bytes from file Logs/20210215T183845/Courier0000.lzma
2021-02-15T18:41:36.003Z,1613414496.003 [NAL9602](INFO): Packets left to send: 0
2021-02-15T18:42:00.600Z,1613414520.600 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2021-02-15T18:42:00.600Z,1613414520.600 [DefaultWithUndock:A.Wait] Stopped
2021-02-15T18:42:00.600Z,1613414520.600 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2021-02-15T18:42:00.601Z,1613414520.601 [DefaultWithUndock:LeaveDock] Running Loop=1
2021-02-15T18:42:00.601Z,1613414520.601 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2021-02-15T18:42:00.601Z,1613414520.601 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2021-02-15T18:42:00.601Z,1613414520.601 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2021-02-15T18:42:00.601Z,1613414520.601 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2021-02-15T18:42:00.601Z,1613414520.601 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2021-02-15T18:42:00.601Z,1613414520.601 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2021-02-15T18:42:00.999Z,1613414520.999 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2021-02-15T18:42:03.476Z,1613414523.476 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-02-15T18:42:03.918Z,1613414523.918 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock.
2021-02-15T18:42:04.254Z,1613414524.254 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-02-15T18:42:04.395Z,1613414524.395 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2021-02-15T18:42:04.783Z,1613414524.783 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-02-15T18:42:05.505Z,1613414525.505 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184204.87*5A

2021-02-15T18:42:06.365Z,1613414526.365 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-02-15T18:42:06.677Z,1613414526.677 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T18:42:07.888Z,1613414527.888 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-02-15T18:42:09.155Z,1613414529.155 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-02-15T18:42:09.915Z,1613414529.915 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-02-15T18:42:10.326Z,1613414530.326 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-02-15T18:42:11.169Z,1613414531.169 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184210.53*56

2021-02-15T18:42:11.952Z,1613414531.952 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-02-15T18:42:13.540Z,1613414533.540 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-02-15T18:42:14.776Z,1613414534.776 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-02-15T18:42:15.565Z,1613414535.565 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-02-15T18:42:15.971Z,1613414535.971 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-02-15T18:42:16.789Z,1613414536.789 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184216.18*5F

2021-02-15T18:42:17.694Z,1613414537.694 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-02-15T18:42:19.240Z,1613414539.240 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-02-15T18:42:20.429Z,1613414540.429 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-02-15T18:42:20.471Z,1613414540.471 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-02-15T18:42:21.226Z,1613414541.226 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-02-15T18:42:21.637Z,1613414541.637 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-02-15T18:42:22.445Z,1613414542.445 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184221.84*5E

2021-02-15T18:42:23.300Z,1613414543.300 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-02-15T18:42:24.495Z,1613414544.495 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2021-02-15T18:42:24.853Z,1613414544.853 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-02-15T18:42:26.088Z,1613414546.088 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-02-15T18:42:26.905Z,1613414546.905 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-02-15T18:42:26.949Z,1613414546.949 [DefaultWithUndock:LeaveDock:B.Undock](INFO): DDM at standby.
2021-02-15T18:42:27.283Z,1613414547.283 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-02-15T18:42:27.340Z,1613414547.340 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2021-02-15T18:42:27.340Z,1613414547.340 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2021-02-15T18:42:27.340Z,1613414547.340 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2021-02-15T18:42:27.340Z,1613414547.340 [DefaultWithUndock:LeaveDock] Stopped
2021-02-15T18:42:27.341Z,1613414547.341 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2021-02-15T18:42:27.341Z,1613414547.341 [DefaultWithUndock:LeaveDock:A.] Stopped
2021-02-15T18:42:27.341Z,1613414547.341 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2021-02-15T18:42:27.341Z,1613414547.341 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2021-02-15T18:42:27.341Z,1613414547.341 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Running Loop=1
2021-02-15T18:42:27.341Z,1613414547.341 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen
2021-02-15T18:42:27.341Z,1613414547.341 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Running Loop=1
2021-02-15T18:42:27.341Z,1613414547.341 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Running Loop=1
2021-02-15T18:42:27.341Z,1613414547.341 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Running Loop=1
2021-02-15T18:42:27.341Z,1613414547.341 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-15T18:42:27.342Z,1613414547.342 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-02-15T18:42:27.342Z,1613414547.342 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-02-15T18:42:27.342Z,1613414547.342 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-02-15T18:42:27.342Z,1613414547.342 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-15T18:42:27.343Z,1613414547.343 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-15T18:42:27.703Z,1613414547.703 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2021-02-15T18:42:27.703Z,1613414547.703 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T18:42:27.703Z,1613414547.703 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-02-15T18:42:27.703Z,1613414547.703 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Stopped
2021-02-15T18:42:27.703Z,1613414547.703 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen
2021-02-15T18:42:27.704Z,1613414547.704 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Stopped
2021-02-15T18:42:27.704Z,1613414547.704 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Stopped
2021-02-15T18:42:28.091Z,1613414548.091 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184227.52*53

2021-02-15T18:42:28.132Z,1613414548.132 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-02-15T18:42:29.683Z,1613414549.683 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184229.00,A,4131.45684,N,07040.31220,W,0.156,321.88,150221,,,A*72
2021-02-15T18:42:29.685Z,1613414549.685 [NAL9602](INFO): GPS fix at 20210215T184229: (41.524281, -70.671870)
2021-02-15T18:42:29.695Z,1613414549.695 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-02-15T18:42:29.696Z,1613414549.696 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-02-15T18:42:29.762Z,1613414549.762 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004829
2021-02-15T18:42:30.222Z,1613414550.222 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-02-15T18:42:33.776Z,1613414553.776 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210215T183845/Courier0004.lzma
2021-02-15T18:42:34.777Z,1613414554.777 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Courier0004.lzma.bak
2021-02-15T18:42:34.777Z,1613414554.777 [DataOverHttps](INFO): SBD MOMSN=80594
2021-02-15T18:42:40.223Z,1613414560.223 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-02-15T18:42:42.222Z,1613414562.222 [NAL9602](INFO): SBD MO Status=0, MOMSN=13295, MT Status=0, MTMSN=0
2021-02-15T18:42:42.223Z,1613414562.223 [NAL9602](INFO): No messages in MT queue
2021-02-15T18:42:43.815Z,1613414563.815 [DataOverHttps](INFO): Sending 125 bytes from file Logs/20210215T182832/Express0011.lzma
2021-02-15T18:42:44.817Z,1613414564.817 [DataOverHttps](INFO): Moved sent file to Logs/20210215T182832/Express0011.lzma.bak
2021-02-15T18:42:44.817Z,1613414564.817 [DataOverHttps](INFO): SBD MOMSN=80597
2021-02-15T18:42:53.856Z,1613414573.856 [DataOverHttps](INFO): Sending 811 bytes from file Logs/20210215T183845/Express0001.lzma
2021-02-15T18:42:54.857Z,1613414574.857 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Express0001.lzma.bak
2021-02-15T18:42:54.857Z,1613414574.857 [DataOverHttps](INFO): SBD MOMSN=80600
2021-02-15T18:43:03.899Z,1613414583.899 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20210215T183845/Express0005.lzma
2021-02-15T18:43:04.901Z,1613414584.901 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Express0005.lzma.bak
2021-02-15T18:43:04.901Z,1613414584.901 [DataOverHttps](INFO): SBD MOMSN=80617
2021-02-15T18:43:06.067Z,1613414586.067 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-02-15T18:43:06.067Z,1613414586.067 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-02-15T18:43:06.067Z,1613414586.067 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-15T18:43:12.956Z,1613414592.956 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T18:43:13.353Z,1613414593.353 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-02-15T18:43:36.039Z,1613414616.039 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T18:43:45.084Z,1613414625.084 [DataOverHttps](IMPORTANT): SBD MTMSN=20210215T184345
2021-02-15T18:43:45.312Z,1613414625.312 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-02-15T18:43:49.111Z,1613414629.111 [DataOverHttps](INFO): Received command:ibit
2021-02-15T18:43:49.181Z,1613414629.181 [CommandLine](IMPORTANT): got command ibit
2021-02-15T18:43:49.334Z,1613414629.334 [IBIT](IMPORTANT): Beginning Initiated BIT
2021-02-15T18:43:49.334Z,1613414629.334 [IBIT](IMPORTANT): Beginning control surface checks.
2021-02-15T18:43:49.338Z,1613414629.338 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-15T18:43:50.907Z,1613414630.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184351.00,A,4131.45890,N,07040.31416,W,0.058,321.88,150221,,,D*7E
2021-02-15T18:43:50.909Z,1613414630.909 [NAL9602](INFO): GPS fix at 20210215T184351: (41.524315, -70.671903)
2021-02-15T18:44:00.266Z,1613414640.266 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010086
CHAN A1 (24V): -0.028799
CHAN A2 (12V): -0.007173
CHAN A3 (5V): -0.001604
CHAN B0 (3.3V): -0.000092
CHAN B1 (3.15aV): -0.000055
CHAN B2 (3.15bV): 0.000607
CHAN B3 (GND): 0.002281
OPEN: 0.005409
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-15T18:44:00.602Z,1613414640.602 [NAL9602](INFO): SBD MO Status=0, MOMSN=13296, MT Status=0, MTMSN=0
2021-02-15T18:44:00.603Z,1613414640.603 [NAL9602](INFO): No messages in MT queue
2021-02-15T18:44:01.811Z,1613414641.811 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184401.00,A,4131.45890,N,07040.31419,W,0.272,321.88,150221,,,D*79
2021-02-15T18:44:01.813Z,1613414641.813 [NAL9602](INFO): GPS fix at 20210215T184401: (41.524315, -70.671903)
2021-02-15T18:44:05.112Z,1613414645.112 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184405.00,A,4131.45874,N,07040.31399,W,0.214,321.88,150221,,,D*78
2021-02-15T18:44:05.114Z,1613414645.114 [NAL9602](INFO): GPS fix at 20210215T184405: (41.524312, -70.671900)
2021-02-15T18:44:05.580Z,1613414645.580 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-02-15T18:44:08.296Z,1613414648.296 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184408.00,A,4131.45866,N,07040.31386,W,0.330,321.88,150221,,,D*7F
2021-02-15T18:44:08.299Z,1613414648.299 [NAL9602](INFO): GPS fix at 20210215T184408: (41.524311, -70.671898)
2021-02-15T18:44:10.306Z,1613414650.306 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-02-15T18:44:11.115Z,1613414651.115 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184411.00,A,4131.45856,N,07040.31378,W,0.039,321.88,150221,,,D*7F
2021-02-15T18:44:11.117Z,1613414651.117 [NAL9602](INFO): GPS fix at 20210215T184411: (41.524309, -70.671896)
2021-02-15T18:44:13.935Z,1613414653.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184414.00,A,4131.45849,N,07040.31380,W,0.117,321.88,150221,,,D*7E
2021-02-15T18:44:13.937Z,1613414653.937 [NAL9602](INFO): GPS fix at 20210215T184414: (41.524308, -70.671897)
2021-02-15T18:44:17.165Z,1613414657.165 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184417.00,A,4131.45849,N,07040.31389,W,0.078,321.88,150221,,,D*7C
2021-02-15T18:44:17.168Z,1613414657.168 [NAL9602](INFO): GPS fix at 20210215T184417: (41.524308, -70.671898)
2021-02-15T18:44:20.009Z,1613414660.009 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184420.00,A,4131.45854,N,07040.31400,W,0.039,321.88,150221,,,D*77
2021-02-15T18:44:20.011Z,1613414660.011 [NAL9602](INFO): GPS fix at 20210215T184420: (41.524309, -70.671900)
2021-02-15T18:44:23.222Z,1613414663.222 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184423.00,A,4131.45864,N,07040.31402,W,0.175,321.88,150221,,,D*7C
2021-02-15T18:44:23.224Z,1613414663.224 [NAL9602](INFO): GPS fix at 20210215T184423: (41.524311, -70.671900)
2021-02-15T18:44:25.257Z,1613414665.257 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-02-15T18:44:26.059Z,1613414666.059 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184426.00,A,4131.45883,N,07040.31402,W,0.058,321.88,150221,,,D*7E
2021-02-15T18:44:26.061Z,1613414666.061 [NAL9602](INFO): GPS fix at 20210215T184426: (41.524314, -70.671900)
2021-02-15T18:44:29.285Z,1613414669.285 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184429.00,A,4131.45890,N,07040.31408,W,0.039,321.88,150221,,,D*7E
2021-02-15T18:44:29.287Z,1613414669.287 [NAL9602](INFO): GPS fix at 20210215T184429: (41.524315, -70.671901)
2021-02-15T18:44:32.123Z,1613414672.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184432.00,A,4131.45888,N,07040.31400,W,0.097,321.88,150221,,,D*71
2021-02-15T18:44:32.129Z,1613414672.129 [NAL9602](INFO): GPS fix at 20210215T184432: (41.524315, -70.671900)
2021-02-15T18:44:34.938Z,1613414674.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184435.00,A,4131.45889,N,07040.31398,W,0.136,321.88,150221,,,D*7B
2021-02-15T18:44:34.940Z,1613414674.940 [NAL9602](INFO): GPS fix at 20210215T184435: (41.524315, -70.671900)
2021-02-15T18:44:35.769Z,1613414675.769 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 41.524315 Longitude: -70.671898
2021-02-15T18:44:36.174Z,1613414676.174 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2021-02-15T18:44:36.603Z,1613414676.603 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2021-02-15T18:44:36.603Z,1613414676.603 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2021-02-15T18:44:36.604Z,1613414676.604 [IBIT](IMPORTANT): Pressure:6.055639 PSI
2021-02-15T18:44:36.604Z,1613414676.604 [IBIT](IMPORTANT): Humidity:7.190819 %
2021-02-15T18:44:36.985Z,1613414676.985 [IBIT](IMPORTANT): Vehicle Pitch:3.601446 degrees
2021-02-15T18:44:36.985Z,1613414676.985 [IBIT](IMPORTANT): Vehicle  Roll:1.591171 degrees
2021-02-15T18:44:36.985Z,1613414676.985 [IBIT](IMPORTANT): Vehicle  Heading:135.493134 degrees
2021-02-15T18:44:37.376Z,1613414677.376 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2021-02-15T18:44:37.377Z,1613414677.377 [IBIT](IMPORTANT): buoyancyNeutral: 175.000000 cc
2021-02-15T18:44:37.377Z,1613414677.377 [IBIT](IMPORTANT): massDefault: -0.400000 cm
2021-02-15T18:44:37.377Z,1613414677.377 [IBIT](IMPORTANT): stopDepth: 12.000000 m
2021-02-15T18:44:37.378Z,1613414677.378 [IBIT](IMPORTANT): abortDepth: 16.000000 m
2021-02-15T18:44:37.378Z,1613414677.378 [IBIT](IMPORTANT): IBIT PASSED
2021-02-15T18:44:55.554Z,1613414695.554 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-02-15T18:45:07.669Z,1613414707.669 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T18:46:13.924Z,1613414773.924 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-02-15T18:46:13.926Z,1613414773.926 [BPC1](INFO): Received data from all battery sticks.
2021-02-15T18:48:06.682Z,1613414886.682 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-15T18:48:06.682Z,1613414886.682 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-02-15T18:48:06.682Z,1613414886.682 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-15T18:48:06.682Z,1613414886.682 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-02-15T18:48:07.059Z,1613414887.059 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-02-15T18:48:07.059Z,1613414887.059 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-02-15T18:48:07.513Z,1613414887.513 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 6.612083 min 
2021-02-15T18:48:07.513Z,1613414887.513 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-02-15T18:48:07.514Z,1613414887.514 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T18:48:07.514Z,1613414887.514 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-02-15T18:48:07.514Z,1613414887.514 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T18:48:07.519Z,1613414887.519 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2
2021-02-15T18:48:07.519Z,1613414887.519 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2
2021-02-15T18:48:07.520Z,1613414887.520 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T18:48:07.520Z,1613414887.520 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-02-15T18:48:09.473Z,1613414889.473 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184809.00,A,4131.45649,N,07040.30992,W,0.039,350.36,150221,,,A*73
2021-02-15T18:48:09.476Z,1613414889.476 [NAL9602](INFO): GPS fix at 20210215T184809: (41.524275, -70.671832)
2021-02-15T18:48:09.506Z,1613414889.506 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-02-15T18:48:09.506Z,1613414889.506 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-02-15T18:48:13.215Z,1613414893.215 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20210215T183845/Courier0007.lzma
2021-02-15T18:48:14.217Z,1613414894.217 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Courier0007.lzma.bak
2021-02-15T18:48:14.217Z,1613414894.217 [DataOverHttps](INFO): SBD MOMSN=80625
2021-02-15T18:48:23.254Z,1613414903.254 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210215T183845/Courier0010.lzma
2021-02-15T18:48:24.257Z,1613414904.257 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Courier0010.lzma.bak
2021-02-15T18:48:24.257Z,1613414904.257 [DataOverHttps](INFO): SBD MOMSN=80628
2021-02-15T18:48:25.627Z,1613414905.627 [NAL9602](INFO): SBD MO Status=0, MOMSN=13297, MT Status=0, MTMSN=0
2021-02-15T18:48:25.627Z,1613414905.627 [NAL9602](INFO): No messages in MT queue
2021-02-15T18:48:33.296Z,1613414913.296 [DataOverHttps](INFO): Sending 834 bytes from file Logs/20210215T183845/Express0008.lzma
2021-02-15T18:48:34.297Z,1613414914.297 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Express0008.lzma.bak
2021-02-15T18:48:34.297Z,1613414914.297 [DataOverHttps](INFO): SBD MOMSN=80630
2021-02-15T18:48:43.335Z,1613414923.335 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210215T183845/Express0011.lzma
2021-02-15T18:48:44.337Z,1613414924.337 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Express0011.lzma.bak
2021-02-15T18:48:44.337Z,1613414924.337 [DataOverHttps](INFO): SBD MOMSN=80655
2021-02-15T18:48:45.489Z,1613414925.489 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-02-15T18:48:45.489Z,1613414925.489 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-02-15T18:48:45.489Z,1613414925.489 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-15T18:48:56.346Z,1613414936.346 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T18:50:32.355Z,1613415032.355 [CommandLine](IMPORTANT): got command get platform_battery_charge
2021-02-15T18:50:32.356Z,1613415032.356 [CommandLine](FAULT): Element has no value
2021-02-15T18:51:11.298Z,1613415071.298 [RDI_PathfinderUp](ERROR): only read -1 of 4 data items
2021-02-15T18:51:23.958Z,1613415083.958 [CommandLine](IMPORTANT): got command failComponent
2021-02-15T18:51:23.961Z,1613415083.961 [CommandLine](IMPORTANT): Failed components:
2021-02-15T18:51:23.962Z,1613415083.962 [CommandLine](IMPORTANT): No failed Components.
2021-02-15T18:51:51.275Z,1613415111.275 [RDI_PathfinderUp](ERROR): only read 0 of 4 data items
2021-02-15T18:52:10.208Z,1613415130.208 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239460
2021-02-15T18:52:20.251Z,1613415140.251 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003242
2021-02-15T18:52:39.431Z,1613415159.431 [CommandLine](IMPORTANT): got command report mod platform_mass_position
2021-02-15T18:52:39.790Z,1613415159.790 [Reporter](INFO): platform_mass_position -0.003974 m
2021-02-15T18:52:51.520Z,1613415171.520 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2021-02-15T18:52:51.533Z,1613415171.533 [Reporter](INFO): platform_buoyancy_position 945.579102 cc
2021-02-15T18:53:17.121Z,1613415197.121 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2021-02-15T18:53:17.125Z,1613415197.125 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2021-02-15T18:53:17.349Z,1613415197.349 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,RDI_Pathfinder,RDI_PathfinderUp,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-02-15T18:53:19.498Z,1613415199.498 [Reporter](INFO): platform_buoyancy_position 960.217773 cc
2021-02-15T18:53:20.650Z,1613415200.650 [Reporter](INFO): platform_buoyancy_position 952.159790 cc
2021-02-15T18:53:21.017Z,1613415201.017 [Reporter](INFO): platform_buoyancy_position 949.742432 cc
2021-02-15T18:53:21.449Z,1613415201.449 [Reporter](INFO): platform_buoyancy_position 945.310425 cc
2021-02-15T18:53:21.825Z,1613415201.825 [Reporter](INFO): platform_buoyancy_position 941.012939 cc
2021-02-15T18:53:22.244Z,1613415202.244 [Reporter](INFO): platform_buoyancy_position 936.715332 cc
2021-02-15T18:53:22.634Z,1613415202.634 [Reporter](INFO): platform_buoyancy_position 930.268921 cc
2021-02-15T18:53:23.018Z,1613415203.018 [Reporter](INFO): platform_buoyancy_position 925.971313 cc
2021-02-15T18:53:23.456Z,1613415203.456 [Reporter](INFO): platform_buoyancy_position 919.524902 cc
2021-02-15T18:53:23.845Z,1613415203.845 [Reporter](INFO): platform_buoyancy_position 915.093018 cc
2021-02-15T18:53:24.289Z,1613415204.289 [Reporter](INFO): platform_buoyancy_position 908.780884 cc
2021-02-15T18:53:24.653Z,1613415204.653 [Reporter](INFO): platform_buoyancy_position 904.348999 cc
2021-02-15T18:53:25.073Z,1613415205.073 [Reporter](INFO): platform_buoyancy_position 898.036865 cc
2021-02-15T18:53:25.497Z,1613415205.497 [Reporter](INFO): platform_buoyancy_position 892.530640 cc
2021-02-15T18:53:25.877Z,1613415205.877 [Reporter](INFO): platform_buoyancy_position 888.501587 cc
2021-02-15T18:53:26.284Z,1613415206.284 [Reporter](INFO): platform_buoyancy_position 882.860962 cc
2021-02-15T18:53:26.713Z,1613415206.713 [Reporter](INFO): platform_buoyancy_position 876.683228 cc
2021-02-15T18:53:27.061Z,1613415207.061 [Reporter](INFO): platform_buoyancy_position 872.251221 cc
2021-02-15T18:53:27.491Z,1613415207.491 [RDI_PathfinderUp](ERROR): only read 0 of 4 data items
2021-02-15T18:53:27.601Z,1613415207.601 [Reporter](INFO): platform_buoyancy_position 865.670532 cc
2021-02-15T18:53:27.909Z,1613415207.909 [Reporter](INFO): platform_buoyancy_position 861.372925 cc
2021-02-15T18:53:28.310Z,1613415208.310 [Reporter](INFO): platform_buoyancy_position 857.209717 cc
2021-02-15T18:53:28.730Z,1613415208.730 [Reporter](INFO): platform_buoyancy_position 850.629028 cc
2021-02-15T18:53:29.117Z,1613415209.117 [Reporter](INFO): platform_buoyancy_position 846.331421 cc
2021-02-15T18:53:29.529Z,1613415209.529 [Reporter](INFO): platform_buoyancy_position 839.885010 cc
2021-02-15T18:53:29.970Z,1613415209.970 [Reporter](INFO): platform_buoyancy_position 835.587402 cc
2021-02-15T18:53:30.445Z,1613415210.445 [Reporter](INFO): platform_buoyancy_position 829.006714 cc
2021-02-15T18:53:30.760Z,1613415210.760 [Reporter](INFO): platform_buoyancy_position 824.843384 cc
2021-02-15T18:53:31.186Z,1613415211.186 [Reporter](INFO): platform_buoyancy_position 818.665588 cc
2021-02-15T18:53:31.589Z,1613415211.589 [Reporter](INFO): platform_buoyancy_position 812.219177 cc
2021-02-15T18:53:31.973Z,1613415211.973 [Reporter](INFO): platform_buoyancy_position 807.787292 cc
2021-02-15T18:53:32.470Z,1613415212.470 [Reporter](INFO): platform_buoyancy_position 801.475159 cc
2021-02-15T18:53:32.832Z,1613415212.832 [Reporter](INFO): platform_buoyancy_position 797.043274 cc
2021-02-15T18:53:33.253Z,1613415213.253 [Reporter](INFO): platform_buoyancy_position 790.462585 cc
2021-02-15T18:53:33.628Z,1613415213.628 [Reporter](INFO): platform_buoyancy_position 786.299255 cc
2021-02-15T18:53:34.009Z,1613415214.009 [Reporter](INFO): platform_buoyancy_position 782.001648 cc
2021-02-15T18:53:34.418Z,1613415214.418 [Reporter](INFO): platform_buoyancy_position 775.555298 cc
2021-02-15T18:53:34.785Z,1613415214.785 [Reporter](INFO): platform_buoyancy_position 771.257690 cc
2021-02-15T18:53:35.249Z,1613415215.249 [Reporter](INFO): platform_buoyancy_position 766.825745 cc
2021-02-15T18:53:35.664Z,1613415215.664 [Reporter](INFO): platform_buoyancy_position 760.513672 cc
2021-02-15T18:53:35.973Z,1613415215.973 [Reporter](INFO): platform_buoyancy_position 756.081787 cc
2021-02-15T18:53:36.456Z,1613415216.456 [Reporter](INFO): platform_buoyancy_position 749.769653 cc
2021-02-15T18:53:36.793Z,1613415216.793 [Reporter](INFO): platform_buoyancy_position 745.472046 cc
2021-02-15T18:53:37.171Z,1613415217.171 [Reporter](INFO): platform_buoyancy_position 740.502991 cc
2021-02-15T18:53:37.671Z,1613415217.671 [Reporter](INFO): platform_buoyancy_position 733.250793 cc
2021-02-15T18:53:38.005Z,1613415218.005 [Reporter](INFO): platform_buoyancy_position 728.147339 cc
2021-02-15T18:53:38.382Z,1613415218.382 [Reporter](INFO): platform_buoyancy_position 723.984070 cc
2021-02-15T18:53:38.802Z,1613415218.802 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 4.000000 millimeter
2021-02-15T18:53:38.811Z,1613415218.811 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2021-02-15T18:53:38.870Z,1613415218.870 [Reporter](INFO): platform_buoyancy_position 717.537659 cc
2021-02-15T18:53:38.876Z,1613415218.876 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,RDI_Pathfinder,RDI_PathfinderUp,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-02-15T18:53:39.264Z,1613415219.264 [Reporter](INFO): platform_buoyancy_position 712.971497 cc
2021-02-15T18:53:39.645Z,1613415219.645 [Reporter](INFO): platform_buoyancy_position 706.659363 cc
2021-02-15T18:53:40.025Z,1613415220.025 [Reporter](INFO): platform_buoyancy_position 702.496094 cc
2021-02-15T18:53:40.525Z,1613415220.525 [Reporter](INFO): platform_buoyancy_position 696.049683 cc
2021-02-15T18:53:40.881Z,1613415220.881 [Reporter](INFO): platform_buoyancy_position 691.886353 cc
2021-02-15T18:53:41.333Z,1613415221.333 [Reporter](INFO): platform_mass_position -0.003645 m
2021-02-15T18:53:41.333Z,1613415221.333 [Reporter](INFO): platform_buoyancy_position 685.171387 cc
2021-02-15T18:53:41.636Z,1613415221.636 [Reporter](INFO): platform_mass_position -0.003407 m
2021-02-15T18:53:41.637Z,1613415221.637 [Reporter](INFO): platform_buoyancy_position 681.008057 cc
2021-02-15T18:53:42.049Z,1613415222.049 [Reporter](INFO): platform_mass_position -0.003079 m
2021-02-15T18:53:42.050Z,1613415222.050 [Reporter](INFO): platform_buoyancy_position 676.710449 cc
2021-02-15T18:53:42.529Z,1613415222.529 [Reporter](INFO): platform_mass_position -0.002705 m
2021-02-15T18:53:42.529Z,1613415222.529 [Reporter](INFO): platform_buoyancy_position 670.264038 cc
2021-02-15T18:53:42.860Z,1613415222.860 [Reporter](INFO): platform_mass_position -0.002451 m
2021-02-15T18:53:42.861Z,1613415222.861 [Reporter](INFO): platform_buoyancy_position 665.966492 cc
2021-02-15T18:53:43.349Z,1613415223.349 [Reporter](INFO): platform_mass_position -0.002066 m
2021-02-15T18:53:43.350Z,1613415223.350 [Reporter](INFO): platform_buoyancy_position 659.520081 cc
2021-02-15T18:53:43.681Z,1613415223.681 [Reporter](INFO): platform_mass_position -0.001805 m
2021-02-15T18:53:43.682Z,1613415223.682 [Reporter](INFO): platform_buoyancy_position 655.088135 cc
2021-02-15T18:53:44.100Z,1613415224.100 [Reporter](INFO): platform_mass_position -0.001489 m
2021-02-15T18:53:44.116Z,1613415224.116 [Reporter](INFO): platform_buoyancy_position 649.984741 cc
2021-02-15T18:53:44.536Z,1613415224.536 [Reporter](INFO): platform_mass_position -0.001134 m
2021-02-15T18:53:44.537Z,1613415224.537 [Reporter](INFO): platform_buoyancy_position 644.075562 cc
2021-02-15T18:53:44.893Z,1613415224.893 [Reporter](INFO): platform_mass_position -0.000849 m
2021-02-15T18:53:44.894Z,1613415224.894 [Reporter](INFO): platform_buoyancy_position 639.912231 cc
2021-02-15T18:53:45.357Z,1613415225.357 [Reporter](INFO): platform_mass_position -0.000489 m
2021-02-15T18:53:45.357Z,1613415225.357 [Reporter](INFO): platform_buoyancy_position 633.600159 cc
2021-02-15T18:53:45.712Z,1613415225.712 [Reporter](INFO): platform_mass_position -0.000211 m
2021-02-15T18:53:45.713Z,1613415225.713 [Reporter](INFO): platform_buoyancy_position 629.302551 cc
2021-02-15T18:53:46.037Z,1613415226.037 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-15T18:53:46.037Z,1613415226.037 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-02-15T18:53:46.037Z,1613415226.037 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-15T18:53:46.038Z,1613415226.038 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-02-15T18:53:46.109Z,1613415226.109 [Reporter](INFO): platform_mass_position 0.000104 m
2021-02-15T18:53:46.110Z,1613415226.110 [Reporter](INFO): platform_buoyancy_position 623.124756 cc
2021-02-15T18:53:46.480Z,1613415226.480 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-02-15T18:53:46.480Z,1613415226.480 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-02-15T18:53:46.557Z,1613415226.557 [Reporter](INFO): platform_mass_position 0.000450 m
2021-02-15T18:53:46.558Z,1613415226.558 [Reporter](INFO): platform_buoyancy_position 616.409790 cc
2021-02-15T18:53:46.833Z,1613415226.833 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 12.269096 min 
2021-02-15T18:53:46.833Z,1613415226.833 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-02-15T18:53:46.833Z,1613415226.833 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T18:53:46.833Z,1613415226.833 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-02-15T18:53:46.833Z,1613415226.833 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T18:53:46.833Z,1613415226.833 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3
2021-02-15T18:53:46.833Z,1613415226.833 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3
2021-02-15T18:53:46.833Z,1613415226.833 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T18:53:46.834Z,1613415226.834 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-02-15T18:53:46.912Z,1613415226.912 [Reporter](INFO): platform_mass_position 0.000728 m
2021-02-15T18:53:46.913Z,1613415226.913 [Reporter](INFO): platform_buoyancy_position 612.246460 cc
2021-02-15T18:53:47.304Z,1613415227.304 [Reporter](INFO): platform_mass_position 0.001036 m
2021-02-15T18:53:47.305Z,1613415227.305 [Reporter](INFO): platform_buoyancy_position 607.814575 cc
2021-02-15T18:53:47.769Z,1613415227.769 [Reporter](INFO): platform_mass_position 0.001405 m
2021-02-15T18:53:47.770Z,1613415227.770 [Reporter](INFO): platform_buoyancy_position 601.502441 cc
2021-02-15T18:53:48.101Z,1613415228.101 [Reporter](INFO): platform_mass_position 0.001662 m
2021-02-15T18:53:48.101Z,1613415228.101 [Reporter](INFO): platform_buoyancy_position 597.070557 cc
2021-02-15T18:53:48.625Z,1613415228.625 [Reporter](INFO): platform_mass_position 0.002070 m
2021-02-15T18:53:48.626Z,1613415228.626 [Reporter](INFO): platform_buoyancy_position 590.624146 cc
2021-02-15T18:53:48.827Z,1613415228.827 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185349.00,A,4131.45886,N,07040.31479,W,0.233,120.33,150221,,,A*71
2021-02-15T18:53:48.829Z,1613415228.829 [NAL9602](INFO): GPS fix at 20210215T185349: (41.524314, -70.671913)
2021-02-15T18:53:48.912Z,1613415228.912 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-02-15T18:53:48.912Z,1613415228.912 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-02-15T18:53:48.985Z,1613415228.985 [Reporter](INFO): platform_mass_position 0.002352 m
2021-02-15T18:53:48.986Z,1613415228.986 [Reporter](INFO): platform_buoyancy_position 586.057983 cc
2021-02-15T18:53:49.369Z,1613415229.369 [Reporter](INFO): platform_mass_position 0.002659 m
2021-02-15T18:53:49.370Z,1613415229.370 [Reporter](INFO): platform_buoyancy_position 580.014465 cc
2021-02-15T18:53:49.904Z,1613415229.904 [Reporter](INFO): platform_mass_position 0.003068 m
2021-02-15T18:53:49.905Z,1613415229.905 [Reporter](INFO): platform_buoyancy_position 573.433777 cc
2021-02-15T18:53:50.333Z,1613415230.333 [Reporter](INFO): platform_mass_position 0.003409 m
2021-02-15T18:53:50.334Z,1613415230.334 [Reporter](INFO): platform_buoyancy_position 567.390259 cc
2021-02-15T18:53:50.785Z,1613415230.785 [Reporter](INFO): platform_mass_position 0.003769 m
2021-02-15T18:53:50.786Z,1613415230.786 [Reporter](INFO): platform_buoyancy_position 562.689758 cc
2021-02-15T18:53:51.174Z,1613415231.174 [Reporter](INFO): platform_mass_position 0.003999 m
2021-02-15T18:53:51.187Z,1613415231.187 [Reporter](INFO): platform_buoyancy_position 556.243347 cc
2021-02-15T18:53:51.718Z,1613415231.718 [Reporter](INFO): platform_buoyancy_position 549.931274 cc
2021-02-15T18:53:51.929Z,1613415231.929 [Reporter](INFO): platform_buoyancy_position 545.902283 cc
2021-02-15T18:53:52.359Z,1613415232.359 [Reporter](INFO): platform_buoyancy_position 541.201782 cc
2021-02-15T18:53:52.646Z,1613415232.646 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210215T183845/Courier0013.lzma
2021-02-15T18:53:52.677Z,1613415232.677 [Reporter](INFO): platform_buoyancy_position 536.904175 cc
2021-02-15T18:53:53.105Z,1613415233.105 [Reporter](INFO): platform_buoyancy_position 530.726379 cc
2021-02-15T18:53:53.504Z,1613415233.504 [Reporter](INFO): platform_buoyancy_position 526.160156 cc
2021-02-15T18:53:53.649Z,1613415233.649 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Courier0013.lzma.bak
2021-02-15T18:53:53.649Z,1613415233.649 [DataOverHttps](INFO): SBD MOMSN=80657
2021-02-15T18:53:53.895Z,1613415233.895 [Reporter](INFO): platform_buoyancy_position 520.250977 cc
2021-02-15T18:53:54.317Z,1613415234.317 [Reporter](INFO): platform_buoyancy_position 515.416138 cc
2021-02-15T18:53:54.705Z,1613415234.705 [Reporter](INFO): platform_buoyancy_position 510.984283 cc
2021-02-15T18:53:55.112Z,1613415235.112 [Reporter](INFO): platform_buoyancy_position 504.672180 cc
2021-02-15T18:53:55.543Z,1613415235.543 [Reporter](INFO): platform_buoyancy_position 498.360016 cc
2021-02-15T18:53:55.897Z,1613415235.897 [Reporter](INFO): platform_buoyancy_position 494.196777 cc
2021-02-15T18:53:56.386Z,1613415236.386 [Reporter](INFO): platform_buoyancy_position 488.018921 cc
2021-02-15T18:53:56.773Z,1613415236.773 [Reporter](INFO): platform_buoyancy_position 483.587036 cc
2021-02-15T18:53:57.109Z,1613415237.109 [Reporter](INFO): platform_buoyancy_position 479.020874 cc
2021-02-15T18:53:57.577Z,1613415237.577 [Reporter](INFO): platform_buoyancy_position 472.440125 cc
2021-02-15T18:53:57.947Z,1613415237.947 [Reporter](INFO): platform_buoyancy_position 468.142578 cc
2021-02-15T18:53:58.317Z,1613415238.317 [Reporter](INFO): platform_buoyancy_position 463.844971 cc
2021-02-15T18:53:58.777Z,1613415238.777 [Reporter](INFO): platform_buoyancy_position 457.398560 cc
2021-02-15T18:53:59.245Z,1613415239.245 [Reporter](INFO): platform_buoyancy_position 451.086426 cc
2021-02-15T18:53:59.568Z,1613415239.568 [Reporter](INFO): platform_buoyancy_position 446.654541 cc
2021-02-15T18:53:59.933Z,1613415239.933 [Reporter](INFO): platform_buoyancy_position 440.342468 cc
2021-02-15T18:54:00.365Z,1613415240.365 [Reporter](INFO): platform_buoyancy_position 436.044861 cc
2021-02-15T18:54:00.705Z,1613415240.705 [NAL9602](INFO): SBD MO Status=0, MOMSN=13298, MT Status=0, MTMSN=0
2021-02-15T18:54:00.705Z,1613415240.705 [NAL9602](INFO): No messages in MT queue
2021-02-15T18:54:00.797Z,1613415240.797 [Reporter](INFO): platform_buoyancy_position 429.464172 cc
2021-02-15T18:54:01.229Z,1613415241.229 [Reporter](INFO): platform_buoyancy_position 425.166565 cc
2021-02-15T18:54:01.577Z,1613415241.577 [Reporter](INFO): platform_buoyancy_position 419.794556 cc
2021-02-15T18:54:01.973Z,1613415241.973 [Reporter](INFO): platform_buoyancy_position 414.422546 cc
2021-02-15T18:54:02.392Z,1613415242.392 [Reporter](INFO): platform_buoyancy_position 408.244751 cc
2021-02-15T18:54:02.687Z,1613415242.687 [DataOverHttps](INFO): Sending 348 bytes from file Logs/20210215T183845/Express0014.lzma
2021-02-15T18:54:02.754Z,1613415242.754 [Reporter](INFO): platform_buoyancy_position 403.812866 cc
2021-02-15T18:54:03.241Z,1613415243.241 [Reporter](INFO): platform_buoyancy_position 397.366455 cc
2021-02-15T18:54:03.581Z,1613415243.581 [Reporter](INFO): platform_buoyancy_position 393.068848 cc
2021-02-15T18:54:03.689Z,1613415243.689 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Express0014.lzma.bak
2021-02-15T18:54:03.689Z,1613415243.689 [DataOverHttps](INFO): SBD MOMSN=80660
2021-02-15T18:54:03.997Z,1613415243.997 [Reporter](INFO): platform_buoyancy_position 388.771240 cc
2021-02-15T18:54:04.430Z,1613415244.430 [Reporter](INFO): platform_buoyancy_position 382.324829 cc
2021-02-15T18:54:04.744Z,1613415244.744 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-02-15T18:54:04.744Z,1613415244.744 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-02-15T18:54:04.744Z,1613415244.744 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-15T18:54:04.796Z,1613415244.796 [Reporter](INFO): platform_buoyancy_position 377.892944 cc
2021-02-15T18:54:05.273Z,1613415245.273 [Reporter](INFO): platform_buoyancy_position 371.446533 cc
2021-02-15T18:54:05.613Z,1613415245.613 [Reporter](INFO): platform_buoyancy_position 367.148926 cc
2021-02-15T18:54:06.013Z,1613415246.013 [Reporter](INFO): platform_buoyancy_position 360.971130 cc
2021-02-15T18:54:06.426Z,1613415246.426 [Reporter](INFO): platform_buoyancy_position 356.539246 cc
2021-02-15T18:54:06.817Z,1613415246.817 [Reporter](INFO): platform_buoyancy_position 350.495728 cc
2021-02-15T18:54:07.205Z,1613415247.205 [Reporter](INFO): platform_buoyancy_position 345.795227 cc
2021-02-15T18:54:07.637Z,1613415247.637 [Reporter](INFO): platform_buoyancy_position 341.363342 cc
2021-02-15T18:54:08.029Z,1613415248.029 [Reporter](INFO): platform_buoyancy_position 335.051270 cc
2021-02-15T18:54:08.453Z,1613415248.453 [Reporter](INFO): platform_buoyancy_position 330.619324 cc
2021-02-15T18:54:08.849Z,1613415248.849 [Reporter](INFO): platform_buoyancy_position 324.307251 cc
2021-02-15T18:54:09.260Z,1613415249.260 [Reporter](INFO): platform_buoyancy_position 320.009644 cc
2021-02-15T18:54:09.633Z,1613415249.633 [Reporter](INFO): platform_buoyancy_position 314.771912 cc
2021-02-15T18:54:10.033Z,1613415250.033 [Reporter](INFO): platform_buoyancy_position 309.131348 cc
2021-02-15T18:54:10.461Z,1613415250.461 [Reporter](INFO): platform_buoyancy_position 302.953522 cc
2021-02-15T18:54:10.861Z,1613415250.861 [Reporter](INFO): platform_buoyancy_position 298.790222 cc
2021-02-15T18:54:11.284Z,1613415251.284 [Reporter](INFO): platform_buoyancy_position 292.075226 cc
2021-02-15T18:54:11.649Z,1613415251.649 [Reporter](INFO): platform_buoyancy_position 287.777649 cc
2021-02-15T18:54:12.074Z,1613415252.074 [Reporter](INFO): platform_buoyancy_position 283.345734 cc
2021-02-15T18:54:12.499Z,1613415252.499 [Reporter](INFO): platform_buoyancy_position 277.033630 cc
2021-02-15T18:54:12.865Z,1613415252.865 [Reporter](INFO): platform_buoyancy_position 272.736023 cc
2021-02-15T18:54:13.289Z,1613415253.289 [Reporter](INFO): platform_buoyancy_position 266.289642 cc
2021-02-15T18:54:13.681Z,1613415253.681 [Reporter](INFO): platform_buoyancy_position 261.992035 cc
2021-02-15T18:54:14.117Z,1613415254.117 [Reporter](INFO): platform_buoyancy_position 255.679947 cc
2021-02-15T18:54:14.493Z,1613415254.493 [Reporter](INFO): platform_buoyancy_position 250.979446 cc
2021-02-15T18:54:14.905Z,1613415254.905 [Reporter](INFO): platform_buoyancy_position 244.667313 cc
2021-02-15T18:54:15.324Z,1613415255.324 [Reporter](INFO): platform_buoyancy_position 238.623840 cc
2021-02-15T18:54:15.712Z,1613415255.712 [Reporter](INFO): platform_buoyancy_position 235.266327 cc
2021-02-15T18:54:16.104Z,1613415256.104 [Reporter](INFO): platform_buoyancy_position 229.491425 cc
2021-02-15T18:54:16.521Z,1613415256.521 [Reporter](INFO): platform_buoyancy_position 224.253723 cc
2021-02-15T18:54:16.922Z,1613415256.922 [Reporter](INFO): platform_buoyancy_position 219.016022 cc
2021-02-15T18:54:17.305Z,1613415257.305 [Reporter](INFO): platform_buoyancy_position 213.509735 cc
2021-02-15T18:54:17.761Z,1613415257.761 [Reporter](INFO): platform_buoyancy_position 208.406342 cc
2021-02-15T18:54:31.405Z,1613415271.405 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T18:57:49.922Z,1613415469.922 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -2.000000 millimeter
2021-02-15T18:57:51.403Z,1613415471.403 [RDI_PathfinderUp](ERROR): DVL BIT error. See manual. Result code: 5
2021-02-15T18:57:52.316Z,1613415472.316 [Reporter](INFO): platform_mass_position 0.003687 m
2021-02-15T18:57:52.681Z,1613415472.681 [Reporter](INFO): platform_mass_position 0.003404 m
2021-02-15T18:57:53.068Z,1613415473.068 [Reporter](INFO): platform_mass_position 0.003110 m
2021-02-15T18:57:53.485Z,1613415473.485 [Reporter](INFO): platform_mass_position 0.002770 m
2021-02-15T18:57:53.889Z,1613415473.889 [Reporter](INFO): platform_mass_position 0.002458 m
2021-02-15T18:57:54.350Z,1613415474.350 [Reporter](INFO): platform_mass_position 0.002093 m
2021-02-15T18:57:54.684Z,1613415474.684 [Reporter](INFO): platform_mass_position 0.001843 m
2021-02-15T18:57:55.100Z,1613415475.100 [Reporter](INFO): platform_mass_position 0.001517 m
2021-02-15T18:57:55.521Z,1613415475.521 [Reporter](INFO): platform_mass_position 0.001178 m
2021-02-15T18:57:55.932Z,1613415475.932 [Reporter](INFO): platform_mass_position 0.000854 m
2021-02-15T18:57:56.397Z,1613415476.397 [Reporter](INFO): platform_mass_position 0.000492 m
2021-02-15T18:57:56.713Z,1613415476.713 [Reporter](INFO): platform_mass_position 0.000244 m
2021-02-15T18:57:57.122Z,1613415477.122 [Reporter](INFO): platform_mass_position -0.000076 m
2021-02-15T18:57:57.549Z,1613415477.549 [Reporter](INFO): platform_mass_position -0.000405 m
2021-02-15T18:57:57.920Z,1613415477.920 [Reporter](INFO): platform_mass_position -0.000694 m
2021-02-15T18:57:58.369Z,1613415478.369 [Reporter](INFO): platform_mass_position -0.001056 m
2021-02-15T18:57:58.733Z,1613415478.733 [Reporter](INFO): platform_mass_position -0.001343 m
2021-02-15T18:57:59.123Z,1613415479.123 [Reporter](INFO): platform_mass_position -0.001646 m
2021-02-15T18:57:59.541Z,1613415479.541 [Reporter](INFO): platform_mass_position -0.001977 m
2021-02-15T18:58:54.320Z,1613415534.320 [CommandLine](IMPORTANT): got command maintain clear
2021-02-15T18:58:54.478Z,1613415534.478 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,RDI_Pathfinder,RDI_PathfinderUp,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-02-15T18:58:57.004Z,1613415537.004 [Reporter](INFO): platform_buoyancy_position 199.542542 cc
2021-02-15T18:58:57.373Z,1613415537.373 [Reporter](INFO): platform_mass_position -0.002236 m
2021-02-15T18:58:57.733Z,1613415537.733 [Reporter](INFO): platform_mass_position -0.002521 m
2021-02-15T18:58:58.160Z,1613415538.160 [Reporter](INFO): platform_mass_position -0.002847 m
2021-02-15T18:58:58.163Z,1613415538.163 [Reporter](INFO): platform_buoyancy_position 198.199524 cc
2021-02-15T18:58:58.569Z,1613415538.569 [Reporter](INFO): platform_mass_position -0.003182 m
2021-02-15T18:58:58.572Z,1613415538.572 [Reporter](INFO): platform_buoyancy_position 199.542542 cc
2021-02-15T18:58:58.948Z,1613415538.948 [Reporter](INFO): platform_mass_position -0.003480 m
2021-02-15T18:58:58.949Z,1613415538.949 [Reporter](INFO): platform_buoyancy_position 205.988922 cc
2021-02-15T18:58:59.388Z,1613415539.388 [Reporter](INFO): platform_mass_position -0.003819 m
2021-02-15T18:58:59.389Z,1613415539.389 [Reporter](INFO): platform_buoyancy_position 212.838226 cc
2021-02-15T18:58:59.745Z,1613415539.745 [Reporter](INFO): platform_mass_position -0.004000 m
2021-02-15T18:58:59.745Z,1613415539.745 [Reporter](INFO): platform_buoyancy_position 218.881744 cc
2021-02-15T18:59:00.247Z,1613415540.247 [Reporter](INFO): platform_buoyancy_position 227.476929 cc
2021-02-15T18:59:00.561Z,1613415540.561 [Reporter](INFO): platform_buoyancy_position 231.908844 cc
2021-02-15T18:59:00.961Z,1613415540.961 [Reporter](INFO): platform_buoyancy_position 240.235443 cc
2021-02-15T18:59:01.374Z,1613415541.374 [Reporter](INFO): platform_buoyancy_position 244.667313 cc
2021-02-15T18:59:01.771Z,1613415541.771 [Reporter](INFO): platform_buoyancy_position 250.710846 cc
2021-02-15T18:59:02.162Z,1613415542.162 [Reporter](INFO): platform_buoyancy_position 255.545624 cc
2021-02-15T18:59:02.571Z,1613415542.571 [Reporter](INFO): platform_buoyancy_position 261.857727 cc
2021-02-15T18:59:02.969Z,1613415542.969 [Reporter](INFO): platform_buoyancy_position 266.289642 cc
2021-02-15T18:59:03.381Z,1613415543.381 [Reporter](INFO): platform_buoyancy_position 272.736023 cc
2021-02-15T18:59:03.770Z,1613415543.770 [Reporter](INFO): platform_buoyancy_position 277.033630 cc
2021-02-15T18:59:04.184Z,1613415544.184 [Reporter](INFO): platform_buoyancy_position 281.196930 cc
2021-02-15T18:59:04.617Z,1613415544.617 [Reporter](INFO): platform_buoyancy_position 287.777649 cc
2021-02-15T18:59:04.977Z,1613415544.977 [Reporter](INFO): platform_buoyancy_position 292.209534 cc
2021-02-15T18:59:05.368Z,1613415545.368 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-15T18:59:05.368Z,1613415545.368 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-02-15T18:59:05.369Z,1613415545.369 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-15T18:59:05.369Z,1613415545.369 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-02-15T18:59:05.409Z,1613415545.409 [Reporter](INFO): platform_buoyancy_position 298.521637 cc
2021-02-15T18:59:05.738Z,1613415545.738 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-02-15T18:59:05.738Z,1613415545.738 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-02-15T18:59:05.805Z,1613415545.805 [Reporter](INFO): platform_buoyancy_position 302.953522 cc
2021-02-15T18:59:06.154Z,1613415546.154 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 17.590068 min 
2021-02-15T18:59:06.154Z,1613415546.154 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-02-15T18:59:06.175Z,1613415546.175 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T18:59:06.176Z,1613415546.176 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-02-15T18:59:06.176Z,1613415546.176 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T18:59:06.177Z,1613415546.177 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #4
2021-02-15T18:59:06.177Z,1613415546.177 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=4
2021-02-15T18:59:06.177Z,1613415546.177 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T18:59:06.177Z,1613415546.177 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-02-15T18:59:06.263Z,1613415546.263 [Reporter](INFO): platform_buoyancy_position 309.265625 cc
2021-02-15T18:59:06.609Z,1613415546.609 [Reporter](INFO): platform_buoyancy_position 313.428955 cc
2021-02-15T18:59:07.006Z,1613415547.006 [Reporter](INFO): platform_buoyancy_position 318.532349 cc
2021-02-15T18:59:07.433Z,1613415547.433 [Reporter](INFO): platform_buoyancy_position 324.172913 cc
2021-02-15T18:59:07.821Z,1613415547.821 [Reporter](INFO): platform_buoyancy_position 328.604858 cc
2021-02-15T18:59:08.042Z,1613415548.042 [CommandLine](IMPORTANT): got command report clear
2021-02-15T18:59:08.182Z,1613415548.182 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185909.00,A,4131.46047,N,07040.31585,W,0.136,149.03,150221,,,A*71
2021-02-15T18:59:08.185Z,1613415548.185 [NAL9602](INFO): GPS fix at 20210215T185909: (41.524341, -70.671931)
2021-02-15T18:59:08.240Z,1613415548.240 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-02-15T18:59:08.240Z,1613415548.240 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-02-15T18:59:11.978Z,1613415551.978 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210215T183845/Courier0016.lzma
2021-02-15T18:59:12.981Z,1613415552.981 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Courier0016.lzma.bak
2021-02-15T18:59:12.981Z,1613415552.981 [DataOverHttps](INFO): SBD MOMSN=80673
2021-02-15T18:59:22.019Z,1613415562.019 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20210215T183845/Express0017.lzma
2021-02-15T18:59:22.702Z,1613415562.702 [NAL9602](INFO): SBD MO Status=0, MOMSN=13299, MT Status=0, MTMSN=0
2021-02-15T18:59:22.702Z,1613415562.702 [NAL9602](INFO): No messages in MT queue
2021-02-15T18:59:23.021Z,1613415563.021 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Express0017.lzma.bak
2021-02-15T18:59:23.021Z,1613415563.021 [DataOverHttps](INFO): SBD MOMSN=80676
2021-02-15T18:59:24.360Z,1613415564.360 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-02-15T18:59:24.360Z,1613415564.360 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-02-15T18:59:24.360Z,1613415564.360 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-15T18:59:53.407Z,1613415593.407 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T19:03:31.204Z,1613415811.204 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-02-15T19:04:24.919Z,1613415864.919 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-15T19:04:24.919Z,1613415864.919 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-02-15T19:04:24.919Z,1613415864.919 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-15T19:04:24.919Z,1613415864.919 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-02-15T19:04:25.381Z,1613415865.381 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-02-15T19:04:25.381Z,1613415865.381 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-02-15T19:04:25.739Z,1613415865.739 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 22.917448 min 
2021-02-15T19:04:25.739Z,1613415865.739 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-02-15T19:04:25.740Z,1613415865.740 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:04:25.740Z,1613415865.740 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-02-15T19:04:25.740Z,1613415865.740 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:04:25.740Z,1613415865.740 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #5
2021-02-15T19:04:25.740Z,1613415865.740 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=5
2021-02-15T19:04:25.740Z,1613415865.740 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:04:25.740Z,1613415865.740 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-02-15T19:04:27.739Z,1613415867.739 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190428.00,A,4131.45755,N,07040.31290,W,0.117,337.91,150221,,,A*7C
2021-02-15T19:04:27.741Z,1613415867.741 [NAL9602](INFO): GPS fix at 20210215T190428: (41.524293, -70.671882)
2021-02-15T19:04:27.800Z,1613415867.800 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-02-15T19:04:27.800Z,1613415867.800 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-02-15T19:04:31.294Z,1613415871.294 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210215T183845/Courier0019.lzma
2021-02-15T19:04:32.297Z,1613415872.297 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Courier0019.lzma.bak
2021-02-15T19:04:32.297Z,1613415872.297 [DataOverHttps](INFO): SBD MOMSN=80683
2021-02-15T19:04:36.688Z,1613415876.688 [NAL9602](INFO): SBD MO Status=0, MOMSN=13300, MT Status=0, MTMSN=0
2021-02-15T19:04:36.689Z,1613415876.689 [NAL9602](INFO): No messages in MT queue
2021-02-15T19:04:41.343Z,1613415881.343 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20210215T183845/Express0020.lzma
2021-02-15T19:04:42.345Z,1613415882.345 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Express0020.lzma.bak
2021-02-15T19:04:42.345Z,1613415882.345 [DataOverHttps](INFO): SBD MOMSN=80686
2021-02-15T19:04:43.581Z,1613415883.581 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-02-15T19:04:43.581Z,1613415883.581 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-02-15T19:04:43.581Z,1613415883.581 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-15T19:05:07.396Z,1613415907.396 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T19:05:46.612Z,1613415946.612 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-02-15T19:06:57.912Z,1613416017.912 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259377
2021-02-15T19:07:07.956Z,1613416027.956 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003537
2021-02-15T19:09:44.357Z,1613416184.357 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-15T19:09:44.357Z,1613416184.357 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-02-15T19:09:44.357Z,1613416184.357 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-15T19:09:44.357Z,1613416184.357 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-02-15T19:09:44.693Z,1613416184.693 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-02-15T19:09:44.693Z,1613416184.693 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-02-15T19:09:45.099Z,1613416185.099 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 28.239315 min 
2021-02-15T19:09:45.103Z,1613416185.103 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-02-15T19:09:45.103Z,1613416185.103 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:09:45.103Z,1613416185.103 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-02-15T19:09:45.103Z,1613416185.103 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:09:45.104Z,1613416185.104 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #6
2021-02-15T19:09:45.104Z,1613416185.104 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=6
2021-02-15T19:09:45.104Z,1613416185.104 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:09:45.104Z,1613416185.104 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-02-15T19:09:47.103Z,1613416187.103 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190948.00,A,4131.45853,N,07040.31354,W,0.058,10.38,150221,,,A*48
2021-02-15T19:09:47.105Z,1613416187.105 [NAL9602](INFO): GPS fix at 20210215T190948: (41.524309, -70.671892)
2021-02-15T19:09:47.140Z,1613416187.140 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-02-15T19:09:47.140Z,1613416187.140 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-02-15T19:09:51.674Z,1613416191.674 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210215T183845/Courier0022.lzma
2021-02-15T19:09:52.677Z,1613416192.677 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Courier0022.lzma.bak
2021-02-15T19:09:52.678Z,1613416192.678 [DataOverHttps](INFO): SBD MOMSN=80690
2021-02-15T19:10:01.714Z,1613416201.714 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20210215T183845/Express0023.lzma
2021-02-15T19:10:02.717Z,1613416202.717 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Express0023.lzma.bak
2021-02-15T19:10:02.717Z,1613416202.717 [DataOverHttps](INFO): SBD MOMSN=80693
2021-02-15T19:10:02.894Z,1613416202.894 [NAL9602](INFO): SBD MO Status=0, MOMSN=13301, MT Status=0, MTMSN=0
2021-02-15T19:10:02.895Z,1613416202.895 [NAL9602](INFO): No messages in MT queue
2021-02-15T19:10:04.142Z,1613416204.142 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-02-15T19:10:04.142Z,1613416204.142 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-02-15T19:10:04.142Z,1613416204.142 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-15T19:10:21.492Z,1613416221.492 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-02-15T19:10:33.597Z,1613416233.597 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T19:10:56.636Z,1613416256.636 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-02-15T19:12:53.448Z,1613416373.448 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255646
2021-02-15T19:13:03.492Z,1613416383.492 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003527
2021-02-15T19:14:38.876Z,1613416478.876 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243443
2021-02-15T19:15:04.703Z,1613416504.703 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-15T19:15:04.703Z,1613416504.703 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-02-15T19:15:04.703Z,1613416504.703 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-15T19:15:04.703Z,1613416504.703 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-02-15T19:15:05.115Z,1613416505.115 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-02-15T19:15:05.115Z,1613416505.115 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-02-15T19:15:05.552Z,1613416505.552 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 33.579677 min 
2021-02-15T19:15:05.552Z,1613416505.552 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-02-15T19:15:05.552Z,1613416505.552 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:15:05.552Z,1613416505.552 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-02-15T19:15:05.552Z,1613416505.552 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:15:05.553Z,1613416505.553 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #7
2021-02-15T19:15:05.553Z,1613416505.553 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=7
2021-02-15T19:15:05.553Z,1613416505.553 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:15:05.553Z,1613416505.553 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-02-15T19:15:07.521Z,1613416507.521 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191508.00,A,4131.44515,N,07040.34191,W,4.102,221.64,150221,,,A*72
2021-02-15T19:15:07.523Z,1613416507.523 [NAL9602](INFO): GPS fix at 20210215T191508: (41.524086, -70.672365)
2021-02-15T19:15:07.599Z,1613416507.599 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-02-15T19:15:07.599Z,1613416507.599 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-02-15T19:15:15.019Z,1613416515.019 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:15:29.011Z,1613416529.011 [NAL9602](INFO): SBD MO Status=1, MOMSN=13302, MT Status=0, MTMSN=0
2021-02-15T19:15:29.076Z,1613416529.076 [NAL9602](INFO): Sent 72 bytes from file Logs/20210215T183845/Courier0025.lzma
2021-02-15T19:15:29.077Z,1613416529.077 [NAL9602](INFO): Packets left to send: 0
2021-02-15T19:15:36.838Z,1613416536.838 [NAL9602](INFO): SBD MO Status=1, MOMSN=13303, MT Status=0, MTMSN=0
2021-02-15T19:15:36.888Z,1613416536.888 [NAL9602](INFO): Sent 118 bytes from file Logs/20210215T183845/Express0026.lzma
2021-02-15T19:15:36.888Z,1613416536.888 [NAL9602](INFO): Packets left to send: 0
2021-02-15T19:15:47.358Z,1613416547.358 [NAL9602](INFO): SBD MO Status=0, MOMSN=13304, MT Status=0, MTMSN=0
2021-02-15T19:15:47.575Z,1613416547.575 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-02-15T19:15:47.587Z,1613416547.587 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-02-15T19:15:47.587Z,1613416547.587 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-15T19:15:50.171Z,1613416550.171 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:16:18.049Z,1613416578.049 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T19:16:25.312Z,1613416585.312 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:16:34.352Z,1613416594.352 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003538
2021-02-15T19:18:44.880Z,1613416724.880 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239265
2021-02-15T19:18:54.924Z,1613416734.924 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003538
2021-02-15T19:20:30.320Z,1613416830.320 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.257568
2021-02-15T19:20:47.929Z,1613416847.929 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-15T19:20:47.930Z,1613416847.930 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-02-15T19:20:47.930Z,1613416847.930 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-15T19:20:47.930Z,1613416847.930 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-02-15T19:20:48.358Z,1613416848.358 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-02-15T19:20:48.358Z,1613416848.358 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-02-15T19:20:48.762Z,1613416848.762 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 39.300403 min 
2021-02-15T19:20:48.762Z,1613416848.762 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-02-15T19:20:48.762Z,1613416848.762 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:20:48.762Z,1613416848.762 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-02-15T19:20:48.762Z,1613416848.762 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:20:48.762Z,1613416848.762 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #8
2021-02-15T19:20:48.762Z,1613416848.762 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=8
2021-02-15T19:20:48.763Z,1613416848.763 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:20:48.763Z,1613416848.763 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-02-15T19:20:50.755Z,1613416850.755 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192051.00,A,4131.16430,N,07041.16946,W,15.006,275.80,150221,,,D*41
2021-02-15T19:20:50.757Z,1613416850.757 [NAL9602](INFO): GPS fix at 20210215T192051: (41.519405, -70.686158)
2021-02-15T19:20:50.783Z,1613416850.783 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-02-15T19:20:50.783Z,1613416850.783 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-02-15T19:21:06.464Z,1613416866.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:21:19.158Z,1613416879.158 [NAL9602](INFO): SBD MO Status=1, MOMSN=13305, MT Status=0, MTMSN=0
2021-02-15T19:21:19.203Z,1613416879.203 [NAL9602](INFO): Sent 72 bytes from file Logs/20210215T183845/Courier0028.lzma
2021-02-15T19:21:19.203Z,1613416879.203 [NAL9602](INFO): Packets left to send: 0
2021-02-15T19:21:35.075Z,1613416895.075 [NAL9602](INFO): SBD MO Status=2, MOMSN=13306, MT Status=2, MTMSN=0
2021-02-15T19:21:35.079Z,1613416895.079 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-02-15T19:21:41.620Z,1613416901.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:22:00.273Z,1613416920.273 [NAL9602](INFO): SBD MO Status=2, MOMSN=13306, MT Status=2, MTMSN=0
2021-02-15T19:22:00.273Z,1613416920.273 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-02-15T19:22:12.423Z,1613416932.423 [RDI_PathfinderUp](ERROR): only read 1 of 4 data items
2021-02-15T19:22:16.759Z,1613416936.759 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:22:22.134Z,1613416942.134 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-02-15T19:22:36.322Z,1613416956.322 [NAL9602](INFO): SBD MO Status=2, MOMSN=13306, MT Status=2, MTMSN=0
2021-02-15T19:22:36.322Z,1613416956.322 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-02-15T19:22:51.899Z,1613416971.899 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:23:10.060Z,1613416990.060 [NAL9602](INFO): SBD MO Status=2, MOMSN=13306, MT Status=2, MTMSN=0
2021-02-15T19:23:10.060Z,1613416990.060 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-02-15T19:23:12.174Z,1613416992.174 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-02-15T19:23:27.039Z,1613417007.039 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:23:32.402Z,1613417012.402 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-02-15T19:23:35.631Z,1613417015.631 [NAL9602](INFO): SBD MO Status=2, MOMSN=13306, MT Status=2, MTMSN=0
2021-02-15T19:23:35.631Z,1613417015.631 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-02-15T19:23:55.567Z,1613417035.567 [NAL9602](INFO): SBD MO Status=2, MOMSN=13306, MT Status=2, MTMSN=0
2021-02-15T19:23:55.567Z,1613417035.567 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-02-15T19:24:02.198Z,1613417042.198 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:24:15.463Z,1613417055.463 [NAL9602](INFO): SBD MO Status=1, MOMSN=13306, MT Status=0, MTMSN=0
2021-02-15T19:24:15.511Z,1613417055.511 [NAL9602](INFO): Sent 132 bytes from file Logs/20210215T183845/Express0029.lzma
2021-02-15T19:24:15.511Z,1613417055.511 [NAL9602](INFO): Packets left to send: 0
2021-02-15T19:24:25.578Z,1613417065.578 [NAL9602](INFO): SBD MO Status=0, MOMSN=13307, MT Status=0, MTMSN=0
2021-02-15T19:24:25.660Z,1613417065.660 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-02-15T19:24:25.660Z,1613417065.660 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-02-15T19:24:25.660Z,1613417065.660 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-15T19:24:37.343Z,1613417077.343 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:24:56.276Z,1613417096.276 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T19:25:12.483Z,1613417112.483 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:25:47.627Z,1613417147.627 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:26:22.767Z,1613417182.767 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-15T19:26:36.828Z,1613417196.828 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003439
2021-02-15T19:29:26.147Z,1613417366.147 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-15T19:29:26.147Z,1613417366.147 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-02-15T19:29:26.147Z,1613417366.147 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-15T19:29:26.147Z,1613417366.147 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-02-15T19:29:26.578Z,1613417366.578 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-02-15T19:29:26.578Z,1613417366.578 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-02-15T19:29:26.975Z,1613417366.975 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 47.937390 min 
2021-02-15T19:29:26.975Z,1613417366.975 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-02-15T19:29:26.976Z,1613417366.976 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:29:26.976Z,1613417366.976 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-02-15T19:29:26.976Z,1613417366.976 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:29:26.976Z,1613417366.976 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #9
2021-02-15T19:29:26.976Z,1613417366.976 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=9
2021-02-15T19:29:26.976Z,1613417366.976 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-02-15T19:29:26.976Z,1613417366.976 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-02-15T19:29:28.969Z,1613417368.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192930.00,A,4132.87249,N,07042.15540,W,12.693,323.22,150221,,,D*41
2021-02-15T19:29:28.971Z,1613417368.971 [NAL9602](INFO): GPS fix at 20210215T192930: (41.547875, -70.702590)
2021-02-15T19:29:28.981Z,1613417368.981 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-02-15T19:29:28.981Z,1613417368.981 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-02-15T19:29:32.555Z,1613417372.555 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210215T183845/Courier0031.lzma
2021-02-15T19:29:33.557Z,1613417373.557 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Courier0031.lzma.bak
2021-02-15T19:29:33.557Z,1613417373.557 [DataOverHttps](INFO): SBD MOMSN=80707
2021-02-15T19:29:37.555Z,1613417377.555 [RDI_PathfinderUp](ERROR): only read 0 of 4 data items
2021-02-15T19:29:42.595Z,1613417382.595 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20210215T183845/Express0032.lzma
2021-02-15T19:29:43.597Z,1613417383.597 [DataOverHttps](INFO): Moved sent file to Logs/20210215T183845/Express0032.lzma.bak
2021-02-15T19:29:43.597Z,1613417383.597 [DataOverHttps](INFO): SBD MOMSN=80710
2021-02-15T19:29:44.760Z,1613417384.760 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-02-15T19:29:44.760Z,1613417384.760 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-02-15T19:29:44.761Z,1613417384.761 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-15T19:29:47.151Z,1613417387.151 [NAL9602](INFO): SBD MO Status=2, MOMSN=13308, MT Status=2, MTMSN=0
2021-02-15T19:29:47.151Z,1613417387.151 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-02-15T19:29:59.275Z,1613417399.275 [NAL9602](INFO): SBD MO Status=0, MOMSN=13308, MT Status=0, MTMSN=0
2021-02-15T19:29:59.275Z,1613417399.275 [NAL9602](INFO): No messages in MT queue
2021-02-15T19:30:29.973Z,1613417429.973 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-15T19:30:57.868Z,1613417457.868 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-02-15T19:33:48.364Z,1613417628.364 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-02-15T19:34:06.544Z,1613417646.544 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-02-15T19:34:29.812Z,1613417669.812 [DataOverHttps](IMPORTANT): SBD MTMSN=20210215T193430
2021-02-15T19:34:33.831Z,1613417673.831 [DataOverHttps](INFO): Received command:restart logs