2024-05-24T16:57:33.541Z,1716569853.541 [CommandExec](IMPORTANT): got command restart logs 2024-05-24T16:57:36.995Z,1716569856.995 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T16:57:36.000Z,1716569857.000 [Default:CheckIn:C.Wait] Stopped 2024-05-24T16:57:36.000Z,1716569857.000 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T16:57:37.000Z,1716569857.000 [Default:CheckIn:D] Running Loop=1 2024-05-24T16:57:37.404Z,1716569857.404 [Default:CheckIn:D] Stopped 2024-05-24T16:57:37.404Z,1716569857.404 [Default:CheckIn:E] Running Loop=1 2024-05-24T16:57:37.808Z,1716569857.808 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.163147 min 2024-05-24T16:57:37.808Z,1716569857.808 [Default:CheckIn:E] Stopped 2024-05-24T16:57:37.808Z,1716569857.808 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T16:57:37.808Z,1716569857.808 [Default:CheckIn] Stopped 2024-05-24T16:57:37.808Z,1716569857.808 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T16:57:37.809Z,1716569857.809 [Default:CheckIn](INFO): Running loop #3 2024-05-24T16:57:37.809Z,1716569857.809 [Default:CheckIn] Running Loop=3 2024-05-24T16:57:37.809Z,1716569857.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T16:57:37.809Z,1716569857.809 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T16:57:39.803Z,1716569859.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165741.00,A,3648.19039,N,12150.11877,W,21.693,59.44,240524,,,D*7B 2024-05-24T16:57:39.805Z,1716569859.805 [NAL9602](INFO): GPS fix at 20240524T165741: (36.803173, -121.835313) 2024-05-24T16:57:39.827Z,1716569859.827 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T16:57:39.836Z,1716569859.836 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T16:57:50.155Z,1716569870.155 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240523T210623/Courier0090.lzma 2024-05-24T16:57:51.158Z,1716569871.158 [DataOverHttps](INFO): Moved sent file to Logs/20240523T210623/Courier0090.lzma.bak 2024-05-24T16:57:51.159Z,1716569871.159 [DataOverHttps](INFO): SBD MOMSN=21083243 2024-05-24T16:58:01.347Z,1716569881.347 [NAL9602](INFO): SBD MO Status=0, MOMSN=51596, MT Status=0, MTMSN=0 2024-05-24T16:58:01.347Z,1716569881.347 [NAL9602](INFO): No messages in MT queue 2024-05-24T16:58:06.963Z,1716569886.963 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20240523T210623/Express0091.lzma 2024-05-24T16:58:07.966Z,1716569887.966 [DataOverHttps](INFO): Moved sent file to Logs/20240523T210623/Express0091.lzma.bak 2024-05-24T16:58:07.966Z,1716569887.966 [DataOverHttps](INFO): SBD MOMSN=21083245 2024-05-24T16:58:09.440Z,1716569889.440 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T16:58:09.440Z,1716569889.440 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T16:58:09.440Z,1716569889.440 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T16:58:32.049Z,1716569912.049 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:03:10.242Z,1716570190.242 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T17:03:10.242Z,1716570190.242 [Default:CheckIn:C.Wait] Stopped 2024-05-24T17:03:10.242Z,1716570190.242 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T17:03:10.243Z,1716570190.243 [Default:CheckIn:D] Running Loop=1 2024-05-24T17:03:10.640Z,1716570190.640 [Default:CheckIn:D] Stopped 2024-05-24T17:03:10.641Z,1716570190.641 [Default:CheckIn:E] Running Loop=1 2024-05-24T17:03:11.043Z,1716570191.043 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.717161 min 2024-05-24T17:03:11.043Z,1716570191.043 [Default:CheckIn:E] Stopped 2024-05-24T17:03:11.043Z,1716570191.043 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T17:03:11.043Z,1716570191.043 [Default:CheckIn] Stopped 2024-05-24T17:03:11.044Z,1716570191.044 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T17:03:11.044Z,1716570191.044 [Default:CheckIn](INFO): Running loop #4 2024-05-24T17:03:11.044Z,1716570191.044 [Default:CheckIn] Running Loop=4 2024-05-24T17:03:11.044Z,1716570191.044 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T17:03:11.044Z,1716570191.044 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T17:03:13.059Z,1716570193.059 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170314.00,A,3648.61364,N,12148.97033,W,8.903,179.77,240524,,,D*7E 2024-05-24T17:03:13.061Z,1716570193.061 [NAL9602](INFO): GPS fix at 20240524T170314: (36.810227, -121.816172) 2024-05-24T17:03:13.072Z,1716570193.072 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T17:03:13.072Z,1716570193.072 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T17:03:20.324Z,1716570200.324 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20240524T165733/Courier0005.lzma 2024-05-24T17:03:21.326Z,1716570201.326 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0005.lzma.bak 2024-05-24T17:03:21.326Z,1716570201.326 [DataOverHttps](INFO): SBD MOMSN=21083262 2024-05-24T17:03:37.280Z,1716570217.280 [DataOverHttps](INFO): Sending 441 bytes from file Logs/20240524T165733/Express0007.lzma 2024-05-24T17:03:38.282Z,1716570218.282 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0007.lzma.bak 2024-05-24T17:03:38.282Z,1716570218.282 [DataOverHttps](INFO): SBD MOMSN=21083266 2024-05-24T17:03:38.502Z,1716570218.502 [NAL9602](INFO): SBD MO Status=0, MOMSN=51597, MT Status=0, MTMSN=0 2024-05-24T17:03:38.502Z,1716570218.502 [NAL9602](INFO): No messages in MT queue 2024-05-24T17:03:39.733Z,1716570219.733 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T17:03:39.733Z,1716570219.733 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T17:03:39.733Z,1716570219.733 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T17:04:09.205Z,1716570249.205 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:08:40.485Z,1716570520.485 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T17:08:40.485Z,1716570520.485 [Default:CheckIn:C.Wait] Stopped 2024-05-24T17:08:40.485Z,1716570520.485 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T17:08:40.485Z,1716570520.485 [Default:CheckIn:D] Running Loop=1 2024-05-24T17:08:40.883Z,1716570520.883 [Default:CheckIn:D] Stopped 2024-05-24T17:08:40.883Z,1716570520.883 [Default:CheckIn:E] Running Loop=1 2024-05-24T17:08:41.282Z,1716570521.282 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.221200 min 2024-05-24T17:08:41.282Z,1716570521.282 [Default:CheckIn:E] Stopped 2024-05-24T17:08:41.282Z,1716570521.282 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T17:08:41.282Z,1716570521.282 [Default:CheckIn] Stopped 2024-05-24T17:08:41.283Z,1716570521.283 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T17:08:41.283Z,1716570521.283 [Default:CheckIn](INFO): Running loop #5 2024-05-24T17:08:41.283Z,1716570521.283 [Default:CheckIn] Running Loop=5 2024-05-24T17:08:41.283Z,1716570521.283 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T17:08:41.283Z,1716570521.283 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T17:08:43.295Z,1716570523.295 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170845.00,A,3648.39583,N,12148.92158,W,0.505,304.28,240524,,,A*7F 2024-05-24T17:08:43.297Z,1716570523.297 [NAL9602](INFO): GPS fix at 20240524T170845: (36.806597, -121.815360) 2024-05-24T17:08:43.308Z,1716570523.308 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T17:08:43.308Z,1716570523.308 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T17:08:44.137Z,1716570524.137 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T17:08:44.137Z,1716570524.137 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T17:08:44.137Z,1716570524.137 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T17:08:44.745Z,1716570524.745 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-05-24T17:09:05.198Z,1716570545.198 [NAL9602](INFO): SBD MO Status=0, MOMSN=51598, MT Status=0, MTMSN=0 2024-05-24T17:09:05.198Z,1716570545.198 [NAL9602](INFO): No messages in MT queue 2024-05-24T17:09:35.895Z,1716570575.895 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:09:47.300Z,1716570587.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:10:18.424Z,1716570618.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:10:49.549Z,1716570649.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:10:52.561Z,1716570652.561 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.923508 2024-05-24T17:11:50.176Z,1716570710.176 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003129 2024-05-24T17:13:44.779Z,1716570824.779 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T17:13:44.779Z,1716570824.779 [Default:CheckIn:C.Wait] Stopped 2024-05-24T17:13:44.779Z,1716570824.779 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T17:13:44.780Z,1716570824.780 [Default:CheckIn:D] Running Loop=1 2024-05-24T17:13:45.187Z,1716570825.187 [Default:CheckIn:D] Stopped 2024-05-24T17:13:45.187Z,1716570825.187 [Default:CheckIn:E] Running Loop=1 2024-05-24T17:13:45.603Z,1716570825.603 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.292940 min 2024-05-24T17:13:45.603Z,1716570825.603 [Default:CheckIn:E] Stopped 2024-05-24T17:13:45.603Z,1716570825.603 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T17:13:45.603Z,1716570825.603 [Default:CheckIn] Stopped 2024-05-24T17:13:45.603Z,1716570825.603 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T17:13:45.603Z,1716570825.603 [Default:CheckIn](INFO): Running loop #6 2024-05-24T17:13:45.603Z,1716570825.603 [Default:CheckIn] Running Loop=6 2024-05-24T17:13:45.603Z,1716570825.603 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T17:13:45.603Z,1716570825.603 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T17:13:47.597Z,1716570827.597 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171349.00,A,3648.43067,N,12148.84654,W,4.276,72.06,240524,,,D*4B 2024-05-24T17:13:47.599Z,1716570827.599 [NAL9602](INFO): GPS fix at 20240524T171349: (36.807178, -121.814109) 2024-05-24T17:13:47.611Z,1716570827.611 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T17:13:47.611Z,1716570827.611 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T17:14:06.994Z,1716570846.994 [NAL9602](INFO): SBD MO Status=0, MOMSN=51599, MT Status=0, MTMSN=0 2024-05-24T17:14:06.994Z,1716570846.994 [NAL9602](INFO): No messages in MT queue 2024-05-24T17:14:37.693Z,1716570877.693 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:14:50.868Z,1716570890.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:15:21.992Z,1716570921.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:15:53.128Z,1716570953.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:16:24.272Z,1716570984.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:16:31.301Z,1716570991.301 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.479492 2024-05-24T17:16:42.986Z,1716571002.986 [NAL9602](INFO): SBD MO Status=1, MOMSN=51600, MT Status=0, MTMSN=0 2024-05-24T17:16:43.044Z,1716571003.044 [NAL9602](INFO): Sent 74 bytes from file Logs/20240524T165733/Courier0009.lzma 2024-05-24T17:16:43.044Z,1716571003.044 [NAL9602](INFO): Packets left to send: 0 2024-05-24T17:16:53.140Z,1716571013.140 [NAL9602](INFO): SBD MO Status=1, MOMSN=51601, MT Status=0, MTMSN=0 2024-05-24T17:16:53.200Z,1716571013.200 [NAL9602](INFO): Sent 74 bytes from file Logs/20240524T165733/Courier0012.lzma 2024-05-24T17:16:53.200Z,1716571013.200 [NAL9602](INFO): Packets left to send: 0 2024-05-24T17:17:02.424Z,1716571022.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:17:11.757Z,1716571031.757 [NAL9602](INFO): SBD MO Status=1, MOMSN=51602, MT Status=0, MTMSN=0 2024-05-24T17:17:11.804Z,1716571031.804 [NAL9602](INFO): Sent 145 bytes from file Logs/20240524T165733/Express0010.lzma 2024-05-24T17:17:11.804Z,1716571031.804 [NAL9602](INFO): Packets left to send: 0 2024-05-24T17:17:19.890Z,1716571039.890 [NAL9602](INFO): SBD MO Status=1, MOMSN=51603, MT Status=0, MTMSN=0 2024-05-24T17:17:19.940Z,1716571039.940 [NAL9602](INFO): Sent 144 bytes from file Logs/20240524T165733/Express0013.lzma 2024-05-24T17:17:19.940Z,1716571039.940 [NAL9602](INFO): Packets left to send: 0 2024-05-24T17:17:29.182Z,1716571049.182 [NAL9602](INFO): SBD MO Status=0, MOMSN=51604, MT Status=0, MTMSN=0 2024-05-24T17:17:29.261Z,1716571049.261 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T17:17:29.262Z,1716571049.262 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T17:17:29.262Z,1716571049.262 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T17:17:33.548Z,1716571053.548 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:17:59.889Z,1716571079.889 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:18:04.672Z,1716571084.672 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:18:21.318Z,1716571101.318 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T17:18:34.218Z,1716571114.218 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T17:18:35.816Z,1716571115.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:18:47.550Z,1716571127.550 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T17:19:00.883Z,1716571140.883 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T17:19:06.940Z,1716571146.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:19:13.407Z,1716571153.407 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T17:19:17.616Z,1716571157.616 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003130 2024-05-24T17:19:25.931Z,1716571165.931 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T17:19:38.457Z,1716571178.457 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T17:20:08.844Z,1716571208.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:20:39.996Z,1716571239.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:21:11.120Z,1716571271.120 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:21:42.244Z,1716571302.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:22:13.368Z,1716571333.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-24T17:22:18.389Z,1716571338.389 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.775238 2024-05-24T17:22:19.254Z,1716571339.254 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6. 2024-05-24T17:22:19.257Z,1716571339.257 [BPC1](INFO): Received data from all battery sticks. 2024-05-24T17:22:29.767Z,1716571349.767 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T17:22:29.767Z,1716571349.767 [Default:CheckIn:C.Wait] Stopped 2024-05-24T17:22:29.767Z,1716571349.767 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T17:22:29.767Z,1716571349.767 [Default:CheckIn:D] Running Loop=1 2024-05-24T17:22:30.171Z,1716571350.171 [Default:CheckIn:D] Stopped 2024-05-24T17:22:30.171Z,1716571350.171 [Default:CheckIn:E] Running Loop=1 2024-05-24T17:22:30.575Z,1716571350.575 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.042664 min 2024-05-24T17:22:30.575Z,1716571350.575 [Default:CheckIn:E] Stopped 2024-05-24T17:22:30.575Z,1716571350.575 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T17:22:30.575Z,1716571350.575 [Default:CheckIn] Stopped 2024-05-24T17:22:30.575Z,1716571350.575 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T17:22:30.576Z,1716571350.576 [Default:CheckIn](INFO): Running loop #7 2024-05-24T17:22:30.576Z,1716571350.576 [Default:CheckIn] Running Loop=7 2024-05-24T17:22:30.576Z,1716571350.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T17:22:30.576Z,1716571350.576 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T17:22:32.582Z,1716571352.582 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172234.00,A,3648.36195,N,12148.04236,W,4.102,94.14,240524,,,D*4E 2024-05-24T17:22:32.594Z,1716571352.594 [NAL9602](INFO): GPS fix at 20240524T172234: (36.806033, -121.800706) 2024-05-24T17:22:32.683Z,1716571352.683 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T17:22:32.683Z,1716571352.683 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T17:23:03.334Z,1716571383.334 [NAL9602](INFO): SBD MO Status=1, MOMSN=51605, MT Status=0, MTMSN=0 2024-05-24T17:23:03.384Z,1716571383.384 [NAL9602](INFO): Sent 202 bytes from file Logs/20240524T165733/Courier0015.lzma 2024-05-24T17:23:03.384Z,1716571383.384 [NAL9602](INFO): Packets left to send: 0 2024-05-24T17:23:13.016Z,1716571393.016 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003146 2024-05-24T17:23:21.202Z,1716571401.202 [NAL9602](INFO): SBD MO Status=1, MOMSN=51606, MT Status=0, MTMSN=0 2024-05-24T17:23:21.262Z,1716571401.262 [NAL9602](INFO): Sent 145 bytes from file Logs/20240524T165733/Express0016.lzma 2024-05-24T17:23:21.262Z,1716571401.262 [NAL9602](INFO): Packets left to send: 0 2024-05-24T17:23:22.020Z,1716571402.020 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T17:23:22.021Z,1716571402.021 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T17:23:22.021Z,1716571402.021 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T17:23:51.893Z,1716571431.893 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:28:22.832Z,1716571702.832 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T17:28:22.833Z,1716571702.833 [Default:CheckIn:C.Wait] Stopped 2024-05-24T17:28:22.833Z,1716571702.833 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T17:28:22.833Z,1716571702.833 [Default:CheckIn:D] Running Loop=1 2024-05-24T17:28:23.218Z,1716571703.218 [Default:CheckIn:D] Stopped 2024-05-24T17:28:23.218Z,1716571703.218 [Default:CheckIn:E] Running Loop=1 2024-05-24T17:28:23.645Z,1716571703.645 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.926778 min 2024-05-24T17:28:23.645Z,1716571703.645 [Default:CheckIn:E] Stopped 2024-05-24T17:28:23.645Z,1716571703.645 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T17:28:23.645Z,1716571703.645 [Default:CheckIn] Stopped 2024-05-24T17:28:23.646Z,1716571703.646 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T17:28:23.646Z,1716571703.646 [Default:CheckIn](INFO): Running loop #8 2024-05-24T17:28:23.646Z,1716571703.646 [Default:CheckIn] Running Loop=8 2024-05-24T17:28:23.646Z,1716571703.646 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T17:28:23.646Z,1716571703.646 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T17:28:25.591Z,1716571705.591 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172827.00,A,3648.35190,N,12147.49035,W,4.976,63.39,240524,,,D*4B 2024-05-24T17:28:25.593Z,1716571705.593 [NAL9602](INFO): GPS fix at 20240524T172827: (36.805865, -121.791506) 2024-05-24T17:28:25.661Z,1716571705.661 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T17:28:25.661Z,1716571705.661 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T17:28:28.135Z,1716571708.135 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240524T165733/Courier0018.lzma 2024-05-24T17:28:29.138Z,1716571709.138 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0018.lzma.bak 2024-05-24T17:28:29.138Z,1716571709.138 [DataOverHttps](INFO): SBD MOMSN=21083444 2024-05-24T17:28:42.318Z,1716571722.318 [NAL9602](INFO): SBD MO Status=2, MOMSN=51607, MT Status=2, MTMSN=0 2024-05-24T17:28:42.319Z,1716571722.319 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-24T17:28:45.300Z,1716571725.300 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240524T165733/Express0019.lzma 2024-05-24T17:28:46.302Z,1716571726.302 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0019.lzma.bak 2024-05-24T17:28:46.302Z,1716571726.302 [DataOverHttps](INFO): SBD MOMSN=21083448 2024-05-24T17:28:47.589Z,1716571727.589 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T17:28:47.589Z,1716571727.589 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T17:28:47.589Z,1716571727.589 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T17:29:13.465Z,1716571753.465 [NAL9602](INFO): SBD MO Status=0, MOMSN=51607, MT Status=0, MTMSN=0 2024-05-24T17:29:13.465Z,1716571753.465 [NAL9602](INFO): No messages in MT queue 2024-05-24T17:29:44.173Z,1716571784.173 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:33:48.251Z,1716572028.251 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T17:33:48.251Z,1716572028.251 [Default:CheckIn:C.Wait] Stopped 2024-05-24T17:33:48.251Z,1716572028.251 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T17:33:48.252Z,1716572028.252 [Default:CheckIn:D] Running Loop=1 2024-05-24T17:33:48.661Z,1716572028.661 [Default:CheckIn:D] Stopped 2024-05-24T17:33:48.661Z,1716572028.661 [Default:CheckIn:E] Running Loop=1 2024-05-24T17:33:49.054Z,1716572029.054 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.350834 min 2024-05-24T17:33:49.055Z,1716572029.055 [Default:CheckIn:E] Stopped 2024-05-24T17:33:49.055Z,1716572029.055 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T17:33:49.055Z,1716572029.055 [Default:CheckIn] Stopped 2024-05-24T17:33:49.055Z,1716572029.055 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T17:33:49.055Z,1716572029.055 [Default:CheckIn](INFO): Running loop #9 2024-05-24T17:33:49.055Z,1716572029.055 [Default:CheckIn] Running Loop=9 2024-05-24T17:33:49.055Z,1716572029.055 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T17:33:49.055Z,1716572029.055 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T17:33:51.066Z,1716572031.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173352.00,A,3648.50200,N,12147.11452,W,4.218,139.35,240524,,,D*73 2024-05-24T17:33:51.068Z,1716572031.068 [NAL9602](INFO): GPS fix at 20240524T173352: (36.808367, -121.785242) 2024-05-24T17:33:51.079Z,1716572031.079 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T17:33:51.079Z,1716572031.079 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T17:33:52.686Z,1716572032.686 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2024-05-24T17:33:52.686Z,1716572032.686 [NAL9602] Hardware Fault, FailCount= 1 2024-05-24T17:33:52.686Z,1716572032.686 [NAL9602](ERROR): Hardware Fault 2024-05-24T17:33:52.708Z,1716572032.708 [CBIT](ERROR): Hardware Fault in component: NAL9602 2024-05-24T17:33:53.089Z,1716572033.089 [NAL9602](INFO): Powering down 2024-05-24T17:33:53.493Z,1716572033.493 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2024-05-24T17:33:53.948Z,1716572033.948 [CBIT](INFO): Clearing failed state for component NAL9602 2024-05-24T17:33:53.948Z,1716572033.948 [NAL9602] No Fault, FailCount= 1 2024-05-24T17:33:58.183Z,1716572038.183 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240524T165733/Courier0021.lzma 2024-05-24T17:33:59.186Z,1716572039.186 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0021.lzma.bak 2024-05-24T17:33:59.186Z,1716572039.186 [DataOverHttps](INFO): SBD MOMSN=21083473 2024-05-24T17:34:14.968Z,1716572054.968 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240524T165733/Express0022.lzma 2024-05-24T17:34:15.970Z,1716572055.970 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0022.lzma.bak 2024-05-24T17:34:15.970Z,1716572055.970 [DataOverHttps](INFO): SBD MOMSN=21083476 2024-05-24T17:34:17.354Z,1716572057.354 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T17:34:17.355Z,1716572057.355 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T17:34:17.355Z,1716572057.355 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T17:34:23.429Z,1716572063.429 [NAL9602](INFO): Powering up NAL9602 2024-05-24T17:34:34.333Z,1716572074.333 [NAL9602](INFO): NAL9602 initialized 2024-05-24T17:35:05.506Z,1716572105.506 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:39:18.052Z,1716572358.052 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T17:39:18.052Z,1716572358.052 [Default:CheckIn:C.Wait] Stopped 2024-05-24T17:39:18.052Z,1716572358.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T17:39:18.052Z,1716572358.052 [Default:CheckIn:D] Running Loop=1 2024-05-24T17:39:18.469Z,1716572358.469 [Default:CheckIn:D] Stopped 2024-05-24T17:39:18.469Z,1716572358.469 [Default:CheckIn:E] Running Loop=1 2024-05-24T17:39:18.855Z,1716572358.855 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.847640 min 2024-05-24T17:39:18.855Z,1716572358.855 [Default:CheckIn:E] Stopped 2024-05-24T17:39:18.855Z,1716572358.855 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T17:39:18.855Z,1716572358.855 [Default:CheckIn] Stopped 2024-05-24T17:39:18.856Z,1716572358.856 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T17:39:18.856Z,1716572358.856 [Default:CheckIn](INFO): Running loop #10 2024-05-24T17:39:18.856Z,1716572358.856 [Default:CheckIn] Running Loop=10 2024-05-24T17:39:18.856Z,1716572358.856 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T17:39:18.856Z,1716572358.856 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T17:39:20.863Z,1716572360.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173922.00,A,3648.15482,N,12147.17618,W,2.313,203.26,240524,,,A*78 2024-05-24T17:39:20.866Z,1716572360.866 [NAL9602](INFO): GPS fix at 20240524T173922: (36.802580, -121.786270) 2024-05-24T17:39:20.877Z,1716572360.877 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T17:39:20.877Z,1716572360.877 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T17:39:21.352Z,1716572361.352 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-05-24T17:39:27.900Z,1716572367.900 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240524T165733/Courier0024.lzma 2024-05-24T17:39:28.902Z,1716572368.902 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0024.lzma.bak 2024-05-24T17:39:28.902Z,1716572368.902 [DataOverHttps](INFO): SBD MOMSN=21083488 2024-05-24T17:39:35.890Z,1716572375.890 [NAL9602](INFO): SBD MO Status=0, MOMSN=51608, MT Status=0, MTMSN=0 2024-05-24T17:39:35.890Z,1716572375.890 [NAL9602](INFO): No messages in MT queue 2024-05-24T17:39:44.824Z,1716572384.824 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20240524T165733/Express0025.lzma 2024-05-24T17:39:45.826Z,1716572385.826 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0025.lzma.bak 2024-05-24T17:39:45.826Z,1716572385.826 [DataOverHttps](INFO): SBD MOMSN=21083491 2024-05-24T17:39:47.211Z,1716572387.211 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T17:39:47.211Z,1716572387.211 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T17:39:47.211Z,1716572387.211 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T17:40:06.593Z,1716572406.593 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:44:47.883Z,1716572687.883 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T17:44:47.883Z,1716572687.883 [Default:CheckIn:C.Wait] Stopped 2024-05-24T17:44:47.883Z,1716572687.883 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T17:44:47.883Z,1716572687.883 [Default:CheckIn:D] Running Loop=1 2024-05-24T17:44:48.322Z,1716572688.322 [Default:CheckIn:D] Stopped 2024-05-24T17:44:48.322Z,1716572688.322 [Default:CheckIn:E] Running Loop=1 2024-05-24T17:44:48.709Z,1716572688.709 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.345186 min 2024-05-24T17:44:48.709Z,1716572688.709 [Default:CheckIn:E] Stopped 2024-05-24T17:44:48.710Z,1716572688.710 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T17:44:48.710Z,1716572688.710 [Default:CheckIn] Stopped 2024-05-24T17:44:48.710Z,1716572688.710 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T17:44:48.710Z,1716572688.710 [Default:CheckIn](INFO): Running loop #11 2024-05-24T17:44:48.710Z,1716572688.710 [Default:CheckIn] Running Loop=11 2024-05-24T17:44:48.710Z,1716572688.710 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T17:44:48.710Z,1716572688.710 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T17:44:50.706Z,1716572690.706 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174452.00,A,3648.13588,N,12147.20522,W,0.447,232.31,240524,,,A*76 2024-05-24T17:44:50.724Z,1716572690.724 [NAL9602](INFO): GPS fix at 20240524T174452: (36.802265, -121.786754) 2024-05-24T17:44:50.751Z,1716572690.751 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T17:44:50.751Z,1716572690.751 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T17:44:57.864Z,1716572697.864 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240524T165733/Courier0027.lzma 2024-05-24T17:44:58.866Z,1716572698.866 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0027.lzma.bak 2024-05-24T17:44:58.866Z,1716572698.866 [DataOverHttps](INFO): SBD MOMSN=21083518 2024-05-24T17:45:06.069Z,1716572706.069 [NAL9602](INFO): SBD MO Status=0, MOMSN=51609, MT Status=0, MTMSN=0 2024-05-24T17:45:06.069Z,1716572706.069 [NAL9602](INFO): No messages in MT queue 2024-05-24T17:45:14.585Z,1716572714.585 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240524T165733/Express0028.lzma 2024-05-24T17:45:15.586Z,1716572715.586 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0028.lzma.bak 2024-05-24T17:45:15.586Z,1716572715.586 [DataOverHttps](INFO): SBD MOMSN=21083521 2024-05-24T17:45:16.979Z,1716572716.979 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T17:45:16.979Z,1716572716.979 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T17:45:16.979Z,1716572716.979 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T17:45:36.765Z,1716572736.765 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:50:17.693Z,1716573017.693 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T17:50:17.694Z,1716573017.694 [Default:CheckIn:C.Wait] Stopped 2024-05-24T17:50:17.694Z,1716573017.694 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T17:50:17.694Z,1716573017.694 [Default:CheckIn:D] Running Loop=1 2024-05-24T17:50:18.086Z,1716573018.086 [Default:CheckIn:D] Stopped 2024-05-24T17:50:18.086Z,1716573018.086 [Default:CheckIn:E] Running Loop=1 2024-05-24T17:50:18.484Z,1716573018.484 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.841248 min 2024-05-24T17:50:18.484Z,1716573018.484 [Default:CheckIn:E] Stopped 2024-05-24T17:50:18.484Z,1716573018.484 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T17:50:18.484Z,1716573018.484 [Default:CheckIn] Stopped 2024-05-24T17:50:18.484Z,1716573018.484 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T17:50:18.484Z,1716573018.484 [Default:CheckIn](INFO): Running loop #12 2024-05-24T17:50:18.484Z,1716573018.484 [Default:CheckIn] Running Loop=12 2024-05-24T17:50:18.484Z,1716573018.484 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T17:50:18.485Z,1716573018.485 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T17:50:20.482Z,1716573020.482 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175022.00,A,3648.13895,N,12147.20597,W,0.078,253.65,240524,,,A*75 2024-05-24T17:50:20.502Z,1716573020.502 [NAL9602](INFO): GPS fix at 20240524T175022: (36.802316, -121.786766) 2024-05-24T17:50:20.520Z,1716573020.520 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T17:50:20.521Z,1716573020.521 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T17:50:28.415Z,1716573028.415 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20240524T165733/Courier0030.lzma 2024-05-24T17:50:29.428Z,1716573029.428 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0030.lzma.bak 2024-05-24T17:50:29.428Z,1716573029.428 [DataOverHttps](INFO): SBD MOMSN=21083531 2024-05-24T17:50:45.592Z,1716573045.592 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240524T165733/Express0031.lzma 2024-05-24T17:50:46.598Z,1716573046.598 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0031.lzma.bak 2024-05-24T17:50:46.598Z,1716573046.598 [DataOverHttps](INFO): SBD MOMSN=21083534 2024-05-24T17:50:48.063Z,1716573048.063 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T17:50:48.063Z,1716573048.063 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T17:50:48.063Z,1716573048.063 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T17:51:32.111Z,1716573092.111 [NAL9602](INFO): SBD MO Status=0, MOMSN=51610, MT Status=0, MTMSN=0 2024-05-24T17:51:32.111Z,1716573092.111 [NAL9602](INFO): No messages in MT queue 2024-05-24T17:52:02.812Z,1716573122.812 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T17:55:48.687Z,1716573348.687 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T17:55:48.687Z,1716573348.687 [Default:CheckIn:C.Wait] Stopped 2024-05-24T17:55:48.687Z,1716573348.687 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T17:55:48.687Z,1716573348.687 [Default:CheckIn:D] Running Loop=1 2024-05-24T17:55:49.097Z,1716573349.097 [Default:CheckIn:D] Stopped 2024-05-24T17:55:49.097Z,1716573349.097 [Default:CheckIn:E] Running Loop=1 2024-05-24T17:55:49.508Z,1716573349.508 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.358097 min 2024-05-24T17:55:49.508Z,1716573349.508 [Default:CheckIn:E] Stopped 2024-05-24T17:55:49.508Z,1716573349.508 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T17:55:49.508Z,1716573349.508 [Default:CheckIn] Stopped 2024-05-24T17:55:49.508Z,1716573349.508 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T17:55:49.508Z,1716573349.508 [Default:CheckIn](INFO): Running loop #13 2024-05-24T17:55:49.509Z,1716573349.509 [Default:CheckIn] Running Loop=13 2024-05-24T17:55:49.509Z,1716573349.509 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T17:55:49.509Z,1716573349.509 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T17:55:51.502Z,1716573351.502 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175553.00,A,3648.13453,N,12147.20975,W,0.136,253.65,240524,,,A*7B 2024-05-24T17:55:51.504Z,1716573351.504 [NAL9602](INFO): GPS fix at 20240524T175553: (36.802242, -121.786829) 2024-05-24T17:55:51.515Z,1716573351.515 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T17:55:51.515Z,1716573351.515 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T17:55:59.447Z,1716573359.447 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240524T165733/Courier0033.lzma 2024-05-24T17:56:00.450Z,1716573360.450 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0033.lzma.bak 2024-05-24T17:56:00.450Z,1716573360.450 [DataOverHttps](INFO): SBD MOMSN=21083557 2024-05-24T17:56:17.672Z,1716573377.672 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240524T165733/Express0034.lzma 2024-05-24T17:56:18.674Z,1716573378.674 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0034.lzma.bak 2024-05-24T17:56:18.674Z,1716573378.674 [DataOverHttps](INFO): SBD MOMSN=21083560 2024-05-24T17:56:19.840Z,1716573379.840 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T17:56:19.840Z,1716573379.840 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T17:56:19.840Z,1716573379.840 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T17:56:20.190Z,1716573380.190 [NAL9602](INFO): SBD MO Status=0, MOMSN=51611, MT Status=0, MTMSN=0 2024-05-24T17:56:20.190Z,1716573380.190 [NAL9602](INFO): No messages in MT queue 2024-05-24T17:56:50.921Z,1716573410.921 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T18:01:20.416Z,1716573680.416 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T18:01:20.416Z,1716573680.416 [Default:CheckIn:C.Wait] Stopped 2024-05-24T18:01:20.416Z,1716573680.416 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T18:01:20.417Z,1716573680.417 [Default:CheckIn:D] Running Loop=1 2024-05-24T18:01:20.820Z,1716573680.820 [Default:CheckIn:D] Stopped 2024-05-24T18:01:20.820Z,1716573680.820 [Default:CheckIn:E] Running Loop=1 2024-05-24T18:01:21.219Z,1716573681.219 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.886825 min 2024-05-24T18:01:21.220Z,1716573681.220 [Default:CheckIn:E] Stopped 2024-05-24T18:01:21.220Z,1716573681.220 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T18:01:21.220Z,1716573681.220 [Default:CheckIn] Stopped 2024-05-24T18:01:21.220Z,1716573681.220 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T18:01:21.220Z,1716573681.220 [Default:CheckIn](INFO): Running loop #14 2024-05-24T18:01:21.220Z,1716573681.220 [Default:CheckIn] Running Loop=14 2024-05-24T18:01:21.220Z,1716573681.220 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T18:01:21.221Z,1716573681.220 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T18:01:23.229Z,1716573683.229 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180125.00,A,3648.13506,N,12147.20865,W,0.078,253.65,240524,,,D*7B 2024-05-24T18:01:23.231Z,1716573683.231 [NAL9602](INFO): GPS fix at 20240524T180125: (36.802251, -121.786811) 2024-05-24T18:01:23.242Z,1716573683.242 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T18:01:23.242Z,1716573683.242 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T18:01:30.963Z,1716573690.963 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240524T165733/Courier0036.lzma 2024-05-24T18:01:31.966Z,1716573691.966 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0036.lzma.bak 2024-05-24T18:01:31.966Z,1716573691.966 [DataOverHttps](INFO): SBD MOMSN=21083572 2024-05-24T18:01:48.228Z,1716573708.228 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240524T165733/Express0037.lzma 2024-05-24T18:01:49.230Z,1716573709.230 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0037.lzma.bak 2024-05-24T18:01:49.230Z,1716573709.230 [DataOverHttps](INFO): SBD MOMSN=21083575 2024-05-24T18:01:50.715Z,1716573710.715 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T18:01:50.715Z,1716573710.715 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T18:01:50.715Z,1716573710.715 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T18:02:46.454Z,1716573766.454 [NAL9602](INFO): SBD MO Status=2, MOMSN=51612, MT Status=2, MTMSN=0 2024-05-24T18:02:46.455Z,1716573766.455 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-24T18:03:24.842Z,1716573804.842 [NAL9602](INFO): SBD MO Status=0, MOMSN=51612, MT Status=0, MTMSN=0 2024-05-24T18:03:24.843Z,1716573804.843 [NAL9602](INFO): No messages in MT queue 2024-05-24T18:03:55.537Z,1716573835.537 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T18:06:51.307Z,1716574011.307 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T18:06:51.307Z,1716574011.307 [Default:CheckIn:C.Wait] Stopped 2024-05-24T18:06:51.307Z,1716574011.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T18:06:51.307Z,1716574011.307 [Default:CheckIn:D] Running Loop=1 2024-05-24T18:06:51.699Z,1716574011.699 [Default:CheckIn:D] Stopped 2024-05-24T18:06:51.699Z,1716574011.699 [Default:CheckIn:E] Running Loop=1 2024-05-24T18:06:52.114Z,1716574012.114 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.401465 min 2024-05-24T18:06:52.114Z,1716574012.114 [Default:CheckIn:E] Stopped 2024-05-24T18:06:52.114Z,1716574012.114 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T18:06:52.114Z,1716574012.114 [Default:CheckIn] Stopped 2024-05-24T18:06:52.114Z,1716574012.114 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T18:06:52.114Z,1716574012.114 [Default:CheckIn](INFO): Running loop #15 2024-05-24T18:06:52.115Z,1716574012.115 [Default:CheckIn] Running Loop=15 2024-05-24T18:06:52.115Z,1716574012.115 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T18:06:52.115Z,1716574012.115 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T18:06:54.112Z,1716574014.112 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180656.00,A,3648.13945,N,12147.20573,W,0.097,253.65,240524,,,D*78 2024-05-24T18:06:54.114Z,1716574014.114 [NAL9602](INFO): GPS fix at 20240524T180656: (36.802324, -121.786762) 2024-05-24T18:06:54.125Z,1716574014.125 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T18:06:54.125Z,1716574014.125 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T18:07:01.704Z,1716574021.704 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240524T165733/Courier0039.lzma 2024-05-24T18:07:02.706Z,1716574022.706 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0039.lzma.bak 2024-05-24T18:07:02.707Z,1716574022.707 [DataOverHttps](INFO): SBD MOMSN=21083639 2024-05-24T18:07:15.114Z,1716574035.114 [NAL9602](INFO): SBD MO Status=0, MOMSN=51613, MT Status=0, MTMSN=0 2024-05-24T18:07:15.114Z,1716574035.114 [NAL9602](INFO): No messages in MT queue 2024-05-24T18:07:18.688Z,1716574038.688 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240524T165733/Express0040.lzma 2024-05-24T18:07:19.690Z,1716574039.690 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0040.lzma.bak 2024-05-24T18:07:19.690Z,1716574039.690 [DataOverHttps](INFO): SBD MOMSN=21083642 2024-05-24T18:07:21.186Z,1716574041.186 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T18:07:21.186Z,1716574041.186 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T18:07:21.186Z,1716574041.186 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T18:07:45.817Z,1716574065.817 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T18:12:21.765Z,1716574341.765 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T18:12:21.765Z,1716574341.765 [Default:CheckIn:C.Wait] Stopped 2024-05-24T18:12:21.765Z,1716574341.765 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T18:12:21.765Z,1716574341.765 [Default:CheckIn:D] Running Loop=1 2024-05-24T18:12:22.169Z,1716574342.169 [Default:CheckIn:D] Stopped 2024-05-24T18:12:22.169Z,1716574342.169 [Default:CheckIn:E] Running Loop=1 2024-05-24T18:12:22.599Z,1716574342.599 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.909302 min 2024-05-24T18:12:22.599Z,1716574342.599 [Default:CheckIn:E] Stopped 2024-05-24T18:12:22.599Z,1716574342.599 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T18:12:22.599Z,1716574342.599 [Default:CheckIn] Stopped 2024-05-24T18:12:22.599Z,1716574342.599 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T18:12:22.600Z,1716574342.600 [Default:CheckIn](INFO): Running loop #16 2024-05-24T18:12:22.600Z,1716574342.600 [Default:CheckIn] Running Loop=16 2024-05-24T18:12:22.600Z,1716574342.600 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T18:12:22.601Z,1716574342.601 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T18:12:24.579Z,1716574344.579 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181226.00,A,3648.17690,N,12147.27855,W,0.428,253.30,240524,,,A*72 2024-05-24T18:12:24.581Z,1716574344.581 [NAL9602](INFO): GPS fix at 20240524T181226: (36.802948, -121.787976) 2024-05-24T18:12:24.613Z,1716574344.613 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T18:12:24.613Z,1716574344.613 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T18:12:31.615Z,1716574351.615 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240524T165733/Courier0042.lzma 2024-05-24T18:12:32.618Z,1716574352.618 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0042.lzma.bak 2024-05-24T18:12:32.618Z,1716574352.618 [DataOverHttps](INFO): SBD MOMSN=21083652 2024-05-24T18:12:51.604Z,1716574371.604 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240524T165733/Express0043.lzma 2024-05-24T18:12:52.614Z,1716574372.614 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0043.lzma.bak 2024-05-24T18:12:52.614Z,1716574372.614 [DataOverHttps](INFO): SBD MOMSN=21083655 2024-05-24T18:12:53.779Z,1716574373.779 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T18:12:53.780Z,1716574373.780 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T18:12:53.780Z,1716574373.780 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T18:17:06.461Z,1716574626.461 [DataOverHttps](IMPORTANT): SBD MTMSN=20240524T181708 2024-05-24T18:17:13.808Z,1716574633.808 [DataOverHttps](INFO): Received command: strobe off 2024-05-24T18:17:13.841Z,1716574633.841 [CommandExec](IMPORTANT): got command strobe off 2024-05-24T18:17:13.841Z,1716574633.841 [CommandExec](IMPORTANT): Deactivating strobe 2024-05-24T18:17:26.821Z,1716574646.821 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-24T18:17:54.302Z,1716574674.302 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T18:17:54.302Z,1716574674.302 [Default:CheckIn:C.Wait] Stopped 2024-05-24T18:17:54.302Z,1716574674.302 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T18:17:54.303Z,1716574674.303 [Default:CheckIn:D] Running Loop=1 2024-05-24T18:17:54.707Z,1716574674.707 [Default:CheckIn:D] Stopped 2024-05-24T18:17:54.707Z,1716574674.707 [Default:CheckIn:E] Running Loop=1 2024-05-24T18:17:55.122Z,1716574675.122 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.451603 min 2024-05-24T18:17:55.122Z,1716574675.122 [Default:CheckIn:E] Stopped 2024-05-24T18:17:55.122Z,1716574675.122 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T18:17:55.122Z,1716574675.122 [Default:CheckIn] Stopped 2024-05-24T18:17:55.122Z,1716574675.122 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T18:17:55.122Z,1716574675.122 [Default:CheckIn](INFO): Running loop #17 2024-05-24T18:17:55.122Z,1716574675.122 [Default:CheckIn] Running Loop=17 2024-05-24T18:17:55.122Z,1716574675.122 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T18:17:55.123Z,1716574675.123 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T18:17:57.127Z,1716574677.127 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181759.00,A,3648.17088,N,12147.27684,W,1.341,253.30,240524,,,A*7B 2024-05-24T18:17:57.137Z,1716574677.137 [NAL9602](INFO): GPS fix at 20240524T181759: (36.802848, -121.787947) 2024-05-24T18:17:57.148Z,1716574677.148 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T18:17:57.148Z,1716574677.148 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T18:18:04.951Z,1716574684.951 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240524T165733/Courier0045.lzma 2024-05-24T18:18:05.954Z,1716574685.954 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0045.lzma.bak 2024-05-24T18:18:05.954Z,1716574685.954 [DataOverHttps](INFO): SBD MOMSN=21083678 2024-05-24T18:18:24.728Z,1716574704.728 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240524T165733/Courier0048.lzma 2024-05-24T18:18:25.730Z,1716574705.730 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0048.lzma.bak 2024-05-24T18:18:25.730Z,1716574705.730 [DataOverHttps](INFO): SBD MOMSN=21083684 2024-05-24T18:18:29.889Z,1716574709.889 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T18:18:41.576Z,1716574721.576 [DataOverHttps](INFO): Sending 305 bytes from file Logs/20240524T165733/Express0046.lzma 2024-05-24T18:18:42.578Z,1716574722.578 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0046.lzma.bak 2024-05-24T18:18:42.578Z,1716574722.578 [DataOverHttps](INFO): SBD MOMSN=21083689 2024-05-24T18:19:01.752Z,1716574741.752 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20240524T165733/Express0049.lzma 2024-05-24T18:19:02.754Z,1716574742.754 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0049.lzma.bak 2024-05-24T18:19:02.754Z,1716574742.754 [DataOverHttps](INFO): SBD MOMSN=21083696 2024-05-24T18:19:03.905Z,1716574743.905 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T18:19:03.905Z,1716574743.905 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T18:19:03.905Z,1716574743.905 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T18:23:26.079Z,1716575006.079 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T18:23:38.605Z,1716575018.605 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T18:23:51.934Z,1716575031.934 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T18:24:04.500Z,1716575044.500 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T18:24:04.500Z,1716575044.500 [Default:CheckIn:C.Wait] Stopped 2024-05-24T18:24:04.500Z,1716575044.500 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T18:24:04.501Z,1716575044.501 [Default:CheckIn:D] Running Loop=1 2024-05-24T18:24:04.921Z,1716575044.921 [Default:CheckIn:D] Stopped 2024-05-24T18:24:04.921Z,1716575044.921 [Default:CheckIn:E] Running Loop=1 2024-05-24T18:24:05.270Z,1716575045.270 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T18:24:05.288Z,1716575045.288 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.621834 min 2024-05-24T18:24:05.289Z,1716575045.289 [Default:CheckIn:E] Stopped 2024-05-24T18:24:05.289Z,1716575045.289 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T18:24:05.289Z,1716575045.289 [Default:CheckIn] Stopped 2024-05-24T18:24:05.289Z,1716575045.289 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T18:24:05.289Z,1716575045.289 [Default:CheckIn](INFO): Running loop #18 2024-05-24T18:24:05.289Z,1716575045.289 [Default:CheckIn] Running Loop=18 2024-05-24T18:24:05.289Z,1716575045.289 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T18:24:05.289Z,1716575045.289 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T18:24:07.355Z,1716575047.355 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182409.00,A,3648.17037,N,12147.28253,W,0.719,108.34,240524,,,A*7A 2024-05-24T18:24:07.358Z,1716575047.358 [NAL9602](INFO): GPS fix at 20240524T182409: (36.802839, -121.788042) 2024-05-24T18:24:07.369Z,1716575047.369 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T18:24:07.369Z,1716575047.369 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T18:24:15.068Z,1716575055.068 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240524T165733/Courier0051.lzma 2024-05-24T18:24:16.070Z,1716575056.070 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0051.lzma.bak 2024-05-24T18:24:16.070Z,1716575056.070 [DataOverHttps](INFO): SBD MOMSN=21083704 2024-05-24T18:24:18.194Z,1716575058.194 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T18:24:31.578Z,1716575071.578 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T18:24:34.948Z,1716575074.948 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240524T165733/Express0052.lzma 2024-05-24T18:24:35.950Z,1716575075.950 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0052.lzma.bak 2024-05-24T18:24:35.950Z,1716575075.950 [DataOverHttps](INFO): SBD MOMSN=21083708 2024-05-24T18:24:37.258Z,1716575077.258 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T18:24:37.259Z,1716575077.259 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T18:24:37.259Z,1716575077.259 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T18:24:38.045Z,1716575078.045 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-24T18:24:38.120Z,1716575078.120 [NAL9602](ERROR): received: +CSQ:0 OK613, 0, 0, 0, 0 OK 2024-05-24T18:24:44.107Z,1716575084.107 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T18:27:24.130Z,1716575244.130 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6. 2024-05-24T18:27:24.148Z,1716575244.148 [BPC1](INFO): Received data from all battery sticks. 2024-05-24T18:29:09.634Z,1716575349.634 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-24T18:29:37.947Z,1716575377.947 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T18:29:37.948Z,1716575377.948 [Default:CheckIn:C.Wait] Stopped 2024-05-24T18:29:37.952Z,1716575377.952 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T18:29:37.952Z,1716575377.952 [Default:CheckIn:D] Running Loop=1 2024-05-24T18:29:38.335Z,1716575378.335 [Default:CheckIn:D] Stopped 2024-05-24T18:29:38.335Z,1716575378.335 [Default:CheckIn:E] Running Loop=1 2024-05-24T18:29:38.740Z,1716575378.740 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.178743 min 2024-05-24T18:29:38.741Z,1716575378.741 [Default:CheckIn:E] Stopped 2024-05-24T18:29:38.741Z,1716575378.741 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T18:29:38.741Z,1716575378.741 [Default:CheckIn] Stopped 2024-05-24T18:29:38.741Z,1716575378.741 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T18:29:38.741Z,1716575378.741 [Default:CheckIn](INFO): Running loop #19 2024-05-24T18:29:38.741Z,1716575378.741 [Default:CheckIn] Running Loop=19 2024-05-24T18:29:38.741Z,1716575378.741 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T18:29:38.741Z,1716575378.741 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T18:29:40.747Z,1716575380.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182942.00,A,3648.16387,N,12147.27858,W,0.680,108.34,240524,,,A*7E 2024-05-24T18:29:40.757Z,1716575380.757 [NAL9602](INFO): GPS fix at 20240524T182942: (36.802731, -121.787976) 2024-05-24T18:29:40.768Z,1716575380.768 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T18:29:40.768Z,1716575380.768 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T18:29:48.263Z,1716575388.263 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20240524T165733/Courier0054.lzma 2024-05-24T18:29:49.266Z,1716575389.266 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0054.lzma.bak 2024-05-24T18:29:49.266Z,1716575389.266 [DataOverHttps](INFO): SBD MOMSN=21083729 2024-05-24T18:30:05.268Z,1716575405.268 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20240524T165733/Express0055.lzma 2024-05-24T18:30:06.270Z,1716575406.270 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0055.lzma.bak 2024-05-24T18:30:06.270Z,1716575406.270 [DataOverHttps](INFO): SBD MOMSN=21083732 2024-05-24T18:30:07.426Z,1716575407.426 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T18:30:07.426Z,1716575407.426 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T18:30:07.427Z,1716575407.427 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T18:30:12.657Z,1716575412.657 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T18:35:08.041Z,1716575708.041 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T18:35:08.042Z,1716575708.042 [Default:CheckIn:C.Wait] Stopped 2024-05-24T18:35:08.042Z,1716575708.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T18:35:08.042Z,1716575708.042 [Default:CheckIn:D] Running Loop=1 2024-05-24T18:35:08.455Z,1716575708.455 [Default:CheckIn:D] Stopped 2024-05-24T18:35:08.455Z,1716575708.455 [Default:CheckIn:E] Running Loop=1 2024-05-24T18:35:08.860Z,1716575708.860 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.680729 min 2024-05-24T18:35:08.860Z,1716575708.860 [Default:CheckIn:E] Stopped 2024-05-24T18:35:08.860Z,1716575708.860 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T18:35:08.860Z,1716575708.860 [Default:CheckIn] Stopped 2024-05-24T18:35:08.860Z,1716575708.860 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T18:35:08.860Z,1716575708.860 [Default:CheckIn](INFO): Running loop #20 2024-05-24T18:35:08.861Z,1716575708.861 [Default:CheckIn] Running Loop=20 2024-05-24T18:35:08.861Z,1716575708.861 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T18:35:08.861Z,1716575708.861 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T18:35:10.867Z,1716575710.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183512.00,A,3648.17234,N,12147.28294,W,0.758,0.00,240524,,,A*71 2024-05-24T18:35:10.869Z,1716575710.869 [NAL9602](INFO): GPS fix at 20240524T183512: (36.802872, -121.788049) 2024-05-24T18:35:10.879Z,1716575710.879 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T18:35:10.879Z,1716575710.879 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T18:35:18.275Z,1716575718.275 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240524T165733/Courier0057.lzma 2024-05-24T18:35:19.278Z,1716575719.278 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0057.lzma.bak 2024-05-24T18:35:19.278Z,1716575719.278 [DataOverHttps](INFO): SBD MOMSN=21083742 2024-05-24T18:35:35.149Z,1716575735.149 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240524T165733/Express0058.lzma 2024-05-24T18:35:36.146Z,1716575736.146 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0058.lzma.bak 2024-05-24T18:35:36.146Z,1716575736.146 [DataOverHttps](INFO): SBD MOMSN=21083745 2024-05-24T18:35:37.745Z,1716575737.745 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T18:35:37.745Z,1716575737.745 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T18:35:37.745Z,1716575737.745 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T18:35:41.567Z,1716575741.567 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-24T18:35:41.640Z,1716575741.640 [NAL9602](ERROR): received: +CSQ:0 OK613, 0, 0, 0, 0 OK 2024-05-24T18:37:12.077Z,1716575832.077 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-05-24T18:37:12.077Z,1716575832.077 [DropWeight] Hardware Fault, FailCount= 1 2024-05-24T18:37:12.077Z,1716575832.077 [DropWeight](ERROR): Hardware Fault 2024-05-24T18:37:12.112Z,1716575832.112 [CommandExec](FAULT): Scheduling is paused 2024-05-24T18:37:12.112Z,1716575832.112 [CBIT](INFO): Critical error at 20240524T183712 2024-05-24T18:37:12.115Z,1716575832.115 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-05-24T18:37:12.115Z,1716575832.115 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-05-24T18:37:12.524Z,1716575832.524 [CBIT](INFO): Critical error at 20240524T183712 2024-05-24T18:40:12.702Z,1716576012.702 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-24T18:40:38.219Z,1716576038.219 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T18:40:38.219Z,1716576038.219 [Default:CheckIn:C.Wait] Stopped 2024-05-24T18:40:38.219Z,1716576038.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T18:40:38.220Z,1716576038.220 [Default:CheckIn:D] Running Loop=1 2024-05-24T18:40:38.611Z,1716576038.611 [Default:CheckIn:D] Stopped 2024-05-24T18:40:38.611Z,1716576038.611 [Default:CheckIn:E] Running Loop=1 2024-05-24T18:40:39.015Z,1716576039.015 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.183333 min 2024-05-24T18:40:39.015Z,1716576039.015 [Default:CheckIn:E] Stopped 2024-05-24T18:40:39.015Z,1716576039.015 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T18:40:39.015Z,1716576039.015 [Default:CheckIn] Stopped 2024-05-24T18:40:39.015Z,1716576039.015 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T18:40:39.016Z,1716576039.016 [Default:CheckIn](INFO): Running loop #21 2024-05-24T18:40:39.016Z,1716576039.016 [Default:CheckIn] Running Loop=21 2024-05-24T18:40:39.016Z,1716576039.016 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T18:40:39.016Z,1716576039.016 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T18:40:41.026Z,1716576041.026 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184043.00,A,3648.11697,N,12147.34791,W,8.942,211.37,240524,,,A*7A 2024-05-24T18:40:41.029Z,1716576041.029 [NAL9602](INFO): GPS fix at 20240524T184043: (36.801949, -121.789132) 2024-05-24T18:40:41.039Z,1716576041.039 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T18:40:41.040Z,1716576041.040 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T18:40:51.560Z,1716576051.560 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20240524T165733/Courier0060.lzma 2024-05-24T18:40:52.562Z,1716576052.562 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0060.lzma.bak 2024-05-24T18:40:52.562Z,1716576052.562 [DataOverHttps](INFO): SBD MOMSN=21083768 2024-05-24T18:41:08.972Z,1716576068.972 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20240524T165733/Express0061.lzma 2024-05-24T18:41:09.974Z,1716576069.974 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0061.lzma.bak 2024-05-24T18:41:09.974Z,1716576069.974 [DataOverHttps](INFO): SBD MOMSN=21083773 2024-05-24T18:41:11.379Z,1716576071.379 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T18:41:11.379Z,1716576071.379 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T18:41:11.379Z,1716576071.379 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T18:41:13.777Z,1716576073.777 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-24T18:46:12.131Z,1716576372.131 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-24T18:46:12.131Z,1716576372.131 [Default:CheckIn:C.Wait] Stopped 2024-05-24T18:46:12.131Z,1716576372.131 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T18:46:12.131Z,1716576372.131 [Default:CheckIn:D] Running Loop=1 2024-05-24T18:46:12.542Z,1716576372.542 [Default:CheckIn:D] Stopped 2024-05-24T18:46:12.542Z,1716576372.542 [Default:CheckIn:E] Running Loop=1 2024-05-24T18:46:12.955Z,1716576372.955 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.748853 min 2024-05-24T18:46:12.955Z,1716576372.955 [Default:CheckIn:E] Stopped 2024-05-24T18:46:12.956Z,1716576372.956 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-24T18:46:12.956Z,1716576372.956 [Default:CheckIn] Stopped 2024-05-24T18:46:12.956Z,1716576372.956 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-24T18:46:12.956Z,1716576372.956 [Default:CheckIn](INFO): Running loop #22 2024-05-24T18:46:12.956Z,1716576372.956 [Default:CheckIn] Running Loop=22 2024-05-24T18:46:12.956Z,1716576372.956 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-24T18:46:12.956Z,1716576372.956 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-24T18:46:14.954Z,1716576374.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184616.00,A,3648.15910,N,12147.26795,W,2.449,184.18,240524,,,A*71 2024-05-24T18:46:14.957Z,1716576374.957 [NAL9602](INFO): GPS fix at 20240524T184616: (36.802652, -121.787799) 2024-05-24T18:46:14.967Z,1716576374.967 [Default:CheckIn:Read_GPS] Stopped 2024-05-24T18:46:14.967Z,1716576374.967 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-24T18:46:45.652Z,1716576405.652 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-24T18:46:45.736Z,1716576405.736 [NAL9602](ERROR): received: +CSQ:0 OK613, 0, 0, 0, 0 OK 2024-05-24T18:46:53.503Z,1716576413.503 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240524T165733/Courier0063.lzma 2024-05-24T18:46:54.506Z,1716576414.506 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Courier0063.lzma.bak 2024-05-24T18:46:54.506Z,1716576414.506 [DataOverHttps](INFO): SBD MOMSN=21083792 2024-05-24T18:47:10.316Z,1716576430.316 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240524T165733/Express0064.lzma 2024-05-24T18:47:11.318Z,1716576431.318 [DataOverHttps](INFO): Moved sent file to Logs/20240524T165733/Express0064.lzma.bak 2024-05-24T18:47:11.318Z,1716576431.318 [DataOverHttps](INFO): SBD MOMSN=21083795 2024-05-24T18:47:12.749Z,1716576432.749 [Default:CheckIn:Read_Iridium] Stopped 2024-05-24T18:47:12.749Z,1716576432.749 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-24T18:47:12.749Z,1716576432.749 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-24T18:48:25.136Z,1716576505.136 [CommandExec](IMPORTANT): got command quit 2024-05-24T18:48:26.140Z,1716576506.140 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T18:48:26.140Z,1716576506.140 [CommandExec](INFO): Uninitializing the command executive. 2024-05-24T18:48:26.140Z,1716576506.140 [CommandExec](INFO): Uninitializing the command scheduler. 2024-05-24T18:48:26.140Z,1716576506.140 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:26.332Z,1716576506.332 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-05-24T18:48:26.332Z,1716576506.332 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-05-24T18:48:26.332Z,1716576506.332 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:26.333Z,1716576506.333 [NavChartDb](INFO): Join timeout helper Thread ID is 6501 2024-05-24T18:48:26.588Z,1716576506.588 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T18:48:26.589Z,1716576506.589 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:26.600Z,1716576506.600 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-05-24T18:48:26.600Z,1716576506.600 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:26.601Z,1716576506.601 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6502 2024-05-24T18:48:26.604Z,1716576506.604 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T18:48:26.604Z,1716576506.604 [WetLabsBB2FL](INFO): Powering down 2024-05-24T18:48:26.605Z,1716576506.605 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:26.620Z,1716576506.620 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-05-24T18:48:26.620Z,1716576506.620 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:26.620Z,1716576506.620 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6503 2024-05-24T18:48:27.052Z,1716576507.052 [CTD_Seabird](INFO): Powering down 2024-05-24T18:48:27.071Z,1716576507.071 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T18:48:27.071Z,1716576507.071 [CTD_Seabird](INFO): Powering down 2024-05-24T18:48:27.084Z,1716576507.084 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:27.088Z,1716576507.088 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-05-24T18:48:27.088Z,1716576507.088 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:27.088Z,1716576507.088 [DAT](INFO): Join timeout helper Thread ID is 6504 2024-05-24T18:48:27.096Z,1716576507.096 [DAT](INFO): Powering down 2024-05-24T18:48:27.165Z,1716576507.165 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T18:48:27.165Z,1716576507.165 [DAT](INFO): Powering down 2024-05-24T18:48:27.166Z,1716576507.166 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:27.180Z,1716576507.180 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-05-24T18:48:27.180Z,1716576507.180 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:27.180Z,1716576507.180 [Radio_Surface](INFO): Join timeout helper Thread ID is 6505 2024-05-24T18:48:27.372Z,1716576507.372 [Radio_Surface](INFO): Powering down 2024-05-24T18:48:27.381Z,1716576507.381 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T18:48:27.382Z,1716576507.382 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:27.385Z,1716576507.385 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-05-24T18:48:27.385Z,1716576507.385 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:27.385Z,1716576507.385 [Onboard](INFO): Join timeout helper Thread ID is 6506 2024-05-24T18:48:27.848Z,1716576507.848 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-05-24T18:48:29.900Z,1716576509.900 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T18:48:29.900Z,1716576509.900 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:29.905Z,1716576509.905 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-05-24T18:48:29.905Z,1716576509.905 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:29.905Z,1716576509.905 [DataOverHttps](INFO): Join timeout helper Thread ID is 6507 2024-05-24T18:48:30.860Z,1716576510.860 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T18:48:30.860Z,1716576510.860 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:30.881Z,1716576510.881 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-05-24T18:48:30.881Z,1716576510.881 [logger ThreadHandler](INFO): Thread cancelled. 2024-05-24T18:48:30.881Z,1716576510.881 [logger](INFO): Join timeout helper Thread ID is 6508