2020-01-05T03:15:42.176Z,1578194142.176 [CommandLine](IMPORTANT): got command restart logs
2020-01-05T03:16:20.366Z,1578194180.366 [CommandLine](IMPORTANT): got command failComponent
2020-01-05T03:16:20.367Z,1578194180.367 [CommandLine](IMPORTANT): Failed components:
2020-01-05T03:16:20.367Z,1578194180.367 [CommandLine](IMPORTANT): No failed Components.
2020-01-05T03:18:34.003Z,1578194314.003 [CommandLine](IMPORTANT): got command load
2020-01-05T03:18:34.003Z,1578194314.003 [CommandLine](FAULT): Incomplete syntax. Try: help load
2020-01-05T03:20:37.544Z,1578194437.544 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-05T03:20:37.544Z,1578194437.544 [Default:CheckIn:C.Wait] Stopped
2020-01-05T03:20:37.544Z,1578194437.544 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-05T03:20:37.544Z,1578194437.544 [Default:CheckIn:D] Running Loop=1
2020-01-05T03:20:37.940Z,1578194437.940 [Default:CheckIn:D] Stopped
2020-01-05T03:20:37.941Z,1578194437.941 [Default:CheckIn:E] Running Loop=1
2020-01-05T03:20:38.332Z,1578194438.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.523502 min 
2020-01-05T03:20:38.332Z,1578194438.332 [Default:CheckIn:E] Stopped
2020-01-05T03:20:38.332Z,1578194438.332 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-05T03:20:38.332Z,1578194438.332 [Default:CheckIn] Stopped
2020-01-05T03:20:38.332Z,1578194438.332 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-05T03:20:38.332Z,1578194438.332 [Default:CheckIn](INFO): Running loop #3
2020-01-05T03:20:38.332Z,1578194438.332 [Default:CheckIn] Running Loop=3
2020-01-05T03:20:38.337Z,1578194438.337 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-05T03:20:38.337Z,1578194438.337 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-05T03:20:40.307Z,1578194440.307 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,032016.00,A,3647.20533,N,12151.60669,W,0.194,186.49,050120,,,D*7F
2020-01-05T03:20:40.310Z,1578194440.310 [NAL9602](INFO): GPS fix at 20200105T032016: (36.786755, -121.860112)
2020-01-05T03:20:40.411Z,1578194440.411 [Default:CheckIn:Read_GPS] Stopped
2020-01-05T03:20:40.411Z,1578194440.411 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-05T03:20:52.576Z,1578194452.576 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20200105T025917/Courier0010.lzma
2020-01-05T03:20:54.582Z,1578194454.582 [DataOverHttps](INFO): Moved sent file to Logs/20200105T025917/Courier0010.lzma.bak
2020-01-05T03:20:54.583Z,1578194454.583 [DataOverHttps](INFO): SBD MOMSN=12184828
2020-01-05T03:20:57.823Z,1578194457.823 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=18812, MT Status=1, MTMSN=2219
2020-01-05T03:20:57.824Z,1578194457.824 [NAL9602](INFO): Data available in MT queue
2020-01-05T03:20:58.410Z,1578194458.410 [NAL9602](INFO): Received command:! echo i > /dev/loadB6
2020-01-05T03:20:58.453Z,1578194458.453 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB6
2020-01-05T03:21:28.926Z,1578194488.926 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-05T03:21:56.973Z,1578194516.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:22:28.105Z,1578194548.105 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:22:59.229Z,1578194579.229 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:23:30.353Z,1578194610.353 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:23:55.453Z,1578194635.453 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.871597
2020-01-05T03:24:09.787Z,1578194649.787 [NAL9602](INFO): SBD MO Status=1, MOMSN=18813, MT Status=0, MTMSN=0
2020-01-05T03:24:09.853Z,1578194649.853 [NAL9602](INFO): Sent 212 bytes from file Logs/20200105T031542/Courier0000.lzma
2020-01-05T03:24:09.853Z,1578194649.853 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:24:17.519Z,1578194657.519 [NAL9602](INFO): SBD MO Status=1, MOMSN=18814, MT Status=0, MTMSN=0
2020-01-05T03:24:17.577Z,1578194657.577 [NAL9602](INFO): Sent 146 bytes from file Logs/20200105T025917/Express0011.lzma
2020-01-05T03:24:17.577Z,1578194657.577 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:24:26.601Z,1578194666.601 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:24:30.891Z,1578194670.891 [NAL9602](INFO): SBD MO Status=1, MOMSN=18815, MT Status=0, MTMSN=0
2020-01-05T03:24:30.953Z,1578194670.953 [NAL9602](INFO): Sent 332 bytes from file Logs/20200105T031542/Express0001.lzma
2020-01-05T03:24:30.953Z,1578194670.953 [NAL9602](INFO): Packets left to send: 1
2020-01-05T03:24:52.399Z,1578194692.399 [NAL9602](INFO): SBD MO Status=1, MOMSN=18816, MT Status=0, MTMSN=0
2020-01-05T03:24:52.449Z,1578194692.449 [NAL9602](INFO): Sent 85 bytes from file Logs/20200105T031542/Express0001.lzma
2020-01-05T03:24:52.449Z,1578194692.449 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:24:57.757Z,1578194697.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:25:00.065Z,1578194700.065 [NAL9602](INFO): SBD MO Status=0, MOMSN=18817, MT Status=0, MTMSN=0
2020-01-05T03:25:00.153Z,1578194700.153 [Default:CheckIn:Read_Iridium] Stopped
2020-01-05T03:25:00.153Z,1578194700.153 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-05T03:25:00.153Z,1578194700.153 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-05T03:25:28.885Z,1578194728.885 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:25:30.768Z,1578194730.768 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-05T03:26:00.033Z,1578194760.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:26:31.157Z,1578194791.157 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:27:02.289Z,1578194822.289 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:27:33.413Z,1578194853.413 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:28:04.537Z,1578194884.537 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:28:35.673Z,1578194915.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:29:06.797Z,1578194946.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:29:37.929Z,1578194977.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:30:00.673Z,1578195000.673 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-05T03:30:00.673Z,1578195000.673 [Default:CheckIn:C.Wait] Stopped
2020-01-05T03:30:00.673Z,1578195000.673 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-05T03:30:00.673Z,1578195000.673 [Default:CheckIn:D] Running Loop=1
2020-01-05T03:30:01.079Z,1578195001.079 [Default:CheckIn:D] Stopped
2020-01-05T03:30:01.079Z,1578195001.079 [Default:CheckIn:E] Running Loop=1
2020-01-05T03:30:01.482Z,1578195001.482 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.909149 min 
2020-01-05T03:30:01.482Z,1578195001.482 [Default:CheckIn:E] Stopped
2020-01-05T03:30:01.483Z,1578195001.483 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-05T03:30:01.483Z,1578195001.483 [Default:CheckIn] Stopped
2020-01-05T03:30:01.483Z,1578195001.483 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-05T03:30:01.483Z,1578195001.483 [Default:CheckIn](INFO): Running loop #4
2020-01-05T03:30:01.483Z,1578195001.483 [Default:CheckIn] Running Loop=4
2020-01-05T03:30:01.483Z,1578195001.483 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-05T03:30:01.483Z,1578195001.483 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-05T03:30:03.468Z,1578195003.468 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,032938.00,A,3647.17219,N,12151.59716,W,0.914,239.36,050120,,,D*7D
2020-01-05T03:30:03.470Z,1578195003.470 [NAL9602](INFO): GPS fix at 20200105T032938: (36.786203, -121.859953)
2020-01-05T03:30:03.541Z,1578195003.541 [Default:CheckIn:Read_GPS] Stopped
2020-01-05T03:30:03.541Z,1578195003.541 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-05T03:30:09.089Z,1578195009.089 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:30:25.755Z,1578195025.755 [NAL9602](INFO): SBD MO Status=2, MOMSN=18818, MT Status=2, MTMSN=0
2020-01-05T03:30:25.755Z,1578195025.755 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T03:30:40.221Z,1578195040.221 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:30:51.667Z,1578195051.667 [NAL9602](INFO): SBD MO Status=2, MOMSN=18818, MT Status=2, MTMSN=0
2020-01-05T03:30:51.667Z,1578195051.667 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T03:31:09.085Z,1578195069.085 [NAL9602](INFO): SBD MO Status=2, MOMSN=18818, MT Status=2, MTMSN=0
2020-01-05T03:31:09.085Z,1578195069.085 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T03:31:11.353Z,1578195071.353 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:31:35.015Z,1578195095.015 [NAL9602](INFO): SBD MO Status=2, MOMSN=18818, MT Status=2, MTMSN=0
2020-01-05T03:31:35.015Z,1578195095.015 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T03:31:42.509Z,1578195102.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:32:04.188Z,1578195124.188 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=18818, MT Status=1, MTMSN=2220
2020-01-05T03:32:04.237Z,1578195124.237 [NAL9602](INFO): Sent 63 bytes from file Logs/20200105T031542/Courier0003.lzma
2020-01-05T03:32:04.238Z,1578195124.238 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:32:04.769Z,1578195124.769 [NAL9602](INFO): Received command:! echo 1 > /dev/loadB6
2020-01-05T03:32:04.788Z,1578195124.788 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB6
2020-01-05T03:32:13.637Z,1578195133.637 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:32:18.791Z,1578195138.791 [NAL9602](INFO): SBD MO Status=1, MOMSN=18819, MT Status=0, MTMSN=0
2020-01-05T03:32:18.853Z,1578195138.853 [NAL9602](INFO): Sent 239 bytes from file Logs/20200105T031542/Express0004.lzma
2020-01-05T03:32:18.854Z,1578195138.854 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:32:35.359Z,1578195155.359 [NAL9602](INFO): SBD MO Status=2, MOMSN=18820, MT Status=2, MTMSN=0
2020-01-05T03:32:35.359Z,1578195155.359 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T03:32:44.762Z,1578195164.762 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:32:47.079Z,1578195167.079 [NAL9602](INFO): SBD MO Status=0, MOMSN=18820, MT Status=0, MTMSN=0
2020-01-05T03:33:00.861Z,1578195180.861 [NAL9602](INFO): SBD MO Status=1, MOMSN=18821, MT Status=0, MTMSN=0
2020-01-05T03:33:00.913Z,1578195180.913 [NAL9602](INFO): Sent 25 bytes from file Logs/20200105T031542/Courier0006.lzma
2020-01-05T03:33:00.913Z,1578195180.913 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:33:12.655Z,1578195192.655 [NAL9602](INFO): SBD MO Status=1, MOMSN=18822, MT Status=0, MTMSN=0
2020-01-05T03:33:12.710Z,1578195192.710 [NAL9602](INFO): Sent 151 bytes from file Logs/20200105T031542/Express0007.lzma
2020-01-05T03:33:12.710Z,1578195192.710 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:33:15.889Z,1578195195.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:33:22.331Z,1578195202.331 [NAL9602](INFO): SBD MO Status=0, MOMSN=18823, MT Status=0, MTMSN=0
2020-01-05T03:33:22.425Z,1578195202.425 [Default:CheckIn:Read_Iridium] Stopped
2020-01-05T03:33:22.425Z,1578195202.425 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-05T03:33:22.425Z,1578195202.425 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-05T03:33:47.013Z,1578195227.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:33:53.038Z,1578195233.038 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-05T03:34:18.145Z,1578195258.145 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:34:49.270Z,1578195289.270 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:35:20.401Z,1578195320.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:35:50.558Z,1578195350.558 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003755
2020-01-05T03:38:23.085Z,1578195503.085 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-05T03:38:23.085Z,1578195503.085 [Default:CheckIn:C.Wait] Stopped
2020-01-05T03:38:23.085Z,1578195503.085 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-05T03:38:23.085Z,1578195503.085 [Default:CheckIn:D] Running Loop=1
2020-01-05T03:38:23.497Z,1578195503.497 [Default:CheckIn:D] Stopped
2020-01-05T03:38:23.497Z,1578195503.497 [Default:CheckIn:E] Running Loop=1
2020-01-05T03:38:23.878Z,1578195503.878 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.282780 min 
2020-01-05T03:38:23.878Z,1578195503.878 [Default:CheckIn:E] Stopped
2020-01-05T03:38:23.878Z,1578195503.878 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-05T03:38:23.878Z,1578195503.878 [Default:CheckIn] Stopped
2020-01-05T03:38:23.878Z,1578195503.878 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-05T03:38:23.878Z,1578195503.878 [Default:CheckIn](INFO): Running loop #5
2020-01-05T03:38:23.879Z,1578195503.879 [Default:CheckIn] Running Loop=5
2020-01-05T03:38:23.879Z,1578195503.879 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-05T03:38:23.879Z,1578195503.879 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-05T03:38:25.876Z,1578195505.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033800.00,A,3647.14139,N,12151.58133,W,0.914,206.23,050120,,,D*7C
2020-01-05T03:38:25.897Z,1578195505.897 [NAL9602](INFO): GPS fix at 20200105T033800: (36.785690, -121.859689)
2020-01-05T03:38:25.949Z,1578195505.949 [Default:CheckIn:Read_GPS] Stopped
2020-01-05T03:38:25.950Z,1578195505.950 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-05T03:38:30.684Z,1578195510.684 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200105T031542/Courier0009.lzma
2020-01-05T03:38:32.690Z,1578195512.690 [DataOverHttps](INFO): Moved sent file to Logs/20200105T031542/Courier0009.lzma.bak
2020-01-05T03:38:32.690Z,1578195512.690 [DataOverHttps](INFO): SBD MOMSN=12184856
2020-01-05T03:38:51.381Z,1578195531.381 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20200105T031542/Express0010.lzma
2020-01-05T03:38:53.386Z,1578195533.386 [DataOverHttps](INFO): Moved sent file to Logs/20200105T031542/Express0010.lzma.bak
2020-01-05T03:38:53.387Z,1578195533.387 [DataOverHttps](INFO): SBD MOMSN=12184858
2020-01-05T03:38:54.680Z,1578195534.680 [Default:CheckIn:Read_Iridium] Stopped
2020-01-05T03:38:54.680Z,1578195534.680 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-05T03:38:54.685Z,1578195534.685 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-05T03:39:23.724Z,1578195563.724 [NAL9602](INFO): SBD MO Status=2, MOMSN=18824, MT Status=2, MTMSN=0
2020-01-05T03:39:23.724Z,1578195563.724 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T03:39:25.513Z,1578195565.513 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:39:33.015Z,1578195573.015 [NAL9602](INFO): SBD MO Status=0, MOMSN=18824, MT Status=0, MTMSN=0
2020-01-05T03:39:33.016Z,1578195573.016 [NAL9602](INFO): No messages in MT queue
2020-01-05T03:40:03.718Z,1578195603.718 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-05T03:40:58.706Z,1578195658.706 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:41:29.829Z,1578195689.829 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:42:00.953Z,1578195720.953 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:42:32.077Z,1578195752.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:42:44.126Z,1578195764.126 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.107712
2020-01-05T03:43:33.321Z,1578195813.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:43:55.243Z,1578195835.243 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-05T03:43:55.243Z,1578195835.243 [Default:CheckIn:C.Wait] Stopped
2020-01-05T03:43:55.243Z,1578195835.243 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-05T03:43:55.243Z,1578195835.243 [Default:CheckIn:D] Running Loop=1
2020-01-05T03:43:55.656Z,1578195835.656 [Default:CheckIn:D] Stopped
2020-01-05T03:43:55.656Z,1578195835.656 [Default:CheckIn:E] Running Loop=1
2020-01-05T03:43:56.047Z,1578195836.047 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.818766 min 
2020-01-05T03:43:56.047Z,1578195836.047 [Default:CheckIn:E] Stopped
2020-01-05T03:43:56.047Z,1578195836.047 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-05T03:43:56.047Z,1578195836.047 [Default:CheckIn] Stopped
2020-01-05T03:43:56.047Z,1578195836.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-05T03:43:56.048Z,1578195836.048 [Default:CheckIn](INFO): Running loop #6
2020-01-05T03:43:56.048Z,1578195836.048 [Default:CheckIn] Running Loop=6
2020-01-05T03:43:56.048Z,1578195836.048 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-05T03:43:56.048Z,1578195836.048 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-05T03:43:58.043Z,1578195838.043 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034331.00,A,3647.12204,N,12151.57350,W,2.294,116.14,050120,,,D*76
2020-01-05T03:43:58.045Z,1578195838.045 [NAL9602](INFO): GPS fix at 20200105T034331: (36.785367, -121.859558)
2020-01-05T03:43:58.070Z,1578195838.070 [Default:CheckIn:Read_GPS] Stopped
2020-01-05T03:43:58.070Z,1578195838.070 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-05T03:44:04.445Z,1578195844.445 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:44:27.180Z,1578195867.180 [NAL9602](INFO): SBD MO Status=2, MOMSN=18825, MT Status=2, MTMSN=0
2020-01-05T03:44:27.180Z,1578195867.180 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T03:44:35.569Z,1578195875.569 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:44:46.234Z,1578195886.234 [NAL9602](INFO): SBD MO Status=1, MOMSN=18825, MT Status=0, MTMSN=0
2020-01-05T03:44:46.289Z,1578195886.289 [NAL9602](INFO): Sent 63 bytes from file Logs/20200105T031542/Courier0012.lzma
2020-01-05T03:44:46.289Z,1578195886.289 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:45:06.709Z,1578195906.709 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:45:10.951Z,1578195910.951 [NAL9602](INFO): SBD MO Status=2, MOMSN=18826, MT Status=2, MTMSN=0
2020-01-05T03:45:10.951Z,1578195910.951 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T03:45:30.412Z,1578195930.412 [NAL9602](INFO): SBD MO Status=1, MOMSN=18826, MT Status=0, MTMSN=0
2020-01-05T03:45:30.470Z,1578195930.470 [NAL9602](INFO): Sent 130 bytes from file Logs/20200105T031542/Express0013.lzma
2020-01-05T03:45:30.470Z,1578195930.470 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:45:37.837Z,1578195937.837 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:45:40.511Z,1578195940.511 [NAL9602](INFO): SBD MO Status=0, MOMSN=18827, MT Status=0, MTMSN=0
2020-01-05T03:45:40.618Z,1578195940.618 [Default:CheckIn:Read_Iridium] Stopped
2020-01-05T03:45:40.618Z,1578195940.618 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-05T03:45:40.618Z,1578195940.618 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-05T03:46:08.962Z,1578195968.962 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:46:11.213Z,1578195971.213 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-05T03:46:40.085Z,1578196000.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:47:11.238Z,1578196031.238 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:47:42.361Z,1578196062.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:48:13.489Z,1578196093.489 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:48:44.613Z,1578196124.613 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:49:15.737Z,1578196155.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:49:46.889Z,1578196186.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:50:18.021Z,1578196218.021 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:50:41.114Z,1578196241.114 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-05T03:50:41.115Z,1578196241.115 [Default:CheckIn:C.Wait] Stopped
2020-01-05T03:50:41.115Z,1578196241.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-05T03:50:41.115Z,1578196241.115 [Default:CheckIn:D] Running Loop=1
2020-01-05T03:50:41.539Z,1578196241.539 [Default:CheckIn:D] Stopped
2020-01-05T03:50:41.539Z,1578196241.539 [Default:CheckIn:E] Running Loop=1
2020-01-05T03:50:41.913Z,1578196241.913 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.583036 min 
2020-01-05T03:50:41.913Z,1578196241.913 [Default:CheckIn:E] Stopped
2020-01-05T03:50:41.913Z,1578196241.913 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-05T03:50:41.913Z,1578196241.913 [Default:CheckIn] Stopped
2020-01-05T03:50:41.913Z,1578196241.913 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-05T03:50:41.914Z,1578196241.914 [Default:CheckIn](INFO): Running loop #7
2020-01-05T03:50:41.914Z,1578196241.914 [Default:CheckIn] Running Loop=7
2020-01-05T03:50:41.914Z,1578196241.914 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-05T03:50:41.914Z,1578196241.914 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-05T03:50:43.916Z,1578196243.916 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,035017.00,A,3647.09828,N,12151.56653,W,0.272,157.53,050120,,,D*75
2020-01-05T03:50:43.932Z,1578196243.932 [NAL9602](INFO): GPS fix at 20200105T035017: (36.784971, -121.859442)
2020-01-05T03:50:43.956Z,1578196243.956 [Default:CheckIn:Read_GPS] Stopped
2020-01-05T03:50:43.956Z,1578196243.956 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-05T03:50:49.145Z,1578196249.145 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:51:17.924Z,1578196277.924 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=18828, MT Status=1, MTMSN=2221
2020-01-05T03:51:17.977Z,1578196277.977 [NAL9602](INFO): Sent 63 bytes from file Logs/20200105T031542/Courier0015.lzma
2020-01-05T03:51:17.977Z,1578196277.977 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:51:18.833Z,1578196278.833 [NAL9602](INFO): Received command:load Science/sci2.xml;set sci2.NeedCommsTime 30 minute;set sci2.Lat1 36.797 degree;set sci2.Lon1 -121.847 degree;set sci2.YoYoMinDepth 5 meter;set sci2.YoYoMaxDepth 30 meter;set sci2.MaxDepth 37 meter;run 
2020-01-05T03:51:18.862Z,1578196278.862 [CommandLine](IMPORTANT): got command load ./Missions/Science/sci2.xml
2020-01-05T03:51:18.862Z,1578196278.862 [MissionManager](INFO): Loading Mission: ./Missions/Science/sci2.xml
2020-01-05T03:51:18.986Z,1578196278.986 [MissionManager](INFO): DefineArg sci2.MissionTimeout = 2.000000 h
2020-01-05T03:51:18.989Z,1578196278.989 [MissionManager](INFO): DefineArg sci2.NeedCommsTime = 60.000000 min
2020-01-05T03:51:18.992Z,1578196278.992 [MissionManager](INFO): DefineArg sci2.Repeat = 1.000000 count
2020-01-05T03:51:18.996Z,1578196278.996 [MissionManager](INFO): DefineArg sci2.Lat1 = nan arcdeg
2020-01-05T03:51:19.042Z,1578196279.042 [MissionManager](INFO): DefineArg sci2.Lon1 = nan arcdeg
2020-01-05T03:51:19.044Z,1578196279.044 [MissionManager](INFO): DefineArg sci2.Lat2 = nan arcdeg
2020-01-05T03:51:19.047Z,1578196279.047 [MissionManager](INFO): DefineArg sci2.Lon2 = nan arcdeg
2020-01-05T03:51:19.066Z,1578196279.066 [MissionManager](INFO): DefineArg sci2.Lat3 = nan arcdeg
2020-01-05T03:51:19.068Z,1578196279.068 [MissionManager](INFO): DefineArg sci2.Lon3 = nan arcdeg
2020-01-05T03:51:19.071Z,1578196279.071 [MissionManager](INFO): DefineArg sci2.Lat4 = nan arcdeg
2020-01-05T03:51:19.103Z,1578196279.103 [MissionManager](INFO): DefineArg sci2.Lon4 = nan arcdeg
2020-01-05T03:51:19.106Z,1578196279.106 [MissionManager](INFO): DefineArg sci2.Lat5 = nan arcdeg
2020-01-05T03:51:19.109Z,1578196279.109 [MissionManager](INFO): DefineArg sci2.Lon5 = nan arcdeg
2020-01-05T03:51:19.111Z,1578196279.111 [MissionManager](INFO): DefineArg sci2.Lat6 = nan arcdeg
2020-01-05T03:51:19.130Z,1578196279.130 [MissionManager](INFO): DefineArg sci2.Lon6 = nan arcdeg
2020-01-05T03:51:19.133Z,1578196279.133 [MissionManager](INFO): DefineArg sci2.Lat7 = nan arcdeg
2020-01-05T03:51:19.136Z,1578196279.136 [MissionManager](INFO): DefineArg sci2.Lon7 = nan arcdeg
2020-01-05T03:51:19.154Z,1578196279.154 [MissionManager](INFO): DefineArg sci2.Speed = 1.000000 m/s
2020-01-05T03:51:19.191Z,1578196279.191 [MissionManager](INFO): DefineArg sci2.CaptureRadius = nan m
2020-01-05T03:51:19.194Z,1578196279.194 [MissionManager](INFO): DefineArg sci2.YoYoMinDepth = 2.000000 m
2020-01-05T03:51:19.197Z,1578196279.197 [MissionManager](INFO): DefineArg sci2.YoYoMaxDepth = 200.000000 m
2020-01-05T03:51:19.213Z,1578196279.213 [MissionManager](INFO): DefineArg sci2.YoYoMinAltitude = 9.000000 m
2020-01-05T03:51:19.216Z,1578196279.216 [MissionManager](INFO): DefineArg sci2.YoYoUpPitch = 20.000000 arcdeg
2020-01-05T03:51:19.219Z,1578196279.219 [MissionManager](INFO): DefineArg sci2.YoYoDownPitch = -20.000000 arcdeg
2020-01-05T03:51:19.222Z,1578196279.222 [MissionManager](INFO): DefineArg sci2.BuoyancyNeutral = 90.000001 cc
2020-01-05T03:51:19.246Z,1578196279.246 [MissionManager](INFO): DefineArg sci2.MassDefault = -3.500000 cm
2020-01-05T03:51:19.249Z,1578196279.249 [MissionManager](INFO): DefineArg sci2.MinAltitude = 5.000000 m
2020-01-05T03:51:19.252Z,1578196279.252 [MissionManager](INFO): DefineArg sci2.MaxDepth = 205.000000 m
2020-01-05T03:51:19.255Z,1578196279.255 [MissionManager](INFO): DefineArg sci2.MinOffshore = 2.000000 km
2020-01-05T03:51:19.255Z,1578196279.255 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2020-01-05T03:51:19.688Z,1578196279.688 [MissionManager](INFO): DefineArg sci2:Science.SampleISUS = 0 bool
2020-01-05T03:51:19.692Z,1578196279.692 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2020-01-05T03:51:19.695Z,1578196279.695 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSminPitch = -60.000000 arcdeg
2020-01-05T03:51:19.697Z,1578196279.697 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectChlActive = 0 bool
2020-01-05T03:51:19.700Z,1578196279.700 [MissionManager](INFO): DefineArg sci2:Science.TimeWindowPeakReport = nan min
2020-01-05T03:51:19.703Z,1578196279.703 [MissionManager](INFO): DefineArg sci2:Science.HighestChlPeakReportActive = 0 bool
2020-01-05T03:51:19.705Z,1578196279.705 [MissionManager](INFO): DefineArg sci2:Science.PatchTracking = 0 bool
2020-01-05T03:51:19.708Z,1578196279.708 [MissionManager](INFO): DefineArg sci2:Science.FilterWidthHorizontal = 3.000000 count
2020-01-05T03:51:19.711Z,1578196279.711 [MissionManager](INFO): DefineArg sci2:Science.NumProfilesSlidingwindow = 100.000000 count
2020-01-05T03:51:19.714Z,1578196279.714 [MissionManager](INFO): DefineArg sci2:Science.OffPeakFractionHorizontal = 80.000000 %
2020-01-05T03:51:19.717Z,1578196279.717 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectNO3Active = 0 bool
2020-01-05T03:51:19.719Z,1578196279.719 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectOilActive = 0 bool
2020-01-05T03:51:19.722Z,1578196279.722 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectFDOMActive = 0 bool
2020-01-05T03:51:19.725Z,1578196279.725 [MissionManager](INFO): DefineArg sci2:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-01-05T03:51:19.728Z,1578196279.728 [MissionManager](INFO): DefineArg sci2:Science.EnabledAanderaaO2 = 0.000000 bool
2020-01-05T03:51:19.732Z,1578196279.732 [MissionManager](INFO): DefineArg sci2:Science.EnabledNeilBrown = 0.000000 bool
2020-01-05T03:51:19.735Z,1578196279.735 [MissionManager](INFO): DefineArg sci2:Science.EnabledSeabird = 1.000000 bool
2020-01-05T03:51:19.759Z,1578196279.759 [MissionManager](INFO): DefineArg sci2:Science.EnabledTurbulence_NPS = 0.000000 bool
2020-01-05T03:51:19.763Z,1578196279.763 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsBB2FL = 1.000000 bool
2020-01-05T03:51:19.789Z,1578196279.789 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2020-01-05T03:51:19.792Z,1578196279.792 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsUBAT = 0.000000 bool
2020-01-05T03:51:19.796Z,1578196279.796 [MissionManager](INFO): DefineArg sci2:Science.EnabledISUS = 0.000000 bool
2020-01-05T03:51:19.808Z,1578196279.808 [MissionManager](INFO): DefineArg sci2:Science.LowPassWindowLength = 20.000000 count
2020-01-05T03:51:19.810Z,1578196279.810 [MissionManager](INFO): DefineArg sci2:Science.MedianFilterLen = 5.000000 count
2020-01-05T03:51:19.813Z,1578196279.813 [MissionManager](INFO): DefineArg sci2:Science.PeakChlShallowBound = nan m
2020-01-05T03:51:19.816Z,1578196279.816 [MissionManager](INFO): DefineArg sci2:Science.PeakChlDeepBound = nan m
2020-01-05T03:51:19.845Z,1578196279.845 [MissionManager](INFO): DefineArg sci2:Science.PeakShallowBound = nan m
2020-01-05T03:51:19.848Z,1578196279.848 [MissionManager](INFO): DefineArg sci2:Science.PeakDeepBound = nan m
2020-01-05T03:51:19.851Z,1578196279.851 [MissionManager](INFO): DefineOutput sci2:Science.PeakChl = 0.000000 ug/l
2020-01-05T03:51:19.870Z,1578196279.870 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlDepth = 0.000000 m
2020-01-05T03:51:19.873Z,1578196279.873 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlTemperature = 0.000000 degC
2020-01-05T03:51:19.876Z,1578196279.876 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLatitude = nan arcdeg
2020-01-05T03:51:19.896Z,1578196279.896 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLongitude = nan arcdeg
2020-01-05T03:51:19.899Z,1578196279.899 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3 = 0.000000 umol/l
2020-01-05T03:51:19.902Z,1578196279.902 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Depth = 0.000000 m
2020-01-05T03:51:19.905Z,1578196279.905 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Latitude = nan arcdeg
2020-01-05T03:51:19.937Z,1578196279.937 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Longitude = nan arcdeg
2020-01-05T03:51:19.940Z,1578196279.940 [MissionManager](INFO): DefineOutput sci2:Science.PatchChl = 0.000000 m
2020-01-05T03:51:19.943Z,1578196279.943 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDepth = 0.000000 m
2020-01-05T03:51:19.962Z,1578196279.962 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLatitude = nan arcdeg
2020-01-05T03:51:19.978Z,1578196279.978 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLongitude = nan arcdeg
2020-01-05T03:51:19.993Z,1578196279.993 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDistance = nan m
2020-01-05T03:51:19.996Z,1578196279.996 [MissionManager](INFO): DefineOutput sci2:Science.PeakOil = 0.000000 kg/m3
2020-01-05T03:51:20.002Z,1578196280.002 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilDepth = 0.000000 m
2020-01-05T03:51:20.015Z,1578196280.015 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLatitude = nan arcdeg
2020-01-05T03:51:20.018Z,1578196280.018 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLongitude = nan arcdeg
2020-01-05T03:51:20.021Z,1578196280.021 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOM = 0.000000 ppb
2020-01-05T03:51:20.024Z,1578196280.024 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMDepth = 0.000000 m
2020-01-05T03:51:20.027Z,1578196280.027 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMLatitude = nan arcdeg
2020-01-05T03:51:20.030Z,1578196280.030 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMLongitude = nan arcdeg
2020-01-05T03:51:20.033Z,1578196280.033 [MissionManager](INFO): DefineOutput sci2:Science.PatchFDOMDistance = nan m
2020-01-05T03:51:20.049Z,1578196280.049 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-01-05T03:51:20.061Z,1578196280.061 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-01-05T03:51:20.104Z,1578196280.104 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-01-05T03:51:20.127Z,1578196280.127 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-01-05T03:51:20.136Z,1578196280.136 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-01-05T03:51:20.168Z,1578196280.168 [MissionManager](INFO): RedefineArg sci2:Science.SampleISUS = value:1 bool
2020-01-05T03:51:20.182Z,1578196280.182 [MissionManager](INFO): RedefineArg sci2:Science.PeakDetectChlActive = value:1 bool
2020-01-05T03:51:20.183Z,1578196280.183 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-01-05T03:51:20.273Z,1578196280.273 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:51:20.300Z,1578196280.300 [MissionManager](INFO): DefineArg sci2:NeedComms.DiveInterval = 3.000000 h
2020-01-05T03:51:20.327Z,1578196280.327 [MissionManager](INFO): DefineArg sci2:NeedComms.WaitForPitchUp = 10.000000 min
2020-01-05T03:51:20.330Z,1578196280.330 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-01-05T03:51:20.333Z,1578196280.333 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceDepthRate = nan m/s
2020-01-05T03:51:20.336Z,1578196280.336 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-01-05T03:51:20.348Z,1578196280.348 [MissionManager](INFO): DefineArg sci2:NeedComms.GPSTimeout = 7.000000 min
2020-01-05T03:51:20.351Z,1578196280.351 [MissionManager](INFO): DefineArg sci2:NeedComms.CommsTimeout = 30.000000 min
2020-01-05T03:51:20.394Z,1578196280.394 [sci2:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-05T03:51:20.424Z,1578196280.424 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-01-05T03:51:20.468Z,1578196280.468 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinAltitude = 5.000000 m
2020-01-05T03:51:20.486Z,1578196280.486 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MaxDepth = 200.000000 m
2020-01-05T03:51:20.489Z,1578196280.489 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-01-05T03:51:20.490Z,1578196280.490 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-01-05T03:51:20.512Z,1578196280.512 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-01-05T03:51:20.548Z,1578196280.548 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-01-05T03:51:20.575Z,1578196280.575 [sci2:I.Pitch](DEBUG): Construct.
2020-01-05T03:51:20.595Z,1578196280.595 [sci2:J.Buoyancy](DEBUG): Construct Buoyancy.
2020-01-05T03:51:20.598Z,1578196280.598 [sci2:K.SetSpeed](DEBUG): Construct.
2020-01-05T03:51:20.619Z,1578196280.619 [sci2:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-01-05T03:51:20.644Z,1578196280.644 [sci2:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-01-05T03:51:20.651Z,1578196280.651 [sci2:N.YoYo](DEBUG): Construct YoYo.
2020-01-05T03:51:20.688Z,1578196280.688 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2020-01-05T03:51:20.710Z,1578196280.710 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2020-01-05T03:51:20.743Z,1578196280.743 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2020-01-05T03:51:20.764Z,1578196280.764 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2020-01-05T03:51:20.771Z,1578196280.771 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Construct Waypoint.
2020-01-05T03:51:20.817Z,1578196280.817 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Construct Waypoint.
2020-01-05T03:51:20.832Z,1578196280.832 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Construct Waypoint.
2020-01-05T03:51:20.935Z,1578196280.935 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Control="Tethys/Control"
         xmlns:Guidance="Tethys/Guidance"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd"
       Id="sci2">

    <Description>
        Vehicle yo-yo's to the specified waypoints, with science turned on.
    </Description>
    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>2</Value></DefineArg>

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

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

    <DefineArg Name="Lat1"><Description>
        Latitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon1"><Description>
        Longitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat2"><Description>
        Latitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon2"><Description>
        Longitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat3"><Description>
        Latitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon3"><Description>
        Longitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat4"><Description>
        Latitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon4"><Description>
        Longitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat5"><Description>
        Latitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon5"><Description>
        Longitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat6"><Description>
        Latitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon6"><Description>
        Longitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat7"><Description>
        Latitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon7"><Description>
        Longitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Speed while performing the YoYo behavior.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="CaptureRadius"><Description>
        Speed while performing the YoYo behavior.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    
    <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>9</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Description>
        Vehicle up pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Description>
        Vehicle down pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>-20</Value></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position while performing the YoYo behavior. Defaults
        to buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc
        for active buoyancy
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission. Set to NaN mm for active
        mass position
    </Description><Control:VerticalControl.massDefault/></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>
    <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>

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

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

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

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

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Arg Name="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.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
        <Setting><Guidance:DepthEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></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.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
        <Setting><Guidance:YoYo.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="Lap">
        <Sequence Repeat="Repeat"/>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat1"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon1"/></Setting>
                <Setting><Guidance:Waypoint.captureRadius/><Arg Name="CaptureRadius"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat2"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon2"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat3"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon3"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat4"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon4"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat5"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon5"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat6"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon6"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat7"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon7"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>
    </Aggregate>
</Mission>


2020-01-05T03:51:20.936Z,1578196280.936 [CommandLine](IMPORTANT): Loaded ./Missions/Science/sci2.xml
2020-01-05T03:51:26.798Z,1578196286.798 [CommandLine](IMPORTANT): got command set sci2.NeedCommsTime 30.000000 minute
2020-01-05T03:51:26.799Z,1578196286.799 [CommandLine](IMPORTANT): got command set sci2.Lat1 36.797001 degree
2020-01-05T03:51:26.800Z,1578196286.800 [CommandLine](IMPORTANT): got command set sci2.Lon1 -121.847000 degree
2020-01-05T03:51:26.800Z,1578196286.800 [CommandLine](IMPORTANT): got command set sci2.YoYoMinDepth 5.000000 meter
2020-01-05T03:51:26.801Z,1578196286.801 [CommandLine](IMPORTANT): got command set sci2.YoYoMaxDepth 30.000000 meter
2020-01-05T03:51:26.802Z,1578196286.802 [CommandLine](IMPORTANT): got command set sci2.MaxDepth 37.000000 meter
2020-01-05T03:51:26.802Z,1578196286.802 [CommandLine](IMPORTANT): got command run 
2020-01-05T03:51:26.807Z,1578196286.807 [CommandLine](IMPORTANT): Running 
2020-01-05T03:51:26.876Z,1578196286.876 [Default] Stopped
2020-01-05T03:51:26.876Z,1578196286.876 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-05T03:51:26.876Z,1578196286.876 [Default:B.GoToSurface] Stopped
2020-01-05T03:51:26.876Z,1578196286.876 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-05T03:51:26.877Z,1578196286.877 [Default:CheckIn] Stopped
2020-01-05T03:51:26.889Z,1578196286.889 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-05T03:51:26.889Z,1578196286.889 [Default:CheckIn:Read_Iridium] Stopped
2020-01-05T03:51:26.889Z,1578196286.889 [MissionManager](IMPORTANT): Started mission sci2
2020-01-05T03:51:26.890Z,1578196286.890 [sci2] Running Loop=1
2020-01-05T03:51:26.890Z,1578196286.890 [sci2](DEBUG): Aggregate::initialize sci2
2020-01-05T03:51:26.890Z,1578196286.890 [sci2:Science] Running Loop=1
2020-01-05T03:51:26.890Z,1578196286.890 [sci2:Science](DEBUG): Aggregate::initialize sci2:Science
2020-01-05T03:51:26.890Z,1578196286.890 [sci2:Science:A] Running Loop=1
2020-01-05T03:51:26.890Z,1578196286.890 [sci2:Science:B] Running Loop=1
2020-01-05T03:51:26.890Z,1578196286.890 [sci2:Science:C] Running Loop=1
2020-01-05T03:51:26.890Z,1578196286.890 [sci2:Science:D] Running Loop=1
2020-01-05T03:51:26.890Z,1578196286.890 [sci2:Science:E] Running Loop=1
2020-01-05T03:51:26.890Z,1578196286.890 [sci2:Science:F] Running Loop=1
2020-01-05T03:51:26.890Z,1578196286.890 [sci2:Science:G] Running Loop=1
2020-01-05T03:51:26.891Z,1578196286.891 [sci2:Science:H] Running Loop=1
2020-01-05T03:51:26.891Z,1578196286.891 [sci2:Science:Read_Oil] Running Loop=1
2020-01-05T03:51:26.891Z,1578196286.891 [sci2:Science:PeakDetectChl] Running Loop=1
2020-01-05T03:51:26.891Z,1578196286.891 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2:Science:PeakDetectChl
2020-01-05T03:51:26.891Z,1578196286.891 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-01-05T03:51:26.891Z,1578196286.891 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-01-05T03:51:26.902Z,1578196286.902 [sci2:Science:HighestChlPeakReport] Running Loop=1
2020-01-05T03:51:26.902Z,1578196286.902 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestChlPeakReport
2020-01-05T03:51:26.903Z,1578196286.903 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-01-05T03:51:26.903Z,1578196286.903 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-01-05T03:51:26.903Z,1578196286.903 [sci2:Science:PeakDetectNO3] Running Loop=1
2020-01-05T03:51:26.903Z,1578196286.903 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2:Science:PeakDetectNO3
2020-01-05T03:51:26.903Z,1578196286.903 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-01-05T03:51:26.903Z,1578196286.903 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-01-05T03:51:26.906Z,1578196286.906 [sci2:Science:PeakDetectOil] Running Loop=1
2020-01-05T03:51:26.906Z,1578196286.906 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2:Science:PeakDetectOil
2020-01-05T03:51:26.906Z,1578196286.906 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-01-05T03:51:26.906Z,1578196286.906 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-01-05T03:51:26.908Z,1578196286.908 [sci2:Science:PeakDetectFDOM] Running Loop=1
2020-01-05T03:51:26.908Z,1578196286.908 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sci2:Science:PeakDetectFDOM
2020-01-05T03:51:26.909Z,1578196286.909 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-01-05T03:51:26.909Z,1578196286.909 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-01-05T03:51:26.911Z,1578196286.911 [sci2:StandardEnvelopes] Running Loop=1
2020-01-05T03:51:26.911Z,1578196286.911 [sci2:StandardEnvelopes](DEBUG): Aggregate::initialize sci2:StandardEnvelopes
2020-01-05T03:51:26.911Z,1578196286.911 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-01-05T03:51:26.911Z,1578196286.911 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-01-05T03:51:26.911Z,1578196286.911 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-01-05T03:51:26.912Z,1578196286.912 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-01-05T03:51:26.912Z,1578196286.912 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-01-05T03:51:26.912Z,1578196286.912 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-01-05T03:51:26.912Z,1578196286.912 [sci2:I.Pitch] Running Loop=1
2020-01-05T03:51:26.912Z,1578196286.912 [sci2:I.Pitch](DEBUG): Initialize.
2020-01-05T03:51:26.912Z,1578196286.912 [sci2:J.Buoyancy] Running Loop=1
2020-01-05T03:51:26.912Z,1578196286.912 [sci2:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-01-05T03:51:26.912Z,1578196286.912 [sci2:K.SetSpeed] Running Loop=1
2020-01-05T03:51:26.912Z,1578196286.912 [sci2:K.SetSpeed](DEBUG): Initialize.
2020-01-05T03:51:26.913Z,1578196286.913 [sci2:L.DepthEnvelope] Running Loop=1
2020-01-05T03:51:26.913Z,1578196286.913 [sci2:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-01-05T03:51:26.913Z,1578196286.913 [sci2:M.AltitudeEnvelope] Running Loop=1
2020-01-05T03:51:26.913Z,1578196286.913 [sci2:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-01-05T03:51:26.913Z,1578196286.913 [sci2:N.YoYo] Running Loop=1
2020-01-05T03:51:26.914Z,1578196286.914 [sci2:N.YoYo](DEBUG): Initialize YoYoComponent.
2020-01-05T03:51:26.914Z,1578196286.914 [sci2:C] Running Loop=1
2020-01-05T03:51:26.914Z,1578196286.914 [sci2:N.YoYo] Running Loop=1
2020-01-05T03:51:26.915Z,1578196286.915 [sci2:M.AltitudeEnvelope] Running Loop=1
2020-01-05T03:51:26.915Z,1578196286.915 [sci2:L.DepthEnvelope] Running Loop=1
2020-01-05T03:51:26.929Z,1578196286.929 [sci2:K.SetSpeed] Running Loop=1
2020-01-05T03:51:26.929Z,1578196286.929 [sci2:J.Buoyancy] Running Loop=1
2020-01-05T03:51:26.930Z,1578196286.930 [sci2:I.Pitch] Running Loop=1
2020-01-05T03:51:26.930Z,1578196286.930 [sci2:StandardEnvelopes] Running Loop=1
2020-01-05T03:51:26.930Z,1578196286.930 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-01-05T03:51:26.931Z,1578196286.931 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-01-05T03:51:26.931Z,1578196286.931 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-01-05T03:51:26.931Z,1578196286.931 [sci2:C] Stopped
2020-01-05T03:51:26.932Z,1578196286.932 [sci2:E] Running Loop=1
2020-01-05T03:51:26.932Z,1578196286.932 [sci2:Science] Running Loop=1
2020-01-05T03:51:26.945Z,1578196286.945 [sci2:Science:PeakDetectFDOM] Stopped
2020-01-05T03:51:26.945Z,1578196286.945 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectFDOM
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:PeakDetectOil] Stopped
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectOil
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:PeakDetectNO3] Stopped
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectNO3
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:HighestChlPeakReport] Stopped
2020-01-05T03:51:26.946Z,1578196286.946 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestChlPeakReport
2020-01-05T03:51:26.947Z,1578196286.947 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-01-05T03:51:26.947Z,1578196286.947 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-01-05T03:51:26.947Z,1578196286.947 [sci2:Science:PeakDetectChl] Running Loop=1
2020-01-05T03:51:26.947Z,1578196286.947 [sci2:Science:Read_Oil] Stopped
2020-01-05T03:51:26.947Z,1578196286.947 [sci2:Science:H] Stopped
2020-01-05T03:51:26.947Z,1578196286.947 [sci2:Science:G] Running Loop=1
2020-01-05T03:51:26.948Z,1578196286.948 [sci2:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-01-05T03:51:26.948Z,1578196286.948 [sci2:Science:F] Stopped
2020-01-05T03:51:26.948Z,1578196286.948 [sci2:Science:E] Stopped
2020-01-05T03:51:26.948Z,1578196286.948 [sci2:Science:D] Running Loop=1
2020-01-05T03:51:26.955Z,1578196286.955 [sci2:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2020-01-05T03:51:26.955Z,1578196286.955 [sci2:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2020-01-05T03:51:26.956Z,1578196286.956 [sci2:Science:C] Running Loop=1
2020-01-05T03:51:26.956Z,1578196286.956 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2020-01-05T03:51:26.965Z,1578196286.965 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2020-01-05T03:51:26.965Z,1578196286.965 [sci2:Science:B] Stopped
2020-01-05T03:51:26.965Z,1578196286.965 [sci2:Science:A] Stopped
2020-01-05T03:51:27.083Z,1578196287.083 [sci2:E] Stopped
2020-01-05T03:51:27.083Z,1578196287.083 [sci2:F] Running Loop=1
2020-01-05T03:51:27.297Z,1578196287.297 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-01-05T03:51:27.442Z,1578196287.442 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2020-01-05T03:51:27.442Z,1578196287.442 [WetLabsBB2FL](INFO): Powering up
2020-01-05T03:51:27.445Z,1578196287.445 [sci2:F] Stopped
2020-01-05T03:51:27.446Z,1578196287.446 [sci2:G] Running Loop=1
2020-01-05T03:51:27.773Z,1578196287.773 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2020-01-05T03:51:27.773Z,1578196287.773 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2020-01-05T03:51:27.773Z,1578196287.773 [BuoyancyServo] Communications Fault, FailCount= 1
2020-01-05T03:51:27.773Z,1578196287.773 [BuoyancyServo](ERROR): Communications Fault
2020-01-05T03:51:27.880Z,1578196287.880 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2020-01-05T03:51:27.946Z,1578196287.946 [sci2:G] Stopped
2020-01-05T03:51:27.946Z,1578196287.946 [sci2:StartingMission] Running Loop=1
2020-01-05T03:51:27.946Z,1578196287.946 [sci2:StartingMission](DEBUG): Aggregate::initialize sci2:StartingMission
2020-01-05T03:51:28.340Z,1578196288.340 [sci2:M.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2020-01-05T03:51:28.342Z,1578196288.342 [sci2:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2020-01-05T03:51:28.342Z,1578196288.342 [sci2:NeedComms] Running Loop=1
2020-01-05T03:51:28.342Z,1578196288.342 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2020-01-05T03:51:28.342Z,1578196288.342 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2020-01-05T03:51:28.343Z,1578196288.343 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-05T03:51:28.343Z,1578196288.343 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-01-05T03:51:28.343Z,1578196288.343 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-01-05T03:51:28.344Z,1578196288.344 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-01-05T03:51:28.344Z,1578196288.344 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-05T03:51:28.344Z,1578196288.344 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-05T03:51:28.345Z,1578196288.345 [sci2:NeedComms:A] Running Loop=1
2020-01-05T03:51:28.346Z,1578196288.346 [sci2:NeedComms:A](INFO): last time_fix was: 1578196217.000000 second since 1970/01/01T00:00:00Z 
2020-01-05T03:51:28.346Z,1578196288.346 [sci2:NeedComms:A] Stopped
2020-01-05T03:51:28.757Z,1578196288.757 [sci2:NeedComms:C] Running Loop=1
2020-01-05T03:51:29.144Z,1578196289.144 [sci2:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-05T03:51:29.600Z,1578196289.600 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-05T03:51:29.600Z,1578196289.600 [BuoyancyServo](INFO): Powering down
2020-01-05T03:51:30.302Z,1578196290.302 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2020-01-05T03:51:30.429Z,1578196290.429 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-01-05T03:51:30.429Z,1578196290.429 [BuoyancyServo] No Fault, FailCount= 1
2020-01-05T03:51:30.728Z,1578196290.728 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,035103.00,A,3647.09506,N,12151.56477,W,0.933,111.68,050120,,,D*70
2020-01-05T03:51:30.731Z,1578196290.731 [NAL9602](INFO): GPS fix at 20200105T035103: (36.784918, -121.859413)
2020-01-05T03:51:30.758Z,1578196290.758 [sci2:NeedComms:C] Stopped
2020-01-05T03:51:30.758Z,1578196290.758 [sci2:NeedComms:D] Running Loop=1
2020-01-05T03:51:30.763Z,1578196290.763 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-05T03:51:30.764Z,1578196290.764 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-05T03:51:31.185Z,1578196291.185 [sci2:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-05T03:51:50.167Z,1578196310.167 [NAL9602](INFO): SBD MO Status=1, MOMSN=18829, MT Status=0, MTMSN=0
2020-01-05T03:51:50.217Z,1578196310.217 [NAL9602](INFO): Sent 62 bytes from file Logs/20200105T031542/Courier0018.lzma
2020-01-05T03:51:50.217Z,1578196310.217 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:51:51.405Z,1578196311.405 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:52:00.335Z,1578196320.335 [NAL9602](INFO): SBD MO Status=1, MOMSN=18830, MT Status=0, MTMSN=0
2020-01-05T03:52:00.389Z,1578196320.389 [NAL9602](INFO): Sent 130 bytes from file Logs/20200105T031542/Express0016.lzma
2020-01-05T03:52:00.389Z,1578196320.389 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:52:17.787Z,1578196337.787 [NAL9602](INFO): SBD MO Status=2, MOMSN=18831, MT Status=2, MTMSN=0
2020-01-05T03:52:17.788Z,1578196337.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T03:52:22.529Z,1578196342.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:52:35.275Z,1578196355.275 [NAL9602](INFO): SBD MO Status=1, MOMSN=18831, MT Status=0, MTMSN=0
2020-01-05T03:52:35.329Z,1578196355.329 [NAL9602](INFO): Sent 332 bytes from file Logs/20200105T031542/Express0019.lzma
2020-01-05T03:52:35.329Z,1578196355.329 [NAL9602](INFO): Packets left to send: 1
2020-01-05T03:52:46.691Z,1578196366.691 [NAL9602](INFO): SBD MO Status=1, MOMSN=18832, MT Status=0, MTMSN=0
2020-01-05T03:52:46.757Z,1578196366.757 [NAL9602](INFO): Sent 76 bytes from file Logs/20200105T031542/Express0019.lzma
2020-01-05T03:52:46.757Z,1578196366.757 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:52:53.657Z,1578196373.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:53:02.851Z,1578196382.851 [NAL9602](INFO): SBD MO Status=0, MOMSN=18833, MT Status=0, MTMSN=0
2020-01-05T03:53:24.789Z,1578196404.789 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:53:26.731Z,1578196406.731 [NAL9602](INFO): SBD MO Status=1, MOMSN=18834, MT Status=0, MTMSN=0
2020-01-05T03:53:26.786Z,1578196406.786 [NAL9602](INFO): Sent 25 bytes from file Logs/20200105T031542/Courier0021.lzma
2020-01-05T03:53:26.787Z,1578196406.787 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:53:43.763Z,1578196423.763 [NAL9602](INFO): SBD MO Status=1, MOMSN=18835, MT Status=0, MTMSN=0
2020-01-05T03:53:43.825Z,1578196423.825 [NAL9602](INFO): Sent 332 bytes from file Logs/20200105T031542/Express0022.lzma
2020-01-05T03:53:43.825Z,1578196423.825 [NAL9602](INFO): Packets left to send: 1
2020-01-05T03:53:55.913Z,1578196435.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:53:56.791Z,1578196436.791 [NAL9602](INFO): SBD MO Status=1, MOMSN=18836, MT Status=0, MTMSN=0
2020-01-05T03:53:56.841Z,1578196436.841 [NAL9602](INFO): Sent 23 bytes from file Logs/20200105T031542/Express0022.lzma
2020-01-05T03:53:56.841Z,1578196436.841 [NAL9602](INFO): Packets left to send: 0
2020-01-05T03:54:08.511Z,1578196448.511 [NAL9602](INFO): SBD MO Status=0, MOMSN=18837, MT Status=0, MTMSN=0
2020-01-05T03:54:08.616Z,1578196448.616 [sci2:NeedComms:D] Stopped
2020-01-05T03:54:08.616Z,1578196448.616 [sci2:NeedComms:E] Running Loop=1
2020-01-05T03:54:08.958Z,1578196448.958 [sci2:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-05T03:54:10.935Z,1578196450.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,035344.00,A,3647.08547,N,12151.56229,W,0.564,196.08,050120,,,D*7F
2020-01-05T03:54:10.937Z,1578196450.937 [NAL9602](INFO): GPS fix at 20200105T035344: (36.784758, -121.859371)
2020-01-05T03:54:10.992Z,1578196450.992 [sci2:NeedComms:E] Stopped
2020-01-05T03:54:11.009Z,1578196451.009 [sci2:NeedComms](INFO): Completed sci2:NeedComms
2020-01-05T03:54:11.009Z,1578196451.009 [sci2:NeedComms] Stopped
2020-01-05T03:54:11.009Z,1578196451.009 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2020-01-05T03:54:11.010Z,1578196451.010 [sci2:NeedComms:B.GoToSurface] Stopped
2020-01-05T03:54:11.010Z,1578196451.010 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-05T03:54:11.395Z,1578196451.395 [sci2:StartingMission](INFO): Completed sci2:StartingMission
2020-01-05T03:54:11.395Z,1578196451.395 [sci2:StartingMission] Stopped
2020-01-05T03:54:11.395Z,1578196451.395 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission
2020-01-05T03:54:11.395Z,1578196451.395 [sci2:Lap] Running Loop=1
2020-01-05T03:54:11.395Z,1578196451.395 [sci2:Lap](DEBUG): Aggregate::initialize sci2:Lap
2020-01-05T03:54:11.395Z,1578196451.395 [sci2:Lap:Wpt1] Running Loop=1
2020-01-05T03:54:11.396Z,1578196451.396 [sci2:Lap:Wpt1](DEBUG): Aggregate::initialize sci2:Lap:Wpt1
2020-01-05T03:54:11.396Z,1578196451.396 [sci2:Lap:Wpt1:A.Waypoint] Running Loop=1
2020-01-05T03:54:11.396Z,1578196451.396 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent.
2020-01-05T03:54:27.042Z,1578196467.042 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:54:43.656Z,1578196483.656 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-05T03:54:58.173Z,1578196498.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:55:21.654Z,1578196521.654 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position -35.00 mm (1 active estimators).
2020-01-05T03:55:29.305Z,1578196529.305 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:56:00.445Z,1578196560.445 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:56:22.225Z,1578196582.225 [Radio_Surface](INFO): Powering down
2020-01-05T03:56:31.613Z,1578196591.613 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T03:56:32.617Z,1578196592.617 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-01-05T03:56:54.271Z,1578196614.271 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position -35.00 mm (2 active estimators).
2020-01-05T04:13:11.122Z,1578197591.122 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-05T04:13:11.124Z,1578197591.124 [BPC1](INFO): Received data from all battery sticks.
2020-01-05T04:24:11.309Z,1578198251.309 [sci2:NeedComms] Running Loop=1
2020-01-05T04:24:11.309Z,1578198251.309 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2020-01-05T04:24:11.309Z,1578198251.309 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2020-01-05T04:24:11.309Z,1578198251.309 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-05T04:24:11.309Z,1578198251.309 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-01-05T04:24:11.310Z,1578198251.310 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-01-05T04:24:11.310Z,1578198251.310 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-01-05T04:24:11.310Z,1578198251.310 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-05T04:24:11.311Z,1578198251.311 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-05T04:24:11.311Z,1578198251.311 [sci2:NeedComms:A] Running Loop=1
2020-01-05T04:24:11.313Z,1578198251.313 [sci2:NeedComms:A](INFO): last time_fix was: 1578196424.000000 second since 1970/01/01T00:00:00Z 
2020-01-05T04:24:11.313Z,1578198251.313 [sci2:NeedComms:A] Stopped
2020-01-05T04:24:42.805Z,1578198282.805 [sci2:NeedComms:C] Running Loop=1
2020-01-05T04:24:43.301Z,1578198283.301 [Radio_Surface](INFO): Powering up
2020-01-05T04:24:48.653Z,1578198288.653 [DataOverHttps](INFO): Radio surface powered ON.
2020-01-05T04:25:18.774Z,1578198318.774 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:25:49.913Z,1578198349.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:26:21.037Z,1578198381.037 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:26:32.679Z,1578198392.679 [NAL9602](INFO): SBD MO Status=0, MOMSN=18838, MT Status=0, MTMSN=0
2020-01-05T04:26:32.679Z,1578198392.679 [NAL9602](INFO): No messages in MT queue
2020-01-05T04:26:33.892Z,1578198393.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,042603.00,A,3647.69447,N,12150.82671,W,0.350,317.34,050120,,,D*7B
2020-01-05T04:26:33.894Z,1578198393.894 [NAL9602](INFO): GPS fix at 20200105T042603: (36.794908, -121.847112)
2020-01-05T04:26:33.960Z,1578198393.960 [UniversalFixResidualReporter](INFO): Fix residual: 12.8 %DT, over the last 1749.0 m. Residual distance 223.1 m at bearing 0.7 degrees. Fix at (36.7949, -121.8471) with 1570.2 m made good.
2020-01-05T04:26:33.970Z,1578198393.970 [sci2:NeedComms:C] Stopped
2020-01-05T04:26:33.970Z,1578198393.970 [sci2:NeedComms:D] Running Loop=1
2020-01-05T04:26:52.162Z,1578198412.162 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:26:54.984Z,1578198414.984 [NAL9602](INFO): SBD MO Status=2, MOMSN=18839, MT Status=2, MTMSN=0
2020-01-05T04:26:54.984Z,1578198414.984 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T04:27:14.027Z,1578198434.027 [NAL9602](INFO): SBD MO Status=1, MOMSN=18839, MT Status=0, MTMSN=0
2020-01-05T04:27:14.077Z,1578198434.077 [NAL9602](INFO): Sent 282 bytes from file Logs/20200105T031542/Courier0024.lzma
2020-01-05T04:27:14.077Z,1578198434.077 [NAL9602](INFO): Packets left to send: 0
2020-01-05T04:27:23.285Z,1578198443.285 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:27:31.059Z,1578198451.059 [NAL9602](INFO): SBD MO Status=2, MOMSN=18840, MT Status=2, MTMSN=0
2020-01-05T04:27:31.059Z,1578198451.059 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T04:27:48.911Z,1578198468.911 [NAL9602](INFO): SBD MO Status=2, MOMSN=18840, MT Status=2, MTMSN=0
2020-01-05T04:27:48.911Z,1578198468.911 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T04:27:54.425Z,1578198474.425 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:28:15.643Z,1578198495.643 [NAL9602](INFO): SBD MO Status=2, MOMSN=18840, MT Status=2, MTMSN=0
2020-01-05T04:28:15.643Z,1578198495.643 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T04:28:26.722Z,1578198506.722 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2020-01-05T04:28:36.983Z,1578198516.983 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004521
2020-01-05T04:28:44.759Z,1578198524.759 [NAL9602](INFO): SBD MO Status=1, MOMSN=18840, MT Status=0, MTMSN=0
2020-01-05T04:28:44.809Z,1578198524.809 [NAL9602](INFO): Sent 332 bytes from file Logs/20200105T031542/Express0025.lzma
2020-01-05T04:28:44.809Z,1578198524.809 [NAL9602](INFO): Packets left to send: 2
2020-01-05T04:28:44.862Z,1578198524.862 [DataOverHttps](INFO): Sending 671 bytes from file Logs/20200105T031542/Express0025.lzma
2020-01-05T04:28:46.866Z,1578198526.866 [DataOverHttps](INFO): Moved sent file to Logs/20200105T031542/Express0025.lzma.bak
2020-01-05T04:28:46.867Z,1578198526.867 [DataOverHttps](INFO): SBD MOMSN=12184892
2020-01-05T04:28:48.013Z,1578198528.013 [sci2:NeedComms:D] Stopped
2020-01-05T04:28:48.014Z,1578198528.014 [sci2:NeedComms:E] Running Loop=1
2020-01-05T04:28:50.076Z,1578198530.076 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,042819.00,A,3647.69089,N,12150.83829,W,1.594,257.71,050120,,,D*71
2020-01-05T04:28:50.078Z,1578198530.078 [NAL9602](INFO): GPS fix at 20200105T042819: (36.794848, -121.847305)
2020-01-05T04:28:50.155Z,1578198530.155 [sci2:NeedComms:E] Stopped
2020-01-05T04:28:50.156Z,1578198530.156 [sci2:NeedComms](INFO): Completed sci2:NeedComms
2020-01-05T04:28:50.156Z,1578198530.156 [sci2:NeedComms] Stopped
2020-01-05T04:28:50.156Z,1578198530.156 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2020-01-05T04:28:50.156Z,1578198530.156 [sci2:NeedComms:B.GoToSurface] Stopped
2020-01-05T04:28:50.156Z,1578198530.156 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-05T04:29:21.913Z,1578198561.913 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-05T04:30:20.109Z,1578198620.109 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:30:25.193Z,1578198625.193 [Radio_Surface](INFO): Powering down
2020-01-05T04:30:51.225Z,1578198651.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:30:52.229Z,1578198652.229 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-01-05T04:31:58.497Z,1578198718.497 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.859543
2020-01-05T04:33:46.999Z,1578198826.999 [sci2:Lap:Wpt1:A.Waypoint](IMPORTANT): Reached Waypoint: 36.797002,-121.846996
2020-01-05T04:33:46.999Z,1578198826.999 [sci2:Lap:Wpt1:A.Waypoint] Stopped
2020-01-05T04:33:46.999Z,1578198826.999 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2020-01-05T04:33:46.999Z,1578198826.999 [sci2:Lap:Wpt1](INFO): Completed sci2:Lap:Wpt1
2020-01-05T04:33:46.000Z,1578198827.000 [sci2:Lap:Wpt1] Stopped
2020-01-05T04:33:46.000Z,1578198827.000 [sci2:Lap:Wpt1](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt1
2020-01-05T04:33:46.000Z,1578198827.000 [sci2:Lap:Wpt2] Running Loop=1
2020-01-05T04:33:46.000Z,1578198827.000 [sci2:Lap:Wpt2](DEBUG): Aggregate::initialize sci2:Lap:Wpt2
2020-01-05T04:33:47.000Z,1578198827.000 [sci2:Lap:Wpt2:A.Waypoint] Running Loop=1
2020-01-05T04:33:47.000Z,1578198827.000 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Initialize WaypointComponent.
2020-01-05T04:33:47.414Z,1578198827.414 [sci2:Lap:Wpt2:A.Waypoint] Stopped
2020-01-05T04:33:47.414Z,1578198827.414 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2020-01-05T04:33:47.414Z,1578198827.414 [sci2:Lap:Wpt2](INFO): Completed sci2:Lap:Wpt2
2020-01-05T04:33:47.414Z,1578198827.414 [sci2:Lap:Wpt2] Stopped
2020-01-05T04:33:47.415Z,1578198827.415 [sci2:Lap:Wpt2](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt2
2020-01-05T04:33:47.415Z,1578198827.415 [sci2:Lap:Wpt3] Running Loop=1
2020-01-05T04:33:47.415Z,1578198827.415 [sci2:Lap:Wpt3](DEBUG): Aggregate::initialize sci2:Lap:Wpt3
2020-01-05T04:33:47.415Z,1578198827.415 [sci2:Lap:Wpt3:A.Waypoint] Running Loop=1
2020-01-05T04:33:47.415Z,1578198827.415 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Initialize WaypointComponent.
2020-01-05T04:33:47.810Z,1578198827.810 [sci2:Lap:Wpt3:A.Waypoint] Stopped
2020-01-05T04:33:47.810Z,1578198827.810 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2020-01-05T04:33:47.810Z,1578198827.810 [sci2:Lap:Wpt3](INFO): Completed sci2:Lap:Wpt3
2020-01-05T04:33:47.810Z,1578198827.810 [sci2:Lap:Wpt3] Stopped
2020-01-05T04:33:47.811Z,1578198827.811 [sci2:Lap:Wpt3](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt3
2020-01-05T04:33:47.811Z,1578198827.811 [sci2:Lap:Wpt4] Running Loop=1
2020-01-05T04:33:47.811Z,1578198827.811 [sci2:Lap:Wpt4](DEBUG): Aggregate::initialize sci2:Lap:Wpt4
2020-01-05T04:33:47.811Z,1578198827.811 [sci2:Lap:Wpt4:A.Waypoint] Running Loop=1
2020-01-05T04:33:47.811Z,1578198827.811 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Initialize WaypointComponent.
2020-01-05T04:33:48.207Z,1578198828.207 [sci2:Lap:Wpt4:A.Waypoint] Stopped
2020-01-05T04:33:48.207Z,1578198828.207 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2020-01-05T04:33:48.208Z,1578198828.208 [sci2:Lap:Wpt4](INFO): Completed sci2:Lap:Wpt4
2020-01-05T04:33:48.208Z,1578198828.208 [sci2:Lap:Wpt4] Stopped
2020-01-05T04:33:48.208Z,1578198828.208 [sci2:Lap:Wpt4](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt4
2020-01-05T04:33:48.208Z,1578198828.208 [sci2:Lap:Wpt5] Running Loop=1
2020-01-05T04:33:48.208Z,1578198828.208 [sci2:Lap:Wpt5](DEBUG): Aggregate::initialize sci2:Lap:Wpt5
2020-01-05T04:33:48.208Z,1578198828.208 [sci2:Lap:Wpt5:A.Waypoint] Running Loop=1
2020-01-05T04:33:48.208Z,1578198828.208 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Initialize WaypointComponent.
2020-01-05T04:33:48.626Z,1578198828.626 [sci2:Lap:Wpt5:A.Waypoint] Stopped
2020-01-05T04:33:48.626Z,1578198828.626 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2020-01-05T04:33:48.627Z,1578198828.627 [sci2:Lap:Wpt5](INFO): Completed sci2:Lap:Wpt5
2020-01-05T04:33:48.627Z,1578198828.627 [sci2:Lap:Wpt5] Stopped
2020-01-05T04:33:48.627Z,1578198828.627 [sci2:Lap:Wpt5](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt5
2020-01-05T04:33:48.627Z,1578198828.627 [sci2:Lap:Wpt6] Running Loop=1
2020-01-05T04:33:48.627Z,1578198828.627 [sci2:Lap:Wpt6](DEBUG): Aggregate::initialize sci2:Lap:Wpt6
2020-01-05T04:33:48.628Z,1578198828.628 [sci2:Lap:Wpt6:A.Waypoint] Running Loop=1
2020-01-05T04:33:48.628Z,1578198828.628 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Initialize WaypointComponent.
2020-01-05T04:33:49.007Z,1578198829.007 [sci2:Lap:Wpt6:A.Waypoint] Stopped
2020-01-05T04:33:49.007Z,1578198829.007 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2020-01-05T04:33:49.007Z,1578198829.007 [sci2:Lap:Wpt6](INFO): Completed sci2:Lap:Wpt6
2020-01-05T04:33:49.007Z,1578198829.007 [sci2:Lap:Wpt6] Stopped
2020-01-05T04:33:49.007Z,1578198829.007 [sci2:Lap:Wpt6](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt6
2020-01-05T04:33:49.008Z,1578198829.008 [sci2:Lap:Wpt7] Running Loop=1
2020-01-05T04:33:49.008Z,1578198829.008 [sci2:Lap:Wpt7](DEBUG): Aggregate::initialize sci2:Lap:Wpt7
2020-01-05T04:33:49.008Z,1578198829.008 [sci2:Lap:Wpt7:A.Waypoint] Running Loop=1
2020-01-05T04:33:49.008Z,1578198829.008 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Initialize WaypointComponent.
2020-01-05T04:33:49.421Z,1578198829.421 [sci2:Lap:Wpt7:A.Waypoint] Stopped
2020-01-05T04:33:49.422Z,1578198829.422 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2020-01-05T04:33:49.422Z,1578198829.422 [sci2:Lap:Wpt7](INFO): Completed sci2:Lap:Wpt7
2020-01-05T04:33:49.422Z,1578198829.422 [sci2:Lap:Wpt7] Stopped
2020-01-05T04:33:49.422Z,1578198829.422 [sci2:Lap:Wpt7](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt7
2020-01-05T04:33:49.422Z,1578198829.422 [sci2:Lap](INFO): Completed sci2:Lap
2020-01-05T04:33:49.422Z,1578198829.422 [sci2:Lap] Stopped
2020-01-05T04:33:49.422Z,1578198829.422 [sci2:Lap](DEBUG): Aggregate::uninitialize sci2:Lap
2020-01-05T04:33:49.426Z,1578198829.426 [sci2](INFO): Completed sci2
2020-01-05T04:33:49.426Z,1578198829.426 [MissionManager](INFO): sci2 is completed.
2020-01-05T04:33:49.427Z,1578198829.427 [MissionManager](INFO): Uninitializing Mission sci2
2020-01-05T04:33:49.427Z,1578198829.427 [sci2] Stopped
2020-01-05T04:33:49.427Z,1578198829.427 [sci2](DEBUG): Aggregate::uninitialize sci2
2020-01-05T04:33:49.427Z,1578198829.427 [sci2:Science] Stopped
2020-01-05T04:33:49.427Z,1578198829.427 [sci2:Science](DEBUG): Aggregate::uninitialize sci2:Science
2020-01-05T04:33:49.427Z,1578198829.427 [sci2:Science:C] Stopped
2020-01-05T04:33:49.427Z,1578198829.427 [sci2:Science:D] Stopped
2020-01-05T04:33:49.427Z,1578198829.427 [sci2:Science:G] Stopped
2020-01-05T04:33:49.427Z,1578198829.427 [sci2:Science:PeakDetectChl] Stopped
2020-01-05T04:33:49.427Z,1578198829.427 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectChl
2020-01-05T04:33:49.427Z,1578198829.427 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:StandardEnvelopes] Stopped
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2:StandardEnvelopes
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:I.Pitch] Stopped
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:J.Buoyancy] Stopped
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:K.SetSpeed] Stopped
2020-01-05T04:33:49.428Z,1578198829.428 [sci2:K.SetSpeed](DEBUG): Uninitialize.
2020-01-05T04:33:49.429Z,1578198829.429 [sci2:L.DepthEnvelope] Stopped
2020-01-05T04:33:49.429Z,1578198829.429 [sci2:L.DepthEnvelope](DEBUG): Uninitialize.
2020-01-05T04:33:49.429Z,1578198829.429 [sci2:M.AltitudeEnvelope] Stopped
2020-01-05T04:33:49.429Z,1578198829.429 [sci2:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-01-05T04:33:49.429Z,1578198829.429 [sci2:N.YoYo] Stopped
2020-01-05T04:33:49.429Z,1578198829.429 [sci2:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2020-01-05T04:33:49.821Z,1578198829.821 [MissionManager](IMPORTANT): Started mission Default
2020-01-05T04:33:49.821Z,1578198829.821 [Default] Running Loop=1
2020-01-05T04:33:49.821Z,1578198829.821 [Default](DEBUG): Aggregate::initialize Default
2020-01-05T04:33:49.821Z,1578198829.821 [Default:B.GoToSurface] Running Loop=1
2020-01-05T04:33:49.822Z,1578198829.822 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-05T04:33:49.822Z,1578198829.822 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-05T04:33:49.822Z,1578198829.822 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-05T04:33:49.822Z,1578198829.822 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-05T04:33:49.823Z,1578198829.823 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-05T04:33:49.823Z,1578198829.823 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-05T04:33:49.823Z,1578198829.823 [Default:A.Wait] Running Loop=1
2020-01-05T04:33:49.823Z,1578198829.823 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-05T04:33:49.950Z,1578198829.950 [WetLabsBB2FL](INFO): Powering down
2020-01-05T04:33:50.226Z,1578198830.226 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -35.00 mm (3 active estimators).
2020-01-05T04:33:50.441Z,1578198830.441 [Radio_Surface](INFO): Powering up
2020-01-05T04:33:55.973Z,1578198835.973 [DataOverHttps](INFO): Radio surface powered ON.
2020-01-05T04:34:03.171Z,1578198843.171 [Default:A.Wait](INFO): Done Waiting.
2020-01-05T04:34:03.171Z,1578198843.171 [Default:A.Wait] Stopped
2020-01-05T04:34:03.171Z,1578198843.171 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-05T04:34:26.093Z,1578198866.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:34:57.217Z,1578198897.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:35:28.341Z,1578198928.341 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:35:54.662Z,1578198954.662 [Default:B.GoToSurface](ERROR): Pitch down timeout. Pitch: -25.82
2020-01-05T04:35:59.465Z,1578198959.465 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:36:30.590Z,1578198990.590 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:37:01.729Z,1578199021.729 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:37:29.182Z,1578199049.182 [Default:CheckIn] Running Loop=1
2020-01-05T04:37:29.184Z,1578199049.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-05T04:37:29.184Z,1578199049.184 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-05T04:37:32.873Z,1578199052.873 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:38:04.001Z,1578199084.001 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-05T04:38:13.674Z,1578199093.674 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003721
2020-01-05T04:38:44.430Z,1578199124.430 [DataOverHttps](IMPORTANT): SBD MTMSN=20200105T043812
2020-01-05T04:38:51.897Z,1578199131.897 [DataOverHttps](INFO): Received command:stop
2020-01-05T04:38:51.954Z,1578199131.954 [CommandLine](IMPORTANT): got command stop
2020-01-05T04:38:51.954Z,1578199131.954 [CommandLine](IMPORTANT): Scheduling is paused
2020-01-05T04:38:51.954Z,1578199131.954 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-01-05T04:41:54.859Z,1578199314.859 [CommandLine](IMPORTANT): got command failComponent
2020-01-05T04:41:54.859Z,1578199314.859 [CommandLine](IMPORTANT): Failed components:
2020-01-05T04:41:54.860Z,1578199314.860 [CommandLine](IMPORTANT): No failed Components.
2020-01-05T04:42:29.387Z,1578199349.387 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-05T04:37:29.2Z
2020-01-05T04:42:29.387Z,1578199349.387 [Default:CheckIn:Read_GPS] Stopped
2020-01-05T04:42:29.388Z,1578199349.388 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-05T04:42:37.389Z,1578199357.389 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200105T031542/Courier0027.lzma
2020-01-05T04:42:39.395Z,1578199359.395 [DataOverHttps](INFO): Moved sent file to Logs/20200105T031542/Courier0027.lzma.bak
2020-01-05T04:42:39.395Z,1578199359.395 [DataOverHttps](INFO): SBD MOMSN=12184902
2020-01-05T04:42:43.895Z,1578199363.895 [NAL9602](INFO): SBD MO Status=2, MOMSN=18841, MT Status=2, MTMSN=0
2020-01-05T04:42:43.895Z,1578199363.895 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-05T04:42:44.302Z,1578199364.302 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-05T04:42:56.532Z,1578199376.532 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20200105T031542/Courier0030.lzma
2020-01-05T04:42:58.539Z,1578199378.539 [DataOverHttps](INFO): Moved sent file to Logs/20200105T031542/Courier0030.lzma.bak
2020-01-05T04:42:58.539Z,1578199378.539 [DataOverHttps](INFO): SBD MOMSN=12184904
2020-01-05T04:43:15.002Z,1578199395.002 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-05T04:43:16.718Z,1578199396.718 [DataOverHttps](INFO): Sending 560 bytes from file Logs/20200105T031542/Express0028.lzma
2020-01-05T04:43:18.722Z,1578199398.722 [DataOverHttps](INFO): Moved sent file to Logs/20200105T031542/Express0028.lzma.bak
2020-01-05T04:43:18.723Z,1578199398.723 [DataOverHttps](INFO): SBD MOMSN=12184906
2020-01-05T04:43:35.828Z,1578