2023-06-09T15:40:02.853Z,1686325202.853 [CommandExec](IMPORTANT): got command restart logs 2023-06-09T15:42:16.946Z,1686325336.946 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T15:42:16.947Z,1686325336.947 [Default:CheckIn:C.Wait] Stopped 2023-06-09T15:42:16.947Z,1686325336.947 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T15:42:16.947Z,1686325336.947 [Default:CheckIn:D] Running Loop=1 2023-06-09T15:42:17.352Z,1686325337.352 [Default:CheckIn:D] Stopped 2023-06-09T15:42:17.352Z,1686325337.352 [Default:CheckIn:E] Running Loop=1 2023-06-09T15:42:17.749Z,1686325337.749 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.949272 min 2023-06-09T15:42:17.750Z,1686325337.750 [Default:CheckIn:E] Stopped 2023-06-09T15:42:17.750Z,1686325337.750 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T15:42:17.750Z,1686325337.750 [Default:CheckIn] Stopped 2023-06-09T15:42:17.750Z,1686325337.750 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T15:42:17.750Z,1686325337.750 [Default:CheckIn](INFO): Running loop #3 2023-06-09T15:42:17.750Z,1686325337.750 [Default:CheckIn] Running Loop=3 2023-06-09T15:42:17.750Z,1686325337.750 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T15:42:17.750Z,1686325337.750 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T15:42:19.766Z,1686325339.766 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154218.00,A,3647.77404,N,12150.75080,W,5.229,42.82,090623,,,D*47 2023-06-09T15:42:19.768Z,1686325339.768 [NAL9602](INFO): GPS fix at 20230609T154218: (36.796234, -121.845847) 2023-06-09T15:42:19.778Z,1686325339.778 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T15:42:19.778Z,1686325339.778 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T15:42:52.016Z,1686325372.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:43:18.303Z,1686325398.303 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230606T165943/Courier0291.lzma 2023-06-09T15:43:19.305Z,1686325399.305 [DataOverHttps](INFO): Moved sent file to Logs/20230606T165943/Courier0291.lzma.bak 2023-06-09T15:43:19.306Z,1686325399.306 [DataOverHttps](INFO): SBD MOMSN=18402789 2023-06-09T15:43:50.904Z,1686325430.904 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-06-09T15:44:22.028Z,1686325462.028 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:44:39.202Z,1686325479.202 [NAL9602](INFO): SBD MO Status=0, MOMSN=43875, MT Status=0, MTMSN=0 2023-06-09T15:44:39.202Z,1686325479.202 [NAL9602](INFO): No messages in MT queue 2023-06-09T15:44:53.152Z,1686325493.152 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:45:09.903Z,1686325509.903 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T15:45:24.284Z,1686325524.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:45:55.412Z,1686325555.412 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:46:19.508Z,1686325579.508 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.203384 2023-06-09T15:46:42.061Z,1686325602.061 [NAL9602](INFO): SBD MO Status=1, MOMSN=43876, MT Status=0, MTMSN=0 2023-06-09T15:46:42.116Z,1686325602.116 [NAL9602](INFO): Sent 268 bytes from file Logs/20230609T154002/Courier0000.lzma 2023-06-09T15:46:42.116Z,1686325602.116 [NAL9602](INFO): Packets left to send: 0 2023-06-09T15:46:49.402Z,1686325609.402 [NAL9602](INFO): SBD MO Status=1, MOMSN=43877, MT Status=0, MTMSN=0 2023-06-09T15:46:49.483Z,1686325609.483 [NAL9602](INFO): Sent 104 bytes from file Logs/20230606T165943/Express0292.lzma 2023-06-09T15:46:49.483Z,1686325609.483 [NAL9602](INFO): Packets left to send: 0 2023-06-09T15:46:50.632Z,1686325610.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:46:58.330Z,1686325618.330 [NAL9602](INFO): SBD MO Status=1, MOMSN=43878, MT Status=0, MTMSN=0 2023-06-09T15:46:58.383Z,1686325618.383 [NAL9602](INFO): Sent 332 bytes from file Logs/20230609T154002/Express0001.lzma 2023-06-09T15:46:58.384Z,1686325618.384 [NAL9602](INFO): Packets left to send: 1 2023-06-09T15:47:07.702Z,1686325627.702 [NAL9602](INFO): SBD MO Status=1, MOMSN=43879, MT Status=0, MTMSN=0 2023-06-09T15:47:07.760Z,1686325627.760 [NAL9602](INFO): Sent 58 bytes from file Logs/20230609T154002/Express0001.lzma 2023-06-09T15:47:07.760Z,1686325627.760 [NAL9602](INFO): Packets left to send: 0 2023-06-09T15:47:15.022Z,1686325635.022 [NAL9602](INFO): SBD MO Status=1, MOMSN=43880, MT Status=0, MTMSN=0 2023-06-09T15:47:15.076Z,1686325635.076 [NAL9602](INFO): Sent 61 bytes from file Logs/20230609T154002/Express0004.lzma 2023-06-09T15:47:15.076Z,1686325635.076 [NAL9602](INFO): Packets left to send: 0 2023-06-09T15:47:21.764Z,1686325641.764 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:47:23.510Z,1686325643.510 [NAL9602](INFO): SBD MO Status=0, MOMSN=43881, MT Status=0, MTMSN=0 2023-06-09T15:47:23.615Z,1686325643.615 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T15:47:23.615Z,1686325643.615 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T15:47:23.615Z,1686325643.615 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T15:47:38.224Z,1686325658.224 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003201 2023-06-09T15:47:54.208Z,1686325674.208 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T15:49:25.168Z,1686325765.168 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:49:56.292Z,1686325796.292 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:50:27.415Z,1686325827.415 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:51:01.626Z,1686325861.626 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=181.022095 2023-06-09T15:52:02.788Z,1686325922.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:52:24.190Z,1686325944.190 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T15:52:24.190Z,1686325944.190 [Default:CheckIn:C.Wait] Stopped 2023-06-09T15:52:24.190Z,1686325944.190 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T15:52:24.190Z,1686325944.190 [Default:CheckIn:D] Running Loop=1 2023-06-09T15:52:24.589Z,1686325944.589 [Default:CheckIn:D] Stopped 2023-06-09T15:52:24.589Z,1686325944.589 [Default:CheckIn:E] Running Loop=1 2023-06-09T15:52:25.007Z,1686325945.007 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.069885 min 2023-06-09T15:52:25.007Z,1686325945.007 [Default:CheckIn:E] Stopped 2023-06-09T15:52:25.007Z,1686325945.007 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T15:52:25.007Z,1686325945.007 [Default:CheckIn] Stopped 2023-06-09T15:52:25.008Z,1686325945.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T15:52:25.008Z,1686325945.008 [Default:CheckIn](INFO): Running loop #4 2023-06-09T15:52:25.008Z,1686325945.008 [Default:CheckIn] Running Loop=4 2023-06-09T15:52:25.008Z,1686325945.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T15:52:25.008Z,1686325945.008 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T15:52:27.007Z,1686325947.007 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155225.00,A,3648.52101,N,12149.07701,W,0.700,199.07,090623,,,D*72 2023-06-09T15:52:27.009Z,1686325947.009 [NAL9602](INFO): GPS fix at 20230609T155225: (36.808684, -121.817950) 2023-06-09T15:52:27.028Z,1686325947.028 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T15:52:27.028Z,1686325947.028 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T15:52:33.912Z,1686325953.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:53:03.184Z,1686325983.184 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003162 2023-06-09T15:53:05.899Z,1686325985.899 [NAL9602](INFO): SBD MO Status=2, MOMSN=43882, MT Status=2, MTMSN=0 2023-06-09T15:53:05.899Z,1686325985.899 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T15:53:10.467Z,1686325990.467 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230609T154002/Courier0006.lzma 2023-06-09T15:53:11.469Z,1686325991.469 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0006.lzma.bak 2023-06-09T15:53:11.469Z,1686325991.469 [DataOverHttps](INFO): SBD MOMSN=18402806 2023-06-09T15:53:27.451Z,1686326007.451 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230609T154002/Express0007.lzma 2023-06-09T15:53:28.453Z,1686326008.453 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0007.lzma.bak 2023-06-09T15:53:28.453Z,1686326008.453 [DataOverHttps](INFO): SBD MOMSN=18402809 2023-06-09T15:53:29.741Z,1686326009.741 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T15:53:29.741Z,1686326009.741 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T15:53:29.741Z,1686326009.741 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T15:53:36.595Z,1686326016.595 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T15:56:31.844Z,1686326191.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T15:58:30.327Z,1686326310.327 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T15:58:30.327Z,1686326310.327 [Default:CheckIn:C.Wait] Stopped 2023-06-09T15:58:30.327Z,1686326310.327 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T15:58:30.327Z,1686326310.327 [Default:CheckIn:D] Running Loop=1 2023-06-09T15:58:30.727Z,1686326310.727 [Default:CheckIn:D] Stopped 2023-06-09T15:58:30.727Z,1686326310.727 [Default:CheckIn:E] Running Loop=1 2023-06-09T15:58:31.123Z,1686326311.123 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.172188 min 2023-06-09T15:58:31.123Z,1686326311.123 [Default:CheckIn:E] Stopped 2023-06-09T15:58:31.123Z,1686326311.123 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T15:58:31.124Z,1686326311.124 [Default:CheckIn] Stopped 2023-06-09T15:58:31.124Z,1686326311.124 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T15:58:31.124Z,1686326311.124 [Default:CheckIn](INFO): Running loop #5 2023-06-09T15:58:31.124Z,1686326311.124 [Default:CheckIn] Running Loop=5 2023-06-09T15:58:31.124Z,1686326311.124 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T15:58:31.124Z,1686326311.124 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T15:58:33.145Z,1686326313.145 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155831.00,A,3648.39239,N,12148.60122,W,5.676,100.00,090623,,,D*7D 2023-06-09T15:58:33.147Z,1686326313.147 [NAL9602](INFO): GPS fix at 20230609T155831: (36.806540, -121.810020) 2023-06-09T15:58:33.157Z,1686326313.157 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T15:58:33.157Z,1686326313.157 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T15:58:40.875Z,1686326320.875 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0009.lzma 2023-06-09T15:58:41.877Z,1686326321.877 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0009.lzma.bak 2023-06-09T15:58:41.877Z,1686326321.877 [DataOverHttps](INFO): SBD MOMSN=18402812 2023-06-09T15:58:59.035Z,1686326339.035 [NAL9602](INFO): SBD MO Status=2, MOMSN=43882, MT Status=2, MTMSN=0 2023-06-09T15:58:59.036Z,1686326339.036 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T15:59:00.671Z,1686326340.671 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230609T154002/Express0010.lzma 2023-06-09T15:59:09.219Z,1686326349.219 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230609T154002/Express0010.lzma 2023-06-09T15:59:10.221Z,1686326350.221 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0010.lzma.bak 2023-06-09T15:59:10.221Z,1686326350.221 [DataOverHttps](INFO): SBD MOMSN=18402815 2023-06-09T15:59:11.567Z,1686326351.567 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T15:59:11.567Z,1686326351.567 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T15:59:11.567Z,1686326351.567 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:00:14.978Z,1686326414.978 [NAL9602](INFO): SBD MO Status=2, MOMSN=43882, MT Status=2, MTMSN=0 2023-06-09T16:00:14.978Z,1686326414.978 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:02:13.355Z,1686326533.355 [NAL9602](INFO): SBD MO Status=2, MOMSN=43882, MT Status=2, MTMSN=0 2023-06-09T16:02:13.356Z,1686326533.356 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:02:28.706Z,1686326548.706 [NAL9602](INFO): SBD MO Status=2, MOMSN=43882, MT Status=2, MTMSN=0 2023-06-09T16:02:28.706Z,1686326548.706 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:02:55.802Z,1686326575.802 [NAL9602](INFO): SBD MO Status=1, MOMSN=43882, MT Status=0, MTMSN=0 2023-06-09T16:02:55.802Z,1686326575.802 [NAL9602](INFO): No messages in MT queue 2023-06-09T16:03:26.512Z,1686326606.512 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T16:04:12.174Z,1686326652.174 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:04:12.174Z,1686326652.174 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:04:12.174Z,1686326652.174 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:04:12.174Z,1686326652.174 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:04:12.582Z,1686326652.582 [Default:CheckIn:D] Stopped 2023-06-09T16:04:12.582Z,1686326652.582 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:04:12.989Z,1686326652.989 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.869767 min 2023-06-09T16:04:12.990Z,1686326652.990 [Default:CheckIn:E] Stopped 2023-06-09T16:04:12.990Z,1686326652.990 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:04:12.990Z,1686326652.990 [Default:CheckIn] Stopped 2023-06-09T16:04:12.990Z,1686326652.990 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:04:12.990Z,1686326652.990 [Default:CheckIn](INFO): Running loop #6 2023-06-09T16:04:12.990Z,1686326652.990 [Default:CheckIn] Running Loop=6 2023-06-09T16:04:12.990Z,1686326652.990 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:04:12.990Z,1686326652.990 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:04:14.994Z,1686326654.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160413.00,A,3648.27158,N,12148.02054,W,5.132,108.56,090623,,,D*74 2023-06-09T16:04:14.996Z,1686326654.996 [NAL9602](INFO): GPS fix at 20230609T160413: (36.804526, -121.800342) 2023-06-09T16:04:15.030Z,1686326655.030 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:04:15.030Z,1686326655.030 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:04:22.099Z,1686326662.099 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0012.lzma 2023-06-09T16:04:23.101Z,1686326663.101 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0012.lzma.bak 2023-06-09T16:04:23.101Z,1686326663.101 [DataOverHttps](INFO): SBD MOMSN=18402818 2023-06-09T16:04:39.127Z,1686326679.127 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230609T154002/Express0013.lzma 2023-06-09T16:04:40.129Z,1686326680.129 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0013.lzma.bak 2023-06-09T16:04:40.129Z,1686326680.129 [DataOverHttps](INFO): SBD MOMSN=18402821 2023-06-09T16:04:41.267Z,1686326681.267 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:04:41.268Z,1686326681.268 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:04:41.268Z,1686326681.268 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:05:01.050Z,1686326701.050 [NAL9602](INFO): SBD MO Status=2, MOMSN=43883, MT Status=2, MTMSN=0 2023-06-09T16:05:01.050Z,1686326701.050 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:05:12.362Z,1686326712.362 [NAL9602](INFO): SBD MO Status=1, MOMSN=43883, MT Status=0, MTMSN=0 2023-06-09T16:05:12.362Z,1686326712.362 [NAL9602](INFO): No messages in MT queue 2023-06-09T16:05:43.060Z,1686326743.060 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T16:09:41.864Z,1686326981.864 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:09:41.864Z,1686326981.864 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:09:41.864Z,1686326981.864 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:09:41.864Z,1686326981.864 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:09:42.274Z,1686326982.274 [Default:CheckIn:D] Stopped 2023-06-09T16:09:42.274Z,1686326982.274 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:09:42.674Z,1686326982.674 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.364640 min 2023-06-09T16:09:42.674Z,1686326982.674 [Default:CheckIn:E] Stopped 2023-06-09T16:09:42.674Z,1686326982.674 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:09:42.674Z,1686326982.674 [Default:CheckIn] Stopped 2023-06-09T16:09:42.674Z,1686326982.674 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:09:42.675Z,1686326982.675 [Default:CheckIn](INFO): Running loop #7 2023-06-09T16:09:42.675Z,1686326982.675 [Default:CheckIn] Running Loop=7 2023-06-09T16:09:42.675Z,1686326982.675 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:09:42.675Z,1686326982.675 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:09:44.696Z,1686326984.696 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160943.00,A,3648.36291,N,12147.44677,W,5.346,57.12,090623,,,D*4A 2023-06-09T16:09:44.699Z,1686326984.699 [NAL9602](INFO): GPS fix at 20230609T160943: (36.806049, -121.790779) 2023-06-09T16:09:44.709Z,1686326984.709 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:09:44.709Z,1686326984.709 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:09:51.659Z,1686326991.659 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0015.lzma 2023-06-09T16:09:52.661Z,1686326992.661 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0015.lzma.bak 2023-06-09T16:09:52.661Z,1686326992.661 [DataOverHttps](INFO): SBD MOMSN=18402828 2023-06-09T16:10:06.506Z,1686327006.506 [NAL9602](INFO): SBD MO Status=1, MOMSN=43884, MT Status=0, MTMSN=0 2023-06-09T16:10:06.506Z,1686327006.506 [NAL9602](INFO): No messages in MT queue 2023-06-09T16:10:08.387Z,1686327008.387 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230609T154002/Express0016.lzma 2023-06-09T16:10:09.389Z,1686327009.389 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0016.lzma.bak 2023-06-09T16:10:09.389Z,1686327009.389 [DataOverHttps](INFO): SBD MOMSN=18402831 2023-06-09T16:10:10.549Z,1686327010.549 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:10:10.549Z,1686327010.549 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:10:10.550Z,1686327010.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:10:37.203Z,1686327037.203 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T16:13:35.014Z,1686327215.014 [CBIT](IMPORTANT): Beginning ground fault scan 2023-06-09T16:13:37.824Z,1686327217.824 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-06-09T16:13:45.912Z,1686327225.912 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002344 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.002332 CHAN A3 (5V): -0.001960 CHAN B0 (3.3V): -0.000234 CHAN B1 (3.15aV): -0.000275 CHAN B2 (3.15bV): -0.000107 CHAN B3 (GND): 0.000149 OPEN: -0.000512 Full Scale: +/- 1 mA 2023-06-09T16:15:11.187Z,1686327311.187 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:15:11.191Z,1686327311.191 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:15:11.191Z,1686327311.191 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:15:11.192Z,1686327311.192 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:15:11.582Z,1686327311.582 [Default:CheckIn:D] Stopped 2023-06-09T16:15:11.582Z,1686327311.582 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:15:12.001Z,1686327312.001 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.853109 min 2023-06-09T16:15:12.001Z,1686327312.001 [Default:CheckIn:E] Stopped 2023-06-09T16:15:12.001Z,1686327312.001 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:15:12.001Z,1686327312.001 [Default:CheckIn] Stopped 2023-06-09T16:15:12.002Z,1686327312.002 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:15:12.002Z,1686327312.002 [Default:CheckIn](INFO): Running loop #8 2023-06-09T16:15:12.002Z,1686327312.002 [Default:CheckIn] Running Loop=8 2023-06-09T16:15:12.002Z,1686327312.002 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:15:12.002Z,1686327312.002 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:15:13.994Z,1686327313.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161512.00,A,3648.51814,N,12147.16121,W,3.324,100.56,090623,,,D*77 2023-06-09T16:15:13.996Z,1686327313.996 [NAL9602](INFO): GPS fix at 20230609T161512: (36.808636, -121.786020) 2023-06-09T16:15:14.006Z,1686327314.006 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:15:14.007Z,1686327314.007 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:15:20.899Z,1686327320.899 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230609T154002/Courier0018.lzma 2023-06-09T16:15:21.901Z,1686327321.901 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0018.lzma.bak 2023-06-09T16:15:21.901Z,1686327321.901 [DataOverHttps](INFO): SBD MOMSN=18402836 2023-06-09T16:15:36.214Z,1686327336.214 [NAL9602](INFO): SBD MO Status=2, MOMSN=43885, MT Status=2, MTMSN=0 2023-06-09T16:15:36.214Z,1686327336.214 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:15:37.715Z,1686327337.715 [DataOverHttps](INFO): Sending 348 bytes from file Logs/20230609T154002/Express0019.lzma 2023-06-09T16:15:38.717Z,1686327338.717 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0019.lzma.bak 2023-06-09T16:15:38.717Z,1686327338.717 [DataOverHttps](INFO): SBD MOMSN=18402839 2023-06-09T16:15:39.880Z,1686327339.880 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:15:39.880Z,1686327339.880 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:15:39.881Z,1686327339.881 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:16:50.549Z,1686327410.549 [NAL9602](INFO): SBD MO Status=1, MOMSN=43885, MT Status=0, MTMSN=0 2023-06-09T16:16:50.549Z,1686327410.549 [NAL9602](INFO): No messages in MT queue 2023-06-09T16:17:21.256Z,1686327441.256 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T16:20:40.468Z,1686327640.468 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:20:40.469Z,1686327640.469 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:20:40.469Z,1686327640.469 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:20:40.469Z,1686327640.469 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:20:40.858Z,1686327640.858 [Default:CheckIn:D] Stopped 2023-06-09T16:20:40.859Z,1686327640.859 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:20:41.275Z,1686327641.275 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.341048 min 2023-06-09T16:20:41.275Z,1686327641.275 [Default:CheckIn:E] Stopped 2023-06-09T16:20:41.275Z,1686327641.275 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:20:41.275Z,1686327641.275 [Default:CheckIn] Stopped 2023-06-09T16:20:41.275Z,1686327641.275 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:20:41.275Z,1686327641.275 [Default:CheckIn](INFO): Running loop #9 2023-06-09T16:20:41.276Z,1686327641.276 [Default:CheckIn] Running Loop=9 2023-06-09T16:20:41.276Z,1686327641.276 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:20:41.276Z,1686327641.276 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:20:43.280Z,1686327643.280 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162042.00,A,3648.24848,N,12147.14172,W,3.091,192.78,090623,,,D*71 2023-06-09T16:20:43.282Z,1686327643.282 [NAL9602](INFO): GPS fix at 20230609T162042: (36.804141, -121.785695) 2023-06-09T16:20:43.308Z,1686327643.308 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:20:43.308Z,1686327643.308 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:20:53.343Z,1686327653.343 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0021.lzma 2023-06-09T16:20:54.345Z,1686327654.345 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0021.lzma.bak 2023-06-09T16:20:54.345Z,1686327654.345 [DataOverHttps](INFO): SBD MOMSN=18402847 2023-06-09T16:21:11.204Z,1686327671.204 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230609T154002/Express0022.lzma 2023-06-09T16:21:12.208Z,1686327672.208 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0022.lzma.bak 2023-06-09T16:21:12.208Z,1686327672.208 [DataOverHttps](INFO): SBD MOMSN=18402850 2023-06-09T16:21:13.630Z,1686327673.630 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:21:13.631Z,1686327673.631 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:21:13.631Z,1686327673.631 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:23:22.180Z,1686327802.180 [NAL9602](INFO): SBD MO Status=1, MOMSN=43886, MT Status=0, MTMSN=0 2023-06-09T16:23:22.181Z,1686327802.181 [NAL9602](INFO): No messages in MT queue 2023-06-09T16:23:52.888Z,1686327832.888 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T16:26:14.350Z,1686327974.350 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:26:14.350Z,1686327974.350 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:26:14.350Z,1686327974.350 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:26:14.350Z,1686327974.350 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:26:14.766Z,1686327974.766 [Default:CheckIn:D] Stopped 2023-06-09T16:26:14.766Z,1686327974.766 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:26:15.162Z,1686327975.162 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.906177 min 2023-06-09T16:26:15.162Z,1686327975.162 [Default:CheckIn:E] Stopped 2023-06-09T16:26:15.162Z,1686327975.162 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:26:15.162Z,1686327975.162 [Default:CheckIn] Stopped 2023-06-09T16:26:15.162Z,1686327975.162 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:26:15.163Z,1686327975.163 [Default:CheckIn](INFO): Running loop #10 2023-06-09T16:26:15.163Z,1686327975.163 [Default:CheckIn] Running Loop=10 2023-06-09T16:26:15.163Z,1686327975.163 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:26:15.163Z,1686327975.163 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:26:17.174Z,1686327977.174 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162615.00,A,3648.13321,N,12147.20844,W,0.039,245.20,090623,,,D*7B 2023-06-09T16:26:17.177Z,1686327977.177 [NAL9602](INFO): GPS fix at 20230609T162615: (36.802220, -121.786807) 2023-06-09T16:26:17.187Z,1686327977.187 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:26:17.187Z,1686327977.187 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:26:24.911Z,1686327984.911 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0024.lzma 2023-06-09T16:26:25.913Z,1686327985.913 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0024.lzma.bak 2023-06-09T16:26:25.913Z,1686327985.913 [DataOverHttps](INFO): SBD MOMSN=18402856 2023-06-09T16:26:41.739Z,1686328001.739 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230609T154002/Express0025.lzma 2023-06-09T16:26:42.741Z,1686328002.741 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0025.lzma.bak 2023-06-09T16:26:42.742Z,1686328002.742 [DataOverHttps](INFO): SBD MOMSN=18402859 2023-06-09T16:26:43.846Z,1686328003.846 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:26:43.846Z,1686328003.846 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:26:43.846Z,1686328003.846 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:27:34.362Z,1686328054.362 [NAL9602](INFO): SBD MO Status=2, MOMSN=43887, MT Status=2, MTMSN=0 2023-06-09T16:27:34.362Z,1686328054.362 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:28:08.333Z,1686328088.333 [NAL9602](INFO): SBD MO Status=1, MOMSN=43887, MT Status=0, MTMSN=0 2023-06-09T16:28:08.333Z,1686328088.333 [NAL9602](INFO): No messages in MT queue 2023-06-09T16:28:39.004Z,1686328119.004 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T16:31:44.630Z,1686328304.630 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:31:44.631Z,1686328304.631 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:31:44.631Z,1686328304.631 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:31:44.631Z,1686328304.631 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:31:45.030Z,1686328305.030 [Default:CheckIn:D] Stopped 2023-06-09T16:31:45.030Z,1686328305.030 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:31:45.434Z,1686328305.434 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.410567 min 2023-06-09T16:31:45.434Z,1686328305.434 [Default:CheckIn:E] Stopped 2023-06-09T16:31:45.435Z,1686328305.435 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:31:45.435Z,1686328305.435 [Default:CheckIn] Stopped 2023-06-09T16:31:45.435Z,1686328305.435 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:31:45.435Z,1686328305.435 [Default:CheckIn](INFO): Running loop #11 2023-06-09T16:31:45.435Z,1686328305.435 [Default:CheckIn] Running Loop=11 2023-06-09T16:31:45.435Z,1686328305.435 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:31:45.435Z,1686328305.435 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:31:47.450Z,1686328307.450 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163146.00,A,3648.13243,N,12147.20894,W,0.039,245.20,090623,,,D*73 2023-06-09T16:31:47.452Z,1686328307.452 [NAL9602](INFO): GPS fix at 20230609T163146: (36.802207, -121.786816) 2023-06-09T16:31:47.462Z,1686328307.462 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:31:47.462Z,1686328307.462 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:31:55.371Z,1686328315.371 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0027.lzma 2023-06-09T16:31:56.373Z,1686328316.373 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0027.lzma.bak 2023-06-09T16:31:56.373Z,1686328316.373 [DataOverHttps](INFO): SBD MOMSN=18402865 2023-06-09T16:32:12.119Z,1686328332.119 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230609T154002/Express0028.lzma 2023-06-09T16:32:13.121Z,1686328333.121 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0028.lzma.bak 2023-06-09T16:32:13.121Z,1686328333.121 [DataOverHttps](INFO): SBD MOMSN=18402868 2023-06-09T16:32:14.519Z,1686328334.519 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:32:14.519Z,1686328334.519 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:32:14.519Z,1686328334.519 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:36:42.853Z,1686328602.853 [NAL9602](INFO): SBD MO Status=2, MOMSN=43888, MT Status=2, MTMSN=0 2023-06-09T16:36:42.853Z,1686328602.853 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:36:50.124Z,1686328610.124 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-09T16:37:15.273Z,1686328635.273 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:37:15.273Z,1686328635.273 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:37:15.273Z,1686328635.273 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:37:15.274Z,1686328635.274 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:37:15.647Z,1686328635.647 [Default:CheckIn:D] Stopped 2023-06-09T16:37:15.647Z,1686328635.647 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:37:16.067Z,1686328636.067 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.920858 min 2023-06-09T16:37:16.067Z,1686328636.067 [Default:CheckIn:E] Stopped 2023-06-09T16:37:16.067Z,1686328636.067 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:37:16.067Z,1686328636.067 [Default:CheckIn] Stopped 2023-06-09T16:37:16.067Z,1686328636.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:37:16.067Z,1686328636.067 [Default:CheckIn](INFO): Running loop #12 2023-06-09T16:37:16.068Z,1686328636.068 [Default:CheckIn] Running Loop=12 2023-06-09T16:37:16.068Z,1686328636.068 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:37:16.068Z,1686328636.068 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:37:18.062Z,1686328638.062 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163716.00,A,3648.13259,N,12147.20884,W,0.117,245.20,090623,,,D*77 2023-06-09T16:37:18.064Z,1686328638.064 [NAL9602](INFO): GPS fix at 20230609T163716: (36.802210, -121.786814) 2023-06-09T16:37:18.102Z,1686328638.102 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:37:18.102Z,1686328638.102 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:37:25.823Z,1686328645.823 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230609T154002/Courier0030.lzma 2023-06-09T16:37:26.825Z,1686328646.825 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0030.lzma.bak 2023-06-09T16:37:26.825Z,1686328646.825 [DataOverHttps](INFO): SBD MOMSN=18402871 2023-06-09T16:37:42.855Z,1686328662.855 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20230609T154002/Express0031.lzma 2023-06-09T16:37:43.858Z,1686328663.858 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0031.lzma.bak 2023-06-09T16:37:43.858Z,1686328663.858 [DataOverHttps](INFO): SBD MOMSN=18402874 2023-06-09T16:37:45.144Z,1686328665.144 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:37:45.144Z,1686328665.144 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:37:45.144Z,1686328665.144 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:37:49.972Z,1686328669.972 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T16:39:00.322Z,1686328740.322 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T16:39:15.673Z,1686328755.673 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T16:39:30.622Z,1686328770.622 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T16:39:45.973Z,1686328785.973 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T16:40:00.921Z,1686328800.921 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T16:40:16.273Z,1686328816.273 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T16:40:31.224Z,1686328831.224 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T16:40:46.573Z,1686328846.573 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T16:41:02.381Z,1686328862.381 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T16:41:17.329Z,1686328877.329 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T16:41:23.803Z,1686328883.803 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5. 2023-06-09T16:41:23.806Z,1686328883.806 [BPC1](INFO): Received data from all battery sticks. 2023-06-09T16:42:45.827Z,1686328965.827 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:42:45.827Z,1686328965.827 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:42:45.827Z,1686328965.827 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:42:45.827Z,1686328965.827 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:42:46.223Z,1686328966.223 [Default:CheckIn:D] Stopped 2023-06-09T16:42:46.223Z,1686328966.223 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:42:46.634Z,1686328966.634 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.430452 min 2023-06-09T16:42:46.634Z,1686328966.634 [Default:CheckIn:E] Stopped 2023-06-09T16:42:46.634Z,1686328966.634 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:42:46.634Z,1686328966.634 [Default:CheckIn] Stopped 2023-06-09T16:42:46.635Z,1686328966.635 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:42:46.635Z,1686328966.635 [Default:CheckIn](INFO): Running loop #13 2023-06-09T16:42:46.635Z,1686328966.635 [Default:CheckIn] Running Loop=13 2023-06-09T16:42:46.635Z,1686328966.635 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:42:46.635Z,1686328966.635 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:42:48.637Z,1686328968.637 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164247.00,A,3648.14964,N,12147.22070,W,0.039,221.19,090623,,,D*77 2023-06-09T16:42:48.640Z,1686328968.640 [NAL9602](INFO): GPS fix at 20230609T164247: (36.802494, -121.787012) 2023-06-09T16:42:48.658Z,1686328968.658 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:42:48.658Z,1686328968.658 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:42:58.339Z,1686328978.339 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230609T154002/Courier0033.lzma 2023-06-09T16:42:59.341Z,1686328979.341 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0033.lzma.bak 2023-06-09T16:42:59.341Z,1686328979.341 [DataOverHttps](INFO): SBD MOMSN=18402881 2023-06-09T16:43:15.219Z,1686328995.219 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20230609T154002/Express0034.lzma 2023-06-09T16:43:16.221Z,1686328996.221 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0034.lzma.bak 2023-06-09T16:43:16.221Z,1686328996.221 [DataOverHttps](INFO): SBD MOMSN=18402884 2023-06-09T16:43:17.394Z,1686328997.394 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:43:17.394Z,1686328997.394 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:43:17.394Z,1686328997.394 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:43:19.397Z,1686328999.397 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-06-09T16:43:19.471Z,1686328999.471 [NAL9602](ERROR): received: +CSQ:0 OK888, 2, 0, 0, 0 OK 2023-06-09T16:43:48.082Z,1686329028.082 [NAL9602](INFO): SBD MO Status=2, MOMSN=43888, MT Status=2, MTMSN=0 2023-06-09T16:43:48.082Z,1686329028.082 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:44:28.082Z,1686329068.082 [NAL9602](INFO): SBD MO Status=2, MOMSN=43888, MT Status=2, MTMSN=0 2023-06-09T16:44:28.082Z,1686329068.082 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:44:47.874Z,1686329087.874 [NAL9602](INFO): SBD MO Status=2, MOMSN=43888, MT Status=2, MTMSN=0 2023-06-09T16:44:47.874Z,1686329087.874 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:45:32.358Z,1686329132.358 [NAL9602](INFO): SBD MO Status=2, MOMSN=43888, MT Status=2, MTMSN=0 2023-06-09T16:45:32.358Z,1686329132.358 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:46:32.554Z,1686329192.554 [NAL9602](INFO): SBD MO Status=1, MOMSN=43888, MT Status=0, MTMSN=0 2023-06-09T16:46:32.554Z,1686329192.554 [NAL9602](INFO): No messages in MT queue 2023-06-09T16:47:03.266Z,1686329223.266 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T16:48:18.030Z,1686329298.030 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:48:18.030Z,1686329298.030 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:48:18.030Z,1686329298.030 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:48:18.031Z,1686329298.031 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:48:18.425Z,1686329298.425 [Default:CheckIn:D] Stopped 2023-06-09T16:48:18.425Z,1686329298.425 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:48:18.830Z,1686329298.830 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.967147 min 2023-06-09T16:48:18.830Z,1686329298.830 [Default:CheckIn:E] Stopped 2023-06-09T16:48:18.830Z,1686329298.830 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:48:18.830Z,1686329298.830 [Default:CheckIn] Stopped 2023-06-09T16:48:18.830Z,1686329298.830 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:48:18.830Z,1686329298.830 [Default:CheckIn](INFO): Running loop #14 2023-06-09T16:48:18.830Z,1686329298.830 [Default:CheckIn] Running Loop=14 2023-06-09T16:48:18.830Z,1686329298.830 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:48:18.831Z,1686329298.831 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:48:20.846Z,1686329300.846 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164819.00,A,3648.14989,N,12147.22218,W,0.739,200.87,090623,,,D*7A 2023-06-09T16:48:20.848Z,1686329300.848 [NAL9602](INFO): GPS fix at 20230609T164819: (36.802498, -121.787036) 2023-06-09T16:48:20.881Z,1686329300.881 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:48:20.881Z,1686329300.881 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:48:28.071Z,1686329308.071 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0036.lzma 2023-06-09T16:48:29.073Z,1686329309.073 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0036.lzma.bak 2023-06-09T16:48:29.073Z,1686329309.073 [DataOverHttps](INFO): SBD MOMSN=18402888 2023-06-09T16:48:45.427Z,1686329325.427 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230609T154002/Express0037.lzma 2023-06-09T16:48:46.429Z,1686329326.429 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0037.lzma.bak 2023-06-09T16:48:46.429Z,1686329326.429 [DataOverHttps](INFO): SBD MOMSN=18402892 2023-06-09T16:48:47.931Z,1686329327.931 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:48:47.931Z,1686329327.931 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:48:47.931Z,1686329327.931 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:49:01.694Z,1686329341.694 [NAL9602](INFO): SBD MO Status=2, MOMSN=43889, MT Status=2, MTMSN=0 2023-06-09T16:49:01.695Z,1686329341.695 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:49:57.450Z,1686329397.450 [NAL9602](INFO): SBD MO Status=1, MOMSN=43889, MT Status=0, MTMSN=0 2023-06-09T16:49:57.450Z,1686329397.450 [NAL9602](INFO): No messages in MT queue 2023-06-09T16:50:28.149Z,1686329428.149 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T16:53:48.618Z,1686329628.618 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:53:48.618Z,1686329628.618 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:53:48.618Z,1686329628.618 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:53:48.618Z,1686329628.618 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:53:49.034Z,1686329629.034 [Default:CheckIn:D] Stopped 2023-06-09T16:53:49.034Z,1686329629.034 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:53:49.430Z,1686329629.430 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.477311 min 2023-06-09T16:53:49.430Z,1686329629.430 [Default:CheckIn:E] Stopped 2023-06-09T16:53:49.430Z,1686329629.430 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:53:49.430Z,1686329629.430 [Default:CheckIn] Stopped 2023-06-09T16:53:49.430Z,1686329629.430 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:53:49.431Z,1686329629.431 [Default:CheckIn](INFO): Running loop #15 2023-06-09T16:53:49.431Z,1686329629.431 [Default:CheckIn] Running Loop=15 2023-06-09T16:53:49.431Z,1686329629.431 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:53:49.431Z,1686329629.431 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:53:51.442Z,1686329631.442 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165350.00,A,3648.14145,N,12147.22878,W,0.019,146.58,090623,,,D*7F 2023-06-09T16:53:51.445Z,1686329631.445 [NAL9602](INFO): GPS fix at 20230609T165350: (36.802357, -121.787146) 2023-06-09T16:53:51.455Z,1686329631.455 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:53:51.455Z,1686329631.455 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:53:58.975Z,1686329638.975 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230609T154002/Courier0039.lzma 2023-06-09T16:53:59.989Z,1686329639.989 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0039.lzma.bak 2023-06-09T16:53:59.989Z,1686329639.989 [DataOverHttps](INFO): SBD MOMSN=18402901 2023-06-09T16:54:15.763Z,1686329655.763 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230609T154002/Express0040.lzma 2023-06-09T16:54:16.765Z,1686329656.765 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0040.lzma.bak 2023-06-09T16:54:16.765Z,1686329656.765 [DataOverHttps](INFO): SBD MOMSN=18402904 2023-06-09T16:54:18.116Z,1686329658.116 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:54:18.116Z,1686329658.116 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:54:18.116Z,1686329658.116 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T16:54:25.778Z,1686329665.778 [NAL9602](INFO): SBD MO Status=2, MOMSN=43890, MT Status=2, MTMSN=0 2023-06-09T16:54:25.778Z,1686329665.778 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:54:51.654Z,1686329691.654 [NAL9602](INFO): SBD MO Status=2, MOMSN=43890, MT Status=2, MTMSN=0 2023-06-09T16:54:51.654Z,1686329691.654 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T16:55:34.113Z,1686329734.113 [NAL9602](INFO): SBD MO Status=1, MOMSN=43890, MT Status=0, MTMSN=0 2023-06-09T16:55:34.113Z,1686329734.113 [NAL9602](INFO): No messages in MT queue 2023-06-09T16:56:04.820Z,1686329764.820 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T16:59:18.856Z,1686329958.856 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T16:59:18.856Z,1686329958.856 [Default:CheckIn:C.Wait] Stopped 2023-06-09T16:59:18.856Z,1686329958.856 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T16:59:18.856Z,1686329958.856 [Default:CheckIn:D] Running Loop=1 2023-06-09T16:59:19.258Z,1686329959.258 [Default:CheckIn:D] Stopped 2023-06-09T16:59:19.258Z,1686329959.258 [Default:CheckIn:E] Running Loop=1 2023-06-09T16:59:19.665Z,1686329959.665 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.981038 min 2023-06-09T16:59:19.665Z,1686329959.665 [Default:CheckIn:E] Stopped 2023-06-09T16:59:19.665Z,1686329959.665 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T16:59:19.665Z,1686329959.665 [Default:CheckIn] Stopped 2023-06-09T16:59:19.666Z,1686329959.666 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T16:59:19.666Z,1686329959.666 [Default:CheckIn](INFO): Running loop #16 2023-06-09T16:59:19.666Z,1686329959.666 [Default:CheckIn] Running Loop=16 2023-06-09T16:59:19.666Z,1686329959.666 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T16:59:19.666Z,1686329959.666 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T16:59:21.674Z,1686329961.674 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165920.00,A,3648.14060,N,12147.22917,W,0.019,146.58,090623,,,A*79 2023-06-09T16:59:21.676Z,1686329961.676 [NAL9602](INFO): GPS fix at 20230609T165920: (36.802343, -121.787153) 2023-06-09T16:59:21.686Z,1686329961.686 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T16:59:21.687Z,1686329961.687 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T16:59:29.423Z,1686329969.423 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230609T154002/Courier0042.lzma 2023-06-09T16:59:30.425Z,1686329970.425 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0042.lzma.bak 2023-06-09T16:59:30.426Z,1686329970.426 [DataOverHttps](INFO): SBD MOMSN=18402911 2023-06-09T16:59:41.061Z,1686329981.061 [NAL9602](INFO): SBD MO Status=1, MOMSN=43891, MT Status=0, MTMSN=0 2023-06-09T16:59:41.061Z,1686329981.061 [NAL9602](INFO): No messages in MT queue 2023-06-09T16:59:46.219Z,1686329986.219 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230609T154002/Express0043.lzma 2023-06-09T16:59:47.221Z,1686329987.221 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0043.lzma.bak 2023-06-09T16:59:47.221Z,1686329987.221 [DataOverHttps](INFO): SBD MOMSN=18402914 2023-06-09T16:59:48.344Z,1686329988.344 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T16:59:48.344Z,1686329988.344 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T16:59:48.344Z,1686329988.344 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:00:11.765Z,1686330011.765 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T17:04:49.008Z,1686330289.008 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:04:49.008Z,1686330289.008 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:04:49.008Z,1686330289.008 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:04:49.008Z,1686330289.008 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:04:49.401Z,1686330289.401 [Default:CheckIn:D] Stopped 2023-06-09T17:04:49.401Z,1686330289.401 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:04:49.806Z,1686330289.806 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.483423 min 2023-06-09T17:04:49.806Z,1686330289.806 [Default:CheckIn:E] Stopped 2023-06-09T17:04:49.806Z,1686330289.806 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:04:49.806Z,1686330289.806 [Default:CheckIn] Stopped 2023-06-09T17:04:49.806Z,1686330289.806 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:04:49.807Z,1686330289.807 [Default:CheckIn](INFO): Running loop #17 2023-06-09T17:04:49.807Z,1686330289.807 [Default:CheckIn] Running Loop=17 2023-06-09T17:04:49.807Z,1686330289.807 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:04:49.807Z,1686330289.807 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:04:51.818Z,1686330291.818 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170450.00,A,3648.14982,N,12147.22606,W,0.058,6.35,090623,,,D*73 2023-06-09T17:04:51.830Z,1686330291.830 [NAL9602](INFO): GPS fix at 20230609T170450: (36.802497, -121.787101) 2023-06-09T17:04:51.841Z,1686330291.841 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:04:51.841Z,1686330291.841 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T17:05:01.575Z,1686330301.575 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0045.lzma 2023-06-09T17:05:02.577Z,1686330302.577 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0045.lzma.bak 2023-06-09T17:05:02.577Z,1686330302.577 [DataOverHttps](INFO): SBD MOMSN=18402919 2023-06-09T17:05:18.323Z,1686330318.323 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230609T154002/Express0046.lzma 2023-06-09T17:05:19.325Z,1686330319.325 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0046.lzma.bak 2023-06-09T17:05:19.325Z,1686330319.325 [DataOverHttps](INFO): SBD MOMSN=18402922 2023-06-09T17:05:20.548Z,1686330320.548 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T17:05:20.548Z,1686330320.548 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T17:05:20.549Z,1686330320.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:05:37.898Z,1686330337.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=43892, MT Status=2, MTMSN=0 2023-06-09T17:05:37.898Z,1686330337.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T17:06:13.046Z,1686330373.046 [NAL9602](INFO): SBD MO Status=1, MOMSN=43892, MT Status=0, MTMSN=0 2023-06-09T17:06:13.046Z,1686330373.046 [NAL9602](INFO): No messages in MT queue 2023-06-09T17:06:43.752Z,1686330403.752 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T17:10:21.152Z,1686330621.152 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:10:21.152Z,1686330621.152 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:10:21.152Z,1686330621.152 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:10:21.152Z,1686330621.152 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:10:21.565Z,1686330621.565 [Default:CheckIn:D] Stopped 2023-06-09T17:10:21.565Z,1686330621.565 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:10:21.962Z,1686330621.962 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.019499 min 2023-06-09T17:10:21.962Z,1686330621.962 [Default:CheckIn:E] Stopped 2023-06-09T17:10:21.962Z,1686330621.962 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:10:21.962Z,1686330621.962 [Default:CheckIn] Stopped 2023-06-09T17:10:21.962Z,1686330621.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:10:21.963Z,1686330621.963 [Default:CheckIn](INFO): Running loop #18 2023-06-09T17:10:21.963Z,1686330621.963 [Default:CheckIn] Running Loop=18 2023-06-09T17:10:21.963Z,1686330621.963 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:10:21.963Z,1686330621.963 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:10:23.970Z,1686330623.970 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171022.00,A,3648.15027,N,12147.22502,W,0.097,6.35,090623,,,D*70 2023-06-09T17:10:23.972Z,1686330623.972 [NAL9602](INFO): GPS fix at 20230609T171022: (36.802504, -121.787084) 2023-06-09T17:10:23.982Z,1686330623.982 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:10:23.982Z,1686330623.982 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T17:10:31.183Z,1686330631.183 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0048.lzma 2023-06-09T17:10:32.185Z,1686330632.185 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0048.lzma.bak 2023-06-09T17:10:32.185Z,1686330632.185 [DataOverHttps](INFO): SBD MOMSN=18402927 2023-06-09T17:10:35.291Z,1686330635.291 [NAL9602](INFO): SBD MO Status=1, MOMSN=43893, MT Status=0, MTMSN=0 2023-06-09T17:10:35.291Z,1686330635.291 [NAL9602](INFO): No messages in MT queue 2023-06-09T17:10:47.899Z,1686330647.899 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230609T154002/Express0049.lzma 2023-06-09T17:10:48.901Z,1686330648.901 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0049.lzma.bak 2023-06-09T17:10:48.901Z,1686330648.901 [DataOverHttps](INFO): SBD MOMSN=18402930 2023-06-09T17:10:50.240Z,1686330650.240 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T17:10:50.240Z,1686330650.240 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T17:10:50.240Z,1686330650.240 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:11:06.027Z,1686330666.027 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T17:15:50.860Z,1686330950.860 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:15:50.860Z,1686330950.860 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:15:50.860Z,1686330950.860 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:15:50.861Z,1686330950.861 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:15:51.270Z,1686330951.270 [Default:CheckIn:D] Stopped 2023-06-09T17:15:51.270Z,1686330951.270 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:15:51.680Z,1686330951.680 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.514575 min 2023-06-09T17:15:51.680Z,1686330951.680 [Default:CheckIn:E] Stopped 2023-06-09T17:15:51.680Z,1686330951.680 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:15:51.680Z,1686330951.680 [Default:CheckIn] Stopped 2023-06-09T17:15:51.680Z,1686330951.680 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:15:51.681Z,1686330951.681 [Default:CheckIn](INFO): Running loop #19 2023-06-09T17:15:51.681Z,1686330951.681 [Default:CheckIn] Running Loop=19 2023-06-09T17:15:51.681Z,1686330951.681 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:15:51.681Z,1686330951.681 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:15:53.681Z,1686330953.681 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171552.00,A,3648.15211,N,12147.22779,W,0.058,6.35,090623,,,D*78 2023-06-09T17:15:53.684Z,1686330953.684 [NAL9602](INFO): GPS fix at 20230609T171552: (36.802535, -121.787130) 2023-06-09T17:15:53.710Z,1686330953.710 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:15:53.710Z,1686330953.710 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T17:16:00.667Z,1686330960.667 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230609T154002/Courier0051.lzma 2023-06-09T17:16:01.669Z,1686330961.669 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0051.lzma.bak 2023-06-09T17:16:01.669Z,1686330961.669 [DataOverHttps](INFO): SBD MOMSN=18402935 2023-06-09T17:16:17.835Z,1686330977.835 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230609T154002/Express0052.lzma 2023-06-09T17:16:18.837Z,1686330978.837 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0052.lzma.bak 2023-06-09T17:16:18.837Z,1686330978.837 [DataOverHttps](INFO): SBD MOMSN=18402938 2023-06-09T17:16:19.960Z,1686330979.960 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T17:16:19.960Z,1686330979.960 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T17:16:19.960Z,1686330979.960 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:17:08.882Z,1686331028.882 [NAL9602](INFO): SBD MO Status=2, MOMSN=43894, MT Status=2, MTMSN=0 2023-06-09T17:17:08.882Z,1686331028.882 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T17:17:18.578Z,1686331038.578 [NAL9602](INFO): SBD MO Status=1, MOMSN=43894, MT Status=0, MTMSN=0 2023-06-09T17:17:18.578Z,1686331038.578 [NAL9602](INFO): No messages in MT queue 2023-06-09T17:17:49.276Z,1686331069.276 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T17:21:20.603Z,1686331280.603 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:21:20.604Z,1686331280.604 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:21:20.604Z,1686331280.604 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:21:20.604Z,1686331280.604 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:21:21.028Z,1686331281.028 [Default:CheckIn:D] Stopped 2023-06-09T17:21:21.028Z,1686331281.028 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:21:21.412Z,1686331281.412 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.010531 min 2023-06-09T17:21:21.412Z,1686331281.412 [Default:CheckIn:E] Stopped 2023-06-09T17:21:21.412Z,1686331281.412 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:21:21.412Z,1686331281.412 [Default:CheckIn] Stopped 2023-06-09T17:21:21.412Z,1686331281.412 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:21:21.413Z,1686331281.413 [Default:CheckIn](INFO): Running loop #20 2023-06-09T17:21:21.413Z,1686331281.413 [Default:CheckIn] Running Loop=20 2023-06-09T17:21:21.413Z,1686331281.413 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:21:21.413Z,1686331281.413 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:21:23.420Z,1686331283.420 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172122.00,A,3648.15089,N,12147.22838,W,0.019,6.35,090623,,,D*74 2023-06-09T17:21:23.422Z,1686331283.422 [NAL9602](INFO): GPS fix at 20230609T172122: (36.802515, -121.787140) 2023-06-09T17:21:23.433Z,1686331283.433 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:21:23.433Z,1686331283.433 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T17:21:31.175Z,1686331291.175 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0054.lzma 2023-06-09T17:21:32.178Z,1686331292.178 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0054.lzma.bak 2023-06-09T17:21:32.178Z,1686331292.178 [DataOverHttps](INFO): SBD MOMSN=18402943 2023-06-09T17:21:48.039Z,1686331308.039 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230609T154002/Express0055.lzma 2023-06-09T17:21:49.041Z,1686331309.041 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0055.lzma.bak 2023-06-09T17:21:49.041Z,1686331309.041 [DataOverHttps](INFO): SBD MOMSN=18402946 2023-06-09T17:21:49.284Z,1686331309.284 [NAL9602](INFO): SBD MO Status=2, MOMSN=43895, MT Status=2, MTMSN=0 2023-06-09T17:21:49.284Z,1686331309.284 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T17:21:50.500Z,1686331310.500 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T17:21:50.500Z,1686331310.500 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T17:21:50.500Z,1686331310.500 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:23:47.279Z,1686331427.279 [NAL9602](INFO): SBD MO Status=2, MOMSN=43895, MT Status=2, MTMSN=0 2023-06-09T17:23:47.280Z,1686331427.280 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T17:24:46.674Z,1686331486.674 [NAL9602](INFO): SBD MO Status=1, MOMSN=43895, MT Status=0, MTMSN=0 2023-06-09T17:24:46.674Z,1686331486.674 [NAL9602](INFO): No messages in MT queue 2023-06-09T17:25:17.368Z,1686331517.368 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T17:26:51.113Z,1686331611.113 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:26:51.113Z,1686331611.113 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:26:51.113Z,1686331611.113 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:26:51.113Z,1686331611.113 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:26:51.514Z,1686331611.514 [Default:CheckIn:D] Stopped 2023-06-09T17:26:51.514Z,1686331611.514 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:26:51.917Z,1686331611.917 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.518636 min 2023-06-09T17:26:51.917Z,1686331611.917 [Default:CheckIn:E] Stopped 2023-06-09T17:26:51.917Z,1686331611.917 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:26:51.917Z,1686331611.917 [Default:CheckIn] Stopped 2023-06-09T17:26:51.918Z,1686331611.918 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:26:51.918Z,1686331611.918 [Default:CheckIn](INFO): Running loop #21 2023-06-09T17:26:51.918Z,1686331611.918 [Default:CheckIn] Running Loop=21 2023-06-09T17:26:51.918Z,1686331611.918 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:26:51.918Z,1686331611.918 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:26:53.926Z,1686331613.926 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172652.00,A,3648.15232,N,12147.22898,W,0.136,6.35,090623,,,D*70 2023-06-09T17:26:53.928Z,1686331613.928 [NAL9602](INFO): GPS fix at 20230609T172652: (36.802539, -121.787150) 2023-06-09T17:26:53.945Z,1686331613.945 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:26:53.945Z,1686331613.945 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T17:27:01.239Z,1686331621.239 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0057.lzma 2023-06-09T17:27:02.241Z,1686331622.241 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0057.lzma.bak 2023-06-09T17:27:02.241Z,1686331622.241 [DataOverHttps](INFO): SBD MOMSN=18402949 2023-06-09T17:27:10.562Z,1686331630.562 [NAL9602](INFO): SBD MO Status=1, MOMSN=43896, MT Status=0, MTMSN=0 2023-06-09T17:27:10.562Z,1686331630.562 [NAL9602](INFO): No messages in MT queue 2023-06-09T17:27:18.179Z,1686331638.179 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230609T154002/Express0058.lzma 2023-06-09T17:27:19.181Z,1686331639.181 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0058.lzma.bak 2023-06-09T17:27:19.181Z,1686331639.181 [DataOverHttps](INFO): SBD MOMSN=18402956 2023-06-09T17:27:20.283Z,1686331640.283 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T17:27:20.284Z,1686331640.284 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T17:27:20.284Z,1686331640.284 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:27:41.265Z,1686331661.265 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T17:32:20.919Z,1686331940.919 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:32:20.919Z,1686331940.919 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:32:20.919Z,1686331940.919 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:32:20.920Z,1686331940.920 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:32:21.305Z,1686331941.305 [Default:CheckIn:D] Stopped 2023-06-09T17:32:21.305Z,1686331941.305 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:32:21.710Z,1686331941.710 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.015153 min 2023-06-09T17:32:21.710Z,1686331941.710 [Default:CheckIn:E] Stopped 2023-06-09T17:32:21.710Z,1686331941.710 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:32:21.710Z,1686331941.710 [Default:CheckIn] Stopped 2023-06-09T17:32:21.710Z,1686331941.710 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:32:21.710Z,1686331941.710 [Default:CheckIn](INFO): Running loop #22 2023-06-09T17:32:21.710Z,1686331941.710 [Default:CheckIn] Running Loop=22 2023-06-09T17:32:21.710Z,1686331941.710 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:32:21.711Z,1686331941.711 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:32:23.722Z,1686331943.722 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173222.00,A,3648.15152,N,12147.22868,W,0.039,6.35,090623,,,D*76 2023-06-09T17:32:23.724Z,1686331943.724 [NAL9602](INFO): GPS fix at 20230609T173222: (36.802525, -121.787145) 2023-06-09T17:32:23.735Z,1686331943.735 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:32:23.735Z,1686331943.735 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T17:32:31.435Z,1686331951.435 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0060.lzma 2023-06-09T17:32:32.437Z,1686331952.437 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0060.lzma.bak 2023-06-09T17:32:32.437Z,1686331952.437 [DataOverHttps](INFO): SBD MOMSN=18402961 2023-06-09T17:32:48.819Z,1686331968.819 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230609T154002/Express0061.lzma 2023-06-09T17:32:49.821Z,1686331969.821 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0061.lzma.bak 2023-06-09T17:32:49.821Z,1686331969.821 [DataOverHttps](INFO): SBD MOMSN=18402964 2023-06-09T17:32:51.320Z,1686331971.320 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T17:32:51.320Z,1686331971.320 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T17:32:51.320Z,1686331971.320 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:33:35.347Z,1686332015.347 [NAL9602](INFO): SBD MO Status=2, MOMSN=43897, MT Status=2, MTMSN=0 2023-06-09T17:33:35.347Z,1686332015.347 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T17:34:10.898Z,1686332050.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=43897, MT Status=2, MTMSN=0 2023-06-09T17:34:10.898Z,1686332050.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T17:35:06.289Z,1686332106.289 [NAL9602](INFO): SBD MO Status=2, MOMSN=43897, MT Status=2, MTMSN=0 2023-06-09T17:35:06.289Z,1686332106.289 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T17:35:27.702Z,1686332127.702 [NAL9602](INFO): SBD MO Status=2, MOMSN=43897, MT Status=2, MTMSN=0 2023-06-09T17:35:27.702Z,1686332127.702 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T17:35:53.566Z,1686332153.566 [NAL9602](INFO): SBD MO Status=2, MOMSN=43897, MT Status=2, MTMSN=0 2023-06-09T17:35:53.566Z,1686332153.566 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T17:37:17.190Z,1686332237.190 [NAL9602](INFO): SBD MO Status=1, MOMSN=43897, MT Status=0, MTMSN=0 2023-06-09T17:37:17.190Z,1686332237.190 [NAL9602](INFO): No messages in MT queue 2023-06-09T17:37:47.887Z,1686332267.887 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T17:37:51.950Z,1686332271.950 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:37:51.950Z,1686332271.950 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:37:51.950Z,1686332271.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:37:51.950Z,1686332271.950 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:37:52.340Z,1686332272.340 [Default:CheckIn:D] Stopped 2023-06-09T17:37:52.341Z,1686332272.341 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:37:52.751Z,1686332272.751 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.532414 min 2023-06-09T17:37:52.751Z,1686332272.751 [Default:CheckIn:E] Stopped 2023-06-09T17:37:52.751Z,1686332272.751 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:37:52.751Z,1686332272.751 [Default:CheckIn] Stopped 2023-06-09T17:37:52.752Z,1686332272.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:37:52.752Z,1686332272.752 [Default:CheckIn](INFO): Running loop #23 2023-06-09T17:37:52.752Z,1686332272.752 [Default:CheckIn] Running Loop=23 2023-06-09T17:37:52.752Z,1686332272.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:37:52.752Z,1686332272.752 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:37:54.758Z,1686332274.758 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173753.00,A,3648.17226,N,12147.26870,W,2.080,288.52,090623,,,D*7F 2023-06-09T17:37:54.770Z,1686332274.770 [NAL9602](INFO): GPS fix at 20230609T173753: (36.802871, -121.787812) 2023-06-09T17:37:54.780Z,1686332274.780 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:37:54.780Z,1686332274.780 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T17:38:01.951Z,1686332281.951 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0063.lzma 2023-06-09T17:38:02.953Z,1686332282.953 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0063.lzma.bak 2023-06-09T17:38:02.953Z,1686332282.953 [DataOverHttps](INFO): SBD MOMSN=18402970 2023-06-09T17:38:06.878Z,1686332286.878 [NAL9602](INFO): SBD MO Status=1, MOMSN=43898, MT Status=0, MTMSN=0 2023-06-09T17:38:06.878Z,1686332286.878 [NAL9602](INFO): No messages in MT queue 2023-06-09T17:38:19.291Z,1686332299.291 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230609T154002/Express0064.lzma 2023-06-09T17:38:20.293Z,1686332300.293 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0064.lzma.bak 2023-06-09T17:38:20.293Z,1686332300.293 [DataOverHttps](INFO): SBD MOMSN=18402973 2023-06-09T17:38:21.430Z,1686332301.430 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T17:38:21.430Z,1686332301.430 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T17:38:21.430Z,1686332301.430 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:38:37.580Z,1686332317.580 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T17:43:22.067Z,1686332602.067 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:43:22.067Z,1686332602.067 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:43:22.067Z,1686332602.067 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:43:22.067Z,1686332602.067 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:43:22.463Z,1686332602.463 [Default:CheckIn:D] Stopped 2023-06-09T17:43:22.463Z,1686332602.463 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:43:22.866Z,1686332602.866 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.034456 min 2023-06-09T17:43:22.866Z,1686332602.866 [Default:CheckIn:E] Stopped 2023-06-09T17:43:22.866Z,1686332602.866 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:43:22.866Z,1686332602.866 [Default:CheckIn] Stopped 2023-06-09T17:43:22.866Z,1686332602.866 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:43:22.867Z,1686332602.867 [Default:CheckIn](INFO): Running loop #24 2023-06-09T17:43:22.867Z,1686332602.867 [Default:CheckIn] Running Loop=24 2023-06-09T17:43:22.867Z,1686332602.867 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:43:22.868Z,1686332602.868 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:43:24.882Z,1686332604.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174323.00,A,3648.16251,N,12147.28401,W,0.194,345.09,090623,,,D*76 2023-06-09T17:43:24.885Z,1686332604.885 [NAL9602](INFO): GPS fix at 20230609T174323: (36.802709, -121.788067) 2023-06-09T17:43:24.895Z,1686332604.895 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:43:24.895Z,1686332604.895 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T17:43:32.595Z,1686332612.595 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0066.lzma 2023-06-09T17:43:33.598Z,1686332613.598 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0066.lzma.bak 2023-06-09T17:43:33.598Z,1686332613.598 [DataOverHttps](INFO): SBD MOMSN=18402980 2023-06-09T17:43:49.867Z,1686332629.867 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20230609T154002/Express0067.lzma 2023-06-09T17:43:50.869Z,1686332630.869 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0067.lzma.bak 2023-06-09T17:43:50.869Z,1686332630.869 [DataOverHttps](INFO): SBD MOMSN=18402983 2023-06-09T17:43:52.056Z,1686332632.056 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T17:43:52.056Z,1686332632.056 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T17:43:52.056Z,1686332632.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:46:18.190Z,1686332778.190 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:46:30.713Z,1686332790.713 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:46:44.049Z,1686332804.049 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:46:56.569Z,1686332816.569 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:47:09.497Z,1686332829.497 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:47:22.425Z,1686332842.425 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:47:35.354Z,1686332855.354 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:47:48.281Z,1686332868.281 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:48:00.805Z,1686332880.805 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:48:12.926Z,1686332892.926 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:48:25.853Z,1686332905.853 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T17:48:26.664Z,1686332906.664 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-09T17:48:38.377Z,1686332918.377 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-06-09T17:48:38.383Z,1686332918.383 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5. 2023-06-09T17:48:38.386Z,1686332918.386 [BPC1](INFO): Received data from all battery sticks. 2023-06-09T17:48:52.530Z,1686332932.530 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:48:52.530Z,1686332932.530 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:48:52.530Z,1686332932.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:48:52.530Z,1686332932.530 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:48:52.935Z,1686332932.935 [Default:CheckIn:D] Stopped 2023-06-09T17:48:52.936Z,1686332932.936 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:48:53.358Z,1686332933.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.542334 min 2023-06-09T17:48:53.358Z,1686332933.358 [Default:CheckIn:E] Stopped 2023-06-09T17:48:53.358Z,1686332933.358 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:48:53.358Z,1686332933.358 [Default:CheckIn] Stopped 2023-06-09T17:48:53.358Z,1686332933.358 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:48:53.358Z,1686332933.358 [Default:CheckIn](INFO): Running loop #25 2023-06-09T17:48:53.358Z,1686332933.358 [Default:CheckIn] Running Loop=25 2023-06-09T17:48:53.358Z,1686332933.358 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:48:53.358Z,1686332933.358 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:48:55.350Z,1686332935.350 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174854.00,A,3648.17777,N,12147.27586,W,0.039,155.03,090623,,,A*76 2023-06-09T17:48:55.352Z,1686332935.352 [NAL9602](INFO): GPS fix at 20230609T174854: (36.802963, -121.787931) 2023-06-09T17:48:55.415Z,1686332935.415 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:48:55.415Z,1686332935.415 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T17:49:02.267Z,1686332942.267 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20230609T154002/Courier0069.lzma 2023-06-09T17:49:03.274Z,1686332943.274 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0069.lzma.bak 2023-06-09T17:49:03.274Z,1686332943.274 [DataOverHttps](INFO): SBD MOMSN=18402987 2023-06-09T17:49:19.323Z,1686332959.323 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20230609T154002/Express0070.lzma 2023-06-09T17:49:20.325Z,1686332960.325 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0070.lzma.bak 2023-06-09T17:49:20.325Z,1686332960.325 [DataOverHttps](INFO): SBD MOMSN=18402990 2023-06-09T17:49:21.646Z,1686332961.646 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T17:49:21.646Z,1686332961.646 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T17:49:21.647Z,1686332961.647 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:49:28.096Z,1686332968.096 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T17:54:22.236Z,1686333262.236 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:54:22.236Z,1686333262.236 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:54:22.236Z,1686333262.236 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:54:22.237Z,1686333262.237 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:54:22.647Z,1686333262.647 [Default:CheckIn:D] Stopped 2023-06-09T17:54:22.647Z,1686333262.647 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:54:23.034Z,1686333263.034 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.037516 min 2023-06-09T17:54:23.035Z,1686333263.035 [Default:CheckIn:E] Stopped 2023-06-09T17:54:23.035Z,1686333263.035 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:54:23.035Z,1686333263.035 [Default:CheckIn] Stopped 2023-06-09T17:54:23.035Z,1686333263.035 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:54:23.035Z,1686333263.035 [Default:CheckIn](INFO): Running loop #26 2023-06-09T17:54:23.035Z,1686333263.035 [Default:CheckIn] Running Loop=26 2023-06-09T17:54:23.035Z,1686333263.035 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:54:23.036Z,1686333263.036 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:54:25.050Z,1686333265.050 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175423.00,A,3648.16879,N,12147.28301,W,0.505,198.96,090623,,,A*7A 2023-06-09T17:54:25.053Z,1686333265.053 [NAL9602](INFO): GPS fix at 20230609T175423: (36.802813, -121.788050) 2023-06-09T17:54:25.063Z,1686333265.063 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:54:25.063Z,1686333265.063 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T17:54:32.299Z,1686333272.299 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0072.lzma 2023-06-09T17:54:33.301Z,1686333273.301 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0072.lzma.bak 2023-06-09T17:54:33.301Z,1686333273.301 [DataOverHttps](INFO): SBD MOMSN=18402994 2023-06-09T17:54:49.711Z,1686333289.711 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230609T154002/Express0073.lzma 2023-06-09T17:54:50.713Z,1686333290.713 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0073.lzma.bak 2023-06-09T17:54:50.714Z,1686333290.714 [DataOverHttps](INFO): SBD MOMSN=18402997 2023-06-09T17:54:52.143Z,1686333292.143 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T17:54:52.143Z,1686333292.143 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T17:54:52.143Z,1686333292.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T17:54:55.771Z,1686333295.771 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-06-09T17:54:55.855Z,1686333295.855 [NAL9602](ERROR): received: +CSQ:0 OK898, 0, 0, 0, 0 OK 2023-06-09T17:56:51.325Z,1686333411.325 [NAL9602](INFO): SBD MO Status=2, MOMSN=43899, MT Status=2, MTMSN=0 2023-06-09T17:56:51.325Z,1686333411.325 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T17:59:26.868Z,1686333566.868 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-09T17:59:52.730Z,1686333592.730 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T17:59:52.730Z,1686333592.730 [Default:CheckIn:C.Wait] Stopped 2023-06-09T17:59:52.730Z,1686333592.730 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T17:59:52.731Z,1686333592.731 [Default:CheckIn:D] Running Loop=1 2023-06-09T17:59:53.138Z,1686333593.138 [Default:CheckIn:D] Stopped 2023-06-09T17:59:53.138Z,1686333593.138 [Default:CheckIn:E] Running Loop=1 2023-06-09T17:59:53.537Z,1686333593.537 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.545703 min 2023-06-09T17:59:53.537Z,1686333593.537 [Default:CheckIn:E] Stopped 2023-06-09T17:59:53.537Z,1686333593.537 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T17:59:53.537Z,1686333593.537 [Default:CheckIn] Stopped 2023-06-09T17:59:53.537Z,1686333593.537 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T17:59:53.537Z,1686333593.537 [Default:CheckIn](INFO): Running loop #27 2023-06-09T17:59:53.538Z,1686333593.538 [Default:CheckIn] Running Loop=27 2023-06-09T17:59:53.538Z,1686333593.538 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T17:59:53.538Z,1686333593.538 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T17:59:55.554Z,1686333595.554 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175954.00,A,3648.16987,N,12147.28251,W,0.330,336.05,090623,,,A*7F 2023-06-09T17:59:55.566Z,1686333595.566 [NAL9602](INFO): GPS fix at 20230609T175954: (36.802831, -121.788042) 2023-06-09T17:59:55.577Z,1686333595.577 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T17:59:55.577Z,1686333595.577 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T18:00:03.327Z,1686333603.327 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0075.lzma 2023-06-09T18:00:04.329Z,1686333604.329 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0075.lzma.bak 2023-06-09T18:00:04.329Z,1686333604.329 [DataOverHttps](INFO): SBD MOMSN=18403000 2023-06-09T18:00:20.248Z,1686333620.248 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230609T154002/Express0076.lzma 2023-06-09T18:00:21.249Z,1686333621.249 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0076.lzma.bak 2023-06-09T18:00:21.249Z,1686333621.249 [DataOverHttps](INFO): SBD MOMSN=18403003 2023-06-09T18:00:22.632Z,1686333622.632 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T18:00:22.632Z,1686333622.632 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T18:00:22.632Z,1686333622.632 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T18:00:27.874Z,1686333627.874 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T18:05:23.210Z,1686333923.210 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T18:05:23.210Z,1686333923.210 [Default:CheckIn:C.Wait] Stopped 2023-06-09T18:05:23.210Z,1686333923.210 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T18:05:23.210Z,1686333923.210 [Default:CheckIn:D] Running Loop=1 2023-06-09T18:05:23.614Z,1686333923.614 [Default:CheckIn:D] Stopped 2023-06-09T18:05:23.614Z,1686333923.614 [Default:CheckIn:E] Running Loop=1 2023-06-09T18:05:24.024Z,1686333924.024 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.053646 min 2023-06-09T18:05:24.024Z,1686333924.024 [Default:CheckIn:E] Stopped 2023-06-09T18:05:24.024Z,1686333924.024 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T18:05:24.024Z,1686333924.024 [Default:CheckIn] Stopped 2023-06-09T18:05:24.024Z,1686333924.024 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T18:05:24.025Z,1686333924.025 [Default:CheckIn](INFO): Running loop #28 2023-06-09T18:05:24.025Z,1686333924.025 [Default:CheckIn] Running Loop=28 2023-06-09T18:05:24.025Z,1686333924.025 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T18:05:24.025Z,1686333924.025 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T18:05:26.026Z,1686333926.026 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180524.00,A,3648.16845,N,12147.27685,W,3.285,305.86,090623,,,A*74 2023-06-09T18:05:26.029Z,1686333926.029 [NAL9602](INFO): GPS fix at 20230609T180524: (36.802808, -121.787948) 2023-06-09T18:05:26.061Z,1686333926.061 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T18:05:26.061Z,1686333926.061 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T18:05:33.751Z,1686333933.751 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230609T154002/Courier0078.lzma 2023-06-09T18:05:34.753Z,1686333934.753 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0078.lzma.bak 2023-06-09T18:05:34.753Z,1686333934.753 [DataOverHttps](INFO): SBD MOMSN=18403008 2023-06-09T18:05:50.963Z,1686333950.963 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230609T154002/Express0079.lzma 2023-06-09T18:05:51.965Z,1686333951.965 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0079.lzma.bak 2023-06-09T18:05:51.965Z,1686333951.965 [DataOverHttps](INFO): SBD MOMSN=18403011 2023-06-09T18:05:53.107Z,1686333953.107 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T18:05:53.107Z,1686333953.107 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T18:05:53.107Z,1686333953.107 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T18:05:56.736Z,1686333956.736 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-06-09T18:05:56.811Z,1686333956.811 [NAL9602](ERROR): received: +CSQ:0 OK899, 2, 0, 0, 0 OK 2023-06-09T18:10:27.813Z,1686334227.813 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-09T18:10:53.709Z,1686334253.709 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T18:10:53.709Z,1686334253.709 [Default:CheckIn:C.Wait] Stopped 2023-06-09T18:10:53.709Z,1686334253.709 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T18:10:53.709Z,1686334253.709 [Default:CheckIn:D] Running Loop=1 2023-06-09T18:10:54.089Z,1686334254.089 [Default:CheckIn:D] Stopped 2023-06-09T18:10:54.089Z,1686334254.089 [Default:CheckIn:E] Running Loop=1 2023-06-09T18:10:54.487Z,1686334254.487 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 164.561556 min 2023-06-09T18:10:54.487Z,1686334254.487 [Default:CheckIn:E] Stopped 2023-06-09T18:10:54.487Z,1686334254.487 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T18:10:54.487Z,1686334254.487 [Default:CheckIn] Stopped 2023-06-09T18:10:54.487Z,1686334254.487 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T18:10:54.487Z,1686334254.487 [Default:CheckIn](INFO): Running loop #29 2023-06-09T18:10:54.488Z,1686334254.488 [Default:CheckIn] Running Loop=29 2023-06-09T18:10:54.488Z,1686334254.488 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T18:10:54.488Z,1686334254.488 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T18:10:56.498Z,1686334256.498 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181055.00,A,3648.17155,N,12147.28129,W,0.292,305.86,090623,,,A*74 2023-06-09T18:10:56.501Z,1686334256.501 [NAL9602](INFO): GPS fix at 20230609T181055: (36.802859, -121.788021) 2023-06-09T18:10:56.545Z,1686334256.545 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T18:10:56.545Z,1686334256.545 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T18:11:03.803Z,1686334263.803 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0081.lzma 2023-06-09T18:11:04.805Z,1686334264.805 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0081.lzma.bak 2023-06-09T18:11:04.806Z,1686334264.806 [DataOverHttps](INFO): SBD MOMSN=18403014 2023-06-09T18:11:21.031Z,1686334281.031 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230609T154002/Express0082.lzma 2023-06-09T18:11:22.033Z,1686334282.033 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0082.lzma.bak 2023-06-09T18:11:22.033Z,1686334282.033 [DataOverHttps](INFO): SBD MOMSN=18403017 2023-06-09T18:11:23.177Z,1686334283.177 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T18:11:23.177Z,1686334283.177 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T18:11:23.177Z,1686334283.177 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T18:11:29.219Z,1686334289.219 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T18:16:23.749Z,1686334583.749 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T18:16:23.749Z,1686334583.749 [Default:CheckIn:C.Wait] Stopped 2023-06-09T18:16:23.749Z,1686334583.749 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T18:16:23.749Z,1686334583.749 [Default:CheckIn:D] Running Loop=1 2023-06-09T18:16:24.153Z,1686334584.153 [Default:CheckIn:D] Stopped 2023-06-09T18:16:24.153Z,1686334584.153 [Default:CheckIn:E] Running Loop=1 2023-06-09T18:16:24.562Z,1686334584.562 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 170.062630 min 2023-06-09T18:16:24.562Z,1686334584.562 [Default:CheckIn:E] Stopped 2023-06-09T18:16:24.563Z,1686334584.563 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T18:16:24.563Z,1686334584.563 [Default:CheckIn] Stopped 2023-06-09T18:16:24.563Z,1686334584.563 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T18:16:24.563Z,1686334584.563 [Default:CheckIn](INFO): Running loop #30 2023-06-09T18:16:24.563Z,1686334584.563 [Default:CheckIn] Running Loop=30 2023-06-09T18:16:24.563Z,1686334584.563 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T18:16:24.563Z,1686334584.563 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T18:16:26.570Z,1686334586.570 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181625.00,A,3648.16643,N,12147.28211,W,0.369,20.81,090623,,,A*4A 2023-06-09T18:16:26.573Z,1686334586.573 [NAL9602](INFO): GPS fix at 20230609T181625: (36.802774, -121.788035) 2023-06-09T18:16:26.600Z,1686334586.600 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T18:16:26.600Z,1686334586.600 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T18:16:33.487Z,1686334593.487 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0084.lzma 2023-06-09T18:16:34.489Z,1686334594.489 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0084.lzma.bak 2023-06-09T18:16:34.489Z,1686334594.489 [DataOverHttps](INFO): SBD MOMSN=18403021 2023-06-09T18:16:52.131Z,1686334612.131 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230609T154002/Express0085.lzma 2023-06-09T18:16:53.133Z,1686334613.133 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0085.lzma.bak 2023-06-09T18:16:53.134Z,1686334613.134 [DataOverHttps](INFO): SBD MOMSN=18403024 2023-06-09T18:16:54.520Z,1686334614.520 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T18:16:54.520Z,1686334614.520 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T18:16:54.520Z,1686334614.520 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T18:16:57.329Z,1686334617.329 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-06-09T18:16:57.407Z,1686334617.407 [NAL9602](ERROR): received: +CSQ:0 OK899, 2, 0, 0, 0 OK 2023-06-09T18:21:28.820Z,1686334888.820 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-09T18:21:55.085Z,1686334915.085 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T18:21:55.085Z,1686334915.085 [Default:CheckIn:C.Wait] Stopped 2023-06-09T18:21:55.085Z,1686334915.085 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T18:21:55.085Z,1686334915.085 [Default:CheckIn:D] Running Loop=1 2023-06-09T18:21:55.500Z,1686334915.500 [Default:CheckIn:D] Stopped 2023-06-09T18:21:55.500Z,1686334915.500 [Default:CheckIn:E] Running Loop=1 2023-06-09T18:21:55.893Z,1686334915.893 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 175.585075 min 2023-06-09T18:21:55.893Z,1686334915.893 [Default:CheckIn:E] Stopped 2023-06-09T18:21:55.893Z,1686334915.893 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T18:21:55.893Z,1686334915.893 [Default:CheckIn] Stopped 2023-06-09T18:21:55.893Z,1686334915.893 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T18:21:55.893Z,1686334915.893 [Default:CheckIn](INFO): Running loop #31 2023-06-09T18:21:55.893Z,1686334915.893 [Default:CheckIn] Running Loop=31 2023-06-09T18:21:55.894Z,1686334915.894 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T18:21:55.894Z,1686334915.894 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T18:21:57.907Z,1686334917.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182156.00,A,3648.16522,N,12147.27987,W,0.292,353.85,090623,,,A*73 2023-06-09T18:21:57.909Z,1686334917.909 [NAL9602](INFO): GPS fix at 20230609T182156: (36.802754, -121.787998) 2023-06-09T18:21:57.927Z,1686334917.927 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T18:21:57.927Z,1686334917.927 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T18:22:05.251Z,1686334925.251 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230609T154002/Courier0087.lzma 2023-06-09T18:22:06.253Z,1686334926.253 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0087.lzma.bak 2023-06-09T18:22:06.253Z,1686334926.253 [DataOverHttps](INFO): SBD MOMSN=18403027 2023-06-09T18:22:22.112Z,1686334942.112 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20230609T154002/Express0088.lzma 2023-06-09T18:22:23.114Z,1686334943.114 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0088.lzma.bak 2023-06-09T18:22:23.114Z,1686334943.114 [DataOverHttps](INFO): SBD MOMSN=18403030 2023-06-09T18:22:24.590Z,1686334944.590 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T18:22:24.590Z,1686334944.590 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T18:22:24.590Z,1686334944.590 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T18:22:29.820Z,1686334949.820 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T18:27:25.198Z,1686335245.198 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T18:27:25.198Z,1686335245.198 [Default:CheckIn:C.Wait] Stopped 2023-06-09T18:27:25.199Z,1686335245.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T18:27:25.199Z,1686335245.199 [Default:CheckIn:D] Running Loop=1 2023-06-09T18:27:25.590Z,1686335245.590 [Default:CheckIn:D] Stopped 2023-06-09T18:27:25.590Z,1686335245.590 [Default:CheckIn:E] Running Loop=1 2023-06-09T18:27:25.989Z,1686335245.989 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 181.086572 min 2023-06-09T18:27:25.989Z,1686335245.989 [Default:CheckIn:E] Stopped 2023-06-09T18:27:25.989Z,1686335245.989 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T18:27:25.989Z,1686335245.989 [Default:CheckIn] Stopped 2023-06-09T18:27:25.989Z,1686335245.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T18:27:25.990Z,1686335245.990 [Default:CheckIn](INFO): Running loop #32 2023-06-09T18:27:25.990Z,1686335245.990 [Default:CheckIn] Running Loop=32 2023-06-09T18:27:25.990Z,1686335245.990 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T18:27:25.990Z,1686335245.990 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T18:27:28.009Z,1686335248.009 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182726.00,A,3648.16634,N,12147.28358,W,0.447,4.71,090623,,,A*75 2023-06-09T18:27:28.011Z,1686335248.011 [NAL9602](INFO): GPS fix at 20230609T182726: (36.802772, -121.788060) 2023-06-09T18:27:28.021Z,1686335248.021 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T18:27:28.021Z,1686335248.021 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T18:27:35.347Z,1686335255.347 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0090.lzma 2023-06-09T18:27:36.351Z,1686335256.351 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0090.lzma.bak 2023-06-09T18:27:36.351Z,1686335256.351 [DataOverHttps](INFO): SBD MOMSN=18403034 2023-06-09T18:27:52.159Z,1686335272.159 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230609T154002/Express0091.lzma 2023-06-09T18:27:53.161Z,1686335273.161 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0091.lzma.bak 2023-06-09T18:27:53.162Z,1686335273.162 [DataOverHttps](INFO): SBD MOMSN=18403037 2023-06-09T18:27:54.270Z,1686335274.270 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T18:27:54.270Z,1686335274.270 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T18:27:54.271Z,1686335274.271 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T18:27:58.701Z,1686335278.701 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-06-09T18:27:58.775Z,1686335278.775 [NAL9602](ERROR): received: +CSQ:0 OK899, 2, 0, 0, 0 OK 2023-06-09T18:30:08.798Z,1686335408.798 [NAL9602](INFO): SBD MO Status=2, MOMSN=43899, MT Status=2, MTMSN=0 2023-06-09T18:30:08.798Z,1686335408.798 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-09T18:30:33.840Z,1686335433.840 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-06-09T18:30:33.840Z,1686335433.840 [DropWeight] Hardware Fault, FailCount= 1 2023-06-09T18:30:33.840Z,1686335433.840 [DropWeight](ERROR): Hardware Fault 2023-06-09T18:30:33.865Z,1686335433.865 [CommandExec](FAULT): Scheduling is paused 2023-06-09T18:30:33.866Z,1686335433.866 [CBIT](INFO): Critical error at 20230609T183033 2023-06-09T18:30:33.868Z,1686335433.868 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-06-09T18:30:33.869Z,1686335433.869 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-06-09T18:30:34.365Z,1686335434.365 [CBIT](INFO): Critical error at 20230609T183033 2023-06-09T18:32:29.807Z,1686335549.807 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-09T18:32:54.911Z,1686335574.911 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T18:32:54.911Z,1686335574.911 [Default:CheckIn:C.Wait] Stopped 2023-06-09T18:32:54.911Z,1686335574.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T18:32:54.911Z,1686335574.911 [Default:CheckIn:D] Running Loop=1 2023-06-09T18:32:55.280Z,1686335575.280 [Default:CheckIn:D] Stopped 2023-06-09T18:32:55.280Z,1686335575.280 [Default:CheckIn:E] Running Loop=1 2023-06-09T18:32:55.692Z,1686335575.692 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 186.581413 min 2023-06-09T18:32:55.692Z,1686335575.692 [Default:CheckIn:E] Stopped 2023-06-09T18:32:55.692Z,1686335575.692 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T18:32:55.692Z,1686335575.692 [Default:CheckIn] Stopped 2023-06-09T18:32:55.692Z,1686335575.692 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T18:32:55.693Z,1686335575.693 [Default:CheckIn](INFO): Running loop #33 2023-06-09T18:32:55.693Z,1686335575.693 [Default:CheckIn] Running Loop=33 2023-06-09T18:32:55.693Z,1686335575.693 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T18:32:55.693Z,1686335575.693 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T18:32:57.690Z,1686335577.690 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183256.00,A,3648.16701,N,12147.28220,W,0.214,205.27,090623,,,A*7F 2023-06-09T18:32:57.692Z,1686335577.692 [NAL9602](INFO): GPS fix at 20230609T183256: (36.802783, -121.788037) 2023-06-09T18:32:57.702Z,1686335577.702 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T18:32:57.702Z,1686335577.702 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T18:33:05.011Z,1686335585.011 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20230609T154002/Courier0093.lzma 2023-06-09T18:33:06.013Z,1686335586.013 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0093.lzma.bak 2023-06-09T18:33:06.014Z,1686335586.014 [DataOverHttps](INFO): SBD MOMSN=18403040 2023-06-09T18:33:23.123Z,1686335603.123 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230609T154002/Express0094.lzma 2023-06-09T18:33:24.125Z,1686335604.125 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0094.lzma.bak 2023-06-09T18:33:24.125Z,1686335604.125 [DataOverHttps](INFO): SBD MOMSN=18403045 2023-06-09T18:33:25.584Z,1686335605.584 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T18:33:25.584Z,1686335605.584 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T18:33:25.584Z,1686335605.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T18:33:30.008Z,1686335610.008 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T18:38:26.177Z,1686335906.177 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T18:38:26.177Z,1686335906.177 [Default:CheckIn:C.Wait] Stopped 2023-06-09T18:38:26.177Z,1686335906.177 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T18:38:26.177Z,1686335906.177 [Default:CheckIn:D] Running Loop=1 2023-06-09T18:38:26.583Z,1686335906.583 [Default:CheckIn:D] Stopped 2023-06-09T18:38:26.583Z,1686335906.583 [Default:CheckIn:E] Running Loop=1 2023-06-09T18:38:26.999Z,1686335906.999 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 192.103109 min 2023-06-09T18:38:26.999Z,1686335906.999 [Default:CheckIn:E] Stopped 2023-06-09T18:38:26.999Z,1686335906.999 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T18:38:26.999Z,1686335906.999 [Default:CheckIn] Stopped 2023-06-09T18:38:26.999Z,1686335906.999 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T18:38:26.000Z,1686335907.000 [Default:CheckIn](INFO): Running loop #34 2023-06-09T18:38:26.000Z,1686335907.000 [Default:CheckIn] Running Loop=34 2023-06-09T18:38:26.000Z,1686335907.000 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T18:38:26.000Z,1686335907.000 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T18:38:28.997Z,1686335908.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183827.00,A,3648.16940,N,12147.28029,W,0.350,205.27,090623,,,A*72 2023-06-09T18:38:28.999Z,1686335908.999 [NAL9602](INFO): GPS fix at 20230609T183827: (36.802823, -121.788005) 2023-06-09T18:38:29.010Z,1686335909.010 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T18:38:29.010Z,1686335909.010 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T18:38:36.411Z,1686335916.411 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0096.lzma 2023-06-09T18:38:37.413Z,1686335917.413 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0096.lzma.bak 2023-06-09T18:38:37.413Z,1686335917.413 [DataOverHttps](INFO): SBD MOMSN=18403050 2023-06-09T18:38:55.883Z,1686335935.883 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230609T154002/Express0097.lzma 2023-06-09T18:38:56.885Z,1686335936.885 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0097.lzma.bak 2023-06-09T18:38:56.886Z,1686335936.886 [DataOverHttps](INFO): SBD MOMSN=18403053 2023-06-09T18:38:58.189Z,1686335938.189 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T18:38:58.189Z,1686335938.189 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T18:38:58.189Z,1686335938.189 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T18:38:59.766Z,1686335939.766 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-06-09T18:38:59.843Z,1686335939.843 [NAL9602](ERROR): received: +CSQ:0 OK899, 2, 0, 0, 0 OK 2023-06-09T18:40:59.348Z,1686336059.348 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T18:41:30.480Z,1686336090.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T18:42:01.603Z,1686336121.603 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T18:42:32.732Z,1686336152.732 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T18:43:03.856Z,1686336183.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T18:43:06.868Z,1686336186.868 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739044 2023-06-09T18:43:30.843Z,1686336210.843 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-09T18:43:58.745Z,1686336238.745 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T18:43:58.745Z,1686336238.745 [Default:CheckIn:C.Wait] Stopped 2023-06-09T18:43:58.745Z,1686336238.745 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T18:43:58.746Z,1686336238.746 [Default:CheckIn:D] Running Loop=1 2023-06-09T18:43:59.143Z,1686336239.143 [Default:CheckIn:D] Stopped 2023-06-09T18:43:59.143Z,1686336239.143 [Default:CheckIn:E] Running Loop=1 2023-06-09T18:43:59.542Z,1686336239.542 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 197.645785 min 2023-06-09T18:43:59.543Z,1686336239.543 [Default:CheckIn:E] Stopped 2023-06-09T18:43:59.543Z,1686336239.543 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T18:43:59.543Z,1686336239.543 [Default:CheckIn] Stopped 2023-06-09T18:43:59.543Z,1686336239.543 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T18:43:59.543Z,1686336239.543 [Default:CheckIn](INFO): Running loop #35 2023-06-09T18:43:59.543Z,1686336239.543 [Default:CheckIn] Running Loop=35 2023-06-09T18:43:59.543Z,1686336239.543 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T18:43:59.543Z,1686336239.543 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T18:44:05.104Z,1686336245.104 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-09T18:44:15.652Z,1686336255.652 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003544 2023-06-09T18:44:16.502Z,1686336256.502 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184415.00,A,3648.16902,N,12147.28917,W,2.488,221.85,090623,,,A*74 2023-06-09T18:44:16.504Z,1686336256.504 [NAL9602](INFO): GPS fix at 20230609T184415: (36.802817, -121.788153) 2023-06-09T18:44:16.539Z,1686336256.539 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T18:44:16.539Z,1686336256.539 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T18:44:24.331Z,1686336264.331 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230609T154002/Courier0099.lzma 2023-06-09T18:44:25.333Z,1686336265.333 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0099.lzma.bak 2023-06-09T18:44:25.333Z,1686336265.333 [DataOverHttps](INFO): SBD MOMSN=18403056 2023-06-09T18:44:41.127Z,1686336281.127 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230609T154002/Express0100.lzma 2023-06-09T18:44:42.129Z,1686336282.129 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0100.lzma.bak 2023-06-09T18:44:42.130Z,1686336282.130 [DataOverHttps](INFO): SBD MOMSN=18403059 2023-06-09T18:44:43.601Z,1686336283.601 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T18:44:43.602Z,1686336283.602 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T18:44:43.602Z,1686336283.602 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T18:44:48.819Z,1686336288.819 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T18:45:34.088Z,1686336334.088 [CBIT](INFO): Clearing failed state for component DropWeight 2023-06-09T18:45:34.088Z,1686336334.088 [DropWeight] No Fault, FailCount= 1 2023-06-09T18:49:44.158Z,1686336584.158 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T18:49:44.158Z,1686336584.158 [Default:CheckIn:C.Wait] Stopped 2023-06-09T18:49:44.158Z,1686336584.158 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T18:49:44.158Z,1686336584.158 [Default:CheckIn:D] Running Loop=1 2023-06-09T18:49:44.562Z,1686336584.562 [Default:CheckIn:D] Stopped 2023-06-09T18:49:44.562Z,1686336584.562 [Default:CheckIn:E] Running Loop=1 2023-06-09T18:49:44.971Z,1686336584.971 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 203.402767 min 2023-06-09T18:49:44.971Z,1686336584.971 [Default:CheckIn:E] Stopped 2023-06-09T18:49:44.971Z,1686336584.971 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T18:49:44.971Z,1686336584.971 [Default:CheckIn] Stopped 2023-06-09T18:49:44.971Z,1686336584.971 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T18:49:44.971Z,1686336584.971 [Default:CheckIn](INFO): Running loop #36 2023-06-09T18:49:44.971Z,1686336584.971 [Default:CheckIn] Running Loop=36 2023-06-09T18:49:44.972Z,1686336584.972 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T18:49:44.972Z,1686336584.972 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T18:50:17.520Z,1686336617.520 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-06-09T18:50:17.599Z,1686336617.599 [NAL9602](ERROR): received: +CSQ:0 OK899, 2, 0, 0, 0 OK 2023-06-09T18:53:37.505Z,1686336817.505 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T18:53:53.262Z,1686336833.262 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T18:54:08.613Z,1686336848.613 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T18:54:24.369Z,1686336864.369 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T18:54:39.729Z,1686336879.729 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T18:54:45.007Z,1686336885.007 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-09T18:49:44.0Z 2023-06-09T18:54:45.007Z,1686336885.007 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T18:54:45.007Z,1686336885.007 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T18:54:48.756Z,1686336888.756 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-09T18:54:54.824Z,1686336894.824 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T18:54:56.562Z,1686336896.562 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230609T154002/Courier0102.lzma 2023-06-09T18:54:57.565Z,1686336897.565 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0102.lzma.bak 2023-06-09T18:54:57.565Z,1686336897.565 [DataOverHttps](INFO): SBD MOMSN=18403063 2023-06-09T18:55:09.761Z,1686336909.761 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T18:55:19.464Z,1686336919.464 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T18:55:22.555Z,1686336922.555 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230609T154002/Express0103.lzma 2023-06-09T18:55:23.557Z,1686336923.557 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0103.lzma.bak 2023-06-09T18:55:23.557Z,1686336923.557 [DataOverHttps](INFO): SBD MOMSN=18403065 2023-06-09T18:55:24.814Z,1686336924.814 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T18:55:24.814Z,1686336924.814 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T18:55:24.814Z,1686336924.814 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T18:55:25.119Z,1686336925.119 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T18:55:40.873Z,1686336940.873 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-09T18:55:55.831Z,1686336955.831 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5. 2023-06-09T18:55:55.837Z,1686336955.837 [BPC1](INFO): Received data from all battery sticks. 2023-06-09T19:00:25.320Z,1686337225.320 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T19:00:25.320Z,1686337225.320 [Default:CheckIn:C.Wait] Stopped 2023-06-09T19:00:25.320Z,1686337225.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T19:00:25.322Z,1686337225.322 [Default:CheckIn:D] Running Loop=1 2023-06-09T19:00:25.746Z,1686337225.746 [Default:CheckIn:D] Stopped 2023-06-09T19:00:25.746Z,1686337225.746 [Default:CheckIn:E] Running Loop=1 2023-06-09T19:00:26.159Z,1686337226.159 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 214.089176 min 2023-06-09T19:00:26.159Z,1686337226.159 [Default:CheckIn:E] Stopped 2023-06-09T19:00:26.159Z,1686337226.159 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T19:00:26.160Z,1686337226.160 [Default:CheckIn] Stopped 2023-06-09T19:00:26.160Z,1686337226.160 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T19:00:26.160Z,1686337226.160 [Default:CheckIn](INFO): Running loop #37 2023-06-09T19:00:26.160Z,1686337226.160 [Default:CheckIn] Running Loop=37 2023-06-09T19:00:26.160Z,1686337226.160 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T19:00:26.160Z,1686337226.160 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T19:00:27.716Z,1686337227.716 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-06-09T19:00:27.716Z,1686337227.716 [NAL9602] Data Fault, FailCount= 1 2023-06-09T19:00:27.716Z,1686337227.716 [NAL9602](ERROR): Data Fault 2023-06-09T19:00:27.790Z,1686337227.790 [CBIT](ERROR): Data Fault in component: NAL9602 2023-06-09T19:00:28.120Z,1686337228.120 [NAL9602](INFO): Powering down 2023-06-09T19:00:28.970Z,1686337228.970 [CBIT](INFO): Clearing failed state for component NAL9602 2023-06-09T19:00:28.970Z,1686337228.970 [NAL9602] No Fault, FailCount= 1 2023-06-09T19:00:58.420Z,1686337258.420 [NAL9602](INFO): Powering up NAL9602 2023-06-09T19:01:09.328Z,1686337269.328 [NAL9602](INFO): NAL9602 initialized 2023-06-09T19:05:26.295Z,1686337526.295 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-09T19:00:26.2Z 2023-06-09T19:05:26.295Z,1686337526.295 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T19:05:26.295Z,1686337526.295 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T19:05:33.126Z,1686337533.126 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20230609T154002/Courier0105.lzma 2023-06-09T19:05:34.130Z,1686337534.130 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0105.lzma.bak 2023-06-09T19:05:34.130Z,1686337534.130 [DataOverHttps](INFO): SBD MOMSN=18403068 2023-06-09T19:05:50.043Z,1686337550.043 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230609T154002/Express0106.lzma 2023-06-09T19:05:51.045Z,1686337551.045 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0106.lzma.bak 2023-06-09T19:05:51.045Z,1686337551.045 [DataOverHttps](INFO): SBD MOMSN=18403070 2023-06-09T19:05:52.156Z,1686337552.156 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T19:05:52.157Z,1686337552.157 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T19:05:52.157Z,1686337552.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T19:06:12.740Z,1686337572.740 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-09T19:06:43.439Z,1686337603.439 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T19:10:52.738Z,1686337852.738 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T19:10:52.738Z,1686337852.738 [Default:CheckIn:C.Wait] Stopped 2023-06-09T19:10:52.738Z,1686337852.738 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T19:10:52.738Z,1686337852.738 [Default:CheckIn:D] Running Loop=1 2023-06-09T19:10:53.121Z,1686337853.121 [Default:CheckIn:D] Stopped 2023-06-09T19:10:53.121Z,1686337853.121 [Default:CheckIn:E] Running Loop=1 2023-06-09T19:10:53.530Z,1686337853.530 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 224.545426 min 2023-06-09T19:10:53.530Z,1686337853.530 [Default:CheckIn:E] Stopped 2023-06-09T19:10:53.530Z,1686337853.530 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T19:10:53.530Z,1686337853.530 [Default:CheckIn] Stopped 2023-06-09T19:10:53.531Z,1686337853.531 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T19:10:53.531Z,1686337853.531 [Default:CheckIn](INFO): Running loop #38 2023-06-09T19:10:53.531Z,1686337853.531 [Default:CheckIn] Running Loop=38 2023-06-09T19:10:53.531Z,1686337853.531 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T19:10:53.531Z,1686337853.531 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T19:11:26.248Z,1686337886.248 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-06-09T19:11:26.331Z,1686337886.331 [NAL9602](ERROR): received: +CSQ:0 OK899, 2, 0, 0, 0 OK 2023-06-09T19:15:35.524Z,1686338135.524 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-06-09T19:15:35.524Z,1686338135.524 [DropWeight] Hardware Fault, FailCount= 1 2023-06-09T19:15:35.524Z,1686338135.524 [DropWeight](ERROR): Hardware Fault 2023-06-09T19:15:35.576Z,1686338135.576 [CBIT](INFO): Critical error at 20230609T191535 2023-06-09T19:15:35.578Z,1686338135.578 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-06-09T19:15:35.578Z,1686338135.578 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-06-09T19:15:35.958Z,1686338135.958 [CBIT](INFO): Critical error at 20230609T191535 2023-06-09T19:15:53.716Z,1686338153.716 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-09T19:10:53.5Z 2023-06-09T19:15:53.716Z,1686338153.716 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T19:15:53.716Z,1686338153.716 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-09T19:15:57.732Z,1686338157.732 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-09T19:16:01.103Z,1686338161.103 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20230609T154002/Courier0108.lzma 2023-06-09T19:16:02.105Z,1686338162.105 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0108.lzma.bak 2023-06-09T19:16:02.105Z,1686338162.105 [DataOverHttps](INFO): SBD MOMSN=18403075 2023-06-09T19:16:17.855Z,1686338177.855 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230609T154002/Express0109.lzma 2023-06-09T19:16:18.857Z,1686338178.857 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0109.lzma.bak 2023-06-09T19:16:18.857Z,1686338178.857 [DataOverHttps](INFO): SBD MOMSN=18403079 2023-06-09T19:16:19.965Z,1686338179.965 [Default:CheckIn:Read_Iridium] Stopped 2023-06-09T19:16:19.965Z,1686338179.965 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-09T19:16:19.965Z,1686338179.965 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-09T19:16:28.434Z,1686338188.434 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-09T19:21:20.536Z,1686338480.536 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-09T19:21:20.536Z,1686338480.536 [Default:CheckIn:C.Wait] Stopped 2023-06-09T19:21:20.536Z,1686338480.536 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-09T19:21:20.536Z,1686338480.536 [Default:CheckIn:D] Running Loop=1 2023-06-09T19:21:20.946Z,1686338480.946 [Default:CheckIn:D] Stopped 2023-06-09T19:21:20.946Z,1686338480.946 [Default:CheckIn:E] Running Loop=1 2023-06-09T19:21:21.358Z,1686338481.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 235.009163 min 2023-06-09T19:21:21.358Z,1686338481.358 [Default:CheckIn:E] Stopped 2023-06-09T19:21:21.359Z,1686338481.359 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-09T19:21:21.359Z,1686338481.359 [Default:CheckIn] Stopped 2023-06-09T19:21:21.359Z,1686338481.359 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T19:21:21.359Z,1686338481.359 [Default:CheckIn](INFO): Running loop #39 2023-06-09T19:21:21.359Z,1686338481.359 [Default:CheckIn] Running Loop=39 2023-06-09T19:21:21.359Z,1686338481.359 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-09T19:21:21.359Z,1686338481.359 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-09T19:21:22.952Z,1686338482.952 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-06-09T19:21:22.952Z,1686338482.952 [NAL9602] Data Fault, FailCount= 2 2023-06-09T19:21:22.952Z,1686338482.952 [NAL9602](ERROR): Data Fault 2023-06-09T19:21:22.988Z,1686338482.988 [CBIT](ERROR): Data Fault in component: NAL9602 2023-06-09T19:21:23.360Z,1686338483.360 [NAL9602](INFO): Powering down 2023-06-09T19:21:24.190Z,1686338484.190 [CBIT](INFO): Clearing failed state for component NAL9602 2023-06-09T19:21:24.190Z,1686338484.190 [NAL9602] No Fault, FailCount= 2 2023-06-09T19:21:44.540Z,1686338504.540 [CommandExec](IMPORTANT): got command quit 2023-06-09T19:21:45.543Z,1686338505.543 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-09T19:21:45.543Z,1686338505.543 [CommandExec](INFO): Uninitializing the command executive. 2023-06-09T19:21:45.543Z,1686338505.543 [CommandExec](INFO): Uninitializing the command scheduler. 2023-06-09T19:21:45.544Z,1686338505.544 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:45.647Z,1686338505.647 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-06-09T19:21:45.647Z,1686338505.647 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-06-09T19:21:45.648Z,1686338505.648 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:45.648Z,1686338505.648 [NavChartDb](INFO): Join timeout helper Thread ID is 5339 2023-06-09T19:21:45.731Z,1686338505.731 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-09T19:21:45.731Z,1686338505.731 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:45.739Z,1686338505.739 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-06-09T19:21:45.739Z,1686338505.739 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:45.739Z,1686338505.739 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5340 2023-06-09T19:21:46.191Z,1686338506.191 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-09T19:21:46.192Z,1686338506.192 [WetLabsBB2FL](INFO): Powering down 2023-06-09T19:21:46.192Z,1686338506.192 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:46.211Z,1686338506.211 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-06-09T19:21:46.211Z,1686338506.211 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:46.211Z,1686338506.211 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5341 2023-06-09T19:21:46.979Z,1686338506.979 [CTD_Seabird](INFO): Powering down 2023-06-09T19:21:46.991Z,1686338506.991 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-09T19:21:46.991Z,1686338506.991 [CTD_Seabird](INFO): Powering down 2023-06-09T19:21:47.003Z,1686338507.003 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:47.003Z,1686338507.003 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-06-09T19:21:47.003Z,1686338507.003 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:47.004Z,1686338507.004 [Radio_Surface](INFO): Join timeout helper Thread ID is 5342 2023-06-09T19:21:47.355Z,1686338507.355 [Radio_Surface](INFO): Powering down 2023-06-09T19:21:47.356Z,1686338507.356 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-09T19:21:47.356Z,1686338507.356 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:47.371Z,1686338507.371 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-06-09T19:21:47.371Z,1686338507.371 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:47.371Z,1686338507.371 [Onboard](INFO): Join timeout helper Thread ID is 5343 2023-06-09T19:21:48.167Z,1686338508.167 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-06-09T19:21:49.923Z,1686338509.923 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-09T19:21:49.924Z,1686338509.924 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:49.936Z,1686338509.936 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-06-09T19:21:49.936Z,1686338509.936 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:49.936Z,1686338509.936 [DataOverHttps](INFO): Join timeout helper Thread ID is 5344 2023-06-09T19:21:50.175Z,1686338510.175 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-09T19:21:50.175Z,1686338510.175 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.180Z,1686338510.180 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-06-09T19:21:50.180Z,1686338510.180 [logger ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.181Z,1686338510.181 [logger](INFO): Join timeout helper Thread ID is 5345 2023-06-09T19:21:50.183Z,1686338510.183 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-09T19:21:50.183Z,1686338510.183 [logger ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.200Z,1686338510.200 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-06-09T19:21:50.200Z,1686338510.200 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.200Z,1686338510.200 [CommandLine](INFO): Join timeout helper Thread ID is 5346 2023-06-09T19:21:50.299Z,1686338510.299 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-09T19:21:50.299Z,1686338510.299 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.320Z,1686338510.320 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-06-09T19:21:50.320Z,1686338510.320 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.320Z,1686338510.320 [CommandExec](INFO): Join timeout helper Thread ID is 5347 2023-06-09T19:21:50.321Z,1686338510.321 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-06-09T19:21:50.321Z,1686338510.321 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.322Z,1686338510.322 [controlThread](INFO): Join timeout helper Thread ID is 5348 2023-06-09T19:21:50.407Z,1686338510.407 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-09T19:21:50.407Z,1686338510.407 [controlThread](DEBUG): Uninitializing ControlThread 2023-06-09T19:21:50.408Z,1686338510.408 [AHRS_M2](INFO): Powering down 2023-06-09T19:21:50.480Z,1686338510.480 [NAL9602](INFO): Powering down 2023-06-09T19:21:50.482Z,1686338510.482 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-06-09T19:21:50.483Z,1686338510.483 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-06-09T19:21:50.484Z,1686338510.484 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-06-09T19:21:50.484Z,1686338510.484 [MissionManager](INFO): Uninitializing Mission Default 2023-06-09T19:21:50.484Z,1686338510.484 [Default] Stopped 2023-06-09T19:21:50.484Z,1686338510.484 [Default](DEBUG): Aggregate::uninitialize Default 2023-06-09T19:21:50.484Z,1686338510.484 [Default:B.GoToSurface] Stopped 2023-06-09T19:21:50.484Z,1686338510.484 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-06-09T19:21:50.485Z,1686338510.485 [Default:CheckIn] Stopped 2023-06-09T19:21:50.485Z,1686338510.485 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-09T19:21:50.485Z,1686338510.485 [Default:CheckIn:Read_GPS] Stopped 2023-06-09T19:21:50.487Z,1686338510.487 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-06-09T19:21:50.488Z,1686338510.488 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-06-09T19:21:50.488Z,1686338510.488 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-06-09T19:21:50.488Z,1686338510.488 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-06-09T19:21:50.489Z,1686338510.489 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-06-09T19:21:50.489Z,1686338510.489 [BuoyancyServo](INFO): Powering down 2023-06-09T19:21:50.503Z,1686338510.503 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-06-09T19:21:50.503Z,1686338510.503 [ElevatorServo](INFO): Powering down 2023-06-09T19:21:50.504Z,1686338510.504 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-06-09T19:21:50.504Z,1686338510.504 [MassServo](INFO): Powering down 2023-06-09T19:21:50.505Z,1686338510.505 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-06-09T19:21:50.505Z,1686338510.505 [RudderServo](INFO): Powering down 2023-06-09T19:21:50.506Z,1686338510.506 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-06-09T19:21:50.506Z,1686338510.506 [ThrusterServo](INFO): Powering down 2023-06-09T19:21:50.507Z,1686338510.507 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-06-09T19:21:50.507Z,1686338510.507 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-06-09T19:21:50.508Z,1686338510.508 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-06-09T19:21:50.508Z,1686338510.508 [CBIT](DEBUG): Powering off loads. 2023-06-09T19:21:50.519Z,1686338510.519 [CBIT](DEBUG): Disabling WDT. 2023-06-09T19:21:50.531Z,1686338510.531 [CBIT](DEBUG): Opening all GF detection circuits. 2023-06-09T19:21:50.532Z,1686338510.532 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.581Z,1686338510.581 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.584Z,1686338510.584 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.590Z,1686338510.590 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.661Z,1686338510.661 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.664Z,1686338510.664 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:50.704Z,1686338510.704 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-09T19:21:51.108Z,1686338511.108 [logger ThreadHandler](INFO): Thread cancelled.