2022-03-17T19:54:07.373Z,1647546847.373 [DataOverHttps](INFO): Received command: restart logs 2022-03-17T19:54:07.414Z,1647546847.414 [CommandExec](IMPORTANT): got command restart logs 2022-03-17T19:54:15.184Z,1647546855.184 [NAL9602](INFO): SBD MO Status=0, MOMSN=32340, MT Status=0, MTMSN=0 2022-03-17T19:54:15.184Z,1647546855.184 [NAL9602](INFO): No messages in MT queue 2022-03-17T19:54:18.833Z,1647546858.833 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220317T163019/Express0040.lzma 2022-03-17T19:54:19.834Z,1647546859.834 [DataOverHttps](INFO): Moved sent file to Logs/20220317T163019/Express0040.lzma.bak 2022-03-17T19:54:19.835Z,1647546859.835 [DataOverHttps](INFO): SBD MOMSN=16577127 2022-03-17T19:54:36.237Z,1647546876.237 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220317T163019/Courier0042.lzma 2022-03-17T19:54:36.999Z,1647546876.999 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-03-17T19:54:37.002Z,1647546877.002 [BPC1](INFO): Received data from all battery sticks. 2022-03-17T19:54:37.239Z,1647546877.239 [DataOverHttps](INFO): Moved sent file to Logs/20220317T163019/Courier0042.lzma.bak 2022-03-17T19:54:37.239Z,1647546877.239 [DataOverHttps](INFO): SBD MOMSN=16577130 2022-03-17T19:54:45.894Z,1647546885.894 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T19:54:56.580Z,1647546896.580 [DataOverHttps](INFO): Sending 109 bytes from file Logs/20220317T163019/Express0043.lzma 2022-03-17T19:54:57.583Z,1647546897.583 [DataOverHttps](INFO): Moved sent file to Logs/20220317T163019/Express0043.lzma.bak 2022-03-17T19:54:57.583Z,1647546897.583 [DataOverHttps](INFO): SBD MOMSN=16577132 2022-03-17T19:54:58.886Z,1647546898.886 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T19:54:58.887Z,1647546898.887 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T19:54:58.887Z,1647546898.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T19:59:02.957Z,1647547142.957 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-03-17T19:59:34.081Z,1647547174.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-03-17T19:59:59.458Z,1647547199.458 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T19:59:59.458Z,1647547199.458 [Default:CheckIn:C.Wait] Stopped 2022-03-17T19:59:59.458Z,1647547199.458 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T19:59:59.458Z,1647547199.458 [Default:CheckIn:D] Running Loop=1 2022-03-17T19:59:59.876Z,1647547199.876 [Default:CheckIn:D] Stopped 2022-03-17T19:59:59.876Z,1647547199.876 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:00:00.263Z,1647547200.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.357629 min 2022-03-17T20:00:00.264Z,1647547200.264 [Default:CheckIn:E] Stopped 2022-03-17T20:00:00.264Z,1647547200.264 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:00:00.264Z,1647547200.264 [Default:CheckIn] Stopped 2022-03-17T20:00:00.264Z,1647547200.264 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:00:00.264Z,1647547200.264 [Default:CheckIn](INFO): Running loop #6 2022-03-17T20:00:00.264Z,1647547200.264 [Default:CheckIn] Running Loop=6 2022-03-17T20:00:00.265Z,1647547200.265 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:00:00.265Z,1647547200.265 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:00:02.276Z,1647547202.276 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200004.00,A,3648.20834,N,12148.11499,W,12.538,83.23,170322,,,D*73 2022-03-17T20:00:02.279Z,1647547202.279 [NAL9602](INFO): GPS fix at 20220317T200004: (36.803472, -121.801917) 2022-03-17T20:00:02.289Z,1647547202.289 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:00:02.290Z,1647547202.290 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:00:05.205Z,1647547205.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-03-17T20:00:17.232Z,1647547217.232 [NAL9602](INFO): SBD MO Status=0, MOMSN=32341, MT Status=0, MTMSN=0 2022-03-17T20:00:17.232Z,1647547217.232 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:00:36.329Z,1647547236.329 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-03-17T20:00:47.927Z,1647547247.927 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:01:07.453Z,1647547267.453 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-03-17T20:01:10.465Z,1647547270.465 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.775635 2022-03-17T20:01:27.571Z,1647547287.571 [NAL9602](INFO): SBD MO Status=1, MOMSN=32342, MT Status=0, MTMSN=0 2022-03-17T20:01:27.621Z,1647547287.621 [NAL9602](INFO): Sent 309 bytes from file Logs/20220317T195407/Courier0003.lzma 2022-03-17T20:01:27.622Z,1647547287.622 [NAL9602](INFO): Packets left to send: 0 2022-03-17T20:01:41.589Z,1647547301.589 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-03-17T20:01:48.643Z,1647547308.643 [NAL9602](INFO): SBD MO Status=1, MOMSN=32343, MT Status=0, MTMSN=0 2022-03-17T20:01:48.705Z,1647547308.705 [NAL9602](INFO): Sent 332 bytes from file Logs/20220317T195407/Express0004.lzma 2022-03-17T20:01:48.705Z,1647547308.705 [NAL9602](INFO): Packets left to send: 1 2022-03-17T20:01:50.101Z,1647547310.101 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003169 2022-03-17T20:01:57.577Z,1647547317.577 [DataOverHttps](INFO): Sending 401 bytes from file Logs/20220317T195407/Express0004.lzma 2022-03-17T20:01:58.578Z,1647547318.578 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0004.lzma.bak 2022-03-17T20:01:58.579Z,1647547318.579 [DataOverHttps](INFO): SBD MOMSN=16577147 2022-03-17T20:02:00.006Z,1647547320.006 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:02:00.006Z,1647547320.006 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:02:00.006Z,1647547320.006 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:02:03.247Z,1647547323.247 [NAL9602](INFO): SBD MO Status=2, MOMSN=32344, MT Status=2, MTMSN=0 2022-03-17T20:02:03.248Z,1647547323.248 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T20:02:33.950Z,1647547353.950 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:07:00.604Z,1647547620.604 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T20:07:00.604Z,1647547620.604 [Default:CheckIn:C.Wait] Stopped 2022-03-17T20:07:00.604Z,1647547620.604 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T20:07:00.605Z,1647547620.605 [Default:CheckIn:D] Running Loop=1 2022-03-17T20:07:01.006Z,1647547621.006 [Default:CheckIn:D] Stopped 2022-03-17T20:07:01.006Z,1647547621.006 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:07:01.423Z,1647547621.423 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.376461 min 2022-03-17T20:07:01.423Z,1647547621.423 [Default:CheckIn:E] Stopped 2022-03-17T20:07:01.423Z,1647547621.423 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:07:01.423Z,1647547621.423 [Default:CheckIn] Stopped 2022-03-17T20:07:01.423Z,1647547621.423 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:07:01.423Z,1647547621.423 [Default:CheckIn](INFO): Running loop #7 2022-03-17T20:07:01.423Z,1647547621.423 [Default:CheckIn] Running Loop=7 2022-03-17T20:07:01.424Z,1647547621.424 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:07:01.424Z,1647547621.424 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:07:03.420Z,1647547623.420 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200705.00,A,3648.22547,N,12147.82981,W,0.369,275.09,170322,,,D*7D 2022-03-17T20:07:03.423Z,1647547623.423 [NAL9602](INFO): GPS fix at 20220317T200705: (36.803758, -121.797163) 2022-03-17T20:07:03.434Z,1647547623.434 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:07:03.434Z,1647547623.434 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:07:11.248Z,1647547631.248 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0006.lzma 2022-03-17T20:07:12.250Z,1647547632.250 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0006.lzma.bak 2022-03-17T20:07:12.251Z,1647547632.251 [DataOverHttps](INFO): SBD MOMSN=16577170 2022-03-17T20:07:22.411Z,1647547642.411 [NAL9602](INFO): SBD MO Status=1, MOMSN=32344, MT Status=0, MTMSN=0 2022-03-17T20:07:22.412Z,1647547642.412 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:07:28.192Z,1647547648.192 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220317T195407/Express0007.lzma 2022-03-17T20:07:29.194Z,1647547649.194 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0007.lzma.bak 2022-03-17T20:07:29.195Z,1647547649.195 [DataOverHttps](INFO): SBD MOMSN=16577173 2022-03-17T20:07:30.506Z,1647547650.506 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:07:30.506Z,1647547650.506 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:07:30.506Z,1647547650.506 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:07:53.110Z,1647547673.110 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:12:31.112Z,1647547951.112 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T20:12:31.112Z,1647547951.112 [Default:CheckIn:C.Wait] Stopped 2022-03-17T20:12:31.112Z,1647547951.112 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T20:12:31.113Z,1647547951.113 [Default:CheckIn:D] Running Loop=1 2022-03-17T20:12:31.497Z,1647547951.497 [Default:CheckIn:D] Stopped 2022-03-17T20:12:31.497Z,1647547951.497 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:12:31.902Z,1647547951.902 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.884648 min 2022-03-17T20:12:31.902Z,1647547951.902 [Default:CheckIn:E] Stopped 2022-03-17T20:12:31.902Z,1647547951.902 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:12:31.903Z,1647547951.903 [Default:CheckIn] Stopped 2022-03-17T20:12:31.903Z,1647547951.903 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:12:31.903Z,1647547951.903 [Default:CheckIn](INFO): Running loop #8 2022-03-17T20:12:31.903Z,1647547951.903 [Default:CheckIn] Running Loop=8 2022-03-17T20:12:31.903Z,1647547951.903 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:12:31.903Z,1647547951.903 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:12:33.912Z,1647547953.912 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201236.00,A,3648.20885,N,12147.78542,W,0.136,80.70,170322,,,D*40 2022-03-17T20:12:33.914Z,1647547953.914 [NAL9602](INFO): GPS fix at 20220317T201236: (36.803481, -121.796424) 2022-03-17T20:12:33.953Z,1647547953.953 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:12:33.953Z,1647547953.953 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:12:41.716Z,1647547961.716 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0009.lzma 2022-03-17T20:12:42.718Z,1647547962.718 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0009.lzma.bak 2022-03-17T20:12:42.718Z,1647547962.718 [DataOverHttps](INFO): SBD MOMSN=16577185 2022-03-17T20:12:51.744Z,1647547971.744 [NAL9602](INFO): SBD MO Status=1, MOMSN=32345, MT Status=0, MTMSN=0 2022-03-17T20:12:51.744Z,1647547971.744 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:13:01.756Z,1647547981.756 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20220317T195407/Express0010.lzma 2022-03-17T20:13:02.761Z,1647547982.761 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0010.lzma.bak 2022-03-17T20:13:02.761Z,1647547982.761 [DataOverHttps](INFO): SBD MOMSN=16577188 2022-03-17T20:13:03.868Z,1647547983.868 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:13:03.868Z,1647547983.868 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:13:03.868Z,1647547983.868 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:13:22.443Z,1647548002.443 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:18:04.450Z,1647548284.450 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T20:18:04.450Z,1647548284.450 [Default:CheckIn:C.Wait] Stopped 2022-03-17T20:18:04.450Z,1647548284.450 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T20:18:04.451Z,1647548284.451 [Default:CheckIn:D] Running Loop=1 2022-03-17T20:18:04.848Z,1647548284.848 [Default:CheckIn:D] Stopped 2022-03-17T20:18:04.848Z,1647548284.848 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:18:05.249Z,1647548285.249 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.440495 min 2022-03-17T20:18:05.249Z,1647548285.249 [Default:CheckIn:E] Stopped 2022-03-17T20:18:05.249Z,1647548285.249 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:18:05.249Z,1647548285.249 [Default:CheckIn] Stopped 2022-03-17T20:18:05.249Z,1647548285.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:18:05.249Z,1647548285.249 [Default:CheckIn](INFO): Running loop #9 2022-03-17T20:18:05.249Z,1647548285.249 [Default:CheckIn] Running Loop=9 2022-03-17T20:18:05.250Z,1647548285.250 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:18:05.250Z,1647548285.250 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:18:07.268Z,1647548287.268 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201809.00,A,3648.16050,N,12147.69769,W,9.719,42.95,170322,,,A*4A 2022-03-17T20:18:07.271Z,1647548287.271 [NAL9602](INFO): GPS fix at 20220317T201809: (36.802675, -121.794961) 2022-03-17T20:18:07.282Z,1647548287.282 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:18:07.282Z,1647548287.282 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:18:14.276Z,1647548294.276 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0012.lzma 2022-03-17T20:18:15.279Z,1647548295.279 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0012.lzma.bak 2022-03-17T20:18:15.279Z,1647548295.279 [DataOverHttps](INFO): SBD MOMSN=16577206 2022-03-17T20:18:34.056Z,1647548314.056 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220317T195407/Express0013.lzma 2022-03-17T20:18:35.058Z,1647548315.058 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0013.lzma.bak 2022-03-17T20:18:35.059Z,1647548315.059 [DataOverHttps](INFO): SBD MOMSN=16577209 2022-03-17T20:18:36.451Z,1647548316.451 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:18:36.451Z,1647548316.451 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:18:36.451Z,1647548316.451 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:18:49.739Z,1647548329.739 [NAL9602](INFO): SBD MO Status=1, MOMSN=32346, MT Status=0, MTMSN=0 2022-03-17T20:18:49.740Z,1647548329.740 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:19:20.450Z,1647548360.450 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:23:37.071Z,1647548617.071 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T20:23:37.071Z,1647548617.071 [Default:CheckIn:C.Wait] Stopped 2022-03-17T20:23:37.072Z,1647548617.072 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T20:23:37.072Z,1647548617.072 [Default:CheckIn:D] Running Loop=1 2022-03-17T20:23:37.474Z,1647548617.474 [Default:CheckIn:D] Stopped 2022-03-17T20:23:37.474Z,1647548617.474 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:23:37.890Z,1647548617.890 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.984269 min 2022-03-17T20:23:37.890Z,1647548617.890 [Default:CheckIn:E] Stopped 2022-03-17T20:23:37.890Z,1647548617.890 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:23:37.890Z,1647548617.890 [Default:CheckIn] Stopped 2022-03-17T20:23:37.890Z,1647548617.890 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:23:37.891Z,1647548617.891 [Default:CheckIn](INFO): Running loop #10 2022-03-17T20:23:37.891Z,1647548617.891 [Default:CheckIn] Running Loop=10 2022-03-17T20:23:37.891Z,1647548617.891 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:23:37.891Z,1647548617.891 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:23:39.892Z,1647548619.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202342.00,A,3648.44273,N,12147.12245,W,5.054,179.47,170322,,,A*7A 2022-03-17T20:23:39.895Z,1647548619.895 [NAL9602](INFO): GPS fix at 20220317T202342: (36.807379, -121.785374) 2022-03-17T20:23:39.906Z,1647548619.906 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:23:39.906Z,1647548619.906 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:23:47.236Z,1647548627.236 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0015.lzma 2022-03-17T20:23:48.238Z,1647548628.238 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0015.lzma.bak 2022-03-17T20:23:48.239Z,1647548628.239 [DataOverHttps](INFO): SBD MOMSN=16577221 2022-03-17T20:23:57.264Z,1647548637.264 [NAL9602](INFO): SBD MO Status=1, MOMSN=32347, MT Status=0, MTMSN=0 2022-03-17T20:23:57.264Z,1647548637.264 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:24:04.224Z,1647548644.224 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220317T195407/Express0016.lzma 2022-03-17T20:24:05.227Z,1647548645.227 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0016.lzma.bak 2022-03-17T20:24:05.227Z,1647548645.227 [DataOverHttps](INFO): SBD MOMSN=16577224 2022-03-17T20:24:06.585Z,1647548646.585 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:24:06.585Z,1647548646.585 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:24:06.585Z,1647548646.585 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:24:27.974Z,1647548667.974 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:29:07.236Z,1647548947.236 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T20:29:07.236Z,1647548947.236 [Default:CheckIn:C.Wait] Stopped 2022-03-17T20:29:07.236Z,1647548947.236 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T20:29:07.237Z,1647548947.237 [Default:CheckIn:D] Running Loop=1 2022-03-17T20:29:07.632Z,1647548947.632 [Default:CheckIn:D] Stopped 2022-03-17T20:29:07.632Z,1647548947.632 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:29:08.048Z,1647548948.048 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.486902 min 2022-03-17T20:29:08.048Z,1647548948.048 [Default:CheckIn:E] Stopped 2022-03-17T20:29:08.048Z,1647548948.048 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:29:08.048Z,1647548948.048 [Default:CheckIn] Stopped 2022-03-17T20:29:08.048Z,1647548948.048 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:29:08.049Z,1647548948.049 [Default:CheckIn](INFO): Running loop #11 2022-03-17T20:29:08.049Z,1647548948.049 [Default:CheckIn] Running Loop=11 2022-03-17T20:29:08.049Z,1647548948.049 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:29:08.049Z,1647548948.049 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:29:10.056Z,1647548950.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202912.00,A,3648.14974,N,12147.22173,W,0.311,218.74,170322,,,A*7A 2022-03-17T20:29:10.058Z,1647548950.058 [NAL9602](INFO): GPS fix at 20220317T202912: (36.802496, -121.787029) 2022-03-17T20:29:10.069Z,1647548950.069 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:29:10.070Z,1647548950.070 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:29:17.064Z,1647548957.064 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220317T195407/Courier0018.lzma 2022-03-17T20:29:18.066Z,1647548958.066 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0018.lzma.bak 2022-03-17T20:29:18.066Z,1647548958.066 [DataOverHttps](INFO): SBD MOMSN=16577241 2022-03-17T20:29:34.032Z,1647548974.032 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220317T195407/Express0019.lzma 2022-03-17T20:29:35.035Z,1647548975.035 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0019.lzma.bak 2022-03-17T20:29:35.035Z,1647548975.035 [DataOverHttps](INFO): SBD MOMSN=16577244 2022-03-17T20:29:36.353Z,1647548976.353 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:29:36.353Z,1647548976.353 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:29:36.353Z,1647548976.353 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:30:38.951Z,1647549038.951 [NAL9602](INFO): SBD MO Status=2, MOMSN=32348, MT Status=2, MTMSN=0 2022-03-17T20:30:38.952Z,1647549038.952 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T20:31:22.984Z,1647549082.984 [NAL9602](INFO): SBD MO Status=1, MOMSN=32348, MT Status=0, MTMSN=0 2022-03-17T20:31:22.984Z,1647549082.984 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:31:53.686Z,1647549113.686 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:34:37.020Z,1647549277.020 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T20:34:37.020Z,1647549277.020 [Default:CheckIn:C.Wait] Stopped 2022-03-17T20:34:37.020Z,1647549277.020 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T20:34:37.021Z,1647549277.021 [Default:CheckIn:D] Running Loop=1 2022-03-17T20:34:37.430Z,1647549277.430 [Default:CheckIn:D] Stopped 2022-03-17T20:34:37.430Z,1647549277.430 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:34:37.836Z,1647549277.836 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.983529 min 2022-03-17T20:34:37.836Z,1647549277.836 [Default:CheckIn:E] Stopped 2022-03-17T20:34:37.836Z,1647549277.836 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:34:37.836Z,1647549277.836 [Default:CheckIn] Stopped 2022-03-17T20:34:37.837Z,1647549277.837 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:34:37.837Z,1647549277.837 [Default:CheckIn](INFO): Running loop #12 2022-03-17T20:34:37.837Z,1647549277.837 [Default:CheckIn] Running Loop=12 2022-03-17T20:34:37.837Z,1647549277.837 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:34:37.837Z,1647549277.837 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:34:39.851Z,1647549279.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203442.00,A,3648.14946,N,12147.22131,W,0.117,218.74,170322,,,A*70 2022-03-17T20:34:39.853Z,1647549279.853 [NAL9602](INFO): GPS fix at 20220317T203442: (36.802491, -121.787022) 2022-03-17T20:34:39.864Z,1647549279.864 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:34:39.864Z,1647549279.864 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:34:49.908Z,1647549289.908 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20220317T195407/Courier0021.lzma 2022-03-17T20:34:50.911Z,1647549290.911 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0021.lzma.bak 2022-03-17T20:34:50.911Z,1647549290.911 [DataOverHttps](INFO): SBD MOMSN=16577256 2022-03-17T20:34:52.812Z,1647549292.812 [NAL9602](INFO): SBD MO Status=1, MOMSN=32349, MT Status=0, MTMSN=0 2022-03-17T20:34:52.812Z,1647549292.812 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:35:08.096Z,1647549308.096 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0022.lzma 2022-03-17T20:35:09.098Z,1647549309.098 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0022.lzma.bak 2022-03-17T20:35:09.099Z,1647549309.099 [DataOverHttps](INFO): SBD MOMSN=16577259 2022-03-17T20:35:10.211Z,1647549310.211 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:35:10.211Z,1647549310.211 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:35:10.211Z,1647549310.211 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:35:23.528Z,1647549323.528 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:40:11.008Z,1647549611.008 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T20:40:11.008Z,1647549611.008 [Default:CheckIn:C.Wait] Stopped 2022-03-17T20:40:11.008Z,1647549611.008 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T20:40:11.008Z,1647549611.008 [Default:CheckIn:D] Running Loop=1 2022-03-17T20:40:11.420Z,1647549611.420 [Default:CheckIn:D] Stopped 2022-03-17T20:40:11.420Z,1647549611.420 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:40:11.808Z,1647549611.808 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.550033 min 2022-03-17T20:40:11.808Z,1647549611.808 [Default:CheckIn:E] Stopped 2022-03-17T20:40:11.808Z,1647549611.808 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:40:11.809Z,1647549611.809 [Default:CheckIn] Stopped 2022-03-17T20:40:11.809Z,1647549611.809 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:40:11.809Z,1647549611.809 [Default:CheckIn](INFO): Running loop #13 2022-03-17T20:40:11.810Z,1647549611.810 [Default:CheckIn] Running Loop=13 2022-03-17T20:40:11.810Z,1647549611.810 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:40:11.810Z,1647549611.810 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:40:13.820Z,1647549613.820 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204016.00,A,3648.14318,N,12147.22928,W,0.039,207.55,170322,,,A*73 2022-03-17T20:40:13.823Z,1647549613.823 [NAL9602](INFO): GPS fix at 20220317T204016: (36.802386, -121.787155) 2022-03-17T20:40:13.843Z,1647549613.843 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:40:13.843Z,1647549613.843 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:40:24.132Z,1647549624.132 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220317T195407/Courier0024.lzma 2022-03-17T20:40:25.135Z,1647549625.135 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0024.lzma.bak 2022-03-17T20:40:25.135Z,1647549625.135 [DataOverHttps](INFO): SBD MOMSN=16577281 2022-03-17T20:40:34.660Z,1647549634.660 [NAL9602](INFO): SBD MO Status=2, MOMSN=32350, MT Status=2, MTMSN=0 2022-03-17T20:40:34.660Z,1647549634.660 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T20:40:41.096Z,1647549641.096 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220317T195407/Express0025.lzma 2022-03-17T20:40:42.099Z,1647549642.099 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0025.lzma.bak 2022-03-17T20:40:42.099Z,1647549642.099 [DataOverHttps](INFO): SBD MOMSN=16577284 2022-03-17T20:40:43.591Z,1647549643.591 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:40:43.591Z,1647549643.591 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:40:43.591Z,1647549643.591 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:41:02.203Z,1647549662.203 [NAL9602](INFO): SBD MO Status=1, MOMSN=32350, MT Status=0, MTMSN=0 2022-03-17T20:41:02.204Z,1647549662.204 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:41:32.921Z,1647549692.921 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:45:44.054Z,1647549944.054 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T20:45:44.054Z,1647549944.054 [Default:CheckIn:C.Wait] Stopped 2022-03-17T20:45:44.054Z,1647549944.054 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T20:45:44.054Z,1647549944.054 [Default:CheckIn:D] Running Loop=1 2022-03-17T20:45:44.470Z,1647549944.470 [Default:CheckIn:D] Stopped 2022-03-17T20:45:44.470Z,1647549944.470 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:45:44.860Z,1647549944.860 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.100863 min 2022-03-17T20:45:44.860Z,1647549944.860 [Default:CheckIn:E] Stopped 2022-03-17T20:45:44.861Z,1647549944.861 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:45:44.861Z,1647549944.861 [Default:CheckIn] Stopped 2022-03-17T20:45:44.861Z,1647549944.861 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:45:44.861Z,1647549944.861 [Default:CheckIn](INFO): Running loop #14 2022-03-17T20:45:44.861Z,1647549944.861 [Default:CheckIn] Running Loop=14 2022-03-17T20:45:44.861Z,1647549944.861 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:45:44.861Z,1647549944.861 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:45:46.872Z,1647549946.872 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204549.00,A,3648.14369,N,12147.22815,W,0.097,207.55,170322,,,D*74 2022-03-17T20:45:46.875Z,1647549946.875 [NAL9602](INFO): GPS fix at 20220317T204549: (36.802395, -121.787136) 2022-03-17T20:45:46.886Z,1647549946.886 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:45:46.886Z,1647549946.886 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:45:54.888Z,1647549954.888 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0027.lzma 2022-03-17T20:45:55.890Z,1647549955.890 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0027.lzma.bak 2022-03-17T20:45:55.891Z,1647549955.891 [DataOverHttps](INFO): SBD MOMSN=16577297 2022-03-17T20:46:11.933Z,1647549971.933 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0028.lzma 2022-03-17T20:46:12.935Z,1647549972.935 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0028.lzma.bak 2022-03-17T20:46:12.935Z,1647549972.935 [DataOverHttps](INFO): SBD MOMSN=16577302 2022-03-17T20:46:14.369Z,1647549974.369 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:46:14.369Z,1647549974.369 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:46:14.369Z,1647549974.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:48:47.652Z,1647550127.652 [NAL9602](INFO): SBD MO Status=2, MOMSN=32351, MT Status=2, MTMSN=0 2022-03-17T20:48:47.653Z,1647550127.653 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T20:49:05.479Z,1647550145.479 [NAL9602](INFO): SBD MO Status=1, MOMSN=32351, MT Status=0, MTMSN=0 2022-03-17T20:49:05.479Z,1647550145.479 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:49:36.182Z,1647550176.182 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:51:14.786Z,1647550274.786 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T20:51:14.786Z,1647550274.786 [Default:CheckIn:C.Wait] Stopped 2022-03-17T20:51:14.786Z,1647550274.786 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T20:51:14.787Z,1647550274.787 [Default:CheckIn:D] Running Loop=1 2022-03-17T20:51:15.202Z,1647550275.202 [Default:CheckIn:D] Stopped 2022-03-17T20:51:15.202Z,1647550275.202 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:51:15.593Z,1647550275.593 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.613062 min 2022-03-17T20:51:15.593Z,1647550275.593 [Default:CheckIn:E] Stopped 2022-03-17T20:51:15.593Z,1647550275.593 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:51:15.593Z,1647550275.593 [Default:CheckIn] Stopped 2022-03-17T20:51:15.593Z,1647550275.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:51:15.593Z,1647550275.593 [Default:CheckIn](INFO): Running loop #15 2022-03-17T20:51:15.594Z,1647550275.594 [Default:CheckIn] Running Loop=15 2022-03-17T20:51:15.594Z,1647550275.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:51:15.594Z,1647550275.594 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:51:17.608Z,1647550277.608 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205119.00,A,3648.14268,N,12147.22898,W,0.019,207.55,170322,,,D*77 2022-03-17T20:51:17.611Z,1647550277.611 [NAL9602](INFO): GPS fix at 20220317T205119: (36.802378, -121.787150) 2022-03-17T20:51:17.622Z,1647550277.622 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:51:17.622Z,1647550277.622 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:51:25.640Z,1647550285.640 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0030.lzma 2022-03-17T20:51:26.642Z,1647550286.642 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0030.lzma.bak 2022-03-17T20:51:26.643Z,1647550286.643 [DataOverHttps](INFO): SBD MOMSN=16577316 2022-03-17T20:51:42.688Z,1647550302.688 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220317T195407/Express0031.lzma 2022-03-17T20:51:43.691Z,1647550303.691 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0031.lzma.bak 2022-03-17T20:51:43.691Z,1647550303.691 [DataOverHttps](INFO): SBD MOMSN=16577321 2022-03-17T20:51:45.090Z,1647550305.090 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:51:45.090Z,1647550305.090 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:51:45.090Z,1647550305.090 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:51:52.356Z,1647550312.356 [NAL9602](INFO): SBD MO Status=2, MOMSN=32352, MT Status=2, MTMSN=0 2022-03-17T20:51:52.356Z,1647550312.356 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T20:53:01.547Z,1647550381.547 [NAL9602](INFO): SBD MO Status=2, MOMSN=32352, MT Status=2, MTMSN=0 2022-03-17T20:53:01.548Z,1647550381.548 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T20:53:24.576Z,1647550404.576 [NAL9602](INFO): SBD MO Status=2, MOMSN=32352, MT Status=2, MTMSN=0 2022-03-17T20:53:24.576Z,1647550404.576 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T20:54:21.572Z,1647550461.572 [NAL9602](INFO): SBD MO Status=2, MOMSN=32352, MT Status=2, MTMSN=0 2022-03-17T20:54:21.572Z,1647550461.572 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T20:54:50.304Z,1647550490.304 [NAL9602](INFO): SBD MO Status=1, MOMSN=32352, MT Status=0, MTMSN=0 2022-03-17T20:54:50.304Z,1647550490.304 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:55:21.006Z,1647550521.006 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T20:56:45.496Z,1647550605.496 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T20:56:45.496Z,1647550605.496 [Default:CheckIn:C.Wait] Stopped 2022-03-17T20:56:45.496Z,1647550605.496 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T20:56:45.496Z,1647550605.496 [Default:CheckIn:D] Running Loop=1 2022-03-17T20:56:45.900Z,1647550605.900 [Default:CheckIn:D] Stopped 2022-03-17T20:56:45.900Z,1647550605.900 [Default:CheckIn:E] Running Loop=1 2022-03-17T20:56:46.340Z,1647550606.340 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.124707 min 2022-03-17T20:56:46.340Z,1647550606.340 [Default:CheckIn:E] Stopped 2022-03-17T20:56:46.345Z,1647550606.345 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T20:56:46.345Z,1647550606.345 [Default:CheckIn] Stopped 2022-03-17T20:56:46.345Z,1647550606.345 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T20:56:46.345Z,1647550606.345 [Default:CheckIn](INFO): Running loop #16 2022-03-17T20:56:46.345Z,1647550606.345 [Default:CheckIn] Running Loop=16 2022-03-17T20:56:46.345Z,1647550606.345 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T20:56:46.345Z,1647550606.345 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T20:56:48.306Z,1647550608.306 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205650.00,A,3648.14238,N,12147.22750,W,0.019,207.55,170322,,,D*73 2022-03-17T20:56:48.318Z,1647550608.318 [NAL9602](INFO): GPS fix at 20220317T205650: (36.802373, -121.787125) 2022-03-17T20:56:48.329Z,1647550608.329 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T20:56:48.329Z,1647550608.329 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T20:56:58.612Z,1647550618.612 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0033.lzma 2022-03-17T20:56:59.614Z,1647550619.614 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0033.lzma.bak 2022-03-17T20:56:59.615Z,1647550619.615 [DataOverHttps](INFO): SBD MOMSN=16577339 2022-03-17T20:57:16.294Z,1647550636.294 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220317T195407/Express0034.lzma 2022-03-17T20:57:17.294Z,1647550637.294 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0034.lzma.bak 2022-03-17T20:57:17.295Z,1647550637.295 [DataOverHttps](INFO): SBD MOMSN=16577344 2022-03-17T20:57:18.727Z,1647550638.727 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T20:57:18.727Z,1647550638.727 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T20:57:18.728Z,1647550638.728 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T20:57:45.723Z,1647550665.723 [NAL9602](INFO): SBD MO Status=1, MOMSN=32353, MT Status=0, MTMSN=0 2022-03-17T20:57:45.724Z,1647550665.724 [NAL9602](INFO): No messages in MT queue 2022-03-17T20:58:16.422Z,1647550696.422 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T21:01:49.827Z,1647550909.827 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2022-03-17T21:02:01.901Z,1647550921.901 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-03-17T21:02:01.904Z,1647550921.904 [BPC1](INFO): Received data from all battery sticks. 2022-03-17T21:02:19.283Z,1647550939.283 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:02:19.283Z,1647550939.283 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:02:19.283Z,1647550939.283 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:02:19.284Z,1647550939.284 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:02:19.701Z,1647550939.701 [Default:CheckIn:D] Stopped 2022-03-17T21:02:19.701Z,1647550939.701 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:02:20.079Z,1647550940.079 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.687508 min 2022-03-17T21:02:20.079Z,1647550940.079 [Default:CheckIn:E] Stopped 2022-03-17T21:02:20.080Z,1647550940.080 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:02:20.080Z,1647550940.080 [Default:CheckIn] Stopped 2022-03-17T21:02:20.080Z,1647550940.080 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:02:20.080Z,1647550940.080 [Default:CheckIn](INFO): Running loop #17 2022-03-17T21:02:20.080Z,1647550940.080 [Default:CheckIn] Running Loop=17 2022-03-17T21:02:20.080Z,1647550940.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:02:20.080Z,1647550940.080 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:02:22.094Z,1647550942.094 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210224.00,A,3648.17777,N,12147.27291,W,0.078,297.88,170322,,,D*7E 2022-03-17T21:02:22.097Z,1647550942.097 [NAL9602](INFO): GPS fix at 20220317T210224: (36.802963, -121.787882) 2022-03-17T21:02:22.119Z,1647550942.119 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:02:22.119Z,1647550942.119 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:02:29.488Z,1647550949.488 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20220317T195407/Courier0036.lzma 2022-03-17T21:02:30.491Z,1647550950.491 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0036.lzma.bak 2022-03-17T21:02:30.491Z,1647550950.491 [DataOverHttps](INFO): SBD MOMSN=16577356 2022-03-17T21:02:47.485Z,1647550967.485 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220317T195407/Express0037.lzma 2022-03-17T21:02:48.487Z,1647550968.487 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0037.lzma.bak 2022-03-17T21:02:48.487Z,1647550968.487 [DataOverHttps](INFO): SBD MOMSN=16577359 2022-03-17T21:02:50.010Z,1647550970.010 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:02:50.010Z,1647550970.010 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:02:50.010Z,1647550970.010 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T21:05:50.243Z,1647551150.243 [NAL9602](INFO): SBD MO Status=1, MOMSN=32354, MT Status=0, MTMSN=0 2022-03-17T21:05:50.243Z,1647551150.243 [NAL9602](INFO): No messages in MT queue 2022-03-17T21:06:20.935Z,1647551180.935 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T21:07:50.644Z,1647551270.644 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:07:50.644Z,1647551270.644 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:07:50.644Z,1647551270.644 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:07:50.645Z,1647551270.645 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:07:51.058Z,1647551271.058 [Default:CheckIn:D] Stopped 2022-03-17T21:07:51.058Z,1647551271.058 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:07:51.469Z,1647551271.469 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.210661 min 2022-03-17T21:07:51.470Z,1647551271.470 [Default:CheckIn:E] Stopped 2022-03-17T21:07:51.470Z,1647551271.470 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:07:51.470Z,1647551271.470 [Default:CheckIn] Stopped 2022-03-17T21:07:51.470Z,1647551271.470 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:07:51.470Z,1647551271.470 [Default:CheckIn](INFO): Running loop #18 2022-03-17T21:07:51.470Z,1647551271.470 [Default:CheckIn] Running Loop=18 2022-03-17T21:07:51.470Z,1647551271.470 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:07:51.471Z,1647551271.471 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:07:53.464Z,1647551273.464 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210755.00,A,3648.18559,N,12147.28279,W,0.058,297.88,170322,,,A*72 2022-03-17T21:07:53.467Z,1647551273.467 [NAL9602](INFO): GPS fix at 20220317T210755: (36.803093, -121.788046) 2022-03-17T21:07:53.505Z,1647551273.505 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:07:53.505Z,1647551273.505 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:08:00.868Z,1647551280.868 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0039.lzma 2022-03-17T21:08:01.870Z,1647551281.870 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0039.lzma.bak 2022-03-17T21:08:01.871Z,1647551281.871 [DataOverHttps](INFO): SBD MOMSN=16577374 2022-03-17T21:08:16.526Z,1647551296.526 [NAL9602](INFO): SBD MO Status=1, MOMSN=32355, MT Status=0, MTMSN=0 2022-03-17T21:08:16.526Z,1647551296.526 [NAL9602](INFO): No messages in MT queue 2022-03-17T21:08:20.427Z,1647551300.427 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220317T195407/Express0040.lzma 2022-03-17T21:08:21.435Z,1647551301.435 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0040.lzma.bak 2022-03-17T21:08:21.435Z,1647551301.435 [DataOverHttps](INFO): SBD MOMSN=16577377 2022-03-17T21:08:22.719Z,1647551302.719 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:08:22.719Z,1647551302.719 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:08:22.719Z,1647551302.719 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T21:08:47.250Z,1647551327.250 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T21:13:23.226Z,1647551603.226 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:13:23.226Z,1647551603.226 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:13:23.226Z,1647551603.226 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:13:23.226Z,1647551603.226 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:13:23.607Z,1647551603.607 [Default:CheckIn:D] Stopped 2022-03-17T21:13:23.607Z,1647551603.607 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:13:24.020Z,1647551604.020 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.753158 min 2022-03-17T21:13:24.020Z,1647551604.020 [Default:CheckIn:E] Stopped 2022-03-17T21:13:24.021Z,1647551604.021 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:13:24.021Z,1647551604.021 [Default:CheckIn] Stopped 2022-03-17T21:13:24.021Z,1647551604.021 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:13:24.021Z,1647551604.021 [Default:CheckIn](INFO): Running loop #19 2022-03-17T21:13:24.021Z,1647551604.021 [Default:CheckIn] Running Loop=19 2022-03-17T21:13:24.021Z,1647551604.021 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:13:24.021Z,1647551604.021 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:13:26.032Z,1647551606.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211328.00,A,3648.17380,N,12147.27587,W,0.194,297.88,170322,,,A*78 2022-03-17T21:13:26.034Z,1647551606.034 [NAL9602](INFO): GPS fix at 20220317T211328: (36.802897, -121.787931) 2022-03-17T21:13:26.045Z,1647551606.045 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:13:26.045Z,1647551606.045 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:13:34.036Z,1647551614.036 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220317T195407/Courier0042.lzma 2022-03-17T21:13:35.038Z,1647551615.038 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0042.lzma.bak 2022-03-17T21:13:35.039Z,1647551615.039 [DataOverHttps](INFO): SBD MOMSN=16577391 2022-03-17T21:13:51.060Z,1647551631.060 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220317T195407/Express0043.lzma 2022-03-17T21:13:52.063Z,1647551632.063 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0043.lzma.bak 2022-03-17T21:13:52.063Z,1647551632.063 [DataOverHttps](INFO): SBD MOMSN=16577394 2022-03-17T21:13:53.523Z,1647551633.523 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:13:53.523Z,1647551633.523 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:13:53.523Z,1647551633.523 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T21:15:13.104Z,1647551713.104 [NAL9602](INFO): SBD MO Status=1, MOMSN=32356, MT Status=0, MTMSN=0 2022-03-17T21:15:13.105Z,1647551713.105 [NAL9602](INFO): No messages in MT queue 2022-03-17T21:15:43.810Z,1647551743.810 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T21:18:54.110Z,1647551934.110 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:18:54.110Z,1647551934.110 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:18:54.110Z,1647551934.110 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:18:54.111Z,1647551934.111 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:18:54.507Z,1647551934.507 [Default:CheckIn:D] Stopped 2022-03-17T21:18:54.507Z,1647551934.507 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:18:54.917Z,1647551934.917 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.268156 min 2022-03-17T21:18:54.917Z,1647551934.917 [Default:CheckIn:E] Stopped 2022-03-17T21:18:54.918Z,1647551934.918 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:18:54.918Z,1647551934.918 [Default:CheckIn] Stopped 2022-03-17T21:18:54.918Z,1647551934.918 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:18:54.918Z,1647551934.918 [Default:CheckIn](INFO): Running loop #20 2022-03-17T21:18:54.918Z,1647551934.918 [Default:CheckIn] Running Loop=20 2022-03-17T21:18:54.918Z,1647551934.918 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:18:54.918Z,1647551934.918 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:18:56.932Z,1647551936.932 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211859.00,A,3648.18317,N,12147.27583,W,0.175,0.00,170322,,,A*73 2022-03-17T21:18:56.934Z,1647551936.934 [NAL9602](INFO): GPS fix at 20220317T211859: (36.803053, -121.787931) 2022-03-17T21:18:56.945Z,1647551936.945 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:18:56.946Z,1647551936.946 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:19:04.884Z,1647551944.884 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0045.lzma 2022-03-17T21:19:05.887Z,1647551945.887 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0045.lzma.bak 2022-03-17T21:19:05.887Z,1647551945.887 [DataOverHttps](INFO): SBD MOMSN=16577412 2022-03-17T21:19:21.744Z,1647551961.744 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20220317T195407/Express0046.lzma 2022-03-17T21:19:22.747Z,1647551962.747 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0046.lzma.bak 2022-03-17T21:19:22.747Z,1647551962.747 [DataOverHttps](INFO): SBD MOMSN=16577415 2022-03-17T21:19:24.006Z,1647551964.006 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:19:24.006Z,1647551964.006 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:19:24.007Z,1647551964.007 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T21:20:07.228Z,1647552007.228 [NAL9602](INFO): SBD MO Status=2, MOMSN=32357, MT Status=2, MTMSN=0 2022-03-17T21:20:07.228Z,1647552007.228 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T21:20:20.164Z,1647552020.164 [NAL9602](INFO): SBD MO Status=2, MOMSN=32357, MT Status=2, MTMSN=0 2022-03-17T21:20:20.164Z,1647552020.164 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T21:20:29.459Z,1647552029.459 [NAL9602](INFO): SBD MO Status=1, MOMSN=32357, MT Status=0, MTMSN=0 2022-03-17T21:20:29.460Z,1647552029.460 [NAL9602](INFO): No messages in MT queue 2022-03-17T21:21:00.150Z,1647552060.150 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T21:24:24.600Z,1647552264.600 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:24:24.600Z,1647552264.600 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:24:24.600Z,1647552264.600 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:24:24.601Z,1647552264.601 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:24:24.994Z,1647552264.994 [Default:CheckIn:D] Stopped 2022-03-17T21:24:24.994Z,1647552264.994 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:24:25.392Z,1647552265.392 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.776261 min 2022-03-17T21:24:25.392Z,1647552265.392 [Default:CheckIn:E] Stopped 2022-03-17T21:24:25.392Z,1647552265.392 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:24:25.392Z,1647552265.392 [Default:CheckIn] Stopped 2022-03-17T21:24:25.392Z,1647552265.392 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:24:25.392Z,1647552265.392 [Default:CheckIn](INFO): Running loop #21 2022-03-17T21:24:25.392Z,1647552265.392 [Default:CheckIn] Running Loop=21 2022-03-17T21:24:25.393Z,1647552265.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:24:25.393Z,1647552265.393 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:24:27.412Z,1647552267.412 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212429.00,A,3648.17768,N,12147.27705,W,0.078,316.12,170322,,,A*7F 2022-03-17T21:24:27.414Z,1647552267.414 [NAL9602](INFO): GPS fix at 20220317T212429: (36.802961, -121.787951) 2022-03-17T21:24:27.426Z,1647552267.426 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:24:27.426Z,1647552267.426 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:24:34.679Z,1647552274.679 [NAL9602](INFO): SBD MO Status=1, MOMSN=32358, MT Status=0, MTMSN=0 2022-03-17T21:24:34.680Z,1647552274.680 [NAL9602](INFO): No messages in MT queue 2022-03-17T21:24:35.168Z,1647552275.168 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0048.lzma 2022-03-17T21:24:36.170Z,1647552276.170 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0048.lzma.bak 2022-03-17T21:24:36.171Z,1647552276.171 [DataOverHttps](INFO): SBD MOMSN=16577426 2022-03-17T21:24:55.117Z,1647552295.117 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0049.lzma 2022-03-17T21:24:56.118Z,1647552296.118 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0049.lzma.bak 2022-03-17T21:24:56.119Z,1647552296.119 [DataOverHttps](INFO): SBD MOMSN=16577429 2022-03-17T21:24:57.385Z,1647552297.385 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:24:57.385Z,1647552297.385 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:24:57.385Z,1647552297.385 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T21:25:05.450Z,1647552305.450 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T21:29:57.972Z,1647552597.972 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:29:57.972Z,1647552597.972 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:29:57.972Z,1647552597.972 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:29:57.972Z,1647552597.972 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:29:58.364Z,1647552598.364 [Default:CheckIn:D] Stopped 2022-03-17T21:29:58.364Z,1647552598.364 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:29:58.764Z,1647552598.764 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.332430 min 2022-03-17T21:29:58.764Z,1647552598.764 [Default:CheckIn:E] Stopped 2022-03-17T21:29:58.764Z,1647552598.764 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:29:58.765Z,1647552598.765 [Default:CheckIn] Stopped 2022-03-17T21:29:58.765Z,1647552598.765 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:29:58.765Z,1647552598.765 [Default:CheckIn](INFO): Running loop #22 2022-03-17T21:29:58.765Z,1647552598.765 [Default:CheckIn] Running Loop=22 2022-03-17T21:29:58.765Z,1647552598.765 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:29:58.765Z,1647552598.765 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:30:00.778Z,1647552600.778 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213003.00,A,3648.18350,N,12147.27602,W,0.330,172.63,170322,,,A*7D 2022-03-17T21:30:00.781Z,1647552600.781 [NAL9602](INFO): GPS fix at 20220317T213003: (36.803058, -121.787934) 2022-03-17T21:30:00.791Z,1647552600.791 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:30:00.791Z,1647552600.791 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:30:08.348Z,1647552608.348 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0051.lzma 2022-03-17T21:30:09.350Z,1647552609.350 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0051.lzma.bak 2022-03-17T21:30:09.351Z,1647552609.351 [DataOverHttps](INFO): SBD MOMSN=16577452 2022-03-17T21:30:30.236Z,1647552630.236 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220317T195407/Express0052.lzma 2022-03-17T21:30:31.239Z,1647552631.239 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0052.lzma.bak 2022-03-17T21:30:31.239Z,1647552631.239 [DataOverHttps](INFO): SBD MOMSN=16577455 2022-03-17T21:30:32.359Z,1647552632.359 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:30:32.359Z,1647552632.359 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:30:32.359Z,1647552632.359 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T21:33:46.275Z,1647552826.275 [NAL9602](INFO): SBD MO Status=1, MOMSN=32359, MT Status=0, MTMSN=0 2022-03-17T21:33:46.276Z,1647552826.276 [NAL9602](INFO): No messages in MT queue 2022-03-17T21:34:16.974Z,1647552856.974 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T21:35:32.940Z,1647552932.940 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:35:32.940Z,1647552932.940 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:35:32.941Z,1647552932.941 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:35:32.941Z,1647552932.941 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:35:33.343Z,1647552933.343 [Default:CheckIn:D] Stopped 2022-03-17T21:35:33.343Z,1647552933.343 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:35:33.758Z,1647552933.758 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.915413 min 2022-03-17T21:35:33.758Z,1647552933.758 [Default:CheckIn:E] Stopped 2022-03-17T21:35:33.758Z,1647552933.758 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:35:33.758Z,1647552933.758 [Default:CheckIn] Stopped 2022-03-17T21:35:33.759Z,1647552933.759 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:35:33.759Z,1647552933.759 [Default:CheckIn](INFO): Running loop #23 2022-03-17T21:35:33.759Z,1647552933.759 [Default:CheckIn] Running Loop=23 2022-03-17T21:35:33.759Z,1647552933.759 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:35:33.759Z,1647552933.759 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:35:35.757Z,1647552935.757 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213538.00,A,3648.17597,N,12147.27794,W,0.564,42.10,170322,,,A*4D 2022-03-17T21:35:35.759Z,1647552935.759 [NAL9602](INFO): GPS fix at 20220317T213538: (36.802933, -121.787966) 2022-03-17T21:35:35.770Z,1647552935.770 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:35:35.770Z,1647552935.770 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:35:43.176Z,1647552943.176 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0054.lzma 2022-03-17T21:35:44.178Z,1647552944.178 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0054.lzma.bak 2022-03-17T21:35:44.179Z,1647552944.179 [DataOverHttps](INFO): SBD MOMSN=16577464 2022-03-17T21:35:55.559Z,1647552955.559 [NAL9602](INFO): SBD MO Status=1, MOMSN=32360, MT Status=0, MTMSN=0 2022-03-17T21:35:55.560Z,1647552955.560 [NAL9602](INFO): No messages in MT queue 2022-03-17T21:36:00.116Z,1647552960.116 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20220317T195407/Express0055.lzma 2022-03-17T21:36:01.119Z,1647552961.119 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0055.lzma.bak 2022-03-17T21:36:01.119Z,1647552961.119 [DataOverHttps](INFO): SBD MOMSN=16577467 2022-03-17T21:36:02.428Z,1647552962.428 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:36:02.453Z,1647552962.453 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:36:02.453Z,1647552962.453 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T21:36:26.254Z,1647552986.254 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T21:41:03.036Z,1647553263.036 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:41:03.036Z,1647553263.036 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:41:03.036Z,1647553263.036 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:41:03.036Z,1647553263.036 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:41:03.445Z,1647553263.445 [Default:CheckIn:D] Stopped 2022-03-17T21:41:03.445Z,1647553263.445 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:41:03.848Z,1647553263.848 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.417106 min 2022-03-17T21:41:03.848Z,1647553263.848 [Default:CheckIn:E] Stopped 2022-03-17T21:41:03.848Z,1647553263.848 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:41:03.848Z,1647553263.848 [Default:CheckIn] Stopped 2022-03-17T21:41:03.849Z,1647553263.849 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:41:03.849Z,1647553263.849 [Default:CheckIn](INFO): Running loop #24 2022-03-17T21:41:03.849Z,1647553263.849 [Default:CheckIn] Running Loop=24 2022-03-17T21:41:03.849Z,1647553263.849 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:41:03.849Z,1647553263.849 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:41:05.856Z,1647553265.856 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214108.00,A,3648.17728,N,12147.27768,W,0.330,190.13,170322,,,A*72 2022-03-17T21:41:05.867Z,1647553265.867 [NAL9602](INFO): GPS fix at 20220317T214108: (36.802955, -121.787961) 2022-03-17T21:41:05.882Z,1647553265.882 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:41:05.882Z,1647553265.882 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:41:13.472Z,1647553273.472 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220317T195407/Courier0057.lzma 2022-03-17T21:41:14.475Z,1647553274.475 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0057.lzma.bak 2022-03-17T21:41:14.475Z,1647553274.475 [DataOverHttps](INFO): SBD MOMSN=16577486 2022-03-17T21:41:30.576Z,1647553290.576 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20220317T195407/Express0058.lzma 2022-03-17T21:41:31.579Z,1647553291.579 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0058.lzma.bak 2022-03-17T21:41:31.579Z,1647553291.579 [DataOverHttps](INFO): SBD MOMSN=16577489 2022-03-17T21:41:32.947Z,1647553292.947 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:41:32.947Z,1647553292.947 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:41:32.947Z,1647553292.947 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T21:43:16.014Z,1647553396.014 [NAL9602](INFO): SBD MO Status=1, MOMSN=32361, MT Status=0, MTMSN=0 2022-03-17T21:43:16.015Z,1647553396.015 [NAL9602](INFO): No messages in MT queue 2022-03-17T21:43:46.718Z,1647553426.718 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T21:46:33.586Z,1647553593.586 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:46:33.586Z,1647553593.586 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:46:33.586Z,1647553593.586 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:46:33.587Z,1647553593.587 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:46:33.980Z,1647553593.980 [Default:CheckIn:D] Stopped 2022-03-17T21:46:33.980Z,1647553593.980 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:46:34.381Z,1647553594.381 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.926025 min 2022-03-17T21:46:34.381Z,1647553594.381 [Default:CheckIn:E] Stopped 2022-03-17T21:46:34.381Z,1647553594.381 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:46:34.381Z,1647553594.381 [Default:CheckIn] Stopped 2022-03-17T21:46:34.381Z,1647553594.381 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:46:34.381Z,1647553594.381 [Default:CheckIn](INFO): Running loop #25 2022-03-17T21:46:34.381Z,1647553594.381 [Default:CheckIn] Running Loop=25 2022-03-17T21:46:34.382Z,1647553594.382 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:46:34.382Z,1647553594.382 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:46:36.396Z,1647553596.396 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214638.00,A,3648.17335,N,12147.27767,W,0.117,190.13,170322,,,A*76 2022-03-17T21:46:36.398Z,1647553596.398 [NAL9602](INFO): GPS fix at 20220317T214638: (36.802889, -121.787961) 2022-03-17T21:46:36.431Z,1647553596.431 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:46:36.431Z,1647553596.431 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:46:44.177Z,1647553604.177 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0060.lzma 2022-03-17T21:46:45.179Z,1647553605.179 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0060.lzma.bak 2022-03-17T21:46:45.179Z,1647553605.179 [DataOverHttps](INFO): SBD MOMSN=16577501 2022-03-17T21:47:04.016Z,1647553624.016 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0061.lzma 2022-03-17T21:47:05.019Z,1647553625.019 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0061.lzma.bak 2022-03-17T21:47:05.019Z,1647553625.019 [DataOverHttps](INFO): SBD MOMSN=16577505 2022-03-17T21:47:06.378Z,1647553626.378 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:47:06.378Z,1647553626.378 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:47:06.378Z,1647553626.378 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T21:51:38.269Z,1647553898.269 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-17T21:52:06.970Z,1647553926.970 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:52:06.970Z,1647553926.970 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:52:06.971Z,1647553926.971 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:52:06.971Z,1647553926.971 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:52:07.364Z,1647553927.364 [Default:CheckIn:D] Stopped 2022-03-17T21:52:07.364Z,1647553927.364 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:52:07.777Z,1647553927.777 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.482422 min 2022-03-17T21:52:07.777Z,1647553927.777 [Default:CheckIn:E] Stopped 2022-03-17T21:52:07.777Z,1647553927.777 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:52:07.777Z,1647553927.777 [Default:CheckIn] Stopped 2022-03-17T21:52:07.777Z,1647553927.777 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:52:07.777Z,1647553927.777 [Default:CheckIn](INFO): Running loop #26 2022-03-17T21:52:07.777Z,1647553927.777 [Default:CheckIn] Running Loop=26 2022-03-17T21:52:07.778Z,1647553927.778 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:52:07.778Z,1647553927.778 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:52:09.788Z,1647553929.788 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215212.00,A,3648.17476,N,12147.27657,W,0.758,190.13,170322,,,A*74 2022-03-17T21:52:09.791Z,1647553929.791 [NAL9602](INFO): GPS fix at 20220317T215212: (36.802913, -121.787943) 2022-03-17T21:52:09.801Z,1647553929.801 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:52:09.801Z,1647553929.801 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:52:19.817Z,1647553939.817 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20220317T195407/Courier0063.lzma 2022-03-17T21:52:20.819Z,1647553940.819 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0063.lzma.bak 2022-03-17T21:52:20.819Z,1647553940.819 [DataOverHttps](INFO): SBD MOMSN=16577520 2022-03-17T21:52:39.017Z,1647553959.017 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20220317T195407/Express0064.lzma 2022-03-17T21:52:40.019Z,1647553960.019 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0064.lzma.bak 2022-03-17T21:52:40.019Z,1647553960.019 [DataOverHttps](INFO): SBD MOMSN=16577523 2022-03-17T21:52:41.346Z,1647553961.346 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:52:41.346Z,1647553961.346 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:52:41.346Z,1647553961.346 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T21:52:42.545Z,1647553962.545 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T21:57:41.812Z,1647554261.812 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T21:57:41.813Z,1647554261.813 [Default:CheckIn:C.Wait] Stopped 2022-03-17T21:57:41.813Z,1647554261.813 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T21:57:41.813Z,1647554261.813 [Default:CheckIn:D] Running Loop=1 2022-03-17T21:57:42.192Z,1647554262.192 [Default:CheckIn:D] Stopped 2022-03-17T21:57:42.192Z,1647554262.192 [Default:CheckIn:E] Running Loop=1 2022-03-17T21:57:42.607Z,1647554262.607 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.062907 min 2022-03-17T21:57:42.607Z,1647554262.607 [Default:CheckIn:E] Stopped 2022-03-17T21:57:42.607Z,1647554262.607 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T21:57:42.607Z,1647554262.607 [Default:CheckIn] Stopped 2022-03-17T21:57:42.607Z,1647554262.607 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T21:57:42.607Z,1647554262.607 [Default:CheckIn](INFO): Running loop #27 2022-03-17T21:57:42.607Z,1647554262.607 [Default:CheckIn] Running Loop=27 2022-03-17T21:57:42.608Z,1647554262.608 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T21:57:42.608Z,1647554262.608 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T21:57:44.616Z,1647554264.616 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215746.00,A,3648.17343,N,12147.27830,W,0.078,190.13,170322,,,A*7B 2022-03-17T21:57:44.618Z,1647554264.618 [NAL9602](INFO): GPS fix at 20220317T215746: (36.802890, -121.787972) 2022-03-17T21:57:44.629Z,1647554264.629 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T21:57:44.629Z,1647554264.629 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T21:57:51.697Z,1647554271.697 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0066.lzma 2022-03-17T21:57:52.699Z,1647554272.699 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0066.lzma.bak 2022-03-17T21:57:52.699Z,1647554272.699 [DataOverHttps](INFO): SBD MOMSN=16577534 2022-03-17T21:58:08.992Z,1647554288.992 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0067.lzma 2022-03-17T21:58:09.995Z,1647554289.995 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0067.lzma.bak 2022-03-17T21:58:09.995Z,1647554289.995 [DataOverHttps](INFO): SBD MOMSN=16577537 2022-03-17T21:58:11.291Z,1647554291.291 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T21:58:11.291Z,1647554291.291 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T21:58:11.291Z,1647554291.291 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:02:47.090Z,1647554567.090 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-17T22:03:11.817Z,1647554591.817 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:03:11.818Z,1647554591.818 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:03:11.818Z,1647554591.818 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:03:11.818Z,1647554591.818 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:03:12.187Z,1647554592.187 [Default:CheckIn:D] Stopped 2022-03-17T22:03:12.188Z,1647554592.188 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:03:12.602Z,1647554592.602 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.562826 min 2022-03-17T22:03:12.602Z,1647554592.602 [Default:CheckIn:E] Stopped 2022-03-17T22:03:12.603Z,1647554592.603 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:03:12.603Z,1647554592.603 [Default:CheckIn] Stopped 2022-03-17T22:03:12.603Z,1647554592.603 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:03:12.603Z,1647554592.603 [Default:CheckIn](INFO): Running loop #28 2022-03-17T22:03:12.603Z,1647554592.603 [Default:CheckIn] Running Loop=28 2022-03-17T22:03:12.603Z,1647554592.603 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:03:12.603Z,1647554592.603 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:03:14.600Z,1647554594.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220316.00,A,3648.17854,N,12147.27141,W,0.097,0.00,170322,,,A*75 2022-03-17T22:03:14.602Z,1647554594.602 [NAL9602](INFO): GPS fix at 20220317T220316: (36.802976, -121.787857) 2022-03-17T22:03:14.625Z,1647554594.625 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:03:14.625Z,1647554594.625 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:03:21.920Z,1647554601.920 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220317T195407/Courier0069.lzma 2022-03-17T22:03:22.923Z,1647554602.923 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0069.lzma.bak 2022-03-17T22:03:22.923Z,1647554602.923 [DataOverHttps](INFO): SBD MOMSN=16577553 2022-03-17T22:03:39.349Z,1647554619.349 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20220317T195407/Express0070.lzma 2022-03-17T22:03:40.351Z,1647554620.351 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0070.lzma.bak 2022-03-17T22:03:40.351Z,1647554620.351 [DataOverHttps](INFO): SBD MOMSN=16577556 2022-03-17T22:03:41.695Z,1647554621.695 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:03:41.695Z,1647554621.695 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:03:41.695Z,1647554621.695 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:03:47.326Z,1647554627.326 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T22:06:19.504Z,1647554779.504 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2022-03-17T22:08:42.228Z,1647554922.228 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:08:42.228Z,1647554922.228 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:08:42.228Z,1647554922.228 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:08:42.228Z,1647554922.228 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:08:42.627Z,1647554922.627 [Default:CheckIn:D] Stopped 2022-03-17T22:08:42.627Z,1647554922.627 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:08:43.039Z,1647554923.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.070150 min 2022-03-17T22:08:43.039Z,1647554923.039 [Default:CheckIn:E] Stopped 2022-03-17T22:08:43.040Z,1647554923.040 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:08:43.040Z,1647554923.040 [Default:CheckIn] Stopped 2022-03-17T22:08:43.040Z,1647554923.040 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:08:43.040Z,1647554923.040 [Default:CheckIn](INFO): Running loop #29 2022-03-17T22:08:43.040Z,1647554923.040 [Default:CheckIn] Running Loop=29 2022-03-17T22:08:43.040Z,1647554923.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:08:43.041Z,1647554923.041 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:08:45.044Z,1647554925.044 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220847.00,A,3648.17966,N,12147.27831,W,0.369,0.00,170322,,,A*76 2022-03-17T22:08:45.046Z,1647554925.046 [NAL9602](INFO): GPS fix at 20220317T220847: (36.802994, -121.787972) 2022-03-17T22:08:45.094Z,1647554925.094 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:08:45.094Z,1647554925.094 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:08:55.857Z,1647554935.857 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0072.lzma 2022-03-17T22:08:56.875Z,1647554936.875 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0072.lzma.bak 2022-03-17T22:08:56.875Z,1647554936.875 [DataOverHttps](INFO): SBD MOMSN=16577566 2022-03-17T22:09:13.356Z,1647554953.356 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0073.lzma 2022-03-17T22:09:14.359Z,1647554954.359 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0073.lzma.bak 2022-03-17T22:09:14.359Z,1647554954.359 [DataOverHttps](INFO): SBD MOMSN=16577569 2022-03-17T22:09:15.803Z,1647554955.803 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-03-17T22:09:15.877Z,1647554955.877 [NAL9602](ERROR): received: +CSQ:0 OK361, 0, 0, 0, 0 OK 2022-03-17T22:09:15.894Z,1647554955.894 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:09:15.894Z,1647554955.894 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:09:15.894Z,1647554955.894 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:09:25.904Z,1647554965.904 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-03-17T22:09:25.907Z,1647554965.907 [BPC1](INFO): Received data from all battery sticks. 2022-03-17T22:13:45.307Z,1647555225.307 [NAL9602](INFO): SBD MO Status=2, MOMSN=32362, MT Status=2, MTMSN=0 2022-03-17T22:13:45.307Z,1647555225.307 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T22:13:47.326Z,1647555227.326 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-17T22:14:16.424Z,1647555256.424 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:14:16.424Z,1647555256.424 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:14:16.424Z,1647555256.424 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:14:16.425Z,1647555256.425 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:14:16.836Z,1647555256.836 [Default:CheckIn:D] Stopped 2022-03-17T22:14:16.836Z,1647555256.836 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:14:17.247Z,1647555257.247 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 164.640299 min 2022-03-17T22:14:17.247Z,1647555257.247 [Default:CheckIn:E] Stopped 2022-03-17T22:14:17.247Z,1647555257.247 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:14:17.247Z,1647555257.247 [Default:CheckIn] Stopped 2022-03-17T22:14:17.247Z,1647555257.247 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:14:17.247Z,1647555257.247 [Default:CheckIn](INFO): Running loop #30 2022-03-17T22:14:17.247Z,1647555257.247 [Default:CheckIn] Running Loop=30 2022-03-17T22:14:17.248Z,1647555257.248 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:14:17.248Z,1647555257.248 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:14:19.244Z,1647555259.244 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221421.00,A,3648.17405,N,12147.27789,W,0.039,0.00,170322,,,A*79 2022-03-17T22:14:19.246Z,1647555259.246 [NAL9602](INFO): GPS fix at 20220317T221421: (36.802901, -121.787965) 2022-03-17T22:14:19.290Z,1647555259.290 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:14:19.290Z,1647555259.290 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:14:27.232Z,1647555267.232 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20220317T195407/Courier0075.lzma 2022-03-17T22:14:28.235Z,1647555268.235 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0075.lzma.bak 2022-03-17T22:14:28.235Z,1647555268.235 [DataOverHttps](INFO): SBD MOMSN=16577587 2022-03-17T22:14:44.147Z,1647555284.147 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20220317T195407/Express0076.lzma 2022-03-17T22:14:45.163Z,1647555285.163 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0076.lzma.bak 2022-03-17T22:14:45.163Z,1647555285.163 [DataOverHttps](INFO): SBD MOMSN=16577590 2022-03-17T22:14:46.724Z,1647555286.724 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:14:46.724Z,1647555286.724 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:14:46.752Z,1647555286.752 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:14:51.618Z,1647555291.618 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T22:19:47.412Z,1647555587.412 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:19:47.412Z,1647555587.412 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:19:47.436Z,1647555587.436 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:19:47.437Z,1647555587.437 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:19:47.822Z,1647555587.822 [Default:CheckIn:D] Stopped 2022-03-17T22:19:47.822Z,1647555587.822 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:19:48.220Z,1647555588.220 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 170.156738 min 2022-03-17T22:19:48.221Z,1647555588.221 [Default:CheckIn:E] Stopped 2022-03-17T22:19:48.221Z,1647555588.221 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:19:48.221Z,1647555588.221 [Default:CheckIn] Stopped 2022-03-17T22:19:48.221Z,1647555588.221 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:19:48.221Z,1647555588.221 [Default:CheckIn](INFO): Running loop #31 2022-03-17T22:19:48.221Z,1647555588.221 [Default:CheckIn] Running Loop=31 2022-03-17T22:19:48.221Z,1647555588.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:19:48.222Z,1647555588.222 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:19:50.244Z,1647555590.244 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221952.00,A,3648.17295,N,12147.28181,W,0.194,0.00,170322,,,A*78 2022-03-17T22:19:50.246Z,1647555590.246 [NAL9602](INFO): GPS fix at 20220317T221952: (36.802883, -121.788030) 2022-03-17T22:19:50.257Z,1647555590.257 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:19:50.257Z,1647555590.257 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:19:57.872Z,1647555597.872 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220317T195407/Courier0078.lzma 2022-03-17T22:19:58.875Z,1647555598.875 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0078.lzma.bak 2022-03-17T22:19:58.875Z,1647555598.875 [DataOverHttps](INFO): SBD MOMSN=16577600 2022-03-17T22:20:14.964Z,1647555614.964 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0079.lzma 2022-03-17T22:20:15.967Z,1647555615.967 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0079.lzma.bak 2022-03-17T22:20:15.967Z,1647555615.967 [DataOverHttps](INFO): SBD MOMSN=16577606 2022-03-17T22:20:17.315Z,1647555617.315 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:20:17.315Z,1647555617.315 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:20:17.315Z,1647555617.315 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:20:20.946Z,1647555620.946 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-03-17T22:20:21.021Z,1647555621.021 [NAL9602](ERROR): received: +SBDI: 2, 32362, 2, 0, 0, 0 OK 2022-03-17T22:23:23.587Z,1647555803.587 [NAL9602](INFO): SBD MO Status=2, MOMSN=32362, MT Status=2, MTMSN=0 2022-03-17T22:23:23.587Z,1647555803.587 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T22:24:52.464Z,1647555892.464 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-17T22:25:17.930Z,1647555917.930 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:25:17.930Z,1647555917.930 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:25:17.930Z,1647555917.930 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:25:17.930Z,1647555917.930 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:25:18.342Z,1647555918.342 [Default:CheckIn:D] Stopped 2022-03-17T22:25:18.342Z,1647555918.342 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:25:18.732Z,1647555918.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 175.665397 min 2022-03-17T22:25:18.732Z,1647555918.732 [Default:CheckIn:E] Stopped 2022-03-17T22:25:18.733Z,1647555918.733 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:25:18.733Z,1647555918.733 [Default:CheckIn] Stopped 2022-03-17T22:25:18.733Z,1647555918.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:25:18.733Z,1647555918.733 [Default:CheckIn](INFO): Running loop #32 2022-03-17T22:25:18.733Z,1647555918.733 [Default:CheckIn] Running Loop=32 2022-03-17T22:25:18.733Z,1647555918.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:25:18.733Z,1647555918.733 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:25:20.748Z,1647555920.748 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222523.00,A,3648.17158,N,12147.28368,W,0.330,0.00,170322,,,A*7A 2022-03-17T22:25:20.751Z,1647555920.751 [NAL9602](INFO): GPS fix at 20220317T222523: (36.802860, -121.788061) 2022-03-17T22:25:20.762Z,1647555920.762 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:25:20.762Z,1647555920.762 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:25:28.156Z,1647555928.156 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0081.lzma 2022-03-17T22:25:29.159Z,1647555929.159 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0081.lzma.bak 2022-03-17T22:25:29.159Z,1647555929.159 [DataOverHttps](INFO): SBD MOMSN=16577620 2022-03-17T22:25:45.340Z,1647555945.340 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220317T195407/Express0082.lzma 2022-03-17T22:25:46.343Z,1647555946.343 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0082.lzma.bak 2022-03-17T22:25:46.343Z,1647555946.343 [DataOverHttps](INFO): SBD MOMSN=16577623 2022-03-17T22:25:47.841Z,1647555947.841 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:25:47.841Z,1647555947.841 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:25:47.841Z,1647555947.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:25:53.470Z,1647555953.470 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T22:30:48.445Z,1647556248.445 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:30:48.446Z,1647556248.446 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:30:48.446Z,1647556248.446 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:30:48.446Z,1647556248.446 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:30:48.845Z,1647556248.845 [Default:CheckIn:D] Stopped 2022-03-17T22:30:48.845Z,1647556248.845 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:30:49.213Z,1647556249.213 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 181.173372 min 2022-03-17T22:30:49.213Z,1647556249.213 [Default:CheckIn:E] Stopped 2022-03-17T22:30:49.213Z,1647556249.213 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:30:49.213Z,1647556249.213 [Default:CheckIn] Stopped 2022-03-17T22:30:49.213Z,1647556249.213 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:30:49.213Z,1647556249.213 [Default:CheckIn](INFO): Running loop #33 2022-03-17T22:30:49.213Z,1647556249.213 [Default:CheckIn] Running Loop=33 2022-03-17T22:30:49.214Z,1647556249.214 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:30:49.214Z,1647556249.214 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:30:51.225Z,1647556251.225 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223053.00,A,3648.17854,N,12147.27658,W,0.253,0.00,170322,,,A*71 2022-03-17T22:30:51.228Z,1647556251.228 [NAL9602](INFO): GPS fix at 20220317T223053: (36.802976, -121.787943) 2022-03-17T22:30:51.288Z,1647556251.288 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:30:51.288Z,1647556251.288 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:30:59.184Z,1647556259.184 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0084.lzma 2022-03-17T22:31:00.187Z,1647556260.187 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0084.lzma.bak 2022-03-17T22:31:00.187Z,1647556260.187 [DataOverHttps](INFO): SBD MOMSN=16577639 2022-03-17T22:31:16.109Z,1647556276.109 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0085.lzma 2022-03-17T22:31:17.111Z,1647556277.111 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0085.lzma.bak 2022-03-17T22:31:17.111Z,1647556277.111 [DataOverHttps](INFO): SBD MOMSN=16577642 2022-03-17T22:31:18.432Z,1647556278.432 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:31:18.432Z,1647556278.432 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:31:18.432Z,1647556278.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:31:22.046Z,1647556282.046 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-03-17T22:31:22.129Z,1647556282.129 [NAL9602](ERROR): received: +CSQ:0 OK362, 2, 0, 0, 0 OK 2022-03-17T22:33:15.567Z,1647556395.567 [NAL9602](INFO): SBD MO Status=2, MOMSN=32362, MT Status=2, MTMSN=0 2022-03-17T22:33:15.568Z,1647556395.568 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T22:33:53.144Z,1647556433.144 [NAL9602](INFO): SBD MO Status=1, MOMSN=32362, MT Status=0, MTMSN=0 2022-03-17T22:33:53.144Z,1647556433.144 [NAL9602](INFO): No messages in MT queue 2022-03-17T22:34:23.846Z,1647556463.846 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T22:36:19.003Z,1647556579.003 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:36:19.003Z,1647556579.003 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:36:19.003Z,1647556579.003 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:36:19.004Z,1647556579.004 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:36:19.400Z,1647556579.400 [Default:CheckIn:D] Stopped 2022-03-17T22:36:19.400Z,1647556579.400 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:36:19.808Z,1647556579.808 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 186.683024 min 2022-03-17T22:36:19.809Z,1647556579.809 [Default:CheckIn:E] Stopped 2022-03-17T22:36:19.809Z,1647556579.809 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:36:19.809Z,1647556579.809 [Default:CheckIn] Stopped 2022-03-17T22:36:19.809Z,1647556579.809 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:36:19.809Z,1647556579.809 [Default:CheckIn](INFO): Running loop #34 2022-03-17T22:36:19.809Z,1647556579.809 [Default:CheckIn] Running Loop=34 2022-03-17T22:36:19.809Z,1647556579.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:36:19.810Z,1647556579.810 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:36:21.816Z,1647556581.816 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223624.00,A,3648.17191,N,12147.28061,W,0.214,0.00,170322,,,A*77 2022-03-17T22:36:21.818Z,1647556581.818 [NAL9602](INFO): GPS fix at 20220317T223624: (36.802865, -121.788010) 2022-03-17T22:36:21.830Z,1647556581.830 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:36:21.830Z,1647556581.830 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:36:29.200Z,1647556589.200 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220317T195407/Courier0087.lzma 2022-03-17T22:36:30.203Z,1647556590.203 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0087.lzma.bak 2022-03-17T22:36:30.203Z,1647556590.203 [DataOverHttps](INFO): SBD MOMSN=16577654 2022-03-17T22:36:48.060Z,1647556608.060 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220317T195407/Express0088.lzma 2022-03-17T22:36:49.063Z,1647556609.063 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0088.lzma.bak 2022-03-17T22:36:49.063Z,1647556609.063 [DataOverHttps](INFO): SBD MOMSN=16577657 2022-03-17T22:36:50.557Z,1647556610.557 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:36:50.558Z,1647556610.558 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:36:50.558Z,1647556610.558 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:41:24.468Z,1647556884.468 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-17T22:41:51.136Z,1647556911.136 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:41:51.136Z,1647556911.136 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:41:51.136Z,1647556911.136 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:41:51.137Z,1647556911.137 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:41:51.536Z,1647556911.536 [Default:CheckIn:D] Stopped 2022-03-17T22:41:51.536Z,1647556911.536 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:41:51.949Z,1647556911.949 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 192.218620 min 2022-03-17T22:41:51.949Z,1647556911.949 [Default:CheckIn:E] Stopped 2022-03-17T22:41:51.949Z,1647556911.949 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:41:51.949Z,1647556911.949 [Default:CheckIn] Stopped 2022-03-17T22:41:51.949Z,1647556911.949 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:41:51.949Z,1647556911.949 [Default:CheckIn](INFO): Running loop #35 2022-03-17T22:41:51.950Z,1647556911.950 [Default:CheckIn] Running Loop=35 2022-03-17T22:41:51.950Z,1647556911.950 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:41:51.950Z,1647556911.950 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:41:53.956Z,1647556913.956 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224156.00,A,3648.17608,N,12147.27885,W,0.389,0.00,170322,,,A*7D 2022-03-17T22:41:53.969Z,1647556913.969 [NAL9602](INFO): GPS fix at 20220317T224156: (36.802935, -121.787981) 2022-03-17T22:41:53.981Z,1647556913.981 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:41:53.981Z,1647556913.981 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:42:01.132Z,1647556921.132 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220317T195407/Courier0090.lzma 2022-03-17T22:42:02.135Z,1647556922.135 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0090.lzma.bak 2022-03-17T22:42:02.135Z,1647556922.135 [DataOverHttps](INFO): SBD MOMSN=16577672 2022-03-17T22:42:18.143Z,1647556938.143 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20220317T195407/Express0091.lzma 2022-03-17T22:42:19.144Z,1647556939.144 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0091.lzma.bak 2022-03-17T22:42:19.144Z,1647556939.144 [DataOverHttps](INFO): SBD MOMSN=16577675 2022-03-17T22:42:20.639Z,1647556940.639 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:42:20.639Z,1647556940.639 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:42:20.640Z,1647556940.640 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:42:26.274Z,1647556946.274 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T22:47:21.204Z,1647557241.204 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:47:21.204Z,1647557241.204 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:47:21.204Z,1647557241.204 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:47:21.204Z,1647557241.204 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:47:21.613Z,1647557241.613 [Default:CheckIn:D] Stopped 2022-03-17T22:47:21.613Z,1647557241.613 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:47:22.012Z,1647557242.012 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 197.719906 min 2022-03-17T22:47:22.012Z,1647557242.012 [Default:CheckIn:E] Stopped 2022-03-17T22:47:22.013Z,1647557242.013 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:47:22.013Z,1647557242.013 [Default:CheckIn] Stopped 2022-03-17T22:47:22.013Z,1647557242.013 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:47:22.013Z,1647557242.013 [Default:CheckIn](INFO): Running loop #36 2022-03-17T22:47:22.013Z,1647557242.013 [Default:CheckIn] Running Loop=36 2022-03-17T22:47:22.013Z,1647557242.013 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:47:22.013Z,1647557242.013 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:47:24.031Z,1647557244.031 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224726.00,A,3648.17401,N,12147.27743,W,0.603,0.00,170322,,,A*75 2022-03-17T22:47:24.033Z,1647557244.033 [NAL9602](INFO): GPS fix at 20220317T224726: (36.802900, -121.787957) 2022-03-17T22:47:24.044Z,1647557244.044 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:47:24.044Z,1647557244.044 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:47:31.568Z,1647557251.568 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0093.lzma 2022-03-17T22:47:32.571Z,1647557252.571 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0093.lzma.bak 2022-03-17T22:47:32.571Z,1647557252.571 [DataOverHttps](INFO): SBD MOMSN=16577686 2022-03-17T22:47:48.664Z,1647557268.664 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0094.lzma 2022-03-17T22:47:49.667Z,1647557269.667 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0094.lzma.bak 2022-03-17T22:47:49.667Z,1647557269.667 [DataOverHttps](INFO): SBD MOMSN=16577689 2022-03-17T22:47:51.150Z,1647557271.150 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:47:51.150Z,1647557271.150 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:47:51.150Z,1647557271.150 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:52:07.276Z,1647557527.276 [NAL9602](INFO): SBD MO Status=1, MOMSN=32363, MT Status=0, MTMSN=0 2022-03-17T22:52:07.276Z,1647557527.276 [NAL9602](INFO): No messages in MT queue 2022-03-17T22:52:37.982Z,1647557557.982 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T22:52:51.780Z,1647557571.780 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:52:51.780Z,1647557571.780 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:52:51.780Z,1647557571.780 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:52:51.781Z,1647557571.781 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:52:52.200Z,1647557572.200 [Default:CheckIn:D] Stopped 2022-03-17T22:52:52.200Z,1647557572.200 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:52:52.613Z,1647557572.613 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 203.229704 min 2022-03-17T22:52:52.613Z,1647557572.613 [Default:CheckIn:E] Stopped 2022-03-17T22:52:52.613Z,1647557572.613 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:52:52.613Z,1647557572.613 [Default:CheckIn] Stopped 2022-03-17T22:52:52.613Z,1647557572.613 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:52:52.614Z,1647557572.614 [Default:CheckIn](INFO): Running loop #37 2022-03-17T22:52:52.614Z,1647557572.614 [Default:CheckIn] Running Loop=37 2022-03-17T22:52:52.614Z,1647557572.614 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:52:52.614Z,1647557572.614 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:52:54.604Z,1647557574.604 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225257.00,A,3648.18109,N,12147.28154,W,0.836,83.80,170322,,,A*41 2022-03-17T22:52:54.607Z,1647557574.607 [NAL9602](INFO): GPS fix at 20220317T225257: (36.803018, -121.788026) 2022-03-17T22:52:54.669Z,1647557574.669 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:52:54.669Z,1647557574.669 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:53:02.336Z,1647557582.336 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0096.lzma 2022-03-17T22:53:03.339Z,1647557583.339 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0096.lzma.bak 2022-03-17T22:53:03.339Z,1647557583.339 [DataOverHttps](INFO): SBD MOMSN=16577709 2022-03-17T22:53:17.636Z,1647557597.636 [NAL9602](INFO): SBD MO Status=2, MOMSN=32364, MT Status=2, MTMSN=0 2022-03-17T22:53:17.636Z,1647557597.636 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T22:53:19.453Z,1647557599.453 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220317T195407/Express0097.lzma 2022-03-17T22:53:20.455Z,1647557600.455 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0097.lzma.bak 2022-03-17T22:53:20.455Z,1647557600.455 [DataOverHttps](INFO): SBD MOMSN=16577712 2022-03-17T22:53:21.682Z,1647557601.682 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:53:21.682Z,1647557601.682 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:53:21.682Z,1647557601.682 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:57:57.214Z,1647557877.214 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-17T22:58:22.280Z,1647557902.280 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T22:58:22.280Z,1647557902.280 [Default:CheckIn:C.Wait] Stopped 2022-03-17T22:58:22.280Z,1647557902.280 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T22:58:22.281Z,1647557902.281 [Default:CheckIn:D] Running Loop=1 2022-03-17T22:58:22.674Z,1647557902.674 [Default:CheckIn:D] Stopped 2022-03-17T22:58:22.674Z,1647557902.674 [Default:CheckIn:E] Running Loop=1 2022-03-17T22:58:23.072Z,1647557903.072 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 208.737598 min 2022-03-17T22:58:23.072Z,1647557903.072 [Default:CheckIn:E] Stopped 2022-03-17T22:58:23.072Z,1647557903.072 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T22:58:23.072Z,1647557903.072 [Default:CheckIn] Stopped 2022-03-17T22:58:23.072Z,1647557903.072 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T22:58:23.073Z,1647557903.073 [Default:CheckIn](INFO): Running loop #38 2022-03-17T22:58:23.073Z,1647557903.073 [Default:CheckIn] Running Loop=38 2022-03-17T22:58:23.073Z,1647557903.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T22:58:23.073Z,1647557903.073 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T22:58:25.091Z,1647557905.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225827.00,A,3648.18240,N,12147.28266,W,0.641,181.23,170322,,,A*74 2022-03-17T22:58:25.094Z,1647557905.094 [NAL9602](INFO): GPS fix at 20220317T225827: (36.803040, -121.788044) 2022-03-17T22:58:25.105Z,1647557905.105 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T22:58:25.105Z,1647557905.105 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T22:58:32.892Z,1647557912.892 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0099.lzma 2022-03-17T22:58:33.895Z,1647557913.895 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0099.lzma.bak 2022-03-17T22:58:33.895Z,1647557913.895 [DataOverHttps](INFO): SBD MOMSN=16577724 2022-03-17T22:58:52.726Z,1647557932.726 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20220317T195407/Express0100.lzma 2022-03-17T22:58:53.727Z,1647557933.727 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0100.lzma.bak 2022-03-17T22:58:53.727Z,1647557933.727 [DataOverHttps](INFO): SBD MOMSN=16577727 2022-03-17T22:58:55.052Z,1647557935.052 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T22:58:55.053Z,1647557935.053 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T22:58:55.053Z,1647557935.053 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T22:58:57.462Z,1647557937.462 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T23:03:55.628Z,1647558235.628 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T23:03:55.628Z,1647558235.628 [Default:CheckIn:C.Wait] Stopped 2022-03-17T23:03:55.628Z,1647558235.628 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T23:03:55.628Z,1647558235.628 [Default:CheckIn:D] Running Loop=1 2022-03-17T23:03:56.032Z,1647558236.032 [Default:CheckIn:D] Stopped 2022-03-17T23:03:56.032Z,1647558236.032 [Default:CheckIn:E] Running Loop=1 2022-03-17T23:03:56.445Z,1647558236.445 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 214.293571 min 2022-03-17T23:03:56.445Z,1647558236.445 [Default:CheckIn:E] Stopped 2022-03-17T23:03:56.445Z,1647558236.445 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T23:03:56.445Z,1647558236.445 [Default:CheckIn] Stopped 2022-03-17T23:03:56.446Z,1647558236.446 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T23:03:56.446Z,1647558236.446 [Default:CheckIn](INFO): Running loop #39 2022-03-17T23:03:56.446Z,1647558236.446 [Default:CheckIn] Running Loop=39 2022-03-17T23:03:56.446Z,1647558236.446 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T23:03:56.446Z,1647558236.446 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T23:03:58.452Z,1647558238.452 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230400.00,A,3648.16887,N,12147.28094,W,0.058,357.67,170322,,,A*7E 2022-03-17T23:03:58.465Z,1647558238.465 [NAL9602](INFO): GPS fix at 20220317T230400: (36.802814, -121.788016) 2022-03-17T23:03:58.476Z,1647558238.476 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T23:03:58.476Z,1647558238.476 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T23:04:05.832Z,1647558245.832 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20220317T195407/Courier0102.lzma 2022-03-17T23:04:06.835Z,1647558246.835 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0102.lzma.bak 2022-03-17T23:04:06.835Z,1647558246.835 [DataOverHttps](INFO): SBD MOMSN=16577744 2022-03-17T23:04:22.840Z,1647558262.840 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20220317T195407/Express0103.lzma 2022-03-17T23:04:23.843Z,1647558263.843 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0103.lzma.bak 2022-03-17T23:04:23.843Z,1647558263.843 [DataOverHttps](INFO): SBD MOMSN=16577747 2022-03-17T23:04:25.129Z,1647558265.129 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T23:04:25.129Z,1647558265.129 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T23:04:25.129Z,1647558265.129 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T23:08:28.321Z,1647558508.321 [NAL9602](INFO): SBD MO Status=2, MOMSN=32364, MT Status=2, MTMSN=0 2022-03-17T23:08:28.322Z,1647558508.322 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-03-17T23:09:00.242Z,1647558540.242 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-17T23:09:25.709Z,1647558565.709 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T23:09:25.709Z,1647558565.709 [Default:CheckIn:C.Wait] Stopped 2022-03-17T23:09:25.709Z,1647558565.709 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T23:09:25.710Z,1647558565.710 [Default:CheckIn:D] Running Loop=1 2022-03-17T23:09:26.112Z,1647558566.112 [Default:CheckIn:D] Stopped 2022-03-17T23:09:26.112Z,1647558566.112 [Default:CheckIn:E] Running Loop=1 2022-03-17T23:09:26.549Z,1647558566.549 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 219.794906 min 2022-03-17T23:09:26.549Z,1647558566.549 [Default:CheckIn:E] Stopped 2022-03-17T23:09:26.549Z,1647558566.549 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T23:09:26.549Z,1647558566.549 [Default:CheckIn] Stopped 2022-03-17T23:09:26.549Z,1647558566.549 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T23:09:26.549Z,1647558566.549 [Default:CheckIn](INFO): Running loop #40 2022-03-17T23:09:26.549Z,1647558566.549 [Default:CheckIn] Running Loop=40 2022-03-17T23:09:26.550Z,1647558566.550 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T23:09:26.550Z,1647558566.550 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T23:09:28.520Z,1647558568.520 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230930.00,A,3648.17450,N,12147.27708,W,0.739,218.23,170322,,,A*70 2022-03-17T23:09:28.522Z,1647558568.522 [NAL9602](INFO): GPS fix at 20220317T230930: (36.802908, -121.787951) 2022-03-17T23:09:28.533Z,1647558568.533 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T23:09:28.533Z,1647558568.533 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T23:09:35.816Z,1647558575.816 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0105.lzma 2022-03-17T23:09:36.819Z,1647558576.819 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0105.lzma.bak 2022-03-17T23:09:36.819Z,1647558576.819 [DataOverHttps](INFO): SBD MOMSN=16577756 2022-03-17T23:09:52.785Z,1647558592.785 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220317T195407/Express0106.lzma 2022-03-17T23:09:53.787Z,1647558593.787 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0106.lzma.bak 2022-03-17T23:09:53.787Z,1647558593.787 [DataOverHttps](INFO): SBD MOMSN=16577759 2022-03-17T23:09:55.269Z,1647558595.269 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T23:09:55.269Z,1647558595.269 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T23:09:55.269Z,1647558595.269 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T23:10:01.297Z,1647558601.297 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T23:14:55.831Z,1647558895.831 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T23:14:55.831Z,1647558895.831 [Default:CheckIn:C.Wait] Stopped 2022-03-17T23:14:55.831Z,1647558895.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T23:14:55.831Z,1647558895.831 [Default:CheckIn:D] Running Loop=1 2022-03-17T23:14:56.240Z,1647558896.240 [Default:CheckIn:D] Stopped 2022-03-17T23:14:56.240Z,1647558896.240 [Default:CheckIn:E] Running Loop=1 2022-03-17T23:14:56.650Z,1647558896.650 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 225.297038 min 2022-03-17T23:14:56.650Z,1647558896.650 [Default:CheckIn:E] Stopped 2022-03-17T23:14:56.650Z,1647558896.650 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T23:14:56.650Z,1647558896.650 [Default:CheckIn] Stopped 2022-03-17T23:14:56.651Z,1647558896.651 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T23:14:56.651Z,1647558896.651 [Default:CheckIn](INFO): Running loop #41 2022-03-17T23:14:56.651Z,1647558896.651 [Default:CheckIn] Running Loop=41 2022-03-17T23:14:56.651Z,1647558896.651 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T23:14:56.651Z,1647558896.651 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T23:14:58.648Z,1647558898.648 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231501.00,A,3648.17586,N,12147.27791,W,0.369,218.23,170322,,,A*74 2022-03-17T23:14:58.651Z,1647558898.651 [NAL9602](INFO): GPS fix at 20220317T231501: (36.802931, -121.787965) 2022-03-17T23:14:58.662Z,1647558898.662 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T23:14:58.662Z,1647558898.662 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T23:15:06.076Z,1647558906.076 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0108.lzma 2022-03-17T23:15:07.079Z,1647558907.079 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0108.lzma.bak 2022-03-17T23:15:07.079Z,1647558907.079 [DataOverHttps](INFO): SBD MOMSN=16577777 2022-03-17T23:15:23.060Z,1647558923.060 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0109.lzma 2022-03-17T23:15:24.063Z,1647558924.063 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0109.lzma.bak 2022-03-17T23:15:24.063Z,1647558924.063 [DataOverHttps](INFO): SBD MOMSN=16577780 2022-03-17T23:15:25.329Z,1647558925.329 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T23:15:25.329Z,1647558925.329 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T23:15:25.329Z,1647558925.329 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T23:15:29.350Z,1647558929.350 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-03-17T23:15:29.433Z,1647558929.433 [NAL9602](ERROR): received: +CSQ:0 OK364, 2, 0, 0, 0 OK 2022-03-17T23:16:50.964Z,1647559010.964 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-03-17T23:16:50.967Z,1647559010.967 [BPC1](INFO): Received data from all battery sticks. 2022-03-17T23:20:01.242Z,1647559201.242 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-17T23:20:25.896Z,1647559225.896 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T23:20:25.897Z,1647559225.897 [Default:CheckIn:C.Wait] Stopped 2022-03-17T23:20:25.897Z,1647559225.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T23:20:25.897Z,1647559225.897 [Default:CheckIn:D] Running Loop=1 2022-03-17T23:20:26.306Z,1647559226.306 [Default:CheckIn:D] Stopped 2022-03-17T23:20:26.306Z,1647559226.306 [Default:CheckIn:E] Running Loop=1 2022-03-17T23:20:26.711Z,1647559226.711 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 230.798128 min 2022-03-17T23:20:26.711Z,1647559226.711 [Default:CheckIn:E] Stopped 2022-03-17T23:20:26.711Z,1647559226.711 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T23:20:26.712Z,1647559226.712 [Default:CheckIn] Stopped 2022-03-17T23:20:26.712Z,1647559226.712 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T23:20:26.712Z,1647559226.712 [Default:CheckIn](INFO): Running loop #42 2022-03-17T23:20:26.712Z,1647559226.712 [Default:CheckIn] Running Loop=42 2022-03-17T23:20:26.712Z,1647559226.712 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T23:20:26.712Z,1647559226.712 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T23:20:28.716Z,1647559228.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232031.00,A,3648.16772,N,12147.27768,W,0.330,4.27,170322,,,A*78 2022-03-17T23:20:28.719Z,1647559228.719 [NAL9602](INFO): GPS fix at 20220317T232031: (36.802795, -121.787961) 2022-03-17T23:20:28.729Z,1647559228.729 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T23:20:28.730Z,1647559228.730 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T23:20:36.676Z,1647559236.676 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20220317T195407/Courier0111.lzma 2022-03-17T23:20:37.959Z,1647559237.959 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0111.lzma.bak 2022-03-17T23:20:37.959Z,1647559237.959 [DataOverHttps](INFO): SBD MOMSN=16577790 2022-03-17T23:20:56.957Z,1647559256.957 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20220317T195407/Express0112.lzma 2022-03-17T23:20:57.959Z,1647559257.959 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0112.lzma.bak 2022-03-17T23:20:57.959Z,1647559257.959 [DataOverHttps](INFO): SBD MOMSN=16577793 2022-03-17T23:20:59.078Z,1647559259.078 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T23:20:59.079Z,1647559259.079 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T23:20:59.079Z,1647559259.079 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T23:21:01.482Z,1647559261.482 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T23:25:59.663Z,1647559559.663 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T23:25:59.663Z,1647559559.663 [Default:CheckIn:C.Wait] Stopped 2022-03-17T23:25:59.664Z,1647559559.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T23:25:59.664Z,1647559559.664 [Default:CheckIn:D] Running Loop=1 2022-03-17T23:26:00.054Z,1647559560.054 [Default:CheckIn:D] Stopped 2022-03-17T23:26:00.054Z,1647559560.054 [Default:CheckIn:E] Running Loop=1 2022-03-17T23:26:00.461Z,1647559560.461 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 236.360612 min 2022-03-17T23:26:00.461Z,1647559560.461 [Default:CheckIn:E] Stopped 2022-03-17T23:26:00.461Z,1647559560.461 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T23:26:00.461Z,1647559560.461 [Default:CheckIn] Stopped 2022-03-17T23:26:00.461Z,1647559560.461 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T23:26:00.461Z,1647559560.461 [Default:CheckIn](INFO): Running loop #43 2022-03-17T23:26:00.461Z,1647559560.461 [Default:CheckIn] Running Loop=43 2022-03-17T23:26:00.462Z,1647559560.462 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T23:26:00.462Z,1647559560.462 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T23:26:02.476Z,1647559562.476 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232604.00,A,3648.16850,N,12147.28070,W,0.816,260.59,170322,,,A*70 2022-03-17T23:26:02.478Z,1647559562.478 [NAL9602](INFO): GPS fix at 20220317T232604: (36.802808, -121.788012) 2022-03-17T23:26:02.489Z,1647559562.489 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T23:26:02.489Z,1647559562.489 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T23:26:10.372Z,1647559570.372 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220317T195407/Courier0114.lzma 2022-03-17T23:26:11.375Z,1647559571.375 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0114.lzma.bak 2022-03-17T23:26:11.375Z,1647559571.375 [DataOverHttps](INFO): SBD MOMSN=16577809 2022-03-17T23:26:27.601Z,1647559587.601 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220317T195407/Express0115.lzma 2022-03-17T23:26:28.603Z,1647559588.603 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0115.lzma.bak 2022-03-17T23:26:28.603Z,1647559588.603 [DataOverHttps](INFO): SBD MOMSN=16577812 2022-03-17T23:26:29.969Z,1647559589.969 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T23:26:29.969Z,1647559589.969 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T23:26:29.969Z,1647559589.969 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T23:26:33.170Z,1647559593.170 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-03-17T23:26:33.245Z,1647559593.245 [NAL9602](ERROR): received: +CSQ:0 OK364, 2, 0, 0, 0 OK 2022-03-17T23:31:05.070Z,1647559865.070 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-17T23:31:30.542Z,1647559890.542 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T23:31:30.542Z,1647559890.542 [Default:CheckIn:C.Wait] Stopped 2022-03-17T23:31:30.543Z,1647559890.543 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T23:31:30.543Z,1647559890.543 [Default:CheckIn:D] Running Loop=1 2022-03-17T23:31:30.936Z,1647559890.936 [Default:CheckIn:D] Stopped 2022-03-17T23:31:30.936Z,1647559890.936 [Default:CheckIn:E] Running Loop=1 2022-03-17T23:31:31.345Z,1647559891.345 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 241.875293 min 2022-03-17T23:31:31.345Z,1647559891.345 [Default:CheckIn:E] Stopped 2022-03-17T23:31:31.345Z,1647559891.345 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T23:31:31.345Z,1647559891.345 [Default:CheckIn] Stopped 2022-03-17T23:31:31.346Z,1647559891.346 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T23:31:31.346Z,1647559891.346 [Default:CheckIn](INFO): Running loop #44 2022-03-17T23:31:31.346Z,1647559891.346 [Default:CheckIn] Running Loop=44 2022-03-17T23:31:31.346Z,1647559891.346 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T23:31:31.346Z,1647559891.346 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T23:31:33.351Z,1647559893.351 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233135.00,A,3648.16357,N,12147.28244,W,0.564,260.59,170322,,,A*75 2022-03-17T23:31:33.354Z,1647559893.354 [NAL9602](INFO): GPS fix at 20220317T233135: (36.802726, -121.788041) 2022-03-17T23:31:33.393Z,1647559893.393 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T23:31:33.393Z,1647559893.393 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T23:31:41.264Z,1647559901.264 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0117.lzma 2022-03-17T23:31:42.283Z,1647559902.283 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0117.lzma.bak 2022-03-17T23:31:42.283Z,1647559902.283 [DataOverHttps](INFO): SBD MOMSN=16577821 2022-03-17T23:31:58.157Z,1647559918.157 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20220317T195407/Express0118.lzma 2022-03-17T23:31:59.159Z,1647559919.159 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0118.lzma.bak 2022-03-17T23:31:59.159Z,1647559919.159 [DataOverHttps](INFO): SBD MOMSN=16577824 2022-03-17T23:32:00.442Z,1647559920.442 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T23:32:00.442Z,1647559920.442 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T23:32:00.443Z,1647559920.443 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T23:32:05.278Z,1647559925.278 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T23:37:01.018Z,1647560221.018 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T23:37:01.018Z,1647560221.018 [Default:CheckIn:C.Wait] Stopped 2022-03-17T23:37:01.019Z,1647560221.019 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T23:37:01.019Z,1647560221.019 [Default:CheckIn:D] Running Loop=1 2022-03-17T23:37:01.412Z,1647560221.412 [Default:CheckIn:D] Stopped 2022-03-17T23:37:01.412Z,1647560221.412 [Default:CheckIn:E] Running Loop=1 2022-03-17T23:37:01.813Z,1647560221.813 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 247.383236 min 2022-03-17T23:37:01.813Z,1647560221.813 [Default:CheckIn:E] Stopped 2022-03-17T23:37:01.813Z,1647560221.813 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T23:37:01.813Z,1647560221.813 [Default:CheckIn] Stopped 2022-03-17T23:37:01.813Z,1647560221.813 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T23:37:01.814Z,1647560221.814 [Default:CheckIn](INFO): Running loop #45 2022-03-17T23:37:01.814Z,1647560221.814 [Default:CheckIn] Running Loop=45 2022-03-17T23:37:01.814Z,1647560221.814 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T23:37:01.814Z,1647560221.814 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T23:37:03.831Z,1647560223.831 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233706.00,A,3648.16823,N,12147.28526,W,0.272,0.00,170322,,,A*70 2022-03-17T23:37:03.834Z,1647560223.834 [NAL9602](INFO): GPS fix at 20220317T233706: (36.802804, -121.788088) 2022-03-17T23:37:03.844Z,1647560223.844 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T23:37:03.844Z,1647560223.844 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T23:37:11.488Z,1647560231.488 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220317T195407/Courier0120.lzma 2022-03-17T23:37:12.491Z,1647560232.491 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0120.lzma.bak 2022-03-17T23:37:12.491Z,1647560232.491 [DataOverHttps](INFO): SBD MOMSN=16577841 2022-03-17T23:37:28.632Z,1647560248.632 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20220317T195407/Express0121.lzma 2022-03-17T23:37:29.635Z,1647560249.635 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0121.lzma.bak 2022-03-17T23:37:29.635Z,1647560249.635 [DataOverHttps](INFO): SBD MOMSN=16577844 2022-03-17T23:37:30.911Z,1647560250.911 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T23:37:30.911Z,1647560250.911 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T23:37:30.911Z,1647560250.911 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T23:38:18.973Z,1647560298.973 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-03-17T23:38:18.973Z,1647560298.973 [DropWeight] Hardware Fault, FailCount= 1 2022-03-17T23:38:18.973Z,1647560298.973 [DropWeight](ERROR): Hardware Fault 2022-03-17T23:38:18.992Z,1647560298.992 [CommandExec](FAULT): Scheduling is paused 2022-03-17T23:38:18.993Z,1647560298.993 [CBIT](INFO): Critical error at 20220317T233818 2022-03-17T23:38:18.995Z,1647560298.995 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-03-17T23:38:18.996Z,1647560298.996 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-03-17T23:38:19.405Z,1647560299.405 [CBIT](INFO): Critical error at 20220317T233818 2022-03-17T23:38:43.647Z,1647560323.647 [CBIT](IMPORTANT): Beginning ground fault scan 2022-03-17T23:38:54.585Z,1647560334.585 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.002163 CHAN A1 (24V): -0.180847 CHAN A2 (12V): -0.001968 CHAN A3 (5V): -0.001359 CHAN B0 (3.3V): -0.000195 CHAN B1 (3.15aV): 0.000031 CHAN B2 (3.15bV): -0.000279 CHAN B3 (GND): -0.000144 OPEN: -0.000401 Full Scale: +/- 1 mA 2022-03-17T23:42:06.017Z,1647560526.017 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-17T23:42:31.488Z,1647560551.488 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T23:42:31.488Z,1647560551.488 [Default:CheckIn:C.Wait] Stopped 2022-03-17T23:42:31.488Z,1647560551.488 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T23:42:31.488Z,1647560551.488 [Default:CheckIn:D] Running Loop=1 2022-03-17T23:42:31.884Z,1647560551.884 [Default:CheckIn:D] Stopped 2022-03-17T23:42:31.884Z,1647560551.884 [Default:CheckIn:E] Running Loop=1 2022-03-17T23:42:32.288Z,1647560552.288 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 252.891097 min 2022-03-17T23:42:32.288Z,1647560552.288 [Default:CheckIn:E] Stopped 2022-03-17T23:42:32.288Z,1647560552.288 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T23:42:32.288Z,1647560552.288 [Default:CheckIn] Stopped 2022-03-17T23:42:32.288Z,1647560552.288 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T23:42:32.289Z,1647560552.289 [Default:CheckIn](INFO): Running loop #46 2022-03-17T23:42:32.289Z,1647560552.289 [Default:CheckIn] Running Loop=46 2022-03-17T23:42:32.289Z,1647560552.289 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T23:42:32.289Z,1647560552.289 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T23:42:34.303Z,1647560554.303 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234236.00,A,3648.17806,N,12147.27660,W,2.274,176.79,170322,,,A*73 2022-03-17T23:42:34.306Z,1647560554.306 [NAL9602](INFO): GPS fix at 20220317T234236: (36.802968, -121.787943) 2022-03-17T23:42:34.323Z,1647560554.323 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T23:42:34.323Z,1647560554.323 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-17T23:42:42.088Z,1647560562.088 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20220317T195407/Courier0123.lzma 2022-03-17T23:42:43.091Z,1647560563.091 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0123.lzma.bak 2022-03-17T23:42:43.091Z,1647560563.091 [DataOverHttps](INFO): SBD MOMSN=16577853 2022-03-17T23:43:01.941Z,1647560581.941 [DataOverHttps](INFO): Sending 427 bytes from file Logs/20220317T195407/Express0124.lzma 2022-03-17T23:43:02.943Z,1647560582.943 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0124.lzma.bak 2022-03-17T23:43:02.943Z,1647560582.943 [DataOverHttps](INFO): SBD MOMSN=16577858 2022-03-17T23:43:04.248Z,1647560584.248 [Default:CheckIn:Read_Iridium] Stopped 2022-03-17T23:43:04.249Z,1647560584.249 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-17T23:43:04.249Z,1647560584.249 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-17T23:43:06.257Z,1647560586.257 [NAL9602](INFO): Not Powering down - fast GPS 2022-03-17T23:48:04.832Z,1647560884.832 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-17T23:48:04.832Z,1647560884.832 [Default:CheckIn:C.Wait] Stopped 2022-03-17T23:48:04.832Z,1647560884.832 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-17T23:48:04.833Z,1647560884.833 [Default:CheckIn:D] Running Loop=1 2022-03-17T23:48:05.249Z,1647560885.249 [Default:CheckIn:D] Stopped 2022-03-17T23:48:05.249Z,1647560885.249 [Default:CheckIn:E] Running Loop=1 2022-03-17T23:48:05.632Z,1647560885.632 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 258.447168 min 2022-03-17T23:48:05.633Z,1647560885.633 [Default:CheckIn:E] Stopped 2022-03-17T23:48:05.633Z,1647560885.633 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-17T23:48:05.633Z,1647560885.633 [Default:CheckIn] Stopped 2022-03-17T23:48:05.633Z,1647560885.633 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T23:48:05.633Z,1647560885.633 [Default:CheckIn](INFO): Running loop #47 2022-03-17T23:48:05.633Z,1647560885.633 [Default:CheckIn] Running Loop=47 2022-03-17T23:48:05.633Z,1647560885.633 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-17T23:48:05.633Z,1647560885.633 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-17T23:52:40.413Z,1647561160.413 [CommandExec](IMPORTANT): got command quit 2022-03-17T23:52:41.417Z,1647561161.417 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-03-17T23:52:41.417Z,1647561161.417 [CommandExec](INFO): Uninitializing the command executive. 2022-03-17T23:52:41.417Z,1647561161.417 [CommandExec](INFO): Uninitializing the command scheduler. 2022-03-17T23:52:41.418Z,1647561161.418 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:41.505Z,1647561161.505 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-03-17T23:52:41.506Z,1647561161.506 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-03-17T23:52:41.506Z,1647561161.506 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:41.507Z,1647561161.507 [NavChartDb](INFO): Join timeout helper Thread ID is 9284 2022-03-17T23:52:41.801Z,1647561161.801 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-03-17T23:52:41.801Z,1647561161.801 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:41.821Z,1647561161.821 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-03-17T23:52:41.821Z,1647561161.821 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:41.821Z,1647561161.821 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9285 2022-03-17T23:52:42.169Z,1647561162.169 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-03-17T23:52:42.170Z,1647561162.170 [WetLabsBB2FL](INFO): Powering down 2022-03-17T23:52:42.170Z,1647561162.170 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:42.189Z,1647561162.189 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-03-17T23:52:42.189Z,1647561162.189 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:42.189Z,1647561162.189 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9286 2022-03-17T23:52:42.981Z,1647561162.981 [CTD_Seabird](INFO): Powering down 2022-03-17T23:52:42.993Z,1647561162.993 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-03-17T23:52:42.993Z,1647561162.993 [CTD_Seabird](INFO): Powering down 2022-03-17T23:52:43.017Z,1647561163.017 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:43.037Z,1647561163.037 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-03-17T23:52:43.037Z,1647561163.037 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:43.037Z,1647561163.037 [Radio_Surface](INFO): Join timeout helper Thread ID is 9287 2022-03-17T23:52:43.109Z,1647561163.109 [Radio_Surface](INFO): Powering down 2022-03-17T23:52:43.110Z,1647561163.110 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-03-17T23:52:43.111Z,1647561163.111 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:43.117Z,1647561163.117 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-03-17T23:52:43.117Z,1647561163.117 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:43.117Z,1647561163.117 [Onboard](INFO): Join timeout helper Thread ID is 9288 2022-03-17T23:52:43.385Z,1647561163.385 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-03-17T23:52:46.225Z,1647561166.225 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-03-17T23:52:46.225Z,1647561166.225 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:46.243Z,1647561166.243 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-03-17T23:52:46.243Z,1647561166.243 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:46.243Z,1647561166.243 [DataOverHttps](INFO): Join timeout helper Thread ID is 9289 2022-03-17T23:52:46.397Z,1647561166.397 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-03-17T23:52:46.397Z,1647561166.397 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:46.405Z,1647561166.405 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-03-17T23:52:46.405Z,1647561166.405 [logger ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:46.406Z,1647561166.406 [logger](INFO): Join timeout helper Thread ID is 9290 2022-03-17T23:52:46.437Z,1647561166.437 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-03-17T23:52:46.437Z,1647561166.437 [logger ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:46.445Z,1647561166.445 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-03-17T23:52:46.445Z,1647561166.445 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:46.446Z,1647561166.446 [CommandLine](INFO): Join timeout helper Thread ID is 9291 2022-03-17T23:52:46.525Z,1647561166.525 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-03-17T23:52:46.525Z,1647561166.525 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:46.545Z,1647561166.545 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-03-17T23:52:46.545Z,1647561166.545 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:46.546Z,1647561166.546 [CommandExec](INFO): Join timeout helper Thread ID is 9292 2022-03-17T23:52:46.547Z,1647561166.547 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-03-17T23:52:46.547Z,1647561166.547 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:46.548Z,1647561166.548 [controlThread](INFO): Join timeout helper Thread ID is 9293 2022-03-17T23:52:46.853Z,1647561166.853 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2022-03-17T23:52:46.853Z,1647561166.853 [controlThread](DEBUG): Uninitializing ControlThread 2022-03-17T23:52:46.853Z,1647561166.853 [AHRS_M2](INFO): Powering down 2022-03-17T23:52:46.926Z,1647561166.926 [NAL9602](INFO): Powering down 2022-03-17T23:52:46.928Z,1647561166.928 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2022-03-17T23:52:46.929Z,1647561166.929 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2022-03-17T23:52:46.930Z,1647561166.930 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2022-03-17T23:52:46.930Z,1647561166.930 [MissionManager](INFO): Uninitializing Mission Default 2022-03-17T23:52:46.931Z,1647561166.931 [Default] Stopped 2022-03-17T23:52:46.931Z,1647561166.931 [Default](DEBUG): Aggregate::uninitialize Default 2022-03-17T23:52:46.931Z,1647561166.931 [Default:B.GoToSurface] Stopped 2022-03-17T23:52:46.931Z,1647561166.931 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-03-17T23:52:46.931Z,1647561166.931 [Default:CheckIn] Stopped 2022-03-17T23:52:46.931Z,1647561166.931 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-17T23:52:46.931Z,1647561166.931 [Default:CheckIn:Read_GPS] Stopped 2022-03-17T23:52:46.934Z,1647561166.934 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2022-03-17T23:52:46.935Z,1647561166.935 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2022-03-17T23:52:46.935Z,1647561166.935 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2022-03-17T23:52:46.935Z,1647561166.935 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2022-03-17T23:52:46.936Z,1647561166.936 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-03-17T23:52:46.936Z,1647561166.936 [BuoyancyServo](INFO): Powering down 2022-03-17T23:52:46.949Z,1647561166.949 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-03-17T23:52:46.949Z,1647561166.949 [ElevatorServo](INFO): Powering down 2022-03-17T23:52:46.950Z,1647561166.950 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-03-17T23:52:46.950Z,1647561166.950 [MassServo](INFO): Powering down 2022-03-17T23:52:46.951Z,1647561166.951 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-03-17T23:52:46.951Z,1647561166.951 [RudderServo](INFO): Powering down 2022-03-17T23:52:46.952Z,1647561166.952 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2022-03-17T23:52:46.952Z,1647561166.952 [ThrusterServo](INFO): Powering down 2022-03-17T23:52:46.953Z,1647561166.953 [SBIT](DEBUG): Uninitialize SBIT Component. 2022-03-17T23:52:46.953Z,1647561166.953 [IBIT](DEBUG): Uninitialize IBIT Component. 2022-03-17T23:52:46.954Z,1647561166.954 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-03-17T23:52:46.954Z,1647561166.954 [CBIT](DEBUG): Powering off loads. 2022-03-17T23:52:46.965Z,1647561166.965 [CBIT](DEBUG): Disabling WDT. 2022-03-17T23:52:46.976Z,1647561166.976 [CBIT](DEBUG): Opening all GF detection circuits. 2022-03-17T23:52:46.977Z,1647561166.977 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:47.023Z,1647561167.023 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:47.026Z,1647561167.026 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:47.033Z,1647561167.033 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:47.106Z,1647561167.106 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:47.110Z,1647561167.110 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:47.155Z,1647561167.155 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-03-17T23:52:47.267Z,1647561167.267 [logger ThreadHandler](INFO): Thread cancelled.