2024-08-16T20:59:24.038Z,1723841964.038 [lineCaptureHoming:U.Execute] Stopped 2024-08-16T20:59:24.038Z,1723841964.038 [lineCaptureHoming:InitialDive] Running Loop=1 2024-08-16T20:59:24.038Z,1723841964.038 [lineCaptureHoming:InitialDive](DEBUG): Aggregate::initialize lineCaptureHoming:InitialDive 2024-08-16T20:59:24.038Z,1723841964.038 [lineCaptureHoming:InitialDive:A.SetSpeed] Running Loop=1 2024-08-16T20:59:24.038Z,1723841964.038 [lineCaptureHoming:InitialDive:A.SetSpeed](DEBUG): Initialize. 2024-08-16T20:59:24.039Z,1723841964.039 [lineCaptureHoming:InitialDive:B.Pitch] Running Loop=1 2024-08-16T20:59:24.039Z,1723841964.039 [lineCaptureHoming:InitialDive:B.Pitch](DEBUG): Initialize. 2024-08-16T20:59:24.071Z,1723841964.071 [CommandExec](IMPORTANT): got command restart logs 2024-08-16T20:59:24.432Z,1723841964.432 [lineCaptureHoming:InitialDive:A.SetSpeed] Running Loop=1 2024-08-16T20:59:30.474Z,1723841970.474 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T20:59:41.251Z,1723841981.251 [CommandExec](IMPORTANT): got command show stack 2024-08-16T20:59:41.251Z,1723841981.251 [CommandExec](IMPORTANT): Behavior Stack: 2024-08-16T20:59:41.253Z,1723841981.253 [lineCaptureHoming](IMPORTANT): Priority 0: lineCaptureHoming:TrackAC. 2024-08-16T20:59:41.253Z,1723841981.253 [lineCaptureHoming:InitialDive](IMPORTANT): Priority 1: lineCaptureHoming:InitialDive:A.SetSpeed 2024-08-16T20:59:41.259Z,1723841981.259 [lineCaptureHoming:InitialDive](IMPORTANT): Priority 2: lineCaptureHoming:InitialDive:B.Pitch 2024-08-16T20:59:43.359Z,1723841983.359 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T20:59:53.698Z,1723841993.698 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T20:59:54.123Z,1723841994.123 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-16T20:59:55.011Z,1723841995.011 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 75 meter 2024-08-16T20:59:55.019Z,1723841995.019 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2024-08-16T20:59:55.196Z,1723841995.196 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,Maintain_NAL9602.time_fix,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-08-16T20:59:55.345Z,1723841995.345 [lineCaptureHoming:InitialDive:B.Pitch] Stopped 2024-08-16T20:59:55.345Z,1723841995.345 [lineCaptureHoming:InitialDive](INFO): Completed lineCaptureHoming:InitialDive 2024-08-16T20:59:55.346Z,1723841995.346 [lineCaptureHoming:InitialDive] Stopped 2024-08-16T20:59:55.346Z,1723841995.346 [lineCaptureHoming:InitialDive](DEBUG): Aggregate::uninitialize lineCaptureHoming:InitialDive 2024-08-16T20:59:55.346Z,1723841995.346 [lineCaptureHoming:InitialDive:A.SetSpeed] Stopped 2024-08-16T20:59:55.346Z,1723841995.346 [lineCaptureHoming:InitialDive:A.SetSpeed](DEBUG): Uninitialize. 2024-08-16T20:59:55.346Z,1723841995.346 [lineCaptureHoming:Lap] Running Loop=1 2024-08-16T20:59:55.346Z,1723841995.346 [lineCaptureHoming:Lap](DEBUG): Aggregate::initialize lineCaptureHoming:Lap 2024-08-16T20:59:55.346Z,1723841995.346 [lineCaptureHoming:Lap:A] Running Loop=1 2024-08-16T20:59:55.913Z,1723841995.913 [lineCaptureHoming:Lap:A] Stopped 2024-08-16T20:59:55.913Z,1723841995.913 [lineCaptureHoming:Lap:B] Running Loop=1 2024-08-16T20:59:56.266Z,1723841996.266 [lineCaptureHoming:Lap:B] Stopped 2024-08-16T20:59:56.266Z,1723841996.266 [lineCaptureHoming:Lap:C] Running Loop=1 2024-08-16T20:59:56.657Z,1723841996.657 [lineCaptureHoming:Lap:C](INFO): Mode 0.000000 count 2024-08-16T20:59:56.657Z,1723841996.657 [lineCaptureHoming:Lap:C] Stopped 2024-08-16T20:59:56.657Z,1723841996.657 [lineCaptureHoming:Lap:D] Running Loop=1 2024-08-16T20:59:56.682Z,1723841996.682 [lineCaptureHoming:Lap:D](DEBUG): Aggregate::initialize lineCaptureHoming:Lap:D 2024-08-16T20:59:57.065Z,1723841997.065 [lineCaptureHoming:restartDHCP] Running Loop=1 2024-08-16T20:59:57.065Z,1723841997.065 [lineCaptureHoming:restartDHCP](DEBUG): Aggregate::initialize lineCaptureHoming:restartDHCP 2024-08-16T20:59:57.065Z,1723841997.065 [lineCaptureHoming:restartDHCP:A] Running Loop=1 2024-08-16T20:59:57.065Z,1723841997.065 [lineCaptureHoming:restartDHCP:A](IMPORTANT): Restarting DHCP... 2024-08-16T20:59:57.065Z,1723841997.065 [lineCaptureHoming:restartDHCP:A] Stopped 2024-08-16T20:59:57.065Z,1723841997.065 [lineCaptureHoming:restartDHCP:B.Execute] Running Loop=1 2024-08-16T20:59:57.591Z,1723841997.591 [lineCaptureHoming:restartDHCP:B.Execute](INFO): Executing command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-08-16T20:59:57.591Z,1723841997.591 [lineCaptureHoming:restartDHCP:B.Execute] Stopped 2024-08-16T20:59:57.591Z,1723841997.591 [lineCaptureHoming:restartDHCP](INFO): Completed lineCaptureHoming:restartDHCP 2024-08-16T20:59:57.591Z,1723841997.591 [lineCaptureHoming:restartDHCP] Stopped 2024-08-16T20:59:57.591Z,1723841997.591 [lineCaptureHoming:restartDHCP](DEBUG): Aggregate::uninitialize lineCaptureHoming:restartDHCP 2024-08-16T20:59:57.628Z,1723841997.628 [CommandExec](IMPORTANT): got command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-08-16T20:59:57.912Z,1723841997.912 [lineCaptureHoming:Lap:D](INFO): Completed lineCaptureHoming:Lap:D 2024-08-16T20:59:57.912Z,1723841997.912 [lineCaptureHoming:Lap:D] Stopped 2024-08-16T20:59:57.912Z,1723841997.912 [lineCaptureHoming:Lap:D](DEBUG): Aggregate::uninitialize lineCaptureHoming:Lap:D 2024-08-16T20:59:57.912Z,1723841997.912 [lineCaptureHoming:Lap:Docked] Running Loop=1 2024-08-16T20:59:57.912Z,1723841997.912 [lineCaptureHoming:Lap:Docked](DEBUG): Aggregate::initialize lineCaptureHoming:Lap:Docked 2024-08-16T20:59:57.913Z,1723841997.913 [lineCaptureHoming:Lap:Docked:A] Running Loop=1 2024-08-16T20:59:58.335Z,1723841998.335 [lineCaptureHoming:Lap:Docked:A](INFO): Waiting on dock for 10.000000 min 2024-08-16T20:59:58.335Z,1723841998.335 [lineCaptureHoming:Lap:Docked:A] Stopped 2024-08-16T20:59:58.335Z,1723841998.335 [lineCaptureHoming:Lap:Docked:C] Running Loop=1 2024-08-16T20:59:58.339Z,1723841998.339 [CommandExec](IMPORTANT): /mnt/mmc/started/2024-08-16UTC205958_sh 2024-08-16T20:59:58.720Z,1723841998.720 [lineCaptureHoming:Lap:Docked:C] Stopped 2024-08-16T20:59:58.720Z,1723841998.720 [lineCaptureHoming:Lap:Docked:Docked.Docked] Running Loop=1 2024-08-16T20:59:58.720Z,1723841998.720 [lineCaptureHoming:Lap:Docked:Docked.Docked](DEBUG): Initialize. 2024-08-16T20:59:58.720Z,1723841998.720 [lineCaptureHoming:Lap:Docked:Docked.Docked](DEBUG): Initializing internal variables to default values. 2024-08-16T20:59:59.127Z,1723841999.127 [lineCaptureHoming:Lap:Docked:Docked.Docked](FAULT): Off dock, range to dock 16.000000 exceeded threshold. 2024-08-16T20:59:59.127Z,1723841999.127 [lineCaptureHoming:Lap:Docked:Docked.Docked](IMPORTANT): State switched to DETACHED. Waiting for state timeout to act... 2024-08-16T20:59:59.128Z,1723841999.128 [lineCaptureHoming:Lap:Docked:Docked.Docked](IMPORTANT): Slide state transition from 6 to 1 2024-08-16T20:59:59.129Z,1723841999.129 [lineCaptureHoming:Lap:Docked:Docked.Docked](INFO): No local IP found 2024-08-16T20:59:59.151Z,1723841999.151 [DockingStepper](INFO): Resume 2024-08-16T20:59:59.651Z,1723841999.651 [DockingStepper](INFO): Resuming 2024-08-16T20:59:59.659Z,1723841999.659 [DAT](IMPORTANT): Requesting device enable set for address 6. 2024-08-16T20:59:59.911Z,1723841999.911 [DAT](INFO): DAT read: user:191> 2024-08-16T20:59:59.911Z,1723841999.911 [DAT](INFO): DAT read: Tx time:20:59:58.9762 2024-08-16T20:59:59.912Z,1723841999.912 [DAT](INFO): Ping request sent. 2024-08-16T20:59:59.912Z,1723841999.912 [DAT](INFO): transmitted an acoustic signal 2024-08-16T20:59:59.912Z,1723841999.912 [DAT](INFO): publishing transmit ping time 2024-08-16T20:59:59.913Z,1723841999.913 [DAT](IMPORTANT): Requesting device enable set for address 6. 2024-08-16T20:59:59.995Z,1723841999.995 [DockingStepper](INFO): Start 2024-08-16T20:59:59.995Z,1723841999.995 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-08-16T21:00:00.111Z,1723842000.111 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-08-16T21:00:00.162Z,1723842000.162 [DAT](IMPORTANT): Requesting device enable set for address 6. 2024-08-16T21:00:00.341Z,1723842000.341 [DockingStepper](INFO): Starting 2024-08-16T21:00:00.802Z,1723842000.802 [DockingStepper](INFO): Starting 2024-08-16T21:00:01.131Z,1723842001.131 [DockingStepper](INFO): Starting 2024-08-16T21:00:01.178Z,1723842001.178 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50 2024-08-16T21:00:01.641Z,1723842001.641 [DockingStepper](INFO): Starting 2024-08-16T21:00:01.916Z,1723842001.916 [DockingStepper](INFO): Starting 2024-08-16T21:00:02.179Z,1723842002.179 [DAT](INFO): DAT read: Rx Time:21:00:00.9009 2024-08-16T21:00:02.179Z,1723842002.179 [DAT](INFO): Rx dataTimestamp_ set to:1723842002.178772 2024-08-16T21:00:02.347Z,1723842002.347 [DockingStepper](INFO): Starting 2024-08-16T21:00:02.766Z,1723842002.766 [DockingStepper](INFO): Starting 2024-08-16T21:00:02.798Z,1723842002.798 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-08-16T21:00:02.939Z,1723842002.939 [DAT](INFO): DAT read: 21:00:00.9009 LVL= 9984, 9137, 9330, 11091, AGC= 67, IDX= 175, 0.21, 2.714,-2.265,-2.538, 3.050, PHS=-0.248, 1.015, 0.692, RAW= 284.3, -24.4, CAL= 281.6, -35.2, ROT= 228.4, 35.2 2024-08-16T21:00:02.940Z,1723842002.940 [DAT](INFO): got valid direction response: 21:00:00.9009 LVL= 9984, 9137, 9330, 11091, AGC= 67, IDX= 175, 0.21, 2.714,-2.265,-2.538, 3.050, PHS=-0.248, 1.015, 0.692, RAW= 284.3, -24.4, CAL= 281.6, -35.2, ROT= 228.4, 35.2 2024-08-16T21:00:02.940Z,1723842002.940 [DAT](INFO): DAT read: OK 2024-08-16T21:00:02.940Z,1723842002.940 [DAT](INFO): unknown deviceResponse_: OK 2024-08-16T21:00:02.941Z,1723842002.941 [DAT](INFO): DAT read: user:192> 2024-08-16T21:00:02.942Z,1723842002.942 [DAT](INFO): DAT read: Tx time:21:00:01.9771 2024-08-16T21:00:02.942Z,1723842002.942 [DAT](INFO): Ping request sent. 2024-08-16T21:00:02.942Z,1723842002.942 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:00:02.942Z,1723842002.942 [DAT](INFO): #Rx 2: Read direction message, but no range. 2024-08-16T21:00:02.943Z,1723842002.943 [DAT](INFO): direction in FSK: [-0.542524,-0.611059,-0.576432] 2024-08-16T21:00:02.943Z,1723842002.943 [DAT](INFO): publishing transmit ping time 2024-08-16T21:00:02.947Z,1723842002.947 [DAT](INFO): publishing transmit ping time 2024-08-16T21:00:03.295Z,1723842003.295 [DockingStepper](INFO): Starting 2024-08-16T21:00:03.607Z,1723842003.607 [DockingStepper](INFO): Changing to mode: 1 2024-08-16T21:00:03.607Z,1723842003.607 [DockingStepper](INFO): Standby mode. 2024-08-16T21:00:04.836Z,1723842004.836 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:00:05.203Z,1723842005.203 [DAT](INFO): DAT read: Rx Time:21:00:03.9008 2024-08-16T21:00:05.204Z,1723842005.204 [DAT](INFO): Rx dataTimestamp_ set to:1723842005.203028 2024-08-16T21:00:05.965Z,1723842005.965 [DAT](INFO): DAT read: 21:00:03.9008 LVL= 12736, 14481, 14834, 22995, AGC= 66, IDX= 431,-0.10,-0.374, 1.058, 0.788, 0.131, PHS=-0.418, 0.974, 0.654, RAW= 282.7, -18.7, CAL= 284.4, -28.2, ROT= 225.6, 28.2 2024-08-16T21:00:05.966Z,1723842005.966 [DAT](INFO): got valid direction response: 21:00:03.9008 LVL= 12736, 14481, 14834, 22995, AGC= 66, IDX= 431,-0.10,-0.374, 1.058, 0.788, 0.131, PHS=-0.418, 0.974, 0.654, RAW= 282.7, -18.7, CAL= 284.4, -28.2, ROT= 225.6, 28.2 2024-08-16T21:00:05.967Z,1723842005.967 [DAT](INFO): DAT read: OK 2024-08-16T21:00:05.967Z,1723842005.967 [DAT](INFO): unknown deviceResponse_: OK 2024-08-16T21:00:05.967Z,1723842005.967 [DAT](INFO): DAT read: user:193> 2024-08-16T21:00:05.968Z,1723842005.968 [DAT](INFO): DAT read: Tx time:21:00:04.9771 2024-08-16T21:00:05.968Z,1723842005.968 [DAT](INFO): Ping request sent. 2024-08-16T21:00:05.968Z,1723842005.968 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:00:05.969Z,1723842005.969 [DAT](INFO): #Rx 3: Read direction message, but no range. 2024-08-16T21:00:05.970Z,1723842005.970 [DAT](INFO): direction in FSK: [-0.616616,-0.629667,-0.472551] 2024-08-16T21:00:05.971Z,1723842005.971 [DAT](INFO): publishing transmit ping time 2024-08-16T21:00:05.973Z,1723842005.973 [DAT](INFO): publishing transmit ping time 2024-08-16T21:00:08.227Z,1723842008.227 [DAT](INFO): DAT read: Rx Time:21:00:06.9008 2024-08-16T21:00:08.227Z,1723842008.227 [DAT](INFO): Rx dataTimestamp_ set to:1723842008.226847 2024-08-16T21:00:08.751Z,1723842008.751 [DAT](INFO): DAT read: 21:00:06.9008 LVL= 13200, 13121, 14514, 22227, AGC= 67, IDX= 430, 0.22, 2.312,-2.640,-2.944, 2.673, PHS=-0.273, 1.017, 0.664, RAW= 285.4, -23.3, CAL= 283.6, -33.9, ROT= 226.4, 33.9 2024-08-16T21:00:08.752Z,1723842008.752 [DAT](INFO): got valid direction response: 21:00:06.9008 LVL= 13200, 13121, 14514, 22227, AGC= 67, IDX= 430, 0.22, 2.312,-2.640,-2.944, 2.673, PHS=-0.273, 1.017, 0.664, RAW= 285.4, -23.3, CAL= 283.6, -33.9, ROT= 226.4, 33.9 2024-08-16T21:00:08.752Z,1723842008.752 [DAT](INFO): DAT read: OK 2024-08-16T21:00:08.752Z,1723842008.752 [DAT](INFO): unknown deviceResponse_: OK 2024-08-16T21:00:08.753Z,1723842008.753 [DAT](INFO): #Rx 4: Read direction message, but no range. 2024-08-16T21:00:08.758Z,1723842008.758 [DAT](INFO): direction in FSK: [-0.572393,-0.601071,-0.557745] 2024-08-16T21:00:14.538Z,1723842014.538 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:00:23.806Z,1723842023.806 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:00:29.401Z,1723842029.401 [lineCaptureHoming:Lap:Docked:Docked.Docked](IMPORTANT): VEHICLE DETACHED FROM DOCK. 2024-08-16T21:00:29.411Z,1723842029.411 [lineCaptureHoming:Lap:Docked:Docked.Docked] Stopped 2024-08-16T21:00:29.412Z,1723842029.412 [lineCaptureHoming:Lap:Docked:Detach] Running Loop=1 2024-08-16T21:00:29.412Z,1723842029.412 [lineCaptureHoming:Lap:Docked:Detach](DEBUG): Aggregate::initialize lineCaptureHoming:Lap:Docked:Detach 2024-08-16T21:00:29.413Z,1723842029.413 [lineCaptureHoming:Lap:Docked:Detach:A] Running Loop=1 2024-08-16T21:00:29.416Z,1723842029.416 [DAT](IMPORTANT): Requesting device enable clr for address 6. 2024-08-16T21:00:29.527Z,1723842029.527 [DockingStepper](INFO): Changing to mode: 2 2024-08-16T21:00:29.527Z,1723842029.527 [DockingStepper](INFO): Armed mode. 2024-08-16T21:00:29.602Z,1723842029.602 [DockingStepper](INFO): Commanding negative:54599 2024-08-16T21:00:29.666Z,1723842029.666 [DAT](INFO): DAT read: user:194> 2024-08-16T21:00:29.667Z,1723842029.667 [DAT](INFO): DAT read: Tx time:21:00:28.7267 2024-08-16T21:00:29.667Z,1723842029.667 [DAT](INFO): Ping request sent. 2024-08-16T21:00:29.668Z,1723842029.668 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:00:29.668Z,1723842029.668 [DAT](INFO): publishing transmit ping time 2024-08-16T21:00:29.668Z,1723842029.668 [DAT](IMPORTANT): Requesting device enable clr for address 6. 2024-08-16T21:00:29.918Z,1723842029.918 [DAT](IMPORTANT): Requesting device enable clr for address 6. 2024-08-16T21:00:29.979Z,1723842029.979 [lineCaptureHoming:Lap:Docked:Detach:A](IMPORTANT): Breaking off. Range: 16.000000 m . 2024-08-16T21:00:29.979Z,1723842029.979 [lineCaptureHoming:Lap:Docked:Detach:A] Stopped 2024-08-16T21:00:29.979Z,1723842029.979 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock] Running Loop=1 2024-08-16T21:00:29.979Z,1723842029.979 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](DEBUG): Initialize. 2024-08-16T21:00:29.979Z,1723842029.979 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](DEBUG): Initializing internal variables to default values. 2024-08-16T21:00:30.324Z,1723842030.324 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](DEBUG): Initialize. 2024-08-16T21:00:30.324Z,1723842030.324 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](DEBUG): Initializing internal variables to default values. 2024-08-16T21:00:30.325Z,1723842030.325 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](INFO): Detached from dock at depth 75.000000 m. 2024-08-16T21:00:30.703Z,1723842030.703 [DockingStepper](INFO): Changing to mode: 3 2024-08-16T21:00:30.703Z,1723842030.703 [DockingStepper](INFO): Detach mode. 2024-08-16T21:00:31.937Z,1723842031.937 [DAT](INFO): DAT read: Rx Time:21:00:30.6507 2024-08-16T21:00:31.937Z,1723842031.937 [DAT](INFO): Rx dataTimestamp_ set to:1723842031.936788 2024-08-16T21:00:32.440Z,1723842032.440 [DAT](INFO): DAT read: OK 2024-08-16T21:00:32.440Z,1723842032.440 [DAT](INFO): unknown deviceResponse_: OK 2024-08-16T21:00:32.441Z,1723842032.441 [DAT](INFO): DAT read: user:195> 2024-08-16T21:00:32.691Z,1723842032.691 [DAT](INFO): DAT read: Tx time:21:00:31.7267 2024-08-16T21:00:32.692Z,1723842032.692 [DAT](INFO): Ping request sent. 2024-08-16T21:00:32.692Z,1723842032.692 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:00:32.692Z,1723842032.692 [DAT](INFO): publishing transmit ping time 2024-08-16T21:00:34.959Z,1723842034.959 [DAT](INFO): DAT read: Rx Time:21:00:33.6506 2024-08-16T21:00:34.960Z,1723842034.960 [DAT](INFO): Rx dataTimestamp_ set to:1723842034.959613 2024-08-16T21:00:35.355Z,1723842035.355 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:00:35.465Z,1723842035.465 [DAT](INFO): DAT read: OK 2024-08-16T21:00:35.468Z,1723842035.468 [DAT](INFO): unknown deviceResponse_: OK 2024-08-16T21:00:35.470Z,1723842035.470 [DAT](INFO): DAT read: user:196> 2024-08-16T21:00:35.472Z,1723842035.472 [DAT](INFO): DAT read: Tx time:21:00:34.7267 2024-08-16T21:00:35.473Z,1723842035.473 [DAT](INFO): Ping request sent. 2024-08-16T21:00:35.473Z,1723842035.473 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:00:35.474Z,1723842035.474 [DAT](INFO): publishing transmit ping time 2024-08-16T21:00:35.788Z,1723842035.788 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](INFO): Detected possible detachment. Starting timer. 2024-08-16T21:00:42.999Z,1723842042.999 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](INFO): Detected possible reattachment, resetting timer. 2024-08-16T21:00:42.999Z,1723842042.999 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](INFO): Detected reattachement and haven't tried reverse, restarting undock sequence 2024-08-16T21:00:43.383Z,1723842043.383 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2024-08-16T21:00:44.558Z,1723842044.558 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:00:46.059Z,1723842046.059 [DAT](INFO): DAT read: Response Not Received 2024-08-16T21:00:46.059Z,1723842046.059 [DAT](INFO): response not received 2024-08-16T21:00:46.059Z,1723842046.059 [DAT](ERROR): No response from remote modem. 2024-08-16T21:00:53.567Z,1723842053.567 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](INFO): Thruster off. 2024-08-16T21:00:53.782Z,1723842053.782 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:00:55.866Z,1723842055.866 [Radio_Surface](INFO): Powering down 2024-08-16T21:01:19.348Z,1723842079.348 [lineCaptureHoming:TrackAC.](INFO): *** querying acoustic contact *** 2024-08-16T21:01:19.366Z,1723842079.366 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:01:19.367Z,1723842079.367 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:01:19.367Z,1723842079.367 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:01:19.619Z,1723842079.619 [DAT](INFO): DAT read: user:197> 2024-08-16T21:01:19.620Z,1723842079.620 [DAT](INFO): DAT read: Tx time:21:01:18.6784 2024-08-16T21:01:19.620Z,1723842079.620 [DAT](INFO): Ping request sent. 2024-08-16T21:01:19.620Z,1723842079.620 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:01:19.621Z,1723842079.621 [DAT](INFO): DAT read: TxSync time:21:01:18.6776 2024-08-16T21:01:19.621Z,1723842079.621 [DAT](INFO): publishing transmit ping time 2024-08-16T21:01:19.622Z,1723842079.622 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001498 2024-08-16T21:01:19.871Z,1723842079.871 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250899 2024-08-16T21:01:20.123Z,1723842080.123 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502508 2024-08-16T21:01:20.375Z,1723842080.375 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754708 2024-08-16T21:01:20.627Z,1723842080.627 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006633 2024-08-16T21:01:20.879Z,1723842080.879 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258533 2024-08-16T21:01:21.131Z,1723842081.131 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510729 2024-08-16T21:01:21.383Z,1723842081.383 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762564 2024-08-16T21:01:21.635Z,1723842081.635 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015008 2024-08-16T21:01:21.887Z,1723842081.887 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266459 2024-08-16T21:01:22.151Z,1723842082.151 [DAT](INFO): DAT read: Rx Time:21:01:20.7290 2024-08-16T21:01:22.151Z,1723842082.151 [DAT](INFO): Rx dataTimestamp_ set to:1723842082.150844 2024-08-16T21:01:22.152Z,1723842082.152 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.531563 2024-08-16T21:01:22.391Z,1723842082.391 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770594 2024-08-16T21:01:22.643Z,1723842082.643 [DAT](INFO): DAT read: Bearing 165, -2 (Remote) 2024-08-16T21:01:22.643Z,1723842082.643 [DAT](INFO): Remote Bearing received:Bearing 165, -2 (Remote) 2024-08-16T21:01:22.645Z,1723842082.645 [DAT](INFO): DAT read: Range 10 to 6 : 15.9 m (Round-trip 21.3 ms) speed 0.0 m/s 2024-08-16T21:01:22.645Z,1723842082.645 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2024-08-16T21:01:23.001Z,1723842083.001 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](INFO): Detected possible detachment. Starting timer. 2024-08-16T21:01:24.938Z,1723842084.938 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-08-16T21:01:24.938Z,1723842084.938 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:01:43.192Z,1723842103.192 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](INFO): Detached at range: 15.90 m. Transitioning docking module to standby. 2024-08-16T21:01:43.621Z,1723842103.621 [DockingStepper](INFO): Changing to mode: 1 2024-08-16T21:01:43.621Z,1723842103.621 [DockingStepper](INFO): Standby mode. 2024-08-16T21:01:43.682Z,1723842103.682 [DockingStepper](INFO): Commanding positive:54617 2024-08-16T21:01:49.661Z,1723842109.661 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](INFO): Docking module at standby. 2024-08-16T21:01:50.066Z,1723842110.066 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](IMPORTANT): Undocking sequence complete. 2024-08-16T21:01:50.066Z,1723842110.066 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock] Stopped 2024-08-16T21:01:50.067Z,1723842110.067 [lineCaptureHoming:Lap:Docked:Detach:C] Running Loop=1 2024-08-16T21:01:50.067Z,1723842110.067 [lineCaptureHoming:Lap:Docked:Detach:C](DEBUG): Aggregate::initialize lineCaptureHoming:Lap:Docked:Detach:C 2024-08-16T21:01:50.452Z,1723842110.452 [lineCaptureHoming:restartDHCP] Running Loop=1 2024-08-16T21:01:50.452Z,1723842110.452 [lineCaptureHoming:restartDHCP](DEBUG): Aggregate::initialize lineCaptureHoming:restartDHCP 2024-08-16T21:01:50.452Z,1723842110.452 [lineCaptureHoming:restartDHCP:A] Running Loop=1 2024-08-16T21:01:50.452Z,1723842110.452 [lineCaptureHoming:restartDHCP:A](IMPORTANT): Restarting DHCP... 2024-08-16T21:01:50.453Z,1723842110.453 [lineCaptureHoming:restartDHCP:A] Stopped 2024-08-16T21:01:50.453Z,1723842110.453 [lineCaptureHoming:restartDHCP:B.Execute] Running Loop=1 2024-08-16T21:01:50.462Z,1723842110.462 [DockingStepper](INFO): Pause 2024-08-16T21:01:50.863Z,1723842110.863 [lineCaptureHoming:restartDHCP:B.Execute](INFO): Executing command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-08-16T21:01:50.863Z,1723842110.863 [lineCaptureHoming:restartDHCP:B.Execute] Stopped 2024-08-16T21:01:50.863Z,1723842110.863 [lineCaptureHoming:restartDHCP](INFO): Completed lineCaptureHoming:restartDHCP 2024-08-16T21:01:50.863Z,1723842110.863 [lineCaptureHoming:restartDHCP] Stopped 2024-08-16T21:01:50.863Z,1723842110.863 [lineCaptureHoming:restartDHCP](DEBUG): Aggregate::uninitialize lineCaptureHoming:restartDHCP 2024-08-16T21:01:50.892Z,1723842110.892 [CommandExec](IMPORTANT): got command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-08-16T21:01:51.212Z,1723842111.212 [CommandExec](IMPORTANT): /mnt/mmc/started/2024-08-16UTC210151_sh 2024-08-16T21:01:51.301Z,1723842111.301 [lineCaptureHoming:Lap:Docked:Detach:C](INFO): Completed lineCaptureHoming:Lap:Docked:Detach:C 2024-08-16T21:01:51.301Z,1723842111.301 [lineCaptureHoming:Lap:Docked:Detach:C] Stopped 2024-08-16T21:01:51.301Z,1723842111.301 [lineCaptureHoming:Lap:Docked:Detach:C](DEBUG): Aggregate::uninitialize lineCaptureHoming:Lap:Docked:Detach:C 2024-08-16T21:01:51.326Z,1723842111.326 [lineCaptureHoming:Lap:Docked:Detach](INFO): Completed lineCaptureHoming:Lap:Docked:Detach 2024-08-16T21:01:51.326Z,1723842111.326 [lineCaptureHoming:Lap:Docked:Detach] Stopped 2024-08-16T21:01:51.326Z,1723842111.326 [lineCaptureHoming:Lap:Docked:Detach](DEBUG): Aggregate::uninitialize lineCaptureHoming:Lap:Docked:Detach 2024-08-16T21:01:51.326Z,1723842111.326 [lineCaptureHoming:Lap:Docked:Wpt1] Running Loop=1 2024-08-16T21:01:51.326Z,1723842111.326 [lineCaptureHoming:Lap:Docked:Wpt1](DEBUG): Aggregate::initialize lineCaptureHoming:Lap:Docked:Wpt1 2024-08-16T21:01:51.327Z,1723842111.327 [lineCaptureHoming:Lap:Docked:Wpt1:B.Pitch] Running Loop=1 2024-08-16T21:01:51.327Z,1723842111.327 [lineCaptureHoming:Lap:Docked:Wpt1:B.Pitch](DEBUG): Initialize. 2024-08-16T21:01:51.327Z,1723842111.327 [lineCaptureHoming:Lap:Docked:Wpt1:C.SetSpeed] Running Loop=1 2024-08-16T21:01:51.327Z,1723842111.327 [lineCaptureHoming:Lap:Docked:Wpt1:C.SetSpeed](DEBUG): Initialize. 2024-08-16T21:01:51.327Z,1723842111.327 [lineCaptureHoming:Lap:Docked:Wpt1:A] Running Loop=1 2024-08-16T21:01:51.716Z,1723842111.716 [lineCaptureHoming:Lap:Docked:Wpt1] Stopped 2024-08-16T21:01:51.716Z,1723842111.716 [lineCaptureHoming:Lap:Docked:Wpt1](DEBUG): Aggregate::uninitialize lineCaptureHoming:Lap:Docked:Wpt1 2024-08-16T21:01:51.716Z,1723842111.716 [lineCaptureHoming:Lap:Docked:Wpt1:A] Stopped 2024-08-16T21:01:51.716Z,1723842111.716 [lineCaptureHoming:Lap:Docked:Wpt1:B.Pitch] Stopped 2024-08-16T21:01:51.716Z,1723842111.716 [lineCaptureHoming:Lap:Docked:Wpt1:C.SetSpeed] Stopped 2024-08-16T21:01:51.716Z,1723842111.716 [lineCaptureHoming:Lap:Docked:Wpt1:C.SetSpeed](DEBUG): Uninitialize. 2024-08-16T21:01:51.717Z,1723842111.717 [lineCaptureHoming:Lap:Docked:Drift] Running Loop=1 2024-08-16T21:01:51.717Z,1723842111.717 [lineCaptureHoming:Lap:Docked:Drift](DEBUG): Aggregate::initialize lineCaptureHoming:Lap:Docked:Drift 2024-08-16T21:01:51.717Z,1723842111.717 [lineCaptureHoming:Lap:Docked:Drift:B.Buoyancy] Running Loop=1 2024-08-16T21:01:51.717Z,1723842111.717 [lineCaptureHoming:Lap:Docked:Drift:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2024-08-16T21:01:51.717Z,1723842111.717 [lineCaptureHoming:Lap:Docked:Drift:C.Pitch] Running Loop=1 2024-08-16T21:01:51.717Z,1723842111.717 [lineCaptureHoming:Lap:Docked:Drift:C.Pitch](DEBUG): Initialize. 2024-08-16T21:01:51.717Z,1723842111.717 [lineCaptureHoming:Lap:Docked:Drift:D.Point] Running Loop=1 2024-08-16T21:01:51.718Z,1723842111.718 [lineCaptureHoming:Lap:Docked:Drift:D.Point](DEBUG): Initialize. 2024-08-16T21:01:51.718Z,1723842111.718 [lineCaptureHoming:Lap:Docked:Drift:E.SetSpeed] Running Loop=1 2024-08-16T21:01:51.718Z,1723842111.718 [lineCaptureHoming:Lap:Docked:Drift:E.SetSpeed](DEBUG): Initialize. 2024-08-16T21:01:51.718Z,1723842111.718 [lineCaptureHoming:Lap:Docked:Drift:A] Running Loop=1 2024-08-16T21:01:52.069Z,1723842112.069 [lineCaptureHoming:Lap:Docked:Drift:E.SetSpeed] Running Loop=1 2024-08-16T21:01:52.070Z,1723842112.070 [lineCaptureHoming:Lap:Docked:Drift:D.Point] Running Loop=1 2024-08-16T21:01:52.070Z,1723842112.070 [lineCaptureHoming:Lap:Docked:Drift:C.Pitch] Running Loop=1 2024-08-16T21:01:52.071Z,1723842112.071 [lineCaptureHoming:Lap:Docked:Drift:B.Buoyancy] Running Loop=1 2024-08-16T21:01:52.072Z,1723842112.072 [lineCaptureHoming:Lap:Docked:Drift:A](IMPORTANT): No post-undock waypoint, drifting for 5.000000 min minutes 2024-08-16T21:01:52.072Z,1723842112.072 [lineCaptureHoming:Lap:Docked:Drift:A] Stopped 2024-08-16T21:01:52.072Z,1723842112.072 [lineCaptureHoming:Lap:Docked:Drift:F.Wait] Running Loop=1 2024-08-16T21:01:52.072Z,1723842112.072 [lineCaptureHoming:Lap:Docked:Drift:F.Wait](DEBUG): Initialize Wait Component. 2024-08-16T21:01:53.050Z,1723842113.050 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.271324 2024-08-16T21:01:53.312Z,1723842113.312 [lineCaptureHoming:MicromodemComms] Running Loop=1 2024-08-16T21:01:53.312Z,1723842113.312 [lineCaptureHoming:MicromodemComms](DEBUG): Aggregate::initialize lineCaptureHoming:MicromodemComms 2024-08-16T21:01:53.313Z,1723842113.313 [lineCaptureHoming:MicromodemComms:CheckIn] Running Loop=1 2024-08-16T21:01:53.313Z,1723842113.313 [lineCaptureHoming:MicromodemComms:CheckIn](DEBUG): Aggregate::initialize lineCaptureHoming:MicromodemComms:CheckIn 2024-08-16T21:01:53.313Z,1723842113.313 [lineCaptureHoming:MicromodemComms:CheckIn:A] Running Loop=1 2024-08-16T21:01:53.313Z,1723842113.313 [lineCaptureHoming:MicromodemComms] Running Loop=1 2024-08-16T21:01:53.314Z,1723842113.314 [lineCaptureHoming:MicromodemComms:CheckIn:A](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-16T21:01:54.054Z,1723842114.054 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:02:26.106Z,1723842146.106 [CommandExec](IMPORTANT): got command stop 2024-08-16T21:02:26.107Z,1723842146.107 [CommandExec](IMPORTANT): Scheduling is paused 2024-08-16T21:02:26.107Z,1723842146.107 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2024-08-16T21:02:26.443Z,1723842146.443 [MissionManager](INFO): MissionManager is completed. 2024-08-16T21:02:26.443Z,1723842146.443 [MissionManager](INFO): Uninitializing Mission lineCaptureHoming 2024-08-16T21:02:26.443Z,1723842146.443 [lineCaptureHoming] Stopped 2024-08-16T21:02:26.444Z,1723842146.444 [lineCaptureHoming](DEBUG): Aggregate::uninitialize lineCaptureHoming 2024-08-16T21:02:26.444Z,1723842146.444 [lineCaptureHoming:MicromodemComms] Stopped 2024-08-16T21:02:26.444Z,1723842146.444 [lineCaptureHoming:MicromodemComms](DEBUG): Aggregate::uninitialize lineCaptureHoming:MicromodemComms 2024-08-16T21:02:26.444Z,1723842146.444 [lineCaptureHoming:MicromodemComms:CheckIn] Stopped 2024-08-16T21:02:26.444Z,1723842146.444 [lineCaptureHoming:MicromodemComms:CheckIn](DEBUG): Aggregate::uninitialize lineCaptureHoming:MicromodemComms:CheckIn 2024-08-16T21:02:26.444Z,1723842146.444 [lineCaptureHoming:MicromodemComms:CheckIn:A] Stopped 2024-08-16T21:02:26.444Z,1723842146.444 [lineCaptureHoming:TrackAC.] Stopped 2024-08-16T21:02:26.444Z,1723842146.444 [lineCaptureHoming:TrackAC.](DEBUG): Uninitializing TrackAcousticContact. 2024-08-16T21:02:26.444Z,1723842146.444 [lineCaptureHoming:Lap] Stopped 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap](DEBUG): Aggregate::uninitialize lineCaptureHoming:Lap 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked] Stopped 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked](DEBUG): Aggregate::uninitialize lineCaptureHoming:Lap:Docked 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked:Drift] Stopped 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked:Drift](DEBUG): Aggregate::uninitialize lineCaptureHoming:Lap:Docked:Drift 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked:Drift:B.Buoyancy] Stopped 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked:Drift:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked:Drift:C.Pitch] Stopped 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked:Drift:D.Point] Stopped 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked:Drift:E.SetSpeed] Stopped 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked:Drift:E.SetSpeed](DEBUG): Uninitialize. 2024-08-16T21:02:26.445Z,1723842146.445 [lineCaptureHoming:Lap:Docked:Drift:F.Wait] Stopped 2024-08-16T21:02:26.446Z,1723842146.446 [lineCaptureHoming:Lap:Docked:Drift:F.Wait](DEBUG): Uninitialize Wait Component. 2024-08-16T21:02:26.836Z,1723842146.836 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2024-08-16T21:02:26.836Z,1723842146.836 [DefaultWithUndock] Running Loop=1 2024-08-16T21:02:26.836Z,1723842146.836 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2024-08-16T21:02:26.837Z,1723842146.837 [DefaultWithUndock:A.Wait] Running Loop=1 2024-08-16T21:02:26.837Z,1723842146.837 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-08-16T21:02:27.880Z,1723842147.880 [CommandExec](IMPORTANT): got command maintain clear 2024-08-16T21:02:28.036Z,1723842148.036 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-08-16T21:02:29.190Z,1723842149.190 [Radio_Surface](INFO): Powering up 2024-08-16T21:02:34.442Z,1723842154.442 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2024-08-16T21:02:34.443Z,1723842154.443 [Radio_Surface](INFO): No local IP found 2024-08-16T21:02:57.104Z,1723842177.104 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2024-08-16T21:02:57.104Z,1723842177.104 [DefaultWithUndock:A.Wait] Stopped 2024-08-16T21:02:57.104Z,1723842177.104 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-08-16T21:02:57.105Z,1723842177.105 [DefaultWithUndock:LeaveDock] Running Loop=1 2024-08-16T21:02:57.105Z,1723842177.105 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2024-08-16T21:02:57.105Z,1723842177.105 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2024-08-16T21:02:57.105Z,1723842177.105 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2024-08-16T21:02:57.105Z,1723842177.105 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2024-08-16T21:02:57.105Z,1723842177.105 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2024-08-16T21:02:57.105Z,1723842177.105 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-08-16T21:02:57.543Z,1723842177.543 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2024-08-16T21:02:57.543Z,1723842177.543 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-08-16T21:02:57.544Z,1723842177.544 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth -0.292438 m. 2024-08-16T21:02:57.703Z,1723842177.703 [DockingStepper](INFO): Resume 2024-08-16T21:02:57.911Z,1723842177.911 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-16T21:02:57.943Z,1723842177.943 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2024-08-16T21:02:58.091Z,1723842178.091 [DockingStepper](INFO): Resuming 2024-08-16T21:02:58.368Z,1723842178.368 [DockingStepper](INFO): Start 2024-08-16T21:02:58.368Z,1723842178.368 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-08-16T21:02:58.487Z,1723842178.487 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-08-16T21:02:58.768Z,1723842178.768 [DockingStepper](INFO): Starting 2024-08-16T21:02:59.191Z,1723842179.191 [DockingStepper](INFO): Starting 2024-08-16T21:02:59.607Z,1723842179.607 [DockingStepper](INFO): Starting 2024-08-16T21:02:59.655Z,1723842179.655 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50 2024-08-16T21:02:59.936Z,1723842179.936 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-16T21:02:59.983Z,1723842179.983 [DockingStepper](INFO): Starting 2024-08-16T21:03:00.058Z,1723842180.058 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:00.058Z,1723842180.058 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:00.059Z,1723842180.059 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:00.311Z,1723842180.311 [DAT](INFO): DAT read: user:198> 2024-08-16T21:03:00.313Z,1723842180.313 [DAT](INFO): DAT read: Tx time:21:02:59.3801 2024-08-16T21:03:00.314Z,1723842180.314 [DAT](INFO): Ping request sent. 2024-08-16T21:03:00.315Z,1723842180.315 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:00.315Z,1723842180.315 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:00.316Z,1723842180.316 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001721 2024-08-16T21:03:00.423Z,1723842180.423 [DockingStepper](INFO): Starting 2024-08-16T21:03:00.563Z,1723842180.563 [DAT](INFO): DAT read: TxSync time:21:02:59.3793 2024-08-16T21:03:00.564Z,1723842180.564 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249722 2024-08-16T21:03:00.815Z,1723842180.815 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.500479 2024-08-16T21:03:00.823Z,1723842180.823 [DockingStepper](INFO): Starting 2024-08-16T21:03:01.067Z,1723842181.067 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.752302 2024-08-16T21:03:01.199Z,1723842181.199 [DockingStepper](INFO): Starting 2024-08-16T21:03:01.222Z,1723842181.222 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-08-16T21:03:01.319Z,1723842181.319 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.004327 2024-08-16T21:03:01.571Z,1723842181.571 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.256435 2024-08-16T21:03:01.639Z,1723842181.639 [DockingStepper](INFO): Starting 2024-08-16T21:03:01.823Z,1723842181.823 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.508311 2024-08-16T21:03:02.019Z,1723842182.019 [DockingStepper](INFO): Changing to mode: 1 2024-08-16T21:03:02.019Z,1723842182.019 [DockingStepper](INFO): Standby mode. 2024-08-16T21:03:02.075Z,1723842182.075 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.760422 2024-08-16T21:03:02.327Z,1723842182.327 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012341 2024-08-16T21:03:02.579Z,1723842182.579 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.264295 2024-08-16T21:03:02.774Z,1723842182.774 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-16T21:03:02.831Z,1723842182.831 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.516694 2024-08-16T21:03:03.083Z,1723842183.083 [DAT](INFO): DAT read: Rx Time:21:03:01.8057 2024-08-16T21:03:03.083Z,1723842183.083 [DAT](INFO): Rx dataTimestamp_ set to:1723842183.082799 2024-08-16T21:03:03.084Z,1723842183.084 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769385 2024-08-16T21:03:03.335Z,1723842183.335 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.020286 2024-08-16T21:03:03.589Z,1723842183.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274035 2024-08-16T21:03:03.839Z,1723842183.839 [DAT](INFO): DAT read: Range 10 to 6 : 16.0 m (Round-trip 21.4 ms) speed 0.0 m/s 2024-08-16T21:03:03.840Z,1723842183.840 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2024-08-16T21:03:03.841Z,1723842183.841 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:03.841Z,1723842183.841 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:03.841Z,1723842183.841 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:04.091Z,1723842184.091 [DAT](INFO): DAT read: user:199> 2024-08-16T21:03:04.092Z,1723842184.092 [DAT](INFO): DAT read: Tx time:21:03:03.1801 2024-08-16T21:03:04.092Z,1723842184.092 [DAT](INFO): Ping request sent. 2024-08-16T21:03:04.092Z,1723842184.092 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:04.092Z,1723842184.092 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:04.093Z,1723842184.093 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000728 2024-08-16T21:03:04.343Z,1723842184.343 [DAT](INFO): DAT read: TxSync time:21:03:03.1793 2024-08-16T21:03:04.344Z,1723842184.344 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251708 2024-08-16T21:03:04.595Z,1723842184.595 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502562 2024-08-16T21:03:04.847Z,1723842184.847 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754894 2024-08-16T21:03:05.099Z,1723842185.099 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006564 2024-08-16T21:03:05.351Z,1723842185.351 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258580 2024-08-16T21:03:05.603Z,1723842185.603 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510593 2024-08-16T21:03:05.651Z,1723842185.651 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-16T21:03:05.855Z,1723842185.855 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763249 2024-08-16T21:03:06.107Z,1723842186.107 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014690 2024-08-16T21:03:06.358Z,1723842186.358 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266462 2024-08-16T21:03:06.611Z,1723842186.611 [DAT](INFO): DAT read: Rx Time:21:03:05.2306 2024-08-16T21:03:06.611Z,1723842186.611 [DAT](INFO): Rx dataTimestamp_ set to:1723842186.611033 2024-08-16T21:03:06.612Z,1723842186.612 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519830 2024-08-16T21:03:06.871Z,1723842186.871 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.779399 2024-08-16T21:03:07.129Z,1723842187.129 [DAT](INFO): DAT read: 21:03:05.2306 LVL= 9648, 9537, 8226, 9971, AGC= 67, IDX= 179,-0.09,-1.669, 0.238,-0.092,-0.938, PHS=-0.643, 1.223, 0.843, RAW= 281.1, -16.4, CAL= 284.6, -25.2, ROT= 225.4, 25.2 2024-08-16T21:03:07.130Z,1723842187.130 [DAT](INFO): got valid direction response: 21:03:05.2306 LVL= 9648, 9537, 8226, 9971, AGC= 67, IDX= 179,-0.09,-1.669, 0.238,-0.092,-0.938, PHS=-0.643, 1.223, 0.843, RAW= 281.1, -16.4, CAL= 284.6, -25.2, ROT= 225.4, 25.2 2024-08-16T21:03:07.131Z,1723842187.131 [DAT](INFO): DAT read: Bearing 156, -5 (Remote) 2024-08-16T21:03:07.131Z,1723842187.131 [DAT](INFO): Remote Bearing received:Bearing 156, -5 (Remote) 2024-08-16T21:03:07.132Z,1723842187.132 [DAT](INFO): DAT read: Bearing 225.4, 25.2 (Local) 2024-08-16T21:03:07.132Z,1723842187.132 [DAT](INFO): Local bearing/azimuth received: Bearing 225.4, 25.2 (Local) 2024-08-16T21:03:07.134Z,1723842187.134 [DAT](INFO): DAT read: Range 10 to 6 : 15.9 m (Round-trip 21.3 ms) speed 0.0 m/s 2024-08-16T21:03:07.135Z,1723842187.135 [DAT](INFO): #Rx 1: Read range and direction messages. 2024-08-16T21:03:07.136Z,1723842187.136 [DAT](INFO): direction in FSK: [-0.635327,-0.644260,-0.425779] 2024-08-16T21:03:07.136Z,1723842187.136 [DAT](INFO): publishing direction and range info 2024-08-16T21:03:07.139Z,1723842187.139 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:07.139Z,1723842187.139 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:07.139Z,1723842187.139 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:07.374Z,1723842187.374 [DAT](INFO): DAT read: user:200> 2024-08-16T21:03:07.375Z,1723842187.375 [DAT](INFO): DAT read: Tx time:21:03:06.4801 2024-08-16T21:03:07.376Z,1723842187.376 [DAT](INFO): Ping request sent. 2024-08-16T21:03:07.376Z,1723842187.376 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:07.376Z,1723842187.376 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:07.376Z,1723842187.376 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000724 2024-08-16T21:03:07.627Z,1723842187.627 [DAT](INFO): DAT read: TxSync time:21:03:06.4794 2024-08-16T21:03:07.628Z,1723842187.628 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251933 2024-08-16T21:03:07.879Z,1723842187.879 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502902 2024-08-16T21:03:08.131Z,1723842188.131 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755212 2024-08-16T21:03:08.383Z,1723842188.383 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006967 2024-08-16T21:03:08.426Z,1723842188.426 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-16T21:03:08.639Z,1723842188.639 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.262211 2024-08-16T21:03:08.887Z,1723842188.887 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510965 2024-08-16T21:03:09.139Z,1723842189.139 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762938 2024-08-16T21:03:09.391Z,1723842189.391 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014863 2024-08-16T21:03:09.643Z,1723842189.643 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266940 2024-08-16T21:03:09.895Z,1723842189.895 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518895 2024-08-16T21:03:10.147Z,1723842190.147 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771444 2024-08-16T21:03:10.399Z,1723842190.399 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023117 2024-08-16T21:03:10.651Z,1723842190.651 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274941 2024-08-16T21:03:10.904Z,1723842190.904 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.528560 2024-08-16T21:03:11.155Z,1723842191.155 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778891 2024-08-16T21:03:11.261Z,1723842191.261 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-16T21:03:11.407Z,1723842191.407 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031001 2024-08-16T21:03:11.659Z,1723842191.659 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282940 2024-08-16T21:03:11.911Z,1723842191.911 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534897 2024-08-16T21:03:12.163Z,1723842192.163 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786944 2024-08-16T21:03:12.415Z,1723842192.415 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038905 2024-08-16T21:03:12.667Z,1723842192.667 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290893 2024-08-16T21:03:12.919Z,1723842192.919 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543114 2024-08-16T21:03:13.170Z,1723842193.170 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794827 2024-08-16T21:03:13.423Z,1723842193.423 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046895 2024-08-16T21:03:13.675Z,1723842193.675 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.299007 2024-08-16T21:03:13.927Z,1723842193.927 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.551019 2024-08-16T21:03:14.086Z,1723842194.086 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-16T21:03:14.179Z,1723842194.179 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803089 2024-08-16T21:03:14.431Z,1723842194.431 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.055103 2024-08-16T21:03:14.683Z,1723842194.683 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306893 2024-08-16T21:03:14.935Z,1723842194.935 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559239 2024-08-16T21:03:15.187Z,1723842195.187 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810952 2024-08-16T21:03:15.441Z,1723842195.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.064477 2024-08-16T21:03:15.690Z,1723842195.690 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314810 2024-08-16T21:03:15.943Z,1723842195.943 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566881 2024-08-16T21:03:16.195Z,1723842196.195 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819329 2024-08-16T21:03:16.447Z,1723842196.447 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071174 2024-08-16T21:03:16.699Z,1723842196.699 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.323090 2024-08-16T21:03:16.944Z,1723842196.944 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-16T21:03:16.951Z,1723842196.951 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574924 2024-08-16T21:03:17.203Z,1723842197.203 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.827158 2024-08-16T21:03:17.456Z,1723842197.456 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:17.457Z,1723842197.457 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:17.457Z,1723842197.457 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:17.706Z,1723842197.706 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:17.707Z,1723842197.707 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:17.707Z,1723842197.707 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:17.959Z,1723842197.959 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:17.959Z,1723842197.959 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:17.959Z,1723842197.959 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:18.139Z,1723842198.139 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 15.90 m. Transitioning docking module to standby. 2024-08-16T21:03:18.211Z,1723842198.211 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:18.211Z,1723842198.211 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:18.211Z,1723842198.211 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:18.462Z,1723842198.462 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:18.462Z,1723842198.462 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:18.463Z,1723842198.463 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:18.548Z,1723842198.548 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2024-08-16T21:03:18.714Z,1723842198.714 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:18.715Z,1723842198.715 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:18.715Z,1723842198.715 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:18.933Z,1723842198.933 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2024-08-16T21:03:18.934Z,1723842198.934 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2024-08-16T21:03:18.934Z,1723842198.934 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2024-08-16T21:03:18.934Z,1723842198.934 [DefaultWithUndock:LeaveDock] Stopped 2024-08-16T21:03:18.935Z,1723842198.935 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2024-08-16T21:03:18.935Z,1723842198.935 [DefaultWithUndock:LeaveDock:A.] Stopped 2024-08-16T21:03:18.935Z,1723842198.935 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2024-08-16T21:03:18.935Z,1723842198.935 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2024-08-16T21:03:18.935Z,1723842198.935 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2024-08-16T21:03:18.935Z,1723842198.935 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2024-08-16T21:03:18.935Z,1723842198.935 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-16T21:03:18.936Z,1723842198.936 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-16T21:03:18.936Z,1723842198.936 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-16T21:03:18.936Z,1723842198.936 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-16T21:03:18.936Z,1723842198.936 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-16T21:03:18.937Z,1723842198.937 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-16T21:03:18.937Z,1723842198.937 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-16T21:03:18.966Z,1723842198.966 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:18.966Z,1723842198.966 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:18.967Z,1723842198.967 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:19.218Z,1723842199.218 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:19.218Z,1723842199.218 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:19.219Z,1723842199.219 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:19.341Z,1723842199.341 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2024-08-16T21:03:19.342Z,1723842199.342 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-08-16T21:03:19.342Z,1723842199.342 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-08-16T21:03:19.472Z,1723842199.472 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:03:19.472Z,1723842199.472 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:03:19.473Z,1723842199.473 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:03:19.494Z,1723842199.494 [DockingStepper](INFO): Pause 2024-08-16T21:03:19.723Z,1723842199.723 [DAT](INFO): DAT read: Response Not Received 2024-08-16T21:03:19.723Z,1723842199.723 [DAT](INFO): response not received 2024-08-16T21:03:19.723Z,1723842199.723 [DAT](INFO): DAT read: user:201> 2024-08-16T21:03:19.724Z,1723842199.724 [DAT](INFO): DAT read: Tx time:21:03:18.9303 2024-08-16T21:03:19.724Z,1723842199.724 [DAT](INFO): Ping request sent. 2024-08-16T21:03:19.724Z,1723842199.724 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:19.725Z,1723842199.725 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:19.725Z,1723842199.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000714 2024-08-16T21:03:19.975Z,1723842199.975 [DAT](INFO): DAT read: TxSync time:21:03:18.9295 2024-08-16T21:03:19.976Z,1723842199.976 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251138 2024-08-16T21:03:20.227Z,1723842200.227 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502042 2024-08-16T21:03:20.485Z,1723842200.485 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.760482 2024-08-16T21:03:20.731Z,1723842200.731 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006236 2024-08-16T21:03:20.946Z,1723842200.946 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-08-16T21:03:20.946Z,1723842200.946 [NAL9602] Data Fault, FailCount= 4 2024-08-16T21:03:20.946Z,1723842200.946 [NAL9602](ERROR): Data Fault 2024-08-16T21:03:20.983Z,1723842200.983 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258518 2024-08-16T21:03:21.042Z,1723842201.042 [CBIT](ERROR): Data Fault in component: NAL9602 2024-08-16T21:03:21.235Z,1723842201.235 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510083 2024-08-16T21:03:21.347Z,1723842201.347 [NAL9602](INFO): Powering down 2024-08-16T21:03:21.489Z,1723842201.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764106 2024-08-16T21:03:21.743Z,1723842201.743 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018096 2024-08-16T21:03:21.995Z,1723842201.995 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270099 2024-08-16T21:03:22.247Z,1723842202.247 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522164 2024-08-16T21:03:22.346Z,1723842202.346 [CBIT](INFO): Clearing failed state for component NAL9602 2024-08-16T21:03:22.346Z,1723842202.346 [NAL9602] No Fault, FailCount= 4 2024-08-16T21:03:22.498Z,1723842202.498 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774001 2024-08-16T21:03:22.751Z,1723842202.751 [DAT](INFO): DAT read: Rx Time:21:03:21.3560 2024-08-16T21:03:22.751Z,1723842202.751 [DAT](INFO): Rx dataTimestamp_ set to:1723842202.750813 2024-08-16T21:03:22.752Z,1723842202.752 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027136 2024-08-16T21:03:23.003Z,1723842203.003 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278320 2024-08-16T21:03:23.256Z,1723842203.256 [DAT](INFO): DAT read: Range 10 to 6 : 16.0 m (Round-trip 21.4 ms) speed 0.0 m/s 2024-08-16T21:03:23.256Z,1723842203.256 [DAT](INFO): DAT read: user:202> 2024-08-16T21:03:23.257Z,1723842203.257 [DAT](INFO): DAT read: Tx time:21:03:22.4304 2024-08-16T21:03:23.257Z,1723842203.257 [DAT](INFO): Ping request sent. 2024-08-16T21:03:23.257Z,1723842203.257 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:23.258Z,1723842203.258 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2024-08-16T21:03:23.258Z,1723842203.258 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:23.259Z,1723842203.259 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:23.507Z,1723842203.507 [DAT](INFO): DAT read: TxSync time:21:03:22.4296 2024-08-16T21:03:25.787Z,1723842205.787 [DAT](INFO): DAT read: Rx Time:21:03:24.4809 2024-08-16T21:03:25.788Z,1723842205.788 [DAT](INFO): Rx dataTimestamp_ set to:1723842205.787378 2024-08-16T21:03:26.549Z,1723842206.549 [DAT](INFO): DAT read: 21:03:24.4809 LVL= 10496, 9617, 8610, 10867, AGC= 67, IDX= 179,-0.16,-3.009,-1.399,-1.671,-2.390, PHS=-0.531, 1.038, 0.716, RAW= 281.2, -16.8, CAL= 284.4, -25.6, ROT= 225.6, 25.6 2024-08-16T21:03:26.551Z,1723842206.551 [DAT](INFO): got valid direction response: 21:03:24.4809 LVL= 10496, 9617, 8610, 10867, AGC= 67, IDX= 179,-0.16,-3.009,-1.399,-1.671,-2.390, PHS=-0.531, 1.038, 0.716, RAW= 281.2, -16.8, CAL= 284.4, -25.6, ROT= 225.6, 25.6 2024-08-16T21:03:26.551Z,1723842206.551 [DAT](INFO): DAT read: Bearing 156, -2 (Remote) 2024-08-16T21:03:26.552Z,1723842206.552 [DAT](INFO): Remote Bearing received:Bearing 156, -2 (Remote) 2024-08-16T21:03:26.553Z,1723842206.553 [DAT](INFO): DAT read: Bearing 225.6, 25.6 (Local) 2024-08-16T21:03:26.553Z,1723842206.553 [DAT](INFO): Local bearing/azimuth received: Bearing 225.6, 25.6 (Local) 2024-08-16T21:03:26.555Z,1723842206.555 [DAT](INFO): DAT read: Range 10 to 6 : 15.9 m (Round-trip 21.3 ms) speed 0.0 m/s 2024-08-16T21:03:26.555Z,1723842206.555 [DAT](INFO): DAT read: user:203> 2024-08-16T21:03:26.556Z,1723842206.556 [DAT](INFO): DAT read: Tx time:21:03:25.5804 2024-08-16T21:03:26.556Z,1723842206.556 [DAT](INFO): Ping request sent. 2024-08-16T21:03:26.556Z,1723842206.556 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:26.557Z,1723842206.557 [DAT](INFO): DAT read: TxSync time:21:03:25.5796 2024-08-16T21:03:26.558Z,1723842206.558 [DAT](INFO): #Rx 2: Read range and direction messages. 2024-08-16T21:03:26.559Z,1723842206.559 [DAT](INFO): direction in FSK: [-0.630979,-0.644335,-0.432086] 2024-08-16T21:03:26.560Z,1723842206.560 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:26.560Z,1723842206.560 [DAT](INFO): publishing direction and range info 2024-08-16T21:03:26.563Z,1723842206.563 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:26.563Z,1723842206.563 [DAT](INFO): publishing direction and range info 2024-08-16T21:03:29.315Z,1723842209.315 [DAT](INFO): DAT read: Rx Time:21:03:28.0060 2024-08-16T21:03:29.315Z,1723842209.315 [DAT](INFO): Rx dataTimestamp_ set to:1723842209.314772 2024-08-16T21:03:29.820Z,1723842209.820 [DAT](INFO): DAT read: Range 10 to 6 : 16.0 m (Round-trip 21.4 ms) speed 0.0 m/s 2024-08-16T21:03:29.820Z,1723842209.820 [DAT](INFO): DAT read: user:204> 2024-08-16T21:03:29.821Z,1723842209.821 [DAT](INFO): DAT read: Tx time:21:03:29.0805 2024-08-16T21:03:29.821Z,1723842209.821 [DAT](INFO): Ping request sent. 2024-08-16T21:03:29.821Z,1723842209.821 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:29.822Z,1723842209.822 [DAT](ERROR): #Rx 3: Read range message, but no direction. 2024-08-16T21:03:29.822Z,1723842209.822 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:29.822Z,1723842209.822 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:30.071Z,1723842210.071 [DAT](INFO): DAT read: TxSync time:21:03:29.0797 2024-08-16T21:03:32.843Z,1723842212.843 [DAT](INFO): DAT read: Rx Time:21:03:31.5061 2024-08-16T21:03:32.843Z,1723842212.843 [DAT](INFO): Rx dataTimestamp_ set to:1723842212.842850 2024-08-16T21:03:33.348Z,1723842213.348 [DAT](INFO): DAT read: Range 10 to 6 : 16.0 m (Round-trip 21.4 ms) speed 0.0 m/s 2024-08-16T21:03:33.348Z,1723842213.348 [DAT](INFO): DAT read: user:205> 2024-08-16T21:03:33.349Z,1723842213.349 [DAT](INFO): DAT read: Tx time:21:03:32.5805 2024-08-16T21:03:33.350Z,1723842213.350 [DAT](INFO): Ping request sent. 2024-08-16T21:03:33.350Z,1723842213.350 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:33.350Z,1723842213.350 [DAT](ERROR): #Rx 4: Read range message, but no direction. 2024-08-16T21:03:33.350Z,1723842213.350 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:33.351Z,1723842213.351 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:33.599Z,1723842213.599 [DAT](INFO): DAT read: TxSync time:21:03:32.5797 2024-08-16T21:03:36.138Z,1723842216.138 [DAT](INFO): DAT read: Rx Time:21:03:34.6310 2024-08-16T21:03:36.139Z,1723842216.139 [DAT](INFO): Rx dataTimestamp_ set to:1723842216.138502 2024-08-16T21:03:36.646Z,1723842216.646 [DAT](INFO): DAT read: 21:03:34.6310 LVL= 9568, 9553, 8610, 10611, AGC= 67, IDX= 179,-0.14, 0.402, 1.776, 1.519, 0.794, PHS=-0.304, 1.029, 0.722, RAW= 282.7, -22.9, CAL= 281.1, -33.4, ROT= 228.9, 33.4 2024-08-16T21:03:36.647Z,1723842216.647 [DAT](INFO): got valid direction response: 21:03:34.6310 LVL= 9568, 9553, 8610, 10611, AGC= 67, IDX= 179,-0.14, 0.402, 1.776, 1.519, 0.794, PHS=-0.304, 1.029, 0.722, RAW= 282.7, -22.9, CAL= 281.1, -33.4, ROT= 228.9, 33.4 2024-08-16T21:03:36.648Z,1723842216.648 [DAT](INFO): DAT read: Bearing 125, -24 (Remote) 2024-08-16T21:03:36.649Z,1723842216.649 [DAT](INFO): Remote Bearing received:Bearing 125, -24 (Remote) 2024-08-16T21:03:36.650Z,1723842216.650 [DAT](INFO): DAT read: Bearing 228.9, 33.4 (Local) 2024-08-16T21:03:36.650Z,1723842216.650 [DAT](INFO): Local bearing/azimuth received: Bearing 228.9, 33.4 (Local) 2024-08-16T21:03:36.651Z,1723842216.651 [DAT](INFO): DAT read: Range 10 to 6 : 15.9 m (Round-trip 21.3 ms) speed 0.0 m/s 2024-08-16T21:03:36.652Z,1723842216.652 [DAT](INFO): DAT read: user:206> 2024-08-16T21:03:36.653Z,1723842216.653 [DAT](INFO): DAT read: Tx time:21:03:35.7305 2024-08-16T21:03:36.653Z,1723842216.653 [DAT](INFO): Ping request sent. 2024-08-16T21:03:36.653Z,1723842216.653 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:36.653Z,1723842216.653 [DAT](INFO): #Rx 5: Read range and direction messages. 2024-08-16T21:03:36.655Z,1723842216.655 [DAT](INFO): direction in FSK: [-0.548808,-0.629111,-0.550481] 2024-08-16T21:03:36.655Z,1723842216.655 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:36.655Z,1723842216.655 [DAT](INFO): publishing direction and range info 2024-08-16T21:03:36.659Z,1723842216.659 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:36.659Z,1723842216.659 [DAT](INFO): publishing direction and range info 2024-08-16T21:03:36.891Z,1723842216.891 [DAT](INFO): DAT read: TxSync time:21:03:35.7297 2024-08-16T21:03:39.439Z,1723842219.439 [DAT](INFO): DAT read: Rx Time:21:03:38.1562 2024-08-16T21:03:39.440Z,1723842219.440 [DAT](INFO): Rx dataTimestamp_ set to:1723842219.439347 2024-08-16T21:03:40.196Z,1723842220.196 [DAT](INFO): DAT read: 21:03:38.1562 LVL= 10208, 8689, 7570, 10595, AGC= 66, IDX= 179, 0.09,-2.789,-1.457,-1.839,-2.533, PHS=-0.168, 1.123, 0.690, RAW= 289.2, -27.1, CAL= 284.5, -38.8, ROT= 225.5, 38.8 2024-08-16T21:03:40.198Z,1723842220.198 [DAT](INFO): got valid direction response: 21:03:38.1562 LVL= 10208, 8689, 7570, 10595, AGC= 66, IDX= 179, 0.09,-2.789,-1.457,-1.839,-2.533, PHS=-0.168, 1.123, 0.690, RAW= 289.2, -27.1, CAL= 284.5, -38.8, ROT= 225.5, 38.8 2024-08-16T21:03:40.199Z,1723842220.199 [DAT](INFO): DAT read: Bearing 225.5, 38.8 (Local) 2024-08-16T21:03:40.199Z,1723842220.199 [DAT](INFO): Local bearing/azimuth received: Bearing 225.5, 38.8 (Local) 2024-08-16T21:03:40.200Z,1723842220.200 [DAT](INFO): DAT read: Range 10 to 6 : 16.0 m (Round-trip 21.4 ms) speed 0.0 m/s 2024-08-16T21:03:40.201Z,1723842220.201 [DAT](INFO): DAT read: user:207> 2024-08-16T21:03:40.202Z,1723842220.202 [DAT](INFO): DAT read: Tx time:21:03:39.2806 2024-08-16T21:03:40.202Z,1723842220.202 [DAT](INFO): Ping request sent. 2024-08-16T21:03:40.202Z,1723842220.202 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:40.202Z,1723842220.202 [DAT](INFO): #Rx 6: Read range and direction messages. 2024-08-16T21:03:40.203Z,1723842220.203 [DAT](INFO): direction in FSK: [-0.546245,-0.555863,-0.626604] 2024-08-16T21:03:40.204Z,1723842220.204 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:40.204Z,1723842220.204 [DAT](INFO): publishing direction and range info 2024-08-16T21:03:40.207Z,1723842220.207 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:40.207Z,1723842220.207 [DAT](INFO): publishing direction and range info 2024-08-16T21:03:40.443Z,1723842220.443 [DAT](INFO): DAT read: TxSync time:21:03:39.2798 2024-08-16T21:03:42.711Z,1723842222.711 [DAT](INFO): DAT read: Rx Time:21:03:41.3311 2024-08-16T21:03:42.711Z,1723842222.711 [DAT](INFO): Rx dataTimestamp_ set to:1723842222.710807 2024-08-16T21:03:43.221Z,1723842223.221 [DAT](INFO): DAT read: 21:03:41.3311 LVL= 10224, 9905, 8594, 11171, AGC= 67, IDX= 179,-0.37, 0.807, 2.477, 2.162, 1.429, PHS=-0.534, 1.095, 0.730, RAW= 282.3, -17.1, CAL= 285.3, -26.0, ROT= 224.7, 26.0 2024-08-16T21:03:43.222Z,1723842223.222 [DAT](INFO): got valid direction response: 21:03:41.3311 LVL= 10224, 9905, 8594, 11171, AGC= 67, IDX= 179,-0.37, 0.807, 2.477, 2.162, 1.429, PHS=-0.534, 1.095, 0.730, RAW= 282.3, -17.1, CAL= 285.3, -26.0, ROT= 224.7, 26.0 2024-08-16T21:03:43.223Z,1723842223.223 [DAT](INFO): DAT read: Bearing 155, -7 (Remote) 2024-08-16T21:03:43.223Z,1723842223.223 [DAT](INFO): Remote Bearing received:Bearing 155, -7 (Remote) 2024-08-16T21:03:43.224Z,1723842223.224 [DAT](INFO): DAT read: Bearing 224.7, 26.0 (Local) 2024-08-16T21:03:43.224Z,1723842223.224 [DAT](INFO): Local bearing/azimuth received: Bearing 224.7, 26.0 (Local) 2024-08-16T21:03:43.226Z,1723842223.226 [DAT](INFO): DAT read: Range 10 to 6 : 15.9 m (Round-trip 21.3 ms) speed 0.0 m/s 2024-08-16T21:03:43.227Z,1723842223.227 [DAT](INFO): DAT read: user:208> 2024-08-16T21:03:43.227Z,1723842223.227 [DAT](INFO): DAT read: Tx time:21:03:42.4306 2024-08-16T21:03:43.228Z,1723842223.228 [DAT](INFO): Ping request sent. 2024-08-16T21:03:43.228Z,1723842223.228 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:43.228Z,1723842223.228 [DAT](INFO): #Rx 7: Read range and direction messages. 2024-08-16T21:03:43.229Z,1723842223.229 [DAT](INFO): direction in FSK: [-0.638862,-0.632207,-0.438371] 2024-08-16T21:03:43.229Z,1723842223.229 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:43.229Z,1723842223.229 [DAT](INFO): publishing direction and range info 2024-08-16T21:03:43.236Z,1723842223.236 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:43.236Z,1723842223.236 [DAT](INFO): publishing direction and range info 2024-08-16T21:03:43.469Z,1723842223.469 [DAT](INFO): DAT read: TxSync time:21:03:42.4298 2024-08-16T21:03:51.651Z,1723842231.651 [NAL9602](INFO): Powering up NAL9602 2024-08-16T21:03:55.579Z,1723842235.579 [DAT](INFO): DAT read: Response Not Received 2024-08-16T21:03:55.579Z,1723842235.579 [DAT](INFO): response not received 2024-08-16T21:03:55.579Z,1723842235.579 [DAT](INFO): DAT read: user:209> 2024-08-16T21:03:55.580Z,1723842235.580 [DAT](ERROR): No response from remote modem. 2024-08-16T21:03:55.831Z,1723842235.831 [DAT](INFO): DAT read: Tx time:21:03:54.8808 2024-08-16T21:03:55.831Z,1723842235.831 [DAT](INFO): Ping request sent. 2024-08-16T21:03:55.831Z,1723842235.831 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:03:55.832Z,1723842235.832 [DAT](INFO): DAT read: TxSync time:21:03:54.8800 2024-08-16T21:03:55.832Z,1723842235.832 [DAT](INFO): publishing transmit ping time 2024-08-16T21:03:58.355Z,1723842238.355 [DAT](INFO): DAT read: Rx Time:21:03:56.9314 2024-08-16T21:03:58.355Z,1723842238.355 [DAT](INFO): Rx dataTimestamp_ set to:1723842238.354987 2024-08-16T21:03:58.866Z,1723842238.866 [DAT](INFO): DAT read: 21:03:56.9314 LVL= 13936, 16193, 16226, 23347, AGC= 67, IDX= 403, 0.18, 1.470, 3.057, 2.620, 1.911, PHS=-0.352, 1.193, 0.706, RAW= 288.0, -21.8, CAL= 287.5, -32.0, ROT= 222.5, 32.0 2024-08-16T21:03:58.867Z,1723842238.867 [DAT](INFO): got valid direction response: 21:03:56.9314 LVL= 13936, 16193, 16226, 23347, AGC= 67, IDX= 403, 0.18, 1.470, 3.057, 2.620, 1.911, PHS=-0.352, 1.193, 0.706, RAW= 288.0, -21.8, CAL= 287.5, -32.0, ROT= 222.5, 32.0 2024-08-16T21:03:58.868Z,1723842238.868 [DAT](INFO): DAT read: Bearing 125, -25 (Remote) 2024-08-16T21:03:58.868Z,1723842238.868 [DAT](INFO): Remote Bearing received:Bearing 125, -25 (Remote) 2024-08-16T21:03:58.869Z,1723842238.869 [DAT](INFO): DAT read: Bearing 222.5, 32.0 (Local) 2024-08-16T21:03:58.869Z,1723842238.869 [DAT](INFO): Local bearing/azimuth received: Bearing 222.5, 32.0 (Local) 2024-08-16T21:03:58.871Z,1723842238.871 [DAT](INFO): DAT read: Range 10 to 6 : 16.0 m (Round-trip 21.4 ms) speed 0.0 m/s 2024-08-16T21:03:58.871Z,1723842238.871 [DAT](INFO): #Rx 8: Read range and direction messages. 2024-08-16T21:03:58.872Z,1723842238.872 [DAT](INFO): direction in FSK: [-0.625247,-0.572933,-0.529919] 2024-08-16T21:03:58.872Z,1723842238.872 [DAT](INFO): publishing direction and range info 2024-08-16T21:04:02.563Z,1723842242.563 [NAL9602](INFO): NAL9602 initialized 2024-08-16T21:05:01.091Z,1723842301.091 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003710 2024-08-16T21:05:02.090Z,1723842302.090 [DataOverHttps](INFO): Radio surface powered ON. 2024-08-16T21:05:02.090Z,1723842302.090 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:05:13.594Z,1723842313.594 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:05:25.078Z,1723842325.078 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:05:37.460Z,1723842337.460 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:05:46.138Z,1723842346.138 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:05:54.878Z,1723842354.878 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:06:03.650Z,1723842363.650 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:06:12.254Z,1723842372.254 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:06:21.058Z,1723842381.058 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:06:29.710Z,1723842389.710 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:06:38.214Z,1723842398.214 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:06:46.774Z,1723842406.774 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:06:55.418Z,1723842415.418 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:07:04.030Z,1723842424.030 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:07:12.574Z,1723842432.574 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:07:21.214Z,1723842441.214 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:07:32.466Z,1723842452.466 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:07:41.966Z,1723842461.966 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:07:47.141Z,1723842467.141 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/lineCaptureHoming.tl 2024-08-16T21:07:47.146Z,1723842467.146 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/lineCaptureHoming.tl 2024-08-16T21:07:47.146Z,1723842467.146 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/lineCaptureHoming.tl 2024-08-16T21:07:47.154Z,1723842467.154 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/lineCaptureHoming.tl 2024-08-16T21:07:50.949Z,1723842470.949 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/lineCaptureHoming.tl translated into: 4 240 5 1 5 2 5 5 2 2 3 1 60 2.5 2 5 1 1 40 50.0 5 NaN NaN NaN 5 205 2 20 0.05 NaN NaN NaN 60 -1 1 1 0 0 No longer in Terminal. Resuming normal ranging with pings. 5 Entering USBL mode with pings at (mode ). 5 Resuming normal ranging with pings (mode ). 5 Entering Midcourse tracking update period sec at (mode ). 5 0 Entering Terminal tracking update period sec at (mode ). Restarting DHCP... ! /mnt/mmc/LRAUV/Tools/dhcp.sh Restarting logs restart logs Timed out trying to reach the target depth. Stopping mission at current depth of stop -99 0 Mode 0 Waiting on dock for Breaking off. Range: . Driving to post-undock waypoint , No post-undock waypoint, drifting for minutes 0 0 0 2024-08-16T21:07:50.981Z,1723842470.981 [MissionManager](INFO): DefineArg lineCaptureHoming.MissionTimeout = 4.000000 h 2024-08-16T21:07:50.988Z,1723842470.988 [MissionManager](INFO): DefineArg lineCaptureHoming.NeedCommsTime = 240.000000 min 2024-08-16T21:07:50.994Z,1723842470.994 [MissionManager](INFO): DefineArg lineCaptureHoming.MicromodemCommsInterval = 5.000000 min 2024-08-16T21:07:50.996Z,1723842470.996 [MissionManager](INFO): DefineArg lineCaptureHoming.Repeat = 1.000000 count 2024-08-16T21:07:51.046Z,1723842471.046 [MissionManager](INFO): DefineArg lineCaptureHoming.WaitOnDockTimeout = 5.000000 min 2024-08-16T21:07:51.048Z,1723842471.048 [MissionManager](INFO): DefineArg lineCaptureHoming.StayOnDock = 0 bool 2024-08-16T21:07:51.054Z,1723842471.054 [MissionManager](INFO): DefineArg lineCaptureHoming.TrySlide = 0 bool 2024-08-16T21:07:51.056Z,1723842471.056 [MissionManager](INFO): DefineArg lineCaptureHoming.SlideRetries = 2.000000 count 2024-08-16T21:07:51.059Z,1723842471.059 [MissionManager](INFO): DefineArg lineCaptureHoming.SlideSinkDuration = 5.000000 min 2024-08-16T21:07:51.061Z,1723842471.061 [MissionManager](INFO): DefineArg lineCaptureHoming.SlideCloseDuration = 5.000000 min 2024-08-16T21:07:51.080Z,1723842471.080 [MissionManager](INFO): DefineArg lineCaptureHoming.SlideDriveDuration = 2.000000 min 2024-08-16T21:07:51.094Z,1723842471.094 [MissionManager](INFO): DefineArg lineCaptureHoming.TryWiggle = 0 bool 2024-08-16T21:07:51.097Z,1723842471.097 [MissionManager](INFO): DefineArg lineCaptureHoming.SlideWiggleCount = 2.000000 count 2024-08-16T21:07:51.099Z,1723842471.099 [MissionManager](INFO): DefineArg lineCaptureHoming.TryJog = 0 bool 2024-08-16T21:07:51.101Z,1723842471.101 [MissionManager](INFO): DefineArg lineCaptureHoming.JogLength = 3.000000 s 2024-08-16T21:07:51.127Z,1723842471.127 [MissionManager](INFO): DefineArg lineCaptureHoming.TryWhirl = 0 bool 2024-08-16T21:07:51.142Z,1723842471.142 [MissionManager](INFO): DefineArg lineCaptureHoming.WhirlSpeed = 1.000000 m/s 2024-08-16T21:07:51.144Z,1723842471.144 [MissionManager](INFO): DefineArg lineCaptureHoming.TransponderCode = 6 count 2024-08-16T21:07:51.159Z,1723842471.159 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingUpdatePeriodMidcourse = 60.000000 s 2024-08-16T21:07:51.161Z,1723842471.161 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingUpdatePeriodTerminal = 2.500000 s 2024-08-16T21:07:51.172Z,1723842471.172 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingUpdatePeriodDocked = 2.000000 min 2024-08-16T21:07:51.190Z,1723842471.190 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingNumFixesLowPassFilter = 5.000000 count 2024-08-16T21:07:51.192Z,1723842471.192 [MissionManager](INFO): DefineArg lineCaptureHoming.NumberOfPings = 1.000000 count 2024-08-16T21:07:51.223Z,1723842471.223 [MissionManager](INFO): DefineArg lineCaptureHoming.NumPingsUSBL = 1.000000 count 2024-08-16T21:07:51.225Z,1723842471.225 [MissionManager](INFO): DefineArg lineCaptureHoming.RangeUSBL = 40.000000 m 2024-08-16T21:07:51.246Z,1723842471.246 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:07:51.251Z,1723842471.251 [MissionManager](INFO): DefineArg lineCaptureHoming.LightRange = 50.000000 m 2024-08-16T21:07:51.253Z,1723842471.253 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingNumFixesLowPassFilterUSBL = 5.000000 count 2024-08-16T21:07:51.268Z,1723842471.268 [MissionManager](INFO): DefineArg lineCaptureHoming.DockingAltitude = nan m 2024-08-16T21:07:51.290Z,1723842471.290 [MissionManager](INFO): DefineArg lineCaptureHoming.InvalidAltitudeTimeout = nan min 2024-08-16T21:07:51.292Z,1723842471.292 [MissionManager](INFO): DefineArg lineCaptureHoming.AltitudeDepthLimit = nan m 2024-08-16T21:07:51.310Z,1723842471.310 [MissionManager](INFO): DefineArg lineCaptureHoming.MinAltitude = 5.000000 m 2024-08-16T21:07:51.313Z,1723842471.313 [MissionManager](INFO): DefineArg lineCaptureHoming.MaxDepth = 205.000000 m 2024-08-16T21:07:51.321Z,1723842471.321 [MissionManager](INFO): DefineArg lineCaptureHoming.MinOffshore = 2.000000 km 2024-08-16T21:07:51.363Z,1723842471.363 [MissionManager](INFO): DefineArg lineCaptureHoming.ApproachDepthTimeout = 20.000000 min 2024-08-16T21:07:51.365Z,1723842471.365 [MissionManager](INFO): DefineArg lineCaptureHoming.ApproachDepthRate = 0.050000 m/s 2024-08-16T21:07:51.376Z,1723842471.376 [MissionManager](INFO): DefineArg lineCaptureHoming.Lat1 = nan arcdeg 2024-08-16T21:07:51.386Z,1723842471.386 [MissionManager](INFO): DefineArg lineCaptureHoming.Lon1 = nan arcdeg 2024-08-16T21:07:51.389Z,1723842471.389 [MissionManager](INFO): DefineArg lineCaptureHoming.CaptureRadius = nan m 2024-08-16T21:07:51.404Z,1723842471.404 [MissionManager](INFO): DefineArg lineCaptureHoming.MidcourseDepth = 50.000000 m 2024-08-16T21:07:51.415Z,1723842471.415 [MissionManager](INFO): DefineArg lineCaptureHoming.MidcourseSpeed = 0.800000 m/s 2024-08-16T21:07:51.426Z,1723842471.426 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingPeriod = 60.000000 s 2024-08-16T21:07:51.438Z,1723842471.438 [MissionManager](INFO): DefineOutput lineCaptureHoming.Mode = -1.000000 count 2024-08-16T21:07:51.473Z,1723842471.473 [MissionManager](INFO): DefineOutput lineCaptureHoming.NumPings = 1.000000 count 2024-08-16T21:07:51.497Z,1723842471.497 [MissionManager](INFO): DefineOutput lineCaptureHoming.LowPassFilterWinSize = 1.000000 count 2024-08-16T21:07:51.516Z,1723842471.516 [MissionManager](INFO): DefineOutput lineCaptureHoming.LowPassFilterWinOverlap = 1 bool 2024-08-16T21:07:51.516Z,1723842471.516 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2024-08-16T21:07:51.516Z,1723842471.516 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2024-08-16T21:07:51.527Z,1723842471.527 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2024-08-16T21:07:52.286Z,1723842472.286 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2024-08-16T21:07:52.307Z,1723842472.307 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.DiveInterval = 1.000000 h 2024-08-16T21:07:52.309Z,1723842472.309 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.WaitForPitchUp = 10.000000 min 2024-08-16T21:07:52.316Z,1723842472.316 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.SurfacePitch = 20.000000 arcdeg 2024-08-16T21:07:52.326Z,1723842472.326 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.SurfaceDepthRate = nan m/s 2024-08-16T21:07:52.328Z,1723842472.328 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.SurfaceSpeed = 1.000000 m/s 2024-08-16T21:07:52.339Z,1723842472.339 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.SurfacingTimeout = 1000.000000 s 2024-08-16T21:07:52.341Z,1723842472.341 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.GPSTimeout = 7.000000 min 2024-08-16T21:07:52.380Z,1723842472.380 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.CommsTimeout = 30.000000 min 2024-08-16T21:07:52.465Z,1723842472.465 [lineCaptureHoming:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-16T21:07:52.575Z,1723842472.575 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/MicromodemComms.tl 2024-08-16T21:07:52.575Z,1723842472.575 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/MicromodemComms.tl 2024-08-16T21:07:52.577Z,1723842472.577 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/MicromodemComms.tl 2024-08-16T21:07:52.738Z,1723842472.738 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/MicromodemComms.tl translated into: 10 5 2024-08-16T21:07:52.748Z,1723842472.748 [MissionManager](INFO): DefineArg lineCaptureHoming:MicromodemComms.Timeout = 10.000000 min 2024-08-16T21:07:52.751Z,1723842472.751 [MissionManager](INFO): DefineArg lineCaptureHoming:MicromodemComms.CommsInterval = 5.000000 min 2024-08-16T21:07:52.774Z,1723842472.774 [MissionManager](INFO): RedefineArg lineCaptureHoming:MicromodemComms.CommsInterval = reader:lineCaptureHoming.MicromodemCommsInterval 2024-08-16T21:07:52.777Z,1723842472.777 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/LineCapture.tl 2024-08-16T21:07:52.777Z,1723842472.777 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/LineCapture.tl 2024-08-16T21:07:52.779Z,1723842472.779 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/LineCapture.tl 2024-08-16T21:07:55.684Z,1723842475.684 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/LineCapture.tl translated into: 30 0.0 1 0.01 0.001 2 8 1 NaN NaN NaN 0.0 0 NaN -1 0 1 2 3 1 0 Timed out while trying to detach from dock at current depth of . Transitioning to midcourse guidance. Navigating to dock waypoint ( , ) Transitioning to terminal guidance at range Terminating homing sequence: timed out while navigating to the dock waypoint. Executing search pattern. Timed out while executing search pattern around dock waypoint. Homing sequence timed out prior to docking. Terminal guidance. Transitioning to midcourse guidance. Received last range of ago. Docked. Homing sequence complete. 3 GuidanceMode 2024-08-16T21:07:55.734Z,1723842475.734 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.DockLat = 36.903300 arcdeg 2024-08-16T21:07:55.737Z,1723842475.737 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.DockLon = -122.119000 arcdeg 2024-08-16T21:07:55.770Z,1723842475.770 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.DetachTimeout = 30.000000 min 2024-08-16T21:07:55.772Z,1723842475.772 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MidcourseDepth = 50.000000 m 2024-08-16T21:07:55.823Z,1723842475.823 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MidcourseSpeed = 0.800000 m/s 2024-08-16T21:07:55.846Z,1723842475.846 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MidcourseTimeout = 6.000000 h 2024-08-16T21:07:55.849Z,1723842475.849 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.TerminalRange = 650.000000 m 2024-08-16T21:07:55.864Z,1723842475.864 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.AcousticTimeout = 5.000000 min 2024-08-16T21:07:55.890Z,1723842475.890 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.losRateIIRFilterDecay = 0.000000 n/a 2024-08-16T21:07:55.892Z,1723842475.892 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.SearchTimeout = 5.000000 min 2024-08-16T21:07:55.915Z,1723842475.915 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.CircleRadius = 1000.000000 m 2024-08-16T21:07:55.917Z,1723842475.917 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.CircleMaxError = 1.000000 m 2024-08-16T21:07:55.943Z,1723842475.943 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.CircleTurnToPort = 0 bool 2024-08-16T21:07:55.945Z,1723842475.945 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.DepthDeadband = 0.010000 m 2024-08-16T21:07:55.952Z,1723842475.952 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.RudderDeadband = 0.001000 arcdeg 2024-08-16T21:07:55.970Z,1723842475.970 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MinAltitude = 2.000000 m 2024-08-16T21:07:55.972Z,1723842475.972 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MaxDepth = 8.000000 m 2024-08-16T21:07:55.980Z,1723842475.980 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MinOffshore = 1.000000 m 2024-08-16T21:07:56.015Z,1723842476.015 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.DockingAltitude = nan m 2024-08-16T21:07:56.017Z,1723842476.017 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.InvalidAltitudeTimeout = nan min 2024-08-16T21:07:56.046Z,1723842476.046 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.AltitudeDepthLimit = nan m 2024-08-16T21:07:56.048Z,1723842476.048 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.AltitudeIIRFilterDecay = 0.000000 n/a 2024-08-16T21:07:56.092Z,1723842476.092 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.ElapsedRange = 0.000000 min 2024-08-16T21:07:56.110Z,1723842476.110 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.Range = nan m 2024-08-16T21:07:56.113Z,1723842476.113 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.DoneTimeout = -1.000000 count 2024-08-16T21:07:56.135Z,1723842476.135 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.Done = 0.000000 count 2024-08-16T21:07:56.137Z,1723842476.137 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.DetachFromDock = 1.000000 count 2024-08-16T21:07:56.164Z,1723842476.164 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.Midcourse = 2.000000 count 2024-08-16T21:07:56.175Z,1723842476.175 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.Terminal = 3.000000 count 2024-08-16T21:07:56.177Z,1723842476.177 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.GuidanceMode = 1.000000 count 2024-08-16T21:07:56.211Z,1723842476.211 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.DockingDepth = 1.000000 m 2024-08-16T21:07:56.227Z,1723842476.227 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-08-16T21:07:56.227Z,1723842476.227 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-08-16T21:07:56.228Z,1723842476.228 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-08-16T21:07:56.626Z,1723842476.626 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-08-16T21:07:56.629Z,1723842476.629 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture:StandardEnvelopes.MinAltitude = 5.000000 m 2024-08-16T21:07:56.648Z,1723842476.648 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-08-16T21:07:56.666Z,1723842476.666 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture:StandardEnvelopes.MaxDepth = 200.000000 m 2024-08-16T21:07:56.668Z,1723842476.668 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-08-16T21:07:56.669Z,1723842476.669 [lineCaptureHoming:LineCapture:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-08-16T21:07:56.699Z,1723842476.699 [lineCaptureHoming:LineCapture:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-08-16T21:07:56.721Z,1723842476.721 [lineCaptureHoming:LineCapture:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-08-16T21:07:56.785Z,1723842476.785 [lineCaptureHoming:LineCapture:HoldMassAndBuoyancy:B.Mass](DEBUG): Construct. 2024-08-16T21:07:56.800Z,1723842476.800 [lineCaptureHoming:LineCapture:HoldMassAndBuoyancy:C.Buoyancy](DEBUG): Construct Buoyancy. 2024-08-16T21:07:56.830Z,1723842476.830 [lineCaptureHoming:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Construct. 2024-08-16T21:07:56.830Z,1723842476.830 [lineCaptureHoming:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Initializing internal variables to default values. 2024-08-16T21:07:56.944Z,1723842476.944 [lineCaptureHoming:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed](DEBUG): Construct. 2024-08-16T21:07:56.986Z,1723842476.986 [lineCaptureHoming:LineCapture:HomingSequence:MidcourseGuidance:guide:D.Pitch](DEBUG): Construct. 2024-08-16T21:07:57.023Z,1723842477.023 [lineCaptureHoming:LineCapture:HomingSequence:MidcourseGuidance:guide:E.Waypoint](DEBUG): Construct Waypoint. 2024-08-16T21:07:57.067Z,1723842477.067 [lineCaptureHoming:LineCapture:HomingSequence:MidcourseGuidance:guide:SearchPattern:A.Circle](DEBUG): Construct. 2024-08-16T21:07:57.102Z,1723842477.102 [lineCaptureHoming:LineCapture:HomingSequence:MidcourseGuidance:guide:SearchPattern:B.Wait](DEBUG): Construct Wait. 2024-08-16T21:07:57.156Z,1723842477.156 [lineCaptureHoming:LineCapture:HomingSequence:TerminalGuidance:guide:altitudeControl:A.AltitudeServo](DEBUG): Construct. 2024-08-16T21:07:57.183Z,1723842477.183 [lineCaptureHoming:LineCapture:HomingSequence:TerminalGuidance:guide:depthControl:A.Pitch](DEBUG): Construct. 2024-08-16T21:07:57.223Z,1723842477.223 [lineCaptureHoming:LineCapture:HomingSequence:TerminalGuidance:guide:G.LineCapture](DEBUG): Construct. 2024-08-16T21:07:57.299Z,1723842477.299 [lineCaptureHoming:LineCapture:HomingSequence:TerminalGuidance:guide:G.LineCapture](DEBUG): Initializing internal variables to default values. 2024-08-16T21:07:57.299Z,1723842477.299 [lineCaptureHoming:LineCapture:HomingSequence:TerminalGuidance:guide:G.LineCapture](INFO): Transitioning guidance mode to: UNINITIALIZED 2024-08-16T21:07:57.327Z,1723842477.327 [lineCaptureHoming:LineCapture:K.Wait](DEBUG): Construct Wait. 2024-08-16T21:07:57.768Z,1723842477.768 [lineCaptureHoming:restartDHCP:B.Execute](DEBUG): Construct Execute. 2024-08-16T21:07:57.787Z,1723842477.787 [lineCaptureHoming:U.Execute](DEBUG): Construct Execute. 2024-08-16T21:07:57.789Z,1723842477.789 [lineCaptureHoming:InitialDive:A.SetSpeed](DEBUG): Construct. 2024-08-16T21:07:57.792Z,1723842477.792 [lineCaptureHoming:InitialDive:B.Pitch](DEBUG): Construct. 2024-08-16T21:07:57.838Z,1723842477.838 [lineCaptureHoming:InitialDive:B.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2024-08-16T21:07:57.881Z,1723842477.881 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/SetNavAcoustic.tl 2024-08-16T21:07:57.881Z,1723842477.881 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/SetNavAcoustic.tl 2024-08-16T21:07:57.886Z,1723842477.886 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/SetNavAcoustic.tl 2024-08-16T21:07:58.200Z,1723842478.200 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/SetNavAcoustic.tl translated into: Reseting nav to dock location at range 2024-08-16T21:07:58.213Z,1723842478.213 [MissionManager](INFO): DefineArg lineCaptureHoming:Lap:Docked:SetNavAcoustic.DockLat = 36.903300 arcdeg 2024-08-16T21:07:58.223Z,1723842478.223 [MissionManager](INFO): DefineArg lineCaptureHoming:Lap:Docked:SetNavAcoustic.DockLon = -122.119000 arcdeg 2024-08-16T21:07:58.258Z,1723842478.258 [MissionManager](INFO): DefineArg lineCaptureHoming:Lap:Docked:SetNavAcoustic.DockRange = 0.000000 m 2024-08-16T21:07:58.260Z,1723842478.260 [MissionManager](INFO): DefineArg lineCaptureHoming:Lap:Docked:SetNavAcoustic.RangeTimeout = 5.000000 min 2024-08-16T21:07:58.295Z,1723842478.295 [lineCaptureHoming:Lap:Docked:SetNavAcoustic:UpdateNav:B.SetNav](DEBUG): Construct SetNav. 2024-08-16T21:07:58.311Z,1723842478.311 [lineCaptureHoming:Lap:Docked:Docked.Docked](DEBUG): Construct. 2024-08-16T21:07:58.311Z,1723842478.311 [lineCaptureHoming:Lap:Docked:Docked.Docked](DEBUG): Initializing internal variables to default values. 2024-08-16T21:07:58.372Z,1723842478.372 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](DEBUG): Construct. 2024-08-16T21:07:58.372Z,1723842478.372 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](DEBUG): Initializing internal variables to default values. 2024-08-16T21:07:58.407Z,1723842478.407 [lineCaptureHoming:Lap:Docked:Wpt1:B.Pitch](DEBUG): Construct. 2024-08-16T21:07:58.424Z,1723842478.424 [lineCaptureHoming:Lap:Docked:Wpt1:C.SetSpeed](DEBUG): Construct. 2024-08-16T21:07:58.446Z,1723842478.446 [lineCaptureHoming:Lap:Docked:Wpt1:D.Waypoint](DEBUG): Construct Waypoint. 2024-08-16T21:07:58.500Z,1723842478.500 [lineCaptureHoming:Lap:Docked:Drift:B.Buoyancy](DEBUG): Construct Buoyancy. 2024-08-16T21:07:58.515Z,1723842478.515 [lineCaptureHoming:Lap:Docked:Drift:C.Pitch](DEBUG): Construct. 2024-08-16T21:07:58.532Z,1723842478.532 [lineCaptureHoming:Lap:Docked:Drift:D.Point](DEBUG): Construct. 2024-08-16T21:07:58.549Z,1723842478.549 [lineCaptureHoming:Lap:Docked:Drift:E.SetSpeed](DEBUG): Construct. 2024-08-16T21:07:58.588Z,1723842478.588 [lineCaptureHoming:Lap:Docked:Drift:F.Wait](DEBUG): Construct Wait. 2024-08-16T21:07:58.723Z,1723842478.723 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission lineCaptureHoming { """ Home to transponder while maintaining altitude or depth. Altitude gets priority if non nan. The mission sequence: (*) Start (1) Midcourse guidance: nav to target WP while querying transponder (2) Terminal guidance: home to target using USBL tracking data (4) Dock: final approach using fixed heading. """ # Define mission arguments arguments { MissionTimeout = 4 hour """ Maximum length of mission """ NeedCommsTime = 240 minute """ How often to surface for commumications """ MicromodemCommsInterval = 5 minute """ How often to trigger Umodem communications """ Repeat = 1 count """ Number of times the vehicle should try to cycle through the homing sequence. """ WaitOnDockTimeout = 5 minute """ Time duration to wait on the dock after a successful line capture. """ StayOnDock = false """ If true, mission terminates after WaitOnDockTimeout following a successful catpture, without executing the undock sequence. """ TrySlide = false """ If true, once docked, try to slide down the docking rod until charging and comms are acheived """ SlideRetries = 2 count """ If using slide mode, max number of times to try the slide sequence before giving up """ SlideSinkDuration = 5 minute """ Time duration to slide down by sinking during each slide mode iteration """ SlideCloseDuration = 5 minute """ Time duration to close arm to check for power/comms during each slide mode iteration """ SlideDriveDuration = 2 minute """ Time duration for actions that involve the prop (either bumping or continuous driving) during each slide mode iteration """ TryWiggle = false """ If true, uses mass shifter to try to wiggle down the rod after sinking in slide mode """ SlideWiggleCount = 2 count """ Number of mass shifter wiggles to try during each slide mode iteration """ TryJog = false """ If true, bumps prop repeatedly in forward and reverse after sinking in slide mode """ JogLength = 3 second """ Length of time to run the prop in each direction while jogging in slide mode """ TryWhirl = false """ If true, sets elevators to dive, rudders to turn, and drives continuously after sinking in slide mode """ WhirlSpeed = 1 meter_per_second """ Speed at which to drive while whirling in slide mode """ TransponderCode = Dock:Dock.transponderCode """ Transponder Address. """ TrackingUpdatePeriodMidcourse = 60 second """ How long to wait between acoustic queries. """ TrackingUpdatePeriodTerminal = 2.5 second """ How long to wait between acoustic queries. """ TrackingUpdatePeriodDocked = 2 minute """ How long to wait between acoustic queries. """ TrackingNumFixesLowPassFilter = 5 count """ Median filter bin size. Filters the contact estimated lat/lon position. Prefer odd number, set to 1 to disable filter. """ NumberOfPings = 1 count """ Number of pings requested each time. """ NumPingsUSBL = 1 count """ Number of pings requested while in USBL mode range. """ RangeUSBL = 40 meter """ USBL mode range. """ LightRange = 50.0 meter """ Range to activate the dock light. """ TrackingNumFixesLowPassFilterUSBL = 5 count """ Median filter bin size. Filters the contact estimated lat/lon position. Prefer odd number, set to 1 to disable filter. """ DockingAltitude = NaN meter """ Docking altitude. Ignored when NaN and used over depth when a valid altitude is set. """ InvalidAltitudeTimeout = NaN minute """ The mission will use the last good depth command if no valid altitude readings are received within this specified time duration. """ AltitudeDepthLimit = NaN meter """ Vehicle will not be commanded deeper than this regardless of altitude command. """ MinAltitude = 5 meter """ Minimum height above the sea floor for the entire mission. """ MaxDepth = 205 meter """ Maximum depth for the entire mission. """ MinOffshore = 2 kilometer """ Minimum offshore distance for the entire mission. """ ApproachDepthTimeout = 20 minute """ Maximum wait time for the vehicle to reach the target depth on initial dive. """ ApproachDepthRate = 0.05 meter_per_second """ Descent depth rate (positive depth is down). Used only as a smoothing parameter. """ Lat1 = NaN degree """ Latitude of rollout waypoint. If nan, waypoint is skipped. """ Lon1 = NaN degree """ Longitude of rollout waypoint. If nan, waypoint is skipped. """ CaptureRadius = NaN meter """ How close to call the waypiont behavior satisfied. Nan uses default value. """ # Mission Variables (don't change) MidcourseDepth = Dock:LineCapture.midcourseDepth """ Desired vehicle depth when navigating to dock WP (during midcourse phase). """ MidcourseSpeed = Dock:LineCapture.midcourseSpeed """ Speed during initial dive and waypoint. """ TrackingPeriod = 60 second """ Mission variable (don't change). The mission sets this variable to update the how long to wait between acoustic queries. """ } output { Mode = -1 count """ Mission variable (don't change). The mission sets this variable to reflect the termination status of the LineCapture insert. """ NumPings = 1 count """ Mission variable (don't change). The mission sets this variable to update the requested ping number and enter usbl mode. """ LowPassFilterWinSize = 1 count """ Mission variable (don't change). The mission sets this variable to update the requested ping number and enter usbl mode. """ LowPassFilterWinOverlap = true """ Mission variable (don't change). The mission sets this variable to update the requested ping number and enter usbl mode. """ } # Mission timeout timeout duration=MissionTimeout #insert Insert/Science.tl { # """ # Most missions will run the science sensors. If you don't place this # aggregate above NeedComms, science instruments get turned off on the # last upcast and while floating on the surface. # """ # } # Safety nets and surfacing behaviors # insert Insert/StandardEnvelopes.tl # assign in sequence StandardEnvelopes:MinAltitude = MinAltitude # assign in sequence StandardEnvelopes:MaxDepth = MaxDepth #assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 0 minute # Micromodem comms insert id="MicromodemComms" Insert/MicromodemComms.tl { redefineArg CommsInterval = MicromodemCommsInterval } readData { while ( Mode > 0 count and Sensor:DataOverHttps.connectionStatus == true ) Universal:platform_communications } # Add LineCapture directives. insert Insert/LineCapture.tl assign in sequence LineCapture:MaxDepth = MaxDepth assign in sequence LineCapture:MinOffshore = MinOffshore assign in sequence LineCapture:MinAltitude = MinAltitude assign in sequence LineCapture:DockingAltitude = DockingAltitude assign in sequence LineCapture:InvalidAltitudeTimeout = InvalidAltitudeTimeout assign in sequence LineCapture:AltitudeDepthLimit = AltitudeDepthLimit # Add track acoustic contact directive. assign in sequence NumPings = NumberOfPings behavior Estimation:TrackAcousticContact id="TrackAC" { run in parallel set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumPings set updatePeriodSetting = TrackingPeriod set numFixesLowPassSetting = LowPassFilterWinSize set lowPassOverlapSetting = LowPassFilterWinOverlap } # Add USBL mode directives. aggregate UpdateNumPings_NotTerminal { run while ( not ( LineCapture:GuidanceMode == LineCapture:Terminal ) and not ( NumPings == NumberOfPings ) ) syslog info "No longer in Terminal. Resuming normal ranging with " + NumberOfPings~count + "pings." assign in sequence NumPings = NumberOfPings assign in sequence LowPassFilterWinSize = TrackingNumFixesLowPassFilter assign in sequence LowPassFilterWinOverlap = true } aggregate UpdateNumPings_Terminal { run while ( LineCapture:GuidanceMode == LineCapture:Terminal ) aggregate UpdateNumPingsUSBL { run when ( not ( NumPings == NumPingsUSBL ) and ( ( LineCapture:Range < RangeUSBL ) # don't enter USBL mode during rollout and not ( Dock:LineCapture.guidanceMode == 5 count ) ) ) syslog info "Entering USBL mode with " + NumPingsUSBL~count + "pings at " + LineCapture:Range~meter + "(mode " + Dock:LineCapture.guidanceMode~count + ")." assign in sequence NumPings = NumPingsUSBL assign in sequence LowPassFilterWinSize = TrackingNumFixesLowPassFilterUSBL assign in sequence LowPassFilterWinOverlap = true } aggregate UpdateNumPingsATR { run when ( not ( NumPings == NumberOfPings ) and ( ( LineCapture:Range >= RangeUSBL ) # do ATR mode during rollout or Dock:LineCapture.guidanceMode == 5 count ) ) syslog info "Resuming normal ranging with " + NumberOfPings~count + "pings " + LineCapture:Range~meter + "(mode " + Dock:LineCapture.guidanceMode~count + ")." assign in sequence NumPings = NumberOfPings assign in sequence LowPassFilterWinSize = TrackingNumFixesLowPassFilter assign in sequence LowPassFilterWinOverlap = true } } aggregate UpdatePingUpdateRate_Midcourse { run when ( not ( TrackingPeriod == TrackingUpdatePeriodMidcourse ) and ( ( LineCapture:Range > LineCapture:TerminalRange ) # do slow ping mode during rollout or Dock:LineCapture.guidanceMode == 5 count ) ) syslog info "Entering Midcourse tracking update period " + TrackingUpdatePeriodMidcourse~second + "sec at" + LineCapture:Range~meter + "(mode " + Dock:LineCapture.guidanceMode~count + ")." assign in sequence TrackingPeriod = TrackingUpdatePeriodMidcourse assign in sequence LowPassFilterWinSize = TrackingNumFixesLowPassFilter assign in sequence LowPassFilterWinOverlap = true } aggregate UpdatePingUpdateRate_Terminal { run when ( not ( TrackingPeriod == TrackingUpdatePeriodTerminal ) and ( ( LineCapture:Range <= LineCapture:TerminalRange ) and not ( Dock:LineCapture.guidanceMode == 5 count ) and not ( LineCapture:GuidanceMode == 0 count ) ) # don't enter fast ping mode during rollout or on dock ) syslog info "Entering Terminal tracking update period " + TrackingUpdatePeriodTerminal~second + "sec at" + LineCapture:Range~meter + "(mode " + Dock:LineCapture.guidanceMode~count + ")." assign in sequence TrackingPeriod = TrackingUpdatePeriodTerminal assign in sequence LowPassFilterWinSize = TrackingNumFixesLowPassFilter assign in sequence LowPassFilterWinOverlap = true } aggregate restartDHCP { run when ( called ) syslog important "Restarting DHCP..." behavior Guidance:Execute { run in sequence set command = "! /mnt/mmc/LRAUV/Tools/dhcp.sh" } } # Start mission sequence. call id="StartingMission" refId="NeedComms" syslog important "Restarting logs" behavior Guidance:Execute { run in sequence set command = "restart logs" } aggregate InitialDive { run in sequence behavior Guidance:SetSpeed { run in parallel set speed = MidcourseSpeed } behavior Guidance:Pitch { run in sequence timeout duration=ApproachDepthTimeout { syslog important "Timed out trying to reach the target depth. Stopping mission at current depth of " + Universal:depth~meter behavior Guidance:Execute { run in sequence set command = "stop" } } set depth = MidcourseDepth set depthRate = ApproachDepthRate } } aggregate Lap { run in sequence repeat=Repeat assign in sequence Mode = -99 count # Execute the LineCapture sequence. # assign in sequence Mode = LineCapture:GuidanceMode assign in sequence Mode = 0 count syslog info "Mode" + Mode~count call refId="restartDHCP" aggregate Docked { run in sequence break if ( Mode < 0 count ) syslog info "Waiting on dock for " + WaitOnDockTimeout~minute # Add SetNav directives. insert Insert/SetNavAcoustic.tl assign in sequence TrackingPeriod = TrackingUpdatePeriodDocked behavior Dock:Docked id="Docked" { run in sequence timeout duration=WaitOnDockTimeout set slideSinkTime = SlideSinkDuration set slideCloseTime = SlideCloseDuration set slideDriveTime = SlideDriveDuration set jogLength = JogLength set whirlSpeed = WhirlSpeed set slideRetries = SlideRetries set wiggleCount = SlideWiggleCount set slideAllowed = TrySlide set massWiggle = TryWiggle set propJog = TryJog set driveWhirl = TryWhirl } aggregate Detach { run in sequence break if ( StayOnDock == true ) syslog important "Breaking off. Range: " + Universal:acoustic_contact_range~meter + "." behavior Dock:Undock id="Undock" { run in sequence } call refId="restartDHCP" } aggregate Wpt1 { run in sequence break if ( isNaN ( Lat1 ) or isNaN ( Lon1 ) or ( StayOnDock == true ) ) syslog important "Driving to post-undock waypoint " + Lat1~degree + "," + Lon1~degree behavior Guidance:Pitch { run in parallel set depth = MidcourseDepth } behavior Guidance:SetSpeed { run in parallel set speed = MidcourseSpeed } behavior Guidance:Waypoint { run in sequence set latitude = Lat1 set longitude = Lon1 set captureRadius = CaptureRadius } } aggregate Drift { """ Drift before next docking attempt """ run in sequence break if ( not isNaN ( Lat1 ) and not isNaN ( Lon1 ) or ( StayOnDock == true ) ) syslog important "No post-undock waypoint, drifting for " + Dock:LineCapture.rolloutTimeout~minute + " minutes" behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch { run in parallel set massPosition = Control:VerticalControl.massDefault set elevatorAngle = 0 degree } behavior Guidance:Point { run in parallel set rudderAngle = 0 degree } behavior Guidance:SetSpeed { run in parallel set speed = 0 meter_per_second } behavior Guidance:Wait { run in sequence set duration = Dock:LineCapture.rolloutTimeout } } } } } 2024-08-16T21:07:58.724Z,1723842478.724 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/lineCaptureHoming.tl id=lineCaptureHoming 2024-08-16T21:08:06.066Z,1723842486.066 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:08:18.394Z,1723842498.394 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:08:19.585Z,1723842499.585 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](INFO): Timed out from 2024-08-16T21:03:19.3Z 2024-08-16T21:08:19.585Z,1723842499.585 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-08-16T21:08:19.585Z,1723842499.585 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2024-08-16T21:08:29.185Z,1723842509.185 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240816T201826/Courier0016.lzma 2024-08-16T21:08:30.188Z,1723842510.188 [DataOverHttps](INFO): Moved sent file to Logs/20240816T201826/Courier0016.lzma.bak 2024-08-16T21:08:30.188Z,1723842510.188 [DataOverHttps](INFO): SBD MOMSN=22906255 2024-08-16T21:08:53.658Z,1723842533.658 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240816T205924/Courier0000.lzma 2024-08-16T21:08:54.660Z,1723842534.660 [DataOverHttps](INFO): Moved sent file to Logs/20240816T205924/Courier0000.lzma.bak 2024-08-16T21:08:54.660Z,1723842534.660 [DataOverHttps](INFO): SBD MOMSN=22906257 2024-08-16T21:08:55.098Z,1723842535.098 [CommandExec](IMPORTANT): got command set lineCaptureHoming.WaitOnDockTimeout 10 minute 2024-08-16T21:08:55.099Z,1723842535.099 [CommandExec](IMPORTANT): got command set lineCaptureHoming.TrySlide 1 bool 2024-08-16T21:08:55.100Z,1723842535.100 [CommandExec](IMPORTANT): got command set lineCaptureHoming.SlideSinkDuration 30 second 2024-08-16T21:08:55.100Z,1723842535.100 [CommandExec](IMPORTANT): got command set lineCaptureHoming.SlideCloseDuration 30 second 2024-08-16T21:08:55.101Z,1723842535.101 [CommandExec](IMPORTANT): got command maintain sensor 2024-08-16T21:08:55.101Z,1723842535.101 [CommandExec](FAULT): Incomplete syntax. Try: help maintain 2024-08-16T21:09:01.250Z,1723842541.250 [CommandExec](IMPORTANT): got command run 2024-08-16T21:09:01.252Z,1723842541.252 [CommandExec](IMPORTANT): Running 2024-08-16T21:09:01.264Z,1723842541.264 [DefaultWithUndock] Stopped 2024-08-16T21:09:01.264Z,1723842541.264 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2024-08-16T21:09:01.264Z,1723842541.264 [DefaultWithUndock:SurfaceDefault] Stopped 2024-08-16T21:09:01.264Z,1723842541.264 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2024-08-16T21:09:01.264Z,1723842541.264 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped 2024-08-16T21:09:01.264Z,1723842541.264 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-16T21:09:01.264Z,1723842541.264 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2024-08-16T21:09:01.265Z,1723842541.265 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-08-16T21:09:01.265Z,1723842541.265 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2024-08-16T21:09:01.265Z,1723842541.265 [MissionManager](IMPORTANT): Started mission lineCaptureHoming 2024-08-16T21:09:01.265Z,1723842541.265 [lineCaptureHoming] Running Loop=1 2024-08-16T21:09:01.265Z,1723842541.265 [lineCaptureHoming](DEBUG): Aggregate::initialize lineCaptureHoming 2024-08-16T21:09:01.265Z,1723842541.265 [lineCaptureHoming:MicromodemComms] Running Loop=1 2024-08-16T21:09:01.265Z,1723842541.265 [lineCaptureHoming:MicromodemComms](DEBUG): Aggregate::initialize lineCaptureHoming:MicromodemComms 2024-08-16T21:09:01.278Z,1723842541.278 [lineCaptureHoming:MicromodemComms:CheckIn] Running Loop=1 2024-08-16T21:09:01.278Z,1723842541.278 [lineCaptureHoming:MicromodemComms:CheckIn](DEBUG): Aggregate::initialize lineCaptureHoming:MicromodemComms:CheckIn 2024-08-16T21:09:01.278Z,1723842541.278 [lineCaptureHoming:MicromodemComms:CheckIn:A] Running Loop=1 2024-08-16T21:09:01.278Z,1723842541.278 [lineCaptureHoming:E] Running Loop=1 2024-08-16T21:09:01.278Z,1723842541.278 [lineCaptureHoming:TrackAC.] Running Loop=1 2024-08-16T21:09:01.278Z,1723842541.278 [lineCaptureHoming:TrackAC.](INFO): Initializing TrackAcousticContact. 2024-08-16T21:09:01.278Z,1723842541.278 [lineCaptureHoming:UpdateNumPings_NotTerminal] Running Loop=1 2024-08-16T21:09:01.279Z,1723842541.279 [lineCaptureHoming:UpdateNumPings_NotTerminal](DEBUG): Aggregate::initialize lineCaptureHoming:UpdateNumPings_NotTerminal 2024-08-16T21:09:01.279Z,1723842541.279 [lineCaptureHoming:UpdateNumPings_NotTerminal:A] Running Loop=1 2024-08-16T21:09:01.279Z,1723842541.279 [lineCaptureHoming:UpdateNumPings_Terminal] Running Loop=1 2024-08-16T21:09:01.279Z,1723842541.279 [lineCaptureHoming:UpdateNumPings_Terminal](DEBUG): Aggregate::initialize lineCaptureHoming:UpdateNumPings_Terminal 2024-08-16T21:09:01.279Z,1723842541.279 [lineCaptureHoming:B] Running Loop=1 2024-08-16T21:09:01.281Z,1723842541.281 [lineCaptureHoming:UpdateNumPings_Terminal] Stopped 2024-08-16T21:09:01.281Z,1723842541.281 [lineCaptureHoming:UpdateNumPings_Terminal](DEBUG): Aggregate::uninitialize lineCaptureHoming:UpdateNumPings_Terminal 2024-08-16T21:09:01.281Z,1723842541.281 [lineCaptureHoming:UpdateNumPings_NotTerminal] Stopped 2024-08-16T21:09:01.281Z,1723842541.281 [lineCaptureHoming:UpdateNumPings_NotTerminal](DEBUG): Aggregate::uninitialize lineCaptureHoming:UpdateNumPings_NotTerminal 2024-08-16T21:09:01.281Z,1723842541.281 [lineCaptureHoming:UpdateNumPings_NotTerminal:A] Stopped 2024-08-16T21:09:01.281Z,1723842541.281 [lineCaptureHoming:TrackAC.] Running Loop=1 2024-08-16T21:09:01.296Z,1723842541.296 [lineCaptureHoming:E] Stopped 2024-08-16T21:09:01.296Z,1723842541.296 [lineCaptureHoming:MicromodemComms] Running Loop=1 2024-08-16T21:09:01.297Z,1723842541.297 [lineCaptureHoming:MicromodemComms:CheckIn:A](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-16T21:09:01.302Z,1723842541.302 [lineCaptureHoming:B] Stopped 2024-08-16T21:09:01.302Z,1723842541.302 [lineCaptureHoming:C] Running Loop=1 2024-08-16T21:09:01.663Z,1723842541.663 [lineCaptureHoming:C] Stopped 2024-08-16T21:09:01.663Z,1723842541.663 [lineCaptureHoming:G] Running Loop=1 2024-08-16T21:09:02.038Z,1723842542.038 [lineCaptureHoming:G] Stopped 2024-08-16T21:09:02.038Z,1723842542.038 [lineCaptureHoming:H] Running Loop=1 2024-08-16T21:09:02.449Z,1723842542.449 [lineCaptureHoming:H] Stopped 2024-08-16T21:09:02.449Z,1723842542.449 [lineCaptureHoming:I] Running Loop=1 2024-08-16T21:09:02.825Z,1723842542.825 [lineCaptureHoming:I] Stopped 2024-08-16T21:09:02.825Z,1723842542.825 [lineCaptureHoming:J] Running Loop=1 2024-08-16T21:09:03.235Z,1723842543.235 [lineCaptureHoming:J] Stopped 2024-08-16T21:09:03.235Z,1723842543.235 [lineCaptureHoming:K] Running Loop=1 2024-08-16T21:09:03.657Z,1723842543.657 [lineCaptureHoming:K] Stopped 2024-08-16T21:09:03.658Z,1723842543.658 [lineCaptureHoming:L] Running Loop=1 2024-08-16T21:09:04.068Z,1723842544.068 [lineCaptureHoming:L] Stopped 2024-08-16T21:09:04.068Z,1723842544.068 [lineCaptureHoming:M] Running Loop=1 2024-08-16T21:09:04.442Z,1723842544.442 [lineCaptureHoming:M] Stopped 2024-08-16T21:09:04.442Z,1723842544.442 [lineCaptureHoming:StartingMission] Running Loop=1 2024-08-16T21:09:04.442Z,1723842544.442 [lineCaptureHoming:StartingMission](DEBUG): Aggregate::initialize lineCaptureHoming:StartingMission 2024-08-16T21:09:04.859Z,1723842544.859 [lineCaptureHoming:NeedComms] Running Loop=1 2024-08-16T21:09:04.859Z,1723842544.859 [lineCaptureHoming:NeedComms](DEBUG): Aggregate::initialize lineCaptureHoming:NeedComms 2024-08-16T21:09:04.859Z,1723842544.859 [lineCaptureHoming:NeedComms:B.GoToSurface] Running Loop=1 2024-08-16T21:09:04.859Z,1723842544.859 [lineCaptureHoming:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-16T21:09:04.859Z,1723842544.859 [lineCaptureHoming:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2024-08-16T21:09:04.860Z,1723842544.860 [lineCaptureHoming:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2024-08-16T21:09:04.860Z,1723842544.860 [lineCaptureHoming:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2024-08-16T21:09:04.860Z,1723842544.860 [lineCaptureHoming:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2024-08-16T21:09:04.861Z,1723842544.861 [lineCaptureHoming:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-16T21:09:04.861Z,1723842544.861 [lineCaptureHoming:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-16T21:09:04.861Z,1723842544.861 [lineCaptureHoming:NeedComms:A] Running Loop=1 2024-08-16T21:09:04.867Z,1723842544.867 [lineCaptureHoming:NeedComms:A](INFO): last time_fix was: 1723841920.000000 second since 1970/01/01T00:00:00Z 2024-08-16T21:09:04.867Z,1723842544.867 [lineCaptureHoming:NeedComms:A] Stopped 2024-08-16T21:09:05.278Z,1723842545.278 [lineCaptureHoming:NeedComms:C] Running Loop=1 2024-08-16T21:09:05.682Z,1723842545.682 [lineCaptureHoming:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-08-16T21:09:06.043Z,1723842546.043 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-16T21:09:11.277Z,1723842551.277 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240816T205924/Courier0003.lzma 2024-08-16T21:09:12.280Z,1723842552.280 [DataOverHttps](INFO): Moved sent file to Logs/20240816T205924/Courier0003.lzma.bak 2024-08-16T21:09:12.280Z,1723842552.280 [DataOverHttps](INFO): SBD MOMSN=22906260 2024-08-16T21:09:13.686Z,1723842553.686 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 32 degree 2024-08-16T21:09:13.688Z,1723842553.688 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2024-08-16T21:09:13.851Z,1723842553.851 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-08-16T21:09:25.234Z,1723842565.234 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121 degree 2024-08-16T21:09:25.235Z,1723842565.235 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2024-08-16T21:09:25.532Z,1723842565.532 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-08-16T21:09:30.799Z,1723842570.799 [DataOverHttps](INFO): Sending 467 bytes from file Logs/20240816T201826/Express0017.lzma 2024-08-16T21:09:31.800Z,1723842571.800 [DataOverHttps](INFO): Moved sent file to Logs/20240816T201826/Express0017.lzma.bak 2024-08-16T21:09:31.800Z,1723842571.800 [DataOverHttps](INFO): SBD MOMSN=22906262 2024-08-16T21:09:42.569Z,1723842582.569 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1723842552 2024-08-16T21:09:42.570Z,1723842582.570 [CommandExec](FAULT): Incomplete syntax. Try: help set 2024-08-16T21:09:52.615Z,1723842592.615 [DataOverHttps](INFO): Sending 959 bytes from file Logs/20240816T205924/Express0001.lzma 2024-08-16T21:09:53.615Z,1723842593.615 [DataOverHttps](INFO): Moved sent file to Logs/20240816T205924/Express0001.lzma.bak 2024-08-16T21:09:53.616Z,1723842593.616 [DataOverHttps](INFO): SBD MOMSN=22906273 2024-08-16T21:09:55.132Z,1723842595.132 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1723842552 second 2024-08-16T21:09:55.365Z,1723842595.365 [lineCaptureHoming:NeedComms:C] Stopped 2024-08-16T21:09:55.365Z,1723842595.365 [lineCaptureHoming:NeedComms:D] Running Loop=1 2024-08-16T21:09:55.789Z,1723842595.789 [lineCaptureHoming:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-16T21:10:01.405Z,1723842601.405 [lineCaptureHoming:TrackAC.](INFO): *** querying acoustic contact *** 2024-08-16T21:10:01.476Z,1723842601.476 [DAT](INFO): ****** received valid address query ****** 2024-08-16T21:10:01.477Z,1723842601.477 [DAT](INFO): ****** received valid ping request ****** 2024-08-16T21:10:01.477Z,1723842601.477 [DAT](INFO): Querying Benthos address 6 with one ping in standard two-way mode. 2024-08-16T21:10:01.727Z,1723842601.727 [DAT](INFO): DAT read: user:210> 2024-08-16T21:10:01.727Z,1723842601.727 [DAT](INFO): DAT read: Tx time:21:10:00.8366 2024-08-16T21:10:01.728Z,1723842601.728 [DAT](INFO): Ping request sent. 2024-08-16T21:10:01.728Z,1723842601.728 [DAT](INFO): transmitted an acoustic signal 2024-08-16T21:10:01.728Z,1723842601.728 [DAT](INFO): publishing transmit ping time 2024-08-16T21:10:01.729Z,1723842601.729 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000723 2024-08-16T21:10:01.979Z,1723842601.979 [DAT](INFO): DAT read: TxSync time:21:10:00.8358 2024-08-16T21:10:01.979Z,1723842601.979 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251549 2024-08-16T21:10:02.231Z,1723842602.231 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502586 2024-08-16T21:10:02.483Z,1723842602.483 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754881 2024-08-16T21:10:02.735Z,1723842602.735 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006691 2024-08-16T21:10:02.987Z,1723842602.987 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258610 2024-08-16T21:10:03.239Z,1723842603.239 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511064 2024-08-16T21:10:03.492Z,1723842603.492 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764086 2024-08-16T21:10:03.743Z,1723842603.743 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014601 2024-08-16T21:10:03.995Z,1723842603.995 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266940 2024-08-16T21:10:04.247Z,1723842604.247 [DAT](INFO): DAT read: Rx Time:21:10:02.8872 2024-08-16T21:10:04.247Z,1723842604.247 [DAT](INFO): Rx dataTimestamp_ set to:1723842604.246959 2024-08-16T21:10:04.248Z,1723842604.248 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519859 2024-08-16T21:10:04.499Z,1723842604.499 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771045 2024-08-16T21:10:04.757Z,1723842604.757 [DAT](INFO): DAT read: 21:10:02.8872 LVL= 16880, 16881, 17282, 24371, AGC= 66, IDX= 435,-0.09,-2.373,-0.518,-0.916,-1.661, PHS=-0.623, 1.191, 0.743, RAW= 283.7, -15.8, CAL= 288.0, -24.0, ROT= 222.0, 24.0 2024-08-16T21:10:04.758Z,1723842604.758 [DAT](INFO): got valid direction response: 21:10:02.8872 LVL= 16880, 16881, 17282, 24371, AGC= 66, IDX= 435,-0.09,-2.373,-0.518,-0.916,-1.661, PHS=-0.623, 1.191, 0.743, RAW= 283.7, -15.8, CAL= 288.0, -24.0, ROT= 222.0, 24.0 2024-08-16T21:10:04.759Z,1723842604.759 [DAT](INFO): DAT read: Bearing 123, -29 (Remote) 2024-08-16T21:10:04.759Z,1723842604.759 [DAT](INFO): Remote Bearing received:Bearing 123, -29 (Remote) 2024-08-16T21:10:04.760Z,1723842604.760 [DAT](INFO): DAT read: Bearing 222.0, 24.0 (Local) 2024-08-16T21:10:04.760Z,1723842604.760 [DAT](INFO): Local bearing/azimuth received: Bearing 222.0, 24.0 (Local) 2024-08-16T21:10:04.762Z,1723842604.762 [DAT](INFO): DAT read: Range 10 to 6 : 15.9 m (Round-trip 21.3 ms) speed 0.0 m/s 2024-08-16T21:10:04.762Z,1723842604.762 [DAT](INFO): #Rx 1: Read range and direction messages. 2024-08-16T21:10:04.763Z,1723842604.763 [DAT](INFO): direction in FSK: [-0.678897,-0.611281,-0.406737] 2024-08-16T21:10:04.764Z,1723842604.764 [DAT](INFO): publishing direction and range info 2024-08-16T21:10:13.062Z,1723842613.062 [DataOverHttps](INFO): Sending 487 bytes from file Logs/20240816T205924/Express0004.lzma 2024-08-16T21:10:14.064Z,1723842614.064 [DataOverHttps](INFO): Moved sent file to Logs/20240816T205924/Express0004.lzma.bak 2024-08-16T21:10:14.064Z,1723842614.064 [DataOverHttps](INFO): SBD MOMSN=22906301 2024-08-16T21:10:14.921Z,1723842614.921 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1723842552 second 2024-08-16T21:10:15.585Z,1723842615.585 [lineCaptureHoming:MicromodemComms] Stopped 2024-08-16T21:10:15.585Z,1723842615.585 [lineCaptureHoming:MicromodemComms](DEBUG): Aggregate::uninitialize lineCaptureHoming:MicromodemComms 2024-08-16T21:10:15.585Z,1723842615.585 [lineCaptureHoming:MicromodemComms:CheckIn] Stopped 2024-08-16T21:10:15.585Z,1723842615.585 [lineCaptureHoming:MicromodemComms:CheckIn](DEBUG): Aggregate::uninitialize lineCaptureHoming:MicromodemComms:CheckIn 2024-08-16T21:10:15.585Z,1723842615.585 [lineCaptureHoming:MicromodemComms:CheckIn:A] Stopped 2024-08-16T21:10:15.585Z,1723842615.585 [lineCaptureHoming:NeedComms:D] Stopped 2024-08-16T21:10:15.585Z,1723842615.585 [lineCaptureHoming:NeedComms:E] Running Loop=1 2024-08-16T21:10:15.955Z,1723842615.955 [lineCaptureHoming:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-08-16T21:10:21.399Z,1723842621.399 [CommandExec](IMPORTANT): got command show stack 2024-08-16T21:10:21.399Z,1723842621.399 [CommandExec](IMPORTANT): Behavior Stack: 2024-08-16T21:10:21.400Z,1723842621.400 [lineCaptureHoming:NeedComms](IMPORTANT): Priority 0: lineCaptureHoming:NeedComms:B.GoToSurface 2024-08-16T21:10:21.400Z,1723842621.400 [lineCaptureHoming:NeedComms](IMPORTANT): Priority 1: lineCaptureHoming:NeedComms:E 2024-08-16T21:10:21.401Z,1723842621.401 [lineCaptureHoming](IMPORTANT): Priority 2: lineCaptureHoming:TrackAC. 2024-08-16T21:10:23.934Z,1723842623.934 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:10:32.894Z,1723842632.894 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:10:37.677Z,1723842637.677 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.time_fix 1723842552 2024-08-16T21:10:37.686Z,1723842637.686 [CommandExec](FAULT): Incomplete syntax. Try: help maintain 2024-08-16T21:10:43.412Z,1723842643.412 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.time_fix 1723842552 second 2024-08-16T21:10:43.418Z,1723842643.418 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.time_fix" handled in the control thread. 2024-08-16T21:10:43.541Z,1723842643.541 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,Maintain_NAL9602.time_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-08-16T21:10:43.860Z,1723842643.860 [lineCaptureHoming:NeedComms:E] Stopped 2024-08-16T21:10:43.862Z,1723842643.862 [lineCaptureHoming:NeedComms](INFO): Completed lineCaptureHoming:NeedComms 2024-08-16T21:10:43.862Z,1723842643.862 [lineCaptureHoming:NeedComms] Stopped 2024-08-16T21:10:43.862Z,1723842643.862 [lineCaptureHoming:NeedComms](DEBUG): Aggregate::uninitialize lineCaptureHoming:NeedComms 2024-08-16T21:10:43.862Z,1723842643.862 [lineCaptureHoming:NeedComms:B.GoToSurface] Stopped 2024-08-16T21:10:43.862Z,1723842643.862 [lineCaptureHoming:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-16T21:10:43.970Z,1723842643.970 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-16T21:10:44.256Z,1723842644.256 [lineCaptureHoming:StartingMission](INFO): Completed lineCaptureHoming:StartingMission 2024-08-16T21:10:44.256Z,1723842644.256 [lineCaptureHoming:StartingMission] Stopped 2024-08-16T21:10:44.256Z,1723842644.256 [lineCaptureHoming:StartingMission](DEBUG): Aggregate::uninitialize lineCaptureHoming:StartingMission 2024-08-16T21:10:44.256Z,1723842644.256 [lineCaptureHoming:T] Running Loop=1 2024-08-16T21:10:44.640Z,1723842644.640 [lineCaptureHoming:T](IMPORTANT): Restarting logs 2024-08-16T21:10:44.640Z,1723842644.640 [lineCaptureHoming:T] Stopped 2024-08-16T21:10:44.640Z,1723842644.640 [lineCaptureHoming:U.Execute] Running Loop=1 2024-08-16T21:10:45.046Z,1723842645.046 [lineCaptureHoming:U.Execute](INFO): Executing command restart logs 2024-08-16T21:10:45.047Z,1723842645.047 [lineCaptureHoming:U.Execute] Stopped 2024-08-16T21:10:45.047Z,1723842645.047 [lineCaptureHoming:InitialDive] Running Loop=1 2024-08-16T21:10:45.047Z,1723842645.047 [lineCaptureHoming:InitialDive](DEBUG): Aggregate::initialize lineCaptureHoming:InitialDive