2025-09-03T21:36:17.134Z,1756935377.134 [CommandExec](IMPORTANT): got command restart logs
2025-09-03T21:36:18.042Z,1756935378.042 [DataOverHttps](IMPORTANT): SBD MTMSN=20250903T213617
2025-09-03T21:36:28.900Z,1756935388.900 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 40 min;set transit.NeedCommsTime 20 min;set transit.Latitude 36.806966 degree;set transit.Longitude -121.824326 degree;set transit.Speed 1.2 m/s;run
2025-09-03T21:36:30.904Z,1756935390.904 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:36:31.956Z,1756935391.956 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-09-03T21:36:31.959Z,1756935391.959 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-09-03T21:36:31.959Z,1756935391.959 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-09-03T21:36:31.967Z,1756935391.967 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-09-03T21:36:33.815Z,1756935393.815 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="transit">
    <DefineArg Name="MissionTimeout">
        <Units:minute />
        <Value>60</Value>
    </DefineArg>
    <DefineArg Name="NeedCommsTime">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="Latitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Longitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Northings">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="Eastings">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="Depth">
        <Units:meter />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="Speed">
        <Units:meter_per_second />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>20</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="MinAltitude">
        <Units:meter />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="MassHold">
        <True />
    </DefineArg>
    <DefineArg Name="BuoyancyHold">
        <True />
    </DefineArg>
    <Timeout Duration="MissionTimeout" />
    <Insert Id="NeedComms" Filename="Insert/NeedComms.tl" />
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:DiveInterval" />
        <Arg Name="NeedCommsTime" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:WaitForPitchUp" />
        <Units:second />
        <Value>1</Value>
    </Assign>
    <Insert Filename="Insert/StandardEnvelopes.tl" />
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinAltitude" />
        <Arg Name="MinAltitude" />
    </Assign>
    <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:Buoyancy Id="BuoyancyHold">
        <While>
            <Arg Name="BuoyancyHold" />
        </While>
        <Setting>
            <Guidance:Buoyancy.position />
            <Control:VerticalControl.buoyancyNeutral />
        </Setting>
    </Guidance:Buoyancy>
    <Guidance:Pitch Id="MassHold">
        <While>
            <Arg Name="MassHold" />
        </While>
        <Setting>
            <Guidance:Pitch.massPosition />
            <Control:VerticalControl.massDefault />
        </Setting>
    </Guidance:Pitch>
    <Aggregate Id="Transit">
        <Sequence />
        <Guidance:Pitch>
            <Parallel />
            <Setting>
                <Guidance:Pitch.depth />
                <Arg Name="Depth" />
            </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>
            <Setting>
                <Guidance:Waypoint.northingsDelta />
                <Arg Name="Northings" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.eastingsDelta />
                <Arg Name="Eastings" />
            </Setting>
        </Guidance:Waypoint>
        <Call RefId="NeedComms" Id="PhoneHome" PriorityHere="false" />
    </Aggregate>
</Mission>

2025-09-03T21:36:33.846Z,1756935393.846 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-09-03T21:36:33.859Z,1756935393.859 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-09-03T21:36:33.883Z,1756935393.883 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-09-03T21:36:33.894Z,1756935393.894 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-09-03T21:36:33.917Z,1756935393.917 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-09-03T21:36:33.920Z,1756935393.920 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-09-03T21:36:33.923Z,1756935393.923 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-09-03T21:36:33.942Z,1756935393.942 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-09-03T21:36:33.949Z,1756935393.949 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-09-03T21:36:33.960Z,1756935393.960 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-09-03T21:36:33.963Z,1756935393.963 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-09-03T21:36:33.985Z,1756935393.985 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-09-03T21:36:33.998Z,1756935393.998 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-09-03T21:36:33.999Z,1756935393.999 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-09-03T21:36:33.999Z,1756935393.999 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-09-03T21:36:34.007Z,1756935394.007 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-09-03T21:36:34.736Z,1756935394.736 [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-09-03T21:36:34.758Z,1756935394.758 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-09-03T21:36:34.777Z,1756935394.777 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-09-03T21:36:34.786Z,1756935394.786 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-09-03T21:36:34.799Z,1756935394.799 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-09-03T21:36:34.806Z,1756935394.806 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-09-03T21:36:34.822Z,1756935394.822 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-09-03T21:36:34.829Z,1756935394.829 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-09-03T21:36:34.840Z,1756935394.840 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-09-03T21:36:34.856Z,1756935394.856 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-03T21:36:34.970Z,1756935394.970 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-09-03T21:36:34.970Z,1756935394.970 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-09-03T21:36:34.971Z,1756935394.971 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-09-03T21:36:35.242Z,1756935395.242 [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>1.5</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-09-03T21:36:35.255Z,1756935395.255 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-09-03T21:36:35.267Z,1756935395.267 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-09-03T21:36:35.274Z,1756935395.274 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-09-03T21:36:35.317Z,1756935395.317 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-09-03T21:36:35.318Z,1756935395.318 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-09-03T21:36:35.339Z,1756935395.339 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-09-03T21:36:35.389Z,1756935395.389 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-09-03T21:36:35.432Z,1756935395.432 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-09-03T21:36:35.440Z,1756935395.440 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-09-03T21:36:35.456Z,1756935395.456 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-09-03T21:36:35.621Z,1756935395.621 [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-09-03T21:36:35.637Z,1756935395.637 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-09-03T21:36:35.673Z,1756935395.673 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-09-03T21:36:35.737Z,1756935395.737 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-09-03T21:36:35.737Z,1756935395.737 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-09-03T21:36:35.738Z,1756935395.738 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-09-03T21:36:36.258Z,1756935396.258 [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-09-03T21:36:36.271Z,1756935396.271 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-09-03T21:36:36.283Z,1756935396.283 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-09-03T21:36:36.290Z,1756935396.290 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-09-03T21:36:36.305Z,1756935396.305 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-09-03T21:36:36.317Z,1756935396.317 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-09-03T21:36:36.341Z,1756935396.341 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-09-03T21:36:36.343Z,1756935396.343 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-09-03T21:36:36.351Z,1756935396.351 [transit:MassHold.Pitch](DEBUG): Construct.
2025-09-03T21:36:36.392Z,1756935396.392 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-09-03T21:36:36.403Z,1756935396.403 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-09-03T21:36:36.427Z,1756935396.427 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-09-03T21:36:36.537Z,1756935396.537 [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 transit {
  """
  Vehicle dives immediately with no comms/GPS and transits to desired waypoint and can be commanded to use/hold
  mass or buoyancy.
  """

  arguments {
    MissionTimeout = 60 minute
      """
      Maximum duration of mission
      """

    NeedCommsTime = 30 minute
      """
      How often to surface for commumications
      """

    Latitude = NaN degree
      """
      Latitude of waypoint to seek. If set to NaN, uses latitude at mission
      initialization.
      """

    Longitude = NaN degree
      """
      Longitude of waypoint to seek. If set to NaN, uses longitude at mission
      initialization.
      """

    Northings = 0 meter
      """
      Northward distance of the waypoint to seek. If combined with Latitude
      and/or Longitude, this is an offset from the specified Latitude and/or
      Longitude.
      """

    Eastings = 0 meter
      """
      Eastward distance of the waypoint to seek. If combined with Latitude
      and/or Longitude, this is an offset from the specified Latitude and/or
      Longitude.
      """

    Depth = 10 meter
      """
      Depth of flat and level flight during the mission.
      """

    Speed = 1 meter_per_second
      """
      Speed of vehicle (relative to water) during the mission.
      """

    MaxDepth = 20 meter
      """
      Maximum depth for the entire mission.
      """

    MinOffshore = 1 kilometer
      """
      Minimum distance offshore for the entire mission.
      """

    MinAltitude = 7 meter
      """
      Minimum height above the sea floor for the entire mission.
      """

    MassHold = true
      """
      Set to True in order to hold mass at default position, False to allow
      mass to run on its own.
      """

    BuoyancyHold = true
      """
      Set to True in order to hold buoyancy at neutral position, False to
      allow buoyancy to run on its own.
      """
  }

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  assign in sequence NeedComms:WaitForPitchUp = 1 second

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MinAltitude = MinAltitude

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

  insert Insert/PowerOnly.tl

  behavior Guidance:Buoyancy id="BuoyancyHold" {
    run while ( BuoyancyHold )

    set position = Control:VerticalControl.buoyancyNeutral
  }

  behavior Guidance:Pitch id="MassHold" {
    run while ( MassHold )

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate Transit {
    run in sequence

    behavior Guidance:Pitch {
      run in parallel

      set depth = Depth
    }

    behavior Guidance:SetSpeed {
      run in parallel

      set speed = Speed
    }

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

      set latitude = Latitude
      set longitude = Longitude
      set northingsDelta = Northings
      set eastingsDelta = Eastings
    }

    call id="PhoneHome" priorityHere=false refId="NeedComms"
  }
}


2025-09-03T21:36:36.538Z,1756935396.538 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-09-03T21:36:45.176Z,1756935405.176 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:36:57.980Z,1756935417.980 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:37:09.894Z,1756935429.894 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 40 minute
2025-09-03T21:37:09.895Z,1756935429.895 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 20 minute
2025-09-03T21:37:09.896Z,1756935429.896 [CommandExec](IMPORTANT): got command set transit.Latitude 36.806966 degree
2025-09-03T21:37:09.897Z,1756935429.897 [CommandExec](IMPORTANT): got command set transit.Longitude -121.824326 degree
2025-09-03T21:37:09.898Z,1756935429.898 [CommandExec](IMPORTANT): got command set transit.Speed 1.2 meter_per_second
2025-09-03T21:37:09.898Z,1756935429.898 [CommandExec](IMPORTANT): got command run
2025-09-03T21:37:09.901Z,1756935429.901 [CommandExec](IMPORTANT): Running 
2025-09-03T21:37:10.093Z,1756935430.093 [DefaultWithUndock] Stopped
2025-09-03T21:37:10.093Z,1756935430.093 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-09-03T21:37:10.093Z,1756935430.093 [DefaultWithUndock:SurfaceDefault] Stopped
2025-09-03T21:37:10.093Z,1756935430.093 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2025-09-03T21:37:10.093Z,1756935430.093 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped
2025-09-03T21:37:10.093Z,1756935430.093 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-03T21:37:10.093Z,1756935430.093 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-03T21:37:10.101Z,1756935430.101 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-03T21:37:10.101Z,1756935430.101 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-03T21:37:10.102Z,1756935430.102 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:37:10.102Z,1756935430.102 [MissionManager](IMPORTANT): Started mission transit
2025-09-03T21:37:10.102Z,1756935430.102 [transit] Running Loop=1
2025-09-03T21:37:10.102Z,1756935430.102 [transit](DEBUG): Aggregate::initialize transit
2025-09-03T21:37:10.102Z,1756935430.102 [transit:StandardEnvelopes] Running Loop=1
2025-09-03T21:37:10.102Z,1756935430.102 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-09-03T21:37:10.102Z,1756935430.102 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-09-03T21:37:10.102Z,1756935430.102 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-09-03T21:37:10.103Z,1756935430.103 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-09-03T21:37:10.103Z,1756935430.103 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-09-03T21:37:10.103Z,1756935430.103 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-09-03T21:37:10.103Z,1756935430.103 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-09-03T21:37:10.103Z,1756935430.103 [transit:BackseatDriver] Running Loop=1
2025-09-03T21:37:10.103Z,1756935430.103 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-09-03T21:37:10.103Z,1756935430.103 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-09-03T21:37:10.103Z,1756935430.103 [transit:PowerOnly] Running Loop=1
2025-09-03T21:37:10.103Z,1756935430.103 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-09-03T21:37:10.104Z,1756935430.104 [transit:PowerOnly:A] Running Loop=1
2025-09-03T21:37:10.104Z,1756935430.104 [transit:PowerOnly:B] Running Loop=1
2025-09-03T21:37:10.104Z,1756935430.104 [transit:PowerOnly:C] Running Loop=1
2025-09-03T21:37:10.104Z,1756935430.104 [transit:PowerOnly:D] Running Loop=1
2025-09-03T21:37:10.104Z,1756935430.104 [transit:PowerOnly:E.Wait] Running Loop=1
2025-09-03T21:37:10.104Z,1756935430.104 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:37:10.104Z,1756935430.104 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-03T21:37:10.104Z,1756935430.104 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-03T21:37:10.105Z,1756935430.105 [transit:MassHold.Pitch] Running Loop=1
2025-09-03T21:37:10.105Z,1756935430.105 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-09-03T21:37:10.105Z,1756935430.105 [transit:B] Running Loop=1
2025-09-03T21:37:10.105Z,1756935430.105 [transit:MassHold.Pitch] Running Loop=1
2025-09-03T21:37:10.106Z,1756935430.106 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-03T21:37:10.106Z,1756935430.106 [transit:PowerOnly] Running Loop=1
2025-09-03T21:37:10.106Z,1756935430.106 [transit:PowerOnly:D] Stopped
2025-09-03T21:37:10.106Z,1756935430.106 [transit:PowerOnly:C] Stopped
2025-09-03T21:37:10.106Z,1756935430.106 [transit:PowerOnly:B] Stopped
2025-09-03T21:37:10.106Z,1756935430.106 [transit:PowerOnly:A] Stopped
2025-09-03T21:37:10.107Z,1756935430.107 [transit:BackseatDriver] Running Loop=1
2025-09-03T21:37:10.107Z,1756935430.107 [transit:StandardEnvelopes] Running Loop=1
2025-09-03T21:37:10.107Z,1756935430.107 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-09-03T21:37:10.107Z,1756935430.107 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-09-03T21:37:10.108Z,1756935430.108 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-09-03T21:37:10.109Z,1756935430.109 [transit:B] Stopped
2025-09-03T21:37:10.109Z,1756935430.109 [transit:C] Running Loop=1
2025-09-03T21:37:10.483Z,1756935430.483 [transit:C] Stopped
2025-09-03T21:37:10.483Z,1756935430.483 [transit:E] Running Loop=1
2025-09-03T21:37:10.520Z,1756935430.520 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:37:10.902Z,1756935430.902 [transit:E] Stopped
2025-09-03T21:37:10.902Z,1756935430.902 [transit:F] Running Loop=1
2025-09-03T21:37:11.296Z,1756935431.296 [transit:F] Stopped
2025-09-03T21:37:11.297Z,1756935431.297 [transit:G] Running Loop=1
2025-09-03T21:37:11.690Z,1756935431.690 [transit:G] Stopped
2025-09-03T21:37:11.691Z,1756935431.691 [transit:Transit] Running Loop=1
2025-09-03T21:37:11.691Z,1756935431.691 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-09-03T21:37:11.691Z,1756935431.691 [transit:Transit:A.Pitch] Running Loop=1
2025-09-03T21:37:11.691Z,1756935431.691 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-09-03T21:37:11.691Z,1756935431.691 [transit:Transit:B.SetSpeed] Running Loop=1
2025-09-03T21:37:11.691Z,1756935431.691 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-09-03T21:37:11.691Z,1756935431.691 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-09-03T21:37:11.691Z,1756935431.691 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-09-03T21:37:11.709Z,1756935431.709 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.806965,-121.824326
2025-09-03T21:37:12.094Z,1756935432.094 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.806965,-121.824326
2025-09-03T21:37:12.095Z,1756935432.095 [transit:Transit:B.SetSpeed] Running Loop=1
2025-09-03T21:37:12.095Z,1756935432.095 [transit:Transit:A.Pitch] Running Loop=1
2025-09-03T21:37:19.768Z,1756935439.768 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:37:29.061Z,1756935449.061 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:37:38.522Z,1756935458.522 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:37:47.792Z,1756935467.792 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:37:54.367Z,1756935474.367 [NAL9602](INFO): SBD MO Status=2, MOMSN=1214, MT Status=2, MTMSN=0
2025-09-03T21:37:54.367Z,1756935474.367 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:38:19.165Z,1756935499.165 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:38:47.277Z,1756935527.277 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.487465
2025-09-03T21:38:48.301Z,1756935528.301 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:38:55.672Z,1756935535.672 [Radio_Surface](INFO): Powering down
2025-09-03T21:39:18.420Z,1756935558.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:39:19.424Z,1756935559.424 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-03T21:39:19.425Z,1756935559.425 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:39:49.544Z,1756935589.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:39:50.549Z,1756935590.549 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:39:59.785Z,1756935599.785 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-09-03T21:40:20.668Z,1756935620.668 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:40:21.672Z,1756935621.672 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:40:24.433Z,1756935624.433 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T21:40:51.793Z,1756935651.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:40:52.796Z,1756935652.796 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:40:55.149Z,1756935655.149 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T21:41:22.916Z,1756935682.916 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:41:23.920Z,1756935683.920 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:41:54.040Z,1756935714.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:41:55.044Z,1756935715.044 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:42:25.201Z,1756935745.201 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:42:26.204Z,1756935746.204 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:42:56.328Z,1756935776.328 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:42:57.332Z,1756935777.332 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:43:27.452Z,1756935807.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:43:28.457Z,1756935808.457 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:43:58.576Z,1756935838.576 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:43:59.581Z,1756935839.581 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:44:29.700Z,1756935869.700 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:44:30.705Z,1756935870.705 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:45:00.824Z,1756935900.824 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:45:01.829Z,1756935901.829 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:45:31.948Z,1756935931.948 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:45:32.952Z,1756935932.952 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:45:57.403Z,1756935957.403 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-09-03T21:46:03.072Z,1756935963.072 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:46:04.076Z,1756935964.076 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:46:34.212Z,1756935994.212 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:46:35.217Z,1756935995.217 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:47:05.356Z,1756936025.356 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:47:06.361Z,1756936026.361 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:47:36.485Z,1756936056.485 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:47:37.488Z,1756936057.488 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:48:07.624Z,1756936087.624 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:48:08.628Z,1756936088.628 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:48:38.753Z,1756936118.753 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:48:39.756Z,1756936119.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:49:09.877Z,1756936149.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:49:10.880Z,1756936150.880 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:49:41.004Z,1756936181.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:49:42.008Z,1756936182.008 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:50:12.128Z,1756936212.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:50:13.132Z,1756936213.132 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:50:43.252Z,1756936243.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:50:44.256Z,1756936244.256 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:51:14.376Z,1756936274.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:51:15.380Z,1756936275.380 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:51:45.500Z,1756936305.500 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:51:46.504Z,1756936306.504 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:51:51.169Z,1756936311.169 [RDI_Pathfinder](ERROR): only read 0 of 4 data items for beam range. Device response is::RA   0.00,   0.00,   0.00,   0.00
2025-09-03T21:52:16.218Z,1756936336.218 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00031101394665,35.0, +13.7,   0.0,150
2025-09-03T21:52:16.628Z,1756936336.628 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:52:17.632Z,1756936337.632 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:52:26.308Z,1756936346.308 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00031101395665,35.0, +13.7,   0.0,1502

2025-09-03T21:52:46.120Z,1756936366.120 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,  -139,   -3,A
2025-09-03T21:52:47.754Z,1756936367.754 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:52:48.756Z,1756936368.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:53:18.876Z,1756936398.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:53:19.882Z,1756936399.882 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:53:50.005Z,1756936430.005 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:53:51.008Z,1756936431.008 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:54:21.132Z,1756936461.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:54:22.136Z,1756936462.136 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:54:52.256Z,1756936492.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:54:53.260Z,1756936493.260 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:55:22.899Z,1756936522.899 [transit:NeedComms] Running Loop=1
2025-09-03T21:55:22.900Z,1756936522.900 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-09-03T21:55:22.900Z,1756936522.900 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-09-03T21:55:22.917Z,1756936522.917 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-03T21:55:22.917Z,1756936522.917 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-03T21:55:22.918Z,1756936522.918 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-03T21:55:22.918Z,1756936522.918 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-03T21:55:22.919Z,1756936522.919 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-03T21:55:22.919Z,1756936522.919 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-03T21:55:22.919Z,1756936522.919 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-03T21:55:22.920Z,1756936522.920 [transit:NeedComms:A] Running Loop=1
2025-09-03T21:55:22.926Z,1756936522.926 [transit:NeedComms:A](INFO): last time_fix was:  1756935321.000000 second since 1970/01/01T00:00:00Z 
2025-09-03T21:55:22.926Z,1756936522.926 [transit:NeedComms:A] Stopped
2025-09-03T21:55:23.381Z,1756936523.381 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:55:24.384Z,1756936524.384 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:55:25.080Z,1756936525.080 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-09-03T21:55:25.188Z,1756936525.188 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-09-03T21:55:25.188Z,1756936525.188 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-09-03T21:55:25.188Z,1756936525.188 [ThrusterHE] Communications Fault, FailCount= 1
2025-09-03T21:55:25.188Z,1756936525.188 [ThrusterHE](ERROR): Communications Fault
2025-09-03T21:55:25.192Z,1756936525.192 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-09-03T21:55:25.401Z,1756936525.401 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-09-03T21:55:25.401Z,1756936525.401 [ThrusterHE](INFO): Powering down
2025-09-03T21:55:26.249Z,1756936526.249 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-09-03T21:55:26.365Z,1756936526.365 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-09-03T21:55:26.369Z,1756936526.369 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-09-03T21:55:26.369Z,1756936526.369 [ThrusterHE] No Fault, FailCount= 1
2025-09-03T21:55:37.053Z,1756936537.053 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:55:54.529Z,1756936554.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:55:55.532Z,1756936555.532 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:55:58.960Z,1756936558.960 [transit:NeedComms:C] Running Loop=1
2025-09-03T21:55:59.377Z,1756936559.377 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-09-03T21:55:59.464Z,1756936559.464 [Radio_Surface](INFO): Powering up
2025-09-03T21:56:04.716Z,1756936564.716 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-09-03T21:56:04.717Z,1756936564.717 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0
2025-09-03T21:56:25.652Z,1756936585.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:56:26.656Z,1756936586.656 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-03T21:56:26.656Z,1756936586.656 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:56:31.670Z,1756936591.670 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T21:56:31.748Z,1756936591.748 [NAL9602](ERROR): received: 
+CSQ:0

OK14, 2, 0, 0, 0

OK
2025-09-03T21:56:56.780Z,1756936616.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:56:57.786Z,1756936617.786 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:57:13.687Z,1756936633.687 [NAL9602](INFO): SBD MO Status=2, MOMSN=1214, MT Status=2, MTMSN=0
2025-09-03T21:57:13.688Z,1756936633.688 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:57:27.904Z,1756936647.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:57:28.908Z,1756936648.908 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:57:59.032Z,1756936679.032 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:58:00.036Z,1756936680.036 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:58:21.188Z,1756936701.188 [NAL9602](INFO): SBD MO Status=2, MOMSN=1214, MT Status=2, MTMSN=0
2025-09-03T21:58:21.189Z,1756936701.189 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:58:30.164Z,1756936710.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T21:58:31.169Z,1756936711.169 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:58:41.312Z,1756936721.312 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003108
2025-09-03T21:58:42.312Z,1756936722.312 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:58:51.170Z,1756936731.170 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:59:00.056Z,1756936740.056 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:59:08.908Z,1756936748.908 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:59:11.290Z,1756936751.290 [NAL9602](INFO): SBD MO Status=2, MOMSN=1214, MT Status=2, MTMSN=0
2025-09-03T21:59:11.291Z,1756936751.291 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:59:18.060Z,1756936758.060 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:59:26.976Z,1756936766.976 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:59:35.934Z,1756936775.934 [NAL9602](INFO): SBD MO Status=1, MOMSN=1214, MT Status=0, MTMSN=0
2025-09-03T21:59:35.934Z,1756936775.934 [NAL9602](INFO): No messages in MT queue
2025-09-03T21:59:35.936Z,1756936775.936 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T21:59:37.186Z,1756936777.186 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215936.00,A,3648.01321,N,12150.59313,W,0.719,49.05,030925,,,D*4B
2025-09-03T21:59:37.193Z,1756936777.193 [NAL9602](INFO): GPS fix at 20250903T215936: (36.800220, -121.843219)
2025-09-03T21:59:37.247Z,1756936777.247 [UniversalFixResidualReporter](INFO): Fix residual: 6.3 %DT, over the last 1464.6 m. Residual distance 93.0 m at bearing -178.3 degrees. Fix at (36.8002, -121.8432) with 1455.0 m made good.
2025-09-03T21:59:37.293Z,1756936777.293 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.806965,-121.824326
2025-09-03T21:59:37.296Z,1756936777.296 [transit:NeedComms:C] Stopped
2025-09-03T21:59:37.300Z,1756936777.300 [transit:NeedComms:D] Running Loop=1
2025-09-03T21:59:37.585Z,1756936777.585 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-09-03T21:59:43.591Z,1756936783.591 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250903T203057/Courier0016.lzma
2025-09-03T21:59:44.594Z,1756936784.594 [DataOverHttps](INFO): Moved sent file to Logs/20250903T203057/Courier0016.lzma.bak
2025-09-03T21:59:44.594Z,1756936784.594 [DataOverHttps](INFO): SBD MOMSN=25924818
2025-09-03T22:00:02.773Z,1756936802.773 [DataOverHttps](INFO): Sending 322 bytes from file Logs/20250903T213617/Courier0000.lzma
2025-09-03T22:00:03.774Z,1756936803.774 [DataOverHttps](INFO): Moved sent file to Logs/20250903T213617/Courier0000.lzma.bak
2025-09-03T22:00:03.774Z,1756936803.774 [DataOverHttps](INFO): SBD MOMSN=25924820
2025-09-03T22:00:09.477Z,1756936809.477 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T22:00:20.892Z,1756936820.892 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20250903T203057/Express0017.lzma
2025-09-03T22:00:21.894Z,1756936821.894 [DataOverHttps](INFO): Moved sent file to Logs/20250903T203057/Express0017.lzma.bak
2025-09-03T22:00:21.894Z,1756936821.894 [DataOverHttps](INFO): SBD MOMSN=25924824
2025-09-03T22:00:38.698Z,1756936838.698 [DataOverHttps](INFO): Sending 986 bytes from file Logs/20250903T213617/Express0001.lzma
2025-09-03T22:00:39.698Z,1756936839.698 [DataOverHttps](INFO): Moved sent file to Logs/20250903T213617/Express0001.lzma.bak
2025-09-03T22:00:39.698Z,1756936839.698 [DataOverHttps](INFO): SBD MOMSN=25924829
2025-09-03T22:00:56.752Z,1756936856.752 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20250903T213617/Express0004.lzma
2025-09-03T22:00:57.754Z,1756936857.754 [DataOverHttps](INFO): Moved sent file to Logs/20250903T213617/Express0004.lzma.bak
2025-09-03T22:00:57.754Z,1756936857.754 [DataOverHttps](INFO): SBD MOMSN=25924848
2025-09-03T22:00:59.181Z,1756936859.181 [transit:NeedComms:D] Stopped
2025-09-03T22:00:59.181Z,1756936859.181 [transit:NeedComms:E] Running Loop=1
2025-09-03T22:00:59.606Z,1756936859.606 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-09-03T22:01:01.207Z,1756936861.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220100.00,A,3648.02473,N,12150.58301,W,0.544,30.83,030925,,,D*4B
2025-09-03T22:01:01.216Z,1756936861.216 [NAL9602](INFO): GPS fix at 20250903T220100: (36.800412, -121.843050)
2025-09-03T22:01:01.306Z,1756936861.306 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.806965,-121.824326
2025-09-03T22:01:01.337Z,1756936861.337 [transit:NeedComms:E] Stopped
2025-09-03T22:01:01.338Z,1756936861.338 [transit:NeedComms](INFO): Completed transit:NeedComms
2025-09-03T22:01:01.338Z,1756936861.338 [transit:NeedComms] Stopped
2025-09-03T22:01:01.338Z,1756936861.338 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-09-03T22:01:01.338Z,1756936861.338 [transit:NeedComms:B.GoToSurface] Stopped
2025-09-03T22:01:01.338Z,1756936861.338 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-03T22:01:08.084Z,1756936868.084 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:01:16.330Z,1756936876.330 [DataOverHttps](IMPORTANT): SBD MTMSN=20250903T220115
2025-09-03T22:01:24.956Z,1756936884.956 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 10 min;set transit.NeedCommsTime 20 min;set transit.Latitude 36.806966 degree;set transit.Longitude -121.824326 degree;set transit.Speed 1.2 m/s;run
2025-09-03T22:01:25.023Z,1756936885.023 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-09-03T22:01:25.023Z,1756936885.023 [MissionManager](INFO): Uninitializing Mission transit
2025-09-03T22:01:25.023Z,1756936885.023 [transit] Stopped
2025-09-03T22:01:25.023Z,1756936885.023 [transit](DEBUG): Aggregate::uninitialize transit
2025-09-03T22:01:25.023Z,1756936885.023 [transit:StandardEnvelopes] Stopped
2025-09-03T22:01:25.024Z,1756936885.024 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-09-03T22:01:25.024Z,1756936885.024 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-09-03T22:01:25.024Z,1756936885.024 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-09-03T22:01:25.024Z,1756936885.024 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-09-03T22:01:25.024Z,1756936885.024 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-09-03T22:01:25.024Z,1756936885.024 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-09-03T22:01:25.025Z,1756936885.025 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-09-03T22:01:25.025Z,1756936885.025 [transit:BackseatDriver] Stopped
2025-09-03T22:01:25.025Z,1756936885.025 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-09-03T22:01:25.025Z,1756936885.025 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-09-03T22:01:25.025Z,1756936885.025 [transit:PowerOnly] Stopped
2025-09-03T22:01:25.025Z,1756936885.025 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-09-03T22:01:25.025Z,1756936885.025 [transit:PowerOnly:E.Wait] Stopped
2025-09-03T22:01:25.025Z,1756936885.025 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:01:25.025Z,1756936885.025 [transit:BuoyancyHold.Buoyancy] Stopped
2025-09-03T22:01:25.025Z,1756936885.025 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-03T22:01:25.025Z,1756936885.025 [transit:MassHold.Pitch] Stopped
2025-09-03T22:01:25.025Z,1756936885.025 [transit:Transit] Stopped
2025-09-03T22:01:25.026Z,1756936885.026 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-09-03T22:01:25.026Z,1756936885.026 [transit:Transit:A.Pitch] Stopped
2025-09-03T22:01:25.026Z,1756936885.026 [transit:Transit:B.SetSpeed] Stopped
2025-09-03T22:01:25.026Z,1756936885.026 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-09-03T22:01:25.026Z,1756936885.026 [transit:Transit:Wpt1.Waypoint] Stopped
2025-09-03T22:01:25.026Z,1756936885.026 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-09-03T22:01:25.090Z,1756936885.090 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-09-03T22:01:25.090Z,1756936885.090 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-09-03T22:01:25.102Z,1756936885.102 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-09-03T22:01:26.118Z,1756936886.118 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="transit">
    <DefineArg Name="MissionTimeout">
        <Units:minute />
        <Value>60</Value>
    </DefineArg>
    <DefineArg Name="NeedCommsTime">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="Latitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Longitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Northings">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="Eastings">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="Depth">
        <Units:meter />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="Speed">
        <Units:meter_per_second />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>20</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="MinAltitude">
        <Units:meter />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="MassHold">
        <True />
    </DefineArg>
    <DefineArg Name="BuoyancyHold">
        <True />
    </DefineArg>
    <Timeout Duration="MissionTimeout" />
    <Insert Id="NeedComms" Filename="Insert/NeedComms.tl" />
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:DiveInterval" />
        <Arg Name="NeedCommsTime" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:WaitForPitchUp" />
        <Units:second />
        <Value>1</Value>
    </Assign>
    <Insert Filename="Insert/StandardEnvelopes.tl" />
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinAltitude" />
        <Arg Name="MinAltitude" />
    </Assign>
    <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:Buoyancy Id="BuoyancyHold">
        <While>
            <Arg Name="BuoyancyHold" />
        </While>
        <Setting>
            <Guidance:Buoyancy.position />
            <Control:VerticalControl.buoyancyNeutral />
        </Setting>
    </Guidance:Buoyancy>
    <Guidance:Pitch Id="MassHold">
        <While>
            <Arg Name="MassHold" />
        </While>
        <Setting>
            <Guidance:Pitch.massPosition />
            <Control:VerticalControl.massDefault />
        </Setting>
    </Guidance:Pitch>
    <Aggregate Id="Transit">
        <Sequence />
        <Guidance:Pitch>
            <Parallel />
            <Setting>
                <Guidance:Pitch.depth />
                <Arg Name="Depth" />
            </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>
            <Setting>
                <Guidance:Waypoint.northingsDelta />
                <Arg Name="Northings" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.eastingsDelta />
                <Arg Name="Eastings" />
            </Setting>
        </Guidance:Waypoint>
        <Call RefId="NeedComms" Id="PhoneHome" PriorityHere="false" />
    </Aggregate>
</Mission>

2025-09-03T22:01:26.123Z,1756936886.123 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-09-03T22:01:26.126Z,1756936886.126 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-09-03T22:01:26.128Z,1756936886.128 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-09-03T22:01:26.130Z,1756936886.130 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-09-03T22:01:26.133Z,1756936886.133 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-09-03T22:01:26.135Z,1756936886.135 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-09-03T22:01:26.137Z,1756936886.137 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-09-03T22:01:26.140Z,1756936886.140 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-09-03T22:01:26.142Z,1756936886.142 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-09-03T22:01:26.181Z,1756936886.181 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-09-03T22:01:26.183Z,1756936886.183 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-09-03T22:01:26.189Z,1756936886.189 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-09-03T22:01:26.191Z,1756936886.191 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-09-03T22:01:26.192Z,1756936886.192 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-09-03T22:01:26.197Z,1756936886.197 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-09-03T22:01:26.205Z,1756936886.205 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-09-03T22:01:26.557Z,1756936886.557 [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-09-03T22:01:26.567Z,1756936886.567 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-09-03T22:01:26.570Z,1756936886.570 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-09-03T22:01:26.573Z,1756936886.573 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-09-03T22:01:26.576Z,1756936886.576 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-09-03T22:01:26.579Z,1756936886.579 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-09-03T22:01:26.582Z,1756936886.582 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-09-03T22:01:26.585Z,1756936886.585 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-09-03T22:01:26.587Z,1756936886.587 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-09-03T22:01:26.593Z,1756936886.593 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-03T22:01:26.682Z,1756936886.682 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-09-03T22:01:26.682Z,1756936886.682 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-09-03T22:01:26.683Z,1756936886.683 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-09-03T22:01:26.863Z,1756936886.863 [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>1.5</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-09-03T22:01:26.871Z,1756936886.871 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-09-03T22:01:26.874Z,1756936886.874 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-09-03T22:01:26.888Z,1756936886.888 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-09-03T22:01:26.890Z,1756936886.890 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-09-03T22:01:26.891Z,1756936886.891 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-09-03T22:01:26.938Z,1756936886.938 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-09-03T22:01:26.960Z,1756936886.960 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:01:26.967Z,1756936886.967 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-09-03T22:01:27.020Z,1756936887.020 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-09-03T22:01:27.020Z,1756936887.020 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-09-03T22:01:27.022Z,1756936887.022 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-09-03T22:01:27.058Z,1756936887.058 [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-09-03T22:01:27.065Z,1756936887.065 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-09-03T22:01:27.067Z,1756936887.067 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-09-03T22:01:27.117Z,1756936887.117 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-09-03T22:01:27.117Z,1756936887.117 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-09-03T22:01:27.118Z,1756936887.118 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-09-03T22:01:27.361Z,1756936887.361 [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-09-03T22:01:27.365Z,1756936887.365 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-09-03T22:01:27.367Z,1756936887.367 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-09-03T22:01:27.369Z,1756936887.369 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-09-03T22:01:27.371Z,1756936887.371 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-09-03T22:01:27.374Z,1756936887.374 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-09-03T22:01:27.381Z,1756936887.381 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-09-03T22:01:27.382Z,1756936887.382 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-09-03T22:01:27.386Z,1756936887.386 [transit:MassHold.Pitch](DEBUG): Construct.
2025-09-03T22:01:27.424Z,1756936887.424 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-09-03T22:01:27.433Z,1756936887.433 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-09-03T22:01:27.435Z,1756936887.435 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-09-03T22:01:27.463Z,1756936887.463 [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 transit {
  """
  Vehicle dives immediately with no comms/GPS and transits to desired waypoint and can be commanded to use/hold
  mass or buoyancy.
  """

  arguments {
    MissionTimeout = 60 minute
      """
      Maximum duration of mission
      """

    NeedCommsTime = 30 minute
      """
      How often to surface for commumications
      """

    Latitude = NaN degree
      """
      Latitude of waypoint to seek. If set to NaN, uses latitude at mission
      initialization.
      """

    Longitude = NaN degree
      """
      Longitude of waypoint to seek. If set to NaN, uses longitude at mission
      initialization.
      """

    Northings = 0 meter
      """
      Northward distance of the waypoint to seek. If combined with Latitude
      and/or Longitude, this is an offset from the specified Latitude and/or
      Longitude.
      """

    Eastings = 0 meter
      """
      Eastward distance of the waypoint to seek. If combined with Latitude
      and/or Longitude, this is an offset from the specified Latitude and/or
      Longitude.
      """

    Depth = 10 meter
      """
      Depth of flat and level flight during the mission.
      """

    Speed = 1 meter_per_second
      """
      Speed of vehicle (relative to water) during the mission.
      """

    MaxDepth = 20 meter
      """
      Maximum depth for the entire mission.
      """

    MinOffshore = 1 kilometer
      """
      Minimum distance offshore for the entire mission.
      """

    MinAltitude = 7 meter
      """
      Minimum height above the sea floor for the entire mission.
      """

    MassHold = true
      """
      Set to True in order to hold mass at default position, False to allow
      mass to run on its own.
      """

    BuoyancyHold = true
      """
      Set to True in order to hold buoyancy at neutral position, False to
      allow buoyancy to run on its own.
      """
  }

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  assign in sequence NeedComms:WaitForPitchUp = 1 second

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MinAltitude = MinAltitude

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

  insert Insert/PowerOnly.tl

  behavior Guidance:Buoyancy id="BuoyancyHold" {
    run while ( BuoyancyHold )

    set position = Control:VerticalControl.buoyancyNeutral
  }

  behavior Guidance:Pitch id="MassHold" {
    run while ( MassHold )

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate Transit {
    run in sequence

    behavior Guidance:Pitch {
      run in parallel

      set depth = Depth
    }

    behavior Guidance:SetSpeed {
      run in parallel

      set speed = Speed
    }

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

      set latitude = Latitude
      set longitude = Longitude
      set northingsDelta = Northings
      set eastingsDelta = Eastings
    }

    call id="PhoneHome" priorityHere=false refId="NeedComms"
  }
}


2025-09-03T22:01:27.464Z,1756936887.464 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-09-03T22:01:27.574Z,1756936887.574 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-09-03T22:01:27.575Z,1756936887.575 [DefaultWithUndock] Running Loop=1
2025-09-03T22:01:27.575Z,1756936887.575 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-09-03T22:01:27.575Z,1756936887.575 [DefaultWithUndock:A.Wait] Running Loop=1
2025-09-03T22:01:27.575Z,1756936887.575 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:01:33.562Z,1756936893.562 [NAL9602](INFO): SBD MO Status=2, MOMSN=1215, MT Status=2, MTMSN=0
2025-09-03T22:01:33.562Z,1756936893.562 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T22:01:34.013Z,1756936894.013 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.20 m/s, pitch 20.00 deg, mass-position 6.47 mm (2 active estimators).
2025-09-03T22:01:53.547Z,1756936913.547 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 10 minute
2025-09-03T22:01:53.548Z,1756936913.548 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 20 minute
2025-09-03T22:01:53.549Z,1756936913.549 [CommandExec](IMPORTANT): got command set transit.Latitude 36.806966 degree
2025-09-03T22:01:53.550Z,1756936913.550 [CommandExec](IMPORTANT): got command set transit.Longitude -121.824326 degree
2025-09-03T22:01:53.551Z,1756936913.551 [CommandExec](IMPORTANT): got command set transit.Speed 1.2 meter_per_second
2025-09-03T22:01:53.551Z,1756936913.551 [CommandExec](IMPORTANT): got command run
2025-09-03T22:01:53.553Z,1756936913.553 [CommandExec](IMPORTANT): Running 
2025-09-03T22:01:53.801Z,1756936913.801 [DefaultWithUndock] Stopped
2025-09-03T22:01:53.801Z,1756936913.801 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-09-03T22:01:53.801Z,1756936913.801 [DefaultWithUndock:A.Wait] Stopped
2025-09-03T22:01:53.801Z,1756936913.801 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:01:53.801Z,1756936913.801 [MissionManager](IMPORTANT): Started mission transit
2025-09-03T22:01:53.802Z,1756936913.802 [transit] Running Loop=1
2025-09-03T22:01:53.802Z,1756936913.802 [transit](DEBUG): Aggregate::initialize transit
2025-09-03T22:01:53.802Z,1756936913.802 [transit:StandardEnvelopes] Running Loop=1
2025-09-03T22:01:53.802Z,1756936913.802 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-09-03T22:01:53.802Z,1756936913.802 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-09-03T22:01:53.802Z,1756936913.802 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-09-03T22:01:53.802Z,1756936913.802 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-09-03T22:01:53.802Z,1756936913.802 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-09-03T22:01:53.802Z,1756936913.802 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-09-03T22:01:53.803Z,1756936913.803 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-09-03T22:01:53.803Z,1756936913.803 [transit:BackseatDriver] Running Loop=1
2025-09-03T22:01:53.803Z,1756936913.803 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-09-03T22:01:53.803Z,1756936913.803 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-09-03T22:01:53.803Z,1756936913.803 [transit:PowerOnly] Running Loop=1
2025-09-03T22:01:53.803Z,1756936913.803 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-09-03T22:01:53.803Z,1756936913.803 [transit:PowerOnly:A] Running Loop=1
2025-09-03T22:01:53.803Z,1756936913.803 [transit:PowerOnly:B] Running Loop=1
2025-09-03T22:01:53.803Z,1756936913.803 [transit:PowerOnly:C] Running Loop=1
2025-09-03T22:01:53.803Z,1756936913.803 [transit:PowerOnly:D] Running Loop=1
2025-09-03T22:01:53.803Z,1756936913.803 [transit:PowerOnly:E.Wait] Running Loop=1
2025-09-03T22:01:53.804Z,1756936913.804 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:01:53.804Z,1756936913.804 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-03T22:01:53.804Z,1756936913.804 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-03T22:01:53.804Z,1756936913.804 [transit:MassHold.Pitch] Running Loop=1
2025-09-03T22:01:53.804Z,1756936913.804 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-09-03T22:01:53.804Z,1756936913.804 [transit:B] Running Loop=1
2025-09-03T22:01:53.805Z,1756936913.805 [transit:MassHold.Pitch] Running Loop=1
2025-09-03T22:01:53.805Z,1756936913.805 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-03T22:01:53.805Z,1756936913.805 [transit:PowerOnly] Running Loop=1
2025-09-03T22:01:53.806Z,1756936913.806 [transit:PowerOnly:D] Stopped
2025-09-03T22:01:53.806Z,1756936913.806 [transit:PowerOnly:C] Stopped
2025-09-03T22:01:53.806Z,1756936913.806 [transit:PowerOnly:B] Stopped
2025-09-03T22:01:53.806Z,1756936913.806 [transit:PowerOnly:A] Stopped
2025-09-03T22:01:53.806Z,1756936913.806 [transit:BackseatDriver] Running Loop=1
2025-09-03T22:01:53.806Z,1756936913.806 [transit:StandardEnvelopes] Running Loop=1
2025-09-03T22:01:53.806Z,1756936913.806 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-09-03T22:01:53.807Z,1756936913.807 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-09-03T22:01:53.807Z,1756936913.807 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-09-03T22:01:53.807Z,1756936913.807 [transit:B] Stopped
2025-09-03T22:01:53.808Z,1756936913.808 [transit:C] Running Loop=1
2025-09-03T22:01:54.199Z,1756936914.199 [transit:C] Stopped
2025-09-03T22:01:54.199Z,1756936914.199 [transit:E] Running Loop=1
2025-09-03T22:01:54.594Z,1756936914.594 [transit:E] Stopped
2025-09-03T22:01:54.594Z,1756936914.594 [transit:F] Running Loop=1
2025-09-03T22:01:54.975Z,1756936914.975 [transit:F] Stopped
2025-09-03T22:01:54.975Z,1756936914.975 [transit:G] Running Loop=1
2025-09-03T22:01:55.398Z,1756936915.398 [transit:G] Stopped
2025-09-03T22:01:55.398Z,1756936915.398 [transit:Transit] Running Loop=1
2025-09-03T22:01:55.398Z,1756936915.398 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-09-03T22:01:55.399Z,1756936915.399 [transit:Transit:A.Pitch] Running Loop=1
2025-09-03T22:01:55.399Z,1756936915.399 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-09-03T22:01:55.399Z,1756936915.399 [transit:Transit:B.SetSpeed] Running Loop=1
2025-09-03T22:01:55.399Z,1756936915.399 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-09-03T22:01:55.399Z,1756936915.399 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-09-03T22:01:55.399Z,1756936915.399 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-09-03T22:01:55.401Z,1756936915.401 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.806965,-121.824326
2025-09-03T22:01:55.793Z,1756936915.793 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.806965,-121.824326
2025-09-03T22:01:55.794Z,1756936915.794 [transit:Transit:B.SetSpeed] Running Loop=1
2025-09-03T22:01:55.794Z,1756936915.794 [transit:Transit:A.Pitch] Running Loop=1
2025-09-03T22:01:58.644Z,1756936918.644 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:02:26.761Z,1756936946.761 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.800873
2025-09-03T22:02:27.765Z,1756936947.765 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:02:34.300Z,1756936954.300 [Radio_Surface](INFO): Powering down
2025-09-03T22:02:40.360Z,1756936960.360 [Radio_Surface](INFO): Powering up
2025-09-03T22:02:45.613Z,1756936965.613 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-09-03T22:02:45.613Z,1756936965.613 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0
2025-09-03T22:02:57.884Z,1756936977.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:02:58.888Z,1756936978.888 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:03:29.008Z,1756937009.008 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:03:30.012Z,1756937010.012 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:04:00.144Z,1756937040.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:04:01.148Z,1756937041.148 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:04:03.245Z,1756937043.245 [Radio_Surface](INFO): Powering down
2025-09-03T22:04:31.270Z,1756937071.270 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:04:32.272Z,1756937072.272 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-03T22:04:32.273Z,1756937072.273 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:05:02.395Z,1756937102.395 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:05:03.396Z,1756937103.396 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:05:33.517Z,1756937133.517 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:05:34.520Z,1756937134.520 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:06:03.218Z,1756937163.218 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T22:06:04.644Z,1756937164.644 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:06:05.648Z,1756937165.648 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:06:33.874Z,1756937193.874 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T22:06:35.768Z,1756937195.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:06:36.773Z,1756937196.773 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:07:06.893Z,1756937226.893 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:07:07.896Z,1756937227.896 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:07:38.020Z,1756937258.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:07:39.024Z,1756937259.024 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:08:09.144Z,1756937289.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:08:10.150Z,1756937290.150 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:08:40.272Z,1756937320.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:08:41.277Z,1756937321.277 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:09:11.404Z,1756937351.404 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:09:12.408Z,1756937352.408 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:09:17.907Z,1756937357.907 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-03T22:09:17.908Z,1756937357.908 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-03T22:09:17.909Z,1756937357.909 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-03T22:09:17.909Z,1756937357.909 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-03T22:09:17.910Z,1756937357.910 [BPC1](ERROR): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-03T22:09:17.911Z,1756937357.911 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-03T22:09:17.912Z,1756937357.912 [BPC1](INFO): Calculating totals. Valid battery stick count: 31. Valid reserve battery stick count: 2.
2025-09-03T22:09:17.914Z,1756937357.914 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-09-03T22:09:42.528Z,1756937382.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:09:43.533Z,1756937383.533 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:10:13.652Z,1756937413.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:10:14.656Z,1756937414.656 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:10:44.780Z,1756937444.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:10:45.784Z,1756937445.784 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:11:15.906Z,1756937475.906 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:11:16.908Z,1756937476.908 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:11:47.032Z,1756937507.032 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:11:48.036Z,1756937508.036 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:11:53.860Z,1756937513.860 [transit](INFO): Timed out from 2025-09-03T22:01:53.8Z
2025-09-03T22:11:53.860Z,1756937513.860 [MissionManager](INFO): transit is completed.
2025-09-03T22:11:53.860Z,1756937513.860 [MissionManager](INFO): Uninitializing Mission transit
2025-09-03T22:11:53.861Z,1756937513.861 [transit] Stopped
2025-09-03T22:11:53.861Z,1756937513.861 [transit](DEBUG): Aggregate::uninitialize transit
2025-09-03T22:11:53.861Z,1756937513.861 [transit:StandardEnvelopes] Stopped
2025-09-03T22:11:53.861Z,1756937513.861 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-09-03T22:11:53.861Z,1756937513.861 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-09-03T22:11:53.861Z,1756937513.861 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-09-03T22:11:53.861Z,1756937513.861 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-09-03T22:11:53.861Z,1756937513.861 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-09-03T22:11:53.861Z,1756937513.861 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-09-03T22:11:53.861Z,1756937513.861 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-09-03T22:11:53.861Z,1756937513.861 [transit:BackseatDriver] Stopped
2025-09-03T22:11:53.861Z,1756937513.861 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-09-03T22:11:53.862Z,1756937513.862 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-09-03T22:11:53.862Z,1756937513.862 [transit:PowerOnly] Stopped
2025-09-03T22:11:53.862Z,1756937513.862 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-09-03T22:11:53.862Z,1756937513.862 [transit:PowerOnly:E.Wait] Stopped
2025-09-03T22:11:53.862Z,1756937513.862 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:11:53.862Z,1756937513.862 [transit:BuoyancyHold.Buoyancy] Stopped
2025-09-03T22:11:53.862Z,1756937513.862 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-03T22:11:53.862Z,1756937513.862 [transit:MassHold.Pitch] Stopped
2025-09-03T22:11:53.862Z,1756937513.862 [transit:Transit] Stopped
2025-09-03T22:11:53.862Z,1756937513.862 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-09-03T22:11:53.862Z,1756937513.862 [transit:Transit:A.Pitch] Stopped
2025-09-03T22:11:53.862Z,1756937513.862 [transit:Transit:B.SetSpeed] Stopped
2025-09-03T22:11:53.862Z,1756937513.862 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-09-03T22:11:53.862Z,1756937513.862 [transit:Transit:Wpt1.Waypoint] Stopped
2025-09-03T22:11:53.863Z,1756937513.863 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-09-03T22:11:54.265Z,1756937514.265 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-09-03T22:11:54.265Z,1756937514.265 [DefaultWithUndock] Running Loop=1
2025-09-03T22:11:54.265Z,1756937514.265 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-09-03T22:11:54.266Z,1756937514.266 [DefaultWithUndock:A.Wait] Running Loop=1
2025-09-03T22:11:54.266Z,1756937514.266 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:12:18.156Z,1756937538.156 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:12:19.160Z,1756937539.160 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:12:24.574Z,1756937544.574 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-09-03T22:12:24.574Z,1756937544.574 [DefaultWithUndock:A.Wait] Stopped
2025-09-03T22:12:24.574Z,1756937544.574 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:12:24.575Z,1756937544.575 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-09-03T22:12:24.575Z,1756937544.575 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-09-03T22:12:24.575Z,1756937544.575 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-09-03T22:12:24.575Z,1756937544.575 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-09-03T22:12:24.575Z,1756937544.575 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-09-03T22:12:24.575Z,1756937544.575 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-09-03T22:12:24.575Z,1756937544.575 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-09-03T22:12:24.968Z,1756937544.968 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-09-03T22:12:24.969Z,1756937544.969 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-09-03T22:12:24.970Z,1756937544.970 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 10.667964 m.
2025-09-03T22:12:24.970Z,1756937544.970 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-09-03T22:12:25.794Z,1756937545.794 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-09-03T22:12:25.910Z,1756937545.910 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-09-03T22:12:28.305Z,1756937548.305 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2025-09-03T22:12:29.473Z,1756937549.473 [DockingStepper](INFO): Changing to mode: 3
2025-09-03T22:12:34.685Z,1756937554.685 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-09-03T22:12:34.861Z,1756937554.861 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:34.861Z,1756937554.861 [DAT](INFO): setting remote address to 0
2025-09-03T22:12:35.118Z,1756937555.118 [DAT](INFO): DAT read: user:6>

2025-09-03T22:12:35.119Z,1756937555.119 [DAT](INFO): DAT read: RemoteAddr      | 0           

2025-09-03T22:12:35.121Z,1756937555.121 [DAT](INFO): set remote address to 0
2025-09-03T22:12:35.122Z,1756937555.122 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:35.124Z,1756937555.124 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:35.365Z,1756937555.365 [DAT](INFO): DAT read: user:7>

2025-09-03T22:12:35.366Z,1756937555.366 [DAT](INFO): DAT read: Tx time:22:12:34.5182

2025-09-03T22:12:35.366Z,1756937555.366 [DAT](INFO): Ping request sent.
2025-09-03T22:12:35.366Z,1756937555.366 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:12:35.367Z,1756937555.367 [DAT](INFO): publishing transmit ping time
2025-09-03T22:12:35.367Z,1756937555.367 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000921
2025-09-03T22:12:35.502Z,1756937555.502 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-09-03T22:12:35.617Z,1756937555.617 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250722
2025-09-03T22:12:35.869Z,1756937555.869 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502579
2025-09-03T22:12:36.121Z,1756937556.121 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754473
2025-09-03T22:12:36.373Z,1756937556.373 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006477
2025-09-03T22:12:36.626Z,1756937556.626 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259762
2025-09-03T22:12:36.877Z,1756937556.877 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510529
2025-09-03T22:12:37.129Z,1756937557.129 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762431
2025-09-03T22:12:37.381Z,1756937557.381 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014757
2025-09-03T22:12:37.633Z,1756937557.633 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266538
2025-09-03T22:12:37.885Z,1756937557.885 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518563
2025-09-03T22:12:38.137Z,1756937558.137 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770691
2025-09-03T22:12:38.389Z,1756937558.389 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022532
2025-09-03T22:12:38.641Z,1756937558.641 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274611
2025-09-03T22:12:38.893Z,1756937558.893 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526494
2025-09-03T22:12:39.146Z,1756937559.146 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778921
2025-09-03T22:12:39.397Z,1756937559.397 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030482
2025-09-03T22:12:39.650Z,1756937559.650 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283324
2025-09-03T22:12:39.901Z,1756937559.901 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534437
2025-09-03T22:12:40.153Z,1756937560.153 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786458
2025-09-03T22:12:40.405Z,1756937560.405 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038852
2025-09-03T22:12:40.657Z,1756937560.657 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290542
2025-09-03T22:12:40.909Z,1756937560.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542484
2025-09-03T22:12:41.178Z,1756937561.178 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.811378
2025-09-03T22:12:41.429Z,1756937561.429 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.062548
2025-09-03T22:12:41.681Z,1756937561.681 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.314824
2025-09-03T22:12:41.934Z,1756937561.934 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.567053
2025-09-03T22:12:42.185Z,1756937562.185 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.818513
2025-09-03T22:12:42.437Z,1756937562.437 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.070538
2025-09-03T22:12:42.689Z,1756937562.689 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.322501
2025-09-03T22:12:42.941Z,1756937562.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.574549
2025-09-03T22:12:43.206Z,1756937563.206 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.839365
2025-09-03T22:12:43.457Z,1756937563.457 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.090773
2025-09-03T22:12:43.709Z,1756937563.709 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.342549
2025-09-03T22:12:43.961Z,1756937563.961 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.594555
2025-09-03T22:12:44.213Z,1756937564.213 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.846754
2025-09-03T22:12:44.465Z,1756937564.465 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.098530
2025-09-03T22:12:44.717Z,1756937564.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.350467
2025-09-03T22:12:44.779Z,1756937564.779 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-09-03T22:12:44.969Z,1756937564.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.602711
2025-09-03T22:12:45.222Z,1756937565.222 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.855008
2025-09-03T22:12:45.473Z,1756937565.473 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:45.473Z,1756937565.473 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:45.473Z,1756937565.473 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:45.725Z,1756937565.725 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:45.725Z,1756937565.725 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:45.725Z,1756937565.725 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:45.977Z,1756937565.977 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:45.977Z,1756937565.977 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:45.977Z,1756937565.977 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:46.229Z,1756937566.229 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:46.229Z,1756937566.229 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:46.229Z,1756937566.229 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:46.481Z,1756937566.481 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:46.481Z,1756937566.481 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:46.481Z,1756937566.481 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:46.736Z,1756937566.736 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:46.737Z,1756937566.737 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:46.738Z,1756937566.738 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:46.985Z,1756937566.985 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:46.985Z,1756937566.985 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:46.985Z,1756937566.985 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:47.237Z,1756937567.237 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:47.238Z,1756937567.238 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:47.238Z,1756937567.238 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:47.489Z,1756937567.489 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:47.489Z,1756937567.489 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:47.489Z,1756937567.489 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:47.741Z,1756937567.741 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:12:47.741Z,1756937567.741 [DAT](INFO): response not received
2025-09-03T22:12:47.742Z,1756937567.742 [DAT](INFO): DAT read: user:8>

2025-09-03T22:12:47.742Z,1756937567.742 [DAT](INFO): DAT read: Tx time:22:12:46.9681

2025-09-03T22:12:47.743Z,1756937567.743 [DAT](INFO): Ping request sent.
2025-09-03T22:12:47.743Z,1756937567.743 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:12:47.743Z,1756937567.743 [DAT](INFO): publishing transmit ping time
2025-09-03T22:12:47.743Z,1756937567.743 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000731
2025-09-03T22:12:47.993Z,1756937567.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250155
2025-09-03T22:12:48.245Z,1756937568.245 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502432
2025-09-03T22:12:48.497Z,1756937568.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754687
2025-09-03T22:12:48.749Z,1756937568.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006259
2025-09-03T22:12:49.001Z,1756937569.001 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258303
2025-09-03T22:12:49.255Z,1756937569.255 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.512227
2025-09-03T22:12:49.290Z,1756937569.290 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:12:49.505Z,1756937569.505 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762427
2025-09-03T22:12:49.757Z,1756937569.757 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014275
2025-09-03T22:12:50.009Z,1756937570.009 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266347
2025-09-03T22:12:50.262Z,1756937570.262 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518993
2025-09-03T22:12:50.292Z,1756937570.292 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:12:50.514Z,1756937570.514 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770763
2025-09-03T22:12:50.765Z,1756937570.765 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022209
2025-09-03T22:12:51.017Z,1756937571.017 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274241
2025-09-03T22:12:51.272Z,1756937571.272 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.528598
2025-09-03T22:12:51.521Z,1756937571.521 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778694
2025-09-03T22:12:51.773Z,1756937571.773 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030540
2025-09-03T22:12:52.025Z,1756937572.025 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282313
2025-09-03T22:12:52.277Z,1756937572.277 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534201
2025-09-03T22:12:52.530Z,1756937572.530 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.787010
2025-09-03T22:12:52.781Z,1756937572.781 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038288
2025-09-03T22:12:53.033Z,1756937573.033 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290216
2025-09-03T22:12:53.288Z,1756937573.288 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.544461
2025-09-03T22:12:53.537Z,1756937573.537 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794428
2025-09-03T22:12:53.789Z,1756937573.789 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046236
2025-09-03T22:12:54.041Z,1756937574.041 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298377
2025-09-03T22:12:54.293Z,1756937574.293 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550196
2025-09-03T22:12:54.545Z,1756937574.545 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802708
2025-09-03T22:12:54.798Z,1756937574.798 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054792
2025-09-03T22:12:54.871Z,1756937574.871 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-09-03T22:12:55.049Z,1756937575.049 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306272
2025-09-03T22:12:55.304Z,1756937575.304 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.560495
2025-09-03T22:12:55.553Z,1756937575.553 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810511
2025-09-03T22:12:55.677Z,1756937575.677 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2025-09-03T22:12:55.805Z,1756937575.805 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062255
2025-09-03T22:12:56.057Z,1756937576.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314221
2025-09-03T22:12:56.082Z,1756937576.082 [DockingStepper](INFO): Changing to mode: 1
2025-09-03T22:12:56.309Z,1756937576.309 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566531
2025-09-03T22:12:56.561Z,1756937576.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818293
2025-09-03T22:12:56.813Z,1756937576.813 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070170
2025-09-03T22:12:57.065Z,1756937577.065 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322224
2025-09-03T22:12:57.317Z,1756937577.317 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574374
2025-09-03T22:12:57.569Z,1756937577.569 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826210
2025-09-03T22:12:57.821Z,1756937577.821 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:57.821Z,1756937577.821 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:57.822Z,1756937577.822 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:58.075Z,1756937578.075 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:58.075Z,1756937578.075 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:58.075Z,1756937578.075 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:58.329Z,1756937578.329 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:58.329Z,1756937578.329 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:58.329Z,1756937578.329 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:58.581Z,1756937578.581 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:58.581Z,1756937578.581 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:58.581Z,1756937578.581 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:58.833Z,1756937578.833 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:58.833Z,1756937578.833 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:58.833Z,1756937578.833 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:59.085Z,1756937579.085 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:59.085Z,1756937579.085 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:59.085Z,1756937579.085 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:59.337Z,1756937579.337 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:59.337Z,1756937579.337 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:59.337Z,1756937579.337 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:59.589Z,1756937579.589 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:59.589Z,1756937579.589 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:59.589Z,1756937579.589 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:12:59.841Z,1756937579.841 [DAT](INFO): Reached modem response timeout
2025-09-03T22:12:59.841Z,1756937579.841 [DAT](DEBUG): Acoustic response timeout
2025-09-03T22:12:59.841Z,1756937579.841 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-09-03T22:13:00.099Z,1756937580.099 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:13:00.099Z,1756937580.099 [DAT](INFO): response not received
2025-09-03T22:13:00.101Z,1756937580.101 [DAT](INFO): DAT read: user:9>

2025-09-03T22:13:00.102Z,1756937580.102 [DAT](INFO): DAT read: Tx time:22:12:59.4181

2025-09-03T22:13:00.102Z,1756937580.102 [DAT](INFO): Ping request sent.
2025-09-03T22:13:00.102Z,1756937580.102 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:13:00.102Z,1756937580.102 [DAT](INFO): publishing transmit ping time
2025-09-03T22:13:00.103Z,1756937580.103 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000751
2025-09-03T22:13:00.354Z,1756937580.354 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251956
2025-09-03T22:13:00.606Z,1756937580.606 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504087
2025-09-03T22:13:00.857Z,1756937580.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754920
2025-09-03T22:13:01.109Z,1756937581.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007062
2025-09-03T22:13:01.362Z,1756937581.362 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260359
2025-09-03T22:13:01.613Z,1756937581.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511499
2025-09-03T22:13:01.865Z,1756937581.865 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763062
2025-09-03T22:13:02.117Z,1756937582.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015049
2025-09-03T22:13:02.138Z,1756937582.138 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2025-09-03T22:13:02.369Z,1756937582.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266980
2025-09-03T22:13:02.545Z,1756937582.545 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2025-09-03T22:13:02.545Z,1756937582.545 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-09-03T22:13:02.545Z,1756937582.545 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2025-09-03T22:13:02.546Z,1756937582.546 [DefaultWithUndock:LeaveDock] Stopped
2025-09-03T22:13:02.546Z,1756937582.546 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-09-03T22:13:02.546Z,1756937582.546 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-09-03T22:13:02.546Z,1756937582.546 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-09-03T22:13:02.546Z,1756937582.546 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2025-09-03T22:13:02.546Z,1756937582.546 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2025-09-03T22:13:02.546Z,1756937582.546 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2025-09-03T22:13:02.546Z,1756937582.546 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-03T22:13:02.547Z,1756937582.547 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-09-03T22:13:02.547Z,1756937582.547 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-09-03T22:13:02.547Z,1756937582.547 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-09-03T22:13:02.547Z,1756937582.547 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-09-03T22:13:02.548Z,1756937582.548 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-03T22:13:02.549Z,1756937582.549 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-03T22:13:02.621Z,1756937582.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519178
2025-09-03T22:13:02.873Z,1756937582.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770952
2025-09-03T22:13:03.125Z,1756937583.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022981
2025-09-03T22:13:03.368Z,1756937583.368 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -24.00 mm (3 active estimators).
2025-09-03T22:13:03.377Z,1756937583.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275140
2025-09-03T22:13:03.492Z,1756937583.492 [Radio_Surface](INFO): Powering up
2025-09-03T22:13:03.629Z,1756937583.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526952
2025-09-03T22:13:03.881Z,1756937583.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779335
2025-09-03T22:13:04.133Z,1756937584.133 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031109
2025-09-03T22:13:04.385Z,1756937584.385 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282945
2025-09-03T22:13:04.637Z,1756937584.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535463
2025-09-03T22:13:04.889Z,1756937584.889 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.787013
2025-09-03T22:13:05.146Z,1756937585.146 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.043821
2025-09-03T22:13:05.393Z,1756937585.393 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290966
2025-09-03T22:13:05.646Z,1756937585.646 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.544027
2025-09-03T22:13:05.898Z,1756937585.898 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795556
2025-09-03T22:13:06.149Z,1756937586.149 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047119
2025-09-03T22:13:06.402Z,1756937586.402 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.299690
2025-09-03T22:13:06.653Z,1756937586.653 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.551040
2025-09-03T22:13:06.905Z,1756937586.905 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802960
2025-09-03T22:13:07.160Z,1756937587.160 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058058
2025-09-03T22:13:07.409Z,1756937587.409 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.307006
2025-09-03T22:13:07.662Z,1756937587.662 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559590
2025-09-03T22:13:07.914Z,1756937587.914 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.812052
2025-09-03T22:13:08.165Z,1756937588.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.063447
2025-09-03T22:13:08.417Z,1756937588.417 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314980
2025-09-03T22:13:08.669Z,1756937588.669 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.567048
2025-09-03T22:13:08.773Z,1756937588.773 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-09-03T22:13:08.774Z,1756937588.774 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0
2025-09-03T22:13:08.921Z,1756937588.921 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819131
2025-09-03T22:13:09.178Z,1756937589.178 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.075828
2025-09-03T22:13:09.429Z,1756937589.429 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.327035
2025-09-03T22:13:09.681Z,1756937589.681 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.579039
2025-09-03T22:13:09.933Z,1756937589.933 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.831323
2025-09-03T22:13:10.186Z,1756937590.186 [DAT](INFO): Reached modem response timeout
2025-09-03T22:13:12.705Z,1756937592.705 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:13:12.705Z,1756937592.705 [DAT](INFO): response not received
2025-09-03T22:13:12.706Z,1756937592.706 [DAT](INFO): DAT read: user:10>

2025-09-03T22:13:12.706Z,1756937592.706 [DAT](INFO): DAT read: Tx time:22:13:11.8681

2025-09-03T22:13:12.707Z,1756937592.707 [DAT](INFO): Ping request sent.
2025-09-03T22:13:12.707Z,1756937592.707 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:13:12.707Z,1756937592.707 [DAT](INFO): publishing transmit ping time
2025-09-03T22:13:12.708Z,1756937592.708 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000733
2025-09-03T22:13:12.958Z,1756937592.958 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251231
2025-09-03T22:13:13.209Z,1756937593.209 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502216
2025-09-03T22:13:13.461Z,1756937593.461 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754164
2025-09-03T22:13:13.715Z,1756937593.715 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007646
2025-09-03T22:13:13.968Z,1756937593.968 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.261265
2025-09-03T22:13:14.217Z,1756937594.217 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510163
2025-09-03T22:13:14.469Z,1756937594.469 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762254
2025-09-03T22:13:14.721Z,1756937594.721 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014192
2025-09-03T22:13:14.973Z,1756937594.973 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266165
2025-09-03T22:13:15.227Z,1756937595.227 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.520384
2025-09-03T22:13:15.477Z,1756937595.477 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770224
2025-09-03T22:13:15.729Z,1756937595.729 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022237
2025-09-03T22:13:15.981Z,1756937595.981 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274171
2025-09-03T22:13:16.233Z,1756937596.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526590
2025-09-03T22:13:16.486Z,1756937596.486 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779053
2025-09-03T22:13:16.737Z,1756937596.737 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030222
2025-09-03T22:13:16.990Z,1756937596.990 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282743
2025-09-03T22:13:17.244Z,1756937597.244 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.536419
2025-09-03T22:13:17.493Z,1756937597.493 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786102
2025-09-03T22:13:17.745Z,1756937597.745 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038197
2025-09-03T22:13:17.997Z,1756937597.997 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290095
2025-09-03T22:13:18.249Z,1756937598.249 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542165
2025-09-03T22:13:18.501Z,1756937598.501 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794188
2025-09-03T22:13:18.757Z,1756937598.757 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.049696
2025-09-03T22:13:19.005Z,1756937599.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298298
2025-09-03T22:13:19.260Z,1756937599.260 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.552796
2025-09-03T22:13:19.509Z,1756937599.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802075
2025-09-03T22:13:19.761Z,1756937599.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054523
2025-09-03T22:13:20.013Z,1756937600.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306541
2025-09-03T22:13:20.265Z,1756937600.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558138
2025-09-03T22:13:20.420Z,1756937600.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:13:20.517Z,1756937600.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810148
2025-09-03T22:13:20.774Z,1756937600.774 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.064315
2025-09-03T22:13:21.021Z,1756937601.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314172
2025-09-03T22:13:21.278Z,1756937601.278 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570950
2025-09-03T22:13:21.424Z,1756937601.424 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-03T22:13:21.424Z,1756937601.424 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:13:21.525Z,1756937601.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818333
2025-09-03T22:13:21.777Z,1756937601.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070133
2025-09-03T22:13:22.030Z,1756937602.030 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322445
2025-09-03T22:13:22.281Z,1756937602.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574205
2025-09-03T22:13:22.533Z,1756937602.533 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826607
2025-09-03T22:13:25.053Z,1756937605.053 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:13:25.053Z,1756937605.053 [DAT](INFO): response not received
2025-09-03T22:13:25.054Z,1756937605.054 [DAT](INFO): DAT read: user:11>

2025-09-03T22:13:25.054Z,1756937605.054 [DAT](INFO): DAT read: Tx time:22:13:24.3180

2025-09-03T22:13:25.055Z,1756937605.055 [DAT](INFO): Ping request sent.
2025-09-03T22:13:25.055Z,1756937605.055 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:13:25.055Z,1756937605.055 [DAT](INFO): publishing transmit ping time
2025-09-03T22:13:25.056Z,1756937605.056 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000749
2025-09-03T22:13:25.306Z,1756937605.306 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251234
2025-09-03T22:13:25.557Z,1756937605.557 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502246
2025-09-03T22:13:25.810Z,1756937605.810 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754848
2025-09-03T22:13:26.061Z,1756937606.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006218
2025-09-03T22:13:26.313Z,1756937606.313 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258132
2025-09-03T22:13:26.566Z,1756937606.566 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510877
2025-09-03T22:13:26.817Z,1756937606.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762375
2025-09-03T22:13:27.073Z,1756937607.073 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018523
2025-09-03T22:13:27.325Z,1756937607.325 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270525
2025-09-03T22:13:27.581Z,1756937607.581 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.525725
2025-09-03T22:13:27.830Z,1756937607.830 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774996
2025-09-03T22:13:28.081Z,1756937608.081 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026159
2025-09-03T22:13:28.333Z,1756937608.333 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278210
2025-09-03T22:13:28.590Z,1756937608.590 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.534972
2025-09-03T22:13:28.841Z,1756937608.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.786287
2025-09-03T22:13:29.093Z,1756937609.093 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.038294
2025-09-03T22:13:29.345Z,1756937609.345 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.290239
2025-09-03T22:13:29.597Z,1756937609.597 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.542210
2025-09-03T22:13:29.849Z,1756937609.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.794375
2025-09-03T22:13:30.101Z,1756937610.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.046205
2025-09-03T22:13:30.353Z,1756937610.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.298416
2025-09-03T22:13:30.605Z,1756937610.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.550187
2025-09-03T22:13:30.857Z,1756937610.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.802132
2025-09-03T22:13:31.109Z,1756937611.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.054221
2025-09-03T22:13:31.361Z,1756937611.361 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.306211
2025-09-03T22:13:31.614Z,1756937611.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.559305
2025-09-03T22:13:31.865Z,1756937611.865 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.810154
2025-09-03T22:13:32.117Z,1756937612.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.062359
2025-09-03T22:13:32.369Z,1756937612.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.314215
2025-09-03T22:13:32.621Z,1756937612.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.566243
2025-09-03T22:13:32.873Z,1756937612.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.818249
2025-09-03T22:13:33.125Z,1756937613.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.070520
2025-09-03T22:13:33.377Z,1756937613.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.322156
2025-09-03T22:13:33.629Z,1756937613.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.574200
2025-09-03T22:13:33.881Z,1756937613.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.826206
2025-09-03T22:13:34.133Z,1756937614.133 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.078670
2025-09-03T22:13:34.389Z,1756937614.389 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.333962
2025-09-03T22:13:34.637Z,1756937614.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.582081
2025-09-03T22:13:34.890Z,1756937614.890 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.834805
2025-09-03T22:13:37.409Z,1756937617.409 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:13:37.409Z,1756937617.409 [DAT](INFO): response not received
2025-09-03T22:13:37.410Z,1756937617.410 [DAT](INFO): DAT read: user:12>

2025-09-03T22:13:37.410Z,1756937617.410 [DAT](ERROR): No response from remote modem.
2025-09-03T22:13:37.661Z,1756937617.661 [DAT](INFO): DAT read: Tx time:22:13:36.7680

2025-09-03T22:13:37.662Z,1756937617.662 [DAT](INFO): Ping request sent.
2025-09-03T22:13:37.662Z,1756937617.662 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:13:37.662Z,1756937617.662 [DAT](INFO): publishing transmit ping time
2025-09-03T22:13:37.663Z,1756937617.663 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001014
2025-09-03T22:13:37.913Z,1756937617.913 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251069
2025-09-03T22:13:38.165Z,1756937618.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503102
2025-09-03T22:13:38.417Z,1756937618.417 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755202
2025-09-03T22:13:38.669Z,1756937618.669 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007089
2025-09-03T22:13:38.921Z,1756937618.921 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259226
2025-09-03T22:13:39.173Z,1756937619.173 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511104
2025-09-03T22:13:39.425Z,1756937619.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763082
2025-09-03T22:13:39.677Z,1756937619.677 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015195
2025-09-03T22:13:39.929Z,1756937619.929 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267058
2025-09-03T22:13:40.182Z,1756937620.182 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519917
2025-09-03T22:13:40.450Z,1756937620.450 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.787973
2025-09-03T22:13:40.701Z,1756937620.701 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.039058
2025-09-03T22:13:40.954Z,1756937620.954 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.291802
2025-09-03T22:13:41.205Z,1756937621.205 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.543189
2025-09-03T22:13:41.457Z,1756937621.457 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.795053
2025-09-03T22:13:41.709Z,1756937621.709 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.047143
2025-09-03T22:13:41.961Z,1756937621.961 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.299102
2025-09-03T22:13:42.213Z,1756937622.213 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.551041
2025-09-03T22:13:42.465Z,1756937622.465 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.803202
2025-09-03T22:13:42.717Z,1756937622.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.055108
2025-09-03T22:13:42.839Z,1756937622.839 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2025-09-03T22:13:42.839Z,1756937622.839 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-03T22:13:42.839Z,1756937622.839 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:13:42.969Z,1756937622.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.307027
2025-09-03T22:13:43.221Z,1756937623.221 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.559046
2025-09-03T22:13:43.473Z,1756937623.473 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.811359
2025-09-03T22:13:43.726Z,1756937623.726 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.063556
2025-09-03T22:13:43.977Z,1756937623.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.315421
2025-09-03T22:13:44.229Z,1756937624.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.567350
2025-09-03T22:13:44.481Z,1756937624.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.819330
2025-09-03T22:13:44.733Z,1756937624.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.071352
2025-09-03T22:13:44.985Z,1756937624.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.323163
2025-09-03T22:13:45.238Z,1756937625.238 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.575568
2025-09-03T22:13:45.489Z,1756937625.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.827119
2025-09-03T22:13:45.741Z,1756937625.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.079144
2025-09-03T22:13:45.993Z,1756937625.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.331052
2025-09-03T22:13:46.245Z,1756937626.245 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.583068
2025-09-03T22:13:46.497Z,1756937626.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.835349
2025-09-03T22:13:46.749Z,1756937626.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.087064
2025-09-03T22:13:47.001Z,1756937627.001 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.339047
2025-09-03T22:13:47.261Z,1756937627.261 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.598701
2025-09-03T22:13:47.513Z,1756937627.513 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.851019
2025-09-03T22:13:50.033Z,1756937630.033 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:13:50.034Z,1756937630.034 [DAT](INFO): response not received
2025-09-03T22:13:50.034Z,1756937630.034 [DAT](INFO): DAT read: user:13>

2025-09-03T22:13:50.035Z,1756937630.035 [DAT](INFO): DAT read: Tx time:22:13:49.2180

2025-09-03T22:13:50.035Z,1756937630.035 [DAT](INFO): Ping request sent.
2025-09-03T22:13:50.035Z,1756937630.035 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:13:50.035Z,1756937630.035 [DAT](INFO): publishing transmit ping time
2025-09-03T22:13:50.036Z,1756937630.036 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000856
2025-09-03T22:13:50.285Z,1756937630.285 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249829
2025-09-03T22:13:50.537Z,1756937630.537 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501760
2025-09-03T22:13:50.789Z,1756937630.789 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753792
2025-09-03T22:13:51.041Z,1756937631.041 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005802
2025-09-03T22:13:51.299Z,1756937631.299 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.263900
2025-09-03T22:13:51.545Z,1756937631.545 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:13:51.549Z,1756937631.549 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.513860
2025-09-03T22:13:51.801Z,1756937631.801 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765859
2025-09-03T22:13:52.053Z,1756937632.053 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018271
2025-09-03T22:13:52.305Z,1756937632.305 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270091
2025-09-03T22:13:52.549Z,1756937632.549 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:13:52.559Z,1756937632.559 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.523344
2025-09-03T22:13:52.810Z,1756937632.810 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774520
2025-09-03T22:13:53.061Z,1756937633.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.025779
2025-09-03T22:13:53.325Z,1756937633.325 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.289747
2025-09-03T22:13:53.569Z,1756937633.569 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.533742
2025-09-03T22:13:53.821Z,1756937633.821 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.785816
2025-09-03T22:13:54.073Z,1756937634.073 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.037785
2025-09-03T22:13:54.325Z,1756937634.325 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.289982
2025-09-03T22:13:54.577Z,1756937634.577 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.541905
2025-09-03T22:13:54.829Z,1756937634.829 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.793732
2025-09-03T22:13:55.081Z,1756937635.081 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.045809
2025-09-03T22:13:55.337Z,1756937635.337 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.301731
2025-09-03T22:13:55.589Z,1756937635.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.553804
2025-09-03T22:13:55.841Z,1756937635.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.805907
2025-09-03T22:13:56.093Z,1756937636.093 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.057727
2025-09-03T22:13:56.345Z,1756937636.345 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.309777
2025-09-03T22:13:56.597Z,1756937636.597 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.562271
2025-09-03T22:13:56.849Z,1756937636.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.813808
2025-09-03T22:13:57.101Z,1756937637.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.065765
2025-09-03T22:13:57.355Z,1756937637.355 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.319959
2025-09-03T22:13:57.614Z,1756937637.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.578906
2025-09-03T22:13:57.861Z,1756937637.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.826181
2025-09-03T22:13:58.113Z,1756937638.113 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.077863
2025-09-03T22:13:58.365Z,1756937638.365 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.329769
2025-09-03T22:13:58.618Z,1756937638.618 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.582684
2025-09-03T22:13:58.869Z,1756937638.869 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.833881
2025-09-03T22:13:59.121Z,1756937639.121 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.085740
2025-09-03T22:13:59.376Z,1756937639.376 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.341136
2025-09-03T22:13:59.630Z,1756937639.630 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.594388
2025-09-03T22:13:59.881Z,1756937639.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.845750
2025-09-03T22:14:02.405Z,1756937642.405 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:14:02.406Z,1756937642.406 [DAT](INFO): response not received
2025-09-03T22:14:02.406Z,1756937642.406 [DAT](INFO): DAT read: user:14>

2025-09-03T22:14:02.407Z,1756937642.407 [DAT](INFO): DAT read: Tx time:22:14:01.6679

2025-09-03T22:14:02.407Z,1756937642.407 [DAT](INFO): Ping request sent.
2025-09-03T22:14:02.407Z,1756937642.407 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:14:02.407Z,1756937642.407 [DAT](INFO): publishing transmit ping time
2025-09-03T22:14:02.408Z,1756937642.408 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000701
2025-09-03T22:14:02.657Z,1756937642.657 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250342
2025-09-03T22:14:02.909Z,1756937642.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501847
2025-09-03T22:14:03.161Z,1756937643.161 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754148
2025-09-03T22:14:03.415Z,1756937643.415 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008079
2025-09-03T22:14:03.669Z,1756937643.669 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.261893
2025-09-03T22:14:03.921Z,1756937643.921 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.513897
2025-09-03T22:14:04.173Z,1756937644.173 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765956
2025-09-03T22:14:04.425Z,1756937644.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.017828
2025-09-03T22:14:04.677Z,1756937644.677 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270291
2025-09-03T22:14:04.929Z,1756937644.929 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522144
2025-09-03T22:14:05.181Z,1756937645.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.773866
2025-09-03T22:14:05.435Z,1756937645.435 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027881
2025-09-03T22:14:05.689Z,1756937645.689 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.281966
2025-09-03T22:14:05.941Z,1756937645.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.533881
2025-09-03T22:14:06.194Z,1756937646.194 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.786592
2025-09-03T22:14:06.445Z,1756937646.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.037937
2025-09-03T22:14:06.697Z,1756937646.697 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.290061
2025-09-03T22:14:06.949Z,1756937646.949 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.541844
2025-09-03T22:14:07.201Z,1756937647.201 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.793920
2025-09-03T22:14:07.457Z,1756937647.457 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.049852
2025-09-03T22:14:07.709Z,1756937647.709 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.301871
2025-09-03T22:14:07.961Z,1756937647.961 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.553982
2025-09-03T22:14:08.213Z,1756937648.213 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.805856
2025-09-03T22:14:08.465Z,1756937648.465 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.058040
2025-09-03T22:14:08.717Z,1756937648.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.310063
2025-09-03T22:14:08.969Z,1756937648.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.561896
2025-09-03T22:14:09.225Z,1756937649.225 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.816658
2025-09-03T22:14:09.477Z,1756937649.477 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.069852
2025-09-03T22:14:09.729Z,1756937649.729 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.321861
2025-09-03T22:14:09.981Z,1756937649.981 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.574157
2025-09-03T22:14:10.233Z,1756937650.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.825915
2025-09-03T22:14:10.485Z,1756937650.485 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.077822
2025-09-03T22:14:10.737Z,1756937650.737 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.330276
2025-09-03T22:14:10.989Z,1756937650.989 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.581996
2025-09-03T22:14:11.246Z,1756937651.246 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.838061
2025-09-03T22:14:11.497Z,1756937651.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.089787
2025-09-03T22:14:11.749Z,1756937651.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.341930
2025-09-03T22:14:12.001Z,1756937652.001 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.593863
2025-09-03T22:14:12.253Z,1756937652.253 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.845814
2025-09-03T22:14:14.777Z,1756937654.777 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:14:14.778Z,1756937654.778 [DAT](INFO): response not received
2025-09-03T22:14:14.778Z,1756937654.778 [DAT](INFO): DAT read: user:15>

2025-09-03T22:14:14.779Z,1756937654.779 [DAT](ERROR): No response from remote modem.
2025-09-03T22:14:15.029Z,1756937655.029 [DAT](INFO): DAT read: Tx time:22:14:14.1179

2025-09-03T22:14:15.029Z,1756937655.029 [DAT](INFO): Ping request sent.
2025-09-03T22:14:15.029Z,1756937655.029 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:14:15.030Z,1756937655.030 [DAT](INFO): publishing transmit ping time
2025-09-03T22:14:15.030Z,1756937655.030 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000738
2025-09-03T22:14:15.284Z,1756937655.284 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.254052
2025-09-03T22:14:15.535Z,1756937655.535 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.505484
2025-09-03T22:14:15.546Z,1756937655.546 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T22:14:15.629Z,1756937655.629 [NAL9602](ERROR): received: 
+CSQ:0

OK15, 2, 0, 0, 0

OK
2025-09-03T22:14:15.790Z,1756937655.790 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.760108
2025-09-03T22:14:16.041Z,1756937656.041 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.011731
2025-09-03T22:14:16.293Z,1756937656.293 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.263529
2025-09-03T22:14:16.545Z,1756937656.545 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.515421
2025-09-03T22:14:16.797Z,1756937656.797 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.767586
2025-09-03T22:14:17.049Z,1756937657.049 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.019551
2025-09-03T22:14:17.304Z,1756937657.304 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.273708
2025-09-03T22:14:17.557Z,1756937657.557 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.527407
2025-09-03T22:14:17.809Z,1756937657.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.779526
2025-09-03T22:14:18.061Z,1756937658.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.031477
2025-09-03T22:14:18.313Z,1756937658.313 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.283741
2025-09-03T22:14:18.565Z,1756937658.565 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.535533
2025-09-03T22:14:18.817Z,1756937658.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.787826
2025-09-03T22:14:19.069Z,1756937659.069 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.039512
2025-09-03T22:14:19.321Z,1756937659.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.291545
2025-09-03T22:14:19.577Z,1756937659.577 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.547450
2025-09-03T22:14:19.829Z,1756937659.829 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.799637
2025-09-03T22:14:20.081Z,1756937660.081 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.051685
2025-09-03T22:14:20.333Z,1756937660.333 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.303482
2025-09-03T22:14:20.585Z,1756937660.585 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.555450
2025-09-03T22:14:20.840Z,1756937660.840 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.809974
2025-09-03T22:14:21.089Z,1756937661.089 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.059435
2025-09-03T22:14:21.341Z,1756937661.341 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.311488
2025-09-03T22:14:21.595Z,1756937661.595 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.565694
2025-09-03T22:14:21.849Z,1756937661.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.819878
2025-09-03T22:14:22.101Z,1756937662.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.071818
2025-09-03T22:14:22.353Z,1756937662.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.323479
2025-09-03T22:14:22.605Z,1756937662.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.575474
2025-09-03T22:14:22.669Z,1756937662.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:14:22.857Z,1756937662.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.827838
2025-09-03T22:14:23.109Z,1756937663.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.079558
2025-09-03T22:14:23.361Z,1756937663.361 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.331445
2025-09-03T22:14:23.621Z,1756937663.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.591551
2025-09-03T22:14:23.672Z,1756937663.672 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:14:23.873Z,1756937663.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.843523
2025-09-03T22:14:24.125Z,1756937664.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.095492
2025-09-03T22:14:24.377Z,1756937664.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.347482
2025-09-03T22:14:24.629Z,1756937664.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.599531
2025-09-03T22:14:24.881Z,1756937664.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.851852
2025-09-03T22:14:27.401Z,1756937667.401 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:14:27.402Z,1756937667.402 [DAT](INFO): response not received
2025-09-03T22:14:27.402Z,1756937667.402 [DAT](INFO): DAT read: user:16>

2025-09-03T22:14:27.403Z,1756937667.403 [DAT](INFO): DAT read: Tx time:22:14:26.5678

2025-09-03T22:14:27.403Z,1756937667.403 [DAT](INFO): Ping request sent.
2025-09-03T22:14:27.403Z,1756937667.403 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:14:27.403Z,1756937667.403 [DAT](INFO): publishing transmit ping time
2025-09-03T22:14:27.404Z,1756937667.404 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000715
2025-09-03T22:14:27.655Z,1756937667.655 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252059
2025-09-03T22:14:27.909Z,1756937667.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.505828
2025-09-03T22:14:28.161Z,1756937668.161 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.757833
2025-09-03T22:14:28.413Z,1756937668.413 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.009896
2025-09-03T22:14:28.665Z,1756937668.665 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.261811
2025-09-03T22:14:28.917Z,1756937668.917 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.513790
2025-09-03T22:14:29.169Z,1756937669.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765907
2025-09-03T22:14:29.421Z,1756937669.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.017846
2025-09-03T22:14:29.675Z,1756937669.675 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.272153
2025-09-03T22:14:29.935Z,1756937669.935 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.531477
2025-09-03T22:14:30.185Z,1756937670.185 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.781791
2025-09-03T22:14:30.437Z,1756937670.437 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.033818
2025-09-03T22:14:30.689Z,1756937670.689 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.285839
2025-09-03T22:14:30.941Z,1756937670.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.537724
2025-09-03T22:14:31.193Z,1756937671.193 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.789810
2025-09-03T22:14:31.445Z,1756937671.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.042087
2025-09-03T22:14:31.697Z,1756937671.697 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.293814
2025-09-03T22:14:31.949Z,1756937671.949 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.545835
2025-09-03T22:14:32.201Z,1756937672.201 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.797881
2025-09-03T22:14:32.453Z,1756937672.453 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.049853
2025-09-03T22:14:32.705Z,1756937672.705 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.301856
2025-09-03T22:14:32.957Z,1756937672.957 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.554049
2025-09-03T22:14:33.209Z,1756937673.209 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.805862
2025-09-03T22:14:33.461Z,1756937673.461 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.058083
2025-09-03T22:14:33.717Z,1756937673.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.314142
2025-09-03T22:14:33.969Z,1756937673.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.565802
2025-09-03T22:14:34.221Z,1756937674.221 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.817855
2025-09-03T22:14:34.473Z,1756937674.473 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.069906
2025-09-03T22:14:34.725Z,1756937674.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.321815
2025-09-03T22:14:34.977Z,1756937674.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.574009
2025-09-03T22:14:35.229Z,1756937675.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.826027
2025-09-03T22:14:35.481Z,1756937675.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.077837
2025-09-03T22:14:35.737Z,1756937675.737 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.333712
2025-09-03T22:14:35.989Z,1756937675.989 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.585821
2025-09-03T22:14:36.241Z,1756937676.241 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.837797
2025-09-03T22:14:36.493Z,1756937676.493 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.089818
2025-09-03T22:14:36.745Z,1756937676.745 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.341874
2025-09-03T22:14:36.997Z,1756937676.997 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.593883
2025-09-03T22:14:37.253Z,1756937677.253 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.849455
2025-09-03T22:14:39.781Z,1756937679.781 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:14:39.781Z,1756937679.781 [DAT](INFO): response not received
2025-09-03T22:14:39.782Z,1756937679.782 [DAT](INFO): DAT read: user:17>

2025-09-03T22:14:39.783Z,1756937679.783 [DAT](INFO): DAT read: Tx time:22:14:39.0178

2025-09-03T22:14:39.783Z,1756937679.783 [DAT](INFO): Ping request sent.
2025-09-03T22:14:39.783Z,1756937679.783 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:14:39.783Z,1756937679.783 [DAT](INFO): publishing transmit ping time
2025-09-03T22:14:39.784Z,1756937679.784 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000716
2025-09-03T22:14:40.033Z,1756937680.033 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249960
2025-09-03T22:14:40.285Z,1756937680.285 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501986
2025-09-03T22:14:40.537Z,1756937680.537 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754190
2025-09-03T22:14:40.789Z,1756937680.789 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005970
2025-09-03T22:14:41.042Z,1756937681.042 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258971
2025-09-03T22:14:41.295Z,1756937681.295 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511662
2025-09-03T22:14:41.545Z,1756937681.545 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761982
2025-09-03T22:14:41.797Z,1756937681.797 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014418
2025-09-03T22:14:42.049Z,1756937682.049 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266047
2025-09-03T22:14:42.301Z,1756937682.301 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517972
2025-09-03T22:14:42.553Z,1756937682.553 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770424
2025-09-03T22:14:42.805Z,1756937682.805 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022021
2025-09-03T22:14:43.057Z,1756937683.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274391
2025-09-03T22:14:43.309Z,1756937683.309 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526022
2025-09-03T22:14:43.561Z,1756937683.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778038
2025-09-03T22:14:43.818Z,1756937683.818 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034571
2025-09-03T22:14:44.069Z,1756937684.069 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.285964
2025-09-03T22:14:44.321Z,1756937684.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538031
2025-09-03T22:14:44.573Z,1756937684.573 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.789975
2025-09-03T22:14:44.825Z,1756937684.825 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.041981
2025-09-03T22:14:45.077Z,1756937685.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.294508
2025-09-03T22:14:45.329Z,1756937685.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.545991
2025-09-03T22:14:45.581Z,1756937685.581 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.797934
2025-09-03T22:14:45.833Z,1756937685.833 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050110
2025-09-03T22:14:46.085Z,1756937686.085 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.301974
2025-09-03T22:14:46.337Z,1756937686.337 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554000
2025-09-03T22:14:46.589Z,1756937686.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806052
2025-09-03T22:14:46.841Z,1756937686.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.057912
2025-09-03T22:14:47.097Z,1756937687.097 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.314172
2025-09-03T22:14:47.349Z,1756937687.349 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.566512
2025-09-03T22:14:47.601Z,1756937687.601 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.817961
2025-09-03T22:14:47.857Z,1756937687.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.074135
2025-09-03T22:14:48.109Z,1756937688.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.326040
2025-09-03T22:14:48.361Z,1756937688.361 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.578044
2025-09-03T22:14:48.613Z,1756937688.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.830435
2025-09-03T22:14:48.869Z,1756937688.869 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.085134
2025-09-03T22:14:49.117Z,1756937689.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.334128
2025-09-03T22:14:49.369Z,1756937689.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.586278
2025-09-03T22:14:49.621Z,1756937689.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.838209
2025-09-03T22:14:52.151Z,1756937692.151 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:14:52.152Z,1756937692.152 [DAT](INFO): response not received
2025-09-03T22:14:52.152Z,1756937692.152 [DAT](INFO): DAT read: user:18>

2025-09-03T22:14:52.153Z,1756937692.153 [DAT](INFO): DAT read: Tx time:22:14:51.4677

2025-09-03T22:14:52.153Z,1756937692.153 [DAT](INFO): Ping request sent.
2025-09-03T22:14:52.153Z,1756937692.153 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:14:52.153Z,1756937692.153 [DAT](INFO): publishing transmit ping time
2025-09-03T22:14:52.154Z,1756937692.154 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000718
2025-09-03T22:14:52.401Z,1756937692.401 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247698
2025-09-03T22:14:52.653Z,1756937692.653 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.499771
2025-09-03T22:14:52.905Z,1756937692.905 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.751682
2025-09-03T22:14:53.157Z,1756937693.157 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.003630
2025-09-03T22:14:53.409Z,1756937693.409 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.255791
2025-09-03T22:14:53.661Z,1756937693.661 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.507700
2025-09-03T22:14:53.800Z,1756937693.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:14:53.916Z,1756937693.916 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762332
2025-09-03T22:14:54.169Z,1756937694.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015722
2025-09-03T22:14:54.421Z,1756937694.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267683
2025-09-03T22:14:54.673Z,1756937694.673 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519700
2025-09-03T22:14:54.804Z,1756937694.804 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:14:54.925Z,1756937694.925 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771777
2025-09-03T22:14:55.177Z,1756937695.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024130
2025-09-03T22:14:55.429Z,1756937695.429 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275721
2025-09-03T22:14:55.681Z,1756937695.681 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527812
2025-09-03T22:14:55.941Z,1756937695.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.787812
2025-09-03T22:14:56.193Z,1756937696.193 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.039708
2025-09-03T22:14:56.445Z,1756937696.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.291834
2025-09-03T22:14:56.697Z,1756937696.697 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.543678
2025-09-03T22:14:56.949Z,1756937696.949 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.795875
2025-09-03T22:14:57.214Z,1756937697.214 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.060582
2025-09-03T22:14:57.465Z,1756937697.465 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.311706
2025-09-03T22:14:57.717Z,1756937697.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.563970
2025-09-03T22:14:57.969Z,1756937697.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.815694
2025-09-03T22:14:58.221Z,1756937698.221 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.067681
2025-09-03T22:14:58.473Z,1756937698.473 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.319721
2025-09-03T22:14:58.725Z,1756937698.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.571800
2025-09-03T22:14:58.977Z,1756937698.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.823737
2025-09-03T22:14:59.230Z,1756937699.230 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.076486
2025-09-03T22:14:59.481Z,1756937699.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.327759
2025-09-03T22:14:59.733Z,1756937699.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.579689
2025-09-03T22:14:59.985Z,1756937699.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.831821
2025-09-03T22:15:00.237Z,1756937700.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.083770
2025-09-03T22:15:00.489Z,1756937700.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.335923
2025-09-03T22:15:00.741Z,1756937700.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.587705
2025-09-03T22:15:00.993Z,1756937700.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.839757
2025-09-03T22:15:01.247Z,1756937701.247 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.093653
2025-09-03T22:15:01.497Z,1756937701.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.344004
2025-09-03T22:15:01.749Z,1756937701.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.595775
2025-09-03T22:15:02.002Z,1756937702.002 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.848929
2025-09-03T22:15:04.777Z,1756937704.777 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:15:04.777Z,1756937704.777 [DAT](INFO): response not received
2025-09-03T22:15:04.778Z,1756937704.778 [DAT](INFO): DAT read: user:19>

2025-09-03T22:15:04.779Z,1756937704.779 [DAT](INFO): DAT read: Tx time:22:15:03.9177

2025-09-03T22:15:04.779Z,1756937704.779 [DAT](INFO): Ping request sent.
2025-09-03T22:15:04.779Z,1756937704.779 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:15:04.779Z,1756937704.779 [DAT](INFO): publishing transmit ping time
2025-09-03T22:15:04.780Z,1756937704.780 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000722
2025-09-03T22:15:05.029Z,1756937705.029 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250127
2025-09-03T22:15:05.285Z,1756937705.285 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.505374
2025-09-03T22:15:05.533Z,1756937705.533 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754059
2025-09-03T22:15:05.785Z,1756937705.785 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006007
2025-09-03T22:15:06.037Z,1756937706.037 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258109
2025-09-03T22:15:06.289Z,1756937706.289 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510214
2025-09-03T22:15:06.541Z,1756937706.541 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762156
2025-09-03T22:15:06.793Z,1756937706.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014017
2025-09-03T22:15:07.045Z,1756937707.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266039
2025-09-03T22:15:07.299Z,1756937707.299 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519723
2025-09-03T22:15:07.549Z,1756937707.549 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770014
2025-09-03T22:15:07.801Z,1756937707.801 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022083
2025-09-03T22:15:08.055Z,1756937708.055 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275993
2025-09-03T22:15:08.309Z,1756937708.309 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.530016
2025-09-03T22:15:08.561Z,1756937708.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.782137
2025-09-03T22:15:08.813Z,1756937708.813 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.033984
2025-09-03T22:15:09.065Z,1756937709.065 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.285953
2025-09-03T22:15:09.317Z,1756937709.317 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538115
2025-09-03T22:15:09.569Z,1756937709.569 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.790014
2025-09-03T22:15:09.821Z,1756937709.821 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.042419
2025-09-03T22:15:10.077Z,1756937710.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.298038
2025-09-03T22:15:10.329Z,1756937710.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.549981
2025-09-03T22:15:10.581Z,1756937710.581 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.801999
2025-09-03T22:15:10.833Z,1756937710.833 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.054202
2025-09-03T22:15:11.085Z,1756937711.085 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.305991
2025-09-03T22:15:11.337Z,1756937711.337 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.558103
2025-09-03T22:15:11.589Z,1756937711.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.810069
2025-09-03T22:15:11.841Z,1756937711.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.062010
2025-09-03T22:15:12.095Z,1756937712.095 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.315734
2025-09-03T22:15:12.345Z,1756937712.345 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.566034
2025-09-03T22:15:12.597Z,1756937712.597 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.817977
2025-09-03T22:15:12.850Z,1756937712.850 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.070719
2025-09-03T22:15:13.101Z,1756937713.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.322062
2025-09-03T22:15:13.353Z,1756937713.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.574089
2025-09-03T22:15:13.605Z,1756937713.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.826242
2025-09-03T22:15:13.857Z,1756937713.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.078065
2025-09-03T22:15:14.115Z,1756937714.115 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.336040
2025-09-03T22:15:14.361Z,1756937714.361 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.581955
2025-09-03T22:15:14.614Z,1756937714.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.834584
2025-09-03T22:15:17.137Z,1756937717.137 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:15:17.137Z,1756937717.137 [DAT](INFO): response not received
2025-09-03T22:15:17.138Z,1756937717.138 [DAT](INFO): DAT read: user:20>

2025-09-03T22:15:17.139Z,1756937717.139 [DAT](INFO): DAT read: Tx time:22:15:16.3676

2025-09-03T22:15:17.139Z,1756937717.139 [DAT](INFO): Ping request sent.
2025-09-03T22:15:17.139Z,1756937717.139 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:15:17.139Z,1756937717.139 [DAT](INFO): publishing transmit ping time
2025-09-03T22:15:17.140Z,1756937717.140 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000719
2025-09-03T22:15:17.389Z,1756937717.389 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250215
2025-09-03T22:15:17.641Z,1756937717.641 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502047
2025-09-03T22:15:17.893Z,1756937717.893 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754110
2025-09-03T22:15:18.145Z,1756937718.145 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006049
2025-09-03T22:15:18.397Z,1756937718.397 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258462
2025-09-03T22:15:18.649Z,1756937718.649 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510093
2025-09-03T22:15:18.903Z,1756937718.903 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763605
2025-09-03T22:15:19.153Z,1756937719.153 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014050
2025-09-03T22:15:19.405Z,1756937719.405 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266203
2025-09-03T22:15:19.657Z,1756937719.657 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518048
2025-09-03T22:15:19.909Z,1756937719.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770082
2025-09-03T22:15:20.161Z,1756937720.161 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022092
2025-09-03T22:15:20.413Z,1756937720.413 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274016
2025-09-03T22:15:20.665Z,1756937720.665 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526150
2025-09-03T22:15:20.917Z,1756937720.917 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778052
2025-09-03T22:15:21.169Z,1756937721.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030072
2025-09-03T22:15:21.421Z,1756937721.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282189
2025-09-03T22:15:21.673Z,1756937721.673 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534100
2025-09-03T22:15:21.925Z,1756937721.925 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786169
2025-09-03T22:15:22.177Z,1756937722.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038044
2025-09-03T22:15:22.429Z,1756937722.429 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290120
2025-09-03T22:15:22.681Z,1756937722.681 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541994
2025-09-03T22:15:22.933Z,1756937722.933 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794321
2025-09-03T22:15:23.185Z,1756937723.185 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046086
2025-09-03T22:15:23.437Z,1756937723.437 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298092
2025-09-03T22:15:23.689Z,1756937723.689 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550088
2025-09-03T22:15:23.941Z,1756937723.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802113
2025-09-03T22:15:24.193Z,1756937724.193 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054058
2025-09-03T22:15:24.445Z,1756937724.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306051
2025-09-03T22:15:24.698Z,1756937724.698 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558384
2025-09-03T22:15:24.924Z,1756937724.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:15:24.949Z,1756937724.949 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810028
2025-09-03T22:15:25.201Z,1756937725.201 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062046
2025-09-03T22:15:25.453Z,1756937725.453 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314165
2025-09-03T22:15:25.708Z,1756937725.708 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.568853
2025-09-03T22:15:25.928Z,1756937725.928 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:15:25.960Z,1756937725.960 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.820704
2025-09-03T22:15:26.209Z,1756937726.209 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070075
2025-09-03T22:15:26.461Z,1756937726.461 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322028
2025-09-03T22:15:26.713Z,1756937726.713 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574375
2025-09-03T22:15:26.965Z,1756937726.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826096
2025-09-03T22:15:29.493Z,1756937729.493 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:15:29.493Z,1756937729.493 [DAT](INFO): response not received
2025-09-03T22:15:29.494Z,1756937729.494 [DAT](INFO): DAT read: user:21>

2025-09-03T22:15:29.494Z,1756937729.494 [DAT](ERROR): No response from remote modem.
2025-09-03T22:15:29.745Z,1756937729.745 [DAT](INFO): DAT read: Tx time:22:15:28.8175

2025-09-03T22:15:29.745Z,1756937729.745 [DAT](INFO): Ping request sent.
2025-09-03T22:15:29.746Z,1756937729.746 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:15:29.746Z,1756937729.746 [DAT](INFO): publishing transmit ping time
2025-09-03T22:15:29.746Z,1756937729.746 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000729
2025-09-03T22:15:29.997Z,1756937729.997 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251449
2025-09-03T22:15:30.249Z,1756937730.249 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503436
2025-09-03T22:15:30.501Z,1756937730.501 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755662
2025-09-03T22:15:30.753Z,1756937730.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007466
2025-09-03T22:15:31.005Z,1756937731.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259390
2025-09-03T22:15:31.259Z,1756937731.259 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.513254
2025-09-03T22:15:31.509Z,1756937731.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763515
2025-09-03T22:15:31.761Z,1756937731.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015396
2025-09-03T22:15:32.013Z,1756937732.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267395
2025-09-03T22:15:32.265Z,1756937732.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519497
2025-09-03T22:15:32.517Z,1756937732.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771458
2025-09-03T22:15:32.769Z,1756937732.769 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023833
2025-09-03T22:15:33.021Z,1756937733.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275453
2025-09-03T22:15:33.275Z,1756937733.275 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.529546
2025-09-03T22:15:33.525Z,1756937733.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779461
2025-09-03T22:15:33.777Z,1756937733.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031450
2025-09-03T22:15:34.029Z,1756937734.029 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283383
2025-09-03T22:15:34.281Z,1756937734.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535407
2025-09-03T22:15:34.533Z,1756937734.533 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.787485
2025-09-03T22:15:34.793Z,1756937734.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.047875
2025-09-03T22:15:35.045Z,1756937735.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.299625
2025-09-03T22:15:35.299Z,1756937735.299 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.553209
2025-09-03T22:15:35.549Z,1756937735.549 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.803468
2025-09-03T22:15:35.801Z,1756937735.801 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.055343
2025-09-03T22:15:36.053Z,1756937736.053 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.307462
2025-09-03T22:15:36.305Z,1756937736.305 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.559389
2025-09-03T22:15:36.557Z,1756937736.557 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.811738
2025-09-03T22:15:36.809Z,1756937736.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.063744
2025-09-03T22:15:37.061Z,1756937737.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.315580
2025-09-03T22:15:37.313Z,1756937737.313 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.567488
2025-09-03T22:15:37.565Z,1756937737.565 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.819540
2025-09-03T22:15:37.817Z,1756937737.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.071400
2025-09-03T22:15:38.069Z,1756937738.069 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.323463
2025-09-03T22:15:38.321Z,1756937738.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.575497
2025-09-03T22:15:38.573Z,1756937738.573 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.827432
2025-09-03T22:15:38.826Z,1756937738.826 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.080023
2025-09-03T22:15:39.077Z,1756937739.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.331531
2025-09-03T22:15:39.329Z,1756937739.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.583452
2025-09-03T22:15:39.581Z,1756937739.581 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.835467
2025-09-03T22:15:42.101Z,1756937742.101 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:15:42.101Z,1756937742.101 [DAT](INFO): response not received
2025-09-03T22:15:42.102Z,1756937742.102 [DAT](INFO): DAT read: user:22>

2025-09-03T22:15:42.102Z,1756937742.102 [DAT](INFO): DAT read: Tx time:22:15:41.2675

2025-09-03T22:15:42.103Z,1756937742.103 [DAT](INFO): Ping request sent.
2025-09-03T22:15:42.103Z,1756937742.103 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:15:42.103Z,1756937742.103 [DAT](INFO): publishing transmit ping time
2025-09-03T22:15:42.104Z,1756937742.104 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000767
2025-09-03T22:15:42.353Z,1756937742.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250221
2025-09-03T22:15:42.605Z,1756937742.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502100
2025-09-03T22:15:42.857Z,1756937742.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754146
2025-09-03T22:15:42.904Z,1756937742.904 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003096
2025-09-03T22:15:43.109Z,1756937743.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006021
2025-09-03T22:15:43.361Z,1756937743.361 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258121
2025-09-03T22:15:43.614Z,1756937743.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510694
2025-09-03T22:15:43.865Z,1756937743.865 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762141
2025-09-03T22:15:43.904Z,1756937743.904 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:15:44.117Z,1756937744.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014109
2025-09-03T22:15:44.369Z,1756937744.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266123
2025-09-03T22:15:44.621Z,1756937744.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518238
2025-09-03T22:15:44.873Z,1756937744.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770302
2025-09-03T22:15:45.126Z,1756937745.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023022
2025-09-03T22:15:45.377Z,1756937745.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274098
2025-09-03T22:15:45.629Z,1756937745.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526031
2025-09-03T22:15:45.881Z,1756937745.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778154
2025-09-03T22:15:46.133Z,1756937746.133 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030209
2025-09-03T22:15:46.385Z,1756937746.385 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282157
2025-09-03T22:15:46.637Z,1756937746.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534239
2025-09-03T22:15:46.889Z,1756937746.889 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786184
2025-09-03T22:15:47.141Z,1756937747.141 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038615
2025-09-03T22:15:47.393Z,1756937747.393 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290254
2025-09-03T22:15:47.645Z,1756937747.645 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542157
2025-09-03T22:15:47.897Z,1756937747.897 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794220
2025-09-03T22:15:48.149Z,1756937748.149 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046313
2025-09-03T22:15:48.401Z,1756937748.401 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298124
2025-09-03T22:15:48.653Z,1756937748.653 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550156
2025-09-03T22:15:48.905Z,1756937748.905 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802080
2025-09-03T22:15:49.157Z,1756937749.157 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054124
2025-09-03T22:15:49.409Z,1756937749.409 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306410
2025-09-03T22:15:49.661Z,1756937749.661 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558175
2025-09-03T22:15:49.913Z,1756937749.913 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810108
2025-09-03T22:15:50.165Z,1756937750.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062386
2025-09-03T22:15:50.417Z,1756937750.417 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314291
2025-09-03T22:15:50.669Z,1756937750.669 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566065
2025-09-03T22:15:50.921Z,1756937750.921 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818263
2025-09-03T22:15:51.173Z,1756937751.173 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070162
2025-09-03T22:15:51.425Z,1756937751.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322074
2025-09-03T22:15:51.677Z,1756937751.677 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574588
2025-09-03T22:15:51.929Z,1756937751.929 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826140
2025-09-03T22:15:52.820Z,1756937752.820 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:15:54.449Z,1756937754.449 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:15:54.449Z,1756937754.449 [DAT](INFO): response not received
2025-09-03T22:15:54.450Z,1756937754.450 [DAT](INFO): DAT read: user:23>

2025-09-03T22:15:54.450Z,1756937754.450 [DAT](INFO): DAT read: Tx time:22:15:53.7174

2025-09-03T22:15:54.451Z,1756937754.451 [DAT](INFO): Ping request sent.
2025-09-03T22:15:54.451Z,1756937754.451 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:15:54.451Z,1756937754.451 [DAT](INFO): publishing transmit ping time
2025-09-03T22:15:54.452Z,1756937754.452 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000735
2025-09-03T22:15:54.701Z,1756937754.701 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250242
2025-09-03T22:15:54.953Z,1756937754.953 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502264
2025-09-03T22:15:55.205Z,1756937755.205 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754311
2025-09-03T22:15:55.457Z,1756937755.457 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006229
2025-09-03T22:15:55.709Z,1756937755.709 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258287
2025-09-03T22:15:55.961Z,1756937755.961 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510203
2025-09-03T22:15:56.221Z,1756937756.221 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.770506
2025-09-03T22:15:56.473Z,1756937756.473 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.022174
2025-09-03T22:15:56.725Z,1756937756.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.274549
2025-09-03T22:15:56.977Z,1756937756.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.526240
2025-09-03T22:15:57.229Z,1756937757.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.778335
2025-09-03T22:15:57.481Z,1756937757.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.030155
2025-09-03T22:15:57.733Z,1756937757.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.282202
2025-09-03T22:15:57.985Z,1756937757.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.534283
2025-09-03T22:15:58.237Z,1756937758.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.786544
2025-09-03T22:15:58.489Z,1756937758.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.038455
2025-09-03T22:15:58.741Z,1756937758.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.290252
2025-09-03T22:15:58.993Z,1756937758.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.542255
2025-09-03T22:15:59.247Z,1756937759.247 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.796024
2025-09-03T22:15:59.497Z,1756937759.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.046184
2025-09-03T22:15:59.752Z,1756937759.752 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.301457
2025-09-03T22:16:00.005Z,1756937760.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.554454
2025-09-03T22:16:00.257Z,1756937760.257 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.806255
2025-09-03T22:16:00.509Z,1756937760.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.058265
2025-09-03T22:16:00.761Z,1756937760.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.310204
2025-09-03T22:16:01.013Z,1756937761.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.562293
2025-09-03T22:16:01.267Z,1756937761.267 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.815984
2025-09-03T22:16:01.517Z,1756937761.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.066155
2025-09-03T22:16:01.756Z,1756937761.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:16:01.769Z,1756937761.769 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.318202
2025-09-03T22:16:02.021Z,1756937762.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.570665
2025-09-03T22:16:02.273Z,1756937762.273 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.822466
2025-09-03T22:16:02.525Z,1756937762.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.074366
2025-09-03T22:16:02.777Z,1756937762.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.326218
2025-09-03T22:16:03.029Z,1756937763.029 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.578478
2025-09-03T22:16:03.284Z,1756937763.284 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.832462
2025-09-03T22:16:03.533Z,1756937763.533 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.082400
2025-09-03T22:16:03.785Z,1756937763.785 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.334611
2025-09-03T22:16:04.037Z,1756937764.037 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.586265
2025-09-03T22:16:04.289Z,1756937764.289 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.838224
2025-09-03T22:16:06.809Z,1756937766.809 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:16:06.809Z,1756937766.809 [DAT](INFO): response not received
2025-09-03T22:16:06.810Z,1756937766.810 [DAT](INFO): DAT read: user:24>

2025-09-03T22:16:06.810Z,1756937766.810 [DAT](ERROR): No response from remote modem.
2025-09-03T22:16:07.061Z,1756937767.061 [DAT](INFO): DAT read: Tx time:22:16:06.1674

2025-09-03T22:16:07.061Z,1756937767.061 [DAT](INFO): Ping request sent.
2025-09-03T22:16:07.061Z,1756937767.061 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:16:07.062Z,1756937767.062 [DAT](INFO): publishing transmit ping time
2025-09-03T22:16:07.062Z,1756937767.062 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000752
2025-09-03T22:16:07.313Z,1756937767.313 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251591
2025-09-03T22:16:07.565Z,1756937767.565 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503985
2025-09-03T22:16:07.817Z,1756937767.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755649
2025-09-03T22:16:08.069Z,1756937768.069 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007629
2025-09-03T22:16:08.322Z,1756937768.322 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260290
2025-09-03T22:16:08.573Z,1756937768.573 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511864
2025-09-03T22:16:08.825Z,1756937768.825 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763558
2025-09-03T22:16:09.080Z,1756937769.080 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018759
2025-09-03T22:16:09.083Z,1756937769.083 [NAL9602](INFO): SBD MO Status=2, MOMSN=1215, MT Status=2, MTMSN=0
2025-09-03T22:16:09.083Z,1756937769.083 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T22:16:09.333Z,1756937769.333 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.271805
2025-09-03T22:16:09.585Z,1756937769.585 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.523873
2025-09-03T22:16:09.837Z,1756937769.837 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775705
2025-09-03T22:16:10.089Z,1756937770.089 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027866
2025-09-03T22:16:10.341Z,1756937770.341 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.279767
2025-09-03T22:16:10.593Z,1756937770.593 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.531552
2025-09-03T22:16:10.845Z,1756937770.845 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.783601
2025-09-03T22:16:11.084Z,1756937771.084 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:16:11.097Z,1756937771.097 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.035657
2025-09-03T22:16:11.349Z,1756937771.349 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.287695
2025-09-03T22:16:11.601Z,1756937771.601 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.539646
2025-09-03T22:16:11.853Z,1756937771.853 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.791561
2025-09-03T22:16:12.105Z,1756937772.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.043585
2025-09-03T22:16:12.357Z,1756937772.357 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.296045
2025-09-03T22:16:12.609Z,1756937772.609 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.547533
2025-09-03T22:16:12.861Z,1756937772.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.799633
2025-09-03T22:16:13.113Z,1756937773.113 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.051694
2025-09-03T22:16:13.365Z,1756937773.365 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.303600
2025-09-03T22:16:13.617Z,1756937773.617 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.556062
2025-09-03T22:16:13.869Z,1756937773.869 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.807683
2025-09-03T22:16:14.121Z,1756937774.121 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.059571
2025-09-03T22:16:14.373Z,1756937774.373 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.311644
2025-09-03T22:16:14.625Z,1756937774.625 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.563701
2025-09-03T22:16:14.877Z,1756937774.877 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.815611
2025-09-03T22:16:15.129Z,1756937775.129 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.067653
2025-09-03T22:16:15.381Z,1756937775.381 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.319657
2025-09-03T22:16:15.633Z,1756937775.633 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.572027
2025-09-03T22:16:15.885Z,1756937775.885 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.823879
2025-09-03T22:16:16.137Z,1756937776.137 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.075590
2025-09-03T22:16:16.389Z,1756937776.389 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.327432
2025-09-03T22:16:16.641Z,1756937776.641 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.579823
2025-09-03T22:16:16.893Z,1756937776.893 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.831652
2025-09-03T22:16:19.417Z,1756937779.417 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:16:19.417Z,1756937779.417 [DAT](INFO): response not received
2025-09-03T22:16:19.418Z,1756937779.418 [DAT](INFO): DAT read: user:25>

2025-09-03T22:16:19.418Z,1756937779.418 [DAT](INFO): DAT read: Tx time:22:16:18.6173

2025-09-03T22:16:19.419Z,1756937779.419 [DAT](INFO): Ping request sent.
2025-09-03T22:16:19.419Z,1756937779.419 [DAT](INFO): transmitted an acoustic signal
2025-09-03T22:16:19.419Z,1756937779.419 [DAT](INFO): publishing transmit ping time
2025-09-03T22:16:19.420Z,1756937779.420 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000738
2025-09-03T22:16:19.669Z,1756937779.669 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250409
2025-09-03T22:16:19.921Z,1756937779.921 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502140
2025-09-03T22:16:19.948Z,1756937779.948 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:16:20.173Z,1756937780.173 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754110
2025-09-03T22:16:20.426Z,1756937780.426 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007205
2025-09-03T22:16:20.677Z,1756937780.677 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258163
2025-09-03T22:16:20.929Z,1756937780.929 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510122
2025-09-03T22:16:21.183Z,1756937781.183 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764306
2025-09-03T22:16:21.437Z,1756937781.437 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018179
2025-09-03T22:16:21.689Z,1756937781.689 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270197
2025-09-03T22:16:21.941Z,1756937781.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522095
2025-09-03T22:16:22.195Z,1756937782.195 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775945
2025-09-03T22:16:22.445Z,1756937782.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026201
2025-09-03T22:16:22.697Z,1756937782.697 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278158
2025-09-03T22:16:22.949Z,1756937782.949 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.530212
2025-09-03T22:16:23.201Z,1756937783.201 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.782163
2025-09-03T22:16:23.453Z,1756937783.453 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034213
2025-09-03T22:16:23.705Z,1756937783.705 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.286251
2025-09-03T22:16:23.957Z,1756937783.957 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538150
2025-09-03T22:16:24.209Z,1756937784.209 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.790157
2025-09-03T22:16:24.461Z,1756937784.461 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.042222
2025-09-03T22:16:24.714Z,1756937784.714 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.294970
2025-09-03T22:16:24.965Z,1756937784.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.546148
2025-09-03T22:16:25.217Z,1756937785.217 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.798211
2025-09-03T22:16:25.469Z,1756937785.469 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050129
2025-09-03T22:16:25.721Z,1756937785.721 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302314
2025-09-03T22:16:25.973Z,1756937785.973 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554299
2025-09-03T22:16:26.225Z,1756937786.225 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806090
2025-09-03T22:16:26.477Z,1756937786.477 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058534
2025-09-03T22:16:26.729Z,1756937786.729 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310254
2025-09-03T22:16:26.981Z,1756937786.981 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562110
2025-09-03T22:16:27.233Z,1756937787.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.814115
2025-09-03T22:16:27.485Z,1756937787.485 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066165
2025-09-03T22:16:27.737Z,1756937787.737 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.318127
2025-09-03T22:16:27.989Z,1756937787.989 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570413
2025-09-03T22:16:28.241Z,1756937788.241 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.822150
2025-09-03T22:16:28.493Z,1756937788.493 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.074143
2025-09-03T22:16:28.746Z,1756937788.746 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.326753
2025-09-03T22:16:28.904Z,1756937788.904 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:16:28.997Z,1756937788.997 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578154
2025-09-03T22:16:29.251Z,1756937789.251 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.831825
2025-09-03T22:16:31.774Z,1756937791.774 [DAT](INFO): DAT read: Response Not Received

2025-09-03T22:16:31.775Z,1756937791.775 [DAT](INFO): response not received
2025-09-03T22:16:31.776Z,1756937791.776 [DAT](ERROR): No response from remote modem.
2025-09-03T22:16:37.720Z,1756937797.720 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:16:41.811Z,1756937801.811 [NAL9602](INFO): SBD MO Status=1, MOMSN=1215, MT Status=0, MTMSN=0
2025-09-03T22:16:41.811Z,1756937801.811 [NAL9602](INFO): No messages in MT queue
2025-09-03T22:16:43.015Z,1756937803.015 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221642.00,A,3648.21391,N,12150.09407,W,0.661,24.77,030925,,,D*4E
2025-09-03T22:16:43.017Z,1756937803.017 [NAL9602](INFO): GPS fix at 20250903T221642: (36.803565, -121.834901)
2025-09-03T22:16:43.077Z,1756937803.077 [UniversalFixResidualReporter](INFO): Fix residual: 1.8 %DT, over the last 841.0 m. Residual distance 15.1 m at bearing 42.1 degrees. Fix at (36.8036, -121.8349) with 805.8 m made good.
2025-09-03T22:16:43.077Z,1756937803.077 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-03T22:16:43.078Z,1756937803.078 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:16:44.675Z,1756937804.675 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20250903T213617/Courier0006.lzma
2025-09-03T22:16:45.430Z,1756937805.430 [DataOverHttps](INFO): Moved sent file to Logs/20250903T213617/Courier0006.lzma.bak
2025-09-03T22:16:45.430Z,1756937805.430 [DataOverHttps](INFO): SBD MOMSN=25924891
2025-09-03T22:17:02.086Z,1756937822.086 [DataOverHttps](INFO): Sending 828 bytes from file Logs/20250903T213617/Express0007.lzma
2025-09-03T22:17:03.086Z,1756937823.086 [DataOverHttps](INFO): Moved sent file to Logs/20250903T213617/Express0007.lzma.bak
2025-09-03T22:17:03.086Z,1756937823.086 [DataOverHttps](INFO): SBD MOMSN=25924894
2025-09-03T22:17:15.741Z,1756937835.741 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T22:17:19.305Z,1756937839.305 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250903T213617/Express0010.lzma
2025-09-03T22:17:20.302Z,1756937840.302 [DataOverHttps](INFO): Moved sent file to Logs/20250903T213617/Express0010.lzma.bak
2025-09-03T22:17:20.302Z,1756937840.302 [DataOverHttps](INFO): SBD MOMSN=25924914
2025-09-03T22:17:21.437Z,1756937841.437 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-03T22:17:21.438Z,1756937841.438 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:17:21.438Z,1756937841.438 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:17:29.865Z,1756937849.865 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:17:38.440Z,1756937858.440 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:17:47.116Z,1756937867.116 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:17:55.752Z,1756937875.752 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:18:04.220Z,1756937884.220 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:18:12.984Z,1756937892.984 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:18:21.613Z,1756937901.613 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:18:30.104Z,1756937910.104 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:18:38.849Z,1756937918.849 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:18:47.462Z,1756937927.462 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD,     -27  +2217.19,        +7.02,  58.66,  0.00
2025-09-03T22:18:47.484Z,1756937927.484 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:18:56.008Z,1756937936.008 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:19:04.684Z,1756937944.684 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:19:13.293Z,1756937953.293 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:19:23.281Z,1756937963.281 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:19:31.952Z,1756937971.952 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:19:40.512Z,1756937980.512 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:19:49.076Z,1756937989.076 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:19:57.756Z,1756937997.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:20:07.800Z,1756938007.800 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:20:16.452Z,1756938016.452 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:20:25.148Z,1756938025.148 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:20:33.784Z,1756938033.784 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:20:42.236Z,1756938042.236 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:20:50.996Z,1756938050.996 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:20:59.576Z,1756938059.576 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:21:08.112Z,1756938068.112 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:21:16.824Z,1756938076.824 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:21:25.456Z,1756938085.456 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:21:33.980Z,1756938093.980 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:21:42.544Z,1756938102.544 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:21:51.148Z,1756938111.148 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:21:59.780Z,1756938119.780 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:22:10.676Z,1756938130.676 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:22:19.265Z,1756938139.265 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:22:22.069Z,1756938142.069 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:22:22.069Z,1756938142.069 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-03T22:22:22.069Z,1756938142.069 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:22:22.070Z,1756938142.070 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-09-03T22:22:22.511Z,1756938142.511 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-09-03T22:22:22.512Z,1756938142.512 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-09-03T22:22:22.861Z,1756938142.861 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for  10.470767 min 
2025-09-03T22:22:22.861Z,1756938142.861 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-09-03T22:22:22.861Z,1756938142.861 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-03T22:22:22.861Z,1756938142.861 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-03T22:22:22.861Z,1756938142.861 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-03T22:22:22.861Z,1756938142.861 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2
2025-09-03T22:22:22.861Z,1756938142.861 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2
2025-09-03T22:22:22.861Z,1756938142.861 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-03T22:22:22.861Z,1756938142.861 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:22:24.847Z,1756938144.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222224.00,A,3648.25506,N,12150.06979,W,0.389,350.53,030925,,,D*7B
2025-09-03T22:22:24.849Z,1756938144.849 [NAL9602](INFO): GPS fix at 20250903T222224: (36.804251, -121.834497)
2025-09-03T22:22:24.894Z,1756938144.894 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-03T22:22:24.894Z,1756938144.894 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:22:27.302Z,1756938147.302 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250903T213617/Courier0012.lzma
2025-09-03T22:22:28.298Z,1756938148.298 [DataOverHttps](INFO): Moved sent file to Logs/20250903T213617/Courier0012.lzma.bak
2025-09-03T22:22:28.298Z,1756938148.298 [DataOverHttps](INFO): SBD MOMSN=25924928
2025-09-03T22:22:44.857Z,1756938164.857 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20250903T213617/Express0013.lzma
2025-09-03T22:22:45.858Z,1756938165.858 [DataOverHttps](INFO): Moved sent file to Logs/20250903T213617/Express0013.lzma.bak
2025-09-03T22:22:45.858Z,1756938165.858 [DataOverHttps](INFO): SBD MOMSN=25924938
2025-09-03T22:22:47.249Z,1756938167.249 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-03T22:22:47.249Z,1756938167.249 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:22:47.250Z,1756938167.250 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:22:51.304Z,1756938171.304 [NAL9602](INFO): SBD MO Status=2, MOMSN=1216, MT Status=2, MTMSN=0
2025-09-03T22:22:51.305Z,1756938171.305 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T22:22:55.540Z,1756938175.540 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:23:04.064Z,1756938184.064 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:23:12.659Z,1756938192.659 [NAL9602](INFO): SBD MO Status=2, MOMSN=1216, MT Status=2, MTMSN=0
2025-09-03T22:23:12.659Z,1756938192.659 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T22:23:13.000Z,1756938193.000 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:23:21.628Z,1756938201.628 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:23:30.128Z,1756938210.128 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:23:38.824Z,1756938218.824 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:23:47.440Z,1756938227.440 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:23:48.212Z,1756938228.212 [NAL9602](INFO): SBD MO Status=1, MOMSN=1216, MT Status=0, MTMSN=0
2025-09-03T22:23:48.212Z,1756938228.212 [NAL9602](INFO): No messages in MT queue
2025-09-03T22:23:56.008Z,1756938236.008 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:24:04.916Z,1756938244.916 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:24:13.504Z,1756938253.504 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:24:18.917Z,1756938258.917 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T22:24:22.176Z,1756938262.176 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:24:30.780Z,1756938270.780 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:24:39.304Z,1756938279.304 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:24:48.001Z,1756938288.001 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:24:56.608Z,1756938296.608 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:25:05.130Z,1756938305.130 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:25:13.864Z,1756938313.864 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:25:22.456Z,1756938322.456 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:25:31.218Z,1756938331.218 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:25:39.881Z,1756938339.881 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:25:48.496Z,1756938348.496 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:25:57.148Z,1756938357.148 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:26:07.384Z,1756938367.384 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:26:15.864Z,1756938375.864 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:26:24.572Z,1756938384.572 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:26:33.233Z,1756938393.233 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:26:41.841Z,1756938401.841 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:26:50.456Z,1756938410.456 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:26:59.068Z,1756938419.068 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:27:07.756Z,1756938427.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:27:16.448Z,1756938436.448 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:27:26.032Z,1756938446.032 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:27:34.588Z,1756938454.588 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:27:43.304Z,1756938463.304 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:27:47.848Z,1756938467.848 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:27:47.848Z,1756938467.848 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-03T22:27:47.848Z,1756938467.848 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:27:47.848Z,1756938467.848 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-09-03T22:27:48.261Z,1756938468.261 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-09-03T22:27:48.261Z,1756938468.261 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-09-03T22:27:48.649Z,1756938468.649 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for  15.899927 min 
2025-09-03T22:27:48.649Z,1756938468.649 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-09-03T22:27:48.649Z,1756938468.649 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-03T22:27:48.649Z,1756938468.649 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-03T22:27:48.650Z,1756938468.650 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-03T22:27:48.650Z,1756938468.650 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3
2025-09-03T22:27:48.650Z,1756938468.650 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3
2025-09-03T22:27:48.650Z,1756938468.650 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-03T22:27:48.650Z,1756938468.650 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:27:50.656Z,1756938470.656 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222749.00,A,3648.29402,N,12150.04284,W,0.797,28.55,030925,,,D*46
2025-09-03T22:27:50.658Z,1756938470.658 [NAL9602](INFO): GPS fix at 20250903T222749: (36.804900, -121.834047)
2025-09-03T22:27:50.670Z,1756938470.670 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-03T22:27:50.670Z,1756938470.670 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:27:58.700Z,1756938478.700 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250903T213617/Courier0015.lzma
2025-09-03T22:27:59.702Z,1756938479.702 [DataOverHttps](INFO): Moved sent file to Logs/20250903T213617/Courier0015.lzma.bak
2025-09-03T22:27:59.702Z,1756938479.702 [DataOverHttps](INFO): SBD MOMSN=25924950
2025-09-03T22:28:19.332Z,1756938499.332 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250903T213617/Express0016.lzma
2025-09-03T22:28:20.334Z,1756938500.334 [DataOverHttps](INFO): Moved sent file to Logs/20250903T213617/Express0016.lzma.bak
2025-09-03T22:28:20.334Z,1756938500.334 [DataOverHttps](INFO): SBD MOMSN=25924953
2025-09-03T22:28:21.782Z,1756938501.782 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-03T22:28:21.782Z,1756938501.782 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:28:21.782Z,1756938501.782 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:28:29.948Z,1756938509.948 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:28:30.677Z,1756938510.677 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD,     -135.47,     +477    +38.77,  20.00, 71.03
2025-09-03T22:28:38.648Z,1756938518.648 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:28:47.172Z,1756938527.172 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:28:55.836Z,1756938535.836 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:29:04.476Z,1756938544.476 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:29:15.068Z,1756938555.068 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:29:23.716Z,1756938563.716 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:29:32.452Z,1756938572.452 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:29:41.020Z,1756938581.020 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:29:49.584Z,1756938589.584 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:29:58.246Z,1756938598.246 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:30:10.172Z,1756938610.172 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:30:18.704Z,1756938618.704 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:30:27.424Z,1756938627.424 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:30:36.076Z,1756938636.076 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:30:44.672Z,1756938644.672 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:30:53.388Z,1756938653.388 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:31:02.012Z,1756938662.012 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:31:10.621Z,1756938670.621 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:31:19.236Z,1756938679.236 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:31:27.832Z,1756938687.832 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:31:37.160Z,1756938697.160 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:31:45.840Z,1756938705.840 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-03T22:31:51.491Z,1756938711.491 [NAL9602](INFO): SBD MO Status=2, MOMSN=1217, MT Status=2, MTMSN=0
2025-09-03T22:31:51.491Z,1756938711.491 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T22:31:53.413Z,1756938713.413 [DataOverHttps](IMPORTANT): SBD MTMSN=20250903T223152
2025-09-03T22:32:00.940Z,1756938720.940 [DataOverHttps](INFO): Received command: restart logs