2020-06-08T17:02:05.903Z,1591635725.903 [CommandLine](IMPORTANT): got command restart logs
2020-06-08T17:02:15.461Z,1591635735.461 [Default:A.Wait](INFO): Done Waiting.
2020-06-08T17:02:15.462Z,1591635735.462 [Default:A.Wait] Stopped
2020-06-08T17:02:15.462Z,1591635735.462 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-06-08T17:02:15.877Z,1591635735.877 [Default:CheckIn] Running Loop=1
2020-06-08T17:02:15.877Z,1591635735.877 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-08T17:02:15.877Z,1591635735.877 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-08T17:02:21.879Z,1591635741.879 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:02:21.879Z,1591635741.879 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+17.7,0000.0,1489.0,,-00932,-01313,+00215,+00000,A
2020-06-08T17:02:49.355Z,1591635769.355 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-08T17:02:55.017Z,1591635775.017 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:02:55.017Z,1591635775.017 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2020-06-08T17:03:53.600Z,1591635833.600 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:03:53.600Z,1591635833.600 [DVL_micro](ERROR): Failed to parse:
:BI,-1421,+00227,+00000,I
2020-06-08T17:04:26.723Z,1591635866.723 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:04:26.723Z,1591635866.723 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35,0000.0,1489.0,000
2020-06-08T17:04:41.287Z,1591635881.287 [BPC1](ERROR): Battery stick #6 (s/n: 00CA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2020-06-08T17:04:41.288Z,1591635881.288 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2020-06-08T17:04:41.292Z,1591635881.292 [BPC1](INFO): Received data from all battery sticks.
2020-06-08T17:04:52.173Z,1591635892.173 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:04:52.173Z,1591635892.173 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+17.81489.0,000
2020-06-08T17:05:25.302Z,1591635925.302 [DVL_micro](ERROR): Failed to parse:
:WI,-0070,-00082,+00000,A
2020-06-08T17:05:58.430Z,1591635958.430 [DVL_micro](ERROR): Failed to parse:
:WI,-00682,+00065,+00000,+00000,A
2020-06-08T17:06:33.984Z,1591635993.984 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:06:33.984Z,1591635993.984 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0000.0,1489.0,000
2020-06-08T17:07:16.037Z,1591636036.037 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T17:02:15.9Z
2020-06-08T17:07:16.037Z,1591636036.037 [Default:CheckIn:Read_GPS] Stopped
2020-06-08T17:07:16.037Z,1591636036.037 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-08T17:07:16.426Z,1591636036.426 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-06-08T17:07:24.953Z,1591636044.953 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200608T165657/Courier0000.lzma
2020-06-08T17:07:25.954Z,1591636045.954 [DataOverHttps](INFO): Moved sent file to Logs/20200608T165657/Courier0000.lzma.bak
2020-06-08T17:07:25.955Z,1591636045.955 [DataOverHttps](INFO): SBD MOMSN=12372049
2020-06-08T17:07:37.554Z,1591636057.554 [CommandLine](IMPORTANT): got command run ./Missions/Demo/senddata_direct_test.xml
2020-06-08T17:07:37.555Z,1591636057.555 [MissionManager](INFO): Loading Mission: ./Missions/Demo/senddata_direct_test.xml
2020-06-08T17:07:37.588Z,1591636057.588 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
    xmlns:Sensor="Tethys/Sensor"
    xmlns:Units="Tethys/Units"
    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
    xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                        Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                        Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd"
    Id="senddata_direct_test">

    <SendData Service="Direct" Destination="modem:9:_.pressure">
        <Sensor:Onboard.Pressure/><Units:atmosphere/>
    </SendData>
    
    <SendData Service="Direct" Destination="modem:9:_.temperature">
        <Sensor:Onboard.Temperature/><Units:celsius/>
    </SendData>
    
    <SendData Service="Direct" Destination="modem:9:_.humidity">
        <Sensor:Onboard.Humidity/><Units:percent/>
    </SendData>

</Mission>


2020-06-08T17:07:37.590Z,1591636057.590 [CommandLine](IMPORTANT): Running ./Missions/Demo/senddata_direct_test.xml
2020-06-08T17:07:37.841Z,1591636057.841 [Default] Stopped
2020-06-08T17:07:37.842Z,1591636057.842 [Default](DEBUG): Aggregate::uninitialize Default
2020-06-08T17:07:37.842Z,1591636057.842 [Default:B.GoToSurface] Stopped
2020-06-08T17:07:37.842Z,1591636057.842 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-08T17:07:37.842Z,1591636057.842 [Default:CheckIn] Stopped
2020-06-08T17:07:37.842Z,1591636057.842 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-08T17:07:37.842Z,1591636057.842 [Default:CheckIn:Read_Iridium] Stopped
2020-06-08T17:07:37.842Z,1591636057.842 [MissionManager](IMPORTANT): Started mission senddata_direct_test
2020-06-08T17:07:37.842Z,1591636057.842 [senddata_direct_test] Running Loop=1
2020-06-08T17:07:37.842Z,1591636057.842 [senddata_direct_test](DEBUG): Aggregate::initialize senddata_direct_test
2020-06-08T17:07:37.843Z,1591636057.843 [senddata_direct_test:A] Running Loop=1
2020-06-08T17:07:37.843Z,1591636057.843 [senddata_direct_test:A] Stopped
2020-06-08T17:07:37.843Z,1591636057.843 [senddata_direct_test:B] Running Loop=1
2020-06-08T17:07:38.233Z,1591636058.233 [DAT](INFO): modem://9: set _.pressure 0.511544 atmosphere
2020-06-08T17:07:38.233Z,1591636058.233 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-06-08T17:07:38.265Z,1591636058.265 [senddata_direct_test:B] Stopped
2020-06-08T17:07:38.265Z,1591636058.265 [senddata_direct_test:C] Running Loop=1
2020-06-08T17:07:38.636Z,1591636058.636 [DAT](INFO): entering command mode
2020-06-08T17:07:38.646Z,1591636058.646 [senddata_direct_test:C] Stopped
2020-06-08T17:07:38.647Z,1591636058.647 [senddata_direct_test](INFO): Completed senddata_direct_test
2020-06-08T17:07:38.647Z,1591636058.647 [MissionManager](INFO): senddata_direct_test is completed.
2020-06-08T17:07:38.647Z,1591636058.647 [MissionManager](INFO): Uninitializing Mission senddata_direct_test
2020-06-08T17:07:38.647Z,1591636058.647 [senddata_direct_test] Stopped
2020-06-08T17:07:38.647Z,1591636058.647 [senddata_direct_test](DEBUG): Aggregate::uninitialize senddata_direct_test
2020-06-08T17:07:39.045Z,1591636059.045 [DAT](INFO): commRate: 800
2020-06-08T17:07:39.045Z,1591636059.045 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:39.055Z,1591636059.055 [MissionManager](IMPORTANT): Started mission Default
2020-06-08T17:07:39.055Z,1591636059.055 [Default] Running Loop=1
2020-06-08T17:07:39.055Z,1591636059.055 [Default](DEBUG): Aggregate::initialize Default
2020-06-08T17:07:39.055Z,1591636059.055 [Default:B.GoToSurface] Running Loop=1
2020-06-08T17:07:39.055Z,1591636059.055 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-08T17:07:39.055Z,1591636059.055 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-08T17:07:39.056Z,1591636059.056 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-08T17:07:39.056Z,1591636059.056 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-08T17:07:39.056Z,1591636059.056 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-08T17:07:39.056Z,1591636059.056 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-08T17:07:39.057Z,1591636059.057 [Default:A.Wait] Running Loop=1
2020-06-08T17:07:39.057Z,1591636059.057 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-06-08T17:07:39.475Z,1591636059.475 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:39.857Z,1591636059.857 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:40.273Z,1591636060.273 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:40.664Z,1591636060.664 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:41.061Z,1591636061.061 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:41.466Z,1591636061.466 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:41.873Z,1591636061.873 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:42.295Z,1591636062.295 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:42.710Z,1591636062.710 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:43.084Z,1591636063.084 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:43.491Z,1591636063.491 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:43.886Z,1591636063.886 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:43.981Z,1591636063.981 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20200608T165657/Courier0004.lzma
2020-06-08T17:07:44.290Z,1591636064.290 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:44.701Z,1591636064.701 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:44.982Z,1591636064.982 [DataOverHttps](INFO): Moved sent file to Logs/20200608T165657/Courier0004.lzma.bak
2020-06-08T17:07:44.982Z,1591636064.982 [DataOverHttps](INFO): SBD MOMSN=12372052
2020-06-08T17:07:45.133Z,1591636065.133 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:45.504Z,1591636065.504 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:45.907Z,1591636065.907 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:46.315Z,1591636066.315 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:46.721Z,1591636066.721 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:47.120Z,1591636067.120 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:47.508Z,1591636067.508 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-08T17:07:47.525Z,1591636067.525 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:47.942Z,1591636067.942 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:48.338Z,1591636068.338 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:48.733Z,1591636068.733 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-08T17:07:48.734Z,1591636068.734 [DAT](FAULT): failed to enter command mode
2020-06-08T17:07:48.734Z,1591636068.734 [DAT](ERROR): Failure setting remote address to 9
2020-06-08T17:07:49.140Z,1591636069.140 [DAT](INFO): entering command mode
2020-06-08T17:07:49.550Z,1591636069.550 [DAT](INFO): setting remote address to 9
2020-06-08T17:07:49.951Z,1591636069.951 [DAT](INFO): set remote address to 9
2020-06-08T17:07:49.951Z,1591636069.951 [DAT](INFO): entering online mode
2020-06-08T17:07:50.391Z,1591636070.391 [DAT](INFO): commRate: 800
2020-06-08T17:07:50.391Z,1591636070.391 [DAT](INFO): online mode acknowledged
2020-06-08T17:07:50.392Z,1591636070.392 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-06-08T17:07:52.409Z,1591636072.409 [Default:A.Wait](INFO): Done Waiting.
2020-06-08T17:07:52.409Z,1591636072.409 [Default:A.Wait] Stopped
2020-06-08T17:07:52.409Z,1591636072.409 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-06-08T17:07:52.839Z,1591636072.839 [Default:CheckIn] Running Loop=1
2020-06-08T17:07:52.839Z,1591636072.839 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-08T17:07:52.839Z,1591636072.839 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-08T17:07:53.586Z,1591636073.586 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-06-08T17:07:54.383Z,1591636074.383 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-08T17:07:54.383Z,1591636074.383 [NAL9602] Data Fault, FailCount= 1
2020-06-08T17:07:54.383Z,1591636074.383 [NAL9602](ERROR): Data Fault
2020-06-08T17:07:54.421Z,1591636074.421 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-08T17:07:54.781Z,1591636074.781 [NAL9602](INFO): Powering down
2020-06-08T17:07:55.593Z,1591636075.593 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:07:55.593Z,1591636075.593 [DVL_micro](ERROR): Failed to parse:
:BI,-01147,+00142,-0000,I
2020-06-08T17:07:55.673Z,1591636075.673 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-08T17:07:55.673Z,1591636075.673 [NAL9602] No Fault, FailCount= 1
2020-06-08T17:08:02.485Z,1591636082.485 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2020-06-08T17:08:02.485Z,1591636082.485 [DAT](INFO): Got ack
2020-06-08T17:08:02.486Z,1591636082.486 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2020-06-08T17:08:02.904Z,1591636082.904 [DAT](INFO): modem://9: set _.temperature 22.446589 celsius
2020-06-08T17:08:02.904Z,1591636082.904 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-06-08T17:08:03.278Z,1591636083.278 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-06-08T17:08:06.512Z,1591636086.512 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-06-08T17:08:14.608Z,1591636094.608 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2020-06-08T17:08:14.608Z,1591636094.608 [DAT](INFO): Got ack
2020-06-08T17:08:14.608Z,1591636094.608 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2020-06-08T17:08:14.996Z,1591636094.996 [DAT](INFO): modem://9: set _.humidity 10.273471 percent
2020-06-08T17:08:14.996Z,1591636094.996 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-06-08T17:08:15.431Z,1591636095.431 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-06-08T17:08:18.640Z,1591636098.640 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-06-08T17:08:25.072Z,1591636105.072 [NAL9602](INFO): Powering up NAL9602
2020-06-08T17:08:35.623Z,1591636115.623 [DAT](FAULT): Ack receipt timeout failure.
2020-06-08T17:08:35.623Z,1591636115.623 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2020-06-08T17:08:36.010Z,1591636116.010 [NAL9602](INFO): NAL9602 initialized
2020-06-08T17:08:36.024Z,1591636116.024 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-06-08T17:08:36.415Z,1591636116.415 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-06-08T17:08:39.659Z,1591636119.659 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-06-08T17:08:46.177Z,1591636126.177 [CommandLine](IMPORTANT): got command show variable SBDaddress
2020-06-08T17:08:46.207Z,1591636126.207 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.sbdAddress (enum)
2020-06-08T17:08:46.209Z,1591636126.209 [CommandLine](IMPORTANT): DAT.sbdAddress (enum)
2020-06-08T17:08:51.283Z,1591636131.283 [CommandLine](IMPORTANT): got command get DAT.sbdAddress
2020-06-08T17:08:51.283Z,1591636131.283 [CommandLine](IMPORTANT): DAT.sbdAddress -1 enum
2020-06-08T17:08:54.188Z,1591636134.188 [DVL_micro](ERROR): Failed to parse:
:WI,-00583,-00041,-00075,+00000,A
2020-06-08T17:08:56.623Z,1591636136.623 [DAT](FAULT): Ack receipt timeout failure.
2020-06-08T17:08:56.624Z,1591636136.624 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2020-06-08T17:08:57.016Z,1591636137.016 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-06-08T17:08:57.427Z,1591636137.427 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-06-08T17:09:00.667Z,1591636140.667 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-06-08T17:09:08.742Z,1591636148.742 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2020-06-08T17:09:08.742Z,1591636148.742 [DAT](INFO): Got ack
2020-06-08T17:09:08.743Z,1591636148.743 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2020-06-08T17:09:27.304Z,1591636167.304 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-08T17:09:27.304Z,1591636167.304 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999..99,+9999.99
2020-06-08T17:09:55.183Z,1591636195.183 [DVL_micro](ERROR): Failed to parse:
:BI,-0582,+00270,+00000,I
2020-06-08T17:10:25.878Z,1591636225.878 [DVL_micro](ERROR): Failed to parse:
:W,-01628,+00297A
2020-06-08T17:12:05.263Z,1591636325.263 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:12:05.263Z,1591636325.263 [DVL_micro](ERROR): Failed to parse:
:TS,000000035.0,+18.0,0000.0,1489.0,000
2020-06-08T17:12:30.715Z,1591636350.715 [DVL_micro](ERROR): Failed to parse:
:WI,-01046,-01273,+00368,+00000,A
2020-06-08T17:12:52.956Z,1591636372.956 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T17:07:52.8Z
2020-06-08T17:12:52.956Z,1591636372.956 [Default:CheckIn:Read_GPS] Stopped
2020-06-08T17:12:52.956Z,1591636372.956 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-08T17:13:03.557Z,1591636383.557 [DataOverHttps](INFO): Sending 193 bytes from file Logs/20200608T170205/Courier0000.lzma
2020-06-08T17:13:04.558Z,1591636384.558 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Courier0000.lzma.bak
2020-06-08T17:13:04.559Z,1591636384.559 [DataOverHttps](INFO): SBD MOMSN=12372079
2020-06-08T17:13:22.432Z,1591636402.432 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200608T170205/Courier0006.lzma
2020-06-08T17:13:23.434Z,1591636403.434 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Courier0006.lzma.bak
2020-06-08T17:13:23.435Z,1591636403.435 [DataOverHttps](INFO): SBD MOMSN=12372082
2020-06-08T17:13:36.980Z,1591636416.980 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:13:36.981Z,1591636416.981 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.999
2020-06-08T17:13:38.990Z,1591636418.990 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-08T17:13:43.141Z,1591636423.141 [DataOverHttps](INFO): Sending 326 bytes from file Logs/20200608T164432/Express0005.lzma
2020-06-08T17:13:44.143Z,1591636424.143 [DataOverHttps](INFO): Moved sent file to Logs/20200608T164432/Express0005.lzma.bak
2020-06-08T17:13:44.143Z,1591636424.143 [DataOverHttps](INFO): SBD MOMSN=12372084
2020-06-08T17:14:02.427Z,1591636442.427 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:14:02.427Z,1591636442.427 [DVL_micro](ERROR): Failed to parse:
:BI,-01680,-00912,+00407,+00000+0000000000000.00,0.00,000.00
2020-06-08T17:14:03.755Z,1591636443.755 [DataOverHttps](INFO): Sending 959 bytes from file Logs/20200608T164432/Express0008.lzma
2020-06-08T17:14:04.755Z,1591636444.755 [DataOverHttps](INFO): Moved sent file to Logs/20200608T164432/Express0008.lzma.bak
2020-06-08T17:14:04.755Z,1591636444.755 [DataOverHttps](INFO): SBD MOMSN=12372090
2020-06-08T17:14:09.731Z,1591636449.731 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-08T17:14:24.751Z,1591636464.751 [DataOverHttps](INFO): Sending 1186 bytes from file Logs/20200608T165501/Express0001.lzma
2020-06-08T17:14:25.750Z,1591636465.750 [DataOverHttps](INFO): Moved sent file to Logs/20200608T165501/Express0001.lzma.bak
2020-06-08T17:14:25.751Z,1591636465.751 [DataOverHttps](INFO): SBD MOMSN=12372120
2020-06-08T17:14:44.436Z,1591636484.436 [DataOverHttps](INFO): Sending 952 bytes from file Logs/20200608T165657/Express0001.lzma
2020-06-08T17:14:45.434Z,1591636485.434 [DataOverHttps](INFO): Moved sent file to Logs/20200608T165657/Express0001.lzma.bak
2020-06-08T17:14:45.435Z,1591636485.435 [DataOverHttps](INFO): SBD MOMSN=12372159
2020-06-08T17:15:06.246Z,1591636506.246 [DataOverHttps](INFO): Sending 843 bytes from file Logs/20200608T165657/Express0005.lzma
2020-06-08T17:15:07.242Z,1591636507.242 [DataOverHttps](INFO): Moved sent file to Logs/20200608T165657/Express0005.lzma.bak
2020-06-08T17:15:07.243Z,1591636507.243 [DataOverHttps](INFO): SBD MOMSN=12372182
2020-06-08T17:15:20.243Z,1591636520.243 [CommandLine](IMPORTANT): got command show variable threshold
2020-06-08T17:15:20.350Z,1591636520.350 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold (meter)
2020-06-08T17:15:20.365Z,1591636520.365 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.surfaceThreshold (meter)
2020-06-08T17:15:20.367Z,1591636520.367 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold (count)
2020-06-08T17:15:20.446Z,1591636520.446 [CommandLine](IMPORTANT): DAT.surfaceThreshold (meter)
2020-06-08T17:15:20.447Z,1591636520.447 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2020-06-08T17:15:20.458Z,1591636520.458 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold (meter)
2020-06-08T17:15:20.519Z,1591636520.519 [CommandLine](IMPORTANT): StratificationFrontDetector.threshold (celsius)
2020-06-08T17:15:20.520Z,1591636520.520 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.cntThreshold (count)
2020-06-08T17:15:20.520Z,1591636520.520 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.tempHoriGradThreshold (celsius)
2020-06-08T17:15:20.708Z,1591636520.708 [CommandLine](IMPORTANT): CBIT.humidityThreshold (percent)
2020-06-08T17:15:20.708Z,1591636520.708 [CommandLine](IMPORTANT): CBIT.pressureThreshold (pound_per_square_inch)
2020-06-08T17:15:20.709Z,1591636520.709 [CommandLine](IMPORTANT): CBIT.tempThreshold (fahrenheit)
2020-06-08T17:15:20.710Z,1591636520.710 [CommandLine](IMPORTANT): CBIT.battTempThreshold (celsius)
2020-06-08T17:15:20.710Z,1591636520.710 [CommandLine](IMPORTANT): CBIT.gfChanA0_Threshold (milliampere)
2020-06-08T17:15:20.711Z,1591636520.711 [CommandLine](IMPORTANT): CBIT.gfChanA1_Threshold (milliampere)
2020-06-08T17:15:20.711Z,1591636520.711 [CommandLine](IMPORTANT): CBIT.gfChanA2_Threshold (milliampere)
2020-06-08T17:15:20.712Z,1591636520.712 [CommandLine](IMPORTANT): CBIT.gfChanA3_Threshold (milliampere)
2020-06-08T17:15:20.712Z,1591636520.712 [CommandLine](IMPORTANT): CBIT.gfChanB0_Threshold (milliampere)
2020-06-08T17:15:20.712Z,1591636520.712 [CommandLine](IMPORTANT): CBIT.gfChanB1_Threshold (milliampere)
2020-06-08T17:15:20.713Z,1591636520.713 [CommandLine](IMPORTANT): CBIT.gfChanB2_Threshold (milliampere)
2020-06-08T17:15:20.713Z,1591636520.713 [CommandLine](IMPORTANT): CBIT.gfChanB3_Threshold (milliampere)
2020-06-08T17:15:20.715Z,1591636520.715 [CommandLine](IMPORTANT): IBIT.batteryCapacityThreshold (ampere_hour)
2020-06-08T17:15:20.715Z,1591636520.715 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold (volt)
2020-06-08T17:15:27.157Z,1591636527.157 [DataOverHttps](INFO): Sending 412 bytes from file Logs/20200608T170205/Express0001.lzma
2020-06-08T17:15:28.159Z,1591636528.159 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Express0001.lzma.bak
2020-06-08T17:15:28.159Z,1591636528.159 [DataOverHttps](INFO): SBD MOMSN=12372205
2020-06-08T17:15:28.354Z,1591636528.354 [CommandLine](IMPORTANT): got command get DAT.surfaceThreshold
2020-06-08T17:15:28.354Z,1591636528.354 [CommandLine](IMPORTANT): DAT.surfaceThreshold 0.500000 m
2020-06-08T17:15:30.998Z,1591636530.998 [CommandLine](IMPORTANT): got command get depth
2020-06-08T17:15:30.998Z,1591636530.998 [CommandLine](IMPORTANT): depth 0.329871 m
2020-06-08T17:15:48.172Z,1591636548.172 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200608T170205/Express0004.lzma
2020-06-08T17:15:49.175Z,1591636549.175 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Express0004.lzma.bak
2020-06-08T17:15:49.175Z,1591636549.175 [DataOverHttps](INFO): SBD MOMSN=12372209
2020-06-08T17:16:00.185Z,1591636560.185 [CommandLine](IMPORTANT): got command get DAT.surfaceThreshold
2020-06-08T17:16:00.186Z,1591636560.186 [CommandLine](IMPORTANT): DAT.surfaceThreshold 0.500000 m
2020-06-08T17:16:07.252Z,1591636567.252 [DVL_micro](ERROR): Failed to parse:
:000000000,35.0,+18.1,0000.0,1489.0,000
2020-06-08T17:16:11.277Z,1591636571.277 [DataOverHttps](INFO): Sending 413 bytes from file Logs/20200608T170205/Express0007.lzma
2020-06-08T17:16:12.275Z,1591636572.275 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Express0007.lzma.bak
2020-06-08T17:16:12.275Z,1591636572.275 [DataOverHttps](INFO): SBD MOMSN=12372211
2020-06-08T17:16:16.992Z,1591636576.992 [Default:CheckIn:Read_Iridium] Stopped
2020-06-08T17:16:16.992Z,1591636576.992 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-08T17:16:16.992Z,1591636576.992 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-08T17:17:33.718Z,1591636653.718 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:17:33.718Z,1591636653.718 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+18.2,0000.0,148

2020-06-08T17:18:37.538Z,1591636717.538 [DVL_micro](ERROR): Failed to parse:
:T00000000,35.0,+18.2,0000.0,1489.0,000
2020-06-08T17:20:34.698Z,1591636834.698 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:20:34.698Z,1591636834.698 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+180,1489.0,000
2020-06-08T17:21:17.575Z,1591636877.575 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-08T17:21:17.575Z,1591636877.575 [Default:CheckIn:C.Wait] Stopped
2020-06-08T17:21:17.575Z,1591636877.575 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-08T17:21:17.576Z,1591636877.576 [Default:CheckIn:D] Running Loop=1
2020-06-08T17:21:18.005Z,1591636878.005 [Default:CheckIn:D] Stopped
2020-06-08T17:21:18.005Z,1591636878.005 [Default:CheckIn:E] Running Loop=1
2020-06-08T17:21:18.361Z,1591636878.361 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.649167 min 
2020-06-08T17:21:18.361Z,1591636878.361 [Default:CheckIn:E] Stopped
2020-06-08T17:21:18.361Z,1591636878.361 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-08T17:21:18.362Z,1591636878.362 [Default:CheckIn] Stopped
2020-06-08T17:21:18.362Z,1591636878.362 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-08T17:21:18.362Z,1591636878.362 [Default:CheckIn](INFO): Running loop #2
2020-06-08T17:21:18.362Z,1591636878.362 [Default:CheckIn] Running Loop=2
2020-06-08T17:21:18.362Z,1591636878.362 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-08T17:21:18.362Z,1591636878.362 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-08T17:21:19.950Z,1591636879.950 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-08T17:21:19.951Z,1591636879.951 [NAL9602] Data Fault, FailCount= 2
2020-06-08T17:21:19.951Z,1591636879.951 [NAL9602](ERROR): Data Fault
2020-06-08T17:21:20.019Z,1591636880.019 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-08T17:21:20.364Z,1591636880.364 [NAL9602](INFO): Powering down
2020-06-08T17:21:21.195Z,1591636881.195 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-08T17:21:21.195Z,1591636881.195 [NAL9602] No Fault, FailCount= 2
2020-06-08T17:21:33.278Z,1591636893.278 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:21:33.279Z,1591636893.279 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+18.3,00000,000
2020-06-08T17:21:50.650Z,1591636910.650 [NAL9602](INFO): Powering up NAL9602
2020-06-08T17:22:01.559Z,1591636921.559 [NAL9602](INFO): NAL9602 initialized
2020-06-08T17:22:24.188Z,1591636944.188 [DVL_micro](ERROR): only read 1 of 4 data items
2020-06-08T17:22:24.188Z,1591636944.188 [DVL_micro](ERROR): Failed to parse:
:RD,+9999..99,+9999.99,+9999.99
2020-06-08T17:23:55.891Z,1591637035.891 [DVL_micro](ERROR): Failed to parse:
:WI,-00800,-01564,+00467,+00000,A
2020-06-08T17:24:29.020Z,1591637069.020 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-08T17:24:29.020Z,1591637069.020 [DVL_micro](ERROR): Failed to parse:
:BI,-0384,+00219,+

2020-06-08T17:25:27.665Z,1591637127.665 [DVL_micro](ERROR): DVL uart error: serial timeout
2020-06-08T17:25:27.665Z,1591637127.665 [DVL_micro] Communications Fault, FailCount= 1
2020-06-08T17:25:27.665Z,1591637127.665 [DVL_micro](ERROR): Communications Fault
2020-06-08T17:25:27.665Z,1591637127.665 [DVL_micro](ERROR): Failed to parse:
2020-06-08T17:25:27.775Z,1591637127.775 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-08T17:25:28.065Z,1591637128.065 [DVL_micro](INFO): Powering down
2020-06-08T17:25:28.857Z,1591637128.857 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-08T17:25:28.857Z,1591637128.857 [DVL_micro] No Fault, FailCount= 1
2020-06-08T17:26:18.545Z,1591637178.545 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T17:21:18.4Z
2020-06-08T17:26:18.545Z,1591637178.545 [Default:CheckIn:Read_GPS] Stopped
2020-06-08T17:26:18.546Z,1591637178.546 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-08T17:26:27.803Z,1591637187.803 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-08T17:26:27.825Z,1591637187.825 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200608T170205/Courier0009.lzma
2020-06-08T17:26:28.827Z,1591637188.827 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Courier0009.lzma.bak
2020-06-08T17:26:28.827Z,1591637188.827 [DataOverHttps](INFO): SBD MOMSN=12372239
2020-06-08T17:26:50.450Z,1591637210.450 [DataOverHttps](INFO): Sending 722 bytes from file Logs/20200608T170205/Express0010.lzma
2020-06-08T17:26:51.450Z,1591637211.450 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Express0010.lzma.bak
2020-06-08T17:26:51.451Z,1591637211.451 [DataOverHttps](INFO): SBD MOMSN=12372241
2020-06-08T17:26:56.144Z,1591637216.144 [Default:CheckIn:Read_Iridium] Stopped
2020-06-08T17:26:56.144Z,1591637216.144 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-08T17:26:56.149Z,1591637216.149 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-08T17:27:03.353Z,1591637223.353 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:27:03.353Z,1591637223.353 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+180,1489.0,000
2020-06-08T17:27:04.560Z,1591637224.560 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-08T17:27:35.260Z,1591637255.260 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-08T17:27:59.528Z,1591637279.528 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:27:59.529Z,1591637279.529 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999.99,+9999.99
2020-06-08T17:28:58.086Z,1591637338.086 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:28:58.086Z,1591637338.086 [DVL_micro](ERROR): Failed to parse:
:BI,-01178,-01705,+00581,+

2020-06-08T17:29:56.664Z,1591637396.664 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:29:56.665Z,1591637396.665 [DVL_micro](ERROR): Failed to parse:
:BI,-00670,+00165,+00220,+

2020-06-08T17:31:23.128Z,1591637483.128 [DVL_micro](ERROR): Failed to parse:
:SA,-01.03,-06.71,039.1
2020-06-08T17:31:28.383Z,1591637488.383 [DVL_micro](ERROR): Failed to parse:
00000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-08T17:31:56.696Z,1591637516.696 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-08T17:31:56.696Z,1591637516.696 [Default:CheckIn:C.Wait] Stopped
2020-06-08T17:31:56.697Z,1591637516.697 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-08T17:31:56.697Z,1591637516.697 [Default:CheckIn:D] Running Loop=1
2020-06-08T17:31:57.092Z,1591637517.092 [Default:CheckIn:D] Stopped
2020-06-08T17:31:57.092Z,1591637517.092 [Default:CheckIn:E] Running Loop=1
2020-06-08T17:31:57.493Z,1591637517.493 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.300621 min 
2020-06-08T17:31:57.493Z,1591637517.493 [Default:CheckIn:E] Stopped
2020-06-08T17:31:57.494Z,1591637517.494 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-08T17:31:57.494Z,1591637517.494 [Default:CheckIn] Stopped
2020-06-08T17:31:57.494Z,1591637517.494 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-08T17:31:57.494Z,1591637517.494 [Default:CheckIn](INFO): Running loop #3
2020-06-08T17:31:57.494Z,1591637517.494 [Default:CheckIn] Running Loop=3
2020-06-08T17:31:57.494Z,1591637517.494 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-08T17:31:57.494Z,1591637517.494 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-08T17:32:30.183Z,1591637550.183 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-06-08T17:32:30.261Z,1591637550.261 [NAL9602](FAULT): received: 
+CSQ:0

OK
2020-06-08T17:32:30.261Z,1591637550.261 [NAL9602] Data Fault, FailCount= 3
2020-06-08T17:32:30.261Z,1591637550.261 [NAL9602](ERROR): Data Fault
2020-06-08T17:32:30.368Z,1591637550.368 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-08T17:32:30.586Z,1591637550.586 [NAL9602](INFO): Powering down
2020-06-08T17:32:31.455Z,1591637551.455 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-08T17:32:31.455Z,1591637551.455 [NAL9602] No Fault, FailCount= 3
2020-06-08T17:33:00.890Z,1591637580.890 [NAL9602](INFO): Powering up NAL9602
2020-06-08T17:33:11.794Z,1591637591.794 [NAL9602](INFO): NAL9602 initialized
2020-06-08T17:33:12.616Z,1591637592.616 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-08T17:33:12.616Z,1591637592.616 [NAL9602] Data Fault, FailCount= 4
2020-06-08T17:33:12.616Z,1591637592.616 [NAL9602](ERROR): Data Fault
2020-06-08T17:33:12.691Z,1591637592.691 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-08T17:33:13.015Z,1591637593.015 [NAL9602](INFO): Powering down
2020-06-08T17:33:13.864Z,1591637593.864 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-08T17:33:13.864Z,1591637593.864 [NAL9602] No Fault, FailCount= 4
2020-06-08T17:33:25.531Z,1591637605.531 [DVL_micro](ERROR): Failed to parse:
00000000018.8,000.0,000
2020-06-08T17:33:43.325Z,1591637623.325 [NAL9602](INFO): Powering up NAL9602
2020-06-08T17:33:54.219Z,1591637634.219 [NAL9602](INFO): NAL9602 initialized
2020-06-08T17:34:57.240Z,1591637697.240 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:34:57.241Z,1591637697.241 [DVL_micro](ERROR): Failed to parse:
:BI,-01029,-0581,+00000,I
2020-06-08T17:35:52.994Z,1591637752.994 [DVL_micro](ERROR): Failed to parse:
:WI,+01147,+00064,-00151,+00000,A
2020-06-08T17:35:55.819Z,1591637755.819 [DVL_micro](ERROR): Failed to parse:
:WI,-01022,-00493,+00339,+00000,A
2020-06-08T17:36:57.653Z,1591637817.653 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T17:31:57.5Z
2020-06-08T17:36:57.653Z,1591637817.653 [Default:CheckIn:Read_GPS] Stopped
2020-06-08T17:36:57.653Z,1591637817.653 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-08T17:37:01.696Z,1591637821.696 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-08T17:37:06.584Z,1591637826.584 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200608T170205/Courier0012.lzma
2020-06-08T17:37:07.587Z,1591637827.587 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Courier0012.lzma.bak
2020-06-08T17:37:07.587Z,1591637827.587 [DataOverHttps](INFO): SBD MOMSN=12372287
2020-06-08T17:37:27.237Z,1591637847.237 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20200608T170205/Express0013.lzma
2020-06-08T17:37:28.239Z,1591637848.239 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Express0013.lzma.bak
2020-06-08T17:37:28.239Z,1591637848.239 [DataOverHttps](INFO): SBD MOMSN=12372289
2020-06-08T17:37:29.985Z,1591637849.985 [DVL_micro](ERROR): Failed to parse:
9.99,+99999.99,+9999.99
2020-06-08T17:37:32.387Z,1591637852.387 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-08T17:37:33.656Z,1591637853.656 [Default:CheckIn:Read_Iridium] Stopped
2020-06-08T17:37:33.656Z,1591637853.656 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-08T17:37:33.656Z,1591637853.656 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-08T17:38:26.118Z,1591637906.118 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,000.00
2020-06-08T17:38:51.566Z,1591637931.566 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:38:51.566Z,1591637931.566 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000018.9,0000.,000
2020-06-08T17:39:17.019Z,1591637957.019 [DVL_micro](ERROR): Failed to parse:
:BI,-00217,+00286,+00135,+00000,I
2020-06-08T17:39:50.152Z,1591637990.152 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:39:50.153Z,1591637990.153 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+19.0,0000.0,1489.0,000
2020-06-08T17:42:05.090Z,1591638125.090 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:42:05.090Z,1591638125.090 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.0,0000.0,1489.0,,-01014,-0636,+00000,01014,-0636,+000D,+00000000.00,+00000000.00,+00000000.00,999900
2020-06-08T17:42:30.554Z,1591638150.554 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:42:30.554Z,1591638150.554 [DVL_micro](ERROR): Failed to parse:
:BI,-0188,+00650,+00000,I
2020-06-08T17:42:34.212Z,1591638154.212 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-08T17:42:34.212Z,1591638154.212 [Default:CheckIn:C.Wait] Stopped
2020-06-08T17:42:34.212Z,1591638154.212 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-08T17:42:34.249Z,1591638154.249 [Default:CheckIn:D] Running Loop=1
2020-06-08T17:42:34.605Z,1591638154.605 [Default:CheckIn:D] Stopped
2020-06-08T17:42:34.605Z,1591638154.605 [Default:CheckIn:E] Running Loop=1
2020-06-08T17:42:35.013Z,1591638155.013 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.925826 min 
2020-06-08T17:42:35.013Z,1591638155.013 [Default:CheckIn:E] Stopped
2020-06-08T17:42:35.013Z,1591638155.013 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-08T17:42:35.013Z,1591638155.013 [Default:CheckIn] Stopped
2020-06-08T17:42:35.013Z,1591638155.013 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-08T17:42:35.013Z,1591638155.013 [Default:CheckIn](INFO): Running loop #4
2020-06-08T17:42:35.013Z,1591638155.013 [Default:CheckIn] Running Loop=4
2020-06-08T17:42:35.014Z,1591638155.014 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-08T17:42:35.014Z,1591638155.014 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-08T17:43:21.444Z,1591638201.444 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:43:21.444Z,1591638201.444 [DVL_micro](ERROR): Failed to parse:
:TS0000000,35.0000.0,1489.0,000
2020-06-08T17:43:46.898Z,1591638226.898 [DVL_micro](ERROR): only read 1 of 4 data items
2020-06-08T17:43:46.898Z,1591638226.898 [DVL_micro](ERROR): Failed to parse:
:BI,-01921+00555,+00000,I
2020-06-08T17:44:45.476Z,1591638285.476 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:44:45.483Z,1591638285.483 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.11489.0,000
2020-06-08T17:44:53.152Z,1591638293.152 [DVL_micro](ERROR): Failed to parse:
:WI-00391,-00503,+00000,A
2020-06-08T17:46:17.182Z,1591638377.182 [DVL_micro](ERROR): Failed to parse:
43,-01472,+0000,I
2020-06-08T17:46:42.634Z,1591638402.634 [DVL_micro](ERROR): Failed to parse:
79,-00772,+00776,+00000,I
2020-06-08T17:47:10.517Z,1591638430.517 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:47:10.517Z,1591638430.517 [DVL_micro](ERROR): Failed to parse:
:BI,+00435,-01005,+0000,I
2020-06-08T17:47:35.189Z,1591638455.189 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T17:42:35.0Z
2020-06-08T17:47:35.189Z,1591638455.189 [Default:CheckIn:Read_GPS] Stopped
2020-06-08T17:47:35.190Z,1591638455.190 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-08T17:47:39.194Z,1591638459.194 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-08T17:47:44.212Z,1591638464.212 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200608T170205/Courier0015.lzma
2020-06-08T17:47:45.214Z,1591638465.214 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Courier0015.lzma.bak
2020-06-08T17:47:45.215Z,1591638465.215 [DataOverHttps](INFO): SBD MOMSN=12372378
2020-06-08T17:48:03.624Z,1591638483.624 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20200608T170205/Express0016.lzma
2020-06-08T17:48:04.614Z,1591638484.614 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Express0016.lzma.bak
2020-06-08T17:48:04.615Z,1591638484.615 [DataOverHttps](INFO): SBD MOMSN=12372380
2020-06-08T17:48:09.529Z,1591638489.529 [Default:CheckIn:Read_Iridium] Stopped
2020-06-08T17:48:09.529Z,1591638489.529 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-08T17:48:09.529Z,1591638489.529 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-08T17:48:09.894Z,1591638489.894 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-08T17:48:11.543Z,1591638491.543 [DVL_micro](ERROR): Failed to parse:99.99,+99999.99,+

2020-06-08T17:48:13.936Z,1591638493.936 [DVL_micro](ERROR): No DVL communication! Re-initializing
2020-06-08T17:48:13.936Z,1591638493.936 [DVL_micro] Communications Fault, FailCount= 1
2020-06-08T17:48:13.936Z,1591638493.936 [DVL_micro](ERROR): Communications Fault
2020-06-08T17:48:14.037Z,1591638494.037 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-08T17:48:14.413Z,1591638494.413 [DVL_micro](INFO): Powering down
2020-06-08T17:48:15.204Z,1591638495.204 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-08T17:48:15.204Z,1591638495.204 [DVL_micro] No Fault, FailCount= 1
2020-06-08T17:48:32.920Z,1591638512.920 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-06-08T17:48:32.920Z,1591638512.920 [DVL_micro] Communications Fault, FailCount= 2
2020-06-08T17:48:32.920Z,1591638512.920 [DVL_micro](ERROR): Communications Fault
2020-06-08T17:48:33.008Z,1591638513.008 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-08T17:48:33.389Z,1591638513.389 [DVL_micro](INFO): Powering down
2020-06-08T17:48:34.170Z,1591638514.170 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-08T17:48:34.170Z,1591638514.170 [DVL_micro] No Fault, FailCount= 2
2020-06-08T17:48:59.993Z,1591638539.993 [DVL_micro](ERROR): Failed to parse:
:WI,-02014,-00381,+00477,+00000,A
2020-06-08T17:49:35.546Z,1591638575.546 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:49:35.546Z,1591638575.546 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.2,089.0,000
2020-06-08T17:52:38.964Z,1591638758.964 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:52:38.964Z,1591638758.964 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.3,0000.0,00
2020-06-08T17:53:10.109Z,1591638790.109 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-08T17:53:10.109Z,1591638790.109 [Default:CheckIn:C.Wait] Stopped
2020-06-08T17:53:10.109Z,1591638790.109 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-08T17:53:10.110Z,1591638790.110 [Default:CheckIn:D] Running Loop=1
2020-06-08T17:53:10.509Z,1591638790.509 [Default:CheckIn:D] Stopped
2020-06-08T17:53:10.509Z,1591638790.509 [Default:CheckIn:E] Running Loop=1
2020-06-08T17:53:10.912Z,1591638790.912 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.524243 min 
2020-06-08T17:53:10.912Z,1591638790.912 [Default:CheckIn:E] Stopped
2020-06-08T17:53:10.912Z,1591638790.912 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-08T17:53:10.912Z,1591638790.912 [Default:CheckIn] Stopped
2020-06-08T17:53:10.913Z,1591638790.913 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-08T17:53:10.913Z,1591638790.913 [Default:CheckIn](INFO): Running loop #5
2020-06-08T17:53:10.913Z,1591638790.913 [Default:CheckIn] Running Loop=5
2020-06-08T17:53:10.913Z,1591638790.913 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-08T17:53:10.913Z,1591638790.913 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-08T17:53:12.512Z,1591638792.512 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-08T17:53:12.512Z,1591638792.512 [NAL9602] Data Fault, FailCount= 5
2020-06-08T17:53:12.512Z,1591638792.512 [NAL9602](ERROR): Data Fault
2020-06-08T17:53:12.546Z,1591638792.546 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-08T17:53:12.546Z,1591638792.546 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-06-08T17:53:12.894Z,1591638792.894 [NAL9602](INFO): Powering down
2020-06-08T17:53:12.954Z,1591638792.954 [CommandLine](FAULT): Scheduling is paused
2020-06-08T17:53:12.954Z,1591638792.954 [CBIT](INFO): Critical error at 20200608T175312
2020-06-08T17:53:12.955Z,1591638792.955 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-06-08T17:53:29.868Z,1591638809.868 [DVL_micro](ERROR): Failed to parse:
:WI-00335,+00671,+00000,A
2020-06-08T17:53:57.749Z,1591638837.749 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:53:57.749Z,1591638837.749 [DVL_micro](ERROR): Failed to parse:
:BI,+0040,+00385,+00000,I
2020-06-08T17:54:20.837Z,1591638860.837 [DVL_micro](ERROR): DVL uart error: serial timeout
2020-06-08T17:54:20.837Z,1591638860.837 [DVL_micro] Communications Fault, FailCount= 1
2020-06-08T17:54:20.837Z,1591638860.837 [DVL_micro](ERROR): Communications Fault
2020-06-08T17:54:20.838Z,1591638860.838 [DVL_micro](ERROR): Failed to parse:
2020-06-08T17:54:20.921Z,1591638860.921 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-08T17:54:21.237Z,1591638861.237 [DVL_micro](INFO): Powering down
2020-06-08T17:54:22.014Z,1591638862.014 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-08T17:54:22.014Z,1591638862.014 [DVL_micro] No Fault, FailCount= 1
2020-06-08T17:55:12.516Z,1591638912.516 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-08T17:55:12.516Z,1591638912.516 [NAL9602] No Fault, FailCount= 5
2020-06-08T17:55:12.881Z,1591638912.881 [NAL9602](INFO): Powering up NAL9602
2020-06-08T17:55:23.795Z,1591638923.795 [NAL9602](INFO): NAL9602 initialized
2020-06-08T17:56:04.197Z,1591638964.197 [DVL_micro](ERROR): Failed to parse:
:WI,+00281,-01396,+00924,+00000,A
2020-06-08T17:57:05.209Z,1591639025.209 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T17:57:05.209Z,1591639025.209 [DVL_micro](ERROR): Failed to parse:
:BI,-00332,-01406,00000,I
2020-06-08T17:57:28.228Z,1591639048.228 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T17:57:28.229Z,1591639048.229 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+190,1489.0,000
2020-06-08T17:57:53.678Z,1591639073.678 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-08T17:57:53.678Z,1591639073.678 [DVL_micro](ERROR): Failed to parse:
:BI,+02582,-01231+00000,I
2020-06-08T17:58:11.085Z,1591639091.085 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T17:53:10.9Z
2020-06-08T17:58:11.086Z,1591639091.086 [Default:CheckIn:Read_GPS] Stopped
2020-06-08T17:58:11.086Z,1591639091.086 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-08T17:58:20.185Z,1591639100.185 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20200608T170205/Courier0018.lzma
2020-06-08T17:58:21.187Z,1591639101.187 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Courier0018.lzma.bak
2020-06-08T17:58:21.187Z,1591639101.187 [DataOverHttps](INFO): SBD MOMSN=12372451
2020-06-08T17:58:40.821Z,1591639120.821 [DataOverHttps](INFO): Sending 295 bytes from file Logs/20200608T170205/Express0019.lzma
2020-06-08T17:58:41.823Z,1591639121.823 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Express0019.lzma.bak
2020-06-08T17:58:41.823Z,1591639121.823 [DataOverHttps](INFO): SBD MOMSN=12372454
2020-06-08T17:58:46.667Z,1591639126.667 [Default:CheckIn:Read_Iridium] Stopped
2020-06-08T17:58:46.667Z,1591639126.667 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-08T17:58:46.667Z,1591639126.667 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-08T18:00:26.794Z,1591639226.794 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-08T18:00:51.842Z,1591639251.842 [DVL_micro](ERROR): Failed to parse:
09,-01303,-0017,I
2020-06-08T18:00:57.494Z,1591639257.494 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-08T18:01:17.300Z,1591639277.300 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-08T18:01:17.300Z,1591639277.300 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999.99,+9999.99
2020-06-08T18:02:15.880Z,1591639335.880 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-08T18:02:41.328Z,1591639361.328 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T18:02:41.328Z,1591639361.328 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+19.6,009.0,000
2020-06-08T18:03:47.237Z,1591639427.237 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-08T18:03:47.237Z,1591639427.237 [Default:CheckIn:C.Wait] Stopped
2020-06-08T18:03:47.237Z,1591639427.237 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-08T18:03:47.238Z,1591639427.238 [Default:CheckIn:D] Running Loop=1
2020-06-08T18:03:47.642Z,1591639427.642 [Default:CheckIn:D] Stopped
2020-06-08T18:03:47.642Z,1591639427.642 [Default:CheckIn:E] Running Loop=1
2020-06-08T18:03:48.021Z,1591639428.021 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.143111 min 
2020-06-08T18:03:48.022Z,1591639428.022 [Default:CheckIn:E] Stopped
2020-06-08T18:03:48.022Z,1591639428.022 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-08T18:03:48.022Z,1591639428.022 [Default:CheckIn] Stopped
2020-06-08T18:03:48.022Z,1591639428.022 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-08T18:03:48.022Z,1591639428.022 [Default:CheckIn](INFO): Running loop #6
2020-06-08T18:03:48.022Z,1591639428.022 [Default:CheckIn] Running Loop=6
2020-06-08T18:03:48.022Z,1591639428.022 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-08T18:03:48.022Z,1591639428.022 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-08T18:04:20.710Z,1591639460.710 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-06-08T18:04:20.793Z,1591639460.793 [NAL9602](FAULT): received: 
+CSQ:0

OK
2020-06-08T18:04:20.793Z,1591639460.793 [NAL9602] Data Fault, FailCount= 1
2020-06-08T18:04:20.793Z,1591639460.793 [NAL9602](ERROR): Data Fault
2020-06-08T18:04:20.885Z,1591639460.885 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-08T18:04:21.110Z,1591639461.110 [NAL9602](INFO): Powering down
2020-06-08T18:04:21.990Z,1591639461.990 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-08T18:04:21.990Z,1591639461.990 [NAL9602] No Fault, FailCount= 1
2020-06-08T18:04:45.767Z,1591639485.767 [DVL_micro](ERROR): Failed to parse::SA,-01.02,-06.78,039.0
2020-06-08T18:04:51.414Z,1591639491.414 [NAL9602](INFO): Powering up NAL9602
2020-06-08T18:05:02.318Z,1591639502.318 [NAL9602](INFO): NAL9602 initialized
2020-06-08T18:06:30.396Z,1591639590.396 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T18:06:30.396Z,1591639590.396 [DVL_micro](ERROR): Failed to parse:
:TS,000000000.0,+19.6,0000.0,1489.0,000
2020-06-08T18:06:35.324Z,1591639595.324 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-08T18:06:35.325Z,1591639595.325 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.6,00000,000
2020-06-08T18:06:46.074Z,1591639606.074 [CommandLine](IMPORTANT): got command get platform_orientation degree
2020-06-08T18:06:46.074Z,1591639606.074 [CommandLine](IMPORTANT): platform_orientation 65.196293 arcdeg
2020-06-08T18:06:57.714Z,1591639617.714 [CommandLine](IMPORTANT): got command get platform_pitch_angle degree
2020-06-08T18:06:57.715Z,1591639617.715 [CommandLine](IMPORTANT): platform_pitch_angle -0.696335 arcdeg
2020-06-08T18:07:26.269Z,1591639646.269 [CommandLine](IMPORTANT): got command get platform_magnetic_orientation degree
2020-06-08T18:07:26.269Z,1591639646.269 [CommandLine](IMPORTANT): platform_magnetic_orientation 52.235644 arcdeg
2020-06-08T18:07:44.291Z,1591639664.291 [CommandLine](IMPORTANT): got command get platform_roll_angle degree
2020-06-08T18:07:44.291Z,1591639664.291 [CommandLine](IMPORTANT): platform_roll_angle -6.681043 arcdeg
2020-06-08T18:08:48.279Z,1591639728.279 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T18:03:48.0Z
2020-06-08T18:08:48.279Z,1591639728.279 [Default:CheckIn:Read_GPS] Stopped
2020-06-08T18:08:48.279Z,1591639728.279 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-08T18:08:52.702Z,1591639732.702 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-08T18:08:55.581Z,1591639735.581 [DVL_micro](ERROR): DVL uart error: serial timeout
2020-06-08T18:08:55.581Z,1591639735.581 [DVL_micro] Communications Fault, FailCount= 1
2020-06-08T18:08:55.581Z,1591639735.581 [DVL_micro](ERROR): Communications Fault
2020-06-08T18:08:55.581Z,1591639735.581 [DVL_micro](ERROR): Failed to parse:
2020-06-08T18:08:55.681Z,1591639735.681 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-08T18:08:55.997Z,1591639735.997 [DVL_micro](INFO): Powering down
2020-06-08T18:08:56.540Z,1591639736.540 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200608T170205/Courier0021.lzma
2020-06-08T18:08:56.776Z,1591639736.776 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-08T18:08:56.776Z,1591639736.776 [DVL_micro] No Fault, FailCount= 1
2020-06-08T18:08:57.543Z,1591639737.543 [DataOverHttps](INFO): Moved sent file to Logs/20200608T170205/Courier0021.lzma.bak
2020-06-08T18:08:57.544Z,1591639737.544 [DataOverHttps](INFO): SBD MOMSN=12372481
2020-06-08T18:08:58.340Z,1591639738.340 [CommandLine](IMPORTANT): got command restart application
2020-06-08T18:08:59.345Z,1591639739.345 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-08T18:08:59.345Z,1591639739.345 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:08:59.401Z,1591639739.401 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-06-08T18:08:59.401Z,1591639739.401 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:08:59.402Z,1591639739.402 [CommandLine](INFO): Join timeout helper Thread ID is 7907
2020-06-08T18:08:59.409Z,1591639739.409 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-06-08T18:08:59.409Z,1591639739.409 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:08:59.409Z,1591639739.409 [NavChartDb](INFO): Join timeout helper Thread ID is 7908
2020-06-08T18:08:59.681Z,1591639739.681 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-08T18:08:59.681Z,1591639739.681 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:08:59.697Z,1591639739.697 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-06-08T18:08:59.697Z,1591639739.697 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:08:59.697Z,1591639739.697 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7909
2020-06-08T18:09:00.065Z,1591639740.065 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-08T18:09:00.066Z,1591639740.066 [WetLabsBB2FL](INFO): Powering down
2020-06-08T18:09:00.066Z,1591639740.066 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:00.069Z,1591639740.069 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-06-08T18:09:00.069Z,1591639740.069 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:00.069Z,1591639740.069 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7910
2020-06-08T18:09:00.893Z,1591639740.893 [CTD_Seabird](INFO): Powering down
2020-06-08T18:09:00.905Z,1591639740.905 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-08T18:09:00.905Z,1591639740.905 [CTD_Seabird](INFO): Powering down
2020-06-08T18:09:00.917Z,1591639740.917 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:00.937Z,1591639740.937 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-06-08T18:09:00.937Z,1591639740.937 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:00.937Z,1591639740.937 [Radio_Surface](INFO): Join timeout helper Thread ID is 7911
2020-06-08T18:09:01.309Z,1591639741.309 [Radio_Surface](INFO): Powering down
2020-06-08T18:09:01.310Z,1591639741.310 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-08T18:09:01.310Z,1591639741.310 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:01.330Z,1591639741.330 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-06-08T18:09:01.330Z,1591639741.330 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:01.330Z,1591639741.330 [DataOverHttps](INFO): Join timeout helper Thread ID is 7912
2020-06-08T18:09:01.929Z,1591639741.929 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-06-08T18:09:01.930Z,1591639741.930 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-08T18:09:01.930Z,1591639741.930 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:01.942Z,1591639741.942 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-06-08T18:09:01.942Z,1591639741.942 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:01.942Z,1591639741.942 [logger](INFO): Join timeout helper Thread ID is 7913
2020-06-08T18:09:01.977Z,1591639741.977 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-08T18:09:01.977Z,1591639741.977 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:01.983Z,1591639741.983 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-06-08T18:09:01.983Z,1591639741.983 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:01.983Z,1591639741.983 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-06-08T18:09:01.983Z,1591639741.983 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:01.984Z,1591639741.984 [controlThread](INFO): Join timeout helper Thread ID is 7914
2020-06-08T18:09:02.015Z,1591639742.015 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-08T18:09:02.015Z,1591639742.015 [controlThread](DEBUG): Uninitializing ControlThread
2020-06-08T18:09:02.015Z,1591639742.015 [AHRS_M2](INFO): Powering down
2020-06-08T18:09:02.157Z,1591639742.157 [DVL_micro](INFO): Powering down
2020-06-08T18:09:02.158Z,1591639742.158 [NAL9602](INFO): Powering down
2020-06-08T18:09:02.159Z,1591639742.159 [DAT](INFO): Powering down
2020-06-08T18:09:02.298Z,1591639742.298 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-06-08T18:09:02.299Z,1591639742.299 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-06-08T18:09:02.299Z,1591639742.299 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-06-08T18:09:02.300Z,1591639742.300 [MissionManager](INFO): Uninitializing Mission Default
2020-06-08T18:09:02.300Z,1591639742.300 [Default] Stopped
2020-06-08T18:09:02.300Z,1591639742.300 [Default](DEBUG): Aggregate::uninitialize Default
2020-06-08T18:09:02.300Z,1591639742.300 [Default:B.GoToSurface] Stopped
2020-06-08T18:09:02.300Z,1591639742.300 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-08T18:09:02.300Z,1591639742.300 [Default:CheckIn] Stopped
2020-06-08T18:09:02.300Z,1591639742.300 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-08T18:09:02.300Z,1591639742.300 [Default:CheckIn:Read_Iridium] Stopped
2020-06-08T18:09:02.303Z,1591639742.303 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-06-08T18:09:02.303Z,1591639742.303 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-06-08T18:09:02.303Z,1591639742.303 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-06-08T18:09:02.304Z,1591639742.304 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-06-08T18:09:02.304Z,1591639742.304 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-06-08T18:09:02.304Z,1591639742.304 [BuoyancyServo](INFO): Powering down
2020-06-08T18:09:02.317Z,1591639742.317 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-06-08T18:09:02.317Z,1591639742.317 [ElevatorServo](INFO): Powering down
2020-06-08T18:09:02.318Z,1591639742.318 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-06-08T18:09:02.318Z,1591639742.318 [MassServo](INFO): Powering down
2020-06-08T18:09:02.319Z,1591639742.319 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-06-08T18:09:02.319Z,1591639742.319 [RudderServo](INFO): Powering down
2020-06-08T18:09:02.320Z,1591639742.320 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-06-08T18:09:02.320Z,1591639742.320 [ThrusterServo](INFO): Powering down
2020-06-08T18:09:02.320Z,1591639742.320 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-06-08T18:09:02.321Z,1591639742.321 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-06-08T18:09:02.321Z,1591639742.321 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-06-08T18:09:02.321Z,1591639742.321 [CBIT](DEBUG): Powering off loads.
2020-06-08T18:09:02.333Z,1591639742.333 [CBIT](DEBUG): Disabling WDT.
2020-06-08T18:09:02.345Z,1591639742.345 [CBIT](DEBUG): Opening all GF detection circuits.
2020-06-08T18:09:02.346Z,1591639742.346 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:02.444Z,1591639742.444 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:02.451Z,1591639742.451 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:02.519Z,1591639742.519 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:02.524Z,1591639742.524 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:02.556Z,1591639742.556 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-08T18:09:02.618Z,1591639742.618 [logger ThreadHandler](INFO): Thread cancelled.