2023-03-28T17:43:18.769Z,1680025398.769 [CommandExec](IMPORTANT): got command restart logs 2023-03-28T17:45:37.372Z,1680025537.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-28T17:46:08.505Z,1680025568.505 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-28T17:46:39.628Z,1680025599.628 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-28T17:47:10.768Z,1680025630.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-28T17:47:12.539Z,1680025632.539 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-03-28T17:47:12.539Z,1680025632.539 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+13.3,0000.0,14 2023-03-28T17:47:30.318Z,1680025650.318 [DVL_micro](ERROR): only read 3 of 4 data items 2023-03-28T17:47:30.318Z,1680025650.318 [DVL_micro](ERROR): Failed to parse: :BI,-02682,-2095,+00000,00000000.0000.00,+00000000.00,9999.99,000.00 2023-03-28T17:47:35.274Z,1680025655.274 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T17:47:35.274Z,1680025655.274 [Default:CheckIn:C.Wait] Stopped 2023-03-28T17:47:35.274Z,1680025655.274 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T17:47:35.274Z,1680025655.274 [Default:CheckIn:D] Running Loop=1 2023-03-28T17:47:35.653Z,1680025655.653 [Default:CheckIn:D] Stopped 2023-03-28T17:47:35.653Z,1680025655.653 [Default:CheckIn:E] Running Loop=1 2023-03-28T17:47:36.080Z,1680025656.080 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.612594 min 2023-03-28T17:47:36.080Z,1680025656.080 [Default:CheckIn:E] Stopped 2023-03-28T17:47:36.080Z,1680025656.080 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T17:47:36.080Z,1680025656.080 [Default:CheckIn] Stopped 2023-03-28T17:47:36.080Z,1680025656.080 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T17:47:36.081Z,1680025656.081 [Default:CheckIn](INFO): Running loop #7 2023-03-28T17:47:36.081Z,1680025656.081 [Default:CheckIn] Running Loop=7 2023-03-28T17:47:36.081Z,1680025656.081 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T17:47:36.081Z,1680025656.081 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T17:47:38.106Z,1680025658.106 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174737.00,A,3648.17656,N,12147.28443,W,0.058,0.00,280323,,,D*7F 2023-03-28T17:47:38.120Z,1680025658.120 [NAL9602](INFO): GPS fix at 20230328T174737: (36.802943, -121.788074) 2023-03-28T17:47:38.174Z,1680025658.174 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T17:47:38.174Z,1680025658.174 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T17:47:41.459Z,1680025661.459 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230328T170825/Courier0022.lzma 2023-03-28T17:47:42.158Z,1680025662.158 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Courier0022.lzma.bak 2023-03-28T17:47:42.158Z,1680025662.158 [DataOverHttps](INFO): SBD MOMSN=17729398 2023-03-28T17:47:48.626Z,1680025668.626 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T17:47:54.263Z,1680025674.263 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T17:47:58.180Z,1680025678.180 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230328T174318/Courier0000.lzma 2023-03-28T17:47:58.287Z,1680025678.287 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T17:47:59.284Z,1680025679.284 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0000.lzma.bak 2023-03-28T17:47:59.285Z,1680025679.285 [DataOverHttps](INFO): SBD MOMSN=17729400 2023-03-28T17:48:07.191Z,1680025687.191 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T17:48:10.785Z,1680025690.785 [NAL9602](INFO): SBD MO Status=0, MOMSN=47526, MT Status=0, MTMSN=0 2023-03-28T17:48:10.785Z,1680025690.785 [NAL9602](INFO): No messages in MT queue 2023-03-28T17:48:15.191Z,1680025695.191 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230328T170825/Express0023.lzma 2023-03-28T17:48:16.194Z,1680025696.194 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Express0023.lzma.bak 2023-03-28T17:48:16.194Z,1680025696.194 [DataOverHttps](INFO): SBD MOMSN=17729404 2023-03-28T17:48:32.168Z,1680025712.168 [DataOverHttps](INFO): Sending 383 bytes from file Logs/20230328T174318/Express0001.lzma 2023-03-28T17:48:33.170Z,1680025713.170 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0001.lzma.bak 2023-03-28T17:48:33.170Z,1680025713.170 [DataOverHttps](INFO): SBD MOMSN=17729406 2023-03-28T17:48:34.613Z,1680025714.613 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T17:48:34.613Z,1680025714.613 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T17:48:34.613Z,1680025714.613 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T17:48:41.183Z,1680025721.183 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T17:48:41.328Z,1680025721.328 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T17:53:35.359Z,1680026015.359 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T17:53:35.359Z,1680026015.359 [Default:CheckIn:C.Wait] Stopped 2023-03-28T17:53:35.359Z,1680026015.359 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T17:53:35.359Z,1680026015.359 [Default:CheckIn:D] Running Loop=1 2023-03-28T17:53:35.727Z,1680026015.727 [Default:CheckIn:D] Stopped 2023-03-28T17:53:35.727Z,1680026015.727 [Default:CheckIn:E] Running Loop=1 2023-03-28T17:53:36.131Z,1680026016.131 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.613826 min 2023-03-28T17:53:36.131Z,1680026016.131 [Default:CheckIn:E] Stopped 2023-03-28T17:53:36.131Z,1680026016.131 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T17:53:36.131Z,1680026016.131 [Default:CheckIn] Stopped 2023-03-28T17:53:36.132Z,1680026016.132 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T17:53:36.140Z,1680026016.140 [Default:CheckIn](INFO): Running loop #8 2023-03-28T17:53:36.140Z,1680026016.140 [Default:CheckIn] Running Loop=8 2023-03-28T17:53:36.140Z,1680026016.140 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T17:53:36.140Z,1680026016.140 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T17:53:38.148Z,1680026018.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175337.00,A,3648.17581,N,12147.28531,W,0.019,0.00,280323,,,D*72 2023-03-28T17:53:38.150Z,1680026018.150 [NAL9602](INFO): GPS fix at 20230328T175337: (36.802930, -121.788089) 2023-03-28T17:53:38.168Z,1680026018.168 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T17:53:38.168Z,1680026018.168 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T17:53:41.065Z,1680026021.065 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-03-28T17:53:41.065Z,1680026021.065 [DropWeight] Hardware Fault, FailCount= 1 2023-03-28T17:53:41.065Z,1680026021.065 [DropWeight](ERROR): Hardware Fault 2023-03-28T17:53:41.160Z,1680026021.160 [CBIT](INFO): Critical error at 20230328T175341 2023-03-28T17:53:41.163Z,1680026021.163 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-03-28T17:53:41.163Z,1680026021.163 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-03-28T17:53:41.560Z,1680026021.560 [CBIT](INFO): Critical error at 20230328T175341 2023-03-28T17:53:45.663Z,1680026025.663 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0003.lzma 2023-03-28T17:53:46.666Z,1680026026.666 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0003.lzma.bak 2023-03-28T17:53:46.666Z,1680026026.666 [DataOverHttps](INFO): SBD MOMSN=17729411 2023-03-28T17:54:02.560Z,1680026042.560 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230328T174318/Express0004.lzma 2023-03-28T17:54:03.562Z,1680026043.562 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0004.lzma.bak 2023-03-28T17:54:03.562Z,1680026043.562 [DataOverHttps](INFO): SBD MOMSN=17729414 2023-03-28T17:54:04.919Z,1680026044.919 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T17:54:04.919Z,1680026044.919 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T17:54:04.919Z,1680026044.919 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T17:54:44.638Z,1680026084.638 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T17:55:03.131Z,1680026103.131 [NAL9602](INFO): SBD MO Status=2, MOMSN=47527, MT Status=2, MTMSN=0 2023-03-28T17:55:03.131Z,1680026103.131 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T17:55:07.628Z,1680026107.628 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T17:55:20.506Z,1680026120.506 [NAL9602](INFO): SBD MO Status=2, MOMSN=47527, MT Status=2, MTMSN=0 2023-03-28T17:55:20.506Z,1680026120.506 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T17:55:24.991Z,1680026124.991 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T17:55:31.417Z,1680026131.417 [NAL9602](INFO): SBD MO Status=0, MOMSN=47527, MT Status=0, MTMSN=0 2023-03-28T17:55:31.417Z,1680026131.417 [NAL9602](INFO): No messages in MT queue 2023-03-28T17:56:02.116Z,1680026162.116 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T17:56:02.170Z,1680026162.170 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T17:56:22.723Z,1680026182.723 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-03-28T17:56:22.723Z,1680026182.723 [DVL_micro](ERROR): Failed to parse: :TS0000000,35.0,+13.2,0000.0,1489.0,000 2023-03-28T17:56:42.598Z,1680026202.598 [CommandLine](INFO): End of History 2023-03-28T17:56:43.963Z,1680026203.963 [CommandLine](INFO): End of History 2023-03-28T17:56:45.257Z,1680026205.257 [CommandLine](INFO): End of History 2023-03-28T17:56:46.626Z,1680026206.626 [CommandLine](INFO): End of History 2023-03-28T17:56:47.240Z,1680026207.240 [CommandLine](INFO): End of History 2023-03-28T17:56:47.928Z,1680026207.928 [CommandLine](INFO): End of History 2023-03-28T17:56:48.651Z,1680026208.651 [CommandLine](INFO): End of History 2023-03-28T17:56:49.269Z,1680026209.269 [CommandLine](INFO): End of History 2023-03-28T17:56:50.004Z,1680026210.004 [CommandLine](INFO): End of History 2023-03-28T17:56:50.786Z,1680026210.786 [CommandLine](INFO): End of History 2023-03-28T17:59:05.543Z,1680026345.543 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T17:59:05.543Z,1680026345.543 [Default:CheckIn:C.Wait] Stopped 2023-03-28T17:59:05.543Z,1680026345.543 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T17:59:05.543Z,1680026345.543 [Default:CheckIn:D] Running Loop=1 2023-03-28T17:59:05.959Z,1680026345.959 [Default:CheckIn:D] Stopped 2023-03-28T17:59:05.959Z,1680026345.959 [Default:CheckIn:E] Running Loop=1 2023-03-28T17:59:06.349Z,1680026346.349 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.117688 min 2023-03-28T17:59:06.349Z,1680026346.349 [Default:CheckIn:E] Stopped 2023-03-28T17:59:06.350Z,1680026346.350 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T17:59:06.350Z,1680026346.350 [Default:CheckIn] Stopped 2023-03-28T17:59:06.350Z,1680026346.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T17:59:06.350Z,1680026346.350 [Default:CheckIn](INFO): Running loop #9 2023-03-28T17:59:06.350Z,1680026346.350 [Default:CheckIn] Running Loop=9 2023-03-28T17:59:06.350Z,1680026346.350 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T17:59:06.350Z,1680026346.350 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T17:59:08.364Z,1680026348.364 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175907.00,A,3648.17588,N,12147.28464,W,0.019,0.00,280323,,,D*73 2023-03-28T17:59:08.366Z,1680026348.366 [NAL9602](INFO): GPS fix at 20230328T175907: (36.802931, -121.788077) 2023-03-28T17:59:08.376Z,1680026348.376 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T17:59:08.376Z,1680026348.376 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T17:59:15.340Z,1680026355.340 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20230328T174318/Courier0006.lzma 2023-03-28T17:59:16.342Z,1680026356.342 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0006.lzma.bak 2023-03-28T17:59:16.342Z,1680026356.342 [DataOverHttps](INFO): SBD MOMSN=17729793 2023-03-28T17:59:17.707Z,1680026357.707 [Reporter](INFO): NAL9602.sigQuality 4 count 2023-03-28T17:59:32.293Z,1680026372.293 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230328T174318/Express0007.lzma 2023-03-28T17:59:33.294Z,1680026373.294 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0007.lzma.bak 2023-03-28T17:59:33.294Z,1680026373.294 [DataOverHttps](INFO): SBD MOMSN=17729798 2023-03-28T17:59:34.619Z,1680026374.619 [NAL9602](INFO): SBD MO Status=0, MOMSN=47528, MT Status=0, MTMSN=0 2023-03-28T17:59:34.619Z,1680026374.619 [NAL9602](INFO): No messages in MT queue 2023-03-28T17:59:34.677Z,1680026374.677 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T17:59:34.677Z,1680026374.677 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T17:59:34.677Z,1680026374.677 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:00:05.389Z,1680026405.389 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T18:00:05.436Z,1680026405.436 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:04:35.357Z,1680026675.357 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T18:04:35.357Z,1680026675.357 [Default:CheckIn:C.Wait] Stopped 2023-03-28T18:04:35.357Z,1680026675.357 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T18:04:35.357Z,1680026675.358 [Default:CheckIn:D] Running Loop=1 2023-03-28T18:04:35.755Z,1680026675.755 [Default:CheckIn:D] Stopped 2023-03-28T18:04:35.755Z,1680026675.755 [Default:CheckIn:E] Running Loop=1 2023-03-28T18:04:36.161Z,1680026676.161 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.614294 min 2023-03-28T18:04:36.161Z,1680026676.161 [Default:CheckIn:E] Stopped 2023-03-28T18:04:36.162Z,1680026676.162 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T18:04:36.162Z,1680026676.162 [Default:CheckIn] Stopped 2023-03-28T18:04:36.162Z,1680026676.162 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T18:04:36.162Z,1680026676.162 [Default:CheckIn](INFO): Running loop #10 2023-03-28T18:04:36.162Z,1680026676.162 [Default:CheckIn] Running Loop=10 2023-03-28T18:04:36.162Z,1680026676.162 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T18:04:36.162Z,1680026676.162 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T18:04:38.162Z,1680026678.162 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180437.00,A,3648.17566,N,12147.28489,W,0.039,0.00,280323,,,D*76 2023-03-28T18:04:38.172Z,1680026678.172 [NAL9602](INFO): GPS fix at 20230328T180437: (36.802928, -121.788082) 2023-03-28T18:04:38.220Z,1680026678.220 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T18:04:38.220Z,1680026678.220 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T18:04:45.531Z,1680026685.531 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0009.lzma 2023-03-28T18:04:46.534Z,1680026686.534 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0009.lzma.bak 2023-03-28T18:04:46.534Z,1680026686.534 [DataOverHttps](INFO): SBD MOMSN=17729802 2023-03-28T18:04:46.783Z,1680026686.783 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:04:55.681Z,1680026695.681 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T18:05:02.523Z,1680026702.523 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230328T174318/Express0010.lzma 2023-03-28T18:05:03.526Z,1680026703.526 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0010.lzma.bak 2023-03-28T18:05:03.526Z,1680026703.526 [DataOverHttps](INFO): SBD MOMSN=17729805 2023-03-28T18:05:04.938Z,1680026704.938 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T18:05:04.938Z,1680026704.938 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T18:05:04.938Z,1680026704.938 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:05:08.555Z,1680026708.555 [NAL9602](INFO): SBD MO Status=0, MOMSN=47529, MT Status=0, MTMSN=0 2023-03-28T18:05:08.555Z,1680026708.555 [NAL9602](INFO): No messages in MT queue 2023-03-28T18:05:39.312Z,1680026739.312 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T18:05:39.475Z,1680026739.475 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:06:29.026Z,1680026789.026 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2023-03-28T18:08:41.214Z,1680026921.214 [CBIT](INFO): Clearing failed state for component DropWeight 2023-03-28T18:08:41.214Z,1680026921.214 [DropWeight] No Fault, FailCount= 1 2023-03-28T18:10:05.603Z,1680027005.603 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T18:10:05.603Z,1680027005.603 [Default:CheckIn:C.Wait] Stopped 2023-03-28T18:10:05.603Z,1680027005.603 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T18:10:05.603Z,1680027005.603 [Default:CheckIn:D] Running Loop=1 2023-03-28T18:10:06.011Z,1680027006.011 [Default:CheckIn:D] Stopped 2023-03-28T18:10:06.012Z,1680027006.012 [Default:CheckIn:E] Running Loop=1 2023-03-28T18:10:06.403Z,1680027006.403 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.118567 min 2023-03-28T18:10:06.403Z,1680027006.403 [Default:CheckIn:E] Stopped 2023-03-28T18:10:06.403Z,1680027006.403 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T18:10:06.403Z,1680027006.403 [Default:CheckIn] Stopped 2023-03-28T18:10:06.403Z,1680027006.403 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T18:10:06.403Z,1680027006.403 [Default:CheckIn](INFO): Running loop #11 2023-03-28T18:10:06.404Z,1680027006.404 [Default:CheckIn] Running Loop=11 2023-03-28T18:10:06.404Z,1680027006.404 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T18:10:06.404Z,1680027006.404 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T18:10:08.418Z,1680027008.418 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181007.00,A,3648.17610,N,12147.28542,W,0.019,0.00,280323,,,D*76 2023-03-28T18:10:08.421Z,1680027008.421 [NAL9602](INFO): GPS fix at 20230328T181007: (36.802935, -121.788090) 2023-03-28T18:10:08.431Z,1680027008.431 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T18:10:08.431Z,1680027008.431 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T18:10:11.294Z,1680027011.294 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T18:10:16.239Z,1680027016.239 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0012.lzma 2023-03-28T18:10:17.242Z,1680027017.242 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0012.lzma.bak 2023-03-28T18:10:17.242Z,1680027017.242 [DataOverHttps](INFO): SBD MOMSN=17730182 2023-03-28T18:10:22.555Z,1680027022.555 [NAL9602](INFO): SBD MO Status=0, MOMSN=47530, MT Status=0, MTMSN=0 2023-03-28T18:10:22.555Z,1680027022.555 [NAL9602](INFO): No messages in MT queue 2023-03-28T18:10:33.572Z,1680027033.572 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20230328T174318/Express0013.lzma 2023-03-28T18:10:34.574Z,1680027034.574 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0013.lzma.bak 2023-03-28T18:10:34.574Z,1680027034.574 [DataOverHttps](INFO): SBD MOMSN=17730185 2023-03-28T18:10:36.383Z,1680027036.383 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T18:10:36.383Z,1680027036.383 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T18:10:36.383Z,1680027036.383 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:10:53.351Z,1680027053.351 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T18:10:53.373Z,1680027053.373 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:11:14.781Z,1680027074.781 [CBIT](IMPORTANT): Beginning ground fault scan 2023-03-28T18:11:25.705Z,1680027085.705 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006960 CHAN A1 (24V): 0.002496 CHAN A2 (12V): -0.005350 CHAN A3 (5V): -0.002692 CHAN B0 (3.3V): -0.001953 CHAN B1 (3.15aV): -0.001405 CHAN B2 (3.15bV): -0.001332 CHAN B3 (GND): 0.000509 OPEN: 0.006142 Full Scale: +/- 1 mA 2023-03-28T18:12:26.680Z,1680027146.680 [DVL_micro](ERROR): Failed to parse: :WI,+00750,+00754,-01464,+00000,A 2023-03-28T18:15:36.993Z,1680027336.993 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T18:15:36.993Z,1680027336.993 [Default:CheckIn:C.Wait] Stopped 2023-03-28T18:15:36.993Z,1680027336.993 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T18:15:36.993Z,1680027336.993 [Default:CheckIn:D] Running Loop=1 2023-03-28T18:15:37.396Z,1680027337.396 [Default:CheckIn:D] Stopped 2023-03-28T18:15:37.396Z,1680027337.396 [Default:CheckIn:E] Running Loop=1 2023-03-28T18:15:37.807Z,1680027337.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.641646 min 2023-03-28T18:15:37.807Z,1680027337.807 [Default:CheckIn:E] Stopped 2023-03-28T18:15:37.816Z,1680027337.816 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T18:15:37.816Z,1680027337.816 [Default:CheckIn] Stopped 2023-03-28T18:15:37.816Z,1680027337.816 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T18:15:37.816Z,1680027337.816 [Default:CheckIn](INFO): Running loop #12 2023-03-28T18:15:37.816Z,1680027337.816 [Default:CheckIn] Running Loop=12 2023-03-28T18:15:37.816Z,1680027337.816 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T18:15:37.817Z,1680027337.817 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T18:15:39.812Z,1680027339.812 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181539.00,A,3648.17479,N,12147.28506,W,0.019,0.00,280323,,,D*73 2023-03-28T18:15:39.815Z,1680027339.815 [NAL9602](INFO): GPS fix at 20230328T181539: (36.802913, -121.788084) 2023-03-28T18:15:39.833Z,1680027339.833 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T18:15:39.833Z,1680027339.833 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T18:15:46.433Z,1680027346.433 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T18:15:46.843Z,1680027346.843 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0015.lzma 2023-03-28T18:15:47.846Z,1680027347.846 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0015.lzma.bak 2023-03-28T18:15:47.846Z,1680027347.846 [DataOverHttps](INFO): SBD MOMSN=17730189 2023-03-28T18:15:51.643Z,1680027351.643 [NAL9602](INFO): SBD MO Status=0, MOMSN=47531, MT Status=0, MTMSN=0 2023-03-28T18:15:51.643Z,1680027351.643 [NAL9602](INFO): No messages in MT queue 2023-03-28T18:16:03.792Z,1680027363.792 [DataOverHttps](INFO): Sending 330 bytes from file Logs/20230328T174318/Express0016.lzma 2023-03-28T18:16:04.794Z,1680027364.794 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0016.lzma.bak 2023-03-28T18:16:04.794Z,1680027364.794 [DataOverHttps](INFO): SBD MOMSN=17730192 2023-03-28T18:16:06.189Z,1680027366.189 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T18:16:06.190Z,1680027366.190 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T18:16:06.190Z,1680027366.190 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:16:22.343Z,1680027382.343 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T18:16:22.381Z,1680027382.381 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:21:06.816Z,1680027666.816 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T18:21:06.816Z,1680027666.816 [Default:CheckIn:C.Wait] Stopped 2023-03-28T18:21:06.816Z,1680027666.816 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T18:21:06.817Z,1680027666.817 [Default:CheckIn:D] Running Loop=1 2023-03-28T18:21:07.181Z,1680027667.181 [Default:CheckIn:D] Stopped 2023-03-28T18:21:07.181Z,1680027667.181 [Default:CheckIn:E] Running Loop=1 2023-03-28T18:21:07.599Z,1680027667.599 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.138053 min 2023-03-28T18:21:07.599Z,1680027667.599 [Default:CheckIn:E] Stopped 2023-03-28T18:21:07.599Z,1680027667.599 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T18:21:07.599Z,1680027667.599 [Default:CheckIn] Stopped 2023-03-28T18:21:07.599Z,1680027667.599 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T18:21:07.599Z,1680027667.599 [Default:CheckIn](INFO): Running loop #13 2023-03-28T18:21:07.600Z,1680027667.600 [Default:CheckIn] Running Loop=13 2023-03-28T18:21:07.600Z,1680027667.600 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T18:21:07.600Z,1680027667.600 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T18:21:09.599Z,1680027669.599 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182109.00,A,3648.17540,N,12147.28472,W,0.019,0.00,280323,,,D*7E 2023-03-28T18:21:09.602Z,1680027669.602 [NAL9602](INFO): GPS fix at 20230328T182109: (36.802923, -121.788079) 2023-03-28T18:21:09.636Z,1680027669.636 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T18:21:09.636Z,1680027669.636 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T18:21:17.243Z,1680027677.243 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0018.lzma 2023-03-28T18:21:18.246Z,1680027678.246 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0018.lzma.bak 2023-03-28T18:21:18.246Z,1680027678.246 [DataOverHttps](INFO): SBD MOMSN=17730227 2023-03-28T18:21:35.883Z,1680027695.883 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230328T174318/Express0019.lzma 2023-03-28T18:21:36.886Z,1680027696.886 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0019.lzma.bak 2023-03-28T18:21:36.886Z,1680027696.886 [DataOverHttps](INFO): SBD MOMSN=17730230 2023-03-28T18:21:38.352Z,1680027698.352 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T18:21:38.352Z,1680027698.352 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T18:21:38.353Z,1680027698.353 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:21:44.447Z,1680027704.447 [DVL_micro](ERROR): Failed to parse: :SA,-03.11,+00.85,017.8 2023-03-28T18:22:48.667Z,1680027768.667 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:22:57.118Z,1680027777.118 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:23:06.018Z,1680027786.018 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T18:23:18.502Z,1680027798.502 [NAL9602](INFO): SBD MO Status=2, MOMSN=47532, MT Status=2, MTMSN=0 2023-03-28T18:23:18.502Z,1680027798.502 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T18:23:22.981Z,1680027802.981 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T18:23:29.003Z,1680027809.003 [NAL9602](INFO): SBD MO Status=2, MOMSN=47532, MT Status=2, MTMSN=0 2023-03-28T18:23:29.003Z,1680027809.003 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T18:23:33.544Z,1680027813.544 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T18:23:45.566Z,1680027825.566 [NAL9602](INFO): SBD MO Status=0, MOMSN=47532, MT Status=0, MTMSN=0 2023-03-28T18:23:45.567Z,1680027825.567 [NAL9602](INFO): No messages in MT queue 2023-03-28T18:23:56.472Z,1680027836.472 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-03-28T18:23:56.475Z,1680027836.475 [BPC1](INFO): Received data from all battery sticks. 2023-03-28T18:24:16.257Z,1680027856.257 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T18:24:16.311Z,1680027856.311 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:24:47.402Z,1680027887.402 [DVL_micro](ERROR): Failed to parse: :WI,-02060,-00013,00000,A 2023-03-28T18:26:38.931Z,1680027998.931 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T18:26:38.931Z,1680027998.931 [Default:CheckIn:C.Wait] Stopped 2023-03-28T18:26:38.931Z,1680027998.931 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T18:26:38.932Z,1680027998.932 [Default:CheckIn:D] Running Loop=1 2023-03-28T18:26:39.291Z,1680027999.291 [Default:CheckIn:D] Stopped 2023-03-28T18:26:39.291Z,1680027999.291 [Default:CheckIn:E] Running Loop=1 2023-03-28T18:26:39.718Z,1680027999.718 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.673226 min 2023-03-28T18:26:39.718Z,1680027999.718 [Default:CheckIn:E] Stopped 2023-03-28T18:26:39.718Z,1680027999.718 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T18:26:39.718Z,1680027999.718 [Default:CheckIn] Stopped 2023-03-28T18:26:39.718Z,1680027999.718 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T18:26:39.718Z,1680027999.718 [Default:CheckIn](INFO): Running loop #14 2023-03-28T18:26:39.719Z,1680027999.718 [Default:CheckIn] Running Loop=14 2023-03-28T18:26:39.719Z,1680027999.719 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T18:26:39.719Z,1680027999.719 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T18:26:41.707Z,1680028001.707 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182641.00,A,3648.17482,N,12147.28428,W,0.019,0.00,280323,,,D*75 2023-03-28T18:26:41.710Z,1680028001.710 [NAL9602](INFO): GPS fix at 20230328T182641: (36.802914, -121.788071) 2023-03-28T18:26:41.720Z,1680028001.720 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T18:26:41.720Z,1680028001.720 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T18:26:51.380Z,1680028011.380 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20230328T174318/Courier0021.lzma 2023-03-28T18:26:52.309Z,1680028012.309 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T18:26:52.382Z,1680028012.382 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0021.lzma.bak 2023-03-28T18:26:52.382Z,1680028012.382 [DataOverHttps](INFO): SBD MOMSN=17730577 2023-03-28T18:27:03.967Z,1680028023.967 [NAL9602](INFO): SBD MO Status=0, MOMSN=47533, MT Status=0, MTMSN=0 2023-03-28T18:27:03.967Z,1680028023.967 [NAL9602](INFO): No messages in MT queue 2023-03-28T18:27:16.596Z,1680028036.596 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230328T174318/Express0022.lzma 2023-03-28T18:27:17.598Z,1680028037.598 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0022.lzma.bak 2023-03-28T18:27:17.598Z,1680028037.598 [DataOverHttps](INFO): SBD MOMSN=17730581 2023-03-28T18:27:19.380Z,1680028039.380 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T18:27:19.380Z,1680028039.380 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T18:27:19.380Z,1680028039.380 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:27:34.691Z,1680028054.691 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T18:27:34.761Z,1680028054.761 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:27:47.197Z,1680028067.197 [DVL_micro](ERROR): Failed to parse: :WI,-05034,+01295,-02134,+00000,A 2023-03-28T18:29:18.929Z,1680028158.929 [DVL_micro](ERROR): only read 1 of 4 data items 2023-03-28T18:29:18.929Z,1680028158.929 [DVL_micro](ERROR): Failed to parse: :RD,+9999..99,+9999.99,+9999.99 2023-03-28T18:30:42.620Z,1680028242.620 [DVL_micro](ERROR): DVL uart error: serial timeout 2023-03-28T18:30:42.620Z,1680028242.620 [DVL_micro] Communications Fault, FailCount= 1 2023-03-28T18:30:42.620Z,1680028242.620 [DVL_micro](ERROR): Communications Fault 2023-03-28T18:30:42.620Z,1680028242.620 [DVL_micro](ERROR): Failed to parse: 2023-03-28T18:30:42.645Z,1680028242.645 [CBIT](ERROR): Communications Fault in component: DVL_micro 2023-03-28T18:30:43.012Z,1680028243.012 [DVL_micro](INFO): Powering down 2023-03-28T18:30:43.785Z,1680028243.785 [CBIT](INFO): Clearing failed state for component DVL_micro 2023-03-28T18:30:43.785Z,1680028243.785 [DVL_micro] No Fault, FailCount= 1 2023-03-28T18:31:52.446Z,1680028312.446 [DVL_micro](ERROR): only read 1 of 4 data items 2023-03-28T18:31:52.446Z,1680028312.446 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,++9999.99,+9999.99 2023-03-28T18:32:19.919Z,1680028339.919 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T18:32:19.919Z,1680028339.919 [Default:CheckIn:C.Wait] Stopped 2023-03-28T18:32:19.919Z,1680028339.919 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T18:32:19.920Z,1680028339.920 [Default:CheckIn:D] Running Loop=1 2023-03-28T18:32:20.337Z,1680028340.337 [Default:CheckIn:D] Stopped 2023-03-28T18:32:20.337Z,1680028340.337 [Default:CheckIn:E] Running Loop=1 2023-03-28T18:32:20.726Z,1680028340.726 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.357332 min 2023-03-28T18:32:20.726Z,1680028340.726 [Default:CheckIn:E] Stopped 2023-03-28T18:32:20.726Z,1680028340.726 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T18:32:20.726Z,1680028340.726 [Default:CheckIn] Stopped 2023-03-28T18:32:20.726Z,1680028340.726 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T18:32:20.726Z,1680028340.726 [Default:CheckIn](INFO): Running loop #15 2023-03-28T18:32:20.726Z,1680028340.726 [Default:CheckIn] Running Loop=15 2023-03-28T18:32:20.726Z,1680028340.726 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T18:32:20.726Z,1680028340.726 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T18:32:22.738Z,1680028342.738 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183222.00,A,3648.17641,N,12147.28457,W,0.019,0.00,280323,,,D*70 2023-03-28T18:32:22.741Z,1680028342.741 [NAL9602](INFO): GPS fix at 20230328T183222: (36.802940, -121.788076) 2023-03-28T18:32:22.751Z,1680028342.751 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T18:32:22.751Z,1680028342.751 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T18:32:29.678Z,1680028349.678 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:32:31.139Z,1680028351.139 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0024.lzma 2023-03-28T18:32:32.142Z,1680028352.142 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0024.lzma.bak 2023-03-28T18:32:32.142Z,1680028352.142 [DataOverHttps](INFO): SBD MOMSN=17730614 2023-03-28T18:32:51.156Z,1680028371.156 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230328T174318/Express0025.lzma 2023-03-28T18:32:52.158Z,1680028372.158 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0025.lzma.bak 2023-03-28T18:32:52.158Z,1680028372.158 [DataOverHttps](INFO): SBD MOMSN=17730617 2023-03-28T18:32:53.528Z,1680028373.528 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T18:32:53.528Z,1680028373.528 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T18:32:53.528Z,1680028373.528 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:32:55.575Z,1680028375.575 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:33:04.458Z,1680028384.458 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T18:33:22.583Z,1680028402.583 [NAL9602](INFO): SBD MO Status=2, MOMSN=47534, MT Status=2, MTMSN=0 2023-03-28T18:33:22.583Z,1680028402.583 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T18:33:27.094Z,1680028407.094 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:33:28.341Z,1680028408.341 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:33:36.818Z,1680028416.818 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:34:34.987Z,1680028474.987 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:34:39.428Z,1680028479.428 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T18:34:58.735Z,1680028498.735 [NAL9602](INFO): SBD MO Status=2, MOMSN=47534, MT Status=2, MTMSN=0 2023-03-28T18:34:58.736Z,1680028498.736 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T18:35:03.219Z,1680028503.219 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T18:35:08.459Z,1680028508.459 [NAL9602](INFO): SBD MO Status=0, MOMSN=47534, MT Status=0, MTMSN=0 2023-03-28T18:35:08.459Z,1680028508.459 [NAL9602](INFO): No messages in MT queue 2023-03-28T18:35:39.134Z,1680028539.134 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T18:35:39.224Z,1680028539.224 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:37:52.906Z,1680028672.906 [DVL_micro](ERROR): Failed to parse: :WI,+05796,+04759,-01332,+00000,A 2023-03-28T18:37:54.132Z,1680028674.132 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T18:37:54.132Z,1680028674.132 [Default:CheckIn:C.Wait] Stopped 2023-03-28T18:37:54.132Z,1680028674.132 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T18:37:54.132Z,1680028674.132 [Default:CheckIn:D] Running Loop=1 2023-03-28T18:37:54.531Z,1680028674.531 [Default:CheckIn:D] Stopped 2023-03-28T18:37:54.531Z,1680028674.531 [Default:CheckIn:E] Running Loop=1 2023-03-28T18:37:54.935Z,1680028674.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.927222 min 2023-03-28T18:37:54.935Z,1680028674.935 [Default:CheckIn:E] Stopped 2023-03-28T18:37:54.935Z,1680028674.935 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T18:37:54.935Z,1680028674.935 [Default:CheckIn] Stopped 2023-03-28T18:37:54.936Z,1680028674.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T18:37:54.936Z,1680028674.936 [Default:CheckIn](INFO): Running loop #16 2023-03-28T18:37:54.936Z,1680028674.936 [Default:CheckIn] Running Loop=16 2023-03-28T18:37:54.936Z,1680028674.936 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T18:37:54.936Z,1680028674.936 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T18:37:56.953Z,1680028676.953 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183756.00,A,3648.17616,N,12147.28562,W,0.058,0.00,280323,,,D*76 2023-03-28T18:37:56.955Z,1680028676.955 [NAL9602](INFO): GPS fix at 20230328T183756: (36.802936, -121.788094) 2023-03-28T18:37:56.965Z,1680028676.965 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T18:37:56.965Z,1680028676.965 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T18:38:03.979Z,1680028683.979 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230328T174318/Courier0027.lzma 2023-03-28T18:38:04.982Z,1680028684.982 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0027.lzma.bak 2023-03-28T18:38:04.982Z,1680028684.982 [DataOverHttps](INFO): SBD MOMSN=17730976 2023-03-28T18:38:20.899Z,1680028700.899 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230328T174318/Express0028.lzma 2023-03-28T18:38:21.641Z,1680028701.641 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:38:21.902Z,1680028701.902 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0028.lzma.bak 2023-03-28T18:38:21.902Z,1680028701.902 [DataOverHttps](INFO): SBD MOMSN=17730979 2023-03-28T18:38:23.235Z,1680028703.235 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T18:38:23.235Z,1680028703.235 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T18:38:23.235Z,1680028703.235 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:38:30.114Z,1680028710.114 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:38:42.602Z,1680028722.602 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-03-28T18:38:42.602Z,1680028722.602 [DropWeight] Hardware Fault, FailCount= 1 2023-03-28T18:38:42.602Z,1680028722.602 [DropWeight](ERROR): Hardware Fault 2023-03-28T18:38:42.660Z,1680028722.660 [CBIT](INFO): Critical error at 20230328T183842 2023-03-28T18:38:42.663Z,1680028722.663 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-03-28T18:38:42.663Z,1680028722.663 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-03-28T18:38:43.020Z,1680028723.020 [CBIT](INFO): Critical error at 20230328T183842 2023-03-28T18:40:05.358Z,1680028805.358 [CommandLine](INFO): End of History 2023-03-28T18:40:06.091Z,1680028806.091 [CommandLine](INFO): End of History 2023-03-28T18:40:06.790Z,1680028806.790 [CommandLine](INFO): End of History 2023-03-28T18:40:07.453Z,1680028807.453 [CommandLine](INFO): End of History 2023-03-28T18:40:08.169Z,1680028808.169 [CommandLine](INFO): End of History 2023-03-28T18:40:08.871Z,1680028808.871 [CommandLine](INFO): End of History 2023-03-28T18:40:09.561Z,1680028809.561 [CommandLine](INFO): End of History 2023-03-28T18:40:10.210Z,1680028810.210 [CommandLine](INFO): End of History 2023-03-28T18:40:10.895Z,1680028810.895 [CommandLine](INFO): End of History 2023-03-28T18:40:11.574Z,1680028811.574 [CommandLine](INFO): End of History 2023-03-28T18:40:24.866Z,1680028824.866 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:40:33.798Z,1680028833.798 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:40:42.235Z,1680028842.235 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:40:50.739Z,1680028850.739 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T18:40:55.533Z,1680028855.533 [DVL_micro](ERROR): only read 3 of 4 data items 2023-03-28T18:40:55.533Z,1680028855.533 [DVL_micro](ERROR): Failed to parse: :BI,+00105,+02238,-00D,+00000.00,+00000000.00,9999.99,000.00 2023-03-28T18:41:09.288Z,1680028869.288 [NAL9602](INFO): SBD MO Status=2, MOMSN=47535, MT Status=2, MTMSN=0 2023-03-28T18:41:09.288Z,1680028869.288 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T18:41:13.775Z,1680028873.775 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:41:57.834Z,1680028917.834 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:42:06.724Z,1680028926.724 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:42:56.843Z,1680028976.843 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:42:59.236Z,1680028979.236 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-03-28T18:43:23.885Z,1680029003.885 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T18:43:23.886Z,1680029003.886 [Default:CheckIn:C.Wait] Stopped 2023-03-28T18:43:23.886Z,1680029003.886 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T18:43:23.886Z,1680029003.886 [Default:CheckIn:D] Running Loop=1 2023-03-28T18:43:24.286Z,1680029004.286 [Default:CheckIn:D] Stopped 2023-03-28T18:43:24.286Z,1680029004.286 [Default:CheckIn:E] Running Loop=1 2023-03-28T18:43:24.692Z,1680029004.692 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.423136 min 2023-03-28T18:43:24.692Z,1680029004.692 [Default:CheckIn:E] Stopped 2023-03-28T18:43:24.692Z,1680029004.692 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T18:43:24.692Z,1680029004.692 [Default:CheckIn] Stopped 2023-03-28T18:43:24.692Z,1680029004.692 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T18:43:24.692Z,1680029004.692 [Default:CheckIn](INFO): Running loop #17 2023-03-28T18:43:24.692Z,1680029004.692 [Default:CheckIn] Running Loop=17 2023-03-28T18:43:24.693Z,1680029004.693 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T18:43:24.693Z,1680029004.693 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T18:43:26.712Z,1680029006.712 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184326.00,A,3648.17590,N,12147.28535,W,0.000,0.00,280323,,,D*70 2023-03-28T18:43:26.714Z,1680029006.714 [NAL9602](INFO): GPS fix at 20230328T184326: (36.802932, -121.788089) 2023-03-28T18:43:26.725Z,1680029006.725 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T18:43:26.725Z,1680029006.725 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T18:43:36.540Z,1680029016.540 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20230328T174318/Courier0030.lzma 2023-03-28T18:43:37.542Z,1680029017.542 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0030.lzma.bak 2023-03-28T18:43:37.542Z,1680029017.542 [DataOverHttps](INFO): SBD MOMSN=17730984 2023-03-28T18:43:55.392Z,1680029035.392 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20230328T174318/Express0031.lzma 2023-03-28T18:43:56.394Z,1680029036.394 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0031.lzma.bak 2023-03-28T18:43:56.394Z,1680029036.394 [DataOverHttps](INFO): SBD MOMSN=17731013 2023-03-28T18:43:57.867Z,1680029037.867 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T18:43:57.867Z,1680029037.867 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T18:43:57.867Z,1680029037.867 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:43:59.477Z,1680029039.477 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T18:43:59.544Z,1680029039.544 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:48:58.296Z,1680029338.296 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T18:48:58.296Z,1680029338.296 [Default:CheckIn:C.Wait] Stopped 2023-03-28T18:48:58.296Z,1680029338.296 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T18:48:58.296Z,1680029338.296 [Default:CheckIn:D] Running Loop=1 2023-03-28T18:48:58.712Z,1680029338.712 [Default:CheckIn:D] Stopped 2023-03-28T18:48:58.712Z,1680029338.712 [Default:CheckIn:E] Running Loop=1 2023-03-28T18:48:59.102Z,1680029339.102 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.996908 min 2023-03-28T18:48:59.102Z,1680029339.102 [Default:CheckIn:E] Stopped 2023-03-28T18:48:59.102Z,1680029339.102 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T18:48:59.102Z,1680029339.102 [Default:CheckIn] Stopped 2023-03-28T18:48:59.102Z,1680029339.102 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T18:48:59.102Z,1680029339.102 [Default:CheckIn](INFO): Running loop #18 2023-03-28T18:48:59.103Z,1680029339.103 [Default:CheckIn] Running Loop=18 2023-03-28T18:48:59.103Z,1680029339.103 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T18:48:59.103Z,1680029339.103 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T18:49:01.112Z,1680029341.112 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184900.00,A,3648.17582,N,12147.28584,W,0.058,0.00,280323,,,D*7A 2023-03-28T18:49:01.114Z,1680029341.114 [NAL9602](INFO): GPS fix at 20230328T184900: (36.802930, -121.788097) 2023-03-28T18:49:01.124Z,1680029341.124 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T18:49:01.124Z,1680029341.124 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T18:49:09.047Z,1680029349.047 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0033.lzma 2023-03-28T18:49:10.058Z,1680029350.058 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0033.lzma.bak 2023-03-28T18:49:10.058Z,1680029350.058 [DataOverHttps](INFO): SBD MOMSN=17731017 2023-03-28T18:49:26.159Z,1680029366.159 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230328T174318/Express0034.lzma 2023-03-28T18:49:27.162Z,1680029367.162 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0034.lzma.bak 2023-03-28T18:49:27.162Z,1680029367.162 [DataOverHttps](INFO): SBD MOMSN=17731020 2023-03-28T18:49:28.607Z,1680029368.607 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T18:49:28.607Z,1680029368.607 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T18:49:28.607Z,1680029368.607 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:49:31.824Z,1680029371.824 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-03-28T18:49:31.904Z,1680029371.904 [NAL9602](ERROR): received: +CSQ:1 OK535, 2, 0, 0, 0 OK 2023-03-28T18:49:37.963Z,1680029377.963 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:49:46.432Z,1680029386.432 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:49:52.891Z,1680029392.891 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T18:50:11.443Z,1680029411.443 [NAL9602](INFO): SBD MO Status=2, MOMSN=47535, MT Status=2, MTMSN=0 2023-03-28T18:50:11.443Z,1680029411.443 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T18:50:15.920Z,1680029415.920 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:50:32.570Z,1680029432.570 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:50:43.049Z,1680029443.049 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T18:50:54.722Z,1680029454.722 [NAL9602](INFO): SBD MO Status=2, MOMSN=47535, MT Status=2, MTMSN=0 2023-03-28T18:50:54.722Z,1680029454.722 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T18:50:59.209Z,1680029459.209 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:51:14.558Z,1680029474.558 [DVL_micro](ERROR): only read 3 of 4 data items 2023-03-28T18:51:14.558Z,1680029474.558 [DVL_micro](ERROR): Failed to parse: :RD,+9999.999,+9999.99,+9999.99 2023-03-28T18:51:26.287Z,1680029486.287 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:51:35.351Z,1680029495.351 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:52:13.195Z,1680029533.195 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T18:52:26.085Z,1680029546.085 [NAL9602](INFO): SBD MO Status=0, MOMSN=47535, MT Status=0, MTMSN=0 2023-03-28T18:52:26.085Z,1680029546.085 [NAL9602](INFO): No messages in MT queue 2023-03-28T18:52:56.836Z,1680029576.836 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T18:52:56.886Z,1680029576.886 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:53:42.976Z,1680029622.976 [CBIT](INFO): Clearing failed state for component DropWeight 2023-03-28T18:53:42.976Z,1680029622.976 [DropWeight] No Fault, FailCount= 1 2023-03-28T18:54:29.410Z,1680029669.410 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T18:54:29.410Z,1680029669.410 [Default:CheckIn:C.Wait] Stopped 2023-03-28T18:54:29.410Z,1680029669.410 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T18:54:29.410Z,1680029669.410 [Default:CheckIn:D] Running Loop=1 2023-03-28T18:54:29.814Z,1680029669.814 [Default:CheckIn:D] Stopped 2023-03-28T18:54:29.814Z,1680029669.814 [Default:CheckIn:E] Running Loop=1 2023-03-28T18:54:30.234Z,1680029670.234 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.515275 min 2023-03-28T18:54:30.234Z,1680029670.234 [Default:CheckIn:E] Stopped 2023-03-28T18:54:30.234Z,1680029670.234 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T18:54:30.234Z,1680029670.234 [Default:CheckIn] Stopped 2023-03-28T18:54:30.234Z,1680029670.234 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T18:54:30.235Z,1680029670.235 [Default:CheckIn](INFO): Running loop #19 2023-03-28T18:54:30.235Z,1680029670.235 [Default:CheckIn] Running Loop=19 2023-03-28T18:54:30.235Z,1680029670.235 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T18:54:30.235Z,1680029670.235 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T18:54:32.233Z,1680029672.233 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185431.00,A,3648.17584,N,12147.28590,W,0.000,0.00,280323,,,D*7A 2023-03-28T18:54:32.235Z,1680029672.235 [NAL9602](INFO): GPS fix at 20230328T185431: (36.802931, -121.788098) 2023-03-28T18:54:32.277Z,1680029672.277 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T18:54:32.277Z,1680029672.277 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T18:54:42.723Z,1680029682.723 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230328T174318/Courier0036.lzma 2023-03-28T18:54:43.726Z,1680029683.726 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0036.lzma.bak 2023-03-28T18:54:43.726Z,1680029683.726 [DataOverHttps](INFO): SBD MOMSN=17731055 2023-03-28T18:54:59.803Z,1680029699.803 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230328T174318/Express0037.lzma 2023-03-28T18:55:00.806Z,1680029700.806 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0037.lzma.bak 2023-03-28T18:55:00.806Z,1680029700.806 [DataOverHttps](INFO): SBD MOMSN=17731059 2023-03-28T18:55:02.190Z,1680029702.190 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T18:55:02.190Z,1680029702.190 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T18:55:02.190Z,1680029702.190 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T18:55:58.437Z,1680029758.437 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T18:56:11.377Z,1680029771.377 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:57:03.568Z,1680029823.568 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T18:57:14.823Z,1680029834.823 [NAL9602](INFO): SBD MO Status=2, MOMSN=47536, MT Status=2, MTMSN=0 2023-03-28T18:57:14.823Z,1680029834.823 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T18:57:19.299Z,1680029839.299 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T18:58:57.183Z,1680029937.183 [Reporter](INFO): NAL9602.sigQuality 4 count 2023-03-28T18:58:59.153Z,1680029939.153 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-03-28T18:58:59.153Z,1680029939.153 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+11.2,0000.0,1489.WI,-00114,-00407,-02820,+00000,A 2023-03-28T18:59:08.038Z,1680029948.038 [NAL9602](INFO): SBD MO Status=0, MOMSN=47536, MT Status=0, MTMSN=0 2023-03-28T18:59:08.039Z,1680029948.039 [NAL9602](INFO): No messages in MT queue 2023-03-28T18:59:38.743Z,1680029978.743 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T18:59:38.782Z,1680029978.782 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:00:02.651Z,1680030002.651 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:00:02.651Z,1680030002.651 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:00:02.651Z,1680030002.651 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:00:02.651Z,1680030002.651 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:00:03.039Z,1680030003.039 [Default:CheckIn:D] Stopped 2023-03-28T19:00:03.039Z,1680030003.039 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:00:03.442Z,1680030003.442 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.069027 min 2023-03-28T19:00:03.442Z,1680030003.442 [Default:CheckIn:E] Stopped 2023-03-28T19:00:03.442Z,1680030003.442 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:00:03.442Z,1680030003.442 [Default:CheckIn] Stopped 2023-03-28T19:00:03.442Z,1680030003.442 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:00:03.442Z,1680030003.442 [Default:CheckIn](INFO): Running loop #20 2023-03-28T19:00:03.442Z,1680030003.442 [Default:CheckIn] Running Loop=20 2023-03-28T19:00:03.443Z,1680030003.443 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:00:03.443Z,1680030003.443 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:00:05.457Z,1680030005.457 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190004.00,A,3648.17644,N,12147.28410,W,0.019,0.00,280323,,,D*72 2023-03-28T19:00:05.459Z,1680030005.459 [NAL9602](INFO): GPS fix at 20230328T190004: (36.802941, -121.788068) 2023-03-28T19:00:05.470Z,1680030005.470 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:00:05.470Z,1680030005.470 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:00:12.847Z,1680030012.847 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230328T174318/Courier0039.lzma 2023-03-28T19:00:13.850Z,1680030013.850 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0039.lzma.bak 2023-03-28T19:00:13.850Z,1680030013.850 [DataOverHttps](INFO): SBD MOMSN=17731725 2023-03-28T19:00:22.062Z,1680030022.062 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:00:30.171Z,1680030030.171 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20230328T174318/Express0040.lzma 2023-03-28T19:00:30.565Z,1680030030.565 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:00:31.170Z,1680030031.170 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0040.lzma.bak 2023-03-28T19:00:31.170Z,1680030031.170 [DataOverHttps](INFO): SBD MOMSN=17731728 2023-03-28T19:00:32.537Z,1680030032.537 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:00:32.537Z,1680030032.537 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:00:32.537Z,1680030032.537 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:00:41.931Z,1680030041.931 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:00:50.810Z,1680030050.810 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T19:01:10.151Z,1680030070.151 [NAL9602](INFO): SBD MO Status=2, MOMSN=47537, MT Status=2, MTMSN=0 2023-03-28T19:01:10.152Z,1680030070.152 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T19:01:14.646Z,1680030074.646 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:01:26.368Z,1680030086.368 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T19:01:44.552Z,1680030104.552 [NAL9602](INFO): SBD MO Status=2, MOMSN=47537, MT Status=2, MTMSN=0 2023-03-28T19:01:44.552Z,1680030104.552 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T19:01:49.090Z,1680030109.090 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:02:00.736Z,1680030120.736 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:02:06.423Z,1680030126.423 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T19:02:24.947Z,1680030144.947 [NAL9602](INFO): SBD MO Status=2, MOMSN=47537, MT Status=2, MTMSN=0 2023-03-28T19:02:24.947Z,1680030144.947 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T19:02:29.073Z,1680030149.073 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:03:32.955Z,1680030212.955 [Reporter](INFO): NAL9602.sigQuality 4 count 2023-03-28T19:03:51.474Z,1680030231.474 [NAL9602](INFO): SBD MO Status=2, MOMSN=47537, MT Status=2, MTMSN=0 2023-03-28T19:03:51.474Z,1680030231.474 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T19:03:55.986Z,1680030235.986 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:04:17.782Z,1680030257.782 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T19:04:32.295Z,1680030272.295 [NAL9602](INFO): SBD MO Status=2, MOMSN=47537, MT Status=2, MTMSN=0 2023-03-28T19:04:32.296Z,1680030272.296 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T19:04:36.813Z,1680030276.813 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:04:59.367Z,1680030299.367 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-03-28T19:04:59.367Z,1680030299.367 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,.9,0000.0,14 2023-03-28T19:05:07.430Z,1680030307.430 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-03-28T19:05:22.398Z,1680030322.398 [DVL_micro](ERROR): only read 3 of 4 data items 2023-03-28T19:05:22.398Z,1680030322.398 [DVL_micro](ERROR): Failed to parse: :BI,-05347,-05811,00000,I 2023-03-28T19:05:33.303Z,1680030333.303 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:05:33.303Z,1680030333.303 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:05:33.303Z,1680030333.303 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:05:33.304Z,1680030333.304 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:05:33.708Z,1680030333.708 [Default:CheckIn:D] Stopped 2023-03-28T19:05:33.708Z,1680030333.708 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:05:34.122Z,1680030334.122 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.580184 min 2023-03-28T19:05:34.123Z,1680030334.123 [Default:CheckIn:E] Stopped 2023-03-28T19:05:34.123Z,1680030334.123 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:05:34.123Z,1680030334.123 [Default:CheckIn] Stopped 2023-03-28T19:05:34.123Z,1680030334.123 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:05:34.123Z,1680030334.123 [Default:CheckIn](INFO): Running loop #21 2023-03-28T19:05:34.123Z,1680030334.123 [Default:CheckIn] Running Loop=21 2023-03-28T19:05:34.123Z,1680030334.123 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:05:34.123Z,1680030334.123 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:05:36.114Z,1680030336.114 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190535.00,A,3648.17650,N,12147.28484,W,0.000,0.00,280323,,,D*75 2023-03-28T19:05:36.125Z,1680030336.125 [NAL9602](INFO): GPS fix at 20230328T190535: (36.802942, -121.788081) 2023-03-28T19:05:36.135Z,1680030336.135 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:05:36.135Z,1680030336.135 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:05:45.435Z,1680030345.435 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0042.lzma 2023-03-28T19:05:46.438Z,1680030346.438 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0042.lzma.bak 2023-03-28T19:05:46.438Z,1680030346.438 [DataOverHttps](INFO): SBD MOMSN=17731732 2023-03-28T19:06:02.316Z,1680030362.316 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230328T174318/Express0043.lzma 2023-03-28T19:06:03.318Z,1680030363.318 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0043.lzma.bak 2023-03-28T19:06:03.318Z,1680030363.318 [DataOverHttps](INFO): SBD MOMSN=17731735 2023-03-28T19:06:04.811Z,1680030364.811 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:06:04.811Z,1680030364.811 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:06:04.811Z,1680030364.811 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:06:08.444Z,1680030368.444 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T19:10:59.850Z,1680030659.850 [DVL_micro](ERROR): only read 3 of 4 data items 2023-03-28T19:10:59.850Z,1680030659.850 [DVL_micro](ERROR): Failed to parse: :BI,+05102,-0004,+00000,I 2023-03-28T19:11:05.502Z,1680030665.502 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:11:05.502Z,1680030665.502 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:11:05.502Z,1680030665.502 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:11:05.502Z,1680030665.502 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:11:05.903Z,1680030665.903 [Default:CheckIn:D] Stopped 2023-03-28T19:11:05.903Z,1680030665.903 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:11:06.309Z,1680030666.309 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.116756 min 2023-03-28T19:11:06.309Z,1680030666.309 [Default:CheckIn:E] Stopped 2023-03-28T19:11:06.309Z,1680030666.309 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:11:06.309Z,1680030666.309 [Default:CheckIn] Stopped 2023-03-28T19:11:06.309Z,1680030666.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:11:06.309Z,1680030666.309 [Default:CheckIn](INFO): Running loop #22 2023-03-28T19:11:06.309Z,1680030666.309 [Default:CheckIn] Running Loop=22 2023-03-28T19:11:06.310Z,1680030666.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:11:06.310Z,1680030666.310 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:11:08.322Z,1680030668.322 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191107.00,A,3648.17630,N,12147.28514,W,0.039,0.00,280323,,,D*75 2023-03-28T19:11:08.324Z,1680030668.324 [NAL9602](INFO): GPS fix at 20230328T191107: (36.802938, -121.788086) 2023-03-28T19:11:08.351Z,1680030668.351 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:11:08.351Z,1680030668.351 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:11:15.275Z,1680030675.275 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0045.lzma 2023-03-28T19:11:16.278Z,1680030676.278 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0045.lzma.bak 2023-03-28T19:11:16.278Z,1680030676.278 [DataOverHttps](INFO): SBD MOMSN=17732169 2023-03-28T19:11:25.775Z,1680030685.775 [CBIT](IMPORTANT): Beginning ground fault scan 2023-03-28T19:11:32.316Z,1680030692.316 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230328T174318/Express0046.lzma 2023-03-28T19:11:33.318Z,1680030693.318 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0046.lzma.bak 2023-03-28T19:11:33.318Z,1680030693.318 [DataOverHttps](INFO): SBD MOMSN=17732172 2023-03-28T19:11:34.677Z,1680030694.677 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:11:34.677Z,1680030694.677 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:11:34.677Z,1680030694.677 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:11:36.844Z,1680030696.844 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.004711 CHAN A1 (24V): 0.021695 CHAN A2 (12V): 0.002941 CHAN A3 (5V): -0.001783 CHAN B0 (3.3V): -0.001532 CHAN B1 (3.15aV): -0.001538 CHAN B2 (3.15bV): -0.001294 CHAN B3 (GND): -0.002354 OPEN: 0.006806 Full Scale: +/- 1 mA 2023-03-28T19:11:38.743Z,1680030698.743 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-03-28T19:11:38.826Z,1680030698.826 [NAL9602](ERROR): received: +CSQ:0 OK537, 2, 0, 0, 0 OK 2023-03-28T19:13:39.780Z,1680030819.780 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:13:48.125Z,1680030828.125 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:13:57.018Z,1680030837.018 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:14:05.495Z,1680030845.495 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:14:15.211Z,1680030855.211 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:14:32.610Z,1680030872.610 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:14:41.072Z,1680030881.072 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T19:14:59.990Z,1680030899.990 [NAL9602](INFO): SBD MO Status=2, MOMSN=47537, MT Status=2, MTMSN=0 2023-03-28T19:14:59.990Z,1680030899.990 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T19:15:04.475Z,1680030904.475 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:15:15.800Z,1680030915.800 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:15:24.682Z,1680030924.682 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:15:38.007Z,1680030938.007 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:15:46.913Z,1680030946.913 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:15:55.379Z,1680030955.379 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:15:57.022Z,1680030957.022 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T19:16:07.858Z,1680030967.858 [NAL9602](INFO): SBD MO Status=0, MOMSN=47537, MT Status=0, MTMSN=0 2023-03-28T19:16:07.858Z,1680030967.858 [NAL9602](INFO): No messages in MT queue 2023-03-28T19:16:35.346Z,1680030995.346 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:16:35.347Z,1680030995.347 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:16:35.347Z,1680030995.347 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:16:35.347Z,1680030995.347 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:16:35.752Z,1680030995.752 [Default:CheckIn:D] Stopped 2023-03-28T19:16:35.752Z,1680030995.752 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:16:36.157Z,1680030996.157 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.614233 min 2023-03-28T19:16:36.157Z,1680030996.157 [Default:CheckIn:E] Stopped 2023-03-28T19:16:36.157Z,1680030996.157 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:16:36.157Z,1680030996.157 [Default:CheckIn] Stopped 2023-03-28T19:16:36.157Z,1680030996.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:16:36.157Z,1680030996.157 [Default:CheckIn](INFO): Running loop #23 2023-03-28T19:16:36.157Z,1680030996.157 [Default:CheckIn] Running Loop=23 2023-03-28T19:16:36.157Z,1680030996.157 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:16:36.158Z,1680030996.158 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:16:38.158Z,1680030998.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191637.00,A,3648.17524,N,12147.28485,W,0.019,0.00,280323,,,D*7C 2023-03-28T19:16:38.168Z,1680030998.168 [NAL9602](INFO): GPS fix at 20230328T191637: (36.802921, -121.788081) 2023-03-28T19:16:38.207Z,1680030998.207 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:16:38.207Z,1680030998.207 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:16:46.287Z,1680031006.287 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0048.lzma 2023-03-28T19:16:47.290Z,1680031007.290 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0048.lzma.bak 2023-03-28T19:16:47.290Z,1680031007.290 [DataOverHttps](INFO): SBD MOMSN=17732175 2023-03-28T19:16:55.166Z,1680031015.166 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-03-28T19:16:55.166Z,1680031015.166 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+10.3,0000.0,148 2023-03-28T19:17:03.244Z,1680031023.244 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20230328T174318/Express0049.lzma 2023-03-28T19:17:04.246Z,1680031024.246 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0049.lzma.bak 2023-03-28T19:17:04.246Z,1680031024.246 [DataOverHttps](INFO): SBD MOMSN=17732180 2023-03-28T19:17:05.683Z,1680031025.683 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:17:05.683Z,1680031025.683 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:17:05.683Z,1680031025.683 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:17:10.518Z,1680031030.518 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T19:17:10.556Z,1680031030.556 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:22:06.282Z,1680031326.282 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:22:06.282Z,1680031326.282 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:22:06.282Z,1680031326.282 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:22:06.282Z,1680031326.282 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:22:06.686Z,1680031326.686 [Default:CheckIn:D] Stopped 2023-03-28T19:22:06.686Z,1680031326.686 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:22:07.111Z,1680031327.111 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.129810 min 2023-03-28T19:22:07.112Z,1680031327.112 [Default:CheckIn:E] Stopped 2023-03-28T19:22:07.112Z,1680031327.112 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:22:07.112Z,1680031327.112 [Default:CheckIn] Stopped 2023-03-28T19:22:07.112Z,1680031327.112 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:22:07.112Z,1680031327.112 [Default:CheckIn](INFO): Running loop #24 2023-03-28T19:22:07.112Z,1680031327.112 [Default:CheckIn] Running Loop=24 2023-03-28T19:22:07.112Z,1680031327.112 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:22:07.113Z,1680031327.113 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:22:09.098Z,1680031329.098 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192208.00,A,3648.17679,N,12147.28449,W,0.058,0.00,280323,,,D*79 2023-03-28T19:22:09.100Z,1680031329.100 [NAL9602](INFO): GPS fix at 20230328T192208: (36.802946, -121.788075) 2023-03-28T19:22:09.123Z,1680031329.123 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:22:09.123Z,1680031329.123 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:22:16.417Z,1680031336.417 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:22:16.735Z,1680031336.735 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230328T174318/Courier0051.lzma 2023-03-28T19:22:17.738Z,1680031337.738 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0051.lzma.bak 2023-03-28T19:22:17.738Z,1680031337.738 [DataOverHttps](INFO): SBD MOMSN=17732567 2023-03-28T19:22:25.327Z,1680031345.327 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:22:35.015Z,1680031355.015 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20230328T174318/Express0052.lzma 2023-03-28T19:22:36.019Z,1680031356.019 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0052.lzma.bak 2023-03-28T19:22:36.019Z,1680031356.019 [DataOverHttps](INFO): SBD MOMSN=17732571 2023-03-28T19:22:37.425Z,1680031357.425 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:22:37.425Z,1680031357.425 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:22:37.425Z,1680031357.425 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:22:40.249Z,1680031360.249 [DVL_micro](ERROR): only read 3 of 4 data items 2023-03-28T19:22:40.249Z,1680031360.249 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+99999.99,+9999.99 2023-03-28T19:23:44.468Z,1680031424.468 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-03-28T19:23:44.468Z,1680031424.468 [DropWeight] Hardware Fault, FailCount= 1 2023-03-28T19:23:44.468Z,1680031424.468 [DropWeight](ERROR): Hardware Fault 2023-03-28T19:23:44.540Z,1680031424.540 [CBIT](INFO): Critical error at 20230328T192344 2023-03-28T19:23:44.543Z,1680031424.543 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-03-28T19:23:44.543Z,1680031424.543 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-03-28T19:23:44.907Z,1680031424.907 [CBIT](INFO): Critical error at 20230328T192344 2023-03-28T19:24:20.048Z,1680031460.048 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:24:28.542Z,1680031468.542 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T19:24:39.447Z,1680031479.447 [NAL9602](INFO): SBD MO Status=0, MOMSN=47538, MT Status=0, MTMSN=0 2023-03-28T19:24:39.447Z,1680031479.447 [NAL9602](INFO): No messages in MT queue 2023-03-28T19:25:10.124Z,1680031510.124 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T19:25:10.146Z,1680031510.146 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:25:35.585Z,1680031535.585 [DVL_micro](ERROR): only read 3 of 4 data items 2023-03-28T19:25:35.586Z,1680031535.586 [DVL_micro](ERROR): Failed to parse: :RD,+9999.999,+9999.99,+9999.99 2023-03-28T19:27:38.007Z,1680031658.007 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:27:38.007Z,1680031658.007 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:27:38.007Z,1680031658.007 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:27:38.007Z,1680031658.007 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:27:38.399Z,1680031658.399 [Default:CheckIn:D] Stopped 2023-03-28T19:27:38.399Z,1680031658.399 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:27:38.805Z,1680031658.805 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.658358 min 2023-03-28T19:27:38.805Z,1680031658.805 [Default:CheckIn:E] Stopped 2023-03-28T19:27:38.805Z,1680031658.805 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:27:38.805Z,1680031658.805 [Default:CheckIn] Stopped 2023-03-28T19:27:38.806Z,1680031658.806 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:27:38.806Z,1680031658.806 [Default:CheckIn](INFO): Running loop #25 2023-03-28T19:27:38.806Z,1680031658.806 [Default:CheckIn] Running Loop=25 2023-03-28T19:27:38.806Z,1680031658.806 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:27:38.806Z,1680031658.806 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:27:40.806Z,1680031660.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192740.00,A,3648.17561,N,12147.28448,W,0.039,0.00,280323,,,D*7C 2023-03-28T19:27:40.809Z,1680031660.809 [NAL9602](INFO): GPS fix at 20230328T192740: (36.802927, -121.788075) 2023-03-28T19:27:40.843Z,1680031660.843 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:27:40.843Z,1680031660.843 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:27:48.951Z,1680031668.951 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20230328T174318/Courier0054.lzma 2023-03-28T19:27:49.954Z,1680031669.954 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0054.lzma.bak 2023-03-28T19:27:49.954Z,1680031669.954 [DataOverHttps](INFO): SBD MOMSN=17732581 2023-03-28T19:27:55.052Z,1680031675.052 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:28:03.501Z,1680031683.501 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:28:05.879Z,1680031685.879 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230328T174318/Express0055.lzma 2023-03-28T19:28:06.882Z,1680031686.882 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0055.lzma.bak 2023-03-28T19:28:06.882Z,1680031686.882 [DataOverHttps](INFO): SBD MOMSN=17732586 2023-03-28T19:28:08.302Z,1680031688.302 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:28:08.302Z,1680031688.302 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:28:08.302Z,1680031688.302 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:29:02.870Z,1680031742.870 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:29:11.348Z,1680031751.348 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:31:38.799Z,1680031898.799 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-03-28T19:31:38.802Z,1680031898.802 [BPC1](INFO): Received data from all battery sticks. 2023-03-28T19:32:12.759Z,1680031932.759 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:32:21.251Z,1680031941.251 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:32:43.017Z,1680031963.017 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-03-28T19:33:08.881Z,1680031988.881 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:33:08.881Z,1680031988.881 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:33:08.881Z,1680031988.881 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:33:08.881Z,1680031988.881 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:33:09.285Z,1680031989.285 [Default:CheckIn:D] Stopped 2023-03-28T19:33:09.285Z,1680031989.285 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:33:09.695Z,1680031989.695 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 139.173128 min 2023-03-28T19:33:09.695Z,1680031989.695 [Default:CheckIn:E] Stopped 2023-03-28T19:33:09.695Z,1680031989.695 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:33:09.695Z,1680031989.695 [Default:CheckIn] Stopped 2023-03-28T19:33:09.696Z,1680031989.696 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:33:09.696Z,1680031989.696 [Default:CheckIn](INFO): Running loop #26 2023-03-28T19:33:09.696Z,1680031989.696 [Default:CheckIn] Running Loop=26 2023-03-28T19:33:09.696Z,1680031989.696 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:33:09.696Z,1680031989.696 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:33:11.702Z,1680031991.702 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193311.00,A,3648.17511,N,12147.28477,W,0.000,0.00,280323,,,D*7C 2023-03-28T19:33:11.714Z,1680031991.714 [NAL9602](INFO): GPS fix at 20230328T193311: (36.802918, -121.788079) 2023-03-28T19:33:11.728Z,1680031991.728 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:33:11.728Z,1680031991.728 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:33:19.299Z,1680031999.299 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230328T174318/Courier0057.lzma 2023-03-28T19:33:20.302Z,1680032000.302 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0057.lzma.bak 2023-03-28T19:33:20.302Z,1680032000.302 [DataOverHttps](INFO): SBD MOMSN=17732620 2023-03-28T19:33:38.552Z,1680032018.552 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20230328T174318/Express0058.lzma 2023-03-28T19:33:39.554Z,1680032019.554 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0058.lzma.bak 2023-03-28T19:33:39.554Z,1680032019.554 [DataOverHttps](INFO): SBD MOMSN=17732971 2023-03-28T19:33:41.259Z,1680032021.259 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:33:41.259Z,1680032021.259 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:33:41.259Z,1680032021.259 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:33:44.482Z,1680032024.482 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T19:34:10.812Z,1680032050.812 [DVL_micro](ERROR): DVL uart error: serial timeout 2023-03-28T19:34:10.812Z,1680032050.812 [DVL_micro] Communications Fault, FailCount= 1 2023-03-28T19:34:10.812Z,1680032050.812 [DVL_micro](ERROR): Communications Fault 2023-03-28T19:34:10.812Z,1680032050.812 [DVL_micro](ERROR): Failed to parse: 2023-03-28T19:34:10.869Z,1680032050.869 [CBIT](ERROR): Communications Fault in component: DVL_micro 2023-03-28T19:34:11.220Z,1680032051.220 [DVL_micro](INFO): Powering down 2023-03-28T19:34:11.970Z,1680032051.970 [CBIT](INFO): Clearing failed state for component DVL_micro 2023-03-28T19:34:11.970Z,1680032051.970 [DVL_micro] No Fault, FailCount= 1 2023-03-28T19:38:41.856Z,1680032321.856 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:38:41.856Z,1680032321.856 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:38:41.856Z,1680032321.856 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:38:41.857Z,1680032321.857 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:38:42.242Z,1680032322.242 [Default:CheckIn:D] Stopped 2023-03-28T19:38:42.242Z,1680032322.242 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:38:42.643Z,1680032322.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.722412 min 2023-03-28T19:38:42.643Z,1680032322.643 [Default:CheckIn:E] Stopped 2023-03-28T19:38:42.643Z,1680032322.643 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:38:42.643Z,1680032322.643 [Default:CheckIn] Stopped 2023-03-28T19:38:42.643Z,1680032322.643 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:38:42.644Z,1680032322.644 [Default:CheckIn](INFO): Running loop #27 2023-03-28T19:38:42.644Z,1680032322.644 [Default:CheckIn] Running Loop=27 2023-03-28T19:38:42.644Z,1680032322.644 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:38:42.644Z,1680032322.644 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:38:44.661Z,1680032324.661 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193844.00,A,3648.17581,N,12147.28421,W,0.039,0.00,280323,,,D*77 2023-03-28T19:38:44.663Z,1680032324.663 [NAL9602](INFO): GPS fix at 20230328T193844: (36.802930, -121.788070) 2023-03-28T19:38:44.674Z,1680032324.674 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:38:44.674Z,1680032324.674 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:38:44.683Z,1680032324.683 [CBIT](INFO): Clearing failed state for component DropWeight 2023-03-28T19:38:44.684Z,1680032324.684 [DropWeight] No Fault, FailCount= 1 2023-03-28T19:38:52.199Z,1680032332.199 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230328T174318/Courier0060.lzma 2023-03-28T19:38:53.202Z,1680032333.202 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0060.lzma.bak 2023-03-28T19:38:53.202Z,1680032333.202 [DataOverHttps](INFO): SBD MOMSN=17732983 2023-03-28T19:39:09.302Z,1680032349.302 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230328T174318/Express0061.lzma 2023-03-28T19:39:09.351Z,1680032349.351 [DVL_micro](ERROR): only read 3 of 4 data items 2023-03-28T19:39:09.351Z,1680032349.351 [DVL_micro](ERROR): Failed to parse: :BI,02897,-00165,+00000,I 2023-03-28T19:39:10.066Z,1680032350.066 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0061.lzma.bak 2023-03-28T19:39:10.066Z,1680032350.066 [DataOverHttps](INFO): SBD MOMSN=17732986 2023-03-28T19:39:11.334Z,1680032351.334 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:39:11.334Z,1680032351.334 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:39:11.334Z,1680032351.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:39:15.373Z,1680032355.373 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-03-28T19:39:15.456Z,1680032355.456 [NAL9602](ERROR): received: +CSQ:0 OK538, 0, 0, 0, 0 OK 2023-03-28T19:42:17.642Z,1680032537.642 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:42:27.334Z,1680032547.334 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:42:35.815Z,1680032555.815 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:42:44.694Z,1680032564.694 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:42:53.181Z,1680032573.181 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T19:43:03.626Z,1680032583.626 [NAL9602](INFO): SBD MO Status=0, MOMSN=47539, MT Status=0, MTMSN=0 2023-03-28T19:43:03.626Z,1680032583.626 [NAL9602](INFO): No messages in MT queue 2023-03-28T19:43:34.325Z,1680032614.325 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T19:43:34.397Z,1680032614.397 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:44:11.917Z,1680032651.917 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:44:11.917Z,1680032651.917 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:44:11.917Z,1680032651.917 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:44:11.917Z,1680032651.917 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:44:12.330Z,1680032652.330 [Default:CheckIn:D] Stopped 2023-03-28T19:44:12.330Z,1680032652.330 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:44:12.722Z,1680032652.722 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 150.223877 min 2023-03-28T19:44:12.722Z,1680032652.722 [Default:CheckIn:E] Stopped 2023-03-28T19:44:12.722Z,1680032652.722 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:44:12.722Z,1680032652.722 [Default:CheckIn] Stopped 2023-03-28T19:44:12.722Z,1680032652.722 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:44:12.723Z,1680032652.723 [Default:CheckIn](INFO): Running loop #28 2023-03-28T19:44:12.723Z,1680032652.723 [Default:CheckIn] Running Loop=28 2023-03-28T19:44:12.723Z,1680032652.723 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:44:12.723Z,1680032652.723 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:44:14.731Z,1680032654.731 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194414.00,A,3648.17686,N,12147.28459,W,0.019,0.00,280323,,,D*70 2023-03-28T19:44:14.733Z,1680032654.733 [NAL9602](INFO): GPS fix at 20230328T194414: (36.802948, -121.788077) 2023-03-28T19:44:14.743Z,1680032654.743 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:44:14.743Z,1680032654.743 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:44:22.079Z,1680032662.079 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230328T174318/Courier0063.lzma 2023-03-28T19:44:23.082Z,1680032663.082 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0063.lzma.bak 2023-03-28T19:44:23.082Z,1680032663.082 [DataOverHttps](INFO): SBD MOMSN=17733014 2023-03-28T19:44:28.139Z,1680032668.139 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:44:41.996Z,1680032681.996 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230328T174318/Express0064.lzma 2023-03-28T19:44:42.998Z,1680032682.998 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0064.lzma.bak 2023-03-28T19:44:42.998Z,1680032682.998 [DataOverHttps](INFO): SBD MOMSN=17733017 2023-03-28T19:44:44.694Z,1680032684.694 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:44:44.694Z,1680032684.694 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:44:44.694Z,1680032684.694 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:44:45.130Z,1680032685.130 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T19:44:55.594Z,1680032695.594 [NAL9602](INFO): SBD MO Status=0, MOMSN=47540, MT Status=0, MTMSN=0 2023-03-28T19:44:55.594Z,1680032695.594 [NAL9602](INFO): No messages in MT queue 2023-03-28T19:45:26.298Z,1680032726.298 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T19:45:26.320Z,1680032726.320 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:49:45.270Z,1680032985.270 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:49:45.270Z,1680032985.270 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:49:45.270Z,1680032985.270 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:49:45.270Z,1680032985.270 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:49:45.679Z,1680032985.679 [Default:CheckIn:D] Stopped 2023-03-28T19:49:45.679Z,1680032985.679 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:49:46.084Z,1680032986.084 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.779687 min 2023-03-28T19:49:46.084Z,1680032986.084 [Default:CheckIn:E] Stopped 2023-03-28T19:49:46.084Z,1680032986.084 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:49:46.084Z,1680032986.084 [Default:CheckIn] Stopped 2023-03-28T19:49:46.084Z,1680032986.084 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:49:46.084Z,1680032986.084 [Default:CheckIn](INFO): Running loop #29 2023-03-28T19:49:46.085Z,1680032986.085 [Default:CheckIn] Running Loop=29 2023-03-28T19:49:46.085Z,1680032986.085 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:49:46.085Z,1680032986.085 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:49:46.508Z,1680032986.508 [DVL_micro](ERROR): only read 0 of 4 data items 2023-03-28T19:49:46.508Z,1680032986.508 [DVL_micro](ERROR): Failed to parse: :RD,,+9999.99,+9999.99,+9999.99 2023-03-28T19:49:48.090Z,1680032988.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194947.00,A,3648.17607,N,12147.28441,W,0.058,0.00,280323,,,D*7E 2023-03-28T19:49:48.093Z,1680032988.093 [NAL9602](INFO): GPS fix at 20230328T194947: (36.802934, -121.788073) 2023-03-28T19:49:48.102Z,1680032988.102 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:49:48.102Z,1680032988.102 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:49:55.819Z,1680032995.819 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230328T174318/Courier0066.lzma 2023-03-28T19:49:56.823Z,1680032996.823 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0066.lzma.bak 2023-03-28T19:49:56.823Z,1680032996.823 [DataOverHttps](INFO): SBD MOMSN=17733410 2023-03-28T19:50:13.215Z,1680033013.215 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230328T174318/Express0067.lzma 2023-03-28T19:50:14.218Z,1680033014.218 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0067.lzma.bak 2023-03-28T19:50:14.218Z,1680033014.218 [DataOverHttps](INFO): SBD MOMSN=17733413 2023-03-28T19:50:15.575Z,1680033015.575 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:50:15.575Z,1680033015.575 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:50:15.575Z,1680033015.575 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:51:23.107Z,1680033083.107 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:51:31.554Z,1680033091.554 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T19:51:50.115Z,1680033110.115 [NAL9602](INFO): SBD MO Status=2, MOMSN=47541, MT Status=2, MTMSN=0 2023-03-28T19:51:50.115Z,1680033110.115 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T19:51:54.182Z,1680033114.182 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:51:57.418Z,1680033117.418 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T19:52:17.574Z,1680033137.574 [NAL9602](INFO): SBD MO Status=0, MOMSN=47541, MT Status=0, MTMSN=0 2023-03-28T19:52:17.574Z,1680033137.574 [NAL9602](INFO): No messages in MT queue 2023-03-28T19:52:44.237Z,1680033164.237 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-03-28T19:52:44.237Z,1680033164.237 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+0.0,1489.0,000 2023-03-28T19:52:48.272Z,1680033168.272 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T19:52:48.294Z,1680033168.294 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:55:16.150Z,1680033316.150 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T19:55:16.150Z,1680033316.150 [Default:CheckIn:C.Wait] Stopped 2023-03-28T19:55:16.150Z,1680033316.150 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T19:55:16.150Z,1680033316.150 [Default:CheckIn:D] Running Loop=1 2023-03-28T19:55:16.576Z,1680033316.576 [Default:CheckIn:D] Stopped 2023-03-28T19:55:16.577Z,1680033316.577 [Default:CheckIn:E] Running Loop=1 2023-03-28T19:55:16.960Z,1680033316.960 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 161.294645 min 2023-03-28T19:55:16.960Z,1680033316.960 [Default:CheckIn:E] Stopped 2023-03-28T19:55:16.960Z,1680033316.960 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T19:55:16.960Z,1680033316.960 [Default:CheckIn] Stopped 2023-03-28T19:55:16.961Z,1680033316.961 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T19:55:16.961Z,1680033316.961 [Default:CheckIn](INFO): Running loop #30 2023-03-28T19:55:16.961Z,1680033316.961 [Default:CheckIn] Running Loop=30 2023-03-28T19:55:16.961Z,1680033316.961 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T19:55:16.961Z,1680033316.961 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T19:55:18.966Z,1680033318.966 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195518.00,A,3648.17591,N,12147.28479,W,0.019,0.00,280323,,,D*7B 2023-03-28T19:55:18.969Z,1680033318.969 [NAL9602](INFO): GPS fix at 20230328T195518: (36.802932, -121.788080) 2023-03-28T19:55:18.995Z,1680033318.995 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T19:55:18.995Z,1680033318.995 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T19:55:26.727Z,1680033326.727 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0069.lzma 2023-03-28T19:55:27.730Z,1680033327.730 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0069.lzma.bak 2023-03-28T19:55:27.730Z,1680033327.730 [DataOverHttps](INFO): SBD MOMSN=17733425 2023-03-28T19:55:29.543Z,1680033329.543 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:55:31.895Z,1680033331.895 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-03-28T19:55:31.895Z,1680033331.895 [DVL_micro](ERROR): Failed to parse: :TS,000000005.0,+09.7,0000.0,1489.0,000 2023-03-28T19:55:38.500Z,1680033338.500 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:55:47.711Z,1680033347.711 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20230328T174318/Express0070.lzma 2023-03-28T19:55:48.714Z,1680033348.714 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0070.lzma.bak 2023-03-28T19:55:48.714Z,1680033348.714 [DataOverHttps](INFO): SBD MOMSN=17733453 2023-03-28T19:55:50.140Z,1680033350.140 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T19:55:50.140Z,1680033350.140 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T19:55:50.140Z,1680033350.140 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T19:55:59.451Z,1680033359.451 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:56:06.722Z,1680033366.722 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:58:22.040Z,1680033502.040 [DVL_micro](ERROR): only read 3 of 4 data items 2023-03-28T19:58:22.040Z,1680033502.040 [DVL_micro](ERROR): Failed to parse: :BI,+02713,+04496,-02030,+ 2023-03-28T19:58:43.901Z,1680033523.901 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:58:52.406Z,1680033532.406 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:59:18.238Z,1680033558.238 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:59:27.138Z,1680033567.138 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T19:59:44.193Z,1680033584.193 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T19:59:52.994Z,1680033592.994 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T20:00:21.216Z,1680033621.216 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-03-28T20:00:50.717Z,1680033650.717 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T20:00:50.717Z,1680033650.717 [Default:CheckIn:C.Wait] Stopped 2023-03-28T20:00:50.717Z,1680033650.717 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T20:00:50.718Z,1680033650.718 [Default:CheckIn:D] Running Loop=1 2023-03-28T20:00:51.119Z,1680033651.119 [Default:CheckIn:D] Stopped 2023-03-28T20:00:51.119Z,1680033651.119 [Default:CheckIn:E] Running Loop=1 2023-03-28T20:00:51.533Z,1680033651.533 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 166.870345 min 2023-03-28T20:00:51.533Z,1680033651.533 [Default:CheckIn:E] Stopped 2023-03-28T20:00:51.533Z,1680033651.533 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T20:00:51.533Z,1680033651.533 [Default:CheckIn] Stopped 2023-03-28T20:00:51.533Z,1680033651.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T20:00:51.533Z,1680033651.533 [Default:CheckIn](INFO): Running loop #31 2023-03-28T20:00:51.533Z,1680033651.533 [Default:CheckIn] Running Loop=31 2023-03-28T20:00:51.533Z,1680033651.533 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T20:00:51.533Z,1680033651.533 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T20:00:51.931Z,1680033651.931 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T20:00:53.538Z,1680033653.538 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200053.00,A,3648.17668,N,12147.28481,W,0.039,0.00,280323,,,D*7E 2023-03-28T20:00:53.541Z,1680033653.541 [NAL9602](INFO): GPS fix at 20230328T200053: (36.802945, -121.788080) 2023-03-28T20:00:53.566Z,1680033653.566 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T20:00:53.566Z,1680033653.566 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T20:01:01.507Z,1680033661.507 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0072.lzma 2023-03-28T20:01:02.510Z,1680033662.510 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0072.lzma.bak 2023-03-28T20:01:02.510Z,1680033662.510 [DataOverHttps](INFO): SBD MOMSN=17733808 2023-03-28T20:01:18.608Z,1680033678.608 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230328T174318/Express0073.lzma 2023-03-28T20:01:19.610Z,1680033679.610 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0073.lzma.bak 2023-03-28T20:01:19.610Z,1680033679.610 [DataOverHttps](INFO): SBD MOMSN=17733811 2023-03-28T20:01:21.028Z,1680033681.028 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T20:01:21.028Z,1680033681.028 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T20:01:21.029Z,1680033681.029 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T20:01:24.269Z,1680033684.269 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-03-28T20:01:24.344Z,1680033684.344 [NAL9602](ERROR): received: +CSQ:0 OK541, 0, 0, 0, 0 OK 2023-03-28T20:01:32.023Z,1680033692.023 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T20:01:37.255Z,1680033697.255 [Reporter](INFO): NAL9602.sigQuality 4 count 2023-03-28T20:01:42.852Z,1680033702.852 [DVL_micro](ERROR): Failed to parse: :SA,-03.10,+00.74,017.7 2023-03-28T20:01:55.771Z,1680033715.771 [NAL9602](INFO): SBD MO Status=2, MOMSN=47542, MT Status=2, MTMSN=0 2023-03-28T20:01:55.771Z,1680033715.771 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-28T20:02:00.261Z,1680033720.261 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T20:02:11.963Z,1680033731.963 [Reporter](INFO): NAL9602.sigQuality 4 count 2023-03-28T20:02:22.447Z,1680033742.447 [NAL9602](INFO): SBD MO Status=0, MOMSN=47542, MT Status=0, MTMSN=0 2023-03-28T20:02:22.447Z,1680033742.447 [NAL9602](INFO): No messages in MT queue 2023-03-28T20:02:53.142Z,1680033773.142 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T20:02:53.163Z,1680033773.163 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T20:04:05.064Z,1680033845.064 [DVL_micro](ERROR): Failed to parse: :SA,-03.09,+00.70,017.6 2023-03-28T20:05:51.710Z,1680033951.710 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-03-28T20:05:51.710Z,1680033951.710 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35,0000.0,1489.0,000 2023-03-28T20:06:21.622Z,1680033981.622 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T20:06:21.622Z,1680033981.622 [Default:CheckIn:C.Wait] Stopped 2023-03-28T20:06:21.622Z,1680033981.622 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T20:06:21.622Z,1680033981.622 [Default:CheckIn:D] Running Loop=1 2023-03-28T20:06:22.044Z,1680033982.044 [Default:CheckIn:D] Stopped 2023-03-28T20:06:22.044Z,1680033982.044 [Default:CheckIn:E] Running Loop=1 2023-03-28T20:06:22.418Z,1680033982.418 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 172.385775 min 2023-03-28T20:06:22.418Z,1680033982.418 [Default:CheckIn:E] Stopped 2023-03-28T20:06:22.418Z,1680033982.418 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T20:06:22.418Z,1680033982.418 [Default:CheckIn] Stopped 2023-03-28T20:06:22.418Z,1680033982.418 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T20:06:22.419Z,1680033982.419 [Default:CheckIn](INFO): Running loop #32 2023-03-28T20:06:22.419Z,1680033982.419 [Default:CheckIn] Running Loop=32 2023-03-28T20:06:22.419Z,1680033982.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T20:06:22.419Z,1680033982.419 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T20:06:24.452Z,1680033984.452 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200623.00,A,3648.17729,N,12147.28483,W,0.019,0.00,280323,,,D*7B 2023-03-28T20:06:24.455Z,1680033984.455 [NAL9602](INFO): GPS fix at 20230328T200623: (36.802955, -121.788081) 2023-03-28T20:06:24.465Z,1680033984.465 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T20:06:24.465Z,1680033984.465 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T20:06:31.875Z,1680033991.875 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T174318/Courier0075.lzma 2023-03-28T20:06:32.878Z,1680033992.878 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0075.lzma.bak 2023-03-28T20:06:32.878Z,1680033992.878 [DataOverHttps](INFO): SBD MOMSN=17733858 2023-03-28T20:06:51.799Z,1680034011.799 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230328T174318/Express0076.lzma 2023-03-28T20:06:52.802Z,1680034012.802 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0076.lzma.bak 2023-03-28T20:06:52.802Z,1680034012.802 [DataOverHttps](INFO): SBD MOMSN=17733861 2023-03-28T20:06:54.008Z,1680034014.008 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T20:06:54.008Z,1680034014.008 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T20:06:54.008Z,1680034014.008 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T20:07:45.330Z,1680034065.330 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T20:07:54.207Z,1680034074.207 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T20:08:28.567Z,1680034108.567 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T20:08:37.443Z,1680034117.443 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T20:08:45.889Z,1680034125.889 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-03-28T20:08:45.889Z,1680034125.889 [DropWeight] Hardware Fault, FailCount= 1 2023-03-28T20:08:45.889Z,1680034125.889 [DropWeight](ERROR): Hardware Fault 2023-03-28T20:08:45.907Z,1680034125.907 [CBIT](INFO): Critical error at 20230328T200845 2023-03-28T20:08:45.910Z,1680034125.910 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-03-28T20:08:45.910Z,1680034125.910 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-03-28T20:08:46.323Z,1680034126.323 [CBIT](INFO): Critical error at 20230328T200845 2023-03-28T20:08:55.982Z,1680034135.982 [NAL9602](INFO): SBD MO Status=0, MOMSN=47543, MT Status=0, MTMSN=0 2023-03-28T20:08:55.982Z,1680034135.982 [NAL9602](INFO): No messages in MT queue 2023-03-28T20:09:26.708Z,1680034166.708 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T20:09:26.758Z,1680034166.758 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T20:11:36.871Z,1680034296.871 [CBIT](IMPORTANT): Beginning ground fault scan 2023-03-28T20:11:47.836Z,1680034307.836 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.057240 CHAN A1 (24V): 0.111911 CHAN A2 (12V): 0.033213 CHAN A3 (5V): 0.003138 CHAN B0 (3.3V): 0.000040 CHAN B1 (3.15aV): -0.000051 CHAN B2 (3.15bV): 0.000239 CHAN B3 (GND): -0.009388 OPEN: 0.007460 Full Scale: +/- 1 mA 2023-03-28T20:11:54.626Z,1680034314.626 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T20:11:54.626Z,1680034314.626 [Default:CheckIn:C.Wait] Stopped 2023-03-28T20:11:54.626Z,1680034314.626 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T20:11:54.626Z,1680034314.626 [Default:CheckIn:D] Running Loop=1 2023-03-28T20:11:55.011Z,1680034315.011 [Default:CheckIn:D] Stopped 2023-03-28T20:11:55.011Z,1680034315.011 [Default:CheckIn:E] Running Loop=1 2023-03-28T20:11:55.418Z,1680034315.418 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 177.935221 min 2023-03-28T20:11:55.418Z,1680034315.418 [Default:CheckIn:E] Stopped 2023-03-28T20:11:55.418Z,1680034315.418 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T20:11:55.418Z,1680034315.418 [Default:CheckIn] Stopped 2023-03-28T20:11:55.419Z,1680034315.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T20:11:55.419Z,1680034315.419 [Default:CheckIn](INFO): Running loop #33 2023-03-28T20:11:55.419Z,1680034315.419 [Default:CheckIn] Running Loop=33 2023-03-28T20:11:55.419Z,1680034315.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T20:11:55.419Z,1680034315.419 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T20:11:57.437Z,1680034317.437 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201156.00,A,3648.17727,N,12147.28442,W,0.019,0.00,280323,,,D*7C 2023-03-28T20:11:57.439Z,1680034317.439 [NAL9602](INFO): GPS fix at 20230328T201156: (36.802954, -121.788074) 2023-03-28T20:11:57.449Z,1680034317.449 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T20:11:57.449Z,1680034317.449 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T20:12:04.983Z,1680034324.983 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20230328T174318/Courier0078.lzma 2023-03-28T20:12:05.986Z,1680034325.986 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Courier0078.lzma.bak 2023-03-28T20:12:05.986Z,1680034325.986 [DataOverHttps](INFO): SBD MOMSN=17734251 2023-03-28T20:12:07.200Z,1680034327.200 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-28T20:12:08.383Z,1680034328.383 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T20:12:22.352Z,1680034342.352 [DataOverHttps](INFO): Sending 308 bytes from file Logs/20230328T174318/Express0079.lzma 2023-03-28T20:12:23.354Z,1680034343.354 [DataOverHttps](INFO): Moved sent file to Logs/20230328T174318/Express0079.lzma.bak 2023-03-28T20:12:23.354Z,1680034343.354 [DataOverHttps](INFO): SBD MOMSN=17734261 2023-03-28T20:12:24.905Z,1680034344.905 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T20:12:24.905Z,1680034344.905 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T20:12:24.905Z,1680034344.905 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T20:12:27.327Z,1680034347.327 [NAL9602](INFO): SBD MO Status=0, MOMSN=47544, MT Status=0, MTMSN=0 2023-03-28T20:12:27.327Z,1680034347.327 [NAL9602](INFO): No messages in MT queue 2023-03-28T20:12:58.068Z,1680034378.068 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T20:12:58.152Z,1680034378.152 [Reporter](INFO): NAL9602.sigQuality 0 count