2020-01-26T00:00:54.176Z,1579996854.176 [DataOverHttps](INFO): Received command:restart logs
2020-01-26T00:00:54.182Z,1579996854.182 [CommandLine](IMPORTANT): got command restart logs
2020-01-26T00:01:05.498Z,1579996865.498 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-26T00:01:05.498Z,1579996865.498 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,  -434,  +162,   BE,  -434,  +162,   +11,A
2020-01-26T00:01:45.874Z,1579996905.874 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:01:45.874Z,1579996905.874 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 360.93, 283.07, 314.52
2020-01-26T00:02:55.504Z,1579996975.504 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:02:55.504Z,1579996975.504 [RDI_Pathfinder](ERROR): Failed to parse:
:TS7155108,35.0, -0.1,   0.0,1448.9,  0
2020-01-26T00:03:15.272Z,1579996995.272 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-26T00:03:15.272Z,1579996995.272 [Default:CheckIn:C.Wait] Stopped
2020-01-26T00:03:15.272Z,1579996995.272 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T00:03:15.272Z,1579996995.272 [Default:CheckIn:D] Running Loop=1
2020-01-26T00:03:15.683Z,1579996995.683 [Default:CheckIn:D] Stopped
2020-01-26T00:03:15.683Z,1579996995.683 [Default:CheckIn:E] Running Loop=1
2020-01-26T00:03:16.066Z,1579996996.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.610174 min 
2020-01-26T00:03:16.067Z,1579996996.067 [Default:CheckIn:E] Stopped
2020-01-26T00:03:16.067Z,1579996996.067 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-26T00:03:16.067Z,1579996996.067 [Default:CheckIn] Stopped
2020-01-26T00:03:16.067Z,1579996996.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T00:03:16.067Z,1579996996.067 [Default:CheckIn](INFO): Running loop #2
2020-01-26T00:03:16.067Z,1579996996.067 [Default:CheckIn] Running Loop=2
2020-01-26T00:03:16.067Z,1579996996.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-26T00:03:16.067Z,1579996996.067 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-26T00:03:18.063Z,1579996998.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000307.00,A,3648.50572,N,12148.60040,W,1.361,120.51,260120,,,A*7D
2020-01-26T00:03:18.065Z,1579996998.065 [NAL9602](INFO): GPS fix at 20200126T000307: (36.808429, -121.810007)
2020-01-26T00:03:18.138Z,1579996998.138 [Default:CheckIn:Read_GPS] Stopped
2020-01-26T00:03:18.138Z,1579996998.138 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-26T00:03:27.788Z,1579997007.788 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200125T231052/Courier0016.lzma
2020-01-26T00:03:29.794Z,1579997009.794 [DataOverHttps](INFO): Moved sent file to Logs/20200125T231052/Courier0016.lzma.bak
2020-01-26T00:03:29.795Z,1579997009.795 [DataOverHttps](INFO): SBD MOMSN=12204281
2020-01-26T00:03:33.812Z,1579997013.812 [NAL9602](INFO): SBD MO Status=0, MOMSN=10292, MT Status=0, MTMSN=0
2020-01-26T00:03:33.812Z,1579997013.812 [NAL9602](INFO): No messages in MT queue
2020-01-26T00:03:47.241Z,1579997027.241 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20200126T000054/Courier0000.lzma
2020-01-26T00:03:49.246Z,1579997029.246 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0000.lzma.bak
2020-01-26T00:03:49.246Z,1579997029.246 [DataOverHttps](INFO): SBD MOMSN=12204283
2020-01-26T00:04:04.519Z,1579997044.519 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T00:04:12.192Z,1579997052.192 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20200125T231052/Express0017.lzma
2020-01-26T00:04:14.200Z,1579997054.200 [DataOverHttps](INFO): Moved sent file to Logs/20200125T231052/Express0017.lzma.bak
2020-01-26T00:04:14.200Z,1579997054.200 [DataOverHttps](INFO): SBD MOMSN=12204287
2020-01-26T00:04:17.872Z,1579997057.872 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:04:17.872Z,1579997057.872 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012517171308,35.0, -0.1,   0.0,10
2020-01-26T00:04:37.013Z,1579997077.013 [DataOverHttps](INFO): Sending 324 bytes from file Logs/20200126T000054/Express0001.lzma
2020-01-26T00:04:40.022Z,1579997080.022 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0001.lzma.bak
2020-01-26T00:04:40.023Z,1579997080.023 [DataOverHttps](INFO): SBD MOMSN=12204290
2020-01-26T00:05:00.948Z,1579997100.948 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200126T000054/Express0004.lzma
2020-01-26T00:05:02.954Z,1579997102.954 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0004.lzma.bak
2020-01-26T00:05:02.954Z,1579997102.954 [DataOverHttps](INFO): SBD MOMSN=12204294
2020-01-26T00:05:04.341Z,1579997104.341 [Default:CheckIn:Read_Iridium] Stopped
2020-01-26T00:05:04.342Z,1579997104.342 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-26T00:05:04.342Z,1579997104.342 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-26T00:07:24.934Z,1579997244.934 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.496357
2020-01-26T00:07:41.925Z,1579997261.925 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003324
2020-01-26T00:09:34.710Z,1579997374.710 [RDI_Pathfinder](ERROR): Failed to parse:  -152.89,     +1177.35,      -28.40,  32.13,  0.00
2020-01-26T00:10:05.005Z,1579997405.005 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-26T00:10:05.005Z,1579997405.005 [Default:CheckIn:C.Wait] Stopped
2020-01-26T00:10:05.005Z,1579997405.005 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T00:10:05.006Z,1579997405.006 [Default:CheckIn:D] Running Loop=1
2020-01-26T00:10:05.413Z,1579997405.413 [Default:CheckIn:D] Stopped
2020-01-26T00:10:05.413Z,1579997405.413 [Default:CheckIn:E] Running Loop=1
2020-01-26T00:10:05.820Z,1579997405.820 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.439018 min 
2020-01-26T00:10:05.820Z,1579997405.820 [Default:CheckIn:E] Stopped
2020-01-26T00:10:05.820Z,1579997405.820 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-26T00:10:05.820Z,1579997405.820 [Default:CheckIn] Stopped
2020-01-26T00:10:05.821Z,1579997405.821 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T00:10:05.821Z,1579997405.821 [Default:CheckIn](INFO): Running loop #3
2020-01-26T00:10:05.821Z,1579997405.821 [Default:CheckIn] Running Loop=3
2020-01-26T00:10:05.821Z,1579997405.821 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-26T00:10:05.821Z,1579997405.821 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-26T00:10:07.798Z,1579997407.798 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000957.00,A,3648.46151,N,12148.57193,W,1.263,201.55,260120,,,D*79
2020-01-26T00:10:07.800Z,1579997407.800 [NAL9602](INFO): GPS fix at 20200126T000957: (36.807692, -121.809532)
2020-01-26T00:10:07.861Z,1579997407.861 [Default:CheckIn:Read_GPS] Stopped
2020-01-26T00:10:07.861Z,1579997407.861 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-26T00:10:18.909Z,1579997418.909 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T00:10:24.933Z,1579997424.933 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239677
2020-01-26T00:10:32.040Z,1579997432.040 [NAL9602](INFO): SBD MO Status=0, MOMSN=10293, MT Status=0, MTMSN=0
2020-01-26T00:10:32.040Z,1579997432.040 [NAL9602](INFO): No messages in MT queue
2020-01-26T00:10:56.058Z,1579997456.058 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T00:11:02.751Z,1579997462.751 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T00:11:31.198Z,1579997491.198 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T00:11:33.887Z,1579997493.887 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:11:33.887Z,1579997493.887 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 367.03, 317.51, 344.46
2020-01-26T00:12:06.337Z,1579997526.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T00:12:41.494Z,1579997561.494 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T00:13:02.077Z,1579997582.077 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003205
2020-01-26T00:13:10.020Z,1579997590.020 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200126T000054/Courier0006.lzma
2020-01-26T00:13:12.026Z,1579997592.026 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0006.lzma.bak
2020-01-26T00:13:12.026Z,1579997592.026 [DataOverHttps](INFO): SBD MOMSN=12204300
2020-01-26T00:13:29.380Z,1579997609.380 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200126T000054/Express0007.lzma
2020-01-26T00:13:31.386Z,1579997611.386 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0007.lzma.bak
2020-01-26T00:13:31.386Z,1579997611.386 [DataOverHttps](INFO): SBD MOMSN=12204303
2020-01-26T00:13:32.677Z,1579997612.677 [Default:CheckIn:Read_Iridium] Stopped
2020-01-26T00:13:32.677Z,1579997612.677 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-26T00:13:32.678Z,1579997612.678 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-26T00:15:18.938Z,1579997718.938 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,  -179,  -249,    +7,A
2020-01-26T00:16:17.513Z,1579997777.513 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-26T00:16:17.513Z,1579997777.513 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,     -221.39,     +1132  -32.51,  33.06,  0.00
2020-01-26T00:18:33.285Z,1579997913.285 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-26T00:18:33.285Z,1579997913.285 [Default:CheckIn:C.Wait] Stopped
2020-01-26T00:18:33.285Z,1579997913.285 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T00:18:33.286Z,1579997913.286 [Default:CheckIn:D] Running Loop=1
2020-01-26T00:18:33.714Z,1579997913.714 [Default:CheckIn:D] Stopped
2020-01-26T00:18:33.714Z,1579997913.714 [Default:CheckIn:E] Running Loop=1
2020-01-26T00:18:34.085Z,1579997914.085 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.910691 min 
2020-01-26T00:18:34.086Z,1579997914.086 [Default:CheckIn:E] Stopped
2020-01-26T00:18:34.086Z,1579997914.086 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-26T00:18:34.086Z,1579997914.086 [Default:CheckIn] Stopped
2020-01-26T00:18:34.086Z,1579997914.086 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T00:18:34.086Z,1579997914.086 [Default:CheckIn](INFO): Running loop #4
2020-01-26T00:18:34.086Z,1579997914.086 [Default:CheckIn] Running Loop=4
2020-01-26T00:18:34.086Z,1579997914.086 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-26T00:18:34.086Z,1579997914.086 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-26T00:18:36.093Z,1579997916.093 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001825.00,A,3648.40507,N,12148.53907,W,1.400,82.05,260120,,,D*43
2020-01-26T00:18:36.095Z,1579997916.095 [NAL9602](INFO): GPS fix at 20200126T001825: (36.806751, -121.808984)
2020-01-26T00:18:36.160Z,1579997916.160 [Default:CheckIn:Read_GPS] Stopped
2020-01-26T00:18:36.160Z,1579997916.160 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-26T00:18:44.419Z,1579997924.419 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200126T000054/Courier0009.lzma
2020-01-26T00:18:46.422Z,1579997926.422 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0009.lzma.bak
2020-01-26T00:18:46.422Z,1579997926.422 [DataOverHttps](INFO): SBD MOMSN=12204306
2020-01-26T00:18:53.653Z,1579997933.653 [NAL9602](INFO): SBD MO Status=0, MOMSN=10294, MT Status=0, MTMSN=0
2020-01-26T00:18:53.653Z,1579997933.653 [NAL9602](INFO): No messages in MT queue
2020-01-26T00:19:03.748Z,1579997943.748 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20200126T000054/Express0010.lzma
2020-01-26T00:19:05.754Z,1579997945.754 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0010.lzma.bak
2020-01-26T00:19:05.754Z,1579997945.754 [DataOverHttps](INFO): SBD MOMSN=12204309
2020-01-26T00:19:07.033Z,1579997947.033 [Default:CheckIn:Read_Iridium] Stopped
2020-01-26T00:19:07.034Z,1579997947.034 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-26T00:19:07.034Z,1579997947.034 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-26T00:19:11.875Z,1579997951.875 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,   -45,  -290,  +255,A
2020-01-26T00:19:24.373Z,1579997964.373 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T00:23:38.928Z,1579998218.928 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:23:38.928Z,1579998218.928 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012517360, -0.1,   0.0,1448.9,  0
2020-01-26T00:24:07.634Z,1579998247.634 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-26T00:24:07.634Z,1579998247.634 [Default:CheckIn:C.Wait] Stopped
2020-01-26T00:24:07.634Z,1579998247.634 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T00:24:07.635Z,1579998247.635 [Default:CheckIn:D] Running Loop=1
2020-01-26T00:24:08.018Z,1579998248.018 [Default:CheckIn:D] Stopped
2020-01-26T00:24:08.018Z,1579998248.018 [Default:CheckIn:E] Running Loop=1
2020-01-26T00:24:08.421Z,1579998248.421 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.482430 min 
2020-01-26T00:24:08.421Z,1579998248.421 [Default:CheckIn:E] Stopped
2020-01-26T00:24:08.422Z,1579998248.422 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-26T00:24:08.422Z,1579998248.422 [Default:CheckIn] Stopped
2020-01-26T00:24:08.422Z,1579998248.422 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T00:24:08.422Z,1579998248.422 [Default:CheckIn](INFO): Running loop #5
2020-01-26T00:24:08.422Z,1579998248.422 [Default:CheckIn] Running Loop=5
2020-01-26T00:24:08.422Z,1579998248.422 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-26T00:24:08.422Z,1579998248.422 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-26T00:24:10.411Z,1579998250.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002400.00,A,3648.36892,N,12148.51748,W,1.497,187.49,260120,,,D*7E
2020-01-26T00:24:10.413Z,1579998250.413 [NAL9602](INFO): GPS fix at 20200126T002400: (36.806149, -121.808625)
2020-01-26T00:24:10.480Z,1579998250.480 [Default:CheckIn:Read_GPS] Stopped
2020-01-26T00:24:10.480Z,1579998250.480 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-26T00:24:18.368Z,1579998258.368 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200126T000054/Courier0012.lzma
2020-01-26T00:24:20.374Z,1579998260.374 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0012.lzma.bak
2020-01-26T00:24:20.374Z,1579998260.374 [DataOverHttps](INFO): SBD MOMSN=12204314
2020-01-26T00:24:31.948Z,1579998271.948 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-01-26T00:24:33.036Z,1579998273.036 [NAL9602](INFO): SBD MO Status=2, MOMSN=10295, MT Status=2, MTMSN=0
2020-01-26T00:24:33.036Z,1579998273.036 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-26T00:24:41.092Z,1579998281.092 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200126T000054/Express0013.lzma
2020-01-26T00:24:43.098Z,1579998283.098 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0013.lzma.bak
2020-01-26T00:24:43.098Z,1579998283.098 [DataOverHttps](INFO): SBD MOMSN=12204317
2020-01-26T00:24:44.506Z,1579998284.506 [Default:CheckIn:Read_Iridium] Stopped
2020-01-26T00:24:44.506Z,1579998284.506 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-26T00:24:44.506Z,1579998284.506 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-26T00:24:44.813Z,1579998284.813 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-26T00:24:44.815Z,1579998284.815 [BPC1](INFO): Received data from all battery sticks.
2020-01-26T00:25:20.732Z,1579998320.732 [NAL9602](INFO): SBD MO Status=2, MOMSN=10295, MT Status=2, MTMSN=0
2020-01-26T00:25:20.732Z,1579998320.732 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-26T00:25:32.878Z,1579998332.878 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:25:32.878Z,1579998332.878 [RDI_Pathfinder](ERROR): Failed to parse:
:TS7382808,35.0, -0.1,   0.0,1448.9,  0
2020-01-26T00:25:34.068Z,1579998334.068 [NAL9602](INFO): SBD MO Status=0, MOMSN=10295, MT Status=0, MTMSN=0
2020-01-26T00:25:34.068Z,1579998334.068 [NAL9602](INFO): No messages in MT queue
2020-01-26T00:26:04.765Z,1579998364.765 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T00:26:52.520Z,1579998412.520 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:26:52.520Z,1579998412.520 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012517394808,35.0, -0.1,   0.0, 0
2020-01-26T00:27:55.516Z,1579998475.516 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-26T00:27:55.516Z,1579998475.516 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,     -336.44,     +105   -43.67,  35.88,  0.00
2020-01-26T00:28:52.866Z,1579998532.866 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:28:52.866Z,1579998532.866 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012517414808,31,   0.0,1448.9,  0
2020-01-26T00:29:44.992Z,1579998584.992 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-26T00:29:44.992Z,1579998584.992 [Default:CheckIn:C.Wait] Stopped
2020-01-26T00:29:44.992Z,1579998584.992 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T00:29:44.992Z,1579998584.992 [Default:CheckIn:D] Running Loop=1
2020-01-26T00:29:45.407Z,1579998585.407 [Default:CheckIn:D] Stopped
2020-01-26T00:29:45.407Z,1579998585.407 [Default:CheckIn:E] Running Loop=1
2020-01-26T00:29:45.804Z,1579998585.804 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.105579 min 
2020-01-26T00:29:45.804Z,1579998585.804 [Default:CheckIn:E] Stopped
2020-01-26T00:29:45.804Z,1579998585.804 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-26T00:29:45.804Z,1579998585.804 [Default:CheckIn] Stopped
2020-01-26T00:29:45.804Z,1579998585.804 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T00:29:45.804Z,1579998585.804 [Default:CheckIn](INFO): Running loop #6
2020-01-26T00:29:45.804Z,1579998585.804 [Default:CheckIn] Running Loop=6
2020-01-26T00:29:45.804Z,1579998585.804 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-26T00:29:45.805Z,1579998585.805 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-26T00:29:47.806Z,1579998587.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002937.00,A,3648.33219,N,12148.49375,W,0.641,169.50,260120,,,D*78
2020-01-26T00:29:47.813Z,1579998587.813 [NAL9602](INFO): GPS fix at 20200126T002937: (36.805537, -121.808229)
2020-01-26T00:29:47.847Z,1579998587.847 [Default:CheckIn:Read_GPS] Stopped
2020-01-26T00:29:47.848Z,1579998587.848 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-26T00:29:55.785Z,1579998595.785 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20200126T000054/Courier0015.lzma
2020-01-26T00:29:57.790Z,1579998597.790 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0015.lzma.bak
2020-01-26T00:29:57.790Z,1579998597.790 [DataOverHttps](INFO): SBD MOMSN=12204322
2020-01-26T00:30:14.048Z,1579998614.048 [NAL9602](INFO): SBD MO Status=2, MOMSN=10296, MT Status=2, MTMSN=0
2020-01-26T00:30:14.048Z,1579998614.048 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-26T00:30:14.522Z,1579998614.522 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:30:14.522Z,1579998614.522 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 408.18, 366.09, 388.05
2020-01-26T00:30:15.164Z,1579998615.164 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200126T000054/Express0016.lzma
2020-01-26T00:30:17.170Z,1579998617.170 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0016.lzma.bak
2020-01-26T00:30:17.171Z,1579998617.171 [DataOverHttps](INFO): SBD MOMSN=12204325
2020-01-26T00:30:18.548Z,1579998618.548 [Default:CheckIn:Read_Iridium] Stopped
2020-01-26T00:30:18.549Z,1579998618.549 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-26T00:30:18.549Z,1579998618.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-26T00:30:44.761Z,1579998644.761 [NAL9602](INFO): SBD MO Status=2, MOMSN=10296, MT Status=2, MTMSN=0
2020-01-26T00:30:44.762Z,1579998644.762 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-26T00:30:56.068Z,1579998656.068 [NAL9602](INFO): SBD MO Status=0, MOMSN=10296, MT Status=0, MTMSN=0
2020-01-26T00:30:56.068Z,1579998656.068 [NAL9602](INFO): No messages in MT queue
2020-01-26T00:31:26.767Z,1579998686.767 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T00:32:02.368Z,1579998722.368 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-26T00:32:02.368Z,1579998722.368 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,     -361.50,     +10    -43.82,   0.00, 22.00
2020-01-26T00:33:17.082Z,1579998797.082 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:33:17.083Z,1579998797.083 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012517461208,35.0, -0.1,   0.0  0
2020-01-26T00:34:29.816Z,1579998869.816 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:34:29.816Z,1579998869.816 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012517472508,35.0, -0.1,   0.0, 0
2020-01-26T00:35:19.141Z,1579998919.141 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-26T00:35:19.141Z,1579998919.141 [Default:CheckIn:C.Wait] Stopped
2020-01-26T00:35:19.141Z,1579998919.141 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T00:35:19.142Z,1579998919.142 [Default:CheckIn:D] Running Loop=1
2020-01-26T00:35:19.554Z,1579998919.554 [Default:CheckIn:D] Stopped
2020-01-26T00:35:19.554Z,1579998919.554 [Default:CheckIn:E] Running Loop=1
2020-01-26T00:35:19.935Z,1579998919.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.674695 min 
2020-01-26T00:35:19.935Z,1579998919.935 [Default:CheckIn:E] Stopped
2020-01-26T00:35:19.935Z,1579998919.935 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-26T00:35:19.935Z,1579998919.935 [Default:CheckIn] Stopped
2020-01-26T00:35:19.936Z,1579998919.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T00:35:19.936Z,1579998919.936 [Default:CheckIn](INFO): Running loop #7
2020-01-26T00:35:19.936Z,1579998919.936 [Default:CheckIn] Running Loop=7
2020-01-26T00:35:19.936Z,1579998919.936 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-26T00:35:19.936Z,1579998919.936 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-26T00:35:21.927Z,1579998921.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003511.00,A,3648.29605,N,12148.47611,W,1.030,141.79,260120,,,D*7A
2020-01-26T00:35:21.940Z,1579998921.940 [NAL9602](INFO): GPS fix at 20200126T003511: (36.804934, -121.807935)
2020-01-26T00:35:21.975Z,1579998921.975 [Default:CheckIn:Read_GPS] Stopped
2020-01-26T00:35:21.975Z,1579998921.975 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-26T00:35:29.336Z,1579998929.336 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200126T000054/Courier0018.lzma
2020-01-26T00:35:31.342Z,1579998931.342 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0018.lzma.bak
2020-01-26T00:35:31.343Z,1579998931.343 [DataOverHttps](INFO): SBD MOMSN=12204330
2020-01-26T00:35:34.456Z,1579998934.456 [NAL9602](INFO): SBD MO Status=0, MOMSN=10297, MT Status=0, MTMSN=0
2020-01-26T00:35:34.457Z,1579998934.457 [NAL9602](INFO): No messages in MT queue
2020-01-26T00:35:48.743Z,1579998948.743 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200126T000054/Express0019.lzma
2020-01-26T00:35:50.746Z,1579998950.746 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0019.lzma.bak
2020-01-26T00:35:50.746Z,1579998950.746 [DataOverHttps](INFO): SBD MOMSN=12204333
2020-01-26T00:35:52.262Z,1579998952.262 [Default:CheckIn:Read_Iridium] Stopped
2020-01-26T00:35:52.263Z,1579998952.263 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-26T00:35:52.263Z,1579998952.263 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-26T00:36:05.158Z,1579998965.158 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T00:38:15.732Z,1579999095.732 [CommandLine](IMPORTANT): got command get height_above_sea_floor
2020-01-26T00:38:15.732Z,1579999095.732 [CommandLine](FAULT): Element has no value
2020-01-26T00:38:19.088Z,1579999099.088 [CommandLine](IMPORTANT): got command get height_above_sea_floor
2020-01-26T00:38:19.088Z,1579999099.088 [CommandLine](FAULT): Element has no value
2020-01-26T00:38:23.377Z,1579999103.377 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-26T00:38:23.377Z,1579999103.377 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,     -361.51,     +1025  -43.82,   0.00,402.99
2020-01-26T00:38:27.091Z,1579999107.091 [CommandLine](IMPORTANT): got command report mod height_above_sea_floor
2020-01-26T00:38:41.679Z,1579999121.679 [CommandLine](IMPORTANT): got command report
2020-01-26T00:38:41.679Z,1579999121.679 [Reporter](INFO): acoustic_contact_range reporting when touched
2020-01-26T00:38:41.680Z,1579999121.680 [Reporter](INFO): height_above_sea_floor reporting changes
2020-01-26T00:39:09.820Z,1579999149.820 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:39:09.820Z,1579999149.820 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-26T00:40:52.846Z,1579999252.846 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-26T00:40:52.846Z,1579999252.846 [Default:CheckIn:C.Wait] Stopped
2020-01-26T00:40:52.846Z,1579999252.846 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T00:40:52.846Z,1579999252.846 [Default:CheckIn:D] Running Loop=1
2020-01-26T00:40:53.249Z,1579999253.249 [Default:CheckIn:D] Stopped
2020-01-26T00:40:53.249Z,1579999253.249 [Default:CheckIn:E] Running Loop=1
2020-01-26T00:40:53.668Z,1579999253.668 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.236283 min 
2020-01-26T00:40:53.668Z,1579999253.668 [Default:CheckIn:E] Stopped
2020-01-26T00:40:53.668Z,1579999253.668 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-26T00:40:53.669Z,1579999253.669 [Default:CheckIn] Stopped
2020-01-26T00:40:53.669Z,1579999253.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T00:40:53.669Z,1579999253.669 [Default:CheckIn](INFO): Running loop #8
2020-01-26T00:40:53.669Z,1579999253.669 [Default:CheckIn] Running Loop=8
2020-01-26T00:40:53.669Z,1579999253.669 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-26T00:40:53.669Z,1579999253.669 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-26T00:40:55.641Z,1579999255.641 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004045.00,A,3648.26227,N,12148.45980,W,0.914,108.63,260120,,,A*7A
2020-01-26T00:40:55.643Z,1579999255.643 [NAL9602](INFO): GPS fix at 20200126T004045: (36.804371, -121.807663)
2020-01-26T00:40:55.700Z,1579999255.700 [Default:CheckIn:Read_GPS] Stopped
2020-01-26T00:40:55.700Z,1579999255.700 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-26T00:40:59.295Z,1579999259.295 [CommandLine](IMPORTANT): got command report
2020-01-26T00:40:59.295Z,1579999259.295 [Reporter](INFO): acoustic_contact_range reporting when touched
2020-01-26T00:40:59.295Z,1579999259.295 [Reporter](INFO): height_above_sea_floor reporting changes
2020-01-26T00:41:03.361Z,1579999263.361 [CommandLine](IMPORTANT): got command report clear
2020-01-26T00:41:03.800Z,1579999263.800 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200126T000054/Courier0021.lzma
2020-01-26T00:41:05.806Z,1579999265.806 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0021.lzma.bak
2020-01-26T00:41:05.806Z,1579999265.806 [DataOverHttps](INFO): SBD MOMSN=12204338
2020-01-26T00:41:23.149Z,1579999283.149 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20200126T000054/Express0022.lzma
2020-01-26T00:41:25.158Z,1579999285.158 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0022.lzma.bak
2020-01-26T00:41:25.158Z,1579999285.158 [DataOverHttps](INFO): SBD MOMSN=12204341
2020-01-26T00:41:25.568Z,1579999285.568 [RDI_Pathfinder](ERROR): Failed to parse:
 -361.51,     +1025.18,      -43.82,   0.00,583.99
2020-01-26T00:41:26.395Z,1579999286.395 [Default:CheckIn:Read_Iridium] Stopped
2020-01-26T00:41:26.395Z,1579999286.395 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-26T00:41:26.395Z,1579999286.395 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-26T00:41:28.611Z,1579999288.611 [CommandLine](IMPORTANT): got command report
2020-01-26T00:41:38.866Z,1579999298.866 [NAL9602](INFO): SBD MO Status=0, MOMSN=10298, MT Status=0, MTMSN=0
2020-01-26T00:41:38.867Z,1579999298.867 [NAL9602](INFO): No messages in MT queue
2020-01-26T00:42:09.565Z,1579999329.565 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T00:42:10.331Z,1579999330.331 [DataOverHttps](IMPORTANT): SBD MTMSN=20200126T004158
2020-01-26T00:42:18.005Z,1579999338.005 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 1 hour;set profile_station.NeedCommsTime 15 minute;set profile_station.Lat 36.8 degree;set profile_station.Lon -121.82 degree" 4h5xe 1 2
2020-01-26T00:42:18.099Z,1579999338.099 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 1 hour;set profile_station.NeedCommsTime 15 minute;set profile_station.Lat 36.8 degree;set profile_station.Lon -121.82 degree" 4h5xe 1 2.000000
2020-01-26T00:42:18.099Z,1579999338.099 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=4h5xe
2020-01-26T00:42:18.100Z,1579999338.100 [CommandLine](IMPORTANT): Scheduled #3 (#1 of 2 with id='4h5xe'): "load Science/profile_station.xml;set profile_station.MissionTimeout 1 hour;set profile_station.NeedCommsTime 15 minute;set profile_station.Lat 36.8 degree;set profile_station.Lon -121.82 degree" ASAP
2020-01-26T00:42:20.011Z,1579999340.011 [DataOverHttps](IMPORTANT): SBD MTMSN=20200126T004208
2020-01-26T00:42:27.729Z,1579999347.729 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 30 meter;run " 4h5xe 2 2
2020-01-26T00:42:27.802Z,1579999347.802 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 30 meter;run " 4h5xe 2 2.000000
2020-01-26T00:42:27.802Z,1579999347.802 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=4h5xe
2020-01-26T00:42:27.803Z,1579999347.803 [CommandLine](IMPORTANT): Scheduled #4 (#2 of 2 with id='4h5xe'): "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 30 meter;run " ASAP
2020-01-26T00:42:27.924Z,1579999347.924 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2020-01-26T00:42:27.924Z,1579999347.924 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2020-01-26T00:42:28.020Z,1579999348.020 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2020-01-26T00:42:28.023Z,1579999348.023 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2020-01-26T00:42:28.026Z,1579999348.026 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2020-01-26T00:42:28.029Z,1579999348.029 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2020-01-26T00:42:28.032Z,1579999348.032 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2020-01-26T00:42:28.035Z,1579999348.035 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2020-01-26T00:42:28.038Z,1579999348.038 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2020-01-26T00:42:28.042Z,1579999348.042 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2020-01-26T00:42:28.044Z,1579999348.044 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2020-01-26T00:42:28.047Z,1579999348.047 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2020-01-26T00:42:28.051Z,1579999348.051 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2020-01-26T00:42:28.086Z,1579999348.086 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2020-01-26T00:42:28.089Z,1579999348.089 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2020-01-26T00:42:28.101Z,1579999348.101 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2020-01-26T00:42:28.104Z,1579999348.104 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2020-01-26T00:42:28.119Z,1579999348.119 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2020-01-26T00:42:28.120Z,1579999348.120 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2020-01-26T00:42:28.702Z,1579999348.702 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUS = 0 bool
2020-01-26T00:42:28.705Z,1579999348.705 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2020-01-26T00:42:28.724Z,1579999348.724 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSminPitch = -60.000000 arcdeg
2020-01-26T00:42:28.726Z,1579999348.726 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2020-01-26T00:42:28.741Z,1579999348.741 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2020-01-26T00:42:28.744Z,1579999348.744 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2020-01-26T00:42:28.747Z,1579999348.747 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2020-01-26T00:42:28.749Z,1579999348.749 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2020-01-26T00:42:28.752Z,1579999348.752 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2020-01-26T00:42:28.777Z,1579999348.777 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2020-01-26T00:42:28.780Z,1579999348.780 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2020-01-26T00:42:28.782Z,1579999348.782 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2020-01-26T00:42:28.807Z,1579999348.807 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2020-01-26T00:42:28.812Z,1579999348.812 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-01-26T00:42:28.839Z,1579999348.839 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2020-01-26T00:42:28.843Z,1579999348.843 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2020-01-26T00:42:28.846Z,1579999348.846 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 0.000000 bool
2020-01-26T00:42:28.855Z,1579999348.855 [MissionManager](INFO): DefineArg profile_station:Science.EnabledTurbulence_NPS = 0.000000 bool
2020-01-26T00:42:28.859Z,1579999348.859 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 0.000000 bool
2020-01-26T00:42:28.862Z,1579999348.862 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2020-01-26T00:42:28.895Z,1579999348.895 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2020-01-26T00:42:28.910Z,1579999348.910 [MissionManager](INFO): DefineArg profile_station:Science.EnabledISUS = 0.000000 bool
2020-01-26T00:42:28.926Z,1579999348.926 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2020-01-26T00:42:28.933Z,1579999348.933 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2020-01-26T00:42:28.936Z,1579999348.936 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2020-01-26T00:42:28.992Z,1579999348.992 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2020-01-26T00:42:29.011Z,1579999349.011 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2020-01-26T00:42:29.028Z,1579999349.028 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2020-01-26T00:42:29.044Z,1579999349.044 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2020-01-26T00:42:29.071Z,1579999349.071 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2020-01-26T00:42:29.086Z,1579999349.086 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2020-01-26T00:42:29.093Z,1579999349.093 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2020-01-26T00:42:29.096Z,1579999349.096 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2020-01-26T00:42:29.104Z,1579999349.104 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2020-01-26T00:42:29.116Z,1579999349.116 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2020-01-26T00:42:29.127Z,1579999349.127 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2020-01-26T00:42:29.143Z,1579999349.143 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2020-01-26T00:42:29.147Z,1579999349.147 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2020-01-26T00:42:29.167Z,1579999349.167 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2020-01-26T00:42:29.174Z,1579999349.174 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2020-01-26T00:42:29.181Z,1579999349.181 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2020-01-26T00:42:29.184Z,1579999349.184 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2020-01-26T00:42:29.204Z,1579999349.204 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2020-01-26T00:42:29.207Z,1579999349.207 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2020-01-26T00:42:29.224Z,1579999349.224 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2020-01-26T00:42:29.232Z,1579999349.232 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2020-01-26T00:42:29.240Z,1579999349.240 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2020-01-26T00:42:29.244Z,1579999349.244 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2020-01-26T00:42:29.263Z,1579999349.263 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2020-01-26T00:42:29.267Z,1579999349.267 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2020-01-26T00:42:29.278Z,1579999349.278 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2020-01-26T00:42:29.333Z,1579999349.333 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-01-26T00:42:29.436Z,1579999349.436 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-01-26T00:42:29.495Z,1579999349.495 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-01-26T00:42:29.529Z,1579999349.529 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-01-26T00:42:29.555Z,1579999349.555 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-01-26T00:42:29.590Z,1579999349.590 [MissionManager](INFO): RedefineArg profile_station:Science.SampleISUS = value:1 bool
2020-01-26T00:42:29.592Z,1579999349.592 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2020-01-26T00:42:29.592Z,1579999349.592 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-01-26T00:42:29.701Z,1579999349.701 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2020-01-26T00:42:29.704Z,1579999349.704 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2020-01-26T00:42:29.719Z,1579999349.719 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-01-26T00:42:29.726Z,1579999349.726 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2020-01-26T00:42:29.737Z,1579999349.737 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-01-26T00:42:29.740Z,1579999349.740 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2020-01-26T00:42:29.806Z,1579999349.806 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2020-01-26T00:42:29.812Z,1579999349.812 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-26T00:42:29.890Z,1579999349.890 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-01-26T00:42:29.943Z,1579999349.943 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2020-01-26T00:42:29.946Z,1579999349.946 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2020-01-26T00:42:29.966Z,1579999349.966 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-01-26T00:42:29.967Z,1579999349.967 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-01-26T00:42:29.974Z,1579999349.974 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-01-26T00:42:30.008Z,1579999350.008 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-01-26T00:42:30.044Z,1579999350.044 [profile_station:I.Pitch](DEBUG): Construct.
2020-01-26T00:42:30.070Z,1579999350.070 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2020-01-26T00:42:30.074Z,1579999350.074 [profile_station:K.SetSpeed](DEBUG): Construct.
2020-01-26T00:42:30.106Z,1579999350.106 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-01-26T00:42:30.146Z,1579999350.146 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-01-26T00:42:30.210Z,1579999350.210 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2020-01-26T00:42:30.244Z,1579999350.244 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2020-01-26T00:42:30.336Z,1579999350.336 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_station">

    <Description>
        This mission yoyos in a circle around a specified location.
    </Description>

<!-- Mission-wide settings -->

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

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications
    </Description><Units:minute/><Value>60</Value></DefineArg>

<!-- You probably need to change these. -->

    <DefineArg Name="Lat"><Description>
        The latitude of the center of the circle.
    </Description><Units:degree/><Value>36.806966</Value></DefineArg>

    <DefineArg Name="Lon"><Description>
        The longitude of the center of the circle.
    </Description><Units:degree/><Value>-121.824326</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius to circle at
    </Description><Units:meter/><Value>300</Value></DefineArg>

<!-- You probably do not need to change these. -->

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="YoYoPitch"><Description>
        Pitch (plus and minus) for yo-yo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

<!-- You are even less likely to need to change these. -->

    <DefineArg Name="CircleMaxError"><Description>
        If this distance away from the circle, drive straight towards (or away
        from the center). Otherwise, try to reduce distance from the ideal
        circle.
    </Description><Units:meter/><Value>100</Value></DefineArg>

    <DefineArg Name="CircleTurnToPort"><Description>
        If true, vehicle turns to the left around the center point. If false,
        vehicle turns to the right.
    </Description><False/></DefineArg>

    <DefineArg Name="KwpHeading"><Description>
        Used to relax waypoint cross-track error constant that is adjusted for
        docking. (You can override this setting by passing an argument.)
    </Description><Units:radian_per_meter/><Value>0.010</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>

<!-- Missions should almost always start with a timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

<!-- Most missions should use the NeedComms aggregate. -->

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

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

<!-- Missions should almost always start with standard safety envelopes; most missions should not expose the parameters of these envelopes. -->

    <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>

<!-- Check for additional instructions first. -->

    <Call Id="StartingMission" RefId="NeedComms"/>

<!-- Many missions will keep mass position and buoyancy volume fixed at defaults. -->

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

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
    </Guidance:Buoyancy>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
    </Guidance:SetSpeed>

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="CircleWrapper">

        <Sequence Repeat="4096"/>

        <Assign><Parallel/><Control:HorizontalControl.kwpHeading/><Arg Name="KwpHeading"/></Assign>

        <Guidance:Circle>
            <Sequence/>
            <Setting><Guidance:Circle.latitude/><Arg Name="Lat"/></Setting>
            <Setting><Guidance:Circle.longitude/><Arg Name="Lon"/></Setting>
            <Setting><Guidance:Circle.radius/><Arg Name="Radius"/></Setting>
            <Setting><Guidance:Circle.maxError/><Arg Name="CircleMaxError"/></Setting>
            <Setting><Guidance:Circle.turnToPort/><Arg Name="CircleTurnToPort"/></Setting>
        </Guidance:Circle>

    </Aggregate>

</Mission>


2020-01-26T00:42:30.341Z,1579999350.341 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2020-01-26T00:42:43.184Z,1579999363.184 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 1.000000 hour
2020-01-26T00:42:43.219Z,1579999363.219 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 15.000000 minute
2020-01-26T00:42:43.219Z,1579999363.219 [CommandLine](IMPORTANT): got command set profile_station.Lat 36.799999 degree
2020-01-26T00:42:43.220Z,1579999363.220 [CommandLine](IMPORTANT): got command set profile_station.Lon -121.820000 degree
2020-01-26T00:42:49.478Z,1579999369.478 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinDepth 5.000000 meter
2020-01-26T00:42:49.478Z,1579999369.478 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 20.000000 meter
2020-01-26T00:42:49.479Z,1579999369.479 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 30.000000 meter
2020-01-26T00:42:49.480Z,1579999369.480 [CommandLine](IMPORTANT): got command run 
2020-01-26T00:42:49.482Z,1579999369.482 [CommandLine](IMPORTANT): Running 
2020-01-26T00:42:49.643Z,1579999369.643 [Default] Stopped
2020-01-26T00:42:49.643Z,1579999369.643 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-26T00:42:49.643Z,1579999369.643 [Default:B.GoToSurface] Stopped
2020-01-26T00:42:49.643Z,1579999369.643 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-26T00:42:49.643Z,1579999369.643 [Default:CheckIn] Stopped
2020-01-26T00:42:49.643Z,1579999369.643 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T00:42:49.643Z,1579999369.643 [Default:CheckIn:C.Wait] Stopped
2020-01-26T00:42:49.643Z,1579999369.643 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T00:42:49.643Z,1579999369.643 [MissionManager](IMPORTANT): Started mission profile_station
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station](DEBUG): Aggregate::initialize profile_station
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science:A] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science:B] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science:C] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science:D] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science:E] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science:F] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science:G] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science:H] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science:Read_Oil] Running Loop=1
2020-01-26T00:42:49.644Z,1579999369.644 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-01-26T00:42:49.649Z,1579999369.649 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2020-01-26T00:42:49.649Z,1579999369.649 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-01-26T00:42:49.649Z,1579999369.649 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-01-26T00:42:49.651Z,1579999369.651 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2020-01-26T00:42:49.651Z,1579999369.651 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2020-01-26T00:42:49.651Z,1579999369.651 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-01-26T00:42:49.651Z,1579999369.651 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-01-26T00:42:49.652Z,1579999369.652 [profile_station:Science:PeakDetectNO3] Running Loop=1
2020-01-26T00:42:49.652Z,1579999369.652 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2020-01-26T00:42:49.652Z,1579999369.652 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-01-26T00:42:49.652Z,1579999369.652 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-01-26T00:42:49.654Z,1579999369.654 [profile_station:Science:PeakDetectOil] Running Loop=1
2020-01-26T00:42:49.654Z,1579999369.654 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2020-01-26T00:42:49.655Z,1579999369.655 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-01-26T00:42:49.655Z,1579999369.655 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-01-26T00:42:49.656Z,1579999369.656 [profile_station:Science:PeakDetectFDOM] Running Loop=1
2020-01-26T00:42:49.656Z,1579999369.656 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM
2020-01-26T00:42:49.656Z,1579999369.656 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-01-26T00:42:49.656Z,1579999369.656 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-01-26T00:42:49.658Z,1579999369.658 [profile_station:StandardEnvelopes] Running Loop=1
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:I.Pitch] Running Loop=1
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:I.Pitch](DEBUG): Initialize.
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:J.Buoyancy] Running Loop=1
2020-01-26T00:42:49.659Z,1579999369.659 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-01-26T00:42:49.660Z,1579999369.660 [profile_station:K.SetSpeed] Running Loop=1
2020-01-26T00:42:49.660Z,1579999369.660 [profile_station:K.SetSpeed](DEBUG): Initialize.
2020-01-26T00:42:49.660Z,1579999369.660 [profile_station:L.DepthEnvelope] Running Loop=1
2020-01-26T00:42:49.660Z,1579999369.660 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-01-26T00:42:49.660Z,1579999369.660 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-01-26T00:42:49.660Z,1579999369.660 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-01-26T00:42:49.660Z,1579999369.660 [profile_station:N.YoYo] Running Loop=1
2020-01-26T00:42:49.660Z,1579999369.660 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2020-01-26T00:42:49.669Z,1579999369.669 [profile_station:C] Running Loop=1
2020-01-26T00:42:49.669Z,1579999369.669 [profile_station:N.YoYo] Running Loop=1
2020-01-26T00:42:49.670Z,1579999369.670 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-01-26T00:42:49.670Z,1579999369.670 [profile_station:L.DepthEnvelope] Running Loop=1
2020-01-26T00:42:49.671Z,1579999369.671 [profile_station:K.SetSpeed] Running Loop=1
2020-01-26T00:42:49.671Z,1579999369.671 [profile_station:J.Buoyancy] Running Loop=1
2020-01-26T00:42:49.671Z,1579999369.671 [profile_station:I.Pitch] Running Loop=1
2020-01-26T00:42:49.672Z,1579999369.672 [profile_station:StandardEnvelopes] Running Loop=1
2020-01-26T00:42:49.672Z,1579999369.672 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-01-26T00:42:49.684Z,1579999369.684 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-01-26T00:42:49.684Z,1579999369.684 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-01-26T00:42:49.689Z,1579999369.689 [profile_station:C] Stopped
2020-01-26T00:42:49.690Z,1579999369.690 [profile_station:E] Running Loop=1
2020-01-26T00:42:49.690Z,1579999369.690 [profile_station:Science] Running Loop=1
2020-01-26T00:42:49.691Z,1579999369.691 [profile_station:Science:PeakDetectFDOM] Stopped
2020-01-26T00:42:49.691Z,1579999369.691 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM
2020-01-26T00:42:49.691Z,1579999369.691 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-01-26T00:42:49.691Z,1579999369.691 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-01-26T00:42:49.691Z,1579999369.691 [profile_station:Science:PeakDetectOil] Stopped
2020-01-26T00:42:49.691Z,1579999369.691 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:PeakDetectNO3] Stopped
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:HighestChlPeakReport] Stopped
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-01-26T00:42:49.692Z,1579999369.692 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-01-26T00:42:49.693Z,1579999369.693 [profile_station:Science:Read_Oil] Running Loop=1
2020-01-26T00:42:49.697Z,1579999369.697 [profile_station:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-01-26T00:42:49.698Z,1579999369.698 [profile_station:Science:H] Stopped
2020-01-26T00:42:49.698Z,1579999369.698 [profile_station:Science:G] Running Loop=1
2020-01-26T00:42:49.698Z,1579999369.698 [profile_station:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-01-26T00:42:49.698Z,1579999369.698 [profile_station:Science:F] Stopped
2020-01-26T00:42:49.698Z,1579999369.698 [profile_station:Science:E] Stopped
2020-01-26T00:42:49.698Z,1579999369.698 [profile_station:Science:D] Stopped
2020-01-26T00:42:49.698Z,1579999369.698 [profile_station:Science:C] Stopped
2020-01-26T00:42:49.699Z,1579999369.699 [profile_station:Science:B] Stopped
2020-01-26T00:42:49.699Z,1579999369.699 [profile_station:Science:A] Stopped
2020-01-26T00:42:49.959Z,1579999369.959 [WetLabsSeaOWL_UV_A](INFO): Powering up
2020-01-26T00:42:50.031Z,1579999370.031 [profile_station:E] Stopped
2020-01-26T00:42:50.031Z,1579999370.031 [profile_station:F] Running Loop=1
2020-01-26T00:42:50.432Z,1579999370.432 [profile_station:F] Stopped
2020-01-26T00:42:50.432Z,1579999370.432 [profile_station:G] Running Loop=1
2020-01-26T00:42:50.838Z,1579999370.838 [profile_station:G] Stopped
2020-01-26T00:42:50.838Z,1579999370.838 [profile_station:StartingMission] Running Loop=1
2020-01-26T00:42:50.838Z,1579999370.838 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2020-01-26T00:42:51.251Z,1579999371.251 [profile_station:NeedComms] Running Loop=1
2020-01-26T00:42:51.251Z,1579999371.251 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2020-01-26T00:42:51.252Z,1579999371.252 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2020-01-26T00:42:51.252Z,1579999371.252 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-26T00:42:51.252Z,1579999371.252 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-01-26T00:42:51.252Z,1579999371.252 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-01-26T00:42:51.257Z,1579999371.257 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-01-26T00:42:51.257Z,1579999371.257 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-26T00:42:51.258Z,1579999371.258 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-26T00:42:51.258Z,1579999371.258 [profile_station:NeedComms:A] Running Loop=1
2020-01-26T00:42:51.259Z,1579999371.259 [profile_station:NeedComms:A](INFO): last time_fix was: 1579999245.000000 second since 1970/01/01T00:00:00Z 
2020-01-26T00:42:51.259Z,1579999371.259 [profile_station:NeedComms:A] Stopped
2020-01-26T00:42:51.675Z,1579999371.675 [profile_station:NeedComms:C] Running Loop=1
2020-01-26T00:42:52.158Z,1579999372.158 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-26T00:42:53.647Z,1579999373.647 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004243.00,A,3648.25214,N,12148.45515,W,1.302,165.12,260120,,,D*79
2020-01-26T00:42:53.649Z,1579999373.649 [NAL9602](INFO): GPS fix at 20200126T004243: (36.804202, -121.807586)
2020-01-26T00:42:53.731Z,1579999373.731 [profile_station:NeedComms:C] Stopped
2020-01-26T00:42:53.731Z,1579999373.731 [profile_station:NeedComms:D] Running Loop=1
2020-01-26T00:42:54.135Z,1579999374.135 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-26T00:43:02.184Z,1579999382.184 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200126T000054/Courier0024.lzma
2020-01-26T00:43:04.190Z,1579999384.190 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0024.lzma.bak
2020-01-26T00:43:04.191Z,1579999384.191 [DataOverHttps](INFO): SBD MOMSN=12204354
2020-01-26T00:43:12.370Z,1579999392.370 [NAL9602](INFO): SBD MO Status=0, MOMSN=10299, MT Status=0, MTMSN=0
2020-01-26T00:43:12.370Z,1579999392.370 [NAL9602](INFO): No messages in MT queue
2020-01-26T00:43:22.559Z,1579999402.559 [DataOverHttps](INFO): Sending 745 bytes from file Logs/20200126T000054/Express0025.lzma
2020-01-26T00:43:24.562Z,1579999404.562 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0025.lzma.bak
2020-01-26T00:43:24.563Z,1579999404.563 [DataOverHttps](INFO): SBD MOMSN=12204357
2020-01-26T00:43:43.079Z,1579999423.079 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T00:43:43.482Z,1579999423.482 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200126T000054/Express0028.lzma
2020-01-26T00:43:45.486Z,1579999425.486 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0028.lzma.bak
2020-01-26T00:43:45.487Z,1579999425.487 [DataOverHttps](INFO): SBD MOMSN=12204382
2020-01-26T00:43:46.759Z,1579999426.759 [profile_station:NeedComms:D] Stopped
2020-01-26T00:43:46.759Z,1579999426.759 [profile_station:NeedComms:E] Running Loop=1
2020-01-26T00:43:47.169Z,1579999427.169 [profile_station:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-26T00:43:48.732Z,1579999428.732 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004338.00,A,3648.24721,N,12148.45280,W,0.253,16.09,260120,,,D*46
2020-01-26T00:43:48.735Z,1579999428.735 [NAL9602](INFO): GPS fix at 20200126T004338: (36.804120, -121.807547)
2020-01-26T00:43:48.829Z,1579999428.829 [profile_station:NeedComms:E] Stopped
2020-01-26T00:43:48.830Z,1579999428.830 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2020-01-26T00:43:48.831Z,1579999428.831 [profile_station:NeedComms] Stopped
2020-01-26T00:43:48.831Z,1579999428.831 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2020-01-26T00:43:48.831Z,1579999428.831 [profile_station:NeedComms:B.GoToSurface] Stopped
2020-01-26T00:43:48.831Z,1579999428.831 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-26T00:43:49.214Z,1579999429.214 [profile_station:StartingMission](INFO): Completed profile_station:StartingMission
2020-01-26T00:43:49.215Z,1579999429.215 [profile_station:StartingMission] Stopped
2020-01-26T00:43:49.215Z,1579999429.215 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2020-01-26T00:43:49.215Z,1579999429.215 [profile_station:CircleWrapper] Running Loop=1
2020-01-26T00:43:49.215Z,1579999429.215 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper
2020-01-26T00:43:49.215Z,1579999429.215 [profile_station:CircleWrapper:A] Running Loop=1
2020-01-26T00:43:49.215Z,1579999429.215 [profile_station:CircleWrapper:B.Circle] Running Loop=1
2020-01-26T00:43:49.215Z,1579999429.215 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent.
2020-01-26T00:43:49.587Z,1579999429.587 [profile_station:CircleWrapper:A] Running Loop=1
2020-01-26T00:43:53.628Z,1579999433.628 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:43:53.628Z,1579999433.628 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012517564908,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-26T00:44:00.885Z,1579999440.885 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-26T00:44:00.885Z,1579999440.885 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,     -361.51,     ,      -43.82,   0.00,739.98
2020-01-26T00:44:02.097Z,1579999442.097 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:44:02.097Z,1579999442.097 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012517565708,35.0, -0.1,   0.0,10
2020-01-26T00:44:05.725Z,1579999445.725 [RDI_Pathfinder](ERROR): Failed to parse:.00,   0.00,   0.00
2020-01-26T00:44:11.768Z,1579999451.768 [NAL9602](INFO): SBD MO Status=0, MOMSN=10300, MT Status=0, MTMSN=0
2020-01-26T00:44:11.768Z,1579999451.768 [NAL9602](INFO): No messages in MT queue
2020-01-26T00:44:15.431Z,1579999455.431 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 5.00 mm (3 active estimators).
2020-01-26T00:44:26.358Z,1579999466.358 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-26T00:44:26.358Z,1579999466.358 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,     -3 18,      -43.82,   0.00,765.97
2020-01-26T00:44:42.471Z,1579999482.471 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T00:44:45.712Z,1579999485.712 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-26T00:44:51.866Z,1579999491.866 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T00:44:56.886Z,1579999496.886 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.240086
2020-01-26T00:45:06.348Z,1579999506.348 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:45:06.348Z,1579999506.348 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012517580, -0.1,   0.0,1448.9,  0
2020-01-26T00:45:08.749Z,1579999508.749 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:45:08.749Z,1579999508.749 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-26T00:45:15.773Z,1579999515.773 [Radio_Surface](INFO): Powering down
2020-01-26T00:45:15.961Z,1579999515.961 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-01-26T00:45:24.523Z,1579999524.523 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 5.00 mm (4 active estimators).
2020-01-26T00:45:28.544Z,1579999528.544 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-01-26T00:45:28.544Z,1579999528.544 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 26.97
2020-01-26T00:45:48.457Z,1579999548.457 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-26T00:45:48.457Z,1579999548.457 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-26T00:45:48.457Z,1579999548.457 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-26T00:45:48.457Z,1579999548.457 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-26T00:45:48.573Z,1579999548.573 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-26T00:45:48.829Z,1579999548.829 [RDI_Pathfinder](INFO): Powering down
2020-01-26T00:45:49.624Z,1579999549.624 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-26T00:45:49.624Z,1579999549.624 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-26T00:46:02.886Z,1579999562.886 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-26T00:47:13.176Z,1579999633.176 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:47:13.176Z,1579999633.176 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012518000854,31,   0.0,1448.9,  0
2020-01-26T00:47:34.200Z,1579999654.200 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:47:34.200Z,1579999654.200 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-26T00:48:26.299Z,1579999706.299 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   -37,  +823,  -191,    -2,A
2020-01-26T00:48:47.321Z,1579999727.321 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:48:47.321Z,1579999727.321 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 399.36,   0.00, 309.53
2020-01-26T00:49:06.303Z,1579999746.303 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:49:06.303Z,1579999746.303 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 414.33,   0.00, 331.15
2020-01-26T00:49:14.388Z,1579999754.388 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:49:14.388Z,1579999754.388 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 363.43,   0.00, 342.46
2020-01-26T00:49:28.134Z,1579999768.134 [RDI_Pathfinder](ERROR): Failed to parse:
:00
2020-01-26T00:49:30.166Z,1579999770.166 [RDI_Pathfinder](ERROR): Failed to parse:
:B,-32768,-32768,V
2020-01-26T00:49:33.815Z,1579999773.815 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-26T00:49:33.815Z,1579999773.815 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,3:BE,-32768,-32768,-32768,V
2020-01-26T00:50:10.539Z,1579999810.539 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:50:10.539Z,1579999810.539 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-26T00:50:31.168Z,1579999831.168 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:50:31.168Z,1579999831.168 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32BE,-32768,-2768,V
2020-01-26T00:50:33.971Z,1579999833.971 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-26T00:50:35.245Z,1579999835.245 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-26T00:50:35.245Z,1579999835.245 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-26T00:50:35.245Z,1579999835.245 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-26T00:50:35.245Z,1579999835.245 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-26T00:50:35.369Z,1579999835.369 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-26T00:50:35.657Z,1579999835.657 [RDI_Pathfinder](INFO): Powering down
2020-01-26T00:50:36.516Z,1579999836.516 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-26T00:50:36.516Z,1579999836.516 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-26T00:50:49.722Z,1579999849.722 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-26T00:52:53.348Z,1579999973.348 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-26T00:53:14.351Z,1579999994.351 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-26T00:55:45.064Z,1580000145.064 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T00:55:45.065Z,1580000145.065 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00, :B,-32768,-3BS-32768,-32768,V
2020-01-26T00:56:24.679Z,1580000184.679 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-26T00:56:24.680Z,1580000184.680 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3278,V
2020-01-26T00:57:41.838Z,1580000261.838 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-26T00:57:51.116Z,1580000271.116 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:57:51.117Z,1580000271.117 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012518100, -0.1,   0.0  0
2020-01-26T00:58:25.045Z,1580000305.045 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T00:58:25.045Z,1580000305.045 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012518112039,35.0, -0.1,   0.0,144

2020-01-26T00:59:42.220Z,1580000382.220 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-26T00:59:42.221Z,1580000382.221 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3268,-32768,V
2020-01-26T00:59:56.786Z,1580000396.786 [profile_station:NeedComms] Running Loop=1
2020-01-26T00:59:56.786Z,1580000396.786 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2020-01-26T00:59:56.786Z,1580000396.786 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2020-01-26T00:59:56.786Z,1580000396.786 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-26T00:59:56.787Z,1580000396.787 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-01-26T00:59:56.787Z,1580000396.787 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-01-26T00:59:56.787Z,1580000396.787 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-01-26T00:59:56.788Z,1580000396.788 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-26T00:59:56.788Z,1580000396.788 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-26T00:59:56.788Z,1580000396.788 [profile_station:NeedComms:A] Running Loop=1
2020-01-26T00:59:56.790Z,1580000396.790 [profile_station:NeedComms:A](INFO): last time_fix was: 1579999418.000000 second since 1970/01/01T00:00:00Z 
2020-01-26T00:59:56.790Z,1580000396.790 [profile_station:NeedComms:A] Stopped
2020-01-26T01:00:08.661Z,1580000408.661 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T01:00:08.662Z,1580000408.662 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-26T01:00:34.374Z,1580000434.374 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T01:00:34.374Z,1580000434.374 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012518132939,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-26T01:00:43.400Z,1580000443.400 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T01:00:43.401Z,1580000443.401 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012518133839,35.0, -0.1,   0.0,1448.9,,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-26T01:00:47.012Z,1580000447.012 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T01:00:47.012Z,1580000447.012 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00, :BI,-32768,-32768,-32768,-32768,V
2020-01-26T01:01:24.678Z,1580000484.678 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-26T01:01:24.678Z,1580000484.678 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,
2020-01-26T01:01:42.171Z,1580000502.171 [profile_station:NeedComms:C] Running Loop=1
2020-01-26T01:01:42.785Z,1580000502.785 [Radio_Surface](INFO): Powering up
2020-01-26T01:01:48.114Z,1580000508.114 [DataOverHttps](INFO): Radio surface powered ON.
2020-01-26T01:02:11.806Z,1580000531.806 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=10301, MT Status=1, MTMSN=451
2020-01-26T01:02:11.807Z,1580000531.807 [NAL9602](INFO): Data available in MT queue
2020-01-26T01:02:12.329Z,1580000532.329 [NAL9602](INFO): Received command:stop
2020-01-26T01:02:12.390Z,1580000532.390 [CommandLine](IMPORTANT): got command stop
2020-01-26T01:02:12.390Z,1580000532.390 [CommandLine](IMPORTANT): Scheduling is paused
2020-01-26T01:02:12.390Z,1580000532.390 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-01-26T01:02:12.651Z,1580000532.651 [MissionManager](INFO): MissionManager is completed.
2020-01-26T01:02:12.651Z,1580000532.651 [MissionManager](INFO): Uninitializing Mission profile_station
2020-01-26T01:02:12.651Z,1580000532.651 [profile_station] Stopped
2020-01-26T01:02:12.651Z,1580000532.651 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2020-01-26T01:02:12.651Z,1580000532.651 [profile_station:Science] Stopped
2020-01-26T01:02:12.651Z,1580000532.651 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2020-01-26T01:02:12.651Z,1580000532.651 [profile_station:Science:G] Stopped
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:Science:Read_Oil] Stopped
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:Science:PeakDetectChl] Stopped
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:NeedComms] Stopped
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:NeedComms:B.GoToSurface] Stopped
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:NeedComms:C] Stopped
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:StandardEnvelopes] Stopped
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-01-26T01:02:12.652Z,1580000532.652 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-01-26T01:02:12.653Z,1580000532.653 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:I.Pitch] Stopped
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:J.Buoyancy] Stopped
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:K.SetSpeed] Stopped
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:L.DepthEnvelope] Stopped
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2020-01-26T01:02:12.657Z,1580000532.657 [profile_station:M.AltitudeEnvelope] Stopped
2020-01-26T01:02:12.658Z,1580000532.658 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-01-26T01:02:12.658Z,1580000532.658 [profile_station:N.YoYo] Stopped
2020-01-26T01:02:12.658Z,1580000532.658 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2020-01-26T01:02:12.658Z,1580000532.658 [profile_station:CircleWrapper] Stopped
2020-01-26T01:02:12.658Z,1580000532.658 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2020-01-26T01:02:12.658Z,1580000532.658 [profile_station:CircleWrapper:A] Stopped
2020-01-26T01:02:12.658Z,1580000532.658 [profile_station:CircleWrapper:B.Circle] Stopped
2020-01-26T01:02:12.658Z,1580000532.658 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize.
2020-01-26T01:02:12.901Z,1580000532.901 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-26T01:02:13.066Z,1580000533.066 [MissionManager](IMPORTANT): Started mission Default
2020-01-26T01:02:13.066Z,1580000533.066 [Default] Running Loop=1
2020-01-26T01:02:13.066Z,1580000533.066 [Default](DEBUG): Aggregate::initialize Default
2020-01-26T01:02:13.066Z,1580000533.066 [Default:B.GoToSurface] Running Loop=1
2020-01-26T01:02:13.066Z,1580000533.066 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-26T01:02:13.067Z,1580000533.067 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-26T01:02:13.067Z,1580000533.067 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-26T01:02:13.067Z,1580000533.067 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-26T01:02:13.067Z,1580000533.067 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-26T01:02:13.068Z,1580000533.068 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-26T01:02:13.068Z,1580000533.068 [Default:A.Wait] Running Loop=1
2020-01-26T01:02:13.068Z,1580000533.068 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-26T01:02:13.405Z,1580000533.405 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010203.00,A,3648.07276,N,12148.89627,W,1.030,244.86,260120,,,A*74
2020-01-26T01:02:13.407Z,1580000533.407 [NAL9602](INFO): GPS fix at 20200126T010203: (36.801213, -121.814938)
2020-01-26T01:02:13.455Z,1580000533.455 [UniversalFixResidualReporter](INFO): Fix residual: 15.4 %DT, over the last 998.5 m. Residual distance 154.2 m at bearing -101.9 degrees. Fix at (36.8012, -121.8149) with 733.2 m made good.
2020-01-26T01:02:18.245Z,1580000538.245 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T01:02:26.361Z,1580000546.361 [Default:A.Wait](INFO): Done Waiting.
2020-01-26T01:02:26.361Z,1580000546.361 [Default:A.Wait] Stopped
2020-01-26T01:02:26.361Z,1580000546.361 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T01:02:26.782Z,1580000546.782 [Default:CheckIn] Running Loop=1
2020-01-26T01:02:26.782Z,1580000546.782 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-26T01:02:26.783Z,1580000546.783 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-26T01:02:28.760Z,1580000548.760 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010218.00,A,3648.07161,N,12148.89599,W,1.302,239.98,260120,,,A*7A
2020-01-26T01:02:28.763Z,1580000548.763 [NAL9602](INFO): GPS fix at 20200126T010218: (36.801193, -121.814933)
2020-01-26T01:02:28.813Z,1580000548.813 [Default:CheckIn:Read_GPS] Stopped
2020-01-26T01:02:28.814Z,1580000548.814 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-26T01:02:51.830Z,1580000571.830 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T01:02:51.830Z,1580000571.830 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012518154739,35.0, -0.11448.9,  0
2020-01-26T01:02:53.385Z,1580000573.385 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T01:03:01.072Z,1580000581.072 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T01:03:28.525Z,1580000608.525 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T01:04:03.681Z,1580000643.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T01:04:38.821Z,1580000678.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-26T01:05:12.537Z,1580000712.537 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003188
2020-01-26T01:05:20.384Z,1580000720.384 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20200126T000054/Courier0030.lzma
2020-01-26T01:05:22.391Z,1580000722.391 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0030.lzma.bak
2020-01-26T01:05:22.391Z,1580000722.391 [DataOverHttps](IMPORTANT): SBD MOMSN=12204395, MTMSN=20200126T010510
2020-01-26T01:05:30.432Z,1580000730.432 [DataOverHttps](INFO): Received command:stop
2020-01-26T01:05:30.517Z,1580000730.517 [CommandLine](IMPORTANT): got command stop
2020-01-26T01:05:40.185Z,1580000740.185 [DataOverHttps](INFO): Sending 320 bytes from file Logs/20200126T000054/Express0031.lzma
2020-01-26T01:05:42.190Z,1580000742.190 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0031.lzma.bak
2020-01-26T01:05:42.190Z,1580000742.190 [DataOverHttps](INFO): SBD MOMSN=12204399
2020-01-26T01:05:56.035Z,1580000756.035 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T01:05:56.035Z,1580000756.035 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768-32768,-32768,-32768,V
2020-01-26T01:05:59.540Z,1580000759.540 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200126T000054/Courier0033.lzma
2020-01-26T01:06:01.546Z,1580000761.546 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0033.lzma.bak
2020-01-26T01:06:01.547Z,1580000761.547 [DataOverHttps](INFO): SBD MOMSN=12204405
2020-01-26T01:06:21.564Z,1580000781.564 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20200126T000054/Express0034.lzma
2020-01-26T01:06:23.570Z,1580000783.570 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0034.lzma.bak
2020-01-26T01:06:23.571Z,1580000783.571 [DataOverHttps](INFO): SBD MOMSN=12204407
2020-01-26T01:06:24.760Z,1580000784.760 [Default:CheckIn:Read_Iridium] Stopped
2020-01-26T01:06:24.760Z,1580000784.760 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-26T01:06:24.760Z,1580000784.760 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-26T01:07:31.831Z,1580000851.831 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-26T01:07:31.831Z,1580000851.831 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768V
2020-01-26T01:09:56.039Z,1580000996.039 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-26T01:09:56.040Z,1580000996.040 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,  BI,-32768,-32768,-32768,-32768,V
2020-01-26T01:10:15.018Z,1580001015.018 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-26T01:10:15.018Z,1580001015.018 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012518231039,35.0, -0.1,   0.,  0
2020-01-26T01:11:25.332Z,1580001085.332 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-26T01:11:25.332Z,1580001085.332 [Default:CheckIn:C.Wait] Stopped
2020-01-26T01:11:25.332Z,1580001085.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T01:11:25.333Z,1580001085.333 [Default:CheckIn:D] Running Loop=1
2020-01-26T01:11:25.748Z,1580001085.748 [Default:CheckIn:D] Stopped
2020-01-26T01:11:25.748Z,1580001085.748 [Default:CheckIn:E] Running Loop=1
2020-01-26T01:11:26.155Z,1580001086.155 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.211364 min 
2020-01-26T01:11:26.155Z,1580001086.155 [Default:CheckIn:E] Stopped
2020-01-26T01:11:26.155Z,1580001086.155 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-26T01:11:26.155Z,1580001086.155 [Default:CheckIn] Stopped
2020-01-26T01:11:26.156Z,1580001086.156 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T01:11:26.156Z,1580001086.156 [Default:CheckIn](INFO): Running loop #2
2020-01-26T01:11:26.156Z,1580001086.156 [Default:CheckIn] Running Loop=2
2020-01-26T01:11:26.156Z,1580001086.156 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-26T01:11:26.156Z,1580001086.156 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-26T01:11:28.126Z,1580001088.126 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011117.00,A,3648.03013,N,12148.87589,W,0.836,119.67,260120,,,A*74
2020-01-26T01:11:28.128Z,1580001088.128 [NAL9602](INFO): GPS fix at 20200126T011117: (36.800502, -121.814598)
2020-01-26T01:11:28.173Z,1580001088.173 [Default:CheckIn:Read_GPS] Stopped
2020-01-26T01:11:28.173Z,1580001088.173 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-26T01:11:36.356Z,1580001096.356 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200126T000054/Courier0036.lzma
2020-01-26T01:11:38.363Z,1580001098.363 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0036.lzma.bak
2020-01-26T01:11:38.363Z,1580001098.363 [DataOverHttps](INFO): SBD MOMSN=12204411
2020-01-26T01:11:53.188Z,1580001113.188 [NAL9602](INFO): SBD MO Status=0, MOMSN=10302, MT Status=0, MTMSN=0
2020-01-26T01:11:53.188Z,1580001113.188 [NAL9602](INFO): No messages in MT queue
2020-01-26T01:11:55.672Z,1580001115.672 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200126T000054/Express0037.lzma
2020-01-26T01:11:57.679Z,1580001117.679 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Express0037.lzma.bak
2020-01-26T01:11:57.679Z,1580001117.679 [DataOverHttps](INFO): SBD MOMSN=12204414
2020-01-26T01:11:58.869Z,1580001118.869 [Default:CheckIn:Read_Iridium] Stopped
2020-01-26T01:11:58.869Z,1580001118.869 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-26T01:11:58.870Z,1580001118.870 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-26T01:12:23.882Z,1580001143.882 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-26T01:15:36.661Z,1580001336.661 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-26T01:15:36.661Z,1580001336.661 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,
2020-01-26T01:16:37.662Z,1580001397.662 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-26T01:16:37.662Z,1580001397.662 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3768,-32768,V
2020-01-26T01:16:59.457Z,1580001419.457 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-26T01:16:59.457Z,1580001419.457 [Default:CheckIn:C.Wait] Stopped
2020-01-26T01:16:59.457Z,1580001419.457 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-26T01:16:59.458Z,1580001419.458 [Default:CheckIn:D] Running Loop=1
2020-01-26T01:16:59.871Z,1580001419.871 [Default:CheckIn:D] Stopped
2020-01-26T01:16:59.871Z,1580001419.871 [Default:CheckIn:E] Running Loop=1
2020-01-26T01:17:00.269Z,1580001420.269 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.780080 min 
2020-01-26T01:17:00.269Z,1580001420.269 [Default:CheckIn:E] Stopped
2020-01-26T01:17:00.270Z,1580001420.270 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-26T01:17:00.270Z,1580001420.270 [Default:CheckIn] Stopped
2020-01-26T01:17:00.270Z,1580001420.270 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T01:17:00.270Z,1580001420.270 [Default:CheckIn](INFO): Running loop #3
2020-01-26T01:17:00.270Z,1580001420.270 [Default:CheckIn] Running Loop=3
2020-01-26T01:17:00.270Z,1580001420.270 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-26T01:17:00.270Z,1580001420.270 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-26T01:17:02.261Z,1580001422.261 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011651.00,A,3648.00473,N,12148.86682,W,0.603,163.44,260120,,,A*7D
2020-01-26T01:17:02.263Z,1580001422.263 [NAL9602](INFO): GPS fix at 20200126T011651: (36.800079, -121.814447)
2020-01-26T01:17:02.311Z,1580001422.311 [Default:CheckIn:Read_GPS] Stopped
2020-01-26T01:17:02.311Z,1580001422.311 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-26T01:17:09.920Z,1580001429.920 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200126T000054/Courier0039.lzma
2020-01-26T01:17:11.926Z,1580001431.926 [DataOverHttps](INFO): Moved sent file to Logs/20200126T000054/Courier0039.lzma.bak
2020-01-26T01:17:11.927Z,1580001431.927 [DataOverHttps](IMPORTANT): SBD MOMSN=12204420, MTMSN=20200126T011700
2020-01-26T01:17:19.560Z,1580001439.560 [DataOverHttps](INFO): Received command:restart app
2020-01-26T01:17:19.570Z,1580001439.570 [CommandLine](IMPORTANT): got command restart application
2020-01-26T01:17:20.573Z,1580001440.573 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-26T01:17:20.573Z,1580001440.573 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:20.737Z,1580001440.737 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-26T01:17:20.737Z,1580001440.737 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:20.738Z,1580001440.738 [CommandLine](INFO): Join timeout helper Thread ID is 1834
2020-01-26T01:17:20.745Z,1580001440.745 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-26T01:17:20.745Z,1580001440.745 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:20.745Z,1580001440.745 [NavChartDb](INFO): Join timeout helper Thread ID is 1835
2020-01-26T01:17:20.953Z,1580001440.953 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-26T01:17:20.953Z,1580001440.953 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:20.969Z,1580001440.969 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-26T01:17:20.969Z,1580001440.969 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:20.969Z,1580001440.969 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1836
2020-01-26T01:17:21.141Z,1580001441.141 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-26T01:17:21.141Z,1580001441.141 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-26T01:17:21.142Z,1580001441.142 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:21.149Z,1580001441.149 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-26T01:17:21.149Z,1580001441.149 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:21.149Z,1580001441.149 [Radio_Surface](INFO): Join timeout helper Thread ID is 1837
2020-01-26T01:17:21.381Z,1580001441.381 [Radio_Surface](INFO): Powering down
2020-01-26T01:17:21.382Z,1580001441.382 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-26T01:17:21.382Z,1580001441.382 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:21.385Z,1580001441.385 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-26T01:17:21.385Z,1580001441.385 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:21.385Z,1580001441.385 [DataOverHttps](INFO): Join timeout helper Thread ID is 1838
2020-01-26T01:17:21.565Z,1580001441.565 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-26T01:17:21.568Z,1580001441.568 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:21.586Z,1580001441.586 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-26T01:17:21.586Z,1580001441.586 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:21.586Z,1580001441.586 [logger](INFO): Join timeout helper Thread ID is 1839
2020-01-26T01:17:21.589Z,1580001441.589 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-26T01:17:21.589Z,1580001441.589 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:21.606Z,1580001441.606 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-26T01:17:21.606Z,1580001441.606 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:21.606Z,1580001441.606 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-26T01:17:21.606Z,1580001441.606 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:21.606Z,1580001441.606 [controlThread](INFO): Join timeout helper Thread ID is 1840
2020-01-26T01:17:21.629Z,1580001441.629 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-26T01:17:21.629Z,1580001441.629 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-26T01:17:21.630Z,1580001441.630 [AHRS_M2](INFO): Powering down
2020-01-26T01:17:21.701Z,1580001441.701 [DDM](INFO): Powering down
2020-01-26T01:17:21.795Z,1580001441.795 [DUSBL_Hydroid](INFO): Powering down
2020-01-26T01:17:21.865Z,1580001441.865 [Micromodem](INFO): Powering down
2020-01-26T01:17:21.961Z,1580001441.961 [NAL9602](INFO): Powering down
2020-01-26T01:17:22.033Z,1580001442.033 [RDI_Pathfinder](INFO): Powering down
2020-01-26T01:17:22.034Z,1580001442.034 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-26T01:17:22.035Z,1580001442.035 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-26T01:17:22.036Z,1580001442.036 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-26T01:17:22.036Z,1580001442.036 [MissionManager](INFO): Uninitializing Mission Default
2020-01-26T01:17:22.036Z,1580001442.036 [Default] Stopped
2020-01-26T01:17:22.036Z,1580001442.036 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-26T01:17:22.036Z,1580001442.036 [Default:B.GoToSurface] Stopped
2020-01-26T01:17:22.037Z,1580001442.037 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-26T01:17:22.037Z,1580001442.037 [Default:CheckIn] Stopped
2020-01-26T01:17:22.037Z,1580001442.037 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-26T01:17:22.037Z,1580001442.037 [Default:CheckIn:Read_Iridium] Stopped
2020-01-26T01:17:22.039Z,1580001442.039 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-26T01:17:22.039Z,1580001442.039 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-26T01:17:22.040Z,1580001442.040 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-26T01:17:22.040Z,1580001442.040 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-26T01:17:22.040Z,1580001442.040 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-26T01:17:22.040Z,1580001442.040 [BuoyancyServo](INFO): Powering down
2020-01-26T01:17:22.053Z,1580001442.053 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-26T01:17:22.053Z,1580001442.053 [ElevatorServo](INFO): Powering down
2020-01-26T01:17:22.054Z,1580001442.054 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-26T01:17:22.054Z,1580001442.054 [MassServo](INFO): Powering down
2020-01-26T01:17:22.055Z,1580001442.055 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-26T01:17:22.055Z,1580001442.055 [RudderServo](INFO): Powering down
2020-01-26T01:17:22.056Z,1580001442.056 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-26T01:17:22.056Z,1580001442.056 [ThrusterServo](INFO): Powering down
2020-01-26T01:17:22.056Z,1580001442.056 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-26T01:17:22.057Z,1580001442.057 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-26T01:17:22.057Z,1580001442.057 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-26T01:17:22.057Z,1580001442.057 [CBIT](DEBUG): Powering off loads.
2020-01-26T01:17:22.069Z,1580001442.069 [CBIT](DEBUG): Disabling WDT.
2020-01-26T01:17:22.081Z,1580001442.081 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-26T01:17:22.082Z,1580001442.082 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:22.128Z,1580001442.128 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:22.139Z,1580001442.139 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:22.181Z,1580001442.181 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:22.238Z,1580001442.238 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-26T01:17:22.309Z,1580001442.309 [logger ThreadHandler](INFO): Thread cancelled.