2022-02-25T20:11:35.928Z,1645819895.928 [NAL9602](INFO): Received command: restart logs
2022-02-25T20:11:35.953Z,1645819895.953 [CommandExec](IMPORTANT): got command restart logs
2022-02-25T20:11:52.502Z,1645819912.502 [NAL9602](INFO): SBD MO Status=1, MOMSN=31852, MT Status=0, MTMSN=0
2022-02-25T20:11:52.555Z,1645819912.555 [NAL9602](INFO): Sent 71 bytes from file Logs/20220225T180435/Courier0030.lzma
2022-02-25T20:11:52.556Z,1645819912.556 [NAL9602](INFO): Packets left to send: 0
2022-02-25T20:12:01.038Z,1645819921.038 [NAL9602](INFO): SBD MO Status=1, MOMSN=31853, MT Status=0, MTMSN=0
2022-02-25T20:12:01.086Z,1645819921.086 [NAL9602](INFO): Sent 39 bytes from file Logs/20220225T180435/Courier0033.lzma
2022-02-25T20:12:01.086Z,1645819921.086 [NAL9602](INFO): Packets left to send: 0
2022-02-25T20:12:03.723Z,1645819923.723 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-02-25T20:12:12.810Z,1645819932.810 [NAL9602](INFO): SBD MO Status=1, MOMSN=31854, MT Status=0, MTMSN=0
2022-02-25T20:12:12.868Z,1645819932.868 [NAL9602](INFO): Sent 145 bytes from file Logs/20220225T180435/Express0031.lzma
2022-02-25T20:12:12.868Z,1645819932.868 [NAL9602](INFO): Packets left to send: 0
2022-02-25T20:12:32.646Z,1645819952.646 [NAL9602](INFO): SBD MO Status=1, MOMSN=31855, MT Status=0, MTMSN=0
2022-02-25T20:12:32.692Z,1645819952.692 [NAL9602](INFO): Sent 118 bytes from file Logs/20220225T180435/Express0034.lzma
2022-02-25T20:12:32.692Z,1645819952.692 [NAL9602](INFO): Packets left to send: 0
2022-02-25T20:12:34.847Z,1645819954.847 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-02-25T20:12:41.130Z,1645819961.130 [NAL9602](INFO): SBD MO Status=0, MOMSN=31856, MT Status=0, MTMSN=0
2022-02-25T20:13:05.999Z,1645819985.999 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-02-25T20:13:07.056Z,1645819987.056 [NAL9602](INFO): SBD MO Status=2, MOMSN=31857, MT Status=2, MTMSN=0
2022-02-25T20:13:07.056Z,1645819987.056 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-25T20:13:16.806Z,1645819996.806 [NAL9602](INFO): SBD MO Status=1, MOMSN=31857, MT Status=0, MTMSN=0
2022-02-25T20:13:16.868Z,1645819996.868 [NAL9602](INFO): Sent 151 bytes from file Logs/20220225T201135/Courier0000.lzma
2022-02-25T20:13:16.868Z,1645819996.868 [NAL9602](INFO): Packets left to send: 0
2022-02-25T20:13:30.170Z,1645820010.170 [NAL9602](INFO): SBD MO Status=1, MOMSN=31858, MT Status=0, MTMSN=0
2022-02-25T20:13:30.227Z,1645820010.227 [NAL9602](INFO): Sent 314 bytes from file Logs/20220225T201135/Express0001.lzma
2022-02-25T20:13:30.228Z,1645820010.228 [NAL9602](INFO): Packets left to send: 0
2022-02-25T20:13:37.147Z,1645820017.147 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-02-25T20:13:37.842Z,1645820017.842 [NAL9602](INFO): SBD MO Status=0, MOMSN=31859, MT Status=0, MTMSN=0
2022-02-25T20:13:37.925Z,1645820017.925 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T20:13:37.925Z,1645820017.925 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T20:13:37.925Z,1645820017.925 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T20:14:08.271Z,1645820048.271 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-02-25T20:14:08.548Z,1645820048.548 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T20:14:20.411Z,1645820060.411 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002933
2022-02-25T20:18:38.477Z,1645820318.477 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T20:18:38.477Z,1645820318.477 [Default:CheckIn:C.Wait] Stopped
2022-02-25T20:18:38.477Z,1645820318.477 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T20:18:38.478Z,1645820318.478 [Default:CheckIn:D] Running Loop=1
2022-02-25T20:18:38.895Z,1645820318.895 [Default:CheckIn:D] Stopped
2022-02-25T20:18:38.896Z,1645820318.896 [Default:CheckIn:E] Running Loop=1
2022-02-25T20:18:39.287Z,1645820319.287 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.907235 min 
2022-02-25T20:18:39.287Z,1645820319.287 [Default:CheckIn:E] Stopped
2022-02-25T20:18:39.287Z,1645820319.287 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T20:18:39.288Z,1645820319.288 [Default:CheckIn] Stopped
2022-02-25T20:18:39.288Z,1645820319.288 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T20:18:39.288Z,1645820319.288 [Default:CheckIn](INFO): Running loop #6
2022-02-25T20:18:39.288Z,1645820319.288 [Default:CheckIn] Running Loop=6
2022-02-25T20:18:39.288Z,1645820319.288 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T20:18:39.288Z,1645820319.288 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T20:18:41.299Z,1645820321.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201840.00,A,3648.02505,N,12148.01405,W,4.782,2.57,250222,,,D*7F
2022-02-25T20:18:41.301Z,1645820321.301 [NAL9602](INFO): GPS fix at 20220225T201840: (36.800418, -121.800234)
2022-02-25T20:18:41.312Z,1645820321.312 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T20:18:41.312Z,1645820321.312 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T20:18:50.847Z,1645820330.847 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20220225T201135/Courier0003.lzma
2022-02-25T20:18:51.849Z,1645820331.849 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0003.lzma.bak
2022-02-25T20:18:51.849Z,1645820331.849 [DataOverHttps](INFO): SBD MOMSN=16514734
2022-02-25T20:19:12.831Z,1645820352.831 [NAL9602](INFO): SBD MO Status=0, MOMSN=31860, MT Status=0, MTMSN=0
2022-02-25T20:19:12.832Z,1645820352.832 [NAL9602](INFO): No messages in MT queue
2022-02-25T20:19:28.728Z,1645820368.728 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220225T201135/Express0004.lzma
2022-02-25T20:19:29.729Z,1645820369.729 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0004.lzma.bak
2022-02-25T20:19:29.729Z,1645820369.729 [DataOverHttps](INFO): SBD MOMSN=16514737
2022-02-25T20:19:31.040Z,1645820371.040 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T20:19:31.041Z,1645820371.041 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T20:19:31.041Z,1645820371.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T20:19:43.532Z,1645820383.532 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T20:22:53.426Z,1645820573.426 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-02-25T20:22:53.429Z,1645820573.429 [BPC1](INFO): Received data from all battery sticks.
2022-02-25T20:24:31.606Z,1645820671.606 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T20:24:31.606Z,1645820671.606 [Default:CheckIn:C.Wait] Stopped
2022-02-25T20:24:31.606Z,1645820671.606 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T20:24:31.607Z,1645820671.607 [Default:CheckIn:D] Running Loop=1
2022-02-25T20:24:32.012Z,1645820672.012 [Default:CheckIn:D] Stopped
2022-02-25T20:24:32.012Z,1645820672.012 [Default:CheckIn:E] Running Loop=1
2022-02-25T20:24:32.425Z,1645820672.425 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.792505 min 
2022-02-25T20:24:32.426Z,1645820672.426 [Default:CheckIn:E] Stopped
2022-02-25T20:24:32.426Z,1645820672.426 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T20:24:32.426Z,1645820672.426 [Default:CheckIn] Stopped
2022-02-25T20:24:32.426Z,1645820672.426 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T20:24:32.426Z,1645820672.426 [Default:CheckIn](INFO): Running loop #7
2022-02-25T20:24:32.426Z,1645820672.426 [Default:CheckIn] Running Loop=7
2022-02-25T20:24:32.426Z,1645820672.426 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T20:24:32.426Z,1645820672.426 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T20:24:34.439Z,1645820674.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202433.00,A,3648.42115,N,12147.81199,W,6.667,173.21,250222,,,A*79
2022-02-25T20:24:34.441Z,1645820674.441 [NAL9602](INFO): GPS fix at 20220225T202433: (36.807019, -121.796866)
2022-02-25T20:24:34.482Z,1645820674.482 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T20:24:34.482Z,1645820674.482 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T20:24:41.683Z,1645820681.683 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20220225T201135/Courier0006.lzma
2022-02-25T20:24:42.685Z,1645820682.685 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0006.lzma.bak
2022-02-25T20:24:42.685Z,1645820682.685 [DataOverHttps](INFO): SBD MOMSN=16514741
2022-02-25T20:24:56.670Z,1645820696.670 [NAL9602](INFO): SBD MO Status=2, MOMSN=31861, MT Status=2, MTMSN=0
2022-02-25T20:24:56.670Z,1645820696.670 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-25T20:25:02.351Z,1645820702.351 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220225T201135/Express0007.lzma
2022-02-25T20:25:03.353Z,1645820703.353 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0007.lzma.bak
2022-02-25T20:25:03.353Z,1645820703.353 [DataOverHttps](INFO): SBD MOMSN=16514744
2022-02-25T20:25:04.784Z,1645820704.784 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T20:25:04.785Z,1645820704.785 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T20:25:04.785Z,1645820704.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T20:26:25.554Z,1645820785.554 [NAL9602](INFO): SBD MO Status=0, MOMSN=31861, MT Status=0, MTMSN=0
2022-02-25T20:26:25.554Z,1645820785.554 [NAL9602](INFO): No messages in MT queue
2022-02-25T20:26:56.256Z,1645820816.256 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T20:30:05.349Z,1645821005.349 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T20:30:05.349Z,1645821005.349 [Default:CheckIn:C.Wait] Stopped
2022-02-25T20:30:05.349Z,1645821005.349 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T20:30:05.350Z,1645821005.350 [Default:CheckIn:D] Running Loop=1
2022-02-25T20:30:05.752Z,1645821005.752 [Default:CheckIn:D] Stopped
2022-02-25T20:30:05.752Z,1645821005.752 [Default:CheckIn:E] Running Loop=1
2022-02-25T20:30:06.151Z,1645821006.151 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.354850 min 
2022-02-25T20:30:06.151Z,1645821006.151 [Default:CheckIn:E] Stopped
2022-02-25T20:30:06.151Z,1645821006.151 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T20:30:06.188Z,1645821006.188 [Default:CheckIn] Stopped
2022-02-25T20:30:06.188Z,1645821006.188 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T20:30:06.188Z,1645821006.188 [Default:CheckIn](INFO): Running loop #8
2022-02-25T20:30:06.188Z,1645821006.188 [Default:CheckIn] Running Loop=8
2022-02-25T20:30:06.188Z,1645821006.188 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T20:30:06.188Z,1645821006.188 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T20:30:08.158Z,1645821008.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203007.00,A,3648.29550,N,12147.66992,W,6.162,343.20,250222,,,D*7E
2022-02-25T20:30:08.161Z,1645821008.161 [NAL9602](INFO): GPS fix at 20220225T203007: (36.804925, -121.794499)
2022-02-25T20:30:08.173Z,1645821008.173 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T20:30:08.173Z,1645821008.173 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T20:30:16.191Z,1645821016.191 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220225T201135/Courier0009.lzma
2022-02-25T20:30:17.193Z,1645821017.193 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0009.lzma.bak
2022-02-25T20:30:17.193Z,1645821017.193 [DataOverHttps](INFO): SBD MOMSN=16514748
2022-02-25T20:30:27.618Z,1645821027.618 [NAL9602](INFO): SBD MO Status=2, MOMSN=31862, MT Status=2, MTMSN=0
2022-02-25T20:30:27.618Z,1645821027.618 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-25T20:30:36.171Z,1645821036.171 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220225T201135/Express0010.lzma
2022-02-25T20:30:37.173Z,1645821037.173 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0010.lzma.bak
2022-02-25T20:30:37.173Z,1645821037.173 [DataOverHttps](INFO): SBD MOMSN=16514751
2022-02-25T20:30:38.553Z,1645821038.553 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T20:30:38.553Z,1645821038.553 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T20:30:38.553Z,1645821038.553 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T20:30:42.570Z,1645821042.570 [NAL9602](INFO): SBD MO Status=2, MOMSN=31862, MT Status=2, MTMSN=0
2022-02-25T20:30:42.570Z,1645821042.570 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-25T20:30:51.454Z,1645821051.454 [NAL9602](INFO): SBD MO Status=0, MOMSN=31862, MT Status=0, MTMSN=0
2022-02-25T20:30:51.454Z,1645821051.454 [NAL9602](INFO): No messages in MT queue
2022-02-25T20:31:22.164Z,1645821082.164 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T20:35:39.143Z,1645821339.143 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T20:35:39.143Z,1645821339.143 [Default:CheckIn:C.Wait] Stopped
2022-02-25T20:35:39.143Z,1645821339.143 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T20:35:39.144Z,1645821339.144 [Default:CheckIn:D] Running Loop=1
2022-02-25T20:35:39.554Z,1645821339.554 [Default:CheckIn:D] Stopped
2022-02-25T20:35:39.554Z,1645821339.554 [Default:CheckIn:E] Running Loop=1
2022-02-25T20:35:39.948Z,1645821339.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.918205 min 
2022-02-25T20:35:39.948Z,1645821339.948 [Default:CheckIn:E] Stopped
2022-02-25T20:35:39.948Z,1645821339.948 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T20:35:39.948Z,1645821339.948 [Default:CheckIn] Stopped
2022-02-25T20:35:39.948Z,1645821339.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T20:35:39.948Z,1645821339.948 [Default:CheckIn](INFO): Running loop #9
2022-02-25T20:35:39.948Z,1645821339.948 [Default:CheckIn] Running Loop=9
2022-02-25T20:35:39.948Z,1645821339.948 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T20:35:39.949Z,1645821339.949 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T20:35:41.954Z,1645821341.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203541.00,A,3648.37101,N,12147.92128,W,7.153,260.36,250222,,,D*70
2022-02-25T20:35:41.957Z,1645821341.957 [NAL9602](INFO): GPS fix at 20220225T203541: (36.806184, -121.798688)
2022-02-25T20:35:41.998Z,1645821341.998 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T20:35:41.998Z,1645821341.998 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T20:35:49.731Z,1645821349.731 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220225T201135/Courier0012.lzma
2022-02-25T20:35:50.733Z,1645821350.733 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0012.lzma.bak
2022-02-25T20:35:50.733Z,1645821350.733 [DataOverHttps](INFO): SBD MOMSN=16514755
2022-02-25T20:36:08.731Z,1645821368.731 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20220225T201135/Express0013.lzma
2022-02-25T20:36:09.733Z,1645821369.733 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0013.lzma.bak
2022-02-25T20:36:09.733Z,1645821369.733 [DataOverHttps](INFO): SBD MOMSN=16514758
2022-02-25T20:36:11.059Z,1645821371.059 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T20:36:11.059Z,1645821371.059 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T20:36:11.059Z,1645821371.059 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T20:36:28.822Z,1645821388.822 [NAL9602](INFO): SBD MO Status=2, MOMSN=31863, MT Status=2, MTMSN=0
2022-02-25T20:36:28.822Z,1645821388.822 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-25T20:36:38.522Z,1645821398.522 [NAL9602](INFO): SBD MO Status=0, MOMSN=31863, MT Status=0, MTMSN=0
2022-02-25T20:36:38.523Z,1645821398.523 [NAL9602](INFO): No messages in MT queue
2022-02-25T20:37:09.240Z,1645821429.240 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T20:41:11.814Z,1645821671.814 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T20:41:11.814Z,1645821671.814 [Default:CheckIn:C.Wait] Stopped
2022-02-25T20:41:11.814Z,1645821671.814 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T20:41:11.815Z,1645821671.815 [Default:CheckIn:D] Running Loop=1
2022-02-25T20:41:12.219Z,1645821672.219 [Default:CheckIn:D] Stopped
2022-02-25T20:41:12.219Z,1645821672.219 [Default:CheckIn:E] Running Loop=1
2022-02-25T20:41:12.629Z,1645821672.629 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.462630 min 
2022-02-25T20:41:12.629Z,1645821672.629 [Default:CheckIn:E] Stopped
2022-02-25T20:41:12.629Z,1645821672.629 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T20:41:12.629Z,1645821672.629 [Default:CheckIn] Stopped
2022-02-25T20:41:12.630Z,1645821672.630 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T20:41:12.630Z,1645821672.630 [Default:CheckIn](INFO): Running loop #10
2022-02-25T20:41:12.630Z,1645821672.630 [Default:CheckIn] Running Loop=10
2022-02-25T20:41:12.630Z,1645821672.630 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T20:41:12.630Z,1645821672.630 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T20:41:14.626Z,1645821674.626 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204113.00,A,3648.30788,N,12147.91564,W,0.369,266.53,250222,,,A*77
2022-02-25T20:41:14.629Z,1645821674.629 [NAL9602](INFO): GPS fix at 20220225T204113: (36.805131, -121.798594)
2022-02-25T20:41:14.640Z,1645821674.640 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T20:41:14.640Z,1645821674.640 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T20:41:22.455Z,1645821682.455 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220225T201135/Courier0015.lzma
2022-02-25T20:41:23.457Z,1645821683.457 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0015.lzma.bak
2022-02-25T20:41:23.457Z,1645821683.457 [DataOverHttps](INFO): SBD MOMSN=16514762
2022-02-25T20:41:39.379Z,1645821699.379 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20220225T201135/Express0016.lzma
2022-02-25T20:41:40.377Z,1645821700.377 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0016.lzma.bak
2022-02-25T20:41:40.377Z,1645821700.377 [DataOverHttps](INFO): SBD MOMSN=16514765
2022-02-25T20:41:41.720Z,1645821701.720 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T20:41:41.720Z,1645821701.720 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T20:41:41.720Z,1645821701.720 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T20:41:44.522Z,1645821704.522 [NAL9602](INFO): SBD MO Status=0, MOMSN=31864, MT Status=0, MTMSN=0
2022-02-25T20:41:44.522Z,1645821704.522 [NAL9602](INFO): No messages in MT queue
2022-02-25T20:42:15.224Z,1645821735.224 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T20:46:42.137Z,1645822002.137 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T20:46:42.137Z,1645822002.137 [Default:CheckIn:C.Wait] Stopped
2022-02-25T20:46:42.137Z,1645822002.137 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T20:46:42.137Z,1645822002.137 [Default:CheckIn:D] Running Loop=1
2022-02-25T20:46:42.533Z,1645822002.533 [Default:CheckIn:D] Stopped
2022-02-25T20:46:42.534Z,1645822002.534 [Default:CheckIn:E] Running Loop=1
2022-02-25T20:46:42.948Z,1645822002.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.967867 min 
2022-02-25T20:46:42.948Z,1645822002.948 [Default:CheckIn:E] Stopped
2022-02-25T20:46:42.948Z,1645822002.948 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T20:46:42.948Z,1645822002.948 [Default:CheckIn] Stopped
2022-02-25T20:46:42.948Z,1645822002.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T20:46:42.949Z,1645822002.949 [Default:CheckIn](INFO): Running loop #11
2022-02-25T20:46:42.949Z,1645822002.949 [Default:CheckIn] Running Loop=11
2022-02-25T20:46:42.949Z,1645822002.949 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T20:46:42.949Z,1645822002.949 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T20:46:44.950Z,1645822004.950 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204644.00,A,3648.29871,N,12147.90730,W,0.272,229.07,250222,,,D*75
2022-02-25T20:46:44.953Z,1645822004.953 [NAL9602](INFO): GPS fix at 20220225T204644: (36.804978, -121.798455)
2022-02-25T20:46:44.963Z,1645822004.963 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T20:46:44.963Z,1645822004.963 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T20:46:56.999Z,1645822016.999 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220225T201135/Courier0018.lzma
2022-02-25T20:46:58.009Z,1645822018.009 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0018.lzma.bak
2022-02-25T20:46:58.009Z,1645822018.009 [DataOverHttps](INFO): SBD MOMSN=16514769
2022-02-25T20:47:00.354Z,1645822020.354 [NAL9602](INFO): SBD MO Status=0, MOMSN=31865, MT Status=0, MTMSN=0
2022-02-25T20:47:00.354Z,1645822020.354 [NAL9602](INFO): No messages in MT queue
2022-02-25T20:47:21.091Z,1645822041.091 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220225T201135/Express0019.lzma
2022-02-25T20:47:22.093Z,1645822042.093 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0019.lzma.bak
2022-02-25T20:47:22.093Z,1645822042.093 [DataOverHttps](INFO): SBD MOMSN=16514772
2022-02-25T20:47:23.389Z,1645822043.389 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T20:47:23.389Z,1645822043.389 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T20:47:23.389Z,1645822043.389 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T20:47:31.063Z,1645822051.063 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T20:52:24.051Z,1645822344.051 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T20:52:24.052Z,1645822344.052 [Default:CheckIn:C.Wait] Stopped
2022-02-25T20:52:24.052Z,1645822344.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T20:52:24.052Z,1645822344.052 [Default:CheckIn:D] Running Loop=1
2022-02-25T20:52:24.457Z,1645822344.457 [Default:CheckIn:D] Stopped
2022-02-25T20:52:24.457Z,1645822344.457 [Default:CheckIn:E] Running Loop=1
2022-02-25T20:52:24.841Z,1645822344.841 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.666593 min 
2022-02-25T20:52:24.841Z,1645822344.841 [Default:CheckIn:E] Stopped
2022-02-25T20:52:24.841Z,1645822344.841 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T20:52:24.841Z,1645822344.841 [Default:CheckIn] Stopped
2022-02-25T20:52:24.842Z,1645822344.842 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T20:52:24.842Z,1645822344.842 [Default:CheckIn](INFO): Running loop #12
2022-02-25T20:52:24.842Z,1645822344.842 [Default:CheckIn] Running Loop=12
2022-02-25T20:52:24.842Z,1645822344.842 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T20:52:24.842Z,1645822344.842 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T20:52:26.858Z,1645822346.858 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205226.00,A,3648.28654,N,12147.89118,W,0.505,277.62,250222,,,D*77
2022-02-25T20:52:26.861Z,1645822346.861 [NAL9602](INFO): GPS fix at 20220225T205226: (36.804776, -121.798186)
2022-02-25T20:52:26.871Z,1645822346.871 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T20:52:26.872Z,1645822346.872 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T20:52:34.263Z,1645822354.263 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220225T201135/Courier0021.lzma
2022-02-25T20:52:35.265Z,1645822355.265 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0021.lzma.bak
2022-02-25T20:52:35.265Z,1645822355.265 [DataOverHttps](INFO): SBD MOMSN=16514776
2022-02-25T20:53:00.787Z,1645822380.787 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20220225T201135/Express0022.lzma
2022-02-25T20:53:01.790Z,1645822381.790 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0022.lzma.bak
2022-02-25T20:53:01.790Z,1645822381.790 [DataOverHttps](INFO): SBD MOMSN=16514780
2022-02-25T20:53:03.245Z,1645822383.245 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T20:53:03.245Z,1645822383.245 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T20:53:03.245Z,1645822383.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T20:53:05.258Z,1645822385.258 [NAL9602](INFO): SBD MO Status=0, MOMSN=31866, MT Status=0, MTMSN=0
2022-02-25T20:53:05.259Z,1645822385.259 [NAL9602](INFO): No messages in MT queue
2022-02-25T20:53:35.953Z,1645822415.953 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T20:58:03.871Z,1645822683.871 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T20:58:03.871Z,1645822683.871 [Default:CheckIn:C.Wait] Stopped
2022-02-25T20:58:03.871Z,1645822683.871 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T20:58:03.871Z,1645822683.871 [Default:CheckIn:D] Running Loop=1
2022-02-25T20:58:04.286Z,1645822684.286 [Default:CheckIn:D] Stopped
2022-02-25T20:58:04.286Z,1645822684.286 [Default:CheckIn:E] Running Loop=1
2022-02-25T20:58:04.699Z,1645822684.699 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.330412 min 
2022-02-25T20:58:04.699Z,1645822684.699 [Default:CheckIn:E] Stopped
2022-02-25T20:58:04.700Z,1645822684.700 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T20:58:04.700Z,1645822684.700 [Default:CheckIn] Stopped
2022-02-25T20:58:04.700Z,1645822684.700 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T20:58:04.700Z,1645822684.700 [Default:CheckIn](INFO): Running loop #13
2022-02-25T20:58:04.700Z,1645822684.700 [Default:CheckIn] Running Loop=13
2022-02-25T20:58:04.700Z,1645822684.700 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T20:58:04.700Z,1645822684.700 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T20:58:06.694Z,1645822686.694 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205805.00,A,3648.42547,N,12147.34782,W,7.270,51.76,250222,,,D*43
2022-02-25T20:58:06.697Z,1645822686.697 [NAL9602](INFO): GPS fix at 20220225T205805: (36.807091, -121.789130)
2022-02-25T20:58:06.740Z,1645822686.740 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T20:58:06.740Z,1645822686.740 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T20:58:14.299Z,1645822694.299 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220225T201135/Courier0024.lzma
2022-02-25T20:58:15.302Z,1645822695.302 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0024.lzma.bak
2022-02-25T20:58:15.302Z,1645822695.302 [DataOverHttps](INFO): SBD MOMSN=16514784
2022-02-25T20:58:32.614Z,1645822712.614 [NAL9602](INFO): SBD MO Status=2, MOMSN=31867, MT Status=2, MTMSN=0
2022-02-25T20:58:32.614Z,1645822712.614 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-25T20:58:34.244Z,1645822714.244 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20220225T201135/Express0025.lzma
2022-02-25T20:58:35.245Z,1645822715.245 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0025.lzma.bak
2022-02-25T20:58:35.245Z,1645822715.245 [DataOverHttps](INFO): SBD MOMSN=16514787
2022-02-25T20:58:36.673Z,1645822716.673 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T20:58:36.673Z,1645822716.673 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T20:58:36.673Z,1645822716.673 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T20:58:41.910Z,1645822721.910 [NAL9602](INFO): SBD MO Status=0, MOMSN=31867, MT Status=0, MTMSN=0
2022-02-25T20:58:41.910Z,1645822721.910 [NAL9602](INFO): No messages in MT queue
2022-02-25T20:59:12.612Z,1645822752.612 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T21:03:37.295Z,1645823017.295 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T21:03:37.295Z,1645823017.295 [Default:CheckIn:C.Wait] Stopped
2022-02-25T21:03:37.295Z,1645823017.295 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T21:03:37.295Z,1645823017.295 [Default:CheckIn:D] Running Loop=1
2022-02-25T21:03:37.690Z,1645823017.690 [Default:CheckIn:D] Stopped
2022-02-25T21:03:37.690Z,1645823017.690 [Default:CheckIn:E] Running Loop=1
2022-02-25T21:03:38.087Z,1645823018.087 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.887150 min 
2022-02-25T21:03:38.087Z,1645823018.087 [Default:CheckIn:E] Stopped
2022-02-25T21:03:38.088Z,1645823018.088 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T21:03:38.088Z,1645823018.088 [Default:CheckIn] Stopped
2022-02-25T21:03:38.088Z,1645823018.088 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T21:03:38.088Z,1645823018.088 [Default:CheckIn](INFO): Running loop #14
2022-02-25T21:03:38.088Z,1645823018.088 [Default:CheckIn] Running Loop=14
2022-02-25T21:03:38.088Z,1645823018.088 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T21:03:38.088Z,1645823018.088 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T21:03:40.106Z,1645823020.106 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210339.00,A,3648.19498,N,12147.16200,W,3.946,201.51,250222,,,D*79
2022-02-25T21:03:40.109Z,1645823020.109 [NAL9602](INFO): GPS fix at 20220225T210339: (36.803250, -121.786033)
2022-02-25T21:03:40.120Z,1645823020.120 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T21:03:40.120Z,1645823020.120 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T21:03:47.895Z,1645823027.895 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220225T201135/Courier0027.lzma
2022-02-25T21:03:48.897Z,1645823028.897 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0027.lzma.bak
2022-02-25T21:03:48.897Z,1645823028.897 [DataOverHttps](INFO): SBD MOMSN=16514791
2022-02-25T21:03:53.026Z,1645823033.026 [NAL9602](INFO): SBD MO Status=0, MOMSN=31868, MT Status=0, MTMSN=0
2022-02-25T21:03:53.026Z,1645823033.026 [NAL9602](INFO): No messages in MT queue
2022-02-25T21:04:04.815Z,1645823044.815 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220225T201135/Express0028.lzma
2022-02-25T21:04:05.817Z,1645823045.817 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0028.lzma.bak
2022-02-25T21:04:05.817Z,1645823045.817 [DataOverHttps](INFO): SBD MOMSN=16514794
2022-02-25T21:04:07.180Z,1645823047.180 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T21:04:07.181Z,1645823047.181 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T21:04:07.181Z,1645823047.181 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T21:04:23.732Z,1645823063.732 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T21:09:07.781Z,1645823347.781 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T21:09:07.781Z,1645823347.781 [Default:CheckIn:C.Wait] Stopped
2022-02-25T21:09:07.781Z,1645823347.781 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T21:09:07.781Z,1645823347.781 [Default:CheckIn:D] Running Loop=1
2022-02-25T21:09:08.196Z,1645823348.196 [Default:CheckIn:D] Stopped
2022-02-25T21:09:08.196Z,1645823348.196 [Default:CheckIn:E] Running Loop=1
2022-02-25T21:09:08.587Z,1645823348.587 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.395581 min 
2022-02-25T21:09:08.587Z,1645823348.587 [Default:CheckIn:E] Stopped
2022-02-25T21:09:08.587Z,1645823348.587 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T21:09:08.587Z,1645823348.587 [Default:CheckIn] Stopped
2022-02-25T21:09:08.587Z,1645823348.587 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T21:09:08.588Z,1645823348.588 [Default:CheckIn](INFO): Running loop #15
2022-02-25T21:09:08.588Z,1645823348.588 [Default:CheckIn] Running Loop=15
2022-02-25T21:09:08.588Z,1645823348.588 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T21:09:08.588Z,1645823348.588 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T21:09:10.599Z,1645823350.599 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210909.00,A,3648.16325,N,12147.21645,W,0.039,258.38,250222,,,D*7E
2022-02-25T21:09:10.601Z,1645823350.601 [NAL9602](INFO): GPS fix at 20220225T210909: (36.802721, -121.786941)
2022-02-25T21:09:10.613Z,1645823350.613 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T21:09:10.613Z,1645823350.613 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T21:09:17.615Z,1645823357.615 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220225T201135/Courier0030.lzma
2022-02-25T21:09:18.617Z,1645823358.617 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0030.lzma.bak
2022-02-25T21:09:18.617Z,1645823358.617 [DataOverHttps](INFO): SBD MOMSN=16514798
2022-02-25T21:09:34.703Z,1645823374.703 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220225T201135/Express0031.lzma
2022-02-25T21:09:35.713Z,1645823375.713 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0031.lzma.bak
2022-02-25T21:09:35.713Z,1645823375.713 [DataOverHttps](INFO): SBD MOMSN=16514801
2022-02-25T21:09:36.877Z,1645823376.877 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T21:09:36.877Z,1645823376.877 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T21:09:36.878Z,1645823376.878 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T21:10:36.250Z,1645823436.250 [NAL9602](INFO): SBD MO Status=0, MOMSN=31869, MT Status=0, MTMSN=0
2022-02-25T21:10:36.250Z,1645823436.250 [NAL9602](INFO): No messages in MT queue
2022-02-25T21:11:06.964Z,1645823466.964 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T21:14:37.513Z,1645823677.513 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T21:14:37.513Z,1645823677.513 [Default:CheckIn:C.Wait] Stopped
2022-02-25T21:14:37.513Z,1645823677.513 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T21:14:37.513Z,1645823677.513 [Default:CheckIn:D] Running Loop=1
2022-02-25T21:14:37.923Z,1645823677.923 [Default:CheckIn:D] Stopped
2022-02-25T21:14:37.923Z,1645823677.923 [Default:CheckIn:E] Running Loop=1
2022-02-25T21:14:38.314Z,1645823678.314 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.891024 min 
2022-02-25T21:14:38.315Z,1645823678.315 [Default:CheckIn:E] Stopped
2022-02-25T21:14:38.315Z,1645823678.315 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T21:14:38.315Z,1645823678.315 [Default:CheckIn] Stopped
2022-02-25T21:14:38.315Z,1645823678.315 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T21:14:38.315Z,1645823678.315 [Default:CheckIn](INFO): Running loop #16
2022-02-25T21:14:38.315Z,1645823678.315 [Default:CheckIn] Running Loop=16
2022-02-25T21:14:38.315Z,1645823678.315 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T21:14:38.316Z,1645823678.316 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T21:14:40.331Z,1645823680.331 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211439.00,A,3648.15896,N,12147.21946,W,0.039,258.38,250222,,,D*7D
2022-02-25T21:14:40.333Z,1645823680.333 [NAL9602](INFO): GPS fix at 20220225T211439: (36.802649, -121.786991)
2022-02-25T21:14:40.344Z,1645823680.344 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T21:14:40.344Z,1645823680.344 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T21:14:47.299Z,1645823687.299 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220225T201135/Courier0033.lzma
2022-02-25T21:14:48.301Z,1645823688.301 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0033.lzma.bak
2022-02-25T21:14:48.301Z,1645823688.301 [DataOverHttps](INFO): SBD MOMSN=16514805
2022-02-25T21:14:58.166Z,1645823698.166 [NAL9602](INFO): SBD MO Status=0, MOMSN=31870, MT Status=0, MTMSN=0
2022-02-25T21:14:58.166Z,1645823698.166 [NAL9602](INFO): No messages in MT queue
2022-02-25T21:15:07.055Z,1645823707.055 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220225T201135/Express0034.lzma
2022-02-25T21:15:08.057Z,1645823708.057 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0034.lzma.bak
2022-02-25T21:15:08.057Z,1645823708.057 [DataOverHttps](INFO): SBD MOMSN=16514808
2022-02-25T21:15:09.483Z,1645823709.483 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T21:15:09.483Z,1645823709.483 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T21:15:09.484Z,1645823709.484 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T21:15:28.888Z,1645823728.888 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T21:20:10.156Z,1645824010.156 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T21:20:10.156Z,1645824010.156 [Default:CheckIn:C.Wait] Stopped
2022-02-25T21:20:10.156Z,1645824010.156 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T21:20:10.157Z,1645824010.157 [Default:CheckIn:D] Running Loop=1
2022-02-25T21:20:10.559Z,1645824010.559 [Default:CheckIn:D] Stopped
2022-02-25T21:20:10.559Z,1645824010.559 [Default:CheckIn:E] Running Loop=1
2022-02-25T21:20:10.961Z,1645824010.961 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.434961 min 
2022-02-25T21:20:10.961Z,1645824010.961 [Default:CheckIn:E] Stopped
2022-02-25T21:20:10.961Z,1645824010.961 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T21:20:10.961Z,1645824010.961 [Default:CheckIn] Stopped
2022-02-25T21:20:10.961Z,1645824010.961 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T21:20:10.961Z,1645824010.961 [Default:CheckIn](INFO): Running loop #17
2022-02-25T21:20:10.961Z,1645824010.961 [Default:CheckIn] Running Loop=17
2022-02-25T21:20:10.962Z,1645824010.962 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T21:20:10.962Z,1645824010.962 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T21:20:12.971Z,1645824012.971 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212012.00,A,3648.17443,N,12147.28022,W,0.097,273.27,250222,,,D*74
2022-02-25T21:20:12.973Z,1645824012.973 [NAL9602](INFO): GPS fix at 20220225T212012: (36.802907, -121.788004)
2022-02-25T21:20:13.013Z,1645824013.013 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T21:20:13.013Z,1645824013.013 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T21:20:22.407Z,1645824022.407 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220225T201135/Courier0036.lzma
2022-02-25T21:20:23.409Z,1645824023.409 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0036.lzma.bak
2022-02-25T21:20:23.409Z,1645824023.409 [DataOverHttps](INFO): SBD MOMSN=16514812
2022-02-25T21:20:41.007Z,1645824041.007 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20220225T201135/Express0037.lzma
2022-02-25T21:20:42.009Z,1645824042.009 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0037.lzma.bak
2022-02-25T21:20:42.010Z,1645824042.010 [DataOverHttps](INFO): SBD MOMSN=16514815
2022-02-25T21:20:43.324Z,1645824043.324 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T21:20:43.324Z,1645824043.324 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T21:20:43.324Z,1645824043.324 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T21:22:25.986Z,1645824145.986 [NAL9602](INFO): SBD MO Status=0, MOMSN=31871, MT Status=0, MTMSN=0
2022-02-25T21:22:25.986Z,1645824145.986 [NAL9602](INFO): No messages in MT queue
2022-02-25T21:22:56.732Z,1645824176.732 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T21:25:43.906Z,1645824343.906 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T21:25:43.906Z,1645824343.906 [Default:CheckIn:C.Wait] Stopped
2022-02-25T21:25:43.906Z,1645824343.906 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T21:25:43.907Z,1645824343.907 [Default:CheckIn:D] Running Loop=1
2022-02-25T21:25:44.308Z,1645824344.308 [Default:CheckIn:D] Stopped
2022-02-25T21:25:44.308Z,1645824344.308 [Default:CheckIn:E] Running Loop=1
2022-02-25T21:25:44.725Z,1645824344.725 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.997437 min 
2022-02-25T21:25:44.725Z,1645824344.725 [Default:CheckIn:E] Stopped
2022-02-25T21:25:44.725Z,1645824344.725 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T21:25:44.725Z,1645824344.725 [Default:CheckIn] Stopped
2022-02-25T21:25:44.725Z,1645824344.725 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T21:25:44.725Z,1645824344.725 [Default:CheckIn](INFO): Running loop #18
2022-02-25T21:25:44.726Z,1645824344.726 [Default:CheckIn] Running Loop=18
2022-02-25T21:25:44.726Z,1645824344.726 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T21:25:44.726Z,1645824344.726 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T21:25:46.731Z,1645824346.731 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212546.00,A,3648.17121,N,12147.28067,W,0.019,175.72,250222,,,D*73
2022-02-25T21:25:46.733Z,1645824346.733 [NAL9602](INFO): GPS fix at 20220225T212546: (36.802853, -121.788011)
2022-02-25T21:25:46.775Z,1645824346.775 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T21:25:46.779Z,1645824346.779 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T21:25:54.555Z,1645824354.555 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220225T201135/Courier0039.lzma
2022-02-25T21:25:55.557Z,1645824355.557 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0039.lzma.bak
2022-02-25T21:25:55.557Z,1645824355.557 [DataOverHttps](INFO): SBD MOMSN=16514819
2022-02-25T21:26:02.141Z,1645824362.141 [NAL9602](INFO): SBD MO Status=0, MOMSN=31872, MT Status=0, MTMSN=0
2022-02-25T21:26:02.141Z,1645824362.141 [NAL9602](INFO): No messages in MT queue
2022-02-25T21:26:11.467Z,1645824371.467 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220225T201135/Express0040.lzma
2022-02-25T21:26:12.469Z,1645824372.469 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0040.lzma.bak
2022-02-25T21:26:12.469Z,1645824372.469 [DataOverHttps](INFO): SBD MOMSN=16514822
2022-02-25T21:26:13.814Z,1645824373.814 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T21:26:13.814Z,1645824373.814 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T21:26:13.814Z,1645824373.814 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T21:26:32.806Z,1645824392.806 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T21:30:05.962Z,1645824605.962 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-02-25T21:30:17.218Z,1645824617.218 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-02-25T21:30:17.221Z,1645824617.221 [BPC1](INFO): Received data from all battery sticks.
2022-02-25T21:31:14.323Z,1645824674.323 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T21:31:14.323Z,1645824674.323 [Default:CheckIn:C.Wait] Stopped
2022-02-25T21:31:14.323Z,1645824674.323 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T21:31:14.343Z,1645824674.343 [Default:CheckIn:D] Running Loop=1
2022-02-25T21:31:14.720Z,1645824674.720 [Default:CheckIn:D] Stopped
2022-02-25T21:31:14.720Z,1645824674.720 [Default:CheckIn:E] Running Loop=1
2022-02-25T21:31:15.130Z,1645824675.130 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.504313 min 
2022-02-25T21:31:15.130Z,1645824675.130 [Default:CheckIn:E] Stopped
2022-02-25T21:31:15.131Z,1645824675.131 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T21:31:15.131Z,1645824675.131 [Default:CheckIn] Stopped
2022-02-25T21:31:15.131Z,1645824675.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T21:31:15.131Z,1645824675.131 [Default:CheckIn](INFO): Running loop #19
2022-02-25T21:31:15.131Z,1645824675.131 [Default:CheckIn] Running Loop=19
2022-02-25T21:31:15.131Z,1645824675.131 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T21:31:15.131Z,1645824675.131 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T21:31:17.130Z,1645824677.130 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213116.00,A,3648.16777,N,12147.28265,W,0.350,37.10,250222,,,D*4A
2022-02-25T21:31:17.133Z,1645824677.133 [NAL9602](INFO): GPS fix at 20220225T213116: (36.802796, -121.788044)
2022-02-25T21:31:17.173Z,1645824677.173 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T21:31:17.173Z,1645824677.173 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T21:31:24.795Z,1645824684.795 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20220225T201135/Courier0042.lzma
2022-02-25T21:31:25.797Z,1645824685.797 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0042.lzma.bak
2022-02-25T21:31:25.797Z,1645824685.797 [DataOverHttps](INFO): SBD MOMSN=16514826
2022-02-25T21:31:41.867Z,1645824701.867 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220225T201135/Express0043.lzma
2022-02-25T21:31:42.869Z,1645824702.869 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0043.lzma.bak
2022-02-25T21:31:42.869Z,1645824702.869 [DataOverHttps](INFO): SBD MOMSN=16514829
2022-02-25T21:31:44.205Z,1645824704.205 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T21:31:44.205Z,1645824704.205 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T21:31:44.205Z,1645824704.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T21:32:00.354Z,1645824720.354 [NAL9602](INFO): SBD MO Status=2, MOMSN=31873, MT Status=2, MTMSN=0
2022-02-25T21:32:00.354Z,1645824720.354 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-25T21:33:22.486Z,1645824802.486 [NAL9602](INFO): SBD MO Status=2, MOMSN=31873, MT Status=2, MTMSN=0
2022-02-25T21:33:22.487Z,1645824802.487 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-25T21:34:53.890Z,1645824893.890 [NAL9602](INFO): SBD MO Status=2, MOMSN=31873, MT Status=2, MTMSN=0
2022-02-25T21:34:53.890Z,1645824893.890 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-25T21:36:19.150Z,1645824979.150 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-02-25T21:36:44.664Z,1645825004.664 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T21:36:44.664Z,1645825004.664 [Default:CheckIn:C.Wait] Stopped
2022-02-25T21:36:44.664Z,1645825004.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T21:36:44.665Z,1645825004.665 [Default:CheckIn:D] Running Loop=1
2022-02-25T21:36:45.074Z,1645825005.074 [Default:CheckIn:D] Stopped
2022-02-25T21:36:45.074Z,1645825005.074 [Default:CheckIn:E] Running Loop=1
2022-02-25T21:36:45.484Z,1645825005.484 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.010213 min 
2022-02-25T21:36:45.484Z,1645825005.484 [Default:CheckIn:E] Stopped
2022-02-25T21:36:45.484Z,1645825005.484 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T21:36:45.484Z,1645825005.484 [Default:CheckIn] Stopped
2022-02-25T21:36:45.484Z,1645825005.484 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T21:36:45.485Z,1645825005.485 [Default:CheckIn](INFO): Running loop #20
2022-02-25T21:36:45.485Z,1645825005.485 [Default:CheckIn] Running Loop=20
2022-02-25T21:36:45.485Z,1645825005.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T21:36:45.485Z,1645825005.485 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T21:36:47.482Z,1645825007.482 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213646.00,A,3648.16994,N,12147.28492,W,0.467,25.26,250222,,,A*45
2022-02-25T21:36:47.485Z,1645825007.485 [NAL9602](INFO): GPS fix at 20220225T213646: (36.802832, -121.788082)
2022-02-25T21:36:47.537Z,1645825007.537 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T21:36:47.537Z,1645825007.537 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T21:36:57.827Z,1645825017.827 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220225T201135/Courier0045.lzma
2022-02-25T21:36:58.829Z,1645825018.829 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0045.lzma.bak
2022-02-25T21:36:58.829Z,1645825018.829 [DataOverHttps](INFO): SBD MOMSN=16514832
2022-02-25T21:37:14.628Z,1645825034.628 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20220225T201135/Express0046.lzma
2022-02-25T21:37:15.629Z,1645825035.629 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0046.lzma.bak
2022-02-25T21:37:15.629Z,1645825035.629 [DataOverHttps](INFO): SBD MOMSN=16514835
2022-02-25T21:37:17.067Z,1645825037.067 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T21:37:17.067Z,1645825037.067 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T21:37:17.067Z,1645825037.067 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T21:37:19.480Z,1645825039.480 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T21:40:30.756Z,1645825230.756 [CommandExec](IMPORTANT): got command strobe off
2022-02-25T21:40:30.756Z,1645825230.756 [CommandExec](IMPORTANT): Deactivating strobe
2022-02-25T21:42:17.498Z,1645825337.498 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T21:42:17.498Z,1645825337.498 [Default:CheckIn:C.Wait] Stopped
2022-02-25T21:42:17.498Z,1645825337.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T21:42:17.499Z,1645825337.499 [Default:CheckIn:D] Running Loop=1
2022-02-25T21:42:17.903Z,1645825337.903 [Default:CheckIn:D] Stopped
2022-02-25T21:42:17.903Z,1645825337.903 [Default:CheckIn:E] Running Loop=1
2022-02-25T21:42:18.369Z,1645825338.369 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.557357 min 
2022-02-25T21:42:18.369Z,1645825338.369 [Default:CheckIn:E] Stopped
2022-02-25T21:42:18.370Z,1645825338.370 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T21:42:18.370Z,1645825338.370 [Default:CheckIn] Stopped
2022-02-25T21:42:18.370Z,1645825338.370 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T21:42:18.370Z,1645825338.370 [Default:CheckIn](INFO): Running loop #21
2022-02-25T21:42:18.370Z,1645825338.370 [Default:CheckIn] Running Loop=21
2022-02-25T21:42:18.370Z,1645825338.370 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T21:42:18.370Z,1645825338.370 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T21:42:20.319Z,1645825340.319 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214219.00,A,3648.17594,N,12147.28436,W,0.389,330.02,250222,,,A*79
2022-02-25T21:42:20.321Z,1645825340.321 [NAL9602](INFO): GPS fix at 20220225T214219: (36.802932, -121.788073)
2022-02-25T21:42:20.373Z,1645825340.373 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T21:42:20.373Z,1645825340.373 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T21:42:30.203Z,1645825350.203 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220225T201135/Courier0048.lzma
2022-02-25T21:42:31.205Z,1645825351.205 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0048.lzma.bak
2022-02-25T21:42:31.206Z,1645825351.206 [DataOverHttps](INFO): SBD MOMSN=16514839
2022-02-25T21:42:47.099Z,1645825367.099 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20220225T201135/Express0049.lzma
2022-02-25T21:42:48.101Z,1645825368.101 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0049.lzma.bak
2022-02-25T21:42:48.101Z,1645825368.101 [DataOverHttps](INFO): SBD MOMSN=16514842
2022-02-25T21:42:49.480Z,1645825369.480 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T21:42:49.480Z,1645825369.480 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T21:42:49.480Z,1645825369.480 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T21:44:12.336Z,1645825452.336 [NAL9602](INFO): SBD MO Status=2, MOMSN=31873, MT Status=2, MTMSN=0
2022-02-25T21:44:12.336Z,1645825452.336 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-25T21:47:22.693Z,1645825642.693 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-02-25T21:47:50.176Z,1645825670.176 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-25T21:47:50.176Z,1645825670.176 [Default:CheckIn:C.Wait] Stopped
2022-02-25T21:47:50.176Z,1645825670.176 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T21:47:50.176Z,1645825670.176 [Default:CheckIn:D] Running Loop=1
2022-02-25T21:47:50.593Z,1645825670.593 [Default:CheckIn:D] Stopped
2022-02-25T21:47:50.593Z,1645825670.593 [Default:CheckIn:E] Running Loop=1
2022-02-25T21:47:50.983Z,1645825670.983 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.102189 min 
2022-02-25T21:47:50.983Z,1645825670.983 [Default:CheckIn:E] Stopped
2022-02-25T21:47:51.008Z,1645825671.008 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-25T21:47:51.008Z,1645825671.008 [Default:CheckIn] Stopped
2022-02-25T21:47:51.008Z,1645825671.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T21:47:51.008Z,1645825671.008 [Default:CheckIn](INFO): Running loop #22
2022-02-25T21:47:51.008Z,1645825671.008 [Default:CheckIn] Running Loop=22
2022-02-25T21:47:51.008Z,1645825671.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-25T21:47:51.008Z,1645825671.008 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-25T21:47:52.994Z,1645825672.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214752.00,A,3648.17086,N,12147.28230,W,0.797,153.91,250222,,,A*73
2022-02-25T21:47:52.997Z,1645825672.997 [NAL9602](INFO): GPS fix at 20220225T214752: (36.802848, -121.788038)
2022-02-25T21:47:53.012Z,1645825673.012 [Default:CheckIn:Read_GPS] Stopped
2022-02-25T21:47:53.012Z,1645825673.012 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-25T21:48:00.767Z,1645825680.767 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220225T201135/Courier0051.lzma
2022-02-25T21:48:01.769Z,1645825681.769 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Courier0051.lzma.bak
2022-02-25T21:48:01.769Z,1645825681.769 [DataOverHttps](INFO): SBD MOMSN=16514847
2022-02-25T21:48:17.703Z,1645825697.703 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20220225T201135/Express0052.lzma
2022-02-25T21:48:18.705Z,1645825698.705 [DataOverHttps](INFO): Moved sent file to Logs/20220225T201135/Express0052.lzma.bak
2022-02-25T21:48:18.705Z,1645825698.705 [DataOverHttps](INFO): SBD MOMSN=16514850
2022-02-25T21:48:20.082Z,1645825700.082 [Default:CheckIn:Read_Iridium] Stopped
2022-02-25T21:48:20.082Z,1645825700.082 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-25T21:48:20.082Z,1645825700.082 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-25T21:48:25.356Z,1645825705.356 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-25T21:53:11.972Z,1645825991.972 [CommandExec](IMPORTANT): got command quit
2022-02-25T21:53:12.975Z,1645825992.975 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-25T21:53:12.976Z,1645825992.976 [CommandExec](INFO): Uninitializing the command executive.
2022-02-25T21:53:12.976Z,1645825992.976 [CommandExec](INFO): Uninitializing the command scheduler.
2022-02-25T21:53:12.976Z,1645825992.976 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:13.175Z,1645825993.175 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-02-25T21:53:13.175Z,1645825993.175 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-02-25T21:53:13.175Z,1645825993.175 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:13.176Z,1645825993.176 [NavChartDb](INFO): Join timeout helper Thread ID is 5436
2022-02-25T21:53:13.395Z,1645825993.395 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-25T21:53:13.396Z,1645825993.396 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:13.415Z,1645825993.415 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-02-25T21:53:13.415Z,1645825993.415 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:13.416Z,1645825993.416 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5437
2022-02-25T21:53:13.823Z,1645825993.823 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-25T21:53:13.824Z,1645825993.824 [WetLabsBB2FL](INFO): Powering down
2022-02-25T21:53:13.824Z,1645825993.824 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:13.843Z,1645825993.843 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-02-25T21:53:13.843Z,1645825993.843 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:13.844Z,1645825993.844 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5438
2022-02-25T21:53:14.100Z,1645825994.100 [CTD_Seabird](INFO): Powering down
2022-02-25T21:53:14.111Z,1645825994.111 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-25T21:53:14.112Z,1645825994.112 [CTD_Seabird](INFO): Powering down
2022-02-25T21:53:14.123Z,1645825994.123 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:14.124Z,1645825994.124 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-02-25T21:53:14.124Z,1645825994.124 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:14.124Z,1645825994.124 [Radio_Surface](INFO): Join timeout helper Thread ID is 5439
2022-02-25T21:53:14.475Z,1645825994.475 [Radio_Surface](INFO): Powering down
2022-02-25T21:53:14.477Z,1645825994.477 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-25T21:53:14.478Z,1645825994.478 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:14.491Z,1645825994.491 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-02-25T21:53:14.491Z,1645825994.491 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:14.492Z,1645825994.492 [Onboard](INFO): Join timeout helper Thread ID is 5440
2022-02-25T21:53:15.047Z,1645825995.047 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-02-25T21:53:18.764Z,1645825998.764 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-25T21:53:18.764Z,1645825998.764 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:18.784Z,1645825998.784 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-02-25T21:53:18.784Z,1645825998.784 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:18.785Z,1645825998.785 [DataOverHttps](INFO): Join timeout helper Thread ID is 5441
2022-02-25T21:53:19.063Z,1645825999.063 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-25T21:53:19.064Z,1645825999.064 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.068Z,1645825999.068 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-02-25T21:53:19.068Z,1645825999.068 [logger ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.068Z,1645825999.068 [logger](INFO): Join timeout helper Thread ID is 5442
2022-02-25T21:53:19.095Z,1645825999.095 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-25T21:53:19.096Z,1645825999.096 [logger ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.108Z,1645825999.108 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-02-25T21:53:19.108Z,1645825999.108 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.108Z,1645825999.108 [CommandLine](INFO): Join timeout helper Thread ID is 5443
2022-02-25T21:53:19.147Z,1645825999.147 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-25T21:53:19.148Z,1645825999.148 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.156Z,1645825999.156 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-02-25T21:53:19.156Z,1645825999.156 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.156Z,1645825999.156 [CommandExec](INFO): Join timeout helper Thread ID is 5444
2022-02-25T21:53:19.158Z,1645825999.158 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-02-25T21:53:19.158Z,1645825999.158 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.158Z,1645825999.158 [controlThread](INFO): Join timeout helper Thread ID is 5445
2022-02-25T21:53:19.519Z,1645825999.519 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-25T21:53:19.520Z,1645825999.520 [controlThread](DEBUG): Uninitializing ControlThread
2022-02-25T21:53:19.520Z,1645825999.520 [AHRS_M2](INFO): Powering down
2022-02-25T21:53:19.592Z,1645825999.592 [NAL9602](INFO): Powering down
2022-02-25T21:53:19.595Z,1645825999.595 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-02-25T21:53:19.596Z,1645825999.596 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-02-25T21:53:19.596Z,1645825999.596 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-02-25T21:53:19.597Z,1645825999.597 [MissionManager](INFO): Uninitializing Mission Default
2022-02-25T21:53:19.597Z,1645825999.597 [Default] Stopped
2022-02-25T21:53:19.597Z,1645825999.597 [Default](DEBUG): Aggregate::uninitialize Default
2022-02-25T21:53:19.597Z,1645825999.597 [Default:B.GoToSurface] Stopped
2022-02-25T21:53:19.597Z,1645825999.597 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-02-25T21:53:19.597Z,1645825999.597 [Default:CheckIn] Stopped
2022-02-25T21:53:19.598Z,1645825999.598 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-25T21:53:19.598Z,1645825999.598 [Default:CheckIn:C.Wait] Stopped
2022-02-25T21:53:19.598Z,1645825999.598 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-25T21:53:19.601Z,1645825999.601 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-02-25T21:53:19.601Z,1645825999.601 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-02-25T21:53:19.602Z,1645825999.602 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-02-25T21:53:19.602Z,1645825999.602 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-02-25T21:53:19.602Z,1645825999.602 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-02-25T21:53:19.602Z,1645825999.602 [BuoyancyServo](INFO): Powering down
2022-02-25T21:53:19.615Z,1645825999.615 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-02-25T21:53:19.615Z,1645825999.615 [ElevatorServo](INFO): Powering down
2022-02-25T21:53:19.616Z,1645825999.616 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-02-25T21:53:19.617Z,1645825999.617 [MassServo](INFO): Powering down
2022-02-25T21:53:19.617Z,1645825999.617 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-02-25T21:53:19.618Z,1645825999.618 [RudderServo](INFO): Powering down
2022-02-25T21:53:19.618Z,1645825999.618 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2022-02-25T21:53:19.619Z,1645825999.619 [ThrusterServo](INFO): Powering down
2022-02-25T21:53:19.620Z,1645825999.620 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-02-25T21:53:19.620Z,1645825999.620 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-02-25T21:53:19.620Z,1645825999.620 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-02-25T21:53:19.620Z,1645825999.620 [CBIT](DEBUG): Powering off loads.
2022-02-25T21:53:19.631Z,1645825999.631 [CBIT](DEBUG): Disabling WDT.
2022-02-25T21:53:19.643Z,1645825999.643 [CBIT](DEBUG): Opening all GF detection circuits.
2022-02-25T21:53:19.644Z,1645825999.644 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.691Z,1645825999.691 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.694Z,1645825999.694 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.701Z,1645825999.701 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.772Z,1645825999.772 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.776Z,1645825999.776 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.815Z,1645825999.815 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-02-25T21:53:19.898Z,1645825999.898 [logger ThreadHandler](INFO): Thread cancelled.