2025-02-22T01:28:31.380Z,1740187711.380 [CommandExec](IMPORTANT): got command set keepstation:BackseatDriver.EnableBackseat 1 bool
2025-02-22T01:28:31.381Z,1740187711.381 [CommandExec](IMPORTANT): got command run
2025-02-22T01:28:31.383Z,1740187711.383 [CommandExec](IMPORTANT): Running 
2025-02-22T01:28:31.385Z,1740187711.385 [CommandExec](IMPORTANT): got command restart logs
2025-02-22T01:28:31.479Z,1740187711.479 [Default] Stopped
2025-02-22T01:28:31.487Z,1740187711.487 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-22T01:28:31.487Z,1740187711.487 [Default:B.GoToSurface] Stopped
2025-02-22T01:28:31.487Z,1740187711.487 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T01:28:31.487Z,1740187711.487 [Default:CheckIn] Stopped
2025-02-22T01:28:31.487Z,1740187711.487 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-22T01:28:31.488Z,1740187711.488 [Default:CheckIn:Read_Iridium] Stopped
2025-02-22T01:28:31.488Z,1740187711.488 [MissionManager](IMPORTANT): Started mission keepstation
2025-02-22T01:28:31.488Z,1740187711.488 [keepstation] Running Loop=1
2025-02-22T01:28:31.488Z,1740187711.488 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-02-22T01:28:31.488Z,1740187711.488 [keepstation:StandardEnvelopes] Running Loop=1
2025-02-22T01:28:31.488Z,1740187711.488 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-02-22T01:28:31.488Z,1740187711.488 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-22T01:28:31.488Z,1740187711.488 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-22T01:28:31.489Z,1740187711.489 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-22T01:28:31.489Z,1740187711.489 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-22T01:28:31.489Z,1740187711.489 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-22T01:28:31.489Z,1740187711.489 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-22T01:28:31.489Z,1740187711.489 [keepstation:BackseatDriver] Running Loop=1
2025-02-22T01:28:31.489Z,1740187711.489 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-02-22T01:28:31.489Z,1740187711.489 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-22T01:28:31.489Z,1740187711.489 [keepstation:PowerOnly] Running Loop=1
2025-02-22T01:28:31.489Z,1740187711.489 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-02-22T01:28:31.489Z,1740187711.490 [keepstation:PowerOnly:A] Running Loop=1
2025-02-22T01:28:31.490Z,1740187711.490 [keepstation:PowerOnly:B] Running Loop=1
2025-02-22T01:28:31.490Z,1740187711.490 [keepstation:PowerOnly:C] Running Loop=1
2025-02-22T01:28:31.490Z,1740187711.490 [keepstation:PowerOnly:D] Running Loop=1
2025-02-22T01:28:31.490Z,1740187711.490 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-02-22T01:28:31.490Z,1740187711.490 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-22T01:28:31.490Z,1740187711.490 [keepstation:H.Pitch] Running Loop=1
2025-02-22T01:28:31.490Z,1740187711.490 [keepstation:H.Pitch](DEBUG): Initialize.
2025-02-22T01:28:31.490Z,1740187711.490 [keepstation:B] Running Loop=1
2025-02-22T01:28:31.499Z,1740187711.499 [keepstation:H.Pitch] Running Loop=1
2025-02-22T01:28:31.499Z,1740187711.499 [keepstation:PowerOnly] Running Loop=1
2025-02-22T01:28:31.500Z,1740187711.500 [keepstation:PowerOnly:D] Stopped
2025-02-22T01:28:31.500Z,1740187711.500 [keepstation:PowerOnly:C] Stopped
2025-02-22T01:28:31.500Z,1740187711.500 [keepstation:PowerOnly:B] Stopped
2025-02-22T01:28:31.500Z,1740187711.500 [keepstation:PowerOnly:A] Stopped
2025-02-22T01:28:31.500Z,1740187711.500 [keepstation:BackseatDriver] Running Loop=1
2025-02-22T01:28:31.500Z,1740187711.500 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-02-22T01:28:31.553Z,1740187711.553 [keepstation:StandardEnvelopes] Running Loop=1
2025-02-22T01:28:31.553Z,1740187711.553 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-22T01:28:31.553Z,1740187711.553 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-22T01:28:31.554Z,1740187711.554 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-22T01:28:31.554Z,1740187711.554 [keepstation:B] Stopped
2025-02-22T01:28:31.554Z,1740187711.554 [keepstation:D] Running Loop=1
2025-02-22T01:28:31.655Z,1740187711.655 [BackseatComponent](INFO): Powering up
2025-02-22T01:28:31.656Z,1740187711.656 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-02-22T01:28:31.911Z,1740187711.911 [keepstation:D] Stopped
2025-02-22T01:28:31.911Z,1740187711.911 [keepstation:E] Running Loop=1
2025-02-22T01:28:32.315Z,1740187712.315 [keepstation:E] Stopped
2025-02-22T01:28:32.315Z,1740187712.315 [keepstation:StartingMission] Running Loop=1
2025-02-22T01:28:32.316Z,1740187712.316 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-02-22T01:28:32.704Z,1740187712.704 [keepstation:NeedComms] Running Loop=1
2025-02-22T01:28:32.704Z,1740187712.704 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-22T01:28:32.704Z,1740187712.704 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-22T01:28:32.704Z,1740187712.704 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T01:28:32.705Z,1740187712.705 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-22T01:28:32.705Z,1740187712.705 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-22T01:28:32.705Z,1740187712.705 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-22T01:28:32.706Z,1740187712.706 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-22T01:28:32.706Z,1740187712.706 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T01:28:32.706Z,1740187712.706 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T01:28:32.706Z,1740187712.706 [keepstation:NeedComms:A] Running Loop=1
2025-02-22T01:28:32.708Z,1740187712.708 [keepstation:NeedComms:A](INFO): last time_fix was:  1740187667.000000 second since 1970/01/01T00:00:00Z 
2025-02-22T01:28:32.708Z,1740187712.708 [keepstation:NeedComms:A] Stopped
2025-02-22T01:28:33.098Z,1740187713.098 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-22T01:28:33.103Z,1740187713.103 [keepstation:NeedComms:C] Running Loop=1
2025-02-22T01:28:33.351Z,1740187713.351 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012407/Courier0024.lzma
2025-02-22T01:28:33.517Z,1740187713.517 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-22T01:28:34.355Z,1740187714.355 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012407/Courier0024.lzma.bak
2025-02-22T01:28:34.355Z,1740187714.355 [DataOverHttps](IMPORTANT): SBD MOMSN=23971721, MTMSN=20250222T012833
2025-02-22T01:28:35.102Z,1740187715.102 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012834.00,A,3647.54734,N,12150.89196,W,0.700,151.62,220225,,,A*7C
2025-02-22T01:28:35.105Z,1740187715.105 [NAL9602](INFO): GPS fix at 20250222T012834: (36.792456, -121.848199)
2025-02-22T01:28:35.136Z,1740187715.136 [keepstation:NeedComms:C] Stopped
2025-02-22T01:28:35.136Z,1740187715.136 [keepstation:NeedComms:D] Running Loop=1
2025-02-22T01:28:35.537Z,1740187715.537 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-22T01:28:45.411Z,1740187725.411 [DataOverHttps](INFO): Received command: sched "load Transport/keepstation.tl;set keepstation.MissionTimeout 45 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 10 m" 241fc 1 2
2025-02-22T01:28:46.965Z,1740187726.965 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T012845
2025-02-22T01:28:48.985Z,1740187728.985 [CommandExec](IMPORTANT): got command schedule "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 241ej 2 2.000000
2025-02-22T01:28:48.985Z,1740187728.985 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=241ej
2025-02-22T01:28:48.986Z,1740187728.986 [CommandExec](IMPORTANT): Scheduled #64 (#2 of 2 with id='241ej'): "set keepstation:BackseatDriver.EnableBackseat 1 bool;run", AFTER MISSION
2025-02-22T01:28:49.077Z,1740187729.077 [CommandExec](IMPORTANT): got command schedule "load Transport/keepstation.tl;set keepstation.MissionTimeout 45 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 10 m" 241fc 1 2.000000
2025-02-22T01:28:49.078Z,1740187729.078 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=241fc
2025-02-22T01:28:49.083Z,1740187729.083 [CommandExec](IMPORTANT): Scheduled #65 (#1 of 2 with id='241fc'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 45 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 10 m", AFTER MISSION
2025-02-22T01:28:53.822Z,1740187733.822 [NAL9602](INFO): SBD MO Status=0, MOMSN=13108, MT Status=0, MTMSN=0
2025-02-22T01:28:53.822Z,1740187733.822 [NAL9602](INFO): No messages in MT queue
2025-02-22T01:28:55.800Z,1740187735.800 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012407/Courier0027.lzma
2025-02-22T01:28:55.802Z,1740187735.802 [DataOverHttps](INFO): Received command: sched "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 241fc 2 2
2025-02-22T01:28:55.861Z,1740187735.861 [CommandExec](IMPORTANT): got command schedule "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 241fc 2 2.000000
2025-02-22T01:28:55.861Z,1740187735.861 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=241fc
2025-02-22T01:28:55.862Z,1740187735.862 [CommandExec](IMPORTANT): Scheduled #66 (#2 of 2 with id='241fc'): "set keepstation:BackseatDriver.EnableBackseat 1 bool;run", AFTER MISSION
2025-02-22T01:28:56.858Z,1740187736.858 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012407/Courier0027.lzma.bak
2025-02-22T01:28:56.859Z,1740187736.859 [DataOverHttps](IMPORTANT): SBD MOMSN=23971726, MTMSN=20250222T012855
2025-02-22T01:29:06.063Z,1740187746.063 [DataOverHttps](INFO): Received command: sched "load Transport/keepstation.tl;set keepstation.MissionTimeout 45 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 1 m" 241fp 1 2
2025-02-22T01:29:06.079Z,1740187746.079 [CommandExec](IMPORTANT): got command schedule "load Transport/keepstation.tl;set keepstation.MissionTimeout 45 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 1 m" 241fp 1 2.000000
2025-02-22T01:29:06.080Z,1740187746.080 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=241fp
2025-02-22T01:29:06.082Z,1740187746.082 [CommandExec](IMPORTANT): Scheduled #67 (#1 of 2 with id='241fp'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 45 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 1 m", AFTER MISSION
2025-02-22T01:29:09.147Z,1740187749.147 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T012906
2025-02-22T01:29:09.743Z,1740187749.743 [MassServo](ERROR): getPosition uart error serial timeout
2025-02-22T01:29:09.744Z,1740187749.744 [MassServo](FAULT): Invalid EZ Servo response:"\377"
2025-02-22T01:29:09.744Z,1740187749.744 [MassServo] Communications Fault, FailCount= 3
2025-02-22T01:29:09.744Z,1740187749.744 [MassServo](ERROR): Communications Fault
2025-02-22T01:29:09.748Z,1740187749.748 [CBIT](ERROR): Communications Fault in component: MassServo
2025-02-22T01:29:10.043Z,1740187750.043 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-02-22T01:29:10.043Z,1740187750.043 [MassServo](INFO): Powering down
2025-02-22T01:29:10.838Z,1740187750.838 [MassServo](DEBUG): Initializing EZServoServo.
2025-02-22T01:29:10.953Z,1740187750.953 [MassServo](DEBUG): Initializing MassServo.
2025-02-22T01:29:10.957Z,1740187750.957 [CBIT](INFO): Clearing failed state for component MassServo
2025-02-22T01:29:10.957Z,1740187750.957 [MassServo] No Fault, FailCount= 3
2025-02-22T01:29:18.468Z,1740187758.468 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250222T012655/Courier0005.lzma
2025-02-22T01:29:18.470Z,1740187758.470 [DataOverHttps](INFO): Received command: sched "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 241fp 2 2
2025-02-22T01:29:18.561Z,1740187758.561 [CommandExec](IMPORTANT): got command schedule "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 241fp 2 2.000000
2025-02-22T01:29:18.562Z,1740187758.562 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=241fp
2025-02-22T01:29:18.564Z,1740187758.564 [CommandExec](IMPORTANT): Scheduled #68 (#2 of 2 with id='241fp'): "set keepstation:BackseatDriver.EnableBackseat 1 bool;run", AFTER MISSION
2025-02-22T01:29:19.644Z,1740187759.644 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012655/Courier0005.lzma.bak
2025-02-22T01:29:19.644Z,1740187759.644 [DataOverHttps](IMPORTANT): SBD MOMSN=23971731, MTMSN=20250222T012918
2025-02-22T01:29:24.564Z,1740187764.564 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T01:29:27.095Z,1740187767.095 [DataOverHttps](INFO): Received command: sched "restart logs"
2025-02-22T01:29:27.105Z,1740187767.105 [CommandExec](IMPORTANT): got command schedule "restart logs"
2025-02-22T01:29:27.106Z,1740187767.106 [CommandExec](IMPORTANT): Scheduled #69: "restart logs", AFTER MISSION
2025-02-22T01:29:28.347Z,1740187768.347 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T012927
2025-02-22T01:29:35.683Z,1740187775.683 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012655/Courier0009.lzma
2025-02-22T01:29:35.685Z,1740187775.685 [DataOverHttps](INFO): Received command: sched "configset verticalcontrol.kdpitchmass 1.25 s persist; configset verticalcontrol.kipitchmass 1.25 1/s persist; configset verticalcontrol.kppitchmass 1.25 n/a persist; configset verticalcontrol.massonlyforceint 0 bool persist" 241gs 1 2
2025-02-22T01:29:35.778Z,1740187775.778 [CommandExec](IMPORTANT): got command schedule "configset verticalcontrol.kdpitchmass 1.25 s persist; configset verticalcontrol.kipitchmass 1.25 1/s persist; configset verticalcontrol.kppitchmass 1.25 n/a persist; configset verticalcontrol.massonlyforceint 0 bool persist" 241gs 1 2.000000
2025-02-22T01:29:35.779Z,1740187775.779 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=241gs
2025-02-22T01:29:35.789Z,1740187775.789 [CommandExec](IMPORTANT): Scheduled #70 (#1 of 2 with id='241gs'): "configset verticalcontrol.kdpitchmass 1.25 s persist; configset verticalcontrol.kipitchmass 1.25 1/s persist; configset verticalcontrol.kppitchmass 1.25 n/a persist; configset verticalcontrol.massonlyforceint 0 bool persist", AFTER MISSION
2025-02-22T01:29:35.803Z,1740187775.803 [BackseatComponent](IMPORTANT): IBASE811F-02: Running bioacoustics backseat app. Logging to: 20250222T012934
2025-02-22T01:29:36.941Z,1740187776.941 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012655/Courier0009.lzma.bak
2025-02-22T01:29:36.941Z,1740187776.941 [DataOverHttps](IMPORTANT): SBD MOMSN=23971738, MTMSN=20250222T012935
2025-02-22T01:29:44.323Z,1740187784.323 [DataOverHttps](INFO): Received command: sched " configset verticalcontrol.massdeadband 0.05 cm persist; configset verticalcontrol.kpdepthbuoy 120 ratio persist;" 241gs 2 2
2025-02-22T01:29:44.426Z,1740187784.426 [CommandExec](IMPORTANT): got command schedule " configset verticalcontrol.massdeadband 0.05 cm persist; configset verticalcontrol.kpdepthbuoy 120 ratio persist;" 241gs 2 2.000000
2025-02-22T01:29:44.427Z,1740187784.427 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=241gs
2025-02-22T01:29:44.428Z,1740187784.428 [CommandExec](IMPORTANT): Scheduled #71 (#2 of 2 with id='241gs'): " configset verticalcontrol.massdeadband 0.05 cm persist; configset verticalcontrol.kpdepthbuoy 120 ratio persist;", AFTER MISSION
2025-02-22T01:29:53.751Z,1740187793.751 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012655/Courier0012.lzma
2025-02-22T01:29:54.753Z,1740187794.753 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012655/Courier0012.lzma.bak
2025-02-22T01:29:54.753Z,1740187794.753 [DataOverHttps](INFO): SBD MOMSN=23971742
2025-02-22T01:30:11.339Z,1740187811.339 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012655/Courier0015.lzma
2025-02-22T01:30:12.341Z,1740187812.341 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012655/Courier0015.lzma.bak
2025-02-22T01:30:12.341Z,1740187812.341 [DataOverHttps](INFO): SBD MOMSN=23971744
2025-02-22T01:30:29.199Z,1740187829.199 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012655/Courier0018.lzma
2025-02-22T01:30:30.201Z,1740187830.201 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012655/Courier0018.lzma.bak
2025-02-22T01:30:30.201Z,1740187830.201 [DataOverHttps](INFO): SBD MOMSN=23971746
2025-02-22T01:30:36.103Z,1740187836.103 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1975
2025-02-22T01:30:47.020Z,1740187847.020 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250222T012831/Courier0006.lzma
2025-02-22T01:30:48.021Z,1740187848.021 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Courier0006.lzma.bak
2025-02-22T01:30:48.022Z,1740187848.022 [DataOverHttps](INFO): SBD MOMSN=23971748
2025-02-22T01:31:06.943Z,1740187866.943 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012831/Courier0009.lzma
2025-02-22T01:31:07.945Z,1740187867.945 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Courier0009.lzma.bak
2025-02-22T01:31:07.945Z,1740187867.945 [DataOverHttps](INFO): SBD MOMSN=23971752
2025-02-22T01:31:28.055Z,1740187888.055 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012831/Courier0012.lzma
2025-02-22T01:31:29.057Z,1740187889.057 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Courier0012.lzma.bak
2025-02-22T01:31:29.057Z,1740187889.057 [DataOverHttps](INFO): SBD MOMSN=23971754
2025-02-22T01:31:45.735Z,1740187905.735 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012831/Courier0015.lzma
2025-02-22T01:31:46.737Z,1740187906.737 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Courier0015.lzma.bak
2025-02-22T01:31:46.737Z,1740187906.737 [DataOverHttps](INFO): SBD MOMSN=23971756
2025-02-22T01:32:03.439Z,1740187923.439 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012831/Courier0018.lzma
2025-02-22T01:32:04.441Z,1740187924.441 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Courier0018.lzma.bak
2025-02-22T01:32:04.441Z,1740187924.441 [DataOverHttps](INFO): SBD MOMSN=23971758
2025-02-22T01:32:20.904Z,1740187940.904 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T012831/Courier0021.lzma
2025-02-22T01:32:21.905Z,1740187941.905 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Courier0021.lzma.bak
2025-02-22T01:32:21.905Z,1740187941.905 [DataOverHttps](INFO): SBD MOMSN=23971760
2025-02-22T01:32:38.595Z,1740187958.595 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20250222T002409/Express0028.lzma
2025-02-22T01:32:39.597Z,1740187959.597 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Express0028.lzma.bak
2025-02-22T01:32:39.597Z,1740187959.597 [DataOverHttps](INFO): SBD MOMSN=23971762
2025-02-22T01:32:57.043Z,1740187977.043 [DataOverHttps](INFO): Sending 330 bytes from file Logs/20250222T012407/Express0007.lzma
2025-02-22T01:32:58.045Z,1740187978.045 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012407/Express0007.lzma.bak
2025-02-22T01:32:58.046Z,1740187978.046 [DataOverHttps](INFO): SBD MOMSN=23971765
2025-02-22T01:33:05.415Z,1740187985.415 [MassServo](ERROR): getPosition uart error serial timeout
2025-02-22T01:33:05.749Z,1740187985.749 [MassServo](FAULT): Invalid EZ Servo response:"85\r"
2025-02-22T01:33:05.749Z,1740187985.749 [MassServo] Communications Fault, FailCount= 4
2025-02-22T01:33:05.749Z,1740187985.749 [MassServo](ERROR): Communications Fault
2025-02-22T01:33:05.758Z,1740187985.758 [CBIT](ERROR): Communications Fault in component: MassServo
2025-02-22T01:33:06.118Z,1740187986.118 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-02-22T01:33:06.118Z,1740187986.118 [MassServo](INFO): Powering down
2025-02-22T01:33:06.934Z,1740187986.934 [MassServo](DEBUG): Initializing EZServoServo.
2025-02-22T01:33:07.052Z,1740187987.052 [MassServo](DEBUG): Initializing MassServo.
2025-02-22T01:33:07.057Z,1740187987.057 [CBIT](INFO): Clearing failed state for component MassServo
2025-02-22T01:33:07.057Z,1740187987.057 [MassServo] No Fault, FailCount= 4
2025-02-22T01:33:18.916Z,1740187998.916 [DataOverHttps](INFO): Sending 447 bytes from file Logs/20250222T012407/Express0010.lzma
2025-02-22T01:33:19.917Z,1740187999.917 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012407/Express0010.lzma.bak
2025-02-22T01:33:19.917Z,1740187999.917 [DataOverHttps](INFO): SBD MOMSN=23971770
2025-02-22T01:33:36.752Z,1740188016.752 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20250222T012407/Express0013.lzma
2025-02-22T01:33:37.753Z,1740188017.753 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012407/Express0013.lzma.bak
2025-02-22T01:33:37.753Z,1740188017.753 [DataOverHttps](INFO): SBD MOMSN=23971782
2025-02-22T01:33:54.591Z,1740188034.591 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20250222T012407/Express0016.lzma
2025-02-22T01:33:55.577Z,1740188035.577 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012407/Express0016.lzma.bak
2025-02-22T01:33:55.577Z,1740188035.577 [DataOverHttps](INFO): SBD MOMSN=23971786
2025-02-22T01:34:12.283Z,1740188052.283 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20250222T012407/Express0019.lzma
2025-02-22T01:34:15.851Z,1740188055.851 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012407/Express0019.lzma.bak
2025-02-22T01:34:15.851Z,1740188055.851 [DataOverHttps](INFO): SBD MOMSN=23971789
2025-02-22T01:34:31.579Z,1740188071.579 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20250222T012407/Express0022.lzma
2025-02-22T01:34:32.581Z,1740188072.581 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012407/Express0022.lzma.bak
2025-02-22T01:34:32.581Z,1740188072.581 [DataOverHttps](INFO): SBD MOMSN=23971792
2025-02-22T01:34:36.584Z,1740188076.584 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.simrad_data_logging_rate with code: 1976
2025-02-22T01:34:49.826Z,1740188089.826 [DataOverHttps](INFO): Sending 505 bytes from file Logs/20250222T012407/Express0025.lzma
2025-02-22T01:34:50.626Z,1740188090.626 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012407/Express0025.lzma.bak
2025-02-22T01:34:50.626Z,1740188090.626 [DataOverHttps](INFO): SBD MOMSN=23971795
2025-02-22T01:35:10.135Z,1740188110.135 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20250222T012407/Express0028.lzma
2025-02-22T01:35:11.137Z,1740188111.137 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012407/Express0028.lzma.bak
2025-02-22T01:35:11.138Z,1740188111.138 [DataOverHttps](INFO): SBD MOMSN=23971816
2025-02-22T01:35:30.704Z,1740188130.704 [DataOverHttps](INFO): Sending 490 bytes from file Logs/20250222T012655/Express0007.lzma
2025-02-22T01:35:31.545Z,1740188131.545 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012655/Express0007.lzma.bak
2025-02-22T01:35:31.545Z,1740188131.545 [DataOverHttps](INFO): SBD MOMSN=23971821
2025-02-22T01:35:48.235Z,1740188148.235 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250222T012655/Express0010.lzma
2025-02-22T01:35:49.237Z,1740188149.237 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012655/Express0010.lzma.bak
2025-02-22T01:35:49.237Z,1740188149.237 [DataOverHttps](INFO): SBD MOMSN=23971830
2025-02-22T01:36:05.839Z,1740188165.839 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20250222T012655/Express0013.lzma
2025-02-22T01:36:06.841Z,1740188166.841 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012655/Express0013.lzma.bak
2025-02-22T01:36:06.842Z,1740188166.842 [DataOverHttps](INFO): SBD MOMSN=23971832
2025-02-22T01:36:27.080Z,1740188187.080 [DataOverHttps](INFO): Sending 531 bytes from file Logs/20250222T012655/Express0016.lzma
2025-02-22T01:36:28.081Z,1740188188.081 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012655/Express0016.lzma.bak
2025-02-22T01:36:28.081Z,1740188188.081 [DataOverHttps](INFO): SBD MOMSN=23971835
2025-02-22T01:36:45.179Z,1740188205.179 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250222T012655/Express0019.lzma
2025-02-22T01:36:46.181Z,1740188206.181 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012655/Express0019.lzma.bak
2025-02-22T01:36:46.181Z,1740188206.181 [DataOverHttps](INFO): SBD MOMSN=23971853
2025-02-22T01:37:03.454Z,1740188223.454 [DataOverHttps](INFO): Sending 676 bytes from file Logs/20250222T012831/Express0007.lzma
2025-02-22T01:37:04.453Z,1740188224.453 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Express0007.lzma.bak
2025-02-22T01:37:04.453Z,1740188224.453 [DataOverHttps](INFO): SBD MOMSN=23971855
2025-02-22T01:37:25.120Z,1740188245.120 [DataOverHttps](INFO): Sending 447 bytes from file Logs/20250222T012831/Express0010.lzma
2025-02-22T01:37:26.121Z,1740188246.121 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Express0010.lzma.bak
2025-02-22T01:37:26.121Z,1740188246.121 [DataOverHttps](INFO): SBD MOMSN=23971873
2025-02-22T01:37:43.747Z,1740188263.747 [DataOverHttps](INFO): Sending 310 bytes from file Logs/20250222T012831/Express0013.lzma
2025-02-22T01:37:44.750Z,1740188264.750 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Express0013.lzma.bak
2025-02-22T01:37:44.751Z,1740188264.751 [DataOverHttps](INFO): SBD MOMSN=23971879
2025-02-22T01:38:01.340Z,1740188281.340 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250222T012831/Express0016.lzma
2025-02-22T01:38:02.341Z,1740188282.341 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Express0016.lzma.bak
2025-02-22T01:38:02.341Z,1740188282.341 [DataOverHttps](INFO): SBD MOMSN=23971886
2025-02-22T01:38:20.558Z,1740188300.558 [DataOverHttps](INFO): Sending 376 bytes from file Logs/20250222T012831/Express0019.lzma
2025-02-22T01:38:21.561Z,1740188301.561 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Express0019.lzma.bak
2025-02-22T01:38:21.561Z,1740188301.561 [DataOverHttps](INFO): SBD MOMSN=23971891
2025-02-22T01:38:38.196Z,1740188318.196 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20250222T012831/Express0022.lzma
2025-02-22T01:38:39.197Z,1740188319.197 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Express0022.lzma.bak
2025-02-22T01:38:39.197Z,1740188319.197 [DataOverHttps](INFO): SBD MOMSN=23971898
2025-02-22T01:38:40.732Z,1740188320.732 [keepstation:NeedComms:D] Stopped
2025-02-22T01:38:40.733Z,1740188320.733 [keepstation:NeedComms:E] Running Loop=1
2025-02-22T01:38:41.133Z,1740188321.133 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-22T01:38:42.738Z,1740188322.738 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013842.00,A,3647.44787,N,12150.87089,W,1.497,170.95,220225,,,A*73
2025-02-22T01:38:42.741Z,1740188322.741 [NAL9602](INFO): GPS fix at 20250222T013842: (36.790798, -121.847848)
2025-02-22T01:38:42.789Z,1740188322.789 [keepstation:NeedComms:E] Stopped
2025-02-22T01:38:42.790Z,1740188322.790 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-22T01:38:42.790Z,1740188322.790 [keepstation:NeedComms] Stopped
2025-02-22T01:38:42.790Z,1740188322.790 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-22T01:38:42.790Z,1740188322.790 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-22T01:38:42.790Z,1740188322.790 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T01:38:43.181Z,1740188323.181 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-02-22T01:38:43.181Z,1740188323.181 [keepstation:StartingMission] Stopped
2025-02-22T01:38:43.181Z,1740188323.181 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-02-22T01:38:43.181Z,1740188323.181 [keepstation:TransitToStation] Running Loop=1
2025-02-22T01:38:43.181Z,1740188323.181 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-02-22T01:38:43.181Z,1740188323.181 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-22T01:38:43.181Z,1740188323.181 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-22T01:38:43.182Z,1740188323.182 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-22T01:38:43.182Z,1740188323.182 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2025-02-22T01:38:43.182Z,1740188323.182 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-22T01:38:43.182Z,1740188323.182 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2025-02-22T01:38:43.182Z,1740188323.182 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-02-22T01:38:43.182Z,1740188323.182 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-22T01:38:43.211Z,1740188323.211 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-02-22T01:38:43.566Z,1740188323.566 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-02-22T01:38:43.571Z,1740188323.571 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-22T01:38:43.572Z,1740188323.572 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-22T01:38:43.572Z,1740188323.572 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-22T01:39:06.799Z,1740188346.799 [NAL9602](INFO): SBD MO Status=2, MOMSN=13109, MT Status=2, MTMSN=0
2025-02-22T01:39:06.799Z,1740188346.799 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T01:39:17.613Z,1740188357.613 [NAL9602](INFO): SBD MO Status=0, MOMSN=13109, MT Status=0, MTMSN=0
2025-02-22T01:39:17.614Z,1740188357.614 [NAL9602](INFO): No messages in MT queue
2025-02-22T01:39:48.199Z,1740188388.199 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T01:40:42.299Z,1740188442.299 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:40:57.283Z,1740188457.283 [Radio_Surface](INFO): Powering down
2025-02-22T01:41:13.423Z,1740188473.423 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:41:14.428Z,1740188474.428 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-22T01:42:51.832Z,1740188571.832 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.738708
2025-02-22T01:54:57.719Z,1740189297.719 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000
2025-02-22T01:54:57.720Z,1740189297.720 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-02-22T01:54:57.720Z,1740189297.720 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-22T01:54:57.721Z,1740189297.721 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-02-22T01:54:57.721Z,1740189297.721 [keepstation:TransitToStation] Stopped
2025-02-22T01:54:57.721Z,1740189297.721 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-02-22T01:54:57.721Z,1740189297.721 [keepstation:TransitToStation:A.Buoyancy] Stopped
2025-02-22T01:54:57.721Z,1740189297.721 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-22T01:54:57.721Z,1740189297.721 [keepstation:TransitToStation:B.Pitch] Stopped
2025-02-22T01:54:57.721Z,1740189297.721 [keepstation:TransitToStation:C.SetSpeed] Stopped
2025-02-22T01:54:57.721Z,1740189297.721 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2025-02-22T01:54:57.722Z,1740189297.722 [keepstation:OnStation] Running Loop=1
2025-02-22T01:54:57.722Z,1740189297.722 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-02-22T01:54:58.128Z,1740189298.128 [keepstation:NeedComms] Running Loop=1
2025-02-22T01:54:58.128Z,1740189298.128 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-22T01:54:58.128Z,1740189298.128 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-22T01:54:58.128Z,1740189298.128 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T01:54:58.129Z,1740189298.129 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-22T01:54:58.129Z,1740189298.129 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-22T01:54:58.129Z,1740189298.129 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-22T01:54:58.130Z,1740189298.130 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-22T01:54:58.130Z,1740189298.130 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T01:54:58.130Z,1740189298.130 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T01:54:58.131Z,1740189298.131 [keepstation:NeedComms:A] Running Loop=1
2025-02-22T01:54:58.132Z,1740189298.132 [keepstation:NeedComms:A](INFO): last time_fix was:  1740188322.000000 second since 1970/01/01T00:00:00Z 
2025-02-22T01:54:58.132Z,1740189298.132 [keepstation:NeedComms:A] Stopped
2025-02-22T01:55:49.325Z,1740189349.325 [keepstation:NeedComms:C] Running Loop=1
2025-02-22T01:55:50.103Z,1740189350.103 [Radio_Surface](INFO): Powering up
2025-02-22T01:55:56.063Z,1740189356.063 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-22T01:56:11.118Z,1740189371.118 [NAL9602](INFO): SBD MO Status=2, MOMSN=13110, MT Status=2, MTMSN=0
2025-02-22T01:56:11.118Z,1740189371.118 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T01:56:26.183Z,1740189386.183 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:56:27.686Z,1740189387.686 [NAL9602](INFO): SBD MO Status=0, MOMSN=13110, MT Status=0, MTMSN=0
2025-02-22T01:56:27.686Z,1740189387.686 [NAL9602](INFO): No messages in MT queue
2025-02-22T01:56:28.902Z,1740189388.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015628.00,A,3647.67248,N,12150.79696,W,0.719,140.82,220225,,,A*78
2025-02-22T01:56:28.904Z,1740189388.904 [NAL9602](INFO): GPS fix at 20250222T015628: (36.794541, -121.846616)
2025-02-22T01:56:28.917Z,1740189388.917 [UniversalFixResidualReporter](INFO): Fix residual: 39.9 %DT, over the last 757.9 m. Residual distance 302.5 m at bearing -0.3 degrees. Fix at (36.7945, -121.8466) with 430.5 m made good.
2025-02-22T01:56:28.920Z,1740189388.920 [keepstation:NeedComms:C] Stopped
2025-02-22T01:56:28.920Z,1740189388.920 [keepstation:NeedComms:D] Running Loop=1
2025-02-22T01:56:57.351Z,1740189417.351 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:56:58.321Z,1740189418.321 [NAL9602](INFO): SBD MO Status=2, MOMSN=13111, MT Status=0, MTMSN=0
2025-02-22T01:56:58.321Z,1740189418.321 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T01:57:10.770Z,1740189430.770 [NAL9602](INFO): SBD MO Status=1, MOMSN=13111, MT Status=0, MTMSN=0
2025-02-22T01:57:10.819Z,1740189430.819 [NAL9602](INFO): Sent 205 bytes from file Logs/20250222T012831/Courier0024.lzma
2025-02-22T01:57:10.820Z,1740189430.820 [NAL9602](INFO): Packets left to send: 0
2025-02-22T01:57:23.434Z,1740189443.434 [NAL9602](INFO): SBD MO Status=1, MOMSN=13112, MT Status=0, MTMSN=0
2025-02-22T01:57:23.488Z,1740189443.488 [NAL9602](INFO): Sent 290 bytes from file Logs/20250222T012831/Express0025.lzma
2025-02-22T01:57:23.488Z,1740189443.488 [NAL9602](INFO): Packets left to send: 0
2025-02-22T01:57:28.476Z,1740189448.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:57:29.698Z,1740189449.698 [NAL9602](INFO): SBD MO Status=0, MOMSN=13113, MT Status=0, MTMSN=0
2025-02-22T01:57:29.788Z,1740189449.788 [keepstation:NeedComms:D] Stopped
2025-02-22T01:57:29.789Z,1740189449.789 [keepstation:NeedComms:E] Running Loop=1
2025-02-22T01:57:32.118Z,1740189452.118 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015731.00,A,3647.66128,N,12150.79006,W,0.739,135.31,220225,,,A*72
2025-02-22T01:57:32.121Z,1740189452.121 [NAL9602](INFO): GPS fix at 20250222T015731: (36.794355, -121.846501)
2025-02-22T01:57:32.152Z,1740189452.152 [keepstation:NeedComms:E] Stopped
2025-02-22T01:57:32.153Z,1740189452.153 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-22T01:57:32.153Z,1740189452.153 [keepstation:NeedComms] Stopped
2025-02-22T01:57:32.153Z,1740189452.153 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-22T01:57:32.153Z,1740189452.153 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-22T01:57:32.153Z,1740189452.153 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T01:57:32.554Z,1740189452.554 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-02-22T01:57:32.554Z,1740189452.554 [keepstation:OnStation] Stopped
2025-02-22T01:57:32.554Z,1740189452.554 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-02-22T01:57:32.554Z,1740189452.554 [keepstation:KeepStation] Running Loop=1
2025-02-22T01:57:32.554Z,1740189452.554 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-02-22T01:57:32.555Z,1740189452.555 [keepstation:KeepStation:A] Running Loop=1
2025-02-22T01:57:32.555Z,1740189452.555 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-22T01:57:32.555Z,1740189452.555 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize.
2025-02-22T01:57:32.555Z,1740189452.555 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-22T01:57:32.555Z,1740189452.555 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-02-22T01:57:32.556Z,1740189452.556 [keepstation:KeepStation:D.Wait] Running Loop=1
2025-02-22T01:57:32.556Z,1740189452.556 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component.
2025-02-22T01:57:32.937Z,1740189452.937 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-22T01:57:32.938Z,1740189452.938 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint
2025-02-22T01:57:32.938Z,1740189452.938 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-22T01:57:32.939Z,1740189452.939 [keepstation:KeepStation:A] Running Loop=1
2025-02-22T01:57:59.601Z,1740189479.601 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:58:04.530Z,1740189484.530 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T01:58:14.210Z,1740189494.210 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009230
2025-02-22T01:59:01.388Z,1740189541.388 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:59:26.051Z,1740189566.051 [Radio_Surface](INFO): Powering down
2025-02-22T01:59:32.512Z,1740189572.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:59:33.516Z,1740189573.516 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-22T02:01:14.932Z,1740189674.932 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.730392
2025-02-22T02:05:54.032Z,1740189954.032 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T02:06:06.722Z,1740189966.722 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T02:06:20.171Z,1740189980.171 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T02:06:32.678Z,1740189992.678 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T02:06:45.250Z,1740190005.250 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T02:06:58.148Z,1740190018.148 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T02:07:11.254Z,1740190031.254 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T02:07:56.037Z,1740190076.037 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest
2025-02-22T02:08:22.394Z,1740190102.394 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-02-22T02:12:16.315Z,1740190336.315 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-22T02:12:19.972Z,1740190339.972 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-22T02:12:19.973Z,1740190339.973 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-22T02:12:19.975Z,1740190339.975 [BPC1](INFO): Received data from all battery sticks.
2025-02-22T02:13:31.874Z,1740190411.874 [keepstation](INFO): Timed out from 2025-02-22T01:28:31.5Z
2025-02-22T02:13:31.875Z,1740190411.875 [MissionManager](INFO): keepstation is completed.
2025-02-22T02:13:31.891Z,1740190411.891 [MissionManager](INFO): Uninitializing Mission keepstation
2025-02-22T02:13:31.891Z,1740190411.891 [keepstation] Stopped
2025-02-22T02:13:31.891Z,1740190411.891 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-02-22T02:13:31.891Z,1740190411.891 [keepstation:StandardEnvelopes] Stopped
2025-02-22T02:13:31.891Z,1740190411.891 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:BackseatDriver] Stopped
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:PowerOnly] Stopped
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-02-22T02:13:31.892Z,1740190411.892 [keepstation:PowerOnly:E.Wait] Stopped
2025-02-22T02:13:31.893Z,1740190411.893 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T02:13:31.893Z,1740190411.893 [keepstation:H.Pitch] Stopped
2025-02-22T02:13:31.893Z,1740190411.893 [keepstation:KeepStation] Stopped
2025-02-22T02:13:31.893Z,1740190411.893 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-02-22T02:13:31.893Z,1740190411.893 [keepstation:KeepStation:A] Stopped
2025-02-22T02:13:31.893Z,1740190411.893 [keepstation:KeepStation:B.Pitch] Stopped
2025-02-22T02:13:31.893Z,1740190411.893 [keepstation:KeepStation:C.KeepStation] Stopped
2025-02-22T02:13:31.893Z,1740190411.893 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-02-22T02:13:31.893Z,1740190411.893 [keepstation:KeepStation:D.Wait] Stopped
2025-02-22T02:13:31.893Z,1740190411.893 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T02:13:31.948Z,1740190411.948 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-02-22T02:13:32.020Z,1740190412.020 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-02-22T02:13:32.020Z,1740190412.020 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-02-22T02:13:32.022Z,1740190412.022 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-02-22T02:13:32.879Z,1740190412.879 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="keepstation">
    <DefineArg Name="MissionTimeout">
        <Units:hour />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="NeedCommsTime">
        <Units:minute />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="Latitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Longitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Depth">
        <Units:meter />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="ApproachDepth">
        <Units:meter />
        <Value>15</Value>
    </DefineArg>
    <DefineArg Name="DepthDeadband">
        <Units:meter />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="Speed">
        <Units:meter_per_second />
        <Value>0.75</Value>
    </DefineArg>
    <DefineArg Name="Radius">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>2</Value>
    </DefineArg>
    <Timeout Duration="MissionTimeout" />
    <Insert Id="NeedComms" Filename="Insert/NeedComms.tl" />
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:DiveInterval" />
        <Arg Name="NeedCommsTime" />
    </Assign>
    <Insert Filename="Insert/StandardEnvelopes.tl" />
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MaxDepth" />
        <Arg Name="MaxDepth" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinOffshore" />
        <Arg Name="MinOffshore" />
    </Assign>
    <Insert Filename="Insert/BackseatDriver.tl" />
    <Insert Filename="Insert/PowerOnly.tl" />
    <Guidance:Pitch>
        <Parallel />
        <Setting>
            <Guidance:Pitch.massPosition />
            <Control:VerticalControl.massDefault />
        </Setting>
    </Guidance:Pitch>
    <Call RefId="NeedComms" Id="StartingMission" />
    <Aggregate Id="TransitToStation">
        <Sequence />
        <Guidance:Buoyancy>
            <Parallel />
            <Setting>
                <Guidance:Buoyancy.position />
                <Control:VerticalControl.buoyancyNeutral />
            </Setting>
        </Guidance:Buoyancy>
        <Guidance:Pitch>
            <Parallel />
            <Setting>
                <Guidance:Pitch.depth />
                <Arg Name="ApproachDepth" />
            </Setting>
        </Guidance:Pitch>
        <Guidance:SetSpeed>
            <Parallel />
            <Setting>
                <Guidance:SetSpeed.speed />
                <Arg Name="Speed" />
            </Setting>
        </Guidance:SetSpeed>
        <Guidance:Waypoint Id="Wpt1">
            <Sequence />
            <Setting>
                <Guidance:Waypoint.latitude />
                <Arg Name="Latitude" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.longitude />
                <Arg Name="Longitude" />
            </Setting>
        </Guidance:Waypoint>
    </Aggregate>
    <Call RefId="NeedComms" Id="OnStation" />
    <Aggregate Id="KeepStation">
        <Sequence />
        <Assign>
            <Parallel />
            <Control:VerticalControl.depthDeadband />
            <Arg Name="DepthDeadband" />
        </Assign>
        <Guidance:Pitch>
            <Parallel />
            <Setting>
                <Guidance:Pitch.depth />
                <Arg Name="Depth" />
            </Setting>
        </Guidance:Pitch>
        <Guidance:KeepStation>
            <Parallel />
            <Setting>
                <Guidance:KeepStation.latitude />
                <Arg Name="Latitude" />
            </Setting>
            <Setting>
                <Guidance:KeepStation.longitude />
                <Arg Name="Longitude" />
            </Setting>
            <Setting>
                <Guidance:KeepStation.radius />
                <Arg Name="Radius" />
            </Setting>
            <Setting>
                <Guidance:KeepStation.speed />
                <Arg Name="Speed" />
            </Setting>
        </Guidance:KeepStation>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Arg Name="MissionTimeout" />
            </Setting>
        </Guidance:Wait>
    </Aggregate>
</Mission>

2025-02-22T02:13:32.884Z,1740190412.884 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-02-22T02:13:32.886Z,1740190412.886 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-02-22T02:13:32.889Z,1740190412.889 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-02-22T02:13:32.891Z,1740190412.891 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-02-22T02:13:32.893Z,1740190412.893 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-02-22T02:13:32.915Z,1740190412.915 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-02-22T02:13:32.918Z,1740190412.918 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-02-22T02:13:32.920Z,1740190412.920 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-02-22T02:13:32.922Z,1740190412.922 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-02-22T02:13:32.941Z,1740190412.941 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-02-22T02:13:32.941Z,1740190412.941 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-02-22T02:13:32.947Z,1740190412.947 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-02-22T02:13:32.950Z,1740190412.950 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-02-22T02:13:32.950Z,1740190412.950 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-22T02:13:32.950Z,1740190412.950 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-22T02:13:32.960Z,1740190412.960 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-22T02:13:33.230Z,1740190413.230 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="NeedComms">
    <DefineArg Name="DiveInterval">
        <Units:hour />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="WaitForPitchUp">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SurfacePitch">
        <Units:degree />
        <Value>20</Value>
    </DefineArg>
    <DefineArg Name="SurfaceDepthRate">
        <Units:meter_per_second />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="SurfaceSpeed">
        <Units:meter_per_second />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="SurfacingTimeout">
        <Units:second />
        <Value>1000</Value>
    </DefineArg>
    <DefineArg Name="GPSTimeout">
        <Units:minute />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="CommsTimeout">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <When>
        <Called />
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
                <Add>
                    <Arg Name="WaitForPitchUp" />
                </Add>
            </Gt>
        </Or>
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
            </Gt>
            <And>
                <Universal:platform_pitch_angle />
                <Gt>
                    <Units:degree />
                    <Value>0</Value>
                </Gt>
            </And>
        </Or>
    </When>
    <Syslog Severity="Info">
        last time_fix was: 
        <Universal:time_fix />
        <Units:epoch_second />
    </Syslog>
    <Guidance:GoToSurface>
        <Progression />
        <Setting>
            <Guidance:GoToSurface.pitch />
            <Arg Name="SurfacePitch" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.depthRate />
            <Arg Name="SurfaceDepthRate" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.speed />
            <Arg Name="SurfaceSpeed" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.surfaceTimeout />
            <Arg Name="SurfacingTimeout" />
        </Setting>
    </Guidance:GoToSurface>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">first GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="CommsTimeout">
            <Syslog Severity="Important">
                Comms timed out after 
                <Arg Name="CommsTimeout" />
                <Units:minute />
                minutes
            </Syslog>
        </Timeout>
        <Universal:platform_communications />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">second GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
</Aggregate>

2025-02-22T02:13:33.235Z,1740190413.235 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-02-22T02:13:33.237Z,1740190413.237 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-22T02:13:33.240Z,1740190413.240 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-22T02:13:33.242Z,1740190413.242 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-02-22T02:13:33.251Z,1740190413.251 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-22T02:13:33.255Z,1740190413.255 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-22T02:13:33.257Z,1740190413.257 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-02-22T02:13:33.292Z,1740190413.292 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-02-22T02:13:33.302Z,1740190413.302 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-22T02:13:33.335Z,1740190413.335 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-22T02:13:33.336Z,1740190413.336 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-22T02:13:33.337Z,1740190413.337 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-22T02:13:33.412Z,1740190413.412 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="StandardEnvelopes">
    <DefineArg Name="MinAltitude">
        <Units:meter />
        <Value>5</Value>
    </DefineArg>
    <DefineArg Name="MaxDepthIgnore">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:meter />
        <Value>2000</Value>
    </DefineArg>
    <Parallel />
    <Guidance:AltitudeEnvelope>
        <Parallel />
        <Setting>
            <Guidance:AltitudeEnvelope.minAltitude />
            <Arg Name="MinAltitude" />
        </Setting>
        <Setting>
            <Guidance:AltitudeEnvelope.maxDepthIgnore />
            <Arg Name="MaxDepthIgnore" />
        </Setting>
    </Guidance:AltitudeEnvelope>
    <Guidance:DepthEnvelope>
        <Parallel />
        <Setting>
            <Guidance:DepthEnvelope.maxDepth />
            <Arg Name="MaxDepth" />
        </Setting>
    </Guidance:DepthEnvelope>
    <Guidance:OffshoreEnvelope>
        <Parallel />
        <Setting>
            <Guidance:OffshoreEnvelope.minOffshore />
            <Arg Name="MinOffshore" />
        </Setting>
    </Guidance:OffshoreEnvelope>
</Aggregate>

2025-02-22T02:13:33.416Z,1740190413.416 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-22T02:13:33.418Z,1740190413.418 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-22T02:13:33.434Z,1740190413.434 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-22T02:13:33.436Z,1740190413.436 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-22T02:13:33.437Z,1740190413.437 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-22T02:13:33.443Z,1740190413.443 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-22T02:13:33.449Z,1740190413.449 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-22T02:13:33.456Z,1740190413.456 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-22T02:13:33.457Z,1740190413.457 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-22T02:13:33.458Z,1740190413.458 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-22T02:13:33.548Z,1740190413.548 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="BackseatDriver">
    <DefineArg Name="EnableBackseat">
        <False />
    </DefineArg>
    <Parallel />
    <Guidance:BackseatDriver>
        <Sequence />
        <Setting>
            <Guidance:BackseatDriver.powerBackseat />
            <Arg Name="EnableBackseat" />
        </Setting>
    </Guidance:BackseatDriver>
</Aggregate>

2025-02-22T02:13:33.552Z,1740190413.552 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-02-22T02:13:33.554Z,1740190413.554 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-22T02:13:33.558Z,1740190413.558 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-22T02:13:33.558Z,1740190413.558 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-22T02:13:33.560Z,1740190413.560 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-22T02:13:33.697Z,1740190413.697 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="PowerOnly">
    <DefineArg Name="SampleAll">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad1">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad2">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad3">
        <False />
    </DefineArg>
    <DefineArg Name="EnabledPowerOnly">
        <Sensor:PowerOnly.loadAtStartup />
    </DefineArg>
    <Parallel />
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleAll" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.samplePowerOnly />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad1" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad1 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad2" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad2 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad3" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad3 />
    </ReadData>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:hour />
            <Value>99999</Value>
        </Setting>
    </Guidance:Wait>
</Aggregate>

2025-02-22T02:13:33.702Z,1740190413.702 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-02-22T02:13:33.704Z,1740190413.704 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-02-22T02:13:33.707Z,1740190413.707 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-02-22T02:13:33.711Z,1740190413.711 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-02-22T02:13:33.714Z,1740190413.714 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-22T02:13:33.721Z,1740190413.721 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-22T02:13:33.722Z,1740190413.722 [keepstation:H.Pitch](DEBUG): Construct.
2025-02-22T02:13:33.729Z,1740190413.729 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-22T02:13:33.756Z,1740190413.756 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct.
2025-02-22T02:13:33.765Z,1740190413.765 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct.
2025-02-22T02:13:33.775Z,1740190413.775 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-22T02:13:33.786Z,1740190413.786 [keepstation:KeepStation:B.Pitch](DEBUG): Construct.
2025-02-22T02:13:33.791Z,1740190413.791 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation.
2025-02-22T02:13:33.804Z,1740190413.804 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait.
2025-02-22T02:13:33.828Z,1740190413.828 [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 keepstation {
  """
  Vehicle transits to desired waypoint (or stays put if none is commanded)
  and stays within the specified radius.
  """

  arguments {
    MissionTimeout = 4 hour
      """
      Maximum duration of mission
      """

    NeedCommsTime = 45 minute
      """
      How often to surface for communications
      """

    Latitude = NaN degree
      """
      Latitude of waypoint to hold. If NaN, hold the latitude at start of
      mission.
      """

    Longitude = NaN degree
      """
      Longitude of waypoint to hold. If NaN, hold the longitude at start of
      mission.
      """

    Depth = 30 meter
      """
      Depth held during drift mode
      """

    ApproachDepth = 15 meter
      """
      Depth for initial approach to station.
      """

    DepthDeadband = 4 meter
      """
      How much vertical drift from the specified depth is allowed in drift
      mode
      """

    Speed = 0.75 meter_per_second
      """
      Vehicle transit speed.
      """

    Radius = 200 meter
      """
      Radius of circle around waypoint to hold.
      """

    MaxDepth = 45 meter
      """
      Maximum allowable depth during the mission.
      """

    MinOffshore = 2 kilometer
      """
      Minimum distance from the shoreline to maintain
      """
  }

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

  insert Insert/PowerOnly.tl

  behavior Guidance:Pitch {
    run in parallel

    set massPosition = Control:VerticalControl.massDefault
  }

  call id="StartingMission" refId="NeedComms"

  aggregate TransitToStation {
    """
    Need a separate aggregate for transit if we want to specify a
    different depth for the approach.
    """

    run in sequence

    behavior Guidance:Buoyancy {
      run in parallel

      set position = Control:VerticalControl.buoyancyNeutral
    }

    behavior Guidance:Pitch {
      run in parallel

      set depth = ApproachDepth
    }

    behavior Guidance:SetSpeed {
      run in parallel

      set speed = Speed
    }

    behavior Guidance:Waypoint id="Wpt1" {
      run in sequence

      set latitude = Latitude
      set longitude = Longitude
    }
  }

  call id="OnStation" refId="NeedComms"

  aggregate KeepStation {
    run in sequence

    assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband

    behavior Guidance:Pitch {
      run in parallel

      set depth = Depth
    }

    behavior Guidance:KeepStation {
      run in parallel

      set latitude = Latitude
      set longitude = Longitude
      set radius = Radius
      set speed = Speed
    }

    behavior Guidance:Wait {
      """
      Due to the way the KeepStation behavior is currently
      implemented, we must run it in parallel and use a Wait to keep
      from completing the mission before the timeout expires. However,
      this new version of the mission uses a top-level timeout so that
      the entire mission will not run for longer then MissionTimeout.
      """

      run in sequence

      set duration = MissionTimeout
    }
  }
}


2025-02-22T02:13:33.828Z,1740190413.828 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-02-22T02:13:33.909Z,1740190413.909 [MissionManager](IMPORTANT): Started mission Default
2025-02-22T02:13:33.909Z,1740190413.909 [Default] Running Loop=1
2025-02-22T02:13:33.910Z,1740190413.910 [Default](DEBUG): Aggregate::initialize Default
2025-02-22T02:13:33.910Z,1740190413.910 [Default:B.GoToSurface] Running Loop=1
2025-02-22T02:13:33.910Z,1740190413.910 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T02:13:33.910Z,1740190413.910 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-22T02:13:33.910Z,1740190413.910 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-22T02:13:33.911Z,1740190413.911 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-22T02:13:33.915Z,1740190413.915 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-22T02:13:33.915Z,1740190413.915 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T02:13:33.916Z,1740190413.916 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T02:13:33.916Z,1740190413.916 [Default:A.Wait] Running Loop=1
2025-02-22T02:13:33.916Z,1740190413.916 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-22T02:13:34.568Z,1740190414.568 [Radio_Surface](INFO): Powering up
2025-02-22T02:13:39.983Z,1740190419.983 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-22T02:13:47.085Z,1740190427.085 [Default:A.Wait](INFO): Done Waiting.
2025-02-22T02:13:47.085Z,1740190427.085 [Default:A.Wait] Stopped
2025-02-22T02:13:47.085Z,1740190427.085 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T02:13:51.128Z,1740190431.128 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2025-02-22T02:13:51.129Z,1740190431.129 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2025-02-22T02:13:51.129Z,1740190431.129 [LcmUniversalReporter](INFO): Deactivating messaging.
2025-02-22T02:14:00.992Z,1740190440.992 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 45 minute
2025-02-22T02:14:00.994Z,1740190440.994 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute
2025-02-22T02:14:00.994Z,1740190440.994 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree
2025-02-22T02:14:00.995Z,1740190440.995 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree
2025-02-22T02:14:00.996Z,1740190440.996 [CommandExec](IMPORTANT): got command set keepstation.Depth 5 meter
2025-02-22T02:14:10.104Z,1740190450.104 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:14:17.037Z,1740190457.037 [CommandExec](IMPORTANT): got command set keepstation.DepthDeadband 1 meter
2025-02-22T02:14:17.038Z,1740190457.038 [CommandExec](IMPORTANT): got command set keepstation:BackseatDriver.EnableBackseat 1 bool
2025-02-22T02:14:17.039Z,1740190457.039 [CommandExec](IMPORTANT): got command run
2025-02-22T02:14:17.041Z,1740190457.041 [CommandExec](IMPORTANT): Running 
2025-02-22T02:14:17.207Z,1740190457.207 [Default] Stopped
2025-02-22T02:14:17.207Z,1740190457.207 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-22T02:14:17.207Z,1740190457.207 [Default:B.GoToSurface] Stopped
2025-02-22T02:14:17.207Z,1740190457.207 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T02:14:17.207Z,1740190457.207 [MissionManager](IMPORTANT): Started mission keepstation
2025-02-22T02:14:17.208Z,1740190457.208 [keepstation] Running Loop=1
2025-02-22T02:14:17.208Z,1740190457.208 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-02-22T02:14:17.208Z,1740190457.208 [keepstation:StandardEnvelopes] Running Loop=1
2025-02-22T02:14:17.208Z,1740190457.208 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-02-22T02:14:17.208Z,1740190457.208 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-22T02:14:17.208Z,1740190457.208 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-22T02:14:17.208Z,1740190457.208 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-22T02:14:17.208Z,1740190457.208 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:BackseatDriver] Running Loop=1
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:PowerOnly] Running Loop=1
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:PowerOnly:A] Running Loop=1
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:PowerOnly:B] Running Loop=1
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:PowerOnly:C] Running Loop=1
2025-02-22T02:14:17.209Z,1740190457.209 [keepstation:PowerOnly:D] Running Loop=1
2025-02-22T02:14:17.210Z,1740190457.210 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-02-22T02:14:17.210Z,1740190457.210 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-22T02:14:17.210Z,1740190457.210 [keepstation:H.Pitch] Running Loop=1
2025-02-22T02:14:17.210Z,1740190457.210 [keepstation:H.Pitch](DEBUG): Initialize.
2025-02-22T02:14:17.210Z,1740190457.210 [keepstation:B] Running Loop=1
2025-02-22T02:14:17.211Z,1740190457.211 [keepstation:H.Pitch] Running Loop=1
2025-02-22T02:14:17.211Z,1740190457.211 [keepstation:PowerOnly] Running Loop=1
2025-02-22T02:14:17.211Z,1740190457.211 [keepstation:PowerOnly:D] Stopped
2025-02-22T02:14:17.211Z,1740190457.211 [keepstation:PowerOnly:C] Stopped
2025-02-22T02:14:17.212Z,1740190457.212 [keepstation:PowerOnly:B] Stopped
2025-02-22T02:14:17.212Z,1740190457.212 [keepstation:PowerOnly:A] Stopped
2025-02-22T02:14:17.212Z,1740190457.212 [keepstation:BackseatDriver] Running Loop=1
2025-02-22T02:14:17.212Z,1740190457.212 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-02-22T02:14:17.226Z,1740190457.226 [keepstation:StandardEnvelopes] Running Loop=1
2025-02-22T02:14:17.226Z,1740190457.226 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-22T02:14:17.227Z,1740190457.227 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-22T02:14:17.227Z,1740190457.227 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-22T02:14:17.228Z,1740190457.228 [keepstation:B] Stopped
2025-02-22T02:14:17.228Z,1740190457.228 [keepstation:D] Running Loop=1
2025-02-22T02:14:17.339Z,1740190457.339 [BackseatComponent](INFO): Powering up
2025-02-22T02:14:17.340Z,1740190457.340 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-02-22T02:14:17.643Z,1740190457.643 [keepstation:D] Stopped
2025-02-22T02:14:17.643Z,1740190457.643 [keepstation:E] Running Loop=1
2025-02-22T02:14:18.047Z,1740190458.047 [keepstation:E] Stopped
2025-02-22T02:14:18.047Z,1740190458.047 [keepstation:StartingMission] Running Loop=1
2025-02-22T02:14:18.048Z,1740190458.048 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-02-22T02:14:18.452Z,1740190458.452 [keepstation:NeedComms] Running Loop=1
2025-02-22T02:14:18.452Z,1740190458.452 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-22T02:14:18.452Z,1740190458.452 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-22T02:14:18.452Z,1740190458.452 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T02:14:18.452Z,1740190458.452 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-22T02:14:18.453Z,1740190458.453 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-22T02:14:18.453Z,1740190458.453 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-22T02:14:18.454Z,1740190458.454 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-22T02:14:18.454Z,1740190458.454 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T02:14:18.454Z,1740190458.454 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T02:14:18.454Z,1740190458.454 [keepstation:NeedComms:A] Running Loop=1
2025-02-22T02:14:18.456Z,1740190458.456 [keepstation:NeedComms:A](INFO): last time_fix was:  1740189451.000000 second since 1970/01/01T00:00:00Z 
2025-02-22T02:14:18.456Z,1740190458.456 [keepstation:NeedComms:A] Stopped
2025-02-22T02:14:18.860Z,1740190458.860 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-22T02:14:41.227Z,1740190481.227 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:14:59.014Z,1740190499.014 [keepstation:NeedComms:C] Running Loop=1
2025-02-22T02:14:59.433Z,1740190499.433 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-22T02:15:11.924Z,1740190511.924 [NAL9602](INFO): SBD MO Status=0, MOMSN=13114, MT Status=0, MTMSN=0
2025-02-22T02:15:11.924Z,1740190511.924 [NAL9602](INFO): No messages in MT queue
2025-02-22T02:15:12.354Z,1740190512.354 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:15:13.151Z,1740190513.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021512.00,A,3647.86632,N,12150.81836,W,0.894,92.63,220225,,,A*4B
2025-02-22T02:15:13.154Z,1740190513.154 [NAL9602](INFO): GPS fix at 20250222T021512: (36.797772, -121.846973)
2025-02-22T02:15:13.186Z,1740190513.186 [UniversalFixResidualReporter](INFO): Fix residual: 22.8 %DT, over the last 513.9 m. Residual distance 117.3 m at bearing -0.7 degrees. Fix at (36.7978, -121.8470) with 382.3 m made good.
2025-02-22T02:15:13.192Z,1740190513.192 [keepstation:NeedComms:C] Stopped
2025-02-22T02:15:13.192Z,1740190513.192 [keepstation:NeedComms:D] Running Loop=1
2025-02-22T02:15:13.572Z,1740190513.572 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-22T02:15:14.545Z,1740190514.545 [BackseatComponent](IMPORTANT): IBASE811F-02: Running bioacoustics backseat app. Logging to: 20250222T021512
2025-02-22T02:15:30.462Z,1740190530.462 [NAL9602](INFO): SBD MO Status=1, MOMSN=13115, MT Status=0, MTMSN=0
2025-02-22T02:15:30.512Z,1740190530.512 [NAL9602](INFO): Sent 211 bytes from file Logs/20250222T012831/Courier0027.lzma
2025-02-22T02:15:30.512Z,1740190530.512 [NAL9602](INFO): Packets left to send: 0
2025-02-22T02:15:43.476Z,1740190543.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:15:56.738Z,1740190556.738 [NAL9602](INFO): SBD MO Status=2, MOMSN=13116, MT Status=2, MTMSN=0
2025-02-22T02:15:56.738Z,1740190556.738 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T02:15:57.468Z,1740190557.468 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003337
2025-02-22T02:16:10.729Z,1740190570.729 [DataOverHttps](INFO): Sending 421 bytes from file Logs/20250222T012831/Express0028.lzma
2025-02-22T02:16:11.753Z,1740190571.753 [DataOverHttps](INFO): Moved sent file to Logs/20250222T012831/Express0028.lzma.bak
2025-02-22T02:16:11.753Z,1740190571.753 [DataOverHttps](INFO): SBD MOMSN=23971919
2025-02-22T02:16:13.348Z,1740190573.348 [keepstation:NeedComms:D] Stopped
2025-02-22T02:16:13.349Z,1740190573.349 [keepstation:NeedComms:E] Running Loop=1
2025-02-22T02:16:13.793Z,1740190573.793 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-22T02:16:15.023Z,1740190575.023 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1975
2025-02-22T02:16:15.362Z,1740190575.362 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021614.00,A,3647.85812,N,12150.80636,W,1.225,138.78,220225,,,A*74
2025-02-22T02:16:15.364Z,1740190575.364 [NAL9602](INFO): GPS fix at 20250222T021614: (36.797635, -121.846773)
2025-02-22T02:16:15.428Z,1740190575.428 [keepstation:NeedComms:E] Stopped
2025-02-22T02:16:15.429Z,1740190575.429 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-22T02:16:15.429Z,1740190575.429 [keepstation:NeedComms] Stopped
2025-02-22T02:16:15.429Z,1740190575.429 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-22T02:16:15.429Z,1740190575.429 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-22T02:16:15.430Z,1740190575.430 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T02:16:15.842Z,1740190575.842 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-02-22T02:16:15.842Z,1740190575.842 [keepstation:StartingMission] Stopped
2025-02-22T02:16:15.842Z,1740190575.842 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-02-22T02:16:15.842Z,1740190575.842 [keepstation:TransitToStation] Running Loop=1
2025-02-22T02:16:15.842Z,1740190575.842 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-02-22T02:16:15.842Z,1740190575.842 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-22T02:16:15.842Z,1740190575.842 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-22T02:16:15.842Z,1740190575.842 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-22T02:16:15.842Z,1740190575.842 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2025-02-22T02:16:15.843Z,1740190575.843 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-22T02:16:15.843Z,1740190575.843 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2025-02-22T02:16:15.855Z,1740190575.855 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-02-22T02:16:15.855Z,1740190575.855 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-22T02:16:15.856Z,1740190575.856 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-02-22T02:16:16.325Z,1740190576.325 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-02-22T02:16:16.326Z,1740190576.326 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-22T02:16:16.326Z,1740190576.326 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-22T02:16:16.326Z,1740190576.326 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-22T02:16:47.475Z,1740190607.475 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T02:17:44.592Z,1740190664.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:17:55.783Z,1740190675.783 [Radio_Surface](INFO): Powering down
2025-02-22T02:18:15.715Z,1740190695.715 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:18:16.719Z,1740190696.719 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-22T02:19:29.568Z,1740190769.568 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000
2025-02-22T02:19:29.568Z,1740190769.568 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-02-22T02:19:29.568Z,1740190769.568 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-22T02:19:29.569Z,1740190769.569 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-02-22T02:19:29.569Z,1740190769.569 [keepstation:TransitToStation] Stopped
2025-02-22T02:19:29.569Z,1740190769.569 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-02-22T02:19:29.569Z,1740190769.569 [keepstation:TransitToStation:A.Buoyancy] Stopped
2025-02-22T02:19:29.570Z,1740190769.570 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-22T02:19:29.570Z,1740190769.570 [keepstation:TransitToStation:B.Pitch] Stopped
2025-02-22T02:19:29.570Z,1740190769.570 [keepstation:TransitToStation:C.SetSpeed] Stopped
2025-02-22T02:19:29.570Z,1740190769.570 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2025-02-22T02:19:29.570Z,1740190769.570 [keepstation:OnStation] Running Loop=1
2025-02-22T02:19:29.570Z,1740190769.570 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-02-22T02:19:30.084Z,1740190770.084 [keepstation:NeedComms] Running Loop=1
2025-02-22T02:19:30.084Z,1740190770.084 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-22T02:19:30.084Z,1740190770.084 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-22T02:19:30.084Z,1740190770.084 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T02:19:30.084Z,1740190770.084 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-22T02:19:30.085Z,1740190770.085 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-22T02:19:30.085Z,1740190770.085 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-22T02:19:30.085Z,1740190770.085 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-22T02:19:30.086Z,1740190770.086 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T02:19:30.086Z,1740190770.086 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T02:19:30.086Z,1740190770.086 [keepstation:NeedComms:A] Running Loop=1
2025-02-22T02:19:30.088Z,1740190770.088 [keepstation:NeedComms:A](INFO): last time_fix was:  1740190574.000000 second since 1970/01/01T00:00:00Z 
2025-02-22T02:19:30.088Z,1740190770.088 [keepstation:NeedComms:A] Stopped
2025-02-22T02:19:53.120Z,1740190793.120 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.767227
2025-02-22T02:20:15.272Z,1740190815.272 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.simrad_data_logging_rate with code: 1976
2025-02-22T02:20:29.137Z,1740190829.137 [keepstation:NeedComms:C] Running Loop=1
2025-02-22T02:20:29.767Z,1740190829.767 [Radio_Surface](INFO): Powering up
2025-02-22T02:20:35.287Z,1740190835.287 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-22T02:21:05.407Z,1740190865.407 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:21:36.539Z,1740190896.539 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:22:01.238Z,1740190921.238 [NAL9602](INFO): SBD MO Status=1, MOMSN=13116, MT Status=0, MTMSN=0
2025-02-22T02:22:01.238Z,1740190921.238 [NAL9602](INFO): No messages in MT queue
2025-02-22T02:22:02.450Z,1740190922.450 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022201.00,A,3647.75200,N,12150.85082,W,0.428,191.27,220225,,,A*7E
2025-02-22T02:22:02.453Z,1740190922.453 [NAL9602](INFO): GPS fix at 20250222T022201: (36.795867, -121.847514)
2025-02-22T02:22:02.468Z,1740190922.468 [keepstation:NeedComms:C] Stopped
2025-02-22T02:22:02.468Z,1740190922.468 [keepstation:NeedComms:D] Running Loop=1
2025-02-22T02:22:07.672Z,1740190927.672 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:22:12.154Z,1740190932.154 [NAL9602](INFO): SBD MO Status=1, MOMSN=13117, MT Status=0, MTMSN=0
2025-02-22T02:22:12.207Z,1740190932.207 [NAL9602](INFO): Sent 72 bytes from file Logs/20250222T012831/Courier0030.lzma
2025-02-22T02:22:12.208Z,1740190932.208 [NAL9602](INFO): Packets left to send: 0
2025-02-22T02:22:28.438Z,1740190948.438 [NAL9602](INFO): SBD MO Status=1, MOMSN=13118, MT Status=0, MTMSN=0
2025-02-22T02:22:28.492Z,1740190948.492 [NAL9602](INFO): Sent 258 bytes from file Logs/20250222T012831/Express0031.lzma
2025-02-22T02:22:28.492Z,1740190948.492 [NAL9602](INFO): Packets left to send: 0
2025-02-22T02:22:38.799Z,1740190958.799 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:22:43.062Z,1740190963.062 [NAL9602](INFO): SBD MO Status=2, MOMSN=13119, MT Status=2, MTMSN=0
2025-02-22T02:22:43.062Z,1740190963.062 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T02:22:52.923Z,1740190972.923 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.010997
2025-02-22T02:22:55.392Z,1740190975.392 [keepstation:NeedComms:D] Stopped
2025-02-22T02:22:55.392Z,1740190975.392 [keepstation:NeedComms:E] Running Loop=1
2025-02-22T02:23:08.670Z,1740190988.670 [NAL9602](INFO): SBD MO Status=0, MOMSN=13119, MT Status=0, MTMSN=0
2025-02-22T02:23:09.898Z,1740190989.898 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022309.00,A,3647.74127,N,12150.85005,W,0.603,190.49,220225,,,A*7D
2025-02-22T02:23:09.909Z,1740190989.909 [NAL9602](INFO): GPS fix at 20250222T022309: (36.795688, -121.847501)
2025-02-22T02:23:09.000Z,1740190990.000 [keepstation:NeedComms:E] Stopped
2025-02-22T02:23:10.001Z,1740190990.001 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-22T02:23:10.001Z,1740190990.001 [keepstation:NeedComms] Stopped
2025-02-22T02:23:10.001Z,1740190990.001 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-22T02:23:10.001Z,1740190990.001 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-22T02:23:10.001Z,1740190990.001 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T02:23:10.347Z,1740190990.347 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-02-22T02:23:10.347Z,1740190990.347 [keepstation:OnStation] Stopped
2025-02-22T02:23:10.347Z,1740190990.347 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-02-22T02:23:10.348Z,1740190990.348 [keepstation:KeepStation] Running Loop=1
2025-02-22T02:23:10.348Z,1740190990.348 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-02-22T02:23:10.348Z,1740190990.348 [keepstation:KeepStation:A] Running Loop=1
2025-02-22T02:23:10.348Z,1740190990.348 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-22T02:23:10.348Z,1740190990.348 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize.
2025-02-22T02:23:10.348Z,1740190990.348 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-22T02:23:10.348Z,1740190990.348 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-02-22T02:23:10.349Z,1740190990.349 [keepstation:KeepStation:D.Wait] Running Loop=1
2025-02-22T02:23:10.349Z,1740190990.349 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component.
2025-02-22T02:23:10.734Z,1740190990.734 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-22T02:23:10.734Z,1740190990.734 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-22T02:23:10.735Z,1740190990.735 [keepstation:KeepStation:A] Running Loop=1
2025-02-22T02:23:42.520Z,1740191022.520 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T02:26:07.163Z,1740191167.163 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:26:26.071Z,1740191186.071 [Radio_Surface](INFO): Powering down
2025-02-22T02:26:38.287Z,1740191198.287 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T02:26:39.291Z,1740191199.291 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-22T02:28:18.689Z,1740191298.689 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.720413
2025-02-22T02:59:17.439Z,1740193157.439 [keepstation](INFO): Timed out from 2025-02-22T02:14:17.2Z
2025-02-22T02:59:17.440Z,1740193157.440 [MissionManager](INFO): keepstation is completed.
2025-02-22T02:59:17.440Z,1740193157.440 [MissionManager](INFO): Uninitializing Mission keepstation
2025-02-22T02:59:17.440Z,1740193157.440 [keepstation] Stopped
2025-02-22T02:59:17.440Z,1740193157.440 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-02-22T02:59:17.440Z,1740193157.440 [keepstation:StandardEnvelopes] Stopped
2025-02-22T02:59:17.440Z,1740193157.440 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-02-22T02:59:17.440Z,1740193157.440 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-22T02:59:17.440Z,1740193157.440 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.