2019-07-24T22:24:09.618Z,1564007049.618 [CommandLine](IMPORTANT): got command restart logs
2019-07-24T22:24:56.209Z,1564007096.209 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:24:56.209Z,1564007096.209 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:24:56.209Z,1564007096.209 [DVL_micro](ERROR): Data Fault
2019-07-24T22:24:56.246Z,1564007096.246 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:24:56.690Z,1564007096.690 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:24:57.470Z,1564007097.470 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:24:57.470Z,1564007097.470 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:24:57.830Z,1564007097.830 [DVL_micro](INFO): Initializing
2019-07-24T22:25:28.561Z,1564007128.561 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-24T22:25:28.561Z,1564007128.561 [Default:CheckIn:C.Wait] Stopped
2019-07-24T22:25:28.562Z,1564007128.562 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-24T22:25:28.563Z,1564007128.563 [Default:CheckIn:D] Running Loop=1
2019-07-24T22:25:28.961Z,1564007128.961 [Default:CheckIn:D] Stopped
2019-07-24T22:25:28.961Z,1564007128.961 [Default:CheckIn:E] Running Loop=1
2019-07-24T22:25:29.360Z,1564007129.360 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.174064 min 
2019-07-24T22:25:29.360Z,1564007129.360 [Default:CheckIn:E] Stopped
2019-07-24T22:25:29.360Z,1564007129.360 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-24T22:25:29.360Z,1564007129.360 [Default:CheckIn] Stopped
2019-07-24T22:25:29.360Z,1564007129.360 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-24T22:25:29.361Z,1564007129.361 [Default:CheckIn](INFO): Running loop #2
2019-07-24T22:25:29.361Z,1564007129.361 [Default:CheckIn] Running Loop=2
2019-07-24T22:25:29.361Z,1564007129.361 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-24T22:25:29.361Z,1564007129.361 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-24T22:25:30.982Z,1564007130.982 [NAL9602](DEBUG): Fix Requested
2019-07-24T22:25:31.359Z,1564007131.359 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222525.00,A,3647.85714,N,12148.68331,W,24.356,64.94,240719,,,A*74
2019-07-24T22:25:31.361Z,1564007131.361 [NAL9602](INFO): GPS fix at 20190724T222525: (36.797619, -121.811389)
2019-07-24T22:25:31.423Z,1564007131.423 [UniversalFixResidualReporter](INFO): Fix residual: 91.2 %DT, over the last 813.9 m. Residual distance 742.2 m at bearing -61.7 degrees. Fix at (36.7976, -121.8114) with 935.3 m made good.
2019-07-24T22:25:31.424Z,1564007131.424 [Default:CheckIn:Read_GPS] Stopped
2019-07-24T22:25:31.424Z,1564007131.424 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-24T22:25:36.973Z,1564007136.973 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190724T212907/Courier0033.lzma
2019-07-24T22:25:37.779Z,1564007137.779 [DataOverHttps](INFO): Moved sent file to Logs/20190724T212907/Courier0033.lzma.bak
2019-07-24T22:25:37.780Z,1564007137.780 [DataOverHttps](INFO): SBD MOMSN=11472908
2019-07-24T22:25:49.522Z,1564007149.522 [DataOverHttps](INFO): Sending 274 bytes from file Logs/20190724T222409/Courier0000.lzma
2019-07-24T22:25:50.323Z,1564007150.323 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Courier0000.lzma.bak
2019-07-24T22:25:50.323Z,1564007150.323 [DataOverHttps](INFO): SBD MOMSN=11472913
2019-07-24T22:25:53.997Z,1564007153.997 [NAL9602](INFO): SBD MO Status=1, MOMSN=30013, MT Status=0, MTMSN=0
2019-07-24T22:25:53.997Z,1564007153.997 [NAL9602](INFO): No messages in MT queue
2019-07-24T22:26:01.909Z,1564007161.909 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190724T222409/Courier0003.lzma
2019-07-24T22:26:02.715Z,1564007162.715 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Courier0003.lzma.bak
2019-07-24T22:26:02.715Z,1564007162.715 [DataOverHttps](INFO): SBD MOMSN=11472921
2019-07-24T22:26:14.221Z,1564007174.221 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20190724T212907/Express0034.lzma
2019-07-24T22:26:15.028Z,1564007175.028 [DataOverHttps](INFO): Moved sent file to Logs/20190724T212907/Express0034.lzma.bak
2019-07-24T22:26:15.028Z,1564007175.028 [DataOverHttps](INFO): SBD MOMSN=11472923
2019-07-24T22:26:24.691Z,1564007184.691 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-24T22:26:26.262Z,1564007186.262 [DataOverHttps](INFO): Sending 405 bytes from file Logs/20190724T222409/Express0001.lzma
2019-07-24T22:26:27.067Z,1564007187.067 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0001.lzma.bak
2019-07-24T22:26:27.067Z,1564007187.067 [DataOverHttps](INFO): SBD MOMSN=11472926
2019-07-24T22:26:38.361Z,1564007198.361 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190724T222409/Express0005.lzma
2019-07-24T22:26:39.167Z,1564007199.167 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0005.lzma.bak
2019-07-24T22:26:39.168Z,1564007199.168 [DataOverHttps](INFO): SBD MOMSN=11472931
2019-07-24T22:26:39.657Z,1564007199.657 [Default:CheckIn:Read_Iridium] Stopped
2019-07-24T22:26:39.657Z,1564007199.657 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-24T22:26:39.657Z,1564007199.657 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-24T22:26:52.988Z,1564007212.988 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-07-24T22:26:52.988Z,1564007212.988 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-07-24T22:26:52.989Z,1564007212.989 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2019-07-24T22:26:52.991Z,1564007212.991 [BPC1](INFO): Received data from all battery sticks.
2019-07-24T22:28:02.059Z,1564007282.059 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:28:02.059Z,1564007282.059 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:28:02.059Z,1564007282.059 [DVL_micro](ERROR): Data Fault
2019-07-24T22:28:02.090Z,1564007282.090 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:28:02.526Z,1564007282.526 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:28:03.322Z,1564007283.322 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:28:03.322Z,1564007283.322 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:28:03.673Z,1564007283.673 [DVL_micro](INFO): Initializing
2019-07-24T22:31:07.889Z,1564007467.889 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:31:07.889Z,1564007467.889 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:31:07.889Z,1564007467.889 [DVL_micro](ERROR): Data Fault
2019-07-24T22:31:07.921Z,1564007467.921 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:31:08.366Z,1564007468.366 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:31:09.139Z,1564007469.139 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:31:09.139Z,1564007469.139 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:31:09.511Z,1564007469.511 [DVL_micro](INFO): Initializing
2019-07-24T22:31:40.237Z,1564007500.237 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-24T22:31:40.237Z,1564007500.237 [Default:CheckIn:C.Wait] Stopped
2019-07-24T22:31:40.237Z,1564007500.237 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-24T22:31:40.238Z,1564007500.238 [Default:CheckIn:D] Running Loop=1
2019-07-24T22:31:40.636Z,1564007500.636 [Default:CheckIn:D] Stopped
2019-07-24T22:31:40.636Z,1564007500.636 [Default:CheckIn:E] Running Loop=1
2019-07-24T22:31:41.046Z,1564007501.046 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.368638 min 
2019-07-24T22:31:41.046Z,1564007501.046 [Default:CheckIn:E] Stopped
2019-07-24T22:31:41.046Z,1564007501.046 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-24T22:31:41.046Z,1564007501.046 [Default:CheckIn] Stopped
2019-07-24T22:31:41.046Z,1564007501.046 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-24T22:31:41.046Z,1564007501.046 [Default:CheckIn](INFO): Running loop #3
2019-07-24T22:31:41.047Z,1564007501.047 [Default:CheckIn] Running Loop=3
2019-07-24T22:31:41.047Z,1564007501.047 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-24T22:31:41.047Z,1564007501.047 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-24T22:31:42.650Z,1564007502.650 [NAL9602](DEBUG): Fix Requested
2019-07-24T22:31:43.041Z,1564007503.041 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223136.00,A,3648.38755,N,12147.12753,W,4.471,188.37,240719,,,A*77
2019-07-24T22:31:43.043Z,1564007503.043 [NAL9602](INFO): GPS fix at 20190724T223136: (36.806459, -121.785459)
2019-07-24T22:31:43.105Z,1564007503.105 [Default:CheckIn:Read_GPS] Stopped
2019-07-24T22:31:43.105Z,1564007503.105 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-24T22:31:46.927Z,1564007506.927 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190724T222409/Courier0004.lzma
2019-07-24T22:31:47.639Z,1564007507.639 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Courier0004.lzma.bak
2019-07-24T22:31:47.640Z,1564007507.640 [DataOverHttps](INFO): SBD MOMSN=11472945
2019-07-24T22:31:58.857Z,1564007518.857 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190724T222409/Express0006.lzma
2019-07-24T22:31:59.663Z,1564007519.663 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0006.lzma.bak
2019-07-24T22:31:59.664Z,1564007519.664 [DataOverHttps](INFO): SBD MOMSN=11472947
2019-07-24T22:32:00.445Z,1564007520.445 [Default:CheckIn:Read_Iridium] Stopped
2019-07-24T22:32:00.445Z,1564007520.445 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-24T22:32:00.446Z,1564007520.446 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-24T22:32:04.867Z,1564007524.867 [NAL9602](INFO): SBD MO Status=2, MOMSN=30014, MT Status=2, MTMSN=0
2019-07-24T22:32:04.867Z,1564007524.867 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-07-24T22:32:59.810Z,1564007579.810 [NAL9602](INFO): SBD MO Status=2, MOMSN=30014, MT Status=2, MTMSN=0
2019-07-24T22:32:59.811Z,1564007579.811 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-07-24T22:33:10.723Z,1564007590.723 [NAL9602](INFO): SBD MO Status=1, MOMSN=30014, MT Status=0, MTMSN=0
2019-07-24T22:33:10.724Z,1564007590.724 [NAL9602](INFO): No messages in MT queue
2019-07-24T22:33:41.436Z,1564007621.436 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-24T22:34:13.735Z,1564007653.735 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:34:13.735Z,1564007653.735 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:34:13.735Z,1564007653.735 [DVL_micro](ERROR): Data Fault
2019-07-24T22:34:13.768Z,1564007653.768 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:34:14.210Z,1564007654.210 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:34:15.002Z,1564007655.002 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:34:15.002Z,1564007655.002 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:34:15.361Z,1564007655.361 [DVL_micro](INFO): Initializing
2019-07-24T22:37:01.017Z,1564007821.017 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-24T22:37:01.017Z,1564007821.017 [Default:CheckIn:C.Wait] Stopped
2019-07-24T22:37:01.017Z,1564007821.017 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-24T22:37:01.017Z,1564007821.017 [Default:CheckIn:D] Running Loop=1
2019-07-24T22:37:01.429Z,1564007821.429 [Default:CheckIn:D] Stopped
2019-07-24T22:37:01.429Z,1564007821.429 [Default:CheckIn:E] Running Loop=1
2019-07-24T22:37:01.820Z,1564007821.820 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.715196 min 
2019-07-24T22:37:01.821Z,1564007821.821 [Default:CheckIn:E] Stopped
2019-07-24T22:37:01.821Z,1564007821.821 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-24T22:37:01.821Z,1564007821.821 [Default:CheckIn] Stopped
2019-07-24T22:37:01.821Z,1564007821.821 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-24T22:37:01.821Z,1564007821.821 [Default:CheckIn](INFO): Running loop #4
2019-07-24T22:37:01.821Z,1564007821.821 [Default:CheckIn] Running Loop=4
2019-07-24T22:37:01.821Z,1564007821.821 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-24T22:37:01.821Z,1564007821.821 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-24T22:37:03.490Z,1564007823.490 [NAL9602](DEBUG): Fix Requested
2019-07-24T22:37:03.835Z,1564007823.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223656.00,A,3648.15060,N,12147.22307,W,0.019,217.04,240719,,,A*75
2019-07-24T22:37:03.837Z,1564007823.837 [NAL9602](INFO): GPS fix at 20190724T223656: (36.802510, -121.787051)
2019-07-24T22:37:03.889Z,1564007823.889 [Default:CheckIn:Read_GPS] Stopped
2019-07-24T22:37:03.889Z,1564007823.889 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-24T22:37:05.515Z,1564007825.515 [NAL9602](ERROR): parseGSV uart error: serial buffer full
2019-07-24T22:37:07.396Z,1564007827.396 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190724T222409/Courier0009.lzma
2019-07-24T22:37:08.199Z,1564007828.199 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Courier0009.lzma.bak
2019-07-24T22:37:08.200Z,1564007828.200 [DataOverHttps](INFO): SBD MOMSN=11472957
2019-07-24T22:37:19.482Z,1564007839.482 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20190724T222409/Express0010.lzma
2019-07-24T22:37:19.643Z,1564007839.643 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:37:19.643Z,1564007839.643 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:37:19.643Z,1564007839.643 [DVL_micro](ERROR): Data Fault
2019-07-24T22:37:19.710Z,1564007839.710 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:37:20.110Z,1564007840.110 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:37:20.287Z,1564007840.287 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0010.lzma.bak
2019-07-24T22:37:20.288Z,1564007840.288 [DataOverHttps](INFO): SBD MOMSN=11472960
2019-07-24T22:37:20.876Z,1564007840.876 [Default:CheckIn:Read_Iridium] Stopped
2019-07-24T22:37:20.876Z,1564007840.876 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-24T22:37:20.876Z,1564007840.876 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-24T22:37:20.884Z,1564007840.884 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:37:20.884Z,1564007840.884 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:37:21.261Z,1564007841.261 [DVL_micro](INFO): Initializing
2019-07-24T22:37:39.439Z,1564007859.439 [NAL9602](INFO): SBD MO Status=1, MOMSN=30015, MT Status=0, MTMSN=0
2019-07-24T22:37:39.439Z,1564007859.439 [NAL9602](INFO): No messages in MT queue
2019-07-24T22:38:10.139Z,1564007890.139 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-24T22:40:25.479Z,1564008025.479 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:40:25.479Z,1564008025.479 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:40:25.479Z,1564008025.479 [DVL_micro](ERROR): Data Fault
2019-07-24T22:40:25.554Z,1564008025.554 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:40:25.954Z,1564008025.954 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:40:26.730Z,1564008026.730 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:40:26.730Z,1564008026.730 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:40:27.105Z,1564008027.105 [DVL_micro](INFO): Initializing
2019-07-24T22:42:21.454Z,1564008141.454 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-24T22:42:21.454Z,1564008141.454 [Default:CheckIn:C.Wait] Stopped
2019-07-24T22:42:21.454Z,1564008141.454 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-24T22:42:21.454Z,1564008141.454 [Default:CheckIn:D] Running Loop=1
2019-07-24T22:42:21.858Z,1564008141.858 [Default:CheckIn:D] Stopped
2019-07-24T22:42:21.858Z,1564008141.858 [Default:CheckIn:E] Running Loop=1
2019-07-24T22:42:22.262Z,1564008142.262 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.055680 min 
2019-07-24T22:42:22.262Z,1564008142.262 [Default:CheckIn:E] Stopped
2019-07-24T22:42:22.262Z,1564008142.262 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-24T22:42:22.262Z,1564008142.262 [Default:CheckIn] Stopped
2019-07-24T22:42:22.263Z,1564008142.263 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-24T22:42:22.263Z,1564008142.263 [Default:CheckIn](INFO): Running loop #5
2019-07-24T22:42:22.263Z,1564008142.263 [Default:CheckIn] Running Loop=5
2019-07-24T22:42:22.263Z,1564008142.263 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-24T22:42:22.263Z,1564008142.263 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-24T22:42:23.870Z,1564008143.870 [NAL9602](DEBUG): Fix Requested
2019-07-24T22:42:24.272Z,1564008144.272 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224216.00,A,3648.14887,N,12147.22380,W,0.078,254.20,240719,,,D*7E
2019-07-24T22:42:24.274Z,1564008144.274 [NAL9602](INFO): GPS fix at 20190724T224216: (36.802481, -121.787063)
2019-07-24T22:42:24.345Z,1564008144.345 [Default:CheckIn:Read_GPS] Stopped
2019-07-24T22:42:24.345Z,1564008144.345 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-24T22:42:28.721Z,1564008148.721 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190724T222409/Courier0012.lzma
2019-07-24T22:42:29.527Z,1564008149.527 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Courier0012.lzma.bak
2019-07-24T22:42:29.527Z,1564008149.527 [DataOverHttps](INFO): SBD MOMSN=11472972
2019-07-24T22:42:40.686Z,1564008160.686 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20190724T222409/Express0013.lzma
2019-07-24T22:42:41.289Z,1564008161.289 [NAL9602](INFO): SBD MO Status=1, MOMSN=30016, MT Status=0, MTMSN=0
2019-07-24T22:42:41.289Z,1564008161.289 [NAL9602](INFO): No messages in MT queue
2019-07-24T22:42:41.491Z,1564008161.491 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0013.lzma.bak
2019-07-24T22:42:41.492Z,1564008161.492 [DataOverHttps](INFO): SBD MOMSN=11472975
2019-07-24T22:42:42.125Z,1564008162.125 [Default:CheckIn:Read_Iridium] Stopped
2019-07-24T22:42:42.125Z,1564008162.125 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-24T22:42:42.125Z,1564008162.125 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-24T22:43:05.535Z,1564008185.535 [DVL_micro](ERROR): checksum mismatch: reported84 , calculated:85
2019-07-24T22:43:05.535Z,1564008185.535 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 841 594 3 3 2 2 650.0 -162.8 663.5 2 -669.9 -15.6 663.5 2 650 -162 663 2 -669 -15 663 2 13.94 -2.02 195.4 -3.0 16.4 0.005 35.0 1489 84
2019-07-24T22:43:12.018Z,1564008192.018 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-24T22:43:31.385Z,1564008211.385 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:43:31.385Z,1564008211.385 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:43:31.385Z,1564008211.385 [DVL_micro](ERROR): Data Fault
2019-07-24T22:43:31.418Z,1564008211.418 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:43:31.862Z,1564008211.862 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:43:32.626Z,1564008212.626 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:43:32.626Z,1564008212.626 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:43:33.011Z,1564008213.011 [DVL_micro](INFO): Initializing
2019-07-24T22:46:37.221Z,1564008397.221 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:46:37.221Z,1564008397.221 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:46:37.221Z,1564008397.221 [DVL_micro](ERROR): Data Fault
2019-07-24T22:46:37.272Z,1564008397.272 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:46:37.702Z,1564008397.702 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:46:38.467Z,1564008398.467 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:46:38.467Z,1564008398.467 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:46:38.843Z,1564008398.843 [DVL_micro](INFO): Initializing
2019-07-24T22:47:42.717Z,1564008462.717 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-24T22:47:42.717Z,1564008462.717 [Default:CheckIn:C.Wait] Stopped
2019-07-24T22:47:42.717Z,1564008462.717 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-24T22:47:42.718Z,1564008462.718 [Default:CheckIn:D] Running Loop=1
2019-07-24T22:47:43.121Z,1564008463.121 [Default:CheckIn:D] Stopped
2019-07-24T22:47:43.121Z,1564008463.121 [Default:CheckIn:E] Running Loop=1
2019-07-24T22:47:43.546Z,1564008463.546 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.410059 min 
2019-07-24T22:47:43.546Z,1564008463.546 [Default:CheckIn:E] Stopped
2019-07-24T22:47:43.546Z,1564008463.546 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-24T22:47:43.546Z,1564008463.546 [Default:CheckIn] Stopped
2019-07-24T22:47:43.546Z,1564008463.546 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-24T22:47:43.546Z,1564008463.546 [Default:CheckIn](INFO): Running loop #6
2019-07-24T22:47:43.547Z,1564008463.547 [Default:CheckIn] Running Loop=6
2019-07-24T22:47:43.547Z,1564008463.547 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-24T22:47:43.547Z,1564008463.547 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-24T22:47:45.141Z,1564008465.141 [NAL9602](DEBUG): Fix Requested
2019-07-24T22:47:45.533Z,1564008465.533 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224736.00,A,3648.14963,N,12147.22375,W,0.117,254.20,240719,,,D*70
2019-07-24T22:47:45.535Z,1564008465.535 [NAL9602](INFO): GPS fix at 20190724T224736: (36.802494, -121.787063)
2019-07-24T22:47:45.589Z,1564008465.589 [Default:CheckIn:Read_GPS] Stopped
2019-07-24T22:47:45.589Z,1564008465.589 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-24T22:47:49.009Z,1564008469.009 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190724T222409/Courier0015.lzma
2019-07-24T22:47:49.816Z,1564008469.816 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Courier0015.lzma.bak
2019-07-24T22:47:49.816Z,1564008469.816 [DataOverHttps](INFO): SBD MOMSN=11472987
2019-07-24T22:48:01.098Z,1564008481.098 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20190724T222409/Express0016.lzma
2019-07-24T22:48:01.903Z,1564008481.903 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0016.lzma.bak
2019-07-24T22:48:01.904Z,1564008481.904 [DataOverHttps](INFO): SBD MOMSN=11472990
2019-07-24T22:48:02.575Z,1564008482.575 [Default:CheckIn:Read_Iridium] Stopped
2019-07-24T22:48:02.575Z,1564008482.575 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-24T22:48:02.575Z,1564008482.575 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-24T22:48:28.003Z,1564008508.003 [NAL9602](INFO): SBD MO Status=1, MOMSN=30017, MT Status=0, MTMSN=0
2019-07-24T22:48:28.003Z,1564008508.003 [NAL9602](INFO): No messages in MT queue
2019-07-24T22:48:58.706Z,1564008538.706 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-24T22:49:43.141Z,1564008583.141 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:49:43.141Z,1564008583.141 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:49:43.141Z,1564008583.141 [DVL_micro](ERROR): Data Fault
2019-07-24T22:49:43.173Z,1564008583.173 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:49:43.627Z,1564008583.627 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:49:44.391Z,1564008584.391 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:49:44.391Z,1564008584.391 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:49:44.770Z,1564008584.770 [DVL_micro](INFO): Initializing
2019-07-24T22:52:48.981Z,1564008768.981 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:52:48.981Z,1564008768.981 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:52:48.981Z,1564008768.981 [DVL_micro](ERROR): Data Fault
2019-07-24T22:52:49.042Z,1564008769.042 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:52:49.474Z,1564008769.474 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:52:50.287Z,1564008770.287 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:52:50.287Z,1564008770.287 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:52:50.609Z,1564008770.609 [DVL_micro](INFO): Initializing
2019-07-24T22:53:03.144Z,1564008783.144 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-24T22:53:03.144Z,1564008783.144 [Default:CheckIn:C.Wait] Stopped
2019-07-24T22:53:03.144Z,1564008783.144 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-24T22:53:03.144Z,1564008783.144 [Default:CheckIn:D] Running Loop=1
2019-07-24T22:53:03.569Z,1564008783.569 [Default:CheckIn:D] Stopped
2019-07-24T22:53:03.569Z,1564008783.569 [Default:CheckIn:E] Running Loop=1
2019-07-24T22:53:03.962Z,1564008783.962 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.750863 min 
2019-07-24T22:53:03.962Z,1564008783.962 [Default:CheckIn:E] Stopped
2019-07-24T22:53:03.962Z,1564008783.962 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-24T22:53:03.962Z,1564008783.962 [Default:CheckIn] Stopped
2019-07-24T22:53:03.962Z,1564008783.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-24T22:53:03.962Z,1564008783.962 [Default:CheckIn](INFO): Running loop #7
2019-07-24T22:53:03.962Z,1564008783.962 [Default:CheckIn] Running Loop=7
2019-07-24T22:53:03.963Z,1564008783.963 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-24T22:53:03.963Z,1564008783.963 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-24T22:53:05.562Z,1564008785.562 [NAL9602](DEBUG): Fix Requested
2019-07-24T22:53:05.960Z,1564008785.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225257.00,A,3648.14985,N,12147.22437,W,0.136,254.20,240719,,,D*79
2019-07-24T22:53:05.966Z,1564008785.966 [NAL9602](INFO): GPS fix at 20190724T225257: (36.802498, -121.787073)
2019-07-24T22:53:06.017Z,1564008786.017 [Default:CheckIn:Read_GPS] Stopped
2019-07-24T22:53:06.017Z,1564008786.017 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-24T22:53:10.389Z,1564008790.389 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190724T222409/Courier0018.lzma
2019-07-24T22:53:11.195Z,1564008791.195 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Courier0018.lzma.bak
2019-07-24T22:53:11.196Z,1564008791.196 [DataOverHttps](INFO): SBD MOMSN=11473006
2019-07-24T22:53:22.378Z,1564008802.378 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20190724T222409/Express0019.lzma
2019-07-24T22:53:23.183Z,1564008803.183 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0019.lzma.bak
2019-07-24T22:53:23.184Z,1564008803.184 [DataOverHttps](INFO): SBD MOMSN=11473009
2019-07-24T22:53:23.840Z,1564008803.840 [Default:CheckIn:Read_Iridium] Stopped
2019-07-24T22:53:23.840Z,1564008803.840 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-24T22:53:23.841Z,1564008803.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-24T22:53:25.045Z,1564008805.045 [NAL9602](INFO): SBD MO Status=1, MOMSN=30018, MT Status=0, MTMSN=0
2019-07-24T22:53:25.045Z,1564008805.045 [NAL9602](INFO): No messages in MT queue
2019-07-24T22:53:55.742Z,1564008835.742 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-24T22:55:54.925Z,1564008954.925 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:55:54.925Z,1564008954.925 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:55:54.925Z,1564008954.925 [DVL_micro](ERROR): Data Fault
2019-07-24T22:55:54.957Z,1564008954.957 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:55:55.390Z,1564008955.390 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:55:56.195Z,1564008956.195 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:55:56.195Z,1564008956.195 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:55:56.533Z,1564008956.533 [DVL_micro](INFO): Initializing
2019-07-24T22:58:24.429Z,1564009104.429 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-24T22:58:24.430Z,1564009104.430 [Default:CheckIn:C.Wait] Stopped
2019-07-24T22:58:24.430Z,1564009104.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-24T22:58:24.430Z,1564009104.430 [Default:CheckIn:D] Running Loop=1
2019-07-24T22:58:24.829Z,1564009104.829 [Default:CheckIn:D] Stopped
2019-07-24T22:58:24.830Z,1564009104.830 [Default:CheckIn:E] Running Loop=1
2019-07-24T22:58:25.252Z,1564009105.252 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.105200 min 
2019-07-24T22:58:25.252Z,1564009105.252 [Default:CheckIn:E] Stopped
2019-07-24T22:58:25.253Z,1564009105.253 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-24T22:58:25.253Z,1564009105.253 [Default:CheckIn] Stopped
2019-07-24T22:58:25.253Z,1564009105.253 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-24T22:58:25.253Z,1564009105.253 [Default:CheckIn](INFO): Running loop #8
2019-07-24T22:58:25.253Z,1564009105.253 [Default:CheckIn] Running Loop=8
2019-07-24T22:58:25.253Z,1564009105.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-24T22:58:25.253Z,1564009105.253 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-24T22:58:26.842Z,1564009106.842 [NAL9602](DEBUG): Fix Requested
2019-07-24T22:58:27.235Z,1564009107.235 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225818.00,A,3648.20528,N,12147.16911,W,5.443,33.73,240719,,,D*4D
2019-07-24T22:58:27.237Z,1564009107.237 [NAL9602](INFO): GPS fix at 20190724T225818: (36.803421, -121.786152)
2019-07-24T22:58:27.273Z,1564009107.273 [Default:CheckIn:Read_GPS] Stopped
2019-07-24T22:58:27.273Z,1564009107.273 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-24T22:58:28.521Z,1564009108.521 [DVL_micro](ERROR): only read 43 of 46 data items
2019-07-24T22:58:28.535Z,1564009108.535 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 06 1271 8 3 2 3 760.7 583.2 1161.3 2 867.1 -408.5 1161.3 2 760 583 1161 2 867 -408 1161 2 1.03 1.67 297.3 -3.0 18.8 0.005 35.0 1489 74
2019-07-24T22:58:31.141Z,1564009111.141 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190724T222409/Courier0021.lzma
2019-07-24T22:58:31.947Z,1564009111.947 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Courier0021.lzma.bak
2019-07-24T22:58:31.948Z,1564009111.948 [DataOverHttps](INFO): SBD MOMSN=11473028
2019-07-24T22:58:44.287Z,1564009124.287 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20190724T222409/Express0022.lzma
2019-07-24T22:58:45.091Z,1564009125.091 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0022.lzma.bak
2019-07-24T22:58:45.092Z,1564009125.092 [DataOverHttps](INFO): SBD MOMSN=11473031
2019-07-24T22:58:45.536Z,1564009125.536 [Default:CheckIn:Read_Iridium] Stopped
2019-07-24T22:58:45.536Z,1564009125.536 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-24T22:58:45.536Z,1564009125.536 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-24T22:58:46.297Z,1564009126.297 [NAL9602](INFO): SBD MO Status=1, MOMSN=30019, MT Status=0, MTMSN=0
2019-07-24T22:58:46.297Z,1564009126.297 [NAL9602](INFO): No messages in MT queue
2019-07-24T22:59:00.839Z,1564009140.839 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T22:59:00.839Z,1564009140.839 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T22:59:00.839Z,1564009140.839 [DVL_micro](ERROR): Data Fault
2019-07-24T22:59:00.871Z,1564009140.871 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T22:59:01.319Z,1564009141.319 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T22:59:02.097Z,1564009142.097 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T22:59:02.097Z,1564009142.097 [DVL_micro] No Fault, FailCount= 1
2019-07-24T22:59:02.456Z,1564009142.456 [DVL_micro](INFO): Initializing
2019-07-24T22:59:16.993Z,1564009156.993 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-24T22:59:51.663Z,1564009191.663 [DataOverHttps](IMPORTANT): SBD MTMSN=20190724T225942
2019-07-24T22:59:56.921Z,1564009196.921 [DataOverHttps](INFO): Received command:configSet VerticalControl.massDefault 30 mm persist
2019-07-24T22:59:57.203Z,1564009197.203 [CommandLine](IMPORTANT): got command configSet VerticalControl.massDefault 30.000000 millimeter persist
2019-07-24T23:00:28.063Z,1564009228.063 [DataOverHttps](IMPORTANT): SBD MTMSN=20190724T230018
2019-07-24T23:00:33.230Z,1564009233.230 [DataOverHttps](INFO): Received command:configSet VerticalControl.buoyancyNeutral 400 cc persist
2019-07-24T23:00:33.498Z,1564009233.498 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 400.000000 cubic_centimeter persist
2019-07-24T23:02:06.696Z,1564009326.696 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T23:02:06.696Z,1564009326.696 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T23:02:06.696Z,1564009326.696 [DVL_micro](ERROR): Data Fault
2019-07-24T23:02:06.744Z,1564009326.744 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T23:02:07.170Z,1564009327.170 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T23:02:07.937Z,1564009327.937 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T23:02:07.938Z,1564009327.938 [DVL_micro] No Fault, FailCount= 1
2019-07-24T23:02:08.321Z,1564009328.321 [DVL_micro](INFO): Initializing
2019-07-24T23:03:45.282Z,1564009425.282 [DVL_micro](ERROR): only read 43 of 46 data items
2019-07-24T23:03:45.282Z,1564009425.282 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 1416 -80 240 283 3 3 2 2 1569.5 -484.5 501.2 2 -1602.1 362.7 501.2 2 1569 -484 501 2 -1602 362 501 2 -7.39 4.12 184.4 -3.0 19.3 0.005 35.0 1489 68
2019-07-24T23:03:46.101Z,1564009426.101 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-24T23:03:46.101Z,1564009426.101 [Default:CheckIn:C.Wait] Stopped
2019-07-24T23:03:46.101Z,1564009426.101 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-24T23:03:46.101Z,1564009426.101 [Default:CheckIn:D] Running Loop=1
2019-07-24T23:03:46.509Z,1564009426.509 [Default:CheckIn:D] Stopped
2019-07-24T23:03:46.509Z,1564009426.509 [Default:CheckIn:E] Running Loop=1
2019-07-24T23:03:46.914Z,1564009426.914 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.466532 min 
2019-07-24T23:03:46.914Z,1564009426.914 [Default:CheckIn:E] Stopped
2019-07-24T23:03:46.915Z,1564009426.915 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-24T23:03:46.915Z,1564009426.915 [Default:CheckIn] Stopped
2019-07-24T23:03:46.915Z,1564009426.915 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-24T23:03:46.915Z,1564009426.915 [Default:CheckIn](INFO): Running loop #9
2019-07-24T23:03:46.915Z,1564009426.915 [Default:CheckIn] Running Loop=9
2019-07-24T23:03:46.915Z,1564009426.915 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-24T23:03:46.915Z,1564009426.915 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-24T23:03:48.550Z,1564009428.550 [NAL9602](DEBUG): Fix Requested
2019-07-24T23:03:48.915Z,1564009428.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230339.00,A,3648.33109,N,12147.74337,W,17.281,266.93,240719,,,A*4C
2019-07-24T23:03:48.917Z,1564009428.917 [NAL9602](INFO): GPS fix at 20190724T230339: (36.805518, -121.795723)
2019-07-24T23:03:48.965Z,1564009428.965 [Default:CheckIn:Read_GPS] Stopped
2019-07-24T23:03:48.965Z,1564009428.965 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-24T23:03:54.769Z,1564009434.769 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190724T222409/Courier0024.lzma
2019-07-24T23:03:55.575Z,1564009435.575 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Courier0024.lzma.bak
2019-07-24T23:03:55.576Z,1564009435.576 [DataOverHttps](INFO): SBD MOMSN=11473051
2019-07-24T23:04:06.609Z,1564009446.609 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190724T222409/Courier0027.lzma
2019-07-24T23:04:07.415Z,1564009447.415 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Courier0027.lzma.bak
2019-07-24T23:04:07.416Z,1564009447.416 [DataOverHttps](INFO): SBD MOMSN=11473054
2019-07-24T23:04:17.201Z,1564009457.201 [NAL9602](INFO): SBD MO Status=2, MOMSN=30020, MT Status=2, MTMSN=0
2019-07-24T23:04:17.201Z,1564009457.201 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-07-24T23:04:18.710Z,1564009458.710 [DataOverHttps](INFO): Sending 361 bytes from file Logs/20190724T222409/Express0025.lzma
2019-07-24T23:04:19.515Z,1564009459.515 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0025.lzma.bak
2019-07-24T23:04:19.516Z,1564009459.516 [DataOverHttps](INFO): SBD MOMSN=11473056
2019-07-24T23:04:32.277Z,1564009472.277 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20190724T222409/Express0026.lzma
2019-07-24T23:04:33.083Z,1564009473.083 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0026.lzma.bak
2019-07-24T23:04:33.084Z,1564009473.084 [DataOverHttps](INFO): SBD MOMSN=11473065
2019-07-24T23:04:45.909Z,1564009485.909 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190724T222409/Express0028.lzma
2019-07-24T23:04:46.715Z,1564009486.715 [DataOverHttps](INFO): Moved sent file to Logs/20190724T222409/Express0028.lzma.bak
2019-07-24T23:04:46.716Z,1564009486.716 [DataOverHttps](INFO): SBD MOMSN=11473067
2019-07-24T23:04:47.521Z,1564009487.521 [Default:CheckIn:Read_Iridium] Stopped
2019-07-24T23:04:47.521Z,1564009487.521 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-24T23:04:47.524Z,1564009487.524 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-24T23:04:55.573Z,1564009495.573 [NAL9602](INFO): SBD MO Status=1, MOMSN=30020, MT Status=0, MTMSN=0
2019-07-24T23:04:55.573Z,1564009495.573 [NAL9602](INFO): No messages in MT queue
2019-07-24T23:05:12.539Z,1564009512.539 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T23:05:12.539Z,1564009512.539 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T23:05:12.539Z,1564009512.539 [DVL_micro](ERROR): Data Fault
2019-07-24T23:05:12.593Z,1564009512.593 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T23:05:13.014Z,1564009513.014 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T23:05:13.789Z,1564009513.789 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T23:05:13.789Z,1564009513.789 [DVL_micro] No Fault, FailCount= 1
2019-07-24T23:05:14.165Z,1564009514.165 [DVL_micro](INFO): Initializing
2019-07-24T23:05:26.275Z,1564009526.275 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-24T23:08:18.375Z,1564009698.375 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-24T23:08:18.375Z,1564009698.375 [DVL_micro] Data Fault, FailCount= 1
2019-07-24T23:08:18.375Z,1564009698.375 [DVL_micro](ERROR): Data Fault
2019-07-24T23:08:18.439Z,1564009698.439 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-24T23:08:18.854Z,1564009698.854 [DVL_micro](INFO): uninitialize:Powering down
2019-07-24T23:08:19.678Z,1564009699.678 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-24T23:08:19.678Z,1564009699.678 [DVL_micro] No Fault, FailCount= 1
2019-07-24T23:08:20.007Z,1564009700.007 [DVL_micro](INFO): Initializing
2019-07-24T23:09:24.683Z,1564009764.683 [DataOverHttps](IMPORTANT): SBD MTMSN=20190724T230914
2019-07-24T23:09:29.786Z,1564009769.786 [DataOverHttps](INFO): Received command:restart logs