2020-01-25T17:54:07.140Z,1579974847.140 [CommandLine](IMPORTANT): got command restart logs
2020-01-25T17:55:55.483Z,1579974955.483 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 308.53, 296.55, 293.56,   0.00
2020-01-25T17:56:32.643Z,1579974992.643 [DataOverHttps](IMPORTANT): SBD MTMSN=20200125T175622
2020-01-25T17:56:40.466Z,1579975000.466 [DataOverHttps](INFO): Received command:configSet Express none DDM.cablePresent persist
2020-01-25T17:56:41.077Z,1579975001.077 [CommandLine](IMPORTANT): got command configSet Express none DDM.cablePresent persist
2020-01-25T17:57:03.772Z,1579975023.772 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-25T17:57:03.772Z,1579975023.772 [Default:CheckIn:C.Wait] Stopped
2020-01-25T17:57:03.773Z,1579975023.773 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T17:57:03.773Z,1579975023.773 [Default:CheckIn:D] Running Loop=1
2020-01-25T17:57:04.185Z,1579975024.185 [Default:CheckIn:D] Stopped
2020-01-25T17:57:04.185Z,1579975024.185 [Default:CheckIn:E] Running Loop=1
2020-01-25T17:57:04.577Z,1579975024.577 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.008741 min 
2020-01-25T17:57:04.577Z,1579975024.577 [Default:CheckIn:E] Stopped
2020-01-25T17:57:04.577Z,1579975024.577 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-25T17:57:04.577Z,1579975024.577 [Default:CheckIn] Stopped
2020-01-25T17:57:04.578Z,1579975024.578 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T17:57:04.578Z,1579975024.578 [Default:CheckIn](INFO): Running loop #2
2020-01-25T17:57:04.578Z,1579975024.578 [Default:CheckIn] Running Loop=2
2020-01-25T17:57:04.578Z,1579975024.578 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-25T17:57:04.578Z,1579975024.578 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-25T17:57:06.564Z,1579975026.564 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175657.00,A,3648.59814,N,12148.63892,W,0.486,23.29,250120,,,A*4F
2020-01-25T17:57:06.574Z,1579975026.574 [NAL9602](INFO): GPS fix at 20200125T175657: (36.809969, -121.810649)
2020-01-25T17:57:06.641Z,1579975026.641 [Default:CheckIn:Read_GPS] Stopped
2020-01-25T17:57:06.641Z,1579975026.641 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-25T17:57:14.417Z,1579975034.417 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200125T163519/Courier0034.lzma
2020-01-25T17:57:16.423Z,1579975036.423 [DataOverHttps](INFO): Moved sent file to Logs/20200125T163519/Courier0034.lzma.bak
2020-01-25T17:57:16.423Z,1579975036.423 [DataOverHttps](INFO): SBD MOMSN=12203570
2020-01-25T17:57:33.798Z,1579975053.798 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20200125T175407/Courier0000.lzma
2020-01-25T17:57:35.803Z,1579975055.803 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Courier0000.lzma.bak
2020-01-25T17:57:35.803Z,1579975055.803 [DataOverHttps](INFO): SBD MOMSN=12203572
2020-01-25T17:57:53.429Z,1579975073.429 [DataOverHttps](INFO): Sending 97 bytes from file Logs/20200125T163519/Express0035.lzma
2020-01-25T17:57:55.435Z,1579975075.435 [DataOverHttps](INFO): Moved sent file to Logs/20200125T163519/Express0035.lzma.bak
2020-01-25T17:57:55.436Z,1579975075.436 [DataOverHttps](INFO): SBD MOMSN=12203576
2020-01-25T17:58:14.534Z,1579975094.534 [DataOverHttps](INFO): Sending 418 bytes from file Logs/20200125T175407/Express0001.lzma
2020-01-25T17:58:16.539Z,1579975096.539 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0001.lzma.bak
2020-01-25T17:58:16.539Z,1579975096.539 [DataOverHttps](INFO): SBD MOMSN=12203579
2020-01-25T17:58:31.013Z,1579975111.013 [NAL9602](INFO): SBD MO Status=0, MOMSN=10263, MT Status=0, MTMSN=0
2020-01-25T17:58:31.013Z,1579975111.013 [NAL9602](INFO): No messages in MT queue
2020-01-25T17:58:46.265Z,1579975126.265 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200125T175407/Express0004.lzma
2020-01-25T17:58:48.273Z,1579975128.273 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0004.lzma.bak
2020-01-25T17:58:48.273Z,1579975128.273 [DataOverHttps](INFO): SBD MOMSN=12203585
2020-01-25T17:58:49.735Z,1579975129.735 [Default:CheckIn:Read_Iridium] Stopped
2020-01-25T17:58:49.736Z,1579975129.736 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-25T17:58:49.736Z,1579975129.736 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T17:58:55.331Z,1579975135.331 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-25T17:58:55.332Z,1579975135.332 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 298.05, 298.05, 307.03
2020-01-25T17:59:01.748Z,1579975141.748 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-25T17:59:28.561Z,1579975168.561 [CommandLine](IMPORTANT): got command failComponent
2020-01-25T17:59:28.561Z,1579975168.561 [CommandLine](IMPORTANT): Failed components:
2020-01-25T17:59:28.561Z,1579975168.561 [CommandLine](IMPORTANT): No failed Components.
2020-01-25T18:00:44.763Z,1579975244.763 [DataOverHttps](IMPORTANT): SBD MTMSN=20200125T180034
2020-01-25T18:00:52.524Z,1579975252.524 [DataOverHttps](INFO): Received command:load Maintenance/DUSBL.xml;set DUSBL.MissionTimeout 5 minute;run 
2020-01-25T18:00:52.540Z,1579975252.540 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/DUSBL.xml
2020-01-25T18:00:52.541Z,1579975252.541 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2020-01-25T18:00:52.633Z,1579975252.633 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2020-01-25T18:00:52.657Z,1579975252.657 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2020-01-25T18:00:52.664Z,1579975252.664 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2020-01-25T18:00:52.675Z,1579975252.675 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2020-01-25T18:00:52.692Z,1579975252.692 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2020-01-25T18:00:52.693Z,1579975252.693 [DUSBL:A.Pitch](DEBUG): Construct.
2020-01-25T18:00:52.707Z,1579975252.707 [DUSBL:B.SetSpeed](DEBUG): Construct.
2020-01-25T18:00:52.721Z,1579975252.721 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2020-01-25T18:00:52.725Z,1579975252.725 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="NumberOfRequests"><Description>
        Number of pings to requst.
    </Description><Units:count/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>   

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>      
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Sensor:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <ReadData>
        <When>
            <Arg Name="EnabledDUSBL"/>
        </When>
            <Universal:acoustic_contact_range/>
            <Universal:acoustic_contact_direction_vehicle_frame/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

        <Sequence Repeat="NumberOfRequests"/>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.transponderCode/><Arg Name="TransponderCode"/></Assign>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.pingRequested/><Arg Name="NumberOfPings"/></Assign>
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:second/><Value>90</Value></Setting>
          </Guidance:Wait>
      </Aggregate>    
</Mission>


2020-01-25T18:00:52.725Z,1579975252.725 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/DUSBL.xml
2020-01-25T18:00:54.778Z,1579975254.778 [CommandLine](IMPORTANT): got command set DUSBL.MissionTimeout 5.000000 minute
2020-01-25T18:00:54.778Z,1579975254.778 [CommandLine](IMPORTANT): got command run 
2020-01-25T18:00:54.780Z,1579975254.780 [CommandLine](IMPORTANT): Running 
2020-01-25T18:00:54.986Z,1579975254.986 [Default] Stopped
2020-01-25T18:00:54.986Z,1579975254.986 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-25T18:00:54.986Z,1579975254.986 [Default:B.GoToSurface] Stopped
2020-01-25T18:00:54.987Z,1579975254.987 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-25T18:00:54.987Z,1579975254.987 [Default:CheckIn] Stopped
2020-01-25T18:00:54.987Z,1579975254.987 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T18:00:54.987Z,1579975254.987 [Default:CheckIn:C.Wait] Stopped
2020-01-25T18:00:54.987Z,1579975254.987 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:00:54.987Z,1579975254.987 [MissionManager](IMPORTANT): Started mission DUSBL
2020-01-25T18:00:54.987Z,1579975254.987 [DUSBL] Running Loop=1
2020-01-25T18:00:54.987Z,1579975254.987 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2020-01-25T18:00:54.987Z,1579975254.987 [DUSBL:A.Pitch] Running Loop=1
2020-01-25T18:00:54.988Z,1579975254.988 [DUSBL:A.Pitch](DEBUG): Initialize.
2020-01-25T18:00:54.988Z,1579975254.988 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-25T18:00:54.988Z,1579975254.988 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2020-01-25T18:00:54.988Z,1579975254.988 [DUSBL:C] Running Loop=1
2020-01-25T18:00:54.988Z,1579975254.988 [DUSBL:RequestRepeater] Running Loop=1
2020-01-25T18:00:54.988Z,1579975254.988 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2020-01-25T18:00:54.988Z,1579975254.988 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-25T18:00:54.988Z,1579975254.988 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-25T18:00:54.988Z,1579975254.988 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2020-01-25T18:00:54.988Z,1579975254.988 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:00:54.989Z,1579975254.989 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-25T18:00:54.989Z,1579975254.989 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-25T18:00:54.990Z,1579975254.990 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_range
2020-01-25T18:00:54.991Z,1579975254.991 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_direction_vehicle_frame
2020-01-25T18:00:54.991Z,1579975254.991 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-25T18:00:54.991Z,1579975254.991 [DUSBL:A.Pitch] Running Loop=1
2020-01-25T18:00:56.976Z,1579975256.976 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:00:57.381Z,1579975257.381 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:00:58.194Z,1579975258.194 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180058.13*58

2020-01-25T18:01:01.012Z,1579975261.012 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:01.415Z,1579975261.415 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:02.230Z,1579975262.230 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180102.16*53

2020-01-25T18:01:05.050Z,1579975265.050 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:05.461Z,1579975265.461 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:06.284Z,1579975266.284 [Micromodem](INFO): Nmea in: $SNTTA,0.013011,,,,180106.20*4E

2020-01-25T18:01:09.093Z,1579975269.093 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:09.497Z,1579975269.497 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:10.322Z,1579975270.322 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180110.24*51

2020-01-25T18:01:13.130Z,1579975273.130 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:13.543Z,1579975273.543 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:14.350Z,1579975274.350 [Micromodem](INFO): Nmea in: $SNTTA,0.012864,,,,180114.28*4E

2020-01-25T18:01:17.176Z,1579975277.176 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:17.581Z,1579975277.581 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:18.397Z,1579975278.397 [Micromodem](INFO): Nmea in: $SNTTA,0.013188,,,,180118.32*43

2020-01-25T18:01:21.220Z,1579975281.220 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:21.617Z,1579975281.617 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:22.431Z,1579975282.431 [Micromodem](INFO): Nmea in: $SNTTA,0.012408,,,,180122.37*43

2020-01-25T18:01:25.252Z,1579975285.252 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:25.669Z,1579975285.669 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:26.480Z,1579975286.480 [Micromodem](INFO): Nmea in: $SNTTA,0.012182,,,,180126.40*40

2020-01-25T18:01:26.928Z,1579975286.928 [CommandLine](IMPORTANT): got command report mod acoustic_contact_range
2020-01-25T18:01:28.131Z,1579975288.131 [Reporter](INFO): acoustic_contact_range 18.240002 m
2020-01-25T18:01:29.313Z,1579975289.313 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:29.696Z,1579975289.696 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:30.140Z,1579975290.140 [Reporter](INFO): acoustic_contact_range 18.540001 m
2020-01-25T18:01:30.517Z,1579975290.517 [Micromodem](INFO): Nmea in: $SNTTA,0.012309,,,,180130.46*40

2020-01-25T18:01:33.341Z,1579975293.341 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:33.741Z,1579975293.741 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:34.200Z,1579975294.200 [Reporter](INFO): acoustic_contact_range 19.079998 m
2020-01-25T18:01:34.563Z,1579975294.563 [Micromodem](INFO): Nmea in: $SNTTA,0.012753,,,,180134.49*40

2020-01-25T18:01:37.376Z,1579975297.376 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:37.781Z,1579975297.781 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:38.216Z,1579975298.216 [Reporter](INFO): acoustic_contact_range 18.359997 m
2020-01-25T18:01:38.601Z,1579975298.601 [Micromodem](INFO): Nmea in: $SNTTA,0.012214,,,,180138.52*40

2020-01-25T18:01:41.412Z,1579975301.412 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:41.827Z,1579975301.827 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:42.272Z,1579975302.272 [Reporter](INFO): acoustic_contact_range 18.000000 m
2020-01-25T18:01:42.636Z,1579975302.636 [Micromodem](INFO): Nmea in: $SNTTA,0.011970,,,,180142.56*43

2020-01-25T18:01:45.456Z,1579975305.456 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:45.856Z,1579975305.856 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:46.344Z,1579975306.344 [Reporter](INFO): acoustic_contact_range 17.639997 m
2020-01-25T18:01:46.679Z,1579975306.679 [Micromodem](INFO): Nmea in: $SNTTA,0.011780,,,,180146.60*43

2020-01-25T18:01:49.492Z,1579975309.492 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:49.906Z,1579975309.906 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:50.443Z,1579975310.443 [Reporter](INFO): acoustic_contact_range 18.240002 m
2020-01-25T18:01:50.713Z,1579975310.713 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180150.64*51

2020-01-25T18:01:53.536Z,1579975313.536 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:53.941Z,1579975313.941 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:54.404Z,1579975314.404 [Reporter](INFO): acoustic_contact_range 18.899998 m
2020-01-25T18:01:54.750Z,1579975314.750 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180154.69*58

2020-01-25T18:01:57.571Z,1579975317.571 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:01:57.981Z,1579975317.981 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:01:58.465Z,1579975318.465 [Reporter](INFO): acoustic_contact_range 16.619999 m
2020-01-25T18:01:58.800Z,1579975318.800 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180158.72*5E

2020-01-25T18:02:01.624Z,1579975321.624 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:02:02.030Z,1579975322.030 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:02:02.484Z,1579975322.484 [Reporter](INFO): acoustic_contact_range 17.219997 m
2020-01-25T18:02:02.835Z,1579975322.835 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180202.77*57

2020-01-25T18:02:05.650Z,1579975325.650 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:02:06.061Z,1579975326.061 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:02:06.869Z,1579975326.869 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180206.80*5B

2020-01-25T18:02:09.296Z,1579975329.296 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:02:09.348Z,1579975329.348 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-25T18:02:09.348Z,1579975329.348 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,       -3.51,      +7    -22.70,  31.11,  0.00
2020-01-25T18:02:10.100Z,1579975330.100 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:02:10.505Z,1579975330.505 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:02:11.332Z,1579975331.332 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180211.25*52

2020-01-25T18:02:13.735Z,1579975333.735 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:02:14.544Z,1579975334.544 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:02:14.949Z,1579975334.949 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:02:15.427Z,1579975335.427 [Reporter](INFO): acoustic_contact_range 16.439999 m
2020-01-25T18:02:15.769Z,1579975335.769 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180215.69*5E

2020-01-25T18:02:18.580Z,1579975338.580 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:02:18.989Z,1579975338.989 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:02:19.456Z,1579975339.456 [Reporter](INFO): acoustic_contact_range 16.139999 m
2020-01-25T18:02:19.800Z,1579975339.800 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180219.73*59

2020-01-25T18:02:22.620Z,1579975342.620 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:02:22.712Z,1579975342.712 [CommandLine](IMPORTANT): got command stop
2020-01-25T18:02:22.712Z,1579975342.712 [CommandLine](IMPORTANT): Scheduling is paused
2020-01-25T18:02:22.712Z,1579975342.712 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-01-25T18:02:22.801Z,1579975342.801 [MissionManager](INFO): MissionManager is completed.
2020-01-25T18:02:22.801Z,1579975342.801 [MissionManager](INFO): Uninitializing Mission DUSBL
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL] Stopped
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL:A.Pitch] Stopped
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL:B.SetSpeed] Stopped
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL:C] Stopped
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL:RequestRepeater] Stopped
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL:RequestRepeater:A] Stopped
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL:RequestRepeater:B] Stopped
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL:RequestRepeater:C.Wait] Stopped
2020-01-25T18:02:22.801Z,1579975342.801 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:02:23.025Z,1579975343.025 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:02:23.102Z,1579975343.102 [MissionManager](IMPORTANT): Started mission Default
2020-01-25T18:02:23.102Z,1579975343.102 [Default] Running Loop=1
2020-01-25T18:02:23.102Z,1579975343.102 [Default](DEBUG): Aggregate::initialize Default
2020-01-25T18:02:23.102Z,1579975343.102 [Default:B.GoToSurface] Running Loop=1
2020-01-25T18:02:23.102Z,1579975343.102 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-25T18:02:23.103Z,1579975343.103 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-25T18:02:23.103Z,1579975343.103 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-25T18:02:23.103Z,1579975343.103 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-25T18:02:23.104Z,1579975343.104 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-25T18:02:23.104Z,1579975343.104 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-25T18:02:23.104Z,1579975343.104 [Default:A.Wait] Running Loop=1
2020-01-25T18:02:23.104Z,1579975343.104 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:02:23.843Z,1579975343.843 [Micromodem](INFO): Nmea in: $SNTTA,0.011089,,,,180223.76*4A

2020-01-25T18:02:36.465Z,1579975356.465 [Default:A.Wait](INFO): Done Waiting.
2020-01-25T18:02:36.465Z,1579975356.465 [Default:A.Wait] Stopped
2020-01-25T18:02:36.465Z,1579975356.465 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:02:36.856Z,1579975356.856 [Default:CheckIn] Running Loop=1
2020-01-25T18:02:36.856Z,1579975356.856 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-25T18:02:36.856Z,1579975356.856 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-25T18:02:38.819Z,1579975358.819 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180230.00,A,3648.61246,N,12148.64393,W,0.369,280.57,250120,,,D*7A
2020-01-25T18:02:38.821Z,1579975358.821 [NAL9602](INFO): GPS fix at 20200125T180230: (36.810208, -121.810732)
2020-01-25T18:02:38.857Z,1579975358.857 [Default:CheckIn:Read_GPS] Stopped
2020-01-25T18:02:38.857Z,1579975358.857 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-25T18:02:40.352Z,1579975360.352 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200125T175407/Courier0006.lzma
2020-01-25T18:02:42.121Z,1579975362.121 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Courier0006.lzma.bak
2020-01-25T18:02:42.121Z,1579975362.121 [DataOverHttps](INFO): SBD MOMSN=12203591
2020-01-25T18:02:57.797Z,1579975377.797 [NAL9602](INFO): SBD MO Status=0, MOMSN=10264, MT Status=0, MTMSN=0
2020-01-25T18:02:57.797Z,1579975377.797 [NAL9602](INFO): No messages in MT queue
2020-01-25T18:02:59.806Z,1579975379.806 [DataOverHttps](INFO): Sending 469 bytes from file Logs/20200125T175407/Express0007.lzma
2020-01-25T18:03:01.811Z,1579975381.811 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0007.lzma.bak
2020-01-25T18:03:01.811Z,1579975381.811 [DataOverHttps](INFO): SBD MOMSN=12203594
2020-01-25T18:03:11.469Z,1579975391.469 [DataOverHttps](IMPORTANT): SBD MTMSN=20200125T180301
2020-01-25T18:03:19.441Z,1579975399.441 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200125T175407/Express0010.lzma
2020-01-25T18:03:19.444Z,1579975399.444 [DataOverHttps](INFO): Received command:load Maintenance/DUSBL_Tracking.xml;set DUSBL.MissionTimeout 5 minute;set DUSBL.TrackingUpdatePeriod 2.5 second;run 
2020-01-25T18:03:19.483Z,1579975399.483 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/DUSBL_Tracking.xml
2020-01-25T18:03:19.484Z,1579975399.484 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL_Tracking.xml
2020-01-25T18:03:19.532Z,1579975399.532 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2020-01-25T18:03:19.534Z,1579975399.534 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2020-01-25T18:03:19.537Z,1579975399.537 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 4.000000 s
2020-01-25T18:03:19.540Z,1579975399.540 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2020-01-25T18:03:19.541Z,1579975399.541 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2020-01-25T18:03:19.542Z,1579975399.542 [DUSBL:A.Pitch](DEBUG): Construct.
2020-01-25T18:03:19.546Z,1579975399.546 [DUSBL:B.SetSpeed](DEBUG): Construct.
2020-01-25T18:03:19.591Z,1579975399.591 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2020-01-25T18:03:19.625Z,1579975399.625 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>4</Value></DefineArg>
    
    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Sensor:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    
    <Estimation:TrackAcousticContact>
        <Parallel/>
        <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
        <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
        <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
    </Estimation:TrackAcousticContact>
    
      <Aggregate Id="RequestRepeater">

        <Sequence/>
        
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="MissionTimeout"/></Setting>            
          </Guidance:Wait>
      </Aggregate>

</Mission>


2020-01-25T18:03:19.642Z,1579975399.642 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/DUSBL_Tracking.xml
2020-01-25T18:03:23.571Z,1579975403.571 [CommandLine](IMPORTANT): got command set DUSBL.MissionTimeout 5.000000 minute
2020-01-25T18:03:23.572Z,1579975403.572 [CommandLine](IMPORTANT): got command set DUSBL.TrackingUpdatePeriod 2.500000 second
2020-01-25T18:03:23.572Z,1579975403.572 [CommandLine](IMPORTANT): got command run 
2020-01-25T18:03:23.574Z,1579975403.574 [CommandLine](IMPORTANT): Running 
2020-01-25T18:03:23.732Z,1579975403.732 [Default] Stopped
2020-01-25T18:03:23.732Z,1579975403.732 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-25T18:03:23.732Z,1579975403.732 [Default:B.GoToSurface] Stopped
2020-01-25T18:03:23.732Z,1579975403.732 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-25T18:03:23.733Z,1579975403.733 [Default:CheckIn] Stopped
2020-01-25T18:03:23.733Z,1579975403.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T18:03:23.733Z,1579975403.733 [Default:CheckIn:Read_Iridium] Stopped
2020-01-25T18:03:23.733Z,1579975403.733 [MissionManager](IMPORTANT): Started mission DUSBL
2020-01-25T18:03:23.733Z,1579975403.733 [DUSBL] Running Loop=1
2020-01-25T18:03:23.733Z,1579975403.733 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2020-01-25T18:03:23.733Z,1579975403.733 [DUSBL:A.Pitch] Running Loop=1
2020-01-25T18:03:23.733Z,1579975403.733 [DUSBL:A.Pitch](DEBUG): Initialize.
2020-01-25T18:03:23.739Z,1579975403.739 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-25T18:03:23.739Z,1579975403.739 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2020-01-25T18:03:23.739Z,1579975403.739 [DUSBL:C.] Running Loop=1
2020-01-25T18:03:23.739Z,1579975403.739 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2020-01-25T18:03:23.739Z,1579975403.739 [DUSBL:RequestRepeater] Running Loop=1
2020-01-25T18:03:23.740Z,1579975403.740 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2020-01-25T18:03:23.740Z,1579975403.740 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2020-01-25T18:03:23.740Z,1579975403.740 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:03:23.740Z,1579975403.740 [DUSBL:C.] Running Loop=1
2020-01-25T18:03:23.741Z,1579975403.741 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-25T18:03:23.741Z,1579975403.741 [DUSBL:A.Pitch] Running Loop=1
2020-01-25T18:03:24.583Z,1579975404.583 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0010.lzma.bak
2020-01-25T18:03:24.583Z,1579975404.583 [DataOverHttps](INFO): SBD MOMSN=12203611
2020-01-25T18:03:26.572Z,1579975406.572 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:27.307Z,1579975407.307 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:03:27.693Z,1579975407.693 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:03:28.521Z,1579975408.521 [Micromodem](INFO): Nmea in: $SNTTA,0.010539,,,,180328.45*4F

2020-01-25T18:03:28.528Z,1579975408.528 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-25T18:03:29.414Z,1579975409.414 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:30.923Z,1579975410.923 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:03:32.174Z,1579975412.174 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:32.948Z,1579975412.948 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:03:33.359Z,1579975413.359 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:03:34.169Z,1579975414.169 [Micromodem](INFO): Nmea in: $SNTTA,0.010160,,,,180334.09*42

2020-01-25T18:03:35.002Z,1579975415.002 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:36.588Z,1579975416.588 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:03:37.820Z,1579975417.820 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:38.604Z,1579975418.604 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:03:39.009Z,1579975419.009 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:03:39.818Z,1579975419.818 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180339.75*5C

2020-01-25T18:03:40.658Z,1579975420.658 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:42.239Z,1579975422.239 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:03:43.498Z,1579975423.498 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:44.273Z,1579975424.273 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:03:44.663Z,1579975424.663 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:03:45.484Z,1579975425.484 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180345.42*53

2020-01-25T18:03:46.360Z,1579975426.360 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:47.895Z,1579975427.895 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:03:49.143Z,1579975429.143 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:49.916Z,1579975429.916 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:03:50.333Z,1579975430.333 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:03:51.137Z,1579975431.137 [Micromodem](INFO): Nmea in: $SNTTA,0.010450,,,,180351.06*48

2020-01-25T18:03:51.976Z,1579975431.976 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:53.551Z,1579975433.551 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:03:54.796Z,1579975434.796 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:55.572Z,1579975435.572 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:03:55.971Z,1579975435.971 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:03:56.789Z,1579975436.789 [Micromodem](INFO): Nmea in: $SNTTA,0.010601,,,,180356.72*4A

2020-01-25T18:03:57.633Z,1579975437.633 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:03:59.207Z,1579975439.207 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:04:00.459Z,1579975440.459 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:01.228Z,1579975441.228 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:01.635Z,1579975441.635 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:02.090Z,1579975442.090 [Reporter](INFO): acoustic_contact_range 15.840000 m
2020-01-25T18:04:02.455Z,1579975442.455 [Micromodem](INFO): Nmea in: $SNTTA,0.010530,,,,180402.38*43

2020-01-25T18:04:04.895Z,1579975444.895 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:05.672Z,1579975445.672 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:05.729Z,1579975445.729 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-25T18:04:05.742Z,1579975445.742 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 333.98, 308.53, 304.04
2020-01-25T18:04:06.082Z,1579975446.082 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:06.889Z,1579975446.889 [Micromodem](INFO): Nmea in: $SNTTA,0.010381,,,,180406.82*4A

2020-01-25T18:04:07.716Z,1579975447.716 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:09.317Z,1579975449.317 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:04:10.568Z,1579975450.568 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:11.325Z,1579975451.325 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:11.733Z,1579975451.733 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:12.547Z,1579975452.547 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180412.47*53

2020-01-25T18:04:13.393Z,1579975453.393 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:14.959Z,1579975454.959 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:04:16.206Z,1579975456.206 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:16.981Z,1579975456.981 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:17.384Z,1579975457.384 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:18.205Z,1579975458.205 [Micromodem](INFO): Nmea in: $SNTTA,0.021325,,,,180418.13*41

2020-01-25T18:04:19.049Z,1579975459.049 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:20.623Z,1579975460.623 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:04:21.873Z,1579975461.873 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:22.636Z,1579975462.636 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:23.041Z,1579975463.041 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:23.513Z,1579975463.513 [Reporter](INFO): acoustic_contact_range 16.499996 m
2020-01-25T18:04:23.867Z,1579975463.867 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180423.79*5C

2020-01-25T18:04:26.339Z,1579975466.339 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:27.084Z,1579975467.084 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:27.490Z,1579975467.490 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:28.312Z,1579975468.312 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180428.23*58

2020-01-25T18:04:29.136Z,1579975469.136 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:30.713Z,1579975470.713 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:04:32.007Z,1579975472.007 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:32.736Z,1579975472.736 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:33.140Z,1579975473.140 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:34.007Z,1579975474.007 [Micromodem](INFO): Nmea in: $SNTTA,,,,,180433.88*53

2020-01-25T18:04:36.409Z,1579975476.409 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:37.178Z,1579975477.178 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:37.589Z,1579975477.589 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:38.115Z,1579975478.115 [Reporter](INFO): acoustic_contact_range 16.619999 m
2020-01-25T18:04:38.415Z,1579975478.415 [Micromodem](INFO): Nmea in: $SNTTA,0.011097,,,,180438.32*49

2020-01-25T18:04:40.870Z,1579975480.870 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:41.628Z,1579975481.628 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:42.030Z,1579975482.030 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:42.495Z,1579975482.495 [Reporter](INFO): acoustic_contact_range 17.400002 m
2020-01-25T18:04:42.855Z,1579975482.855 [Micromodem](INFO): Nmea in: $SNTTA,0.011601,,,,180442.78*43

2020-01-25T18:04:45.330Z,1579975485.330 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:46.067Z,1579975486.067 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:46.472Z,1579975486.472 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:46.947Z,1579975486.947 [Reporter](INFO): acoustic_contact_range 17.039999 m
2020-01-25T18:04:47.316Z,1579975487.316 [Micromodem](INFO): Nmea in: $SNTTA,0.011283,,,,180447.22*47

2020-01-25T18:04:49.742Z,1579975489.742 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:50.512Z,1579975490.512 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:50.928Z,1579975490.928 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:51.459Z,1579975491.459 [Reporter](INFO): acoustic_contact_range 17.100002 m
2020-01-25T18:04:51.761Z,1579975491.761 [Micromodem](INFO): Nmea in: $SNTTA,0.011340,,,,180451.66*4E

2020-01-25T18:04:54.182Z,1579975494.182 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:54.959Z,1579975494.959 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:55.375Z,1579975495.375 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:04:55.822Z,1579975495.822 [Reporter](INFO): acoustic_contact_range 17.879999 m
2020-01-25T18:04:56.187Z,1579975496.187 [Micromodem](INFO): Nmea in: $SNTTA,0.011922,,,,180456.11*47

2020-01-25T18:04:58.632Z,1579975498.632 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:04:59.404Z,1579975499.404 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:04:59.805Z,1579975499.805 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:05:00.280Z,1579975500.280 [Reporter](INFO): acoustic_contact_range 18.240002 m
2020-01-25T18:05:00.635Z,1579975500.635 [Micromodem](INFO): Nmea in: $SNTTA,0.012288,,,,180500.55*4D

2020-01-25T18:05:02.997Z,1579975502.997 [CommandLine](IMPORTANT): got command stop
2020-01-25T18:05:02.997Z,1579975502.997 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-01-25T18:05:03.075Z,1579975503.075 [DUSBL:C.](INFO): *** querying acoustic contact ***
2020-01-25T18:05:03.075Z,1579975503.075 [MissionManager](INFO): MissionManager is completed.
2020-01-25T18:05:03.075Z,1579975503.075 [MissionManager](INFO): Uninitializing Mission DUSBL
2020-01-25T18:05:03.075Z,1579975503.075 [DUSBL] Stopped
2020-01-25T18:05:03.076Z,1579975503.076 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2020-01-25T18:05:03.076Z,1579975503.076 [DUSBL:A.Pitch] Stopped
2020-01-25T18:05:03.076Z,1579975503.076 [DUSBL:B.SetSpeed] Stopped
2020-01-25T18:05:03.076Z,1579975503.076 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2020-01-25T18:05:03.076Z,1579975503.076 [DUSBL:C.] Stopped
2020-01-25T18:05:03.076Z,1579975503.076 [DUSBL:RequestRepeater] Stopped
2020-01-25T18:05:03.076Z,1579975503.076 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2020-01-25T18:05:03.076Z,1579975503.076 [DUSBL:RequestRepeater:A.Wait] Stopped
2020-01-25T18:05:03.076Z,1579975503.076 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:05:03.485Z,1579975503.485 [MissionManager](IMPORTANT): Started mission Default
2020-01-25T18:05:03.485Z,1579975503.485 [Default] Running Loop=1
2020-01-25T18:05:03.485Z,1579975503.485 [Default](DEBUG): Aggregate::initialize Default
2020-01-25T18:05:03.485Z,1579975503.485 [Default:B.GoToSurface] Running Loop=1
2020-01-25T18:05:03.485Z,1579975503.485 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-25T18:05:03.490Z,1579975503.490 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-25T18:05:03.490Z,1579975503.490 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-25T18:05:03.490Z,1579975503.490 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-25T18:05:03.491Z,1579975503.491 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-25T18:05:03.491Z,1579975503.491 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-25T18:05:03.494Z,1579975503.494 [Default:A.Wait] Running Loop=1
2020-01-25T18:05:03.494Z,1579975503.494 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:05:16.810Z,1579975516.810 [Default:A.Wait](INFO): Done Waiting.
2020-01-25T18:05:16.810Z,1579975516.810 [Default:A.Wait] Stopped
2020-01-25T18:05:16.810Z,1579975516.810 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:05:17.223Z,1579975517.223 [Default:CheckIn] Running Loop=1
2020-01-25T18:05:17.223Z,1579975517.223 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-25T18:05:17.223Z,1579975517.223 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-25T18:05:19.196Z,1579975519.196 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180510.00,A,3648.61902,N,12148.64910,W,1.108,300.72,250120,,,D*7F
2020-01-25T18:05:19.198Z,1579975519.198 [NAL9602](INFO): GPS fix at 20200125T180510: (36.810317, -121.810818)
2020-01-25T18:05:19.247Z,1579975519.247 [Default:CheckIn:Read_GPS] Stopped
2020-01-25T18:05:19.248Z,1579975519.248 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-25T18:05:27.757Z,1579975527.757 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200125T175407/Courier0012.lzma
2020-01-25T18:05:29.763Z,1579975529.763 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Courier0012.lzma.bak
2020-01-25T18:05:29.763Z,1579975529.763 [DataOverHttps](INFO): SBD MOMSN=12203617
2020-01-25T18:05:47.097Z,1579975547.097 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200125T175407/Courier0015.lzma
2020-01-25T18:05:49.104Z,1579975549.104 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Courier0015.lzma.bak
2020-01-25T18:05:49.104Z,1579975549.104 [DataOverHttps](INFO): SBD MOMSN=12203620
2020-01-25T18:06:06.634Z,1579975566.634 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20200125T175407/Express0013.lzma
2020-01-25T18:06:08.639Z,1579975568.639 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0013.lzma.bak
2020-01-25T18:06:08.639Z,1579975568.639 [DataOverHttps](INFO): SBD MOMSN=12203623
2020-01-25T18:06:26.296Z,1579975586.296 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20200125T175407/Express0016.lzma
2020-01-25T18:06:28.299Z,1579975588.299 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0016.lzma.bak
2020-01-25T18:06:28.300Z,1579975588.300 [DataOverHttps](INFO): SBD MOMSN=12203633
2020-01-25T18:06:29.531Z,1579975589.531 [Default:CheckIn:Read_Iridium] Stopped
2020-01-25T18:06:29.532Z,1579975589.532 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-25T18:06:29.532Z,1579975589.532 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:06:39.621Z,1579975599.621 [NAL9602](INFO): SBD MO Status=0, MOMSN=10265, MT Status=0, MTMSN=0
2020-01-25T18:06:39.621Z,1579975599.621 [NAL9602](INFO): No messages in MT queue
2020-01-25T18:07:10.340Z,1579975630.340 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-25T18:07:13.924Z,1579975633.924 [DataOverHttps](IMPORTANT): SBD MTMSN=20200125T180704
2020-01-25T18:07:20.328Z,1579975640.328 [CommandLine](IMPORTANT): got command stop
2020-01-25T18:07:21.702Z,1579975641.702 [DataOverHttps](INFO): Received command:sched asap "load Engineering/lineCaptureDepth.xml;set lineCaptureDepth.MissionTimeout 1 hour;set lineCaptureDepth.NeedCommsTime 60 minute;set lineCaptureDepth.MicromodemCommsInterval 10 minute;set lineCaptureDepth.WaitOnDockTimeout 2 minute" 4gnmz 1 2
2020-01-25T18:07:21.735Z,1579975641.735 [CommandLine](IMPORTANT): got command schedule asap "load Engineering/lineCaptureDepth.xml;set lineCaptureDepth.MissionTimeout 1 hour;set lineCaptureDepth.NeedCommsTime 60 minute;set lineCaptureDepth.MicromodemCommsInterval 10 minute;set lineCaptureDepth.WaitOnDockTimeout 2 minute" 4gnmz 1 2.000000
2020-01-25T18:07:21.735Z,1579975641.735 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=4gnmz
2020-01-25T18:07:21.736Z,1579975641.736 [CommandLine](IMPORTANT): Scheduled #3 (#1 of 2 with id='4gnmz'): "load Engineering/lineCaptureDepth.xml;set lineCaptureDepth.MissionTimeout 1 hour;set lineCaptureDepth.NeedCommsTime 60 minute;set lineCaptureDepth.MicromodemCommsInterval 10 minute;set lineCaptureDepth.WaitOnDockTimeout 2 minute" ASAP
2020-01-25T18:07:23.707Z,1579975643.707 [DataOverHttps](IMPORTANT): SBD MTMSN=20200125T180713
2020-01-25T18:07:31.401Z,1579975651.401 [DataOverHttps](INFO): Received command:sched asap "set lineCaptureDepth.MaxDepth 25 meter;set lineCaptureDepth.MinOffshore 0.5 kilometer;run " 4gnmz 2 2
2020-01-25T18:07:31.467Z,1579975651.467 [CommandLine](IMPORTANT): got command schedule asap "set lineCaptureDepth.MaxDepth 25 meter;set lineCaptureDepth.MinOffshore 0.5 kilometer;run " 4gnmz 2 2.000000
2020-01-25T18:07:31.467Z,1579975651.467 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=4gnmz
2020-01-25T18:07:31.468Z,1579975651.468 [CommandLine](IMPORTANT): Scheduled #4 (#2 of 2 with id='4gnmz'): "set lineCaptureDepth.MaxDepth 25 meter;set lineCaptureDepth.MinOffshore 0.5 kilometer;run " ASAP
2020-01-25T18:07:31.530Z,1579975651.530 [CommandLine](IMPORTANT): got command load ./Missions/Engineering/lineCaptureDepth.xml
2020-01-25T18:07:31.531Z,1579975651.531 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/lineCaptureDepth.xml
2020-01-25T18:07:31.630Z,1579975651.630 [MissionManager](INFO): DefineArg lineCaptureDepth.MissionTimeout = 4.000000 h
2020-01-25T18:07:31.633Z,1579975651.633 [MissionManager](INFO): DefineArg lineCaptureDepth.NeedCommsTime = 240.000000 min
2020-01-25T18:07:31.635Z,1579975651.635 [MissionManager](INFO): DefineArg lineCaptureDepth.MicromodemCommsInterval = 5.000000 min
2020-01-25T18:07:31.637Z,1579975651.637 [MissionManager](INFO): DefineArg lineCaptureDepth.Repeat = 1.000000 count
2020-01-25T18:07:31.639Z,1579975651.639 [MissionManager](INFO): DefineArg lineCaptureDepth.WaitOnDockTimeout = 5.000000 min
2020-01-25T18:07:31.641Z,1579975651.641 [MissionManager](INFO): DefineArg lineCaptureDepth.Depth = 15.000000 m
2020-01-25T18:07:31.643Z,1579975651.643 [MissionManager](INFO): DefineArg lineCaptureDepth.TransponderCode = 2.000000 count
2020-01-25T18:07:31.645Z,1579975651.645 [MissionManager](INFO): DefineArg lineCaptureDepth.TrackingUpdatePeriod = 2.500000 s
2020-01-25T18:07:31.648Z,1579975651.648 [MissionManager](INFO): DefineArg lineCaptureDepth.NumberOfPings = 1.000000 count
2020-01-25T18:07:31.650Z,1579975651.650 [MissionManager](INFO): DefineArg lineCaptureDepth.MinAltitude = 5.000000 m
2020-01-25T18:07:31.652Z,1579975651.652 [MissionManager](INFO): DefineArg lineCaptureDepth.MaxDepth = 205.000000 m
2020-01-25T18:07:31.654Z,1579975651.654 [MissionManager](INFO): DefineArg lineCaptureDepth.MinOffshore = 2.000000 km
2020-01-25T18:07:31.657Z,1579975651.657 [MissionManager](INFO): DefineOutput lineCaptureDepth.Mode = -1.000000 count
2020-01-25T18:07:31.657Z,1579975651.657 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-01-25T18:07:31.788Z,1579975651.788 [MissionManager](INFO): DefineArg lineCaptureDepth:StandardEnvelopes.MinAltitude = 5.000000 m
2020-01-25T18:07:31.794Z,1579975651.794 [MissionManager](INFO): DefineArg lineCaptureDepth:StandardEnvelopes.MaxDepth = 200.000000 m
2020-01-25T18:07:31.796Z,1579975651.796 [MissionManager](INFO): DefineArg lineCaptureDepth:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-01-25T18:07:31.797Z,1579975651.797 [lineCaptureDepth:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-01-25T18:07:31.835Z,1579975651.835 [lineCaptureDepth:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-01-25T18:07:31.859Z,1579975651.859 [lineCaptureDepth:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-01-25T18:07:31.880Z,1579975651.880 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-01-25T18:07:31.988Z,1579975651.988 [MissionManager](INFO): DefineArg lineCaptureDepth:NeedComms.DiveInterval = 3.000000 h
2020-01-25T18:07:31.998Z,1579975651.998 [MissionManager](INFO): DefineArg lineCaptureDepth:NeedComms.WaitForPitchUp = 10.000000 min
2020-01-25T18:07:32.000Z,1579975652.000 [MissionManager](INFO): DefineArg lineCaptureDepth:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-01-25T18:07:32.006Z,1579975652.006 [MissionManager](INFO): DefineArg lineCaptureDepth:NeedComms.SurfaceDepthRate = nan m/s
2020-01-25T18:07:32.008Z,1579975652.008 [MissionManager](INFO): DefineArg lineCaptureDepth:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-01-25T18:07:32.015Z,1579975652.015 [MissionManager](INFO): DefineArg lineCaptureDepth:NeedComms.GPSTimeout = 7.000000 min
2020-01-25T18:07:32.017Z,1579975652.017 [MissionManager](INFO): DefineArg lineCaptureDepth:NeedComms.CommsTimeout = 30.000000 min
2020-01-25T18:07:32.035Z,1579975652.035 [lineCaptureDepth:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-25T18:07:32.089Z,1579975652.089 [MissionManager](INFO): Inserting Stack: Missions/Insert/MicromodemComms.xml
2020-01-25T18:07:32.196Z,1579975652.196 [MissionManager](INFO): DefineArg lineCaptureDepth:MicromodemComms.Timeout = 10.000000 min
2020-01-25T18:07:32.208Z,1579975652.208 [MissionManager](INFO): DefineArg lineCaptureDepth:MicromodemComms.CommsInterval = 5.000000 min
2020-01-25T18:07:32.216Z,1579975652.216 [MissionManager](INFO): RedefineArg lineCaptureDepth:MicromodemComms.CommsInterval = reader:lineCaptureDepth.MicromodemCommsInterval
2020-01-25T18:07:32.239Z,1579975652.239 [MissionManager](INFO): Inserting Stack: Missions/Insert/LineCapture.xml
2020-01-25T18:07:32.499Z,1579975652.499 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.DockDepth = 10.000000 _
2020-01-25T18:07:32.501Z,1579975652.501 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.DockLat = 0.642475 _
2020-01-25T18:07:32.504Z,1579975652.504 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.DockLon = -2.126160 _
2020-01-25T18:07:32.506Z,1579975652.506 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.DetachRange = 8.000000 m
2020-01-25T18:07:32.508Z,1579975652.508 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.DetachTimeout = 30.000000 min
2020-01-25T18:07:32.510Z,1579975652.510 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.MidcourseDepth = 15.000000 m
2020-01-25T18:07:32.512Z,1579975652.512 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.MidcourseSpeed = 1.000000 m/s
2020-01-25T18:07:32.514Z,1579975652.514 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.MidcourseTimeout = 12.000000 h
2020-01-25T18:07:32.516Z,1579975652.516 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.TerminalRange = 650.000000 m
2020-01-25T18:07:32.575Z,1579975652.575 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.ArmRange = 40.000000 m
2020-01-25T18:07:32.577Z,1579975652.577 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.DockRange = 2.000000 m
2020-01-25T18:07:32.604Z,1579975652.604 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.DockTimeout = 60.000000 s
2020-01-25T18:07:32.616Z,1579975652.616 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.RolloutDistance = 250.000000 m
2020-01-25T18:07:32.634Z,1579975652.634 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.AcousticTimeout = 10.000000 min
2020-01-25T18:07:32.636Z,1579975652.636 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.TerminalSpeed = 0.800000 m/s
2020-01-25T18:07:32.658Z,1579975652.658 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.SearchTimeout = 60.000000 min
2020-01-25T18:07:32.660Z,1579975652.660 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.CircleRadius = 300.000000 m
2020-01-25T18:07:32.684Z,1579975652.684 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.CircleMaxError = 100.000000 m
2020-01-25T18:07:32.687Z,1579975652.687 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.CircleTurnToPort = 0 bool
2020-01-25T18:07:32.689Z,1579975652.689 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.DepthDeadband = 0.010000 m
2020-01-25T18:07:32.701Z,1579975652.701 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.RudderDeadband = 0.001000 arcdeg
2020-01-25T18:07:32.703Z,1579975652.703 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.MinAltitude = 5.000000 m
2020-01-25T18:07:32.705Z,1579975652.705 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.MaxDepth = 205.000000 m
2020-01-25T18:07:32.727Z,1579975652.727 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.MinOffshore = 500.000000 m
2020-01-25T18:07:32.729Z,1579975652.729 [MissionManager](INFO): DefineOutput lineCaptureDepth:LineCapture.Depth = 15.000000 n/a
2020-01-25T18:07:32.735Z,1579975652.735 [MissionManager](INFO): DefineOutput lineCaptureDepth:LineCapture.Speed = 1.000000 n/a
2020-01-25T18:07:32.737Z,1579975652.737 [MissionManager](INFO): DefineOutput lineCaptureDepth:LineCapture.DoneTimeout = -1.000000 count
2020-01-25T18:07:32.748Z,1579975652.748 [MissionManager](INFO): DefineOutput lineCaptureDepth:LineCapture.Done = 0.000000 count
2020-01-25T18:07:32.754Z,1579975652.754 [MissionManager](INFO): DefineOutput lineCaptureDepth:LineCapture.DetachFromDock = 1.000000 count
2020-01-25T18:07:32.756Z,1579975652.756 [MissionManager](INFO): DefineOutput lineCaptureDepth:LineCapture.Midcourse = 2.000000 count
2020-01-25T18:07:32.771Z,1579975652.771 [MissionManager](INFO): DefineOutput lineCaptureDepth:LineCapture.Terminal = 3.000000 count
2020-01-25T18:07:32.773Z,1579975652.773 [MissionManager](INFO): DefineOutput lineCaptureDepth:LineCapture.GuidanceMode = 1.000000 n/a
2020-01-25T18:07:32.788Z,1579975652.788 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.ElapsedRange = 0.000000 min
2020-01-25T18:07:32.794Z,1579975652.794 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture.Range = nan m
2020-01-25T18:07:32.796Z,1579975652.796 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-01-25T18:07:32.836Z,1579975652.836 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture:StandardEnvelopes.MinAltitude = 5.000000 m
2020-01-25T18:07:32.850Z,1579975652.850 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture:StandardEnvelopes.MaxDepth = 200.000000 m
2020-01-25T18:07:32.852Z,1579975652.852 [MissionManager](INFO): DefineArg lineCaptureDepth:LineCapture:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-01-25T18:07:32.853Z,1579975652.853 [lineCaptureDepth:LineCapture:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-01-25T18:07:32.874Z,1579975652.874 [lineCaptureDepth:LineCapture:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-01-25T18:07:32.968Z,1579975652.968 [lineCaptureDepth:LineCapture:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-01-25T18:07:33.040Z,1579975653.040 [lineCaptureDepth:LineCapture:H.Mass](DEBUG): Construct.
2020-01-25T18:07:33.062Z,1579975653.062 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:B.Buoyancy](DEBUG): Construct Buoyancy.
2020-01-25T18:07:33.092Z,1579975653.092 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:C.SetSpeed](DEBUG): Construct.
2020-01-25T18:07:33.123Z,1579975653.123 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:D.Pitch](DEBUG): Construct.
2020-01-25T18:07:33.132Z,1579975653.132 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive:A.SetSpeed](DEBUG): Construct.
2020-01-25T18:07:33.146Z,1579975653.146 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive:B.Pitch](DEBUG): Construct.
2020-01-25T18:07:33.180Z,1579975653.180 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Construct.
2020-01-25T18:07:33.255Z,1579975653.255 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance:guide:E.Waypoint](DEBUG): Construct Waypoint.
2020-01-25T18:07:33.378Z,1579975653.378 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance:guide:G.Circle](DEBUG): Construct.
2020-01-25T18:07:33.465Z,1579975653.465 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide:G.LineCapture](DEBUG): Construct.
2020-01-25T18:07:33.500Z,1579975653.500 [lineCaptureDepth:LineCapture:K.Wait](DEBUG): Construct Wait.
2020-01-25T18:07:33.522Z,1579975653.522 [MissionManager](INFO): RedefineArg lineCaptureDepth:LineCapture.MidcourseDepth = reader:lineCaptureDepth:LineCapture.Depth
2020-01-25T18:07:33.538Z,1579975653.538 [lineCaptureDepth:Lap:Docked:B.Wait](DEBUG): Construct Wait.
2020-01-25T18:07:33.539Z,1579975653.539 [lineCaptureDepth:Lap:Docked:Detach:A.SetSpeed](DEBUG): Construct.
2020-01-25T18:07:33.558Z,1579975653.558 [lineCaptureDepth:Lap:Docked:Detach:B.Pitch](DEBUG): Construct.
2020-01-25T18:07:33.563Z,1579975653.563 [lineCaptureDepth:Lap:Docked:Detach:D.Undock](DEBUG): Construct.
2020-01-25T18:07:33.652Z,1579975653.652 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
    xmlns:Estimation="Tethys/Estimation"
    xmlns:Guidance="Tethys/Guidance"
    xmlns:Units="Tethys/Units"
    xmlns:Universal="Tethys/Universal"
    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
    xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                        Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                        Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                        Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                        Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
    Id="lineCaptureDepth">

    <Description>
        Home to transponder while maintaining altitude. The mission sequence:
        (*) Start (1) Midcourse guidance: nav to target WP while querying
        transponder (2) Terminal guidance: home to target using DUSBL tracking
        data (4) Dock: final approach using fixed heading.
    </Description>

    <!-- Define mission arguments -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum length of mission
    </Description><Units:hour/><Value>4</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        How often to surface for commumications
    </Description><Units:minute/><Value>240</Value></DefineArg>

    <DefineArg Name="MicromodemCommsInterval"><Description>
        How often to trigger Umodem communications
    </Description><Units:minute/><Value>5</Value></DefineArg>

    <DefineArg Name="Repeat"><Description>
        Number of times the vehicle should try to cycle through the homing
        sequence.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="WaitOnDockTimeout"><Description>
        Time duration to wait on the dock after a successful line capture.
    </Description><Units:minute/><Value>5</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Desired vehicle depth when navigating to dock WP (during midcourse phase).
    </Description><Units:meter/><Value>15</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries.
    </Description><Units:second/><Value>2.5</Value></DefineArg>

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

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

    <!-- Mission Variables (don't change) -->

    <DefineOutput Name="Mode"><Description>
        Mission variable (don't change).
        The mission sets this variable to reflect the termination status of the LineCapture insert.
    </Description><Units:count/><Value>-1</Value></DefineOutput>

    <!-- Mission timeout -->

    <Timeout Duration="MissionTimeout"/>

    <!-- Safety nets and surfacing behaviors -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <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/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

    <Assign><Sequence/><Arg Name="NeedComms:WaitForPitchUp"/><Units:minute/><Value>0</Value></Assign>

    <!-- Micromodem comms -->

    <Insert Filename="Insert/MicromodemComms.xml" Id="MicromodemComms">
        <RedefineArg Name="CommsInterval"><Arg Name="MicromodemCommsInterval"/></RedefineArg>
    </Insert>

    <!-- Add track acoustic contact directive high in the stack. -->

    <Estimation:TrackAcousticContact>
        <Parallel/>
        <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
        <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
        <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
    </Estimation:TrackAcousticContact>

    <!-- Add LineCapture directives. -->

    <Insert Filename="Insert/LineCapture.xml">
        <RedefineArg Name="MidcourseDepth"><Arg Name="Depth"/></RedefineArg>
    </Insert>

    <!-- Start mission sequence. -->

    <Aggregate Id="Lap">

        <Sequence Repeat="Repeat"/>

        <Assign><Sequence/><Arg Name="Mode"/><Units:count/><Value>-99</Value></Assign>

        <!-- Execute the LineCapture sequence. -->
        <Call RefId="LineCapture"/>

        <Assign><Sequence/><Arg Name="Mode"/><Arg Name="LineCapture:GuidanceMode"/></Assign>

        <Syslog Severity="Info">Mode<Arg Name="Mode"/><Units:count/></Syslog>

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

            <Break>
                <Arg Name="Mode"/><Lt><Units:count/><Value>0</Value></Lt>
            </Break>

            <Syslog Severity="Info">Waiting on dock for <Arg Name="WaitOnDockTimeout"/><Units:minute/></Syslog>

            <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="WaitOnDockTimeout"/></Setting>
            </Guidance:Wait>

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

                <Guidance:SetSpeed>
                    <Parallel/>
                    <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
                </Guidance:SetSpeed>

                <Guidance:Pitch>
                    <Parallel/>
                    <Setting><Guidance:Pitch.depth/><Arg Name="Depth"/></Setting>
                </Guidance:Pitch>

                <Syslog Severity="Important">Breaking off. Range: <Estimation:TrackAcousticContact.range_to_contact/><Units:meter/>.</Syslog>

                <Guidance:Undock>
                    <Sequence/>
                </Guidance:Undock>

            </Aggregate>
        </Aggregate>

    </Aggregate>

</Mission>


2020-01-25T18:07:33.653Z,1579975653.653 [CommandLine](IMPORTANT): Loaded ./Missions/Engineering/lineCaptureDepth.xml
2020-01-25T18:07:42.883Z,1579975662.883 [CommandLine](IMPORTANT): got command set lineCaptureDepth.MissionTimeout 1.000000 hour
2020-01-25T18:07:42.884Z,1579975662.884 [CommandLine](IMPORTANT): got command set lineCaptureDepth.NeedCommsTime 60.000000 minute
2020-01-25T18:07:42.884Z,1579975662.884 [CommandLine](IMPORTANT): got command set lineCaptureDepth.MicromodemCommsInterval 10.000000 minute
2020-01-25T18:07:42.885Z,1579975662.885 [CommandLine](IMPORTANT): got command set lineCaptureDepth.WaitOnDockTimeout 2.000000 minute
2020-01-25T18:07:46.628Z,1579975666.628 [CommandLine](IMPORTANT): got command set lineCaptureDepth.MaxDepth 25.000000 meter
2020-01-25T18:07:46.629Z,1579975666.629 [CommandLine](IMPORTANT): got command set lineCaptureDepth.MinOffshore 0.500000 kilometer
2020-01-25T18:07:46.629Z,1579975666.629 [CommandLine](IMPORTANT): got command run 
2020-01-25T18:07:46.631Z,1579975666.631 [CommandLine](IMPORTANT): Running 
2020-01-25T18:07:46.717Z,1579975666.717 [Default] Stopped
2020-01-25T18:07:46.717Z,1579975666.717 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-25T18:07:46.717Z,1579975666.717 [Default:B.GoToSurface] Stopped
2020-01-25T18:07:46.718Z,1579975666.718 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-25T18:07:46.726Z,1579975666.726 [Default:CheckIn] Stopped
2020-01-25T18:07:46.726Z,1579975666.726 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T18:07:46.726Z,1579975666.726 [Default:CheckIn:C.Wait] Stopped
2020-01-25T18:07:46.726Z,1579975666.726 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:07:46.726Z,1579975666.726 [MissionManager](IMPORTANT): Started mission lineCaptureDepth
2020-01-25T18:07:46.727Z,1579975666.727 [lineCaptureDepth] Running Loop=1
2020-01-25T18:07:46.727Z,1579975666.727 [lineCaptureDepth](DEBUG): Aggregate::initialize lineCaptureDepth
2020-01-25T18:07:46.727Z,1579975666.727 [lineCaptureDepth:StandardEnvelopes] Running Loop=1
2020-01-25T18:07:46.727Z,1579975666.727 [lineCaptureDepth:StandardEnvelopes](DEBUG): Aggregate::initialize lineCaptureDepth:StandardEnvelopes
2020-01-25T18:07:46.727Z,1579975666.727 [lineCaptureDepth:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-01-25T18:07:46.727Z,1579975666.727 [lineCaptureDepth:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-01-25T18:07:46.727Z,1579975666.727 [lineCaptureDepth:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-01-25T18:07:46.727Z,1579975666.727 [lineCaptureDepth:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-01-25T18:07:46.727Z,1579975666.727 [lineCaptureDepth:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-01-25T18:07:46.728Z,1579975666.728 [lineCaptureDepth:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-01-25T18:07:46.728Z,1579975666.728 [lineCaptureDepth:I.] Running Loop=1
2020-01-25T18:07:46.728Z,1579975666.728 [lineCaptureDepth:I.](INFO): Initializing TrackAcousticContact.
2020-01-25T18:07:46.728Z,1579975666.728 [lineCaptureDepth:B] Running Loop=1
2020-01-25T18:07:46.729Z,1579975666.729 [lineCaptureDepth:I.] Running Loop=1
2020-01-25T18:07:46.729Z,1579975666.729 [lineCaptureDepth:B] Stopped
2020-01-25T18:07:46.729Z,1579975666.729 [lineCaptureDepth:C] Running Loop=1
2020-01-25T18:07:46.729Z,1579975666.729 [lineCaptureDepth:StandardEnvelopes] Running Loop=1
2020-01-25T18:07:46.729Z,1579975666.729 [lineCaptureDepth:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-01-25T18:07:46.758Z,1579975666.758 [lineCaptureDepth:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-01-25T18:07:46.758Z,1579975666.758 [lineCaptureDepth:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-01-25T18:07:47.119Z,1579975667.119 [lineCaptureDepth:C] Stopped
2020-01-25T18:07:47.119Z,1579975667.119 [lineCaptureDepth:D] Running Loop=1
2020-01-25T18:07:47.535Z,1579975667.535 [lineCaptureDepth:D] Stopped
2020-01-25T18:07:47.535Z,1579975667.535 [lineCaptureDepth:F] Running Loop=1
2020-01-25T18:07:47.933Z,1579975667.933 [lineCaptureDepth:F] Stopped
2020-01-25T18:07:47.933Z,1579975667.933 [lineCaptureDepth:G] Running Loop=1
2020-01-25T18:07:48.359Z,1579975668.359 [lineCaptureDepth:G] Stopped
2020-01-25T18:07:48.359Z,1579975668.359 [lineCaptureDepth:Lap] Running Loop=1
2020-01-25T18:07:48.359Z,1579975668.359 [lineCaptureDepth:Lap](DEBUG): Aggregate::initialize lineCaptureDepth:Lap
2020-01-25T18:07:48.360Z,1579975668.360 [lineCaptureDepth:Lap:A] Running Loop=1
2020-01-25T18:07:48.747Z,1579975668.747 [lineCaptureDepth:Lap:A] Stopped
2020-01-25T18:07:48.747Z,1579975668.747 [lineCaptureDepth:Lap:B] Running Loop=1
2020-01-25T18:07:48.747Z,1579975668.747 [lineCaptureDepth:Lap:B](DEBUG): Aggregate::initialize lineCaptureDepth:Lap:B
2020-01-25T18:07:49.138Z,1579975669.138 [lineCaptureDepth:LineCapture] Running Loop=1
2020-01-25T18:07:49.138Z,1579975669.138 [lineCaptureDepth:LineCapture](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:StandardEnvelopes] Running Loop=1
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:StandardEnvelopes](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:StandardEnvelopes
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:F] Running Loop=1
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:G] Running Loop=1
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:H.Mass] Running Loop=1
2020-01-25T18:07:49.139Z,1579975669.139 [lineCaptureDepth:LineCapture:H.Mass](DEBUG): Initialize.
2020-01-25T18:07:49.140Z,1579975669.140 [lineCaptureDepth:LineCapture:A] Running Loop=1
2020-01-25T18:07:49.140Z,1579975669.140 [lineCaptureDepth:LineCapture:H.Mass] Running Loop=1
2020-01-25T18:07:49.140Z,1579975669.140 [lineCaptureDepth:LineCapture:G] Running Loop=1
2020-01-25T18:07:49.141Z,1579975669.141 [lineCaptureDepth:LineCapture:F] Running Loop=1
2020-01-25T18:07:49.141Z,1579975669.141 [lineCaptureDepth:LineCapture:StandardEnvelopes] Running Loop=1
2020-01-25T18:07:49.141Z,1579975669.141 [lineCaptureDepth:LineCapture:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-01-25T18:07:49.149Z,1579975669.149 [lineCaptureDepth:LineCapture:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-01-25T18:07:49.149Z,1579975669.149 [lineCaptureDepth:LineCapture:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-01-25T18:07:49.149Z,1579975669.149 [lineCaptureDepth:LineCapture:A] Stopped
2020-01-25T18:07:49.149Z,1579975669.149 [lineCaptureDepth:LineCapture:C] Running Loop=1
2020-01-25T18:07:49.553Z,1579975669.553 [lineCaptureDepth:LineCapture:C] Stopped
2020-01-25T18:07:49.554Z,1579975669.554 [lineCaptureDepth:LineCapture:D] Running Loop=1
2020-01-25T18:07:49.562Z,1579975669.562 [lineCaptureDepth:I.](INFO): *** querying acoustic contact ***
2020-01-25T18:07:49.968Z,1579975669.968 [lineCaptureDepth:LineCapture:D] Stopped
2020-01-25T18:07:49.968Z,1579975669.968 [lineCaptureDepth:LineCapture:E] Running Loop=1
2020-01-25T18:07:50.327Z,1579975670.327 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:07:50.453Z,1579975670.453 [lineCaptureDepth:LineCapture:E] Stopped
2020-01-25T18:07:50.453Z,1579975670.453 [lineCaptureDepth:LineCapture:HomingSequence] Running Loop=1
2020-01-25T18:07:50.453Z,1579975670.453 [lineCaptureDepth:LineCapture:HomingSequence](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:HomingSequence
2020-01-25T18:07:50.453Z,1579975670.453 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock] Running Loop=1
2020-01-25T18:07:50.453Z,1579975670.453 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock
2020-01-25T18:07:50.453Z,1579975670.453 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide] Running Loop=1
2020-01-25T18:07:50.453Z,1579975670.453 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide
2020-01-25T18:07:50.458Z,1579975670.458 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock] Running Loop=1
2020-01-25T18:07:50.458Z,1579975670.458 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Initialize.
2020-01-25T18:07:50.458Z,1579975670.458 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance] Running Loop=1
2020-01-25T18:07:50.458Z,1579975670.458 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance
2020-01-25T18:07:50.458Z,1579975670.458 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance:guide] Running Loop=1
2020-01-25T18:07:50.458Z,1579975670.458 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance:guide](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance:guide
2020-01-25T18:07:50.459Z,1579975670.459 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance:guide:B] Running Loop=1
2020-01-25T18:07:50.459Z,1579975670.459 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance] Running Loop=1
2020-01-25T18:07:50.459Z,1579975670.459 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance
2020-01-25T18:07:50.459Z,1579975670.459 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide] Running Loop=1
2020-01-25T18:07:50.459Z,1579975670.459 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide
2020-01-25T18:07:50.459Z,1579975670.459 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide:C] Running Loop=1
2020-01-25T18:07:50.459Z,1579975670.459 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide:D] Running Loop=1
2020-01-25T18:07:50.460Z,1579975670.460 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide:A] Running Loop=1
2020-01-25T18:07:50.460Z,1579975670.460 [lineCaptureDepth:LineCapture:HomingSequence:A] Running Loop=1
2020-01-25T18:07:50.460Z,1579975670.460 [lineCaptureDepth:LineCapture:HomingSequence:A](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:HomingSequence:A
2020-01-25T18:07:50.715Z,1579975670.715 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance] Stopped
2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance
2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide] Stopped
2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide
2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide:A] Stopped
2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide:C] Stopped
2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:TerminalGuidance:guide:D] Stopped
2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance] Stopped
2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance
2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance:guide] Stopped
2020-01-25T18:07:50.807Z,1579975670.807 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance:guide](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance:guide
2020-01-25T18:07:50.808Z,1579975670.808 [lineCaptureDepth:LineCapture:HomingSequence:MidcourseGuidance:guide:B] Stopped
2020-01-25T18:07:50.808Z,1579975670.808 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock] Running Loop=1
2020-01-25T18:07:50.808Z,1579975670.808 [lineCaptureDepth:LineCapture:DiveCmd] Running Loop=1
2020-01-25T18:07:50.808Z,1579975670.808 [lineCaptureDepth:LineCapture:DiveCmd](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:DiveCmd
2020-01-25T18:07:50.808Z,1579975670.808 [lineCaptureDepth:LineCapture:DiveCmd:Guided] Running Loop=1
2020-01-25T18:07:50.808Z,1579975670.808 [lineCaptureDepth:LineCapture:DiveCmd:Guided](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:DiveCmd:Guided
2020-01-25T18:07:50.808Z,1579975670.808 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive] Running Loop=1
2020-01-25T18:07:50.808Z,1579975670.808 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:DiveCmd:Guided:dive
2020-01-25T18:07:50.809Z,1579975670.809 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:A] Running Loop=1
2020-01-25T18:07:50.809Z,1579975670.809 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:B.Buoyancy] Running Loop=1
2020-01-25T18:07:50.809Z,1579975670.809 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-01-25T18:07:50.809Z,1579975670.809 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:C.SetSpeed] Running Loop=1
2020-01-25T18:07:50.809Z,1579975670.809 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:C.SetSpeed](DEBUG): Initialize.
2020-01-25T18:07:50.809Z,1579975670.809 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:D.Pitch] Running Loop=1
2020-01-25T18:07:50.809Z,1579975670.809 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:D.Pitch](DEBUG): Initialize.
2020-01-25T18:07:50.809Z,1579975670.809 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock] Running Loop=1
2020-01-25T18:07:50.809Z,1579975670.809 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock
2020-01-25T18:07:50.809Z,1579975670.809 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive] Running Loop=1
2020-01-25T18:07:50.810Z,1579975670.810 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive](DEBUG): Aggregate::initialize lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive
2020-01-25T18:07:50.810Z,1579975670.810 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive:A.SetSpeed] Running Loop=1
2020-01-25T18:07:50.810Z,1579975670.810 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive:A.SetSpeed](DEBUG): Initialize.
2020-01-25T18:07:50.810Z,1579975670.810 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive:B.Pitch] Running Loop=1
2020-01-25T18:07:50.810Z,1579975670.810 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive:B.Pitch](DEBUG): Initialize.
2020-01-25T18:07:50.811Z,1579975670.811 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock] Running Loop=1
2020-01-25T18:07:50.811Z,1579975670.811 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive:A.SetSpeed] Running Loop=1
2020-01-25T18:07:50.812Z,1579975670.812 [lineCaptureDepth:LineCapture:DiveCmd:Guided] Stopped
2020-01-25T18:07:50.812Z,1579975670.812 [lineCaptureDepth:LineCapture:DiveCmd:Guided](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:DiveCmd:Guided
2020-01-25T18:07:50.812Z,1579975670.812 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive] Stopped
2020-01-25T18:07:50.812Z,1579975670.812 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:DiveCmd:Guided:dive
2020-01-25T18:07:50.812Z,1579975670.812 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:A] Stopped
2020-01-25T18:07:50.812Z,1579975670.812 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:B.Buoyancy] Stopped
2020-01-25T18:07:50.812Z,1579975670.812 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-01-25T18:07:50.812Z,1579975670.812 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:C.SetSpeed] Stopped
2020-01-25T18:07:50.812Z,1579975670.812 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:C.SetSpeed](DEBUG): Uninitialize.
2020-01-25T18:07:50.812Z,1579975670.812 [lineCaptureDepth:LineCapture:DiveCmd:Guided:dive:D.Pitch] Stopped
2020-01-25T18:07:51.135Z,1579975671.135 [PowerOnly](INFO): Powering up
2020-01-25T18:07:51.291Z,1579975671.291 [Reporter](INFO): acoustic_contact_range 29.040001 m
2020-01-25T18:07:51.647Z,1579975671.647 [Micromodem](INFO): Nmea in: $SNTTA,0.019455,,,,180751.47*45

2020-01-25T18:07:54.016Z,1579975674.016 [lineCaptureDepth:I.](INFO): *** querying acoustic contact ***
2020-01-25T18:07:54.462Z,1579975674.462 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](INFO): Detaching from dock.
2020-01-25T18:07:54.784Z,1579975674.784 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:07:54.891Z,1579975674.891 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](INFO): Detected possible detachment. Starting timer.
2020-01-25T18:07:55.193Z,1579975675.193 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:07:55.708Z,1579975675.708 [Reporter](INFO): acoustic_contact_range 30.059999 m
2020-01-25T18:07:56.019Z,1579975676.019 [Micromodem](INFO): Nmea in: $SNTTA,0.020199,,,,180755.93*47

2020-01-25T18:07:58.473Z,1579975678.473 [lineCaptureDepth:I.](INFO): *** querying acoustic contact ***
2020-01-25T18:07:59.232Z,1579975679.232 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:07:59.637Z,1579975679.637 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:08:00.124Z,1579975680.124 [Reporter](INFO): acoustic_contact_range 29.280003 m
2020-01-25T18:08:00.502Z,1579975680.502 [Micromodem](INFO): Nmea in: $SNTTA,0.019655,,,,180800.38*44

2020-01-25T18:08:02.922Z,1579975682.922 [lineCaptureDepth:I.](INFO): *** querying acoustic contact ***
2020-01-25T18:08:03.672Z,1579975683.672 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:08:04.074Z,1579975684.074 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:08:04.635Z,1579975684.635 [Reporter](INFO): acoustic_contact_range 30.059999 m
2020-01-25T18:08:04.910Z,1579975684.910 [Micromodem](INFO): Nmea in: $SNTTA,0.020120,,,,180804.82*4E

2020-01-25T18:08:07.378Z,1579975687.378 [lineCaptureDepth:I.](INFO): *** querying acoustic contact ***
2020-01-25T18:08:08.120Z,1579975688.120 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:08:08.525Z,1579975688.525 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:08:09.055Z,1579975689.055 [Reporter](INFO): acoustic_contact_range 30.600000 m
2020-01-25T18:08:09.375Z,1579975689.375 [Micromodem](INFO): Nmea in: $SNTTA,0.020504,,,,180809.27*4E

2020-01-25T18:08:10.091Z,1579975690.091 [DataOverHttps](IMPORTANT): SBD MTMSN=20200125T180800
2020-01-25T18:08:11.797Z,1579975691.797 [lineCaptureDepth:I.](INFO): *** querying acoustic contact ***
2020-01-25T18:08:12.568Z,1579975692.568 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:08:12.976Z,1579975692.976 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:08:13.549Z,1579975693.549 [Reporter](INFO): acoustic_contact_range 30.900002 m
2020-01-25T18:08:13.791Z,1579975693.791 [Micromodem](INFO): Nmea in: $SNTTA,0.020759,,,,180813.72*4F

2020-01-25T18:08:15.046Z,1579975695.046 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](INFO): Detached at range: 30.90 m. Transitioning DDM to standby.
2020-01-25T18:08:16.279Z,1579975696.279 [lineCaptureDepth:I.](INFO): *** querying acoustic contact ***
2020-01-25T18:08:17.008Z,1579975697.008 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:08:17.425Z,1579975697.425 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:08:18.264Z,1579975698.264 [Micromodem](INFO): Nmea in: $SNTTA,0.020656,,,,180818.16*48

2020-01-25T18:08:18.341Z,1579975698.341 [DataOverHttps](INFO): Received command:stop
2020-01-25T18:08:18.364Z,1579975698.364 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](INFO): DDM at standby.
2020-01-25T18:08:18.394Z,1579975698.394 [CommandLine](IMPORTANT): got command stop
2020-01-25T18:08:18.394Z,1579975698.394 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-01-25T18:08:18.652Z,1579975698.652 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-25T18:08:18.652Z,1579975698.652 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012511211528,35.0, -0.1,   0.0  0
2020-01-25T18:08:18.668Z,1579975698.668 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](IMPORTANT): Undocking sequence complete.
2020-01-25T18:08:18.668Z,1579975698.668 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock] Stopped
2020-01-25T18:08:18.668Z,1579975698.668 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:B] Running Loop=1
2020-01-25T18:08:18.671Z,1579975698.671 [MissionManager](INFO): MissionManager is completed.
2020-01-25T18:08:18.672Z,1579975698.672 [MissionManager](INFO): Uninitializing Mission lineCaptureDepth
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth] Stopped
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth](DEBUG): Aggregate::uninitialize lineCaptureDepth
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth:StandardEnvelopes] Stopped
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth:StandardEnvelopes](DEBUG): Aggregate::uninitialize lineCaptureDepth:StandardEnvelopes
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth:I.] Stopped
2020-01-25T18:08:18.672Z,1579975698.672 [lineCaptureDepth:LineCapture] Stopped
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:StandardEnvelopes] Stopped
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:StandardEnvelopes](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:StandardEnvelopes
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:F] Stopped
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:G] Stopped
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:H.Mass] Stopped
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:H.Mass](DEBUG): Uninitialize.
2020-01-25T18:08:18.673Z,1579975698.673 [lineCaptureDepth:LineCapture:DiveCmd] Stopped
2020-01-25T18:08:18.715Z,1579975698.715 [lineCaptureDepth:LineCapture:DiveCmd](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:DiveCmd
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock] Stopped
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive] Stopped
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive:A.SetSpeed] Stopped
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive:A.SetSpeed](DEBUG): Uninitialize.
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:DiveCmd:DriftAwayFromDock:dive:B.Pitch] Stopped
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:HomingSequence] Stopped
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:HomingSequence](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:HomingSequence
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:HomingSequence:A] Stopped
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:HomingSequence:A](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:HomingSequence:A
2020-01-25T18:08:18.716Z,1579975698.716 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock] Stopped
2020-01-25T18:08:18.717Z,1579975698.717 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock
2020-01-25T18:08:18.717Z,1579975698.717 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide] Stopped
2020-01-25T18:08:18.717Z,1579975698.717 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide](DEBUG): Aggregate::uninitialize lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide
2020-01-25T18:08:18.717Z,1579975698.717 [lineCaptureDepth:LineCapture:HomingSequence:DetachFromDock:guide:B] Stopped
2020-01-25T18:08:18.717Z,1579975698.717 [lineCaptureDepth:Lap] Stopped
2020-01-25T18:08:18.717Z,1579975698.717 [lineCaptureDepth:Lap](DEBUG): Aggregate::uninitialize lineCaptureDepth:Lap
2020-01-25T18:08:18.717Z,1579975698.717 [lineCaptureDepth:Lap:B] Stopped
2020-01-25T18:08:18.717Z,1579975698.717 [lineCaptureDepth:Lap:B](DEBUG): Aggregate::uninitialize lineCaptureDepth:Lap:B
2020-01-25T18:08:19.060Z,1579975699.060 [MissionManager](IMPORTANT): Started mission Default
2020-01-25T18:08:19.060Z,1579975699.060 [Default] Running Loop=1
2020-01-25T18:08:19.060Z,1579975699.060 [Default](DEBUG): Aggregate::initialize Default
2020-01-25T18:08:19.060Z,1579975699.060 [Default:B.GoToSurface] Running Loop=1
2020-01-25T18:08:19.061Z,1579975699.061 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-25T18:08:19.061Z,1579975699.061 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-25T18:08:19.061Z,1579975699.061 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-25T18:08:19.061Z,1579975699.061 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-25T18:08:19.062Z,1579975699.062 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-25T18:08:19.062Z,1579975699.062 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-25T18:08:19.062Z,1579975699.062 [Default:A.Wait] Running Loop=1
2020-01-25T18:08:19.063Z,1579975699.063 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:08:31.553Z,1579975711.553 [RDI_Pathfinder](ERROR): Failed to parse:
:  +14.29,      +738.48,      -22.06,  29.38,  0.00
2020-01-25T18:08:32.393Z,1579975712.393 [Default:A.Wait](INFO): Done Waiting.
2020-01-25T18:08:32.393Z,1579975712.393 [Default:A.Wait] Stopped
2020-01-25T18:08:32.393Z,1579975712.393 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:08:32.794Z,1579975712.794 [Default:CheckIn] Running Loop=1
2020-01-25T18:08:32.794Z,1579975712.794 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-25T18:08:32.794Z,1579975712.794 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-25T18:08:34.773Z,1579975714.773 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180826.00,A,3648.62795,N,12148.65308,W,0.505,291.78,250120,,,D*7D
2020-01-25T18:08:34.784Z,1579975714.784 [NAL9602](INFO): GPS fix at 20200125T180826: (36.810466, -121.810885)
2020-01-25T18:08:34.855Z,1579975714.855 [Default:CheckIn:Read_GPS] Stopped
2020-01-25T18:08:34.855Z,1579975714.855 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-25T18:08:43.501Z,1579975723.501 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200125T175407/Courier0018.lzma
2020-01-25T18:08:45.507Z,1579975725.507 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Courier0018.lzma.bak
2020-01-25T18:08:45.508Z,1579975725.508 [DataOverHttps](INFO): SBD MOMSN=12203643
2020-01-25T18:08:46.307Z,1579975726.307 [NAL9602](INFO): SBD MO Status=0, MOMSN=10266, MT Status=0, MTMSN=0
2020-01-25T18:08:46.307Z,1579975726.307 [NAL9602](INFO): No messages in MT queue
2020-01-25T18:09:02.922Z,1579975742.922 [DataOverHttps](INFO): Sending 668 bytes from file Logs/20200125T175407/Express0019.lzma
2020-01-25T18:09:04.927Z,1579975744.927 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0019.lzma.bak
2020-01-25T18:09:04.928Z,1579975744.928 [DataOverHttps](INFO): SBD MOMSN=12203647
2020-01-25T18:09:06.112Z,1579975746.112 [Default:CheckIn:Read_Iridium] Stopped
2020-01-25T18:09:06.113Z,1579975746.113 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-25T18:09:06.113Z,1579975746.113 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:09:16.986Z,1579975756.986 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-25T18:09:22.656Z,1579975762.656 [PowerOnly](INFO): Powering down
2020-01-25T18:14:06.712Z,1579976046.712 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-25T18:14:06.712Z,1579976046.712 [Default:CheckIn:C.Wait] Stopped
2020-01-25T18:14:06.713Z,1579976046.713 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:14:06.713Z,1579976046.713 [Default:CheckIn:D] Running Loop=1
2020-01-25T18:14:07.132Z,1579976047.132 [Default:CheckIn:D] Stopped
2020-01-25T18:14:07.132Z,1579976047.132 [Default:CheckIn:E] Running Loop=1
2020-01-25T18:14:07.527Z,1579976047.527 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.800821 min 
2020-01-25T18:14:07.527Z,1579976047.527 [Default:CheckIn:E] Stopped
2020-01-25T18:14:07.527Z,1579976047.527 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-25T18:14:07.527Z,1579976047.527 [Default:CheckIn] Stopped
2020-01-25T18:14:07.527Z,1579976047.527 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T18:14:07.527Z,1579976047.527 [Default:CheckIn](INFO): Running loop #2
2020-01-25T18:14:07.527Z,1579976047.527 [Default:CheckIn] Running Loop=2
2020-01-25T18:14:07.528Z,1579976047.528 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-25T18:14:07.528Z,1579976047.528 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-25T18:14:09.493Z,1579976049.493 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181400.00,A,3648.64629,N,12148.66437,W,0.661,299.58,250120,,,A*72
2020-01-25T18:14:09.503Z,1579976049.503 [NAL9602](INFO): GPS fix at 20200125T181400: (36.810772, -121.811073)
2020-01-25T18:14:09.537Z,1579976049.537 [Default:CheckIn:Read_GPS] Stopped
2020-01-25T18:14:09.537Z,1579976049.537 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-25T18:14:17.605Z,1579976057.605 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200125T175407/Courier0021.lzma
2020-01-25T18:14:19.611Z,1579976059.611 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Courier0021.lzma.bak
2020-01-25T18:14:19.611Z,1579976059.611 [DataOverHttps](INFO): SBD MOMSN=12203675
2020-01-25T18:14:27.341Z,1579976067.341 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-25T18:14:27.341Z,1579976067.341 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,       +13.21699.40,      -23.28,  30.07,  0.00
2020-01-25T18:14:40.626Z,1579976080.626 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200125T175407/Express0023.lzma
2020-01-25T18:14:42.631Z,1579976082.631 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0023.lzma.bak
2020-01-25T18:14:42.631Z,1579976082.631 [DataOverHttps](INFO): SBD MOMSN=12203678
2020-01-25T18:14:43.886Z,1579976083.886 [Default:CheckIn:Read_Iridium] Stopped
2020-01-25T18:14:43.887Z,1579976083.887 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-25T18:14:43.887Z,1579976083.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:14:46.704Z,1579976086.704 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-25T18:14:46.704Z,1579976086.704 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   -88,   -0,A
2020-01-25T18:14:50.734Z,1579976090.734 [CommandLine](IMPORTANT): got command report 
2020-01-25T18:14:50.734Z,1579976090.734 [Reporter](INFO): acoustic_contact_range reporting changes
2020-01-25T18:15:01.221Z,1579976101.221 [NAL9602](INFO): SBD MO Status=0, MOMSN=10267, MT Status=0, MTMSN=0
2020-01-25T18:15:01.221Z,1579976101.221 [NAL9602](INFO): No messages in MT queue
2020-01-25T18:15:27.587Z,1579976127.587 [DataOverHttps](IMPORTANT): SBD MTMSN=20200125T181517
2020-01-25T18:15:31.924Z,1579976131.924 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-25T18:15:35.561Z,1579976135.561 [DataOverHttps](INFO): Received command:run Maintenance/DUSBL.xml
2020-01-25T18:15:35.616Z,1579976135.616 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2020-01-25T18:15:35.616Z,1579976135.616 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2020-01-25T18:15:35.679Z,1579976135.679 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2020-01-25T18:15:35.681Z,1579976135.681 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2020-01-25T18:15:35.687Z,1579976135.687 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2020-01-25T18:15:35.689Z,1579976135.689 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2020-01-25T18:15:35.715Z,1579976135.715 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2020-01-25T18:15:35.716Z,1579976135.716 [DUSBL:A.Pitch](DEBUG): Construct.
2020-01-25T18:15:35.728Z,1579976135.728 [DUSBL:B.SetSpeed](DEBUG): Construct.
2020-01-25T18:15:35.752Z,1579976135.752 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2020-01-25T18:15:35.768Z,1579976135.768 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="NumberOfRequests"><Description>
        Number of pings to requst.
    </Description><Units:count/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>   

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>      
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Sensor:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <ReadData>
        <When>
            <Arg Name="EnabledDUSBL"/>
        </When>
            <Universal:acoustic_contact_range/>
            <Universal:acoustic_contact_direction_vehicle_frame/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

        <Sequence Repeat="NumberOfRequests"/>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.transponderCode/><Arg Name="TransponderCode"/></Assign>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.pingRequested/><Arg Name="NumberOfPings"/></Assign>
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:second/><Value>90</Value></Setting>
          </Guidance:Wait>
      </Aggregate>    
</Mission>


2020-01-25T18:15:35.778Z,1579976135.778 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2020-01-25T18:15:36.006Z,1579976136.006 [Default] Stopped
2020-01-25T18:15:36.007Z,1579976136.007 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-25T18:15:36.007Z,1579976136.007 [Default:B.GoToSurface] Stopped
2020-01-25T18:15:36.007Z,1579976136.007 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-25T18:15:36.007Z,1579976136.007 [Default:CheckIn] Stopped
2020-01-25T18:15:36.007Z,1579976136.007 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T18:15:36.007Z,1579976136.007 [Default:CheckIn:C.Wait] Stopped
2020-01-25T18:15:36.007Z,1579976136.007 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:15:36.007Z,1579976136.007 [MissionManager](IMPORTANT): Started mission DUSBL
2020-01-25T18:15:36.007Z,1579976136.007 [DUSBL] Running Loop=1
2020-01-25T18:15:36.008Z,1579976136.008 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2020-01-25T18:15:36.008Z,1579976136.008 [DUSBL:A.Pitch] Running Loop=1
2020-01-25T18:15:36.008Z,1579976136.008 [DUSBL:A.Pitch](DEBUG): Initialize.
2020-01-25T18:15:36.008Z,1579976136.008 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-25T18:15:36.008Z,1579976136.008 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2020-01-25T18:15:36.008Z,1579976136.008 [DUSBL:C] Running Loop=1
2020-01-25T18:15:36.008Z,1579976136.008 [DUSBL:RequestRepeater] Running Loop=1
2020-01-25T18:15:36.008Z,1579976136.008 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2020-01-25T18:15:36.008Z,1579976136.008 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-25T18:15:36.008Z,1579976136.008 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-25T18:15:36.009Z,1579976136.009 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2020-01-25T18:15:36.009Z,1579976136.009 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:15:36.009Z,1579976136.009 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-25T18:15:36.009Z,1579976136.009 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-25T18:15:36.054Z,1579976136.054 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_range
2020-01-25T18:15:36.054Z,1579976136.054 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_direction_vehicle_frame
2020-01-25T18:15:36.055Z,1579976136.055 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-25T18:15:36.055Z,1579976136.055 [DUSBL:A.Pitch] Running Loop=1
2020-01-25T18:15:37.986Z,1579976137.986 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:15:38.392Z,1579976138.392 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:15:39.210Z,1579976139.210 [Micromodem](INFO): Nmea in: $SNTTA,0.051959,,,,181539.13*44

2020-01-25T18:15:41.619Z,1579976141.619 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:15:42.427Z,1579976142.427 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:15:42.832Z,1579976142.832 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:15:43.326Z,1579976143.326 [Reporter](INFO): acoustic_contact_range 83.040009 m
2020-01-25T18:15:43.649Z,1579976143.649 [Micromodem](INFO): Nmea in: $SNTTA,0.051376,,,,181543.57*4E

2020-01-25T18:15:46.472Z,1579976146.472 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:15:46.872Z,1579976146.872 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:15:47.364Z,1579976147.364 [Reporter](INFO): acoustic_contact_range 77.460007 m
2020-01-25T18:15:47.687Z,1579976147.687 [Micromodem](INFO): Nmea in: $SNTTA,0.051825,,,,181547.62*41

2020-01-25T18:15:50.512Z,1579976150.512 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:15:50.913Z,1579976150.913 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:15:51.387Z,1579976151.387 [Reporter](INFO): acoustic_contact_range 78.180000 m
2020-01-25T18:15:51.736Z,1579976151.736 [Micromodem](INFO): Nmea in: $SNTTA,0.052297,,,,181551.66*42

2020-01-25T18:15:54.552Z,1579976154.552 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:15:54.957Z,1579976154.957 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:15:55.417Z,1579976155.417 [Reporter](INFO): acoustic_contact_range 78.000000 m
2020-01-25T18:15:55.773Z,1579976155.773 [Micromodem](INFO): Nmea in: $SNTTA,0.052167,,,,181555.70*4D

2020-01-25T18:15:58.592Z,1579976158.592 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:15:58.997Z,1579976158.997 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:15:59.452Z,1579976159.452 [Reporter](INFO): acoustic_contact_range 78.480003 m
2020-01-25T18:15:59.807Z,1579976159.807 [Micromodem](INFO): Nmea in: $SNTTA,0.052508,,,,181559.74*48

2020-01-25T18:16:02.632Z,1579976162.632 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:03.031Z,1579976163.031 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:03.530Z,1579976163.530 [Reporter](INFO): acoustic_contact_range 79.379997 m
2020-01-25T18:16:03.847Z,1579976163.847 [Micromodem](INFO): Nmea in: $SNTTA,0.053057,,,,181603.78*46

2020-01-25T18:16:06.672Z,1579976166.672 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:07.073Z,1579976167.073 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:07.576Z,1579976167.576 [Reporter](INFO): acoustic_contact_range 78.779999 m
2020-01-25T18:16:07.890Z,1579976167.890 [Micromodem](INFO): Nmea in: $SNTTA,0.052663,,,,181607.82*47

2020-01-25T18:16:10.726Z,1579976170.726 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:11.112Z,1579976171.112 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:11.589Z,1579976171.589 [Reporter](INFO): acoustic_contact_range 79.799995 m
2020-01-25T18:16:11.932Z,1579976171.932 [Micromodem](INFO): Nmea in: $SNTTA,0.053340,,,,181611.87*40

2020-01-25T18:16:14.752Z,1579976174.752 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:15.157Z,1579976175.157 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:15.628Z,1579976175.628 [Reporter](INFO): acoustic_contact_range 80.100006 m
2020-01-25T18:16:15.971Z,1579976175.971 [Micromodem](INFO): Nmea in: $SNTTA,0.053556,,,,181615.90*43

2020-01-25T18:16:18.792Z,1579976178.792 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:19.192Z,1579976179.192 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:19.672Z,1579976179.672 [Reporter](INFO): acoustic_contact_range 80.160004 m
2020-01-25T18:16:20.009Z,1579976180.009 [Micromodem](INFO): Nmea in: $SNTTA,0.053599,,,,181619.94*48

2020-01-25T18:16:22.832Z,1579976182.832 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:23.232Z,1579976183.232 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:23.700Z,1579976183.700 [Reporter](INFO): acoustic_contact_range 80.880005 m
2020-01-25T18:16:24.055Z,1579976184.055 [Micromodem](INFO): Nmea in: $SNTTA,0.054085,,,,181623.98*42

2020-01-25T18:16:26.869Z,1579976186.869 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:27.308Z,1579976187.308 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:27.729Z,1579976187.729 [Reporter](INFO): acoustic_contact_range 81.480003 m
2020-01-25T18:16:28.087Z,1579976188.087 [Micromodem](INFO): Nmea in: $SNTTA,0.054468,,,,181628.02*4D

2020-01-25T18:16:30.906Z,1579976190.906 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:31.321Z,1579976191.321 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:31.775Z,1579976191.775 [Reporter](INFO): acoustic_contact_range 81.900002 m
2020-01-25T18:16:32.129Z,1579976192.129 [Micromodem](INFO): Nmea in: $SNTTA,0.054736,,,,181632.05*49

2020-01-25T18:16:34.948Z,1579976194.948 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:35.357Z,1579976195.357 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:35.808Z,1579976195.808 [Reporter](INFO): acoustic_contact_range 82.739998 m
2020-01-25T18:16:36.196Z,1579976196.196 [Micromodem](INFO): Nmea in: $SNTTA,0.055298,,,,181636.09*41

2020-01-25T18:16:38.992Z,1579976198.992 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:39.397Z,1579976199.397 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:39.840Z,1579976199.840 [Reporter](INFO): acoustic_contact_range 88.800003 m
2020-01-25T18:16:40.221Z,1579976200.221 [Micromodem](INFO): Nmea in: $SNTTA,0.055577,,,,181640.14*4A

2020-01-25T18:16:43.028Z,1579976203.028 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:43.433Z,1579976203.433 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:43.934Z,1579976203.934 [Reporter](INFO): acoustic_contact_range 83.519997 m
2020-01-25T18:16:44.249Z,1579976204.249 [Micromodem](INFO): Nmea in: $SNTTA,0.055824,,,,181644.17*46

2020-01-25T18:16:47.075Z,1579976207.075 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:16:47.477Z,1579976207.477 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:16:47.931Z,1579976207.931 [Reporter](INFO): acoustic_contact_range 83.640007 m
2020-01-25T18:16:48.308Z,1579976208.308 [Micromodem](INFO): Nmea in: $SNTTA,0.055950,,,,181648.22*4E

2020-01-25T18:16:50.070Z,1579976210.070 [CommandLine](IMPORTANT): got command stop
2020-01-25T18:16:50.070Z,1579976210.070 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-01-25T18:16:50.381Z,1579976210.381 [MissionManager](INFO): MissionManager is completed.
2020-01-25T18:16:50.381Z,1579976210.381 [MissionManager](INFO): Uninitializing Mission DUSBL
2020-01-25T18:16:50.381Z,1579976210.381 [DUSBL] Stopped
2020-01-25T18:16:50.381Z,1579976210.381 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2020-01-25T18:16:50.381Z,1579976210.381 [DUSBL:A.Pitch] Stopped
2020-01-25T18:16:50.381Z,1579976210.381 [DUSBL:B.SetSpeed] Stopped
2020-01-25T18:16:50.381Z,1579976210.381 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2020-01-25T18:16:50.381Z,1579976210.381 [DUSBL:C] Stopped
2020-01-25T18:16:50.381Z,1579976210.381 [DUSBL:RequestRepeater] Stopped
2020-01-25T18:16:50.382Z,1579976210.382 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2020-01-25T18:16:50.382Z,1579976210.382 [DUSBL:RequestRepeater:A] Stopped
2020-01-25T18:16:50.382Z,1579976210.382 [DUSBL:RequestRepeater:B] Stopped
2020-01-25T18:16:50.382Z,1579976210.382 [DUSBL:RequestRepeater:C.Wait] Stopped
2020-01-25T18:16:50.382Z,1579976210.382 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:16:50.741Z,1579976210.741 [MissionManager](IMPORTANT): Started mission Default
2020-01-25T18:16:50.741Z,1579976210.741 [Default] Running Loop=1
2020-01-25T18:16:50.742Z,1579976210.742 [Default](DEBUG): Aggregate::initialize Default
2020-01-25T18:16:50.742Z,1579976210.742 [Default:B.GoToSurface] Running Loop=1
2020-01-25T18:16:50.742Z,1579976210.742 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-25T18:16:50.742Z,1579976210.742 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-25T18:16:50.742Z,1579976210.742 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-25T18:16:50.743Z,1579976210.743 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-25T18:16:50.743Z,1579976210.743 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-25T18:16:50.743Z,1579976210.743 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-25T18:16:50.744Z,1579976210.744 [Default:A.Wait] Running Loop=1
2020-01-25T18:16:50.744Z,1579976210.744 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:17:04.131Z,1579976224.131 [Default:A.Wait](INFO): Done Waiting.
2020-01-25T18:17:04.131Z,1579976224.131 [Default:A.Wait] Stopped
2020-01-25T18:17:04.131Z,1579976224.131 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:17:04.557Z,1579976224.557 [Default:CheckIn] Running Loop=1
2020-01-25T18:17:04.557Z,1579976224.557 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-25T18:17:04.557Z,1579976224.557 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-25T18:17:06.460Z,1579976226.460 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181657.00,A,3648.65528,N,12148.66881,W,0.408,328.75,250120,,,A*79
2020-01-25T18:17:06.462Z,1579976226.462 [NAL9602](INFO): GPS fix at 20200125T181657: (36.810921, -121.811147)
2020-01-25T18:17:06.510Z,1579976226.510 [Default:CheckIn:Read_GPS] Stopped
2020-01-25T18:17:06.510Z,1579976226.510 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-25T18:17:07.704Z,1579976227.704 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-25T18:17:07.704Z,1579976227.704 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 297.57, 284.90, 298.21
2020-01-25T18:17:14.893Z,1579976234.893 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200125T175407/Courier0027.lzma
2020-01-25T18:17:15.353Z,1579976235.353 [NAL9602](INFO): SBD MO Status=0, MOMSN=10268, MT Status=0, MTMSN=0
2020-01-25T18:17:15.353Z,1579976235.353 [NAL9602](INFO): No messages in MT queue
2020-01-25T18:17:16.899Z,1579976236.899 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Courier0027.lzma.bak
2020-01-25T18:17:16.899Z,1579976236.899 [DataOverHttps](INFO): SBD MOMSN=12203685
2020-01-25T18:17:34.458Z,1579976254.458 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20200125T175407/Express0028.lzma
2020-01-25T18:17:36.463Z,1579976256.463 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0028.lzma.bak
2020-01-25T18:17:36.464Z,1579976256.464 [DataOverHttps](INFO): SBD MOMSN=12203688
2020-01-25T18:17:46.049Z,1579976266.049 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-25T18:17:53.905Z,1579976273.905 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200125T175407/Express0031.lzma
2020-01-25T18:17:55.912Z,1579976275.912 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0031.lzma.bak
2020-01-25T18:17:55.912Z,1579976275.912 [DataOverHttps](INFO): SBD MOMSN=12203697
2020-01-25T18:17:57.408Z,1579976277.408 [Default:CheckIn:Read_Iridium] Stopped
2020-01-25T18:17:57.408Z,1579976277.408 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-25T18:17:57.409Z,1579976277.409 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:18:25.666Z,1579976305.666 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 302.21, 275.59, 274.26, 300.88
2020-01-25T18:19:28.320Z,1579976368.320 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,  +287,   -23,  +191,A
2020-01-25T18:20:08.394Z,1579976408.394 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-25T18:20:08.394Z,1579976408.394 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-25T18:20:08.394Z,1579976408.394 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-25T18:20:08.395Z,1579976408.395 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-25T18:20:08.454Z,1579976408.454 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-25T18:20:08.746Z,1579976408.746 [RDI_Pathfinder](INFO): Powering down
2020-01-25T18:20:09.509Z,1579976409.509 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-25T18:20:09.509Z,1579976409.509 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-25T18:20:22.819Z,1579976422.819 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-25T18:22:06.266Z,1579976526.266 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-25T18:22:06.266Z,1579976526.266 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012511350285,35.0, -0.1,   0.0,1448.9A,  0.00, 287.57, 283.57, 276.92, 294.22
2020-01-25T18:22:58.012Z,1579976578.012 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-25T18:22:58.012Z,1579976578.012 [Default:CheckIn:C.Wait] Stopped
2020-01-25T18:22:58.012Z,1579976578.012 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:22:58.012Z,1579976578.012 [Default:CheckIn:D] Running Loop=1
2020-01-25T18:22:58.391Z,1579976578.391 [Default:CheckIn:D] Stopped
2020-01-25T18:22:58.391Z,1579976578.391 [Default:CheckIn:E] Running Loop=1
2020-01-25T18:22:58.791Z,1579976578.791 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.127495 min 
2020-01-25T18:22:58.791Z,1579976578.791 [Default:CheckIn:E] Stopped
2020-01-25T18:22:58.791Z,1579976578.791 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-25T18:22:58.791Z,1579976578.791 [Default:CheckIn] Stopped
2020-01-25T18:22:58.792Z,1579976578.792 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T18:22:58.792Z,1579976578.792 [Default:CheckIn](INFO): Running loop #2
2020-01-25T18:22:58.792Z,1579976578.792 [Default:CheckIn] Running Loop=2
2020-01-25T18:22:58.792Z,1579976578.792 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-25T18:22:58.792Z,1579976578.792 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-25T18:23:00.782Z,1579976580.782 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182252.00,A,3648.67259,N,12148.68497,W,0.505,351.15,250120,,,D*7C
2020-01-25T18:23:00.784Z,1579976580.784 [NAL9602](INFO): GPS fix at 20200125T182252: (36.811210, -121.811416)
2020-01-25T18:23:00.842Z,1579976580.842 [Default:CheckIn:Read_GPS] Stopped
2020-01-25T18:23:00.842Z,1579976580.842 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-25T18:23:08.505Z,1579976588.505 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200125T175407/Courier0033.lzma
2020-01-25T18:23:10.511Z,1579976590.511 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Courier0033.lzma.bak
2020-01-25T18:23:10.511Z,1579976590.511 [DataOverHttps](INFO): SBD MOMSN=12203701
2020-01-25T18:23:23.041Z,1579976603.041 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 318.18, 283.57, 295.55, 299.54
2020-01-25T18:23:27.949Z,1579976607.949 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200125T175407/Express0034.lzma
2020-01-25T18:23:29.956Z,1579976609.956 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0034.lzma.bak
2020-01-25T18:23:29.956Z,1579976609.956 [DataOverHttps](INFO): SBD MOMSN=12203704
2020-01-25T18:23:31.125Z,1579976611.125 [Default:CheckIn:Read_Iridium] Stopped
2020-01-25T18:23:31.125Z,1579976611.125 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-25T18:23:31.125Z,1579976611.125 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:23:43.201Z,1579976623.201 [NAL9602](INFO): SBD MO Status=0, MOMSN=10269, MT Status=0, MTMSN=0
2020-01-25T18:23:43.202Z,1579976623.202 [NAL9602](INFO): No messages in MT queue
2020-01-25T18:24:13.899Z,1579976653.899 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-25T18:25:08.893Z,1579976708.893 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-25T18:25:08.893Z,1579976708.893 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +2.52,      -35.5 -0.61,  28.02,  0.00
2020-01-25T18:25:17.870Z,1579976717.870 [CommandLine](IMPORTANT): got command show variable DUSBL_hydroid.verb
2020-01-25T18:25:17.952Z,1579976717.952 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2020-01-25T18:25:26.463Z,1579976726.463 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.verbosity
2020-01-25T18:25:26.463Z,1579976726.463 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity 0 enum
2020-01-25T18:25:35.019Z,1579976735.019 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.verbosity 3.000000 enum persist
2020-01-25T18:26:07.879Z,1579976767.879 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-25T18:26:07.879Z,1579976767.879 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012511390485,35.0  -0.1,   0.0,1448.9,  0
2020-01-25T18:26:28.883Z,1579976788.883 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-25T18:26:28.883Z,1579976788.883 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 318.12, 286.24, 303.54
2020-01-25T18:26:44.319Z,1579976804.319 [DataOverHttps](IMPORTANT): SBD MTMSN=20200125T182634
2020-01-25T18:26:52.277Z,1579976812.277 [DataOverHttps](INFO): Received command:run Maintenance/DUSBL.xml
2020-01-25T18:26:52.323Z,1579976812.323 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2020-01-25T18:26:52.324Z,1579976812.324 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2020-01-25T18:26:52.383Z,1579976812.383 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2020-01-25T18:26:52.385Z,1579976812.385 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2020-01-25T18:26:52.387Z,1579976812.387 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2020-01-25T18:26:52.389Z,1579976812.389 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2020-01-25T18:26:52.395Z,1579976812.395 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2020-01-25T18:26:52.396Z,1579976812.396 [DUSBL:A.Pitch](DEBUG): Construct.
2020-01-25T18:26:52.430Z,1579976812.430 [DUSBL:B.SetSpeed](DEBUG): Construct.
2020-01-25T18:26:52.446Z,1579976812.446 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2020-01-25T18:26:52.470Z,1579976812.470 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="NumberOfRequests"><Description>
        Number of pings to requst.
    </Description><Units:count/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>   

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>      
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Sensor:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <ReadData>
        <When>
            <Arg Name="EnabledDUSBL"/>
        </When>
            <Universal:acoustic_contact_range/>
            <Universal:acoustic_contact_direction_vehicle_frame/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

        <Sequence Repeat="NumberOfRequests"/>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.transponderCode/><Arg Name="TransponderCode"/></Assign>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.pingRequested/><Arg Name="NumberOfPings"/></Assign>
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:second/><Value>90</Value></Setting>
          </Guidance:Wait>
      </Aggregate>    
</Mission>


2020-01-25T18:26:52.472Z,1579976812.472 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2020-01-25T18:26:52.749Z,1579976812.749 [Default] Stopped
2020-01-25T18:26:52.749Z,1579976812.749 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-25T18:26:52.749Z,1579976812.749 [Default:B.GoToSurface] Stopped
2020-01-25T18:26:52.749Z,1579976812.749 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-25T18:26:52.749Z,1579976812.749 [Default:CheckIn] Stopped
2020-01-25T18:26:52.749Z,1579976812.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T18:26:52.749Z,1579976812.749 [Default:CheckIn:C.Wait] Stopped
2020-01-25T18:26:52.749Z,1579976812.749 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:26:52.749Z,1579976812.749 [MissionManager](IMPORTANT): Started mission DUSBL
2020-01-25T18:26:52.750Z,1579976812.750 [DUSBL] Running Loop=1
2020-01-25T18:26:52.750Z,1579976812.750 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2020-01-25T18:26:52.750Z,1579976812.750 [DUSBL:A.Pitch] Running Loop=1
2020-01-25T18:26:52.750Z,1579976812.750 [DUSBL:A.Pitch](DEBUG): Initialize.
2020-01-25T18:26:52.750Z,1579976812.750 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-25T18:26:52.750Z,1579976812.750 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2020-01-25T18:26:52.750Z,1579976812.750 [DUSBL:C] Running Loop=1
2020-01-25T18:26:52.751Z,1579976812.751 [DUSBL:RequestRepeater] Running Loop=1
2020-01-25T18:26:52.751Z,1579976812.751 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2020-01-25T18:26:52.751Z,1579976812.751 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-25T18:26:52.751Z,1579976812.751 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-25T18:26:52.751Z,1579976812.751 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2020-01-25T18:26:52.751Z,1579976812.751 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:26:52.751Z,1579976812.751 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-25T18:26:52.752Z,1579976812.752 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-25T18:26:52.752Z,1579976812.752 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_range
2020-01-25T18:26:52.753Z,1579976812.753 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_direction_vehicle_frame
2020-01-25T18:26:52.753Z,1579976812.753 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-25T18:26:52.753Z,1579976812.753 [DUSBL:A.Pitch] Running Loop=1
2020-01-25T18:26:54.327Z,1579976814.327 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:26:54.327Z,1579976814.327 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:26:54.327Z,1579976814.327 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:26:54.328Z,1579976814.328 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:26:54.735Z,1579976814.735 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
F7B,000,007E3,186,80,80,E5,FF
2020-01-25T18:26:54.735Z,1579976814.735 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:26:54.735Z,1579976814.735 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:26:54.737Z,1579976814.737 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:26:55.147Z,1579976815.147 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:26:55.950Z,1579976815.950 [Micromodem](INFO): Nmea in: $SNTTA,,,,,182655.89*52

2020-01-25T18:26:58.377Z,1579976818.377 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,E4
3,186,80,80,E5,FF
2020-01-25T18:26:58.377Z,1579976818.377 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,E4
3,186,80,80,E5,FF
2020-01-25T18:26:58.377Z,1579976818.377 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:26:58.775Z,1579976818.775 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:26:58.775Z,1579976818.775 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:26:58.775Z,1579976818.775 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:26:58.776Z,1579976818.776 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:26:59.179Z,1579976819.179 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,E4
3,186,80,80,E5,FF
2020-01-25T18:26:59.179Z,1579976819.179 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:26:59.179Z,1579976819.179 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:26:59.181Z,1579976819.181 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:26:59.582Z,1579976819.582 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:00.394Z,1579976820.394 [Micromodem](INFO): Nmea in: $SNTTA,,,,,182700.33*52

2020-01-25T18:27:02.810Z,1579976822.810 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,E4
3,186,80,80,E5,FF
2020-01-25T18:27:02.811Z,1579976822.811 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,E4
3,186,80,80,E5,FF
2020-01-25T18:27:02.811Z,1579976822.811 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:27:03.225Z,1579976823.225 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:27:03.225Z,1579976823.225 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:27:03.225Z,1579976823.225 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:27:03.226Z,1579976823.226 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:27:03.617Z,1579976823.617 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,E4
3,186,80,80,E5,FF
2020-01-25T18:27:03.617Z,1579976823.617 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:27:03.617Z,1579976823.617 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:27:03.619Z,1579976823.619 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:27:04.029Z,1579976824.029 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:04.431Z,1579976824.431 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,FBE,000,00CA6,184,80,80,E5,FF
2020-01-25T18:27:04.431Z,1579976824.431 [DUSBL_Hydroid](INFO): publishing direction and range info
2020-01-25T18:27:04.496Z,1579976824.496 [Reporter](INFO): acoustic_contact_range 156.779999 m
2020-01-25T18:27:04.855Z,1579976824.855 [Micromodem](INFO): Nmea in: $SNTTA,0.106738,,,,182704.76*42

2020-01-25T18:27:07.255Z,1579976827.255 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:27:07.255Z,1579976827.255 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:27:07.255Z,1579976827.255 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:27:07.256Z,1579976827.256 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:27:07.668Z,1579976827.668 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
FBE,000,00CA6,184,80,80,E5,FF
2020-01-25T18:27:07.668Z,1579976827.668 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:27:07.668Z,1579976827.668 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:27:07.669Z,1579976827.669 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:27:08.069Z,1579976828.069 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:08.469Z,1579976828.469 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,FCE,000,00CA6,184,80,80,E5,FF
2020-01-25T18:27:08.469Z,1579976828.469 [DUSBL_Hydroid](INFO): publishing direction and range info
2020-01-25T18:27:08.889Z,1579976828.889 [Micromodem](INFO): Nmea in: $SNTTA,0.106609,,,,182708.82*46

2020-01-25T18:27:11.311Z,1579976831.311 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:27:11.311Z,1579976831.311 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:27:11.311Z,1579976831.311 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:27:11.312Z,1579976831.312 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:27:11.703Z,1579976831.703 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
FCE,000,00CA6,184,80,80,E5,FF
2020-01-25T18:27:11.703Z,1579976831.703 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:27:11.703Z,1579976831.703 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:27:11.705Z,1579976831.705 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:27:12.128Z,1579976832.128 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:12.929Z,1579976832.929 [Micromodem](INFO): Nmea in: $SNTTA,,,,,182712.85*5C

2020-01-25T18:27:15.335Z,1579976835.335 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,E4
6,184,80,80,E5,FF
2020-01-25T18:27:15.335Z,1579976835.335 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,E4
6,184,80,80,E5,FF
2020-01-25T18:27:15.335Z,1579976835.335 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-25T18:27:15.743Z,1579976835.743 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:27:15.743Z,1579976835.743 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:27:15.743Z,1579976835.743 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:27:15.744Z,1579976835.744 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:27:16.147Z,1579976836.147 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,E4
6,184,80,80,E5,FF
2020-01-25T18:27:16.147Z,1579976836.147 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:27:16.147Z,1579976836.147 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:27:16.149Z,1579976836.149 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:27:16.553Z,1579976836.553 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:16.953Z,1579976836.953 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,00B,000,00C
6,184,80,80,E5,FF
2020-01-25T18:27:16.953Z,1579976836.953 [DUSBL_Hydroid](ERROR): unknown deviceResponse_: !U1,R,00B,000,00C
6,184,80,80,E5,FF
2020-01-25T18:27:17.371Z,1579976837.371 [Micromodem](INFO): Nmea in: $SNTTA,0.107747,,,,182717.30*4B

2020-01-25T18:27:21.814Z,1579976841.814 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:6.070294
2020-01-25T18:27:21.814Z,1579976841.814 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:27:21.814Z,1579976841.814 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:27:21.814Z,1579976841.814 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:27:21.815Z,1579976841.815 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:27:22.201Z,1579976842.201 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
00B,000,00C
6,184,80,80,E5,FF
2020-01-25T18:27:22.201Z,1579976842.201 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:27:22.201Z,1579976842.201 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:27:22.203Z,1579976842.203 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:27:22.609Z,1579976842.609 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:23.015Z,1579976843.015 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,08F,000,00D
6,184,80,80,E5,FF
2020-01-25T18:27:23.016Z,1579976843.016 [DUSBL_Hydroid](ERROR): unknown deviceResponse_: !U1,R,08F,000,00D
6,184,80,80,E5,FF
2020-01-25T18:27:23.430Z,1579976843.430 [Micromodem](INFO): Nmea in: $SNTTA,0.110450,,,,182723.35*4A

2020-01-25T18:27:27.890Z,1579976847.890 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:6.074818
2020-01-25T18:27:27.890Z,1579976847.890 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:27:27.890Z,1579976847.890 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:27:27.890Z,1579976847.890 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:27:27.891Z,1579976847.891 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:27:28.267Z,1579976848.267 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
08F,000,00D
6,184,80,80,E5,FF
2020-01-25T18:27:28.267Z,1579976848.267 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:27:28.267Z,1579976848.267 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:27:28.268Z,1579976848.268 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:27:28.671Z,1579976848.671 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:29.101Z,1579976849.101 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,147,000,00CDC,185,80,80,45,FF
2020-01-25T18:27:29.106Z,1579976849.106 [DUSBL_Hydroid](INFO): publishing direction and range info
2020-01-25T18:27:29.216Z,1579976849.216 [Reporter](INFO): acoustic_contact_range 160.020004 m
2020-01-25T18:27:29.493Z,1579976849.493 [Micromodem](INFO): Nmea in: $SNTTA,0.108938,,,,182729.41*49

2020-01-25T18:27:31.899Z,1579976851.899 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:27:31.899Z,1579976851.899 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:27:31.899Z,1579976851.899 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:27:31.900Z,1579976851.900 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:27:32.303Z,1579976852.303 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
147,000,00CDC,185,80,80,45,FF
2020-01-25T18:27:32.303Z,1579976852.303 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:27:32.303Z,1579976852.303 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:27:32.305Z,1579976852.305 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:27:32.710Z,1579976852.710 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:33.112Z,1579976853.112 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,11E,000,00CDC,185,80,80,45,FF
2020-01-25T18:27:33.112Z,1579976853.112 [DUSBL_Hydroid](INFO): publishing direction and range info
2020-01-25T18:27:33.527Z,1579976853.527 [Micromodem](INFO): Nmea in: $SNTTA,0.109892,,,,182733.45*46

2020-01-25T18:27:35.943Z,1579976855.943 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:27:35.943Z,1579976855.943 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:27:35.943Z,1579976855.943 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:27:35.944Z,1579976855.944 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:27:36.341Z,1579976856.341 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
11E,000,00CDC,185,80,80,45,FF
2020-01-25T18:27:36.341Z,1579976856.341 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:27:36.341Z,1579976856.341 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:27:36.343Z,1579976856.343 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:27:36.753Z,1579976856.753 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:37.152Z,1579976857.152 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,11C,000,00CDC,185,80,80,45,FF
2020-01-25T18:27:37.152Z,1579976857.152 [DUSBL_Hydroid](INFO): publishing direction and range info
2020-01-25T18:27:37.564Z,1579976857.564 [Micromodem](INFO): Nmea in: $SNTTA,,,,,182737.49*5B

2020-01-25T18:27:39.977Z,1579976859.977 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:27:39.977Z,1579976859.977 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:27:39.977Z,1579976859.977 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:27:39.978Z,1579976859.978 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:27:40.400Z,1579976860.400 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
11C,000,00CDC,185,80,80,45,FF
2020-01-25T18:27:40.400Z,1579976860.400 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:27:40.400Z,1579976860.400 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:27:40.401Z,1579976860.401 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:27:40.793Z,1579976860.793 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:41.608Z,1579976861.608 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,121,000,00D36,185,80,80,97,FF
2020-01-25T18:27:41.609Z,1579976861.609 [DUSBL_Hydroid](INFO): publishing direction and range info
2020-01-25T18:27:41.613Z,1579976861.613 [Micromodem](INFO): Nmea in: $SNTTA,0.110481,,,,182741.55*44

2020-01-25T18:27:41.708Z,1579976861.708 [Reporter](INFO): acoustic_contact_range 165.419998 m
2020-01-25T18:27:44.423Z,1579976864.423 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-01-25T18:27:44.423Z,1579976864.423 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-01-25T18:27:44.423Z,1579976864.423 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-01-25T18:27:44.424Z,1579976864.424 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-01-25T18:27:44.844Z,1579976864.844 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
121,000,00D36,185,80,80,97,FF
2020-01-25T18:27:44.844Z,1579976864.844 [DUSBL_Hydroid](INFO): Command Ack
2020-01-25T18:27:44.845Z,1579976864.845 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-01-25T18:27:44.849Z,1579976864.849 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-25T18:27:45.253Z,1579976865.253 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-25T18:27:45.651Z,1579976865.651 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,121,000,00D36,185,80,80,97,FF
2020-01-25T18:27:45.652Z,1579976865.652 [DUSBL_Hydroid](INFO): publishing direction and range info
2020-01-25T18:27:46.060Z,1579976866.060 [Micromodem](INFO): Nmea in: $SNTTA,0.111459,,,,182746.00*47

2020-01-25T18:27:46.258Z,1579976866.258 [CommandLine](IMPORTANT): got command stop
2020-01-25T18:27:46.258Z,1579976866.258 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-01-25T18:27:46.491Z,1579976866.491 [MissionManager](INFO): MissionManager is completed.
2020-01-25T18:27:46.492Z,1579976866.492 [MissionManager](INFO): Uninitializing Mission DUSBL
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL] Stopped
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL:A.Pitch] Stopped
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL:B.SetSpeed] Stopped
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL:C] Stopped
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL:RequestRepeater] Stopped
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL:RequestRepeater:A] Stopped
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL:RequestRepeater:B] Stopped
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL:RequestRepeater:C.Wait] Stopped
2020-01-25T18:27:46.492Z,1579976866.492 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:27:46.923Z,1579976866.923 [MissionManager](IMPORTANT): Started mission Default
2020-01-25T18:27:46.923Z,1579976866.923 [Default] Running Loop=1
2020-01-25T18:27:46.923Z,1579976866.923 [Default](DEBUG): Aggregate::initialize Default
2020-01-25T18:27:46.923Z,1579976866.923 [Default:B.GoToSurface] Running Loop=1
2020-01-25T18:27:46.923Z,1579976866.923 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-25T18:27:46.924Z,1579976866.924 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-25T18:27:46.924Z,1579976866.924 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-25T18:27:46.924Z,1579976866.924 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-25T18:27:46.924Z,1579976866.924 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-25T18:27:46.925Z,1579976866.925 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-25T18:27:46.926Z,1579976866.926 [Default:A.Wait] Running Loop=1
2020-01-25T18:27:46.926Z,1579976866.926 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:28:00.275Z,1579976880.275 [Default:A.Wait](INFO): Done Waiting.
2020-01-25T18:28:00.275Z,1579976880.275 [Default:A.Wait] Stopped
2020-01-25T18:28:00.275Z,1579976880.275 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T18:28:00.627Z,1579976880.627 [Default:CheckIn] Running Loop=1
2020-01-25T18:28:00.627Z,1579976880.627 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-25T18:28:00.627Z,1579976880.627 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-25T18:28:02.628Z,1579976882.628 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182753.00,A,3648.69236,N,12148.70014,W,0.816,308.48,250120,,,D*72
2020-01-25T18:28:02.635Z,1579976882.635 [NAL9602](INFO): GPS fix at 20200125T182753: (36.811539, -121.811669)
2020-01-25T18:28:02.695Z,1579976882.695 [Default:CheckIn:Read_GPS] Stopped
2020-01-25T18:28:02.695Z,1579976882.695 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-25T18:28:12.341Z,1579976892.341 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200125T175407/Courier0036.lzma
2020-01-25T18:28:14.347Z,1579976894.347 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Courier0036.lzma.bak
2020-01-25T18:28:14.348Z,1579976894.348 [DataOverHttps](INFO): SBD MOMSN=12203711
2020-01-25T18:28:21.593Z,1579976901.593 [NAL9602](INFO): SBD MO Status=0, MOMSN=10270, MT Status=0, MTMSN=0
2020-01-25T18:28:21.593Z,1579976901.593 [NAL9602](INFO): No messages in MT queue
2020-01-25T18:28:32.010Z,1579976912.010 [DataOverHttps](INFO): Sending 327 bytes from file Logs/20200125T175407/Express0037.lzma
2020-01-25T18:28:34.016Z,1579976914.016 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0037.lzma.bak
2020-01-25T18:28:34.016Z,1579976914.016 [DataOverHttps](INFO): SBD MOMSN=12203714
2020-01-25T18:28:51.801Z,1579976931.801 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20200125T175407/Express0040.lzma
2020-01-25T18:28:52.300Z,1579976932.300 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-25T18:28:53.807Z,1579976933.807 [DataOverHttps](INFO): Moved sent file to Logs/20200125T175407/Express0040.lzma.bak
2020-01-25T18:28:53.808Z,1579976933.808 [DataOverHttps](INFO): SBD MOMSN=12203727
2020-01-25T18:28:55.176Z,1579976935.176 [Default:CheckIn:Read_Iridium] Stopped
2020-01-25T18:28:55.177Z,1579976935.177 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-25T18:28:55.177Z,1579976935.177 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T18:30:15.235Z,1579977015.235 [DataOverHttps](IMPORTANT): SBD MTMSN=20200125T183005
2020-01-25T18:30:23.193Z,1579977023.193 [DataOverHttps](INFO): Received command:restart logs