2025-02-06T15:37:19.989Z,1738856239.989 [CommandExec](IMPORTANT): got command restart logs 2025-02-06T15:41:35.707Z,1738856495.707 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T15:41:35.707Z,1738856495.707 [Default:CheckIn:C.Wait] Stopped 2025-02-06T15:41:35.707Z,1738856495.707 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T15:41:35.707Z,1738856495.707 [Default:CheckIn:D] Running Loop=1 2025-02-06T15:41:36.111Z,1738856496.111 [Default:CheckIn:D] Stopped 2025-02-06T15:41:36.111Z,1738856496.111 [Default:CheckIn:E] Running Loop=1 2025-02-06T15:41:36.523Z,1738856496.523 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.072367 min 2025-02-06T15:41:36.523Z,1738856496.523 [Default:CheckIn:E] Stopped 2025-02-06T15:41:36.524Z,1738856496.524 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T15:41:36.524Z,1738856496.524 [Default:CheckIn] Stopped 2025-02-06T15:41:36.524Z,1738856496.524 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T15:41:36.524Z,1738856496.524 [Default:CheckIn](INFO): Running loop #4 2025-02-06T15:41:36.524Z,1738856496.524 [Default:CheckIn] Running Loop=4 2025-02-06T15:41:36.524Z,1738856496.524 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T15:41:36.524Z,1738856496.524 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T15:41:38.526Z,1738856498.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154137.00,A,3647.72521,N,12150.81011,W,6.745,45.25,060225,,,D*42 2025-02-06T15:41:38.529Z,1738856498.529 [NAL9602](INFO): GPS fix at 20250206T154137: (36.795420, -121.846835) 2025-02-06T15:41:38.540Z,1738856498.540 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T15:41:38.540Z,1738856498.540 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T15:41:46.595Z,1738856506.595 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20250205T191715/Courier0108.lzma 2025-02-06T15:41:47.598Z,1738856507.598 [DataOverHttps](INFO): Moved sent file to Logs/20250205T191715/Courier0108.lzma.bak 2025-02-06T15:41:47.598Z,1738856507.598 [DataOverHttps](INFO): SBD MOMSN=23959912 2025-02-06T15:41:51.854Z,1738856511.854 [NAL9602](INFO): SBD MO Status=0, MOMSN=62089, MT Status=0, MTMSN=0 2025-02-06T15:41:51.854Z,1738856511.854 [NAL9602](INFO): No messages in MT queue 2025-02-06T15:42:03.451Z,1738856523.451 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250206T153719/Courier0000.lzma 2025-02-06T15:42:04.453Z,1738856524.453 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0000.lzma.bak 2025-02-06T15:42:04.453Z,1738856524.453 [DataOverHttps](INFO): SBD MOMSN=23959914 2025-02-06T15:42:21.767Z,1738856541.767 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20250205T191715/Express0109.lzma 2025-02-06T15:42:22.577Z,1738856542.577 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T15:42:33.523Z,1738856553.523 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20250205T191715/Express0109.lzma 2025-02-06T15:42:34.526Z,1738856554.526 [DataOverHttps](INFO): Moved sent file to Logs/20250205T191715/Express0109.lzma.bak 2025-02-06T15:42:34.526Z,1738856554.526 [DataOverHttps](INFO): SBD MOMSN=23959920 2025-02-06T15:42:51.950Z,1738856571.950 [DataOverHttps](INFO): Sending 398 bytes from file Logs/20250206T153719/Express0001.lzma 2025-02-06T15:42:52.941Z,1738856572.941 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0001.lzma.bak 2025-02-06T15:42:52.942Z,1738856572.942 [DataOverHttps](INFO): SBD MOMSN=23959925 2025-02-06T15:43:10.243Z,1738856590.243 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250206T153719/Express0004.lzma 2025-02-06T15:43:11.245Z,1738856591.245 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0004.lzma.bak 2025-02-06T15:43:11.245Z,1738856591.245 [DataOverHttps](INFO): SBD MOMSN=23959929 2025-02-06T15:43:12.763Z,1738856592.763 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T15:43:12.763Z,1738856592.763 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T15:43:12.763Z,1738856592.763 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T15:48:13.542Z,1738856893.542 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T15:48:13.542Z,1738856893.542 [Default:CheckIn:C.Wait] Stopped 2025-02-06T15:48:13.542Z,1738856893.542 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T15:48:13.543Z,1738856893.543 [Default:CheckIn:D] Running Loop=1 2025-02-06T15:48:13.963Z,1738856893.963 [Default:CheckIn:D] Stopped 2025-02-06T15:48:13.963Z,1738856893.963 [Default:CheckIn:E] Running Loop=1 2025-02-06T15:48:14.363Z,1738856894.363 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.703235 min 2025-02-06T15:48:14.363Z,1738856894.363 [Default:CheckIn:E] Stopped 2025-02-06T15:48:14.364Z,1738856894.364 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T15:48:14.364Z,1738856894.364 [Default:CheckIn] Stopped 2025-02-06T15:48:14.364Z,1738856894.364 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T15:48:14.364Z,1738856894.364 [Default:CheckIn](INFO): Running loop #5 2025-02-06T15:48:14.364Z,1738856894.364 [Default:CheckIn] Running Loop=5 2025-02-06T15:48:14.364Z,1738856894.364 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T15:48:14.364Z,1738856894.364 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T15:48:16.366Z,1738856896.366 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154815.00,A,3647.86052,N,12150.90959,W,6.376,250.21,060225,,,D*72 2025-02-06T15:48:16.369Z,1738856896.369 [NAL9602](INFO): GPS fix at 20250206T154815: (36.797675, -121.848493) 2025-02-06T15:48:16.400Z,1738856896.400 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T15:48:16.400Z,1738856896.400 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T15:48:26.979Z,1738856906.979 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0006.lzma 2025-02-06T15:48:27.981Z,1738856907.981 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0006.lzma.bak 2025-02-06T15:48:27.982Z,1738856907.982 [DataOverHttps](INFO): SBD MOMSN=23959941 2025-02-06T15:48:36.242Z,1738856916.242 [NAL9602](INFO): SBD MO Status=0, MOMSN=62090, MT Status=0, MTMSN=0 2025-02-06T15:48:36.242Z,1738856916.242 [NAL9602](INFO): No messages in MT queue 2025-02-06T15:48:44.619Z,1738856924.619 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20250206T153719/Express0007.lzma 2025-02-06T15:48:45.622Z,1738856925.622 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0007.lzma.bak 2025-02-06T15:48:45.622Z,1738856925.622 [DataOverHttps](INFO): SBD MOMSN=23959944 2025-02-06T15:48:47.170Z,1738856927.170 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T15:48:47.170Z,1738856927.170 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T15:48:47.170Z,1738856927.170 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T15:49:06.976Z,1738856946.976 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T15:51:48.270Z,1738857108.270 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T15:52:04.028Z,1738857124.028 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T15:52:18.974Z,1738857138.974 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T15:52:18.977Z,1738857138.977 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-02-06T15:52:33.978Z,1738857153.978 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T15:52:50.202Z,1738857170.202 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T15:53:05.554Z,1738857185.554 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T15:53:21.310Z,1738857201.310 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T15:53:36.662Z,1738857216.662 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T15:53:47.583Z,1738857227.583 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T15:53:47.583Z,1738857227.583 [Default:CheckIn:C.Wait] Stopped 2025-02-06T15:53:47.584Z,1738857227.584 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T15:53:47.584Z,1738857227.584 [Default:CheckIn:D] Running Loop=1 2025-02-06T15:53:47.998Z,1738857227.998 [Default:CheckIn:D] Stopped 2025-02-06T15:53:47.998Z,1738857227.998 [Default:CheckIn:E] Running Loop=1 2025-02-06T15:53:48.390Z,1738857228.390 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.270492 min 2025-02-06T15:53:48.390Z,1738857228.390 [Default:CheckIn:E] Stopped 2025-02-06T15:53:48.391Z,1738857228.391 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T15:53:48.391Z,1738857228.391 [Default:CheckIn] Stopped 2025-02-06T15:53:48.391Z,1738857228.391 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T15:53:48.391Z,1738857228.391 [Default:CheckIn](INFO): Running loop #6 2025-02-06T15:53:48.391Z,1738857228.391 [Default:CheckIn] Running Loop=6 2025-02-06T15:53:48.391Z,1738857228.391 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T15:53:48.391Z,1738857228.391 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T15:53:50.402Z,1738857230.402 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155349.00,A,3647.80783,N,12151.13130,W,0.369,241.48,060225,,,D*76 2025-02-06T15:53:50.405Z,1738857230.405 [NAL9602](INFO): GPS fix at 20250206T155349: (36.796797, -121.852188) 2025-02-06T15:53:50.415Z,1738857230.415 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T15:53:50.415Z,1738857230.415 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T15:53:52.014Z,1738857232.014 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T15:54:04.563Z,1738857244.563 [NAL9602](INFO): SBD MO Status=0, MOMSN=62091, MT Status=0, MTMSN=0 2025-02-06T15:54:04.563Z,1738857244.563 [NAL9602](INFO): No messages in MT queue 2025-02-06T15:54:21.492Z,1738857261.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T15:54:35.249Z,1738857275.249 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T15:54:52.648Z,1738857292.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T15:55:36.743Z,1738857336.743 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0009.lzma 2025-02-06T15:55:37.745Z,1738857337.745 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0009.lzma.bak 2025-02-06T15:55:37.745Z,1738857337.745 [DataOverHttps](INFO): SBD MOMSN=23959979 2025-02-06T15:56:31.739Z,1738857391.739 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20250206T153719/Express0010.lzma 2025-02-06T15:56:32.742Z,1738857392.742 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0010.lzma.bak 2025-02-06T15:56:32.742Z,1738857392.742 [DataOverHttps](INFO): SBD MOMSN=23959982 2025-02-06T15:56:34.220Z,1738857394.220 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T15:56:34.220Z,1738857394.220 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T15:56:34.220Z,1738857394.220 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T15:59:37.740Z,1738857577.740 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T16:00:08.864Z,1738857608.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T16:00:39.988Z,1738857639.988 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T16:01:11.112Z,1738857671.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T16:01:11.325Z,1738857671.325 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-06T16:01:17.386Z,1738857677.386 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:01:30.718Z,1738857690.718 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:01:34.775Z,1738857694.775 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:01:34.775Z,1738857694.775 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:01:34.775Z,1738857694.775 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:01:34.775Z,1738857694.775 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:01:35.196Z,1738857695.196 [Default:CheckIn:D] Stopped 2025-02-06T16:01:35.196Z,1738857695.196 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:01:35.587Z,1738857695.587 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.057121 min 2025-02-06T16:01:35.588Z,1738857695.588 [Default:CheckIn:E] Stopped 2025-02-06T16:01:35.588Z,1738857695.588 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:01:35.588Z,1738857695.588 [Default:CheckIn] Stopped 2025-02-06T16:01:35.588Z,1738857695.588 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:01:35.588Z,1738857695.588 [Default:CheckIn](INFO): Running loop #7 2025-02-06T16:01:35.588Z,1738857695.588 [Default:CheckIn] Running Loop=7 2025-02-06T16:01:35.588Z,1738857695.588 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:01:35.588Z,1738857695.588 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:01:37.590Z,1738857697.590 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160136.00,A,3648.08809,N,12150.42693,W,6.648,80.85,060225,,,D*4D 2025-02-06T16:01:37.592Z,1738857697.592 [NAL9602](INFO): GPS fix at 20250206T160136: (36.801468, -121.840449) 2025-02-06T16:01:37.603Z,1738857697.603 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:01:37.603Z,1738857697.603 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:01:42.244Z,1738857702.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T16:01:44.457Z,1738857704.457 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:01:45.258Z,1738857705.258 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.737839 2025-02-06T16:01:54.576Z,1738857714.576 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003196 2025-02-06T16:01:56.173Z,1738857716.173 [NAL9602](INFO): SBD MO Status=0, MOMSN=62092, MT Status=0, MTMSN=0 2025-02-06T16:01:56.173Z,1738857716.173 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:01:59.005Z,1738857719.005 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:02:12.736Z,1738857732.736 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:02:19.227Z,1738857739.227 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250206T153719/Courier0012.lzma 2025-02-06T16:02:20.229Z,1738857740.229 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0012.lzma.bak 2025-02-06T16:02:20.229Z,1738857740.229 [DataOverHttps](INFO): SBD MOMSN=23959997 2025-02-06T16:02:26.968Z,1738857746.968 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T16:02:27.291Z,1738857747.291 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:02:39.931Z,1738857759.931 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20250206T153719/Express0013.lzma 2025-02-06T16:02:40.674Z,1738857760.674 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:02:40.933Z,1738857760.933 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0013.lzma.bak 2025-02-06T16:02:40.933Z,1738857760.933 [DataOverHttps](INFO): SBD MOMSN=23960000 2025-02-06T16:02:42.240Z,1738857762.240 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:02:42.241Z,1738857762.241 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:02:42.241Z,1738857762.241 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:02:54.361Z,1738857774.361 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:03:08.897Z,1738857788.897 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:03:21.832Z,1738857801.832 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:03:35.562Z,1738857815.562 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:07:42.983Z,1738858062.983 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:07:42.983Z,1738858062.983 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:07:42.983Z,1738858062.983 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:07:42.984Z,1738858062.984 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:07:43.394Z,1738858063.394 [Default:CheckIn:D] Stopped 2025-02-06T16:07:43.394Z,1738858063.394 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:07:43.790Z,1738858063.790 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.193750 min 2025-02-06T16:07:43.790Z,1738858063.790 [Default:CheckIn:E] Stopped 2025-02-06T16:07:43.790Z,1738858063.790 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:07:43.790Z,1738858063.790 [Default:CheckIn] Stopped 2025-02-06T16:07:43.790Z,1738858063.790 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:07:43.790Z,1738858063.790 [Default:CheckIn](INFO): Running loop #8 2025-02-06T16:07:43.790Z,1738858063.790 [Default:CheckIn] Running Loop=8 2025-02-06T16:07:43.790Z,1738858063.790 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:07:43.790Z,1738858063.790 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:07:45.795Z,1738858065.795 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160745.00,A,3648.17797,N,12150.19212,W,0.505,307.42,060225,,,D*71 2025-02-06T16:07:45.797Z,1738858065.797 [NAL9602](INFO): GPS fix at 20250206T160745: (36.802966, -121.836535) 2025-02-06T16:07:45.808Z,1738858065.808 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:07:45.808Z,1738858065.808 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:07:53.775Z,1738858073.775 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0015.lzma 2025-02-06T16:07:54.777Z,1738858074.777 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0015.lzma.bak 2025-02-06T16:07:54.777Z,1738858074.777 [DataOverHttps](INFO): SBD MOMSN=23960003 2025-02-06T16:08:21.772Z,1738858101.772 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250206T153719/Express0016.lzma 2025-02-06T16:08:22.770Z,1738858102.770 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0016.lzma.bak 2025-02-06T16:08:22.770Z,1738858102.770 [DataOverHttps](INFO): SBD MOMSN=23960006 2025-02-06T16:08:24.232Z,1738858104.232 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:08:24.233Z,1738858104.233 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:08:24.233Z,1738858104.233 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:08:28.288Z,1738858108.288 [NAL9602](INFO): SBD MO Status=0, MOMSN=62093, MT Status=0, MTMSN=0 2025-02-06T16:08:28.288Z,1738858108.288 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:08:58.969Z,1738858138.969 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T16:10:27.496Z,1738858227.496 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-02-06T16:10:56.186Z,1738858256.186 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:11:08.306Z,1738858268.306 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:11:20.830Z,1738858280.830 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:11:33.354Z,1738858293.354 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:11:45.072Z,1738858305.072 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:11:57.190Z,1738858317.190 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:12:09.310Z,1738858329.310 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:12:21.859Z,1738858341.859 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:12:33.960Z,1738858353.960 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:12:45.670Z,1738858365.670 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:12:57.421Z,1738858377.421 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:13:09.125Z,1738858389.125 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:13:24.899Z,1738858404.899 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:13:24.899Z,1738858404.899 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:13:24.900Z,1738858404.900 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:13:24.900Z,1738858404.900 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:13:25.299Z,1738858405.299 [Default:CheckIn:D] Stopped 2025-02-06T16:13:25.299Z,1738858405.299 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:13:25.700Z,1738858405.700 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.892167 min 2025-02-06T16:13:25.700Z,1738858405.700 [Default:CheckIn:E] Stopped 2025-02-06T16:13:25.700Z,1738858405.700 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:13:25.700Z,1738858405.700 [Default:CheckIn] Stopped 2025-02-06T16:13:25.700Z,1738858405.700 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:13:25.700Z,1738858405.700 [Default:CheckIn](INFO): Running loop #9 2025-02-06T16:13:25.700Z,1738858405.700 [Default:CheckIn] Running Loop=9 2025-02-06T16:13:25.700Z,1738858405.700 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:13:25.700Z,1738858405.700 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:13:27.728Z,1738858407.728 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161327.00,A,3648.18595,N,12150.08378,W,1.147,239.22,060225,,,D*7B 2025-02-06T16:13:27.731Z,1738858407.731 [NAL9602](INFO): GPS fix at 20250206T161327: (36.803099, -121.834730) 2025-02-06T16:13:27.758Z,1738858407.758 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:13:27.758Z,1738858407.758 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:13:34.667Z,1738858414.667 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0018.lzma 2025-02-06T16:13:35.669Z,1738858415.669 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0018.lzma.bak 2025-02-06T16:13:35.669Z,1738858415.669 [DataOverHttps](INFO): SBD MOMSN=23960010 2025-02-06T16:13:51.787Z,1738858431.787 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20250206T153719/Express0019.lzma 2025-02-06T16:13:52.789Z,1738858432.789 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0019.lzma.bak 2025-02-06T16:13:52.790Z,1738858432.790 [DataOverHttps](INFO): SBD MOMSN=23960013 2025-02-06T16:13:54.012Z,1738858434.012 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:13:54.012Z,1738858434.012 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:13:54.012Z,1738858434.012 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:14:08.938Z,1738858448.938 [NAL9602](INFO): SBD MO Status=0, MOMSN=62094, MT Status=0, MTMSN=0 2025-02-06T16:14:08.938Z,1738858448.938 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:14:39.673Z,1738858479.673 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T16:17:47.647Z,1738858667.647 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2025-02-06T16:17:47.651Z,1738858667.651 [BPC1](FAULT): Failed to receive data from 11 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 34, 35, 36, 38, 39, 50, 51, 54, 55. 2025-02-06T16:18:54.746Z,1738858734.746 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:18:54.746Z,1738858734.746 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:18:54.746Z,1738858734.746 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:18:54.746Z,1738858734.746 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:18:55.135Z,1738858735.135 [Default:CheckIn:D] Stopped 2025-02-06T16:18:55.135Z,1738858735.135 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:18:55.530Z,1738858735.530 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.389441 min 2025-02-06T16:18:55.530Z,1738858735.530 [Default:CheckIn:E] Stopped 2025-02-06T16:18:55.530Z,1738858735.530 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:18:55.530Z,1738858735.530 [Default:CheckIn] Stopped 2025-02-06T16:18:55.531Z,1738858735.531 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:18:55.531Z,1738858735.531 [Default:CheckIn](INFO): Running loop #10 2025-02-06T16:18:55.531Z,1738858735.531 [Default:CheckIn] Running Loop=10 2025-02-06T16:18:55.531Z,1738858735.531 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:18:55.531Z,1738858735.531 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:18:57.542Z,1738858737.542 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161856.00,A,3648.24314,N,12150.00386,W,10.244,50.56,060225,,,D*71 2025-02-06T16:18:57.545Z,1738858737.545 [NAL9602](INFO): GPS fix at 20250206T161856: (36.804052, -121.833398) 2025-02-06T16:18:57.590Z,1738858737.590 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:18:57.590Z,1738858737.590 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:19:05.171Z,1738858745.171 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20250206T153719/Courier0021.lzma 2025-02-06T16:19:06.173Z,1738858746.173 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0021.lzma.bak 2025-02-06T16:19:06.174Z,1738858746.174 [DataOverHttps](INFO): SBD MOMSN=23960017 2025-02-06T16:19:10.070Z,1738858750.070 [NAL9602](INFO): SBD MO Status=0, MOMSN=62095, MT Status=0, MTMSN=0 2025-02-06T16:19:10.070Z,1738858750.070 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:19:22.027Z,1738858762.027 [DataOverHttps](INFO): Sending 290 bytes from file Logs/20250206T153719/Express0022.lzma 2025-02-06T16:19:23.049Z,1738858763.049 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0022.lzma.bak 2025-02-06T16:19:23.049Z,1738858763.049 [DataOverHttps](INFO): SBD MOMSN=23960020 2025-02-06T16:19:24.216Z,1738858764.216 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:19:24.216Z,1738858764.216 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:19:24.216Z,1738858764.216 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:19:40.772Z,1738858780.772 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T16:20:55.512Z,1738858855.512 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-02-06T16:21:26.668Z,1738858886.668 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T16:21:57.792Z,1738858917.792 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T16:22:28.916Z,1738858948.916 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T16:23:00.040Z,1738858980.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T16:23:02.048Z,1738858982.048 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.195358 2025-02-06T16:23:53.518Z,1738859033.518 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.013604 2025-02-06T16:24:24.797Z,1738859064.797 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:24:24.797Z,1738859064.797 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:24:24.797Z,1738859064.797 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:24:24.797Z,1738859064.797 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:24:25.201Z,1738859065.201 [Default:CheckIn:D] Stopped 2025-02-06T16:24:25.202Z,1738859065.202 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:24:25.611Z,1738859065.611 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.890548 min 2025-02-06T16:24:25.611Z,1738859065.611 [Default:CheckIn:E] Stopped 2025-02-06T16:24:25.611Z,1738859065.611 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:24:25.611Z,1738859065.611 [Default:CheckIn] Stopped 2025-02-06T16:24:25.611Z,1738859065.611 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:24:25.611Z,1738859065.611 [Default:CheckIn](INFO): Running loop #11 2025-02-06T16:24:25.611Z,1738859065.611 [Default:CheckIn] Running Loop=11 2025-02-06T16:24:25.611Z,1738859065.611 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:24:25.612Z,1738859065.612 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:24:27.624Z,1738859067.624 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162426.00,A,3648.45469,N,12149.57667,W,9.758,126.96,060225,,,D*7F 2025-02-06T16:24:27.626Z,1738859067.626 [NAL9602](INFO): GPS fix at 20250206T162426: (36.807578, -121.826278) 2025-02-06T16:24:27.638Z,1738859067.638 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:24:27.638Z,1738859067.638 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:24:48.632Z,1738859088.632 [NAL9602](INFO): SBD MO Status=0, MOMSN=62096, MT Status=0, MTMSN=0 2025-02-06T16:24:48.632Z,1738859088.632 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:24:59.407Z,1738859099.407 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0024.lzma 2025-02-06T16:25:00.409Z,1738859100.409 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0024.lzma.bak 2025-02-06T16:25:00.410Z,1738859100.410 [DataOverHttps](INFO): SBD MOMSN=23960029 2025-02-06T16:25:19.384Z,1738859119.384 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T16:25:30.724Z,1738859130.724 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20250206T153719/Express0025.lzma 2025-02-06T16:25:31.725Z,1738859131.725 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0025.lzma.bak 2025-02-06T16:25:31.725Z,1738859131.725 [DataOverHttps](INFO): SBD MOMSN=23960033 2025-02-06T16:25:33.131Z,1738859133.131 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:25:33.132Z,1738859133.132 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:25:33.132Z,1738859133.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:30:33.815Z,1738859433.815 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:30:33.815Z,1738859433.815 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:30:33.815Z,1738859433.815 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:30:33.815Z,1738859433.815 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:30:34.219Z,1738859434.219 [Default:CheckIn:D] Stopped 2025-02-06T16:30:34.219Z,1738859434.219 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:30:34.603Z,1738859434.603 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.040837 min 2025-02-06T16:30:34.603Z,1738859434.603 [Default:CheckIn:E] Stopped 2025-02-06T16:30:34.603Z,1738859434.603 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:30:34.603Z,1738859434.603 [Default:CheckIn] Stopped 2025-02-06T16:30:34.603Z,1738859434.603 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:30:34.603Z,1738859434.603 [Default:CheckIn](INFO): Running loop #12 2025-02-06T16:30:34.603Z,1738859434.603 [Default:CheckIn] Running Loop=12 2025-02-06T16:30:34.603Z,1738859434.603 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:30:34.604Z,1738859434.604 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:30:36.611Z,1738859436.611 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163035.00,A,3648.14444,N,12149.38205,W,1.263,52.85,060225,,,D*4F 2025-02-06T16:30:36.613Z,1738859436.613 [NAL9602](INFO): GPS fix at 20250206T163035: (36.802407, -121.823034) 2025-02-06T16:30:36.624Z,1738859436.624 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:30:36.624Z,1738859436.624 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:31:07.718Z,1738859467.718 [NAL9602](INFO): SBD MO Status=2, MOMSN=62097, MT Status=2, MTMSN=0 2025-02-06T16:31:07.718Z,1738859467.718 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T16:31:15.159Z,1738859475.159 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0027.lzma 2025-02-06T16:31:16.161Z,1738859476.161 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0027.lzma.bak 2025-02-06T16:31:16.161Z,1738859476.161 [DataOverHttps](INFO): SBD MOMSN=23960036 2025-02-06T16:31:23.526Z,1738859483.526 [NAL9602](INFO): SBD MO Status=2, MOMSN=62097, MT Status=2, MTMSN=0 2025-02-06T16:31:23.526Z,1738859483.526 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T16:31:34.442Z,1738859494.442 [NAL9602](INFO): SBD MO Status=0, MOMSN=62097, MT Status=0, MTMSN=0 2025-02-06T16:31:34.442Z,1738859494.442 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:31:36.243Z,1738859496.243 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20250206T153719/Express0028.lzma 2025-02-06T16:31:37.245Z,1738859497.245 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0028.lzma.bak 2025-02-06T16:31:37.245Z,1738859497.245 [DataOverHttps](INFO): SBD MOMSN=23960039 2025-02-06T16:31:38.502Z,1738859498.502 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:31:38.503Z,1738859498.503 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:31:38.503Z,1738859498.503 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:32:05.144Z,1738859525.144 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T16:32:49.986Z,1738859569.986 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:33:03.722Z,1738859583.722 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:33:17.861Z,1738859597.861 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:33:31.598Z,1738859611.598 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:33:45.738Z,1738859625.738 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:33:59.075Z,1738859639.075 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:34:12.403Z,1738859652.403 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:34:25.742Z,1738859665.742 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:34:39.470Z,1738859679.470 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:34:52.808Z,1738859692.808 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:36:39.115Z,1738859799.115 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:36:39.115Z,1738859799.115 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:36:39.115Z,1738859799.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:36:39.116Z,1738859799.116 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:36:39.526Z,1738859799.526 [Default:CheckIn:D] Stopped 2025-02-06T16:36:39.526Z,1738859799.526 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:36:39.919Z,1738859799.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.129289 min 2025-02-06T16:36:39.919Z,1738859799.919 [Default:CheckIn:E] Stopped 2025-02-06T16:36:39.920Z,1738859799.920 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:36:39.920Z,1738859799.920 [Default:CheckIn] Stopped 2025-02-06T16:36:39.920Z,1738859799.920 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:36:39.920Z,1738859799.920 [Default:CheckIn](INFO): Running loop #13 2025-02-06T16:36:39.920Z,1738859799.920 [Default:CheckIn] Running Loop=13 2025-02-06T16:36:39.920Z,1738859799.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:36:39.920Z,1738859799.920 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:36:41.930Z,1738859801.930 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163641.00,A,3648.12281,N,12149.42306,W,0.544,216.48,060225,,,A*79 2025-02-06T16:36:41.933Z,1738859801.933 [NAL9602](INFO): GPS fix at 20250206T163641: (36.802047, -121.823718) 2025-02-06T16:36:41.944Z,1738859801.944 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:36:41.944Z,1738859801.944 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:36:49.476Z,1738859809.476 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0030.lzma 2025-02-06T16:36:50.477Z,1738859810.477 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0030.lzma.bak 2025-02-06T16:36:50.477Z,1738859810.477 [DataOverHttps](INFO): SBD MOMSN=23960043 2025-02-06T16:36:53.654Z,1738859813.654 [NAL9602](INFO): SBD MO Status=0, MOMSN=62098, MT Status=0, MTMSN=0 2025-02-06T16:36:53.654Z,1738859813.654 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:37:10.404Z,1738859830.404 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20250206T153719/Express0031.lzma 2025-02-06T16:37:11.405Z,1738859831.405 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0031.lzma.bak 2025-02-06T16:37:11.405Z,1738859831.405 [DataOverHttps](INFO): SBD MOMSN=23960046 2025-02-06T16:37:12.707Z,1738859832.707 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:37:12.708Z,1738859832.708 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:37:12.708Z,1738859832.708 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:37:24.392Z,1738859844.392 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T16:42:13.442Z,1738860133.442 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:42:13.442Z,1738860133.442 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:42:13.442Z,1738860133.442 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:42:13.443Z,1738860133.443 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:42:13.806Z,1738860133.806 [Default:CheckIn:D] Stopped 2025-02-06T16:42:13.806Z,1738860133.806 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:42:14.215Z,1738860134.215 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.700627 min 2025-02-06T16:42:14.215Z,1738860134.215 [Default:CheckIn:E] Stopped 2025-02-06T16:42:14.215Z,1738860134.215 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:42:14.215Z,1738860134.215 [Default:CheckIn] Stopped 2025-02-06T16:42:14.215Z,1738860134.215 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:42:14.215Z,1738860134.215 [Default:CheckIn](INFO): Running loop #14 2025-02-06T16:42:14.215Z,1738860134.215 [Default:CheckIn] Running Loop=14 2025-02-06T16:42:14.216Z,1738860134.216 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:42:14.216Z,1738860134.216 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:42:16.218Z,1738860136.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164215.00,A,3648.12723,N,12149.27376,W,3.868,93.70,060225,,,D*43 2025-02-06T16:42:16.220Z,1738860136.220 [NAL9602](INFO): GPS fix at 20250206T164215: (36.802121, -121.821229) 2025-02-06T16:42:16.251Z,1738860136.251 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:42:16.251Z,1738860136.251 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:42:17.482Z,1738860137.482 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:42:29.618Z,1738860149.618 [NAL9602](INFO): SBD MO Status=0, MOMSN=62099, MT Status=0, MTMSN=0 2025-02-06T16:42:29.618Z,1738860149.618 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:42:30.055Z,1738860150.055 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0033.lzma 2025-02-06T16:42:30.824Z,1738860150.824 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:42:31.057Z,1738860151.057 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0033.lzma.bak 2025-02-06T16:42:31.057Z,1738860151.057 [DataOverHttps](INFO): SBD MOMSN=23960050 2025-02-06T16:42:44.154Z,1738860164.154 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:42:46.927Z,1738860166.927 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20250206T153719/Express0034.lzma 2025-02-06T16:42:47.971Z,1738860167.971 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0034.lzma.bak 2025-02-06T16:42:47.971Z,1738860167.971 [DataOverHttps](INFO): SBD MOMSN=23960053 2025-02-06T16:42:49.456Z,1738860169.456 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:42:49.457Z,1738860169.457 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:42:49.457Z,1738860169.457 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:42:57.547Z,1738860177.547 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:43:00.386Z,1738860180.386 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T16:43:12.090Z,1738860192.090 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:43:26.634Z,1738860206.634 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:43:39.966Z,1738860219.966 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:43:52.500Z,1738860232.500 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:44:05.853Z,1738860245.853 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:44:19.559Z,1738860259.559 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:44:22.078Z,1738860262.078 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-02-06T16:44:32.928Z,1738860272.928 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:47:50.098Z,1738860470.098 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:47:50.098Z,1738860470.098 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:47:50.098Z,1738860470.098 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:47:50.099Z,1738860470.099 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:47:50.500Z,1738860470.500 [Default:CheckIn:D] Stopped 2025-02-06T16:47:50.500Z,1738860470.500 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:47:50.911Z,1738860470.911 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.312174 min 2025-02-06T16:47:50.911Z,1738860470.911 [Default:CheckIn:E] Stopped 2025-02-06T16:47:50.912Z,1738860470.912 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:47:50.912Z,1738860470.912 [Default:CheckIn] Stopped 2025-02-06T16:47:50.912Z,1738860470.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:47:50.912Z,1738860470.912 [Default:CheckIn](INFO): Running loop #15 2025-02-06T16:47:50.912Z,1738860470.912 [Default:CheckIn] Running Loop=15 2025-02-06T16:47:50.912Z,1738860470.912 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:47:50.912Z,1738860470.912 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:47:52.918Z,1738860472.918 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164752.00,A,3648.10684,N,12148.63106,W,5.968,101.66,060225,,,D*75 2025-02-06T16:47:52.921Z,1738860472.921 [NAL9602](INFO): GPS fix at 20250206T164752: (36.801781, -121.810518) 2025-02-06T16:47:52.952Z,1738860472.952 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:47:52.952Z,1738860472.952 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:47:59.963Z,1738860479.963 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250206T153719/Courier0036.lzma 2025-02-06T16:48:00.965Z,1738860480.965 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0036.lzma.bak 2025-02-06T16:48:00.965Z,1738860480.965 [DataOverHttps](INFO): SBD MOMSN=23960057 2025-02-06T16:48:09.102Z,1738860489.102 [NAL9602](INFO): SBD MO Status=0, MOMSN=62100, MT Status=0, MTMSN=0 2025-02-06T16:48:09.102Z,1738860489.102 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:48:20.752Z,1738860500.752 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20250206T153719/Express0037.lzma 2025-02-06T16:48:21.754Z,1738860501.754 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0037.lzma.bak 2025-02-06T16:48:21.754Z,1738860501.754 [DataOverHttps](INFO): SBD MOMSN=23960060 2025-02-06T16:48:23.279Z,1738860503.279 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:48:23.279Z,1738860503.279 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:48:23.280Z,1738860503.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:48:39.840Z,1738860519.840 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T16:50:35.418Z,1738860635.418 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-02-06T16:51:33.192Z,1738860693.192 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-02-06T16:51:56.618Z,1738860716.618 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:52:09.960Z,1738860729.960 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:52:23.292Z,1738860743.292 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:52:36.622Z,1738860756.622 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:52:50.359Z,1738860770.359 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:53:03.690Z,1738860783.690 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:53:17.023Z,1738860797.023 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:53:23.904Z,1738860803.904 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:53:23.904Z,1738860803.904 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:53:23.904Z,1738860803.904 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:53:23.904Z,1738860803.904 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:53:24.318Z,1738860804.318 [Default:CheckIn:D] Stopped 2025-02-06T16:53:24.318Z,1738860804.318 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:53:24.712Z,1738860804.712 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.875830 min 2025-02-06T16:53:24.712Z,1738860804.712 [Default:CheckIn:E] Stopped 2025-02-06T16:53:24.713Z,1738860804.713 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:53:24.713Z,1738860804.713 [Default:CheckIn] Stopped 2025-02-06T16:53:24.713Z,1738860804.713 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:53:24.713Z,1738860804.713 [Default:CheckIn](INFO): Running loop #16 2025-02-06T16:53:24.713Z,1738860804.713 [Default:CheckIn] Running Loop=16 2025-02-06T16:53:24.713Z,1738860804.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:53:24.713Z,1738860804.713 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:53:26.722Z,1738860806.722 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165325.00,A,3648.14627,N,12147.95903,W,4.860,62.99,060225,,,D*4A 2025-02-06T16:53:26.724Z,1738860806.724 [NAL9602](INFO): GPS fix at 20250206T165325: (36.802438, -121.799317) 2025-02-06T16:53:26.735Z,1738860806.735 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:53:26.735Z,1738860806.735 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:53:30.758Z,1738860810.758 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:53:33.979Z,1738860813.979 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0039.lzma 2025-02-06T16:53:34.981Z,1738860814.981 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0039.lzma.bak 2025-02-06T16:53:34.982Z,1738860814.982 [DataOverHttps](INFO): SBD MOMSN=23960064 2025-02-06T16:53:43.723Z,1738860823.723 [NAL9602](INFO): SBD MO Status=0, MOMSN=62101, MT Status=0, MTMSN=0 2025-02-06T16:53:43.723Z,1738860823.723 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:53:44.094Z,1738860824.094 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:53:50.935Z,1738860830.935 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20250206T153719/Express0040.lzma 2025-02-06T16:53:51.939Z,1738860831.939 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0040.lzma.bak 2025-02-06T16:53:51.940Z,1738860831.940 [DataOverHttps](INFO): SBD MOMSN=23960067 2025-02-06T16:53:53.408Z,1738860833.408 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:53:53.408Z,1738860833.408 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:53:53.408Z,1738860833.408 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:53:57.830Z,1738860837.830 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:54:11.162Z,1738860851.162 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T16:54:14.402Z,1738860854.402 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T16:58:49.161Z,1738861129.161 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2025-02-06T16:58:49.164Z,1738861129.164 [BPC1](FAULT): Failed to receive data from 11 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 34, 35, 36, 38, 39, 50, 51, 54, 55. 2025-02-06T16:58:54.025Z,1738861134.025 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T16:58:54.025Z,1738861134.025 [Default:CheckIn:C.Wait] Stopped 2025-02-06T16:58:54.025Z,1738861134.025 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T16:58:54.025Z,1738861134.025 [Default:CheckIn:D] Running Loop=1 2025-02-06T16:58:54.482Z,1738861134.482 [Default:CheckIn:D] Stopped 2025-02-06T16:58:54.482Z,1738861134.482 [Default:CheckIn:E] Running Loop=1 2025-02-06T16:58:54.874Z,1738861134.874 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.378556 min 2025-02-06T16:58:54.874Z,1738861134.874 [Default:CheckIn:E] Stopped 2025-02-06T16:58:54.874Z,1738861134.874 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T16:58:54.912Z,1738861134.912 [Default:CheckIn] Stopped 2025-02-06T16:58:54.912Z,1738861134.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T16:58:54.912Z,1738861134.912 [Default:CheckIn](INFO): Running loop #17 2025-02-06T16:58:54.912Z,1738861134.912 [Default:CheckIn] Running Loop=17 2025-02-06T16:58:54.912Z,1738861134.912 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T16:58:54.912Z,1738861134.912 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T16:58:56.838Z,1738861136.838 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165856.00,A,3648.36284,N,12147.44954,W,3.674,58.80,060225,,,D*4B 2025-02-06T16:58:56.841Z,1738861136.841 [NAL9602](INFO): GPS fix at 20250206T165856: (36.806047, -121.790826) 2025-02-06T16:58:56.880Z,1738861136.880 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T16:58:56.880Z,1738861136.880 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T16:59:04.039Z,1738861144.039 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20250206T153719/Courier0042.lzma 2025-02-06T16:59:05.041Z,1738861145.041 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0042.lzma.bak 2025-02-06T16:59:05.042Z,1738861145.042 [DataOverHttps](INFO): SBD MOMSN=23960071 2025-02-06T16:59:20.791Z,1738861160.791 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250206T153719/Express0043.lzma 2025-02-06T16:59:21.075Z,1738861161.075 [NAL9602](INFO): SBD MO Status=0, MOMSN=62102, MT Status=0, MTMSN=0 2025-02-06T16:59:21.075Z,1738861161.075 [NAL9602](INFO): No messages in MT queue 2025-02-06T16:59:21.793Z,1738861161.793 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0043.lzma.bak 2025-02-06T16:59:21.794Z,1738861161.794 [DataOverHttps](INFO): SBD MOMSN=23960074 2025-02-06T16:59:23.110Z,1738861163.110 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T16:59:23.110Z,1738861163.110 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T16:59:23.110Z,1738861163.110 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T16:59:51.775Z,1738861191.775 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:04:23.706Z,1738861463.706 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T17:04:23.706Z,1738861463.706 [Default:CheckIn:C.Wait] Stopped 2025-02-06T17:04:23.707Z,1738861463.707 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T17:04:23.707Z,1738861463.707 [Default:CheckIn:D] Running Loop=1 2025-02-06T17:04:24.103Z,1738861464.103 [Default:CheckIn:D] Stopped 2025-02-06T17:04:24.103Z,1738861464.103 [Default:CheckIn:E] Running Loop=1 2025-02-06T17:04:24.514Z,1738861464.514 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.872233 min 2025-02-06T17:04:24.514Z,1738861464.514 [Default:CheckIn:E] Stopped 2025-02-06T17:04:24.515Z,1738861464.515 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T17:04:24.515Z,1738861464.515 [Default:CheckIn] Stopped 2025-02-06T17:04:24.515Z,1738861464.515 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T17:04:24.515Z,1738861464.515 [Default:CheckIn](INFO): Running loop #18 2025-02-06T17:04:24.515Z,1738861464.515 [Default:CheckIn] Running Loop=18 2025-02-06T17:04:24.515Z,1738861464.515 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T17:04:24.515Z,1738861464.515 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T17:04:26.519Z,1738861466.519 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170425.00,A,3648.42721,N,12147.35380,W,0.622,32.30,060225,,,D*4C 2025-02-06T17:04:26.521Z,1738861466.521 [NAL9602](INFO): GPS fix at 20250206T170425: (36.807120, -121.789230) 2025-02-06T17:04:26.553Z,1738861466.553 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T17:04:26.553Z,1738861466.553 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T17:04:33.559Z,1738861473.559 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0045.lzma 2025-02-06T17:04:34.561Z,1738861474.561 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0045.lzma.bak 2025-02-06T17:04:34.562Z,1738861474.562 [DataOverHttps](INFO): SBD MOMSN=23960096 2025-02-06T17:04:41.875Z,1738861481.875 [NAL9602](INFO): SBD MO Status=0, MOMSN=62103, MT Status=0, MTMSN=0 2025-02-06T17:04:41.875Z,1738861481.875 [NAL9602](INFO): No messages in MT queue 2025-02-06T17:04:52.647Z,1738861492.647 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20250206T153719/Express0046.lzma 2025-02-06T17:04:53.649Z,1738861493.649 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0046.lzma.bak 2025-02-06T17:04:53.650Z,1738861493.650 [DataOverHttps](INFO): SBD MOMSN=23960099 2025-02-06T17:04:55.247Z,1738861495.247 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T17:04:55.247Z,1738861495.247 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T17:04:55.247Z,1738861495.247 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T17:05:12.609Z,1738861512.609 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:09:55.848Z,1738861795.848 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T17:09:55.848Z,1738861795.848 [Default:CheckIn:C.Wait] Stopped 2025-02-06T17:09:55.848Z,1738861795.848 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T17:09:55.849Z,1738861795.849 [Default:CheckIn:D] Running Loop=1 2025-02-06T17:09:56.232Z,1738861796.232 [Default:CheckIn:D] Stopped 2025-02-06T17:09:56.232Z,1738861796.232 [Default:CheckIn:E] Running Loop=1 2025-02-06T17:09:56.640Z,1738861796.640 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.407723 min 2025-02-06T17:09:56.640Z,1738861796.640 [Default:CheckIn:E] Stopped 2025-02-06T17:09:56.640Z,1738861796.640 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T17:09:56.640Z,1738861796.640 [Default:CheckIn] Stopped 2025-02-06T17:09:56.640Z,1738861796.640 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T17:09:56.640Z,1738861796.640 [Default:CheckIn](INFO): Running loop #19 2025-02-06T17:09:56.640Z,1738861796.640 [Default:CheckIn] Running Loop=19 2025-02-06T17:09:56.641Z,1738861796.641 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T17:09:56.641Z,1738861796.641 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T17:09:58.671Z,1738861798.671 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170957.00,A,3648.33317,N,12147.13046,W,5.073,191.27,060225,,,D*77 2025-02-06T17:09:58.673Z,1738861798.673 [NAL9602](INFO): GPS fix at 20250206T170957: (36.805553, -121.785508) 2025-02-06T17:09:58.684Z,1738861798.684 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T17:09:58.684Z,1738861798.684 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T17:10:05.471Z,1738861805.471 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0048.lzma 2025-02-06T17:10:06.473Z,1738861806.473 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0048.lzma.bak 2025-02-06T17:10:06.474Z,1738861806.474 [DataOverHttps](INFO): SBD MOMSN=23960145 2025-02-06T17:10:19.702Z,1738861819.702 [NAL9602](INFO): SBD MO Status=0, MOMSN=62104, MT Status=0, MTMSN=0 2025-02-06T17:10:19.702Z,1738861819.702 [NAL9602](INFO): No messages in MT queue 2025-02-06T17:10:25.500Z,1738861825.500 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20250206T153719/Express0049.lzma 2025-02-06T17:10:26.502Z,1738861826.502 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0049.lzma.bak 2025-02-06T17:10:26.502Z,1738861826.502 [DataOverHttps](INFO): SBD MOMSN=23960149 2025-02-06T17:10:27.835Z,1738861827.835 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T17:10:27.844Z,1738861827.844 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T17:10:27.844Z,1738861827.844 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T17:10:50.428Z,1738861850.428 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:13:51.014Z,1738862031.014 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T17:14:04.346Z,1738862044.346 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T17:14:17.678Z,1738862057.678 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T17:14:31.014Z,1738862071.014 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T17:14:44.750Z,1738862084.750 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T17:14:58.085Z,1738862098.085 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T17:15:11.414Z,1738862111.414 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T17:15:24.746Z,1738862124.746 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T17:15:28.399Z,1738862128.399 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T17:15:28.399Z,1738862128.399 [Default:CheckIn:C.Wait] Stopped 2025-02-06T17:15:28.399Z,1738862128.399 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T17:15:28.399Z,1738862128.399 [Default:CheckIn:D] Running Loop=1 2025-02-06T17:15:28.807Z,1738862128.807 [Default:CheckIn:D] Stopped 2025-02-06T17:15:28.807Z,1738862128.807 [Default:CheckIn:E] Running Loop=1 2025-02-06T17:15:29.219Z,1738862129.219 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.950635 min 2025-02-06T17:15:29.219Z,1738862129.219 [Default:CheckIn:E] Stopped 2025-02-06T17:15:29.219Z,1738862129.219 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T17:15:29.219Z,1738862129.219 [Default:CheckIn] Stopped 2025-02-06T17:15:29.220Z,1738862129.220 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T17:15:29.220Z,1738862129.220 [Default:CheckIn](INFO): Running loop #20 2025-02-06T17:15:29.220Z,1738862129.220 [Default:CheckIn] Running Loop=20 2025-02-06T17:15:29.220Z,1738862129.220 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T17:15:29.220Z,1738862129.220 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T17:15:31.218Z,1738862131.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171530.00,A,3648.13365,N,12147.20901,W,0.039,271.07,060225,,,D*72 2025-02-06T17:15:31.221Z,1738862131.221 [NAL9602](INFO): GPS fix at 20250206T171530: (36.802228, -121.786817) 2025-02-06T17:15:31.252Z,1738862131.252 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T17:15:31.252Z,1738862131.252 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T17:15:38.483Z,1738862138.483 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T17:15:38.791Z,1738862138.791 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0051.lzma 2025-02-06T17:15:39.793Z,1738862139.793 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0051.lzma.bak 2025-02-06T17:15:39.794Z,1738862139.794 [DataOverHttps](INFO): SBD MOMSN=23960153 2025-02-06T17:15:52.622Z,1738862152.622 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T17:15:55.591Z,1738862155.591 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20250206T153719/Express0052.lzma 2025-02-06T17:15:56.593Z,1738862156.593 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0052.lzma.bak 2025-02-06T17:15:56.594Z,1738862156.594 [DataOverHttps](INFO): SBD MOMSN=23960156 2025-02-06T17:15:57.894Z,1738862157.894 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T17:15:57.894Z,1738862157.894 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T17:15:57.894Z,1738862157.894 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T17:16:03.533Z,1738862163.533 [NAL9602](INFO): SBD MO Status=2, MOMSN=62105, MT Status=2, MTMSN=0 2025-02-06T17:16:03.534Z,1738862163.534 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T17:16:06.363Z,1738862166.363 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-02-06T17:17:02.943Z,1738862222.943 [NAL9602](INFO): SBD MO Status=0, MOMSN=62105, MT Status=0, MTMSN=0 2025-02-06T17:17:02.943Z,1738862222.943 [NAL9602](INFO): No messages in MT queue 2025-02-06T17:17:33.629Z,1738862253.629 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:20:50.806Z,1738862450.806 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 4. 2025-02-06T17:20:50.849Z,1738862450.849 [BPC1](INFO): Received data from all battery sticks. 2025-02-06T17:20:58.493Z,1738862458.493 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T17:20:58.494Z,1738862458.494 [Default:CheckIn:C.Wait] Stopped 2025-02-06T17:20:58.494Z,1738862458.494 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T17:20:58.494Z,1738862458.494 [Default:CheckIn:D] Running Loop=1 2025-02-06T17:20:58.914Z,1738862458.914 [Default:CheckIn:D] Stopped 2025-02-06T17:20:58.914Z,1738862458.914 [Default:CheckIn:E] Running Loop=1 2025-02-06T17:20:59.294Z,1738862459.294 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.452417 min 2025-02-06T17:20:59.295Z,1738862459.295 [Default:CheckIn:E] Stopped 2025-02-06T17:20:59.295Z,1738862459.295 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T17:20:59.295Z,1738862459.295 [Default:CheckIn] Stopped 2025-02-06T17:20:59.295Z,1738862459.295 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T17:20:59.295Z,1738862459.295 [Default:CheckIn](INFO): Running loop #21 2025-02-06T17:20:59.295Z,1738862459.295 [Default:CheckIn] Running Loop=21 2025-02-06T17:20:59.295Z,1738862459.295 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T17:20:59.295Z,1738862459.295 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T17:21:01.315Z,1738862461.315 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172100.00,A,3648.13350,N,12147.20958,W,0.078,271.07,060225,,,D*79 2025-02-06T17:21:01.317Z,1738862461.317 [NAL9602](INFO): GPS fix at 20250206T172100: (36.802225, -121.786826) 2025-02-06T17:21:01.328Z,1738862461.328 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T17:21:01.328Z,1738862461.328 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T17:21:08.519Z,1738862468.519 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20250206T153719/Courier0054.lzma 2025-02-06T17:21:09.521Z,1738862469.521 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0054.lzma.bak 2025-02-06T17:21:09.522Z,1738862469.522 [DataOverHttps](INFO): SBD MOMSN=23960163 2025-02-06T17:21:25.271Z,1738862485.271 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250206T153719/Express0055.lzma 2025-02-06T17:21:26.274Z,1738862486.274 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0055.lzma.bak 2025-02-06T17:21:26.274Z,1738862486.274 [DataOverHttps](INFO): SBD MOMSN=23960166 2025-02-06T17:21:27.983Z,1738862487.983 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T17:21:27.983Z,1738862487.983 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T17:21:27.999Z,1738862487.999 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T17:22:20.090Z,1738862540.090 [NAL9602](INFO): SBD MO Status=2, MOMSN=62106, MT Status=2, MTMSN=0 2025-02-06T17:22:20.090Z,1738862540.090 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T17:23:14.241Z,1738862594.241 [NAL9602](INFO): SBD MO Status=2, MOMSN=62106, MT Status=2, MTMSN=0 2025-02-06T17:23:14.241Z,1738862594.241 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T17:23:48.582Z,1738862628.582 [NAL9602](INFO): SBD MO Status=0, MOMSN=62106, MT Status=0, MTMSN=0 2025-02-06T17:23:48.582Z,1738862628.582 [NAL9602](INFO): No messages in MT queue 2025-02-06T17:24:19.326Z,1738862659.326 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:26:28.597Z,1738862788.597 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T17:26:28.597Z,1738862788.597 [Default:CheckIn:C.Wait] Stopped 2025-02-06T17:26:28.597Z,1738862788.597 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T17:26:28.597Z,1738862788.597 [Default:CheckIn:D] Running Loop=1 2025-02-06T17:26:29.031Z,1738862789.031 [Default:CheckIn:D] Stopped 2025-02-06T17:26:29.031Z,1738862789.031 [Default:CheckIn:E] Running Loop=1 2025-02-06T17:26:29.413Z,1738862789.413 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.954362 min 2025-02-06T17:26:29.413Z,1738862789.413 [Default:CheckIn:E] Stopped 2025-02-06T17:26:29.414Z,1738862789.414 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T17:26:29.414Z,1738862789.414 [Default:CheckIn] Stopped 2025-02-06T17:26:29.414Z,1738862789.414 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T17:26:29.414Z,1738862789.414 [Default:CheckIn](INFO): Running loop #22 2025-02-06T17:26:29.414Z,1738862789.414 [Default:CheckIn] Running Loop=22 2025-02-06T17:26:29.414Z,1738862789.414 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T17:26:29.414Z,1738862789.414 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T17:26:31.427Z,1738862791.427 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172630.00,A,3648.13360,N,12147.20967,W,0.097,271.07,060225,,,D*73 2025-02-06T17:26:31.468Z,1738862791.468 [NAL9602](INFO): GPS fix at 20250206T172630: (36.802227, -121.786828) 2025-02-06T17:26:31.521Z,1738862791.521 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T17:26:31.521Z,1738862791.521 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T17:26:42.807Z,1738862802.807 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0057.lzma 2025-02-06T17:26:43.810Z,1738862803.810 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0057.lzma.bak 2025-02-06T17:26:43.810Z,1738862803.810 [DataOverHttps](INFO): SBD MOMSN=23960179 2025-02-06T17:27:15.016Z,1738862835.016 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20250206T153719/Express0058.lzma 2025-02-06T17:27:16.002Z,1738862836.002 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0058.lzma.bak 2025-02-06T17:27:16.002Z,1738862836.002 [DataOverHttps](INFO): SBD MOMSN=23960182 2025-02-06T17:27:17.437Z,1738862837.437 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T17:27:17.437Z,1738862837.437 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T17:27:17.437Z,1738862837.437 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T17:27:59.458Z,1738862879.458 [NAL9602](INFO): SBD MO Status=0, MOMSN=62107, MT Status=0, MTMSN=0 2025-02-06T17:27:59.458Z,1738862879.458 [NAL9602](INFO): No messages in MT queue 2025-02-06T17:28:29.869Z,1738862909.869 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:32:17.872Z,1738863137.872 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T17:32:17.872Z,1738863137.872 [Default:CheckIn:C.Wait] Stopped 2025-02-06T17:32:17.872Z,1738863137.872 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T17:32:17.872Z,1738863137.872 [Default:CheckIn:D] Running Loop=1 2025-02-06T17:32:18.275Z,1738863138.275 [Default:CheckIn:D] Stopped 2025-02-06T17:32:18.275Z,1738863138.275 [Default:CheckIn:E] Running Loop=1 2025-02-06T17:32:18.652Z,1738863138.652 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.775106 min 2025-02-06T17:32:18.652Z,1738863138.652 [Default:CheckIn:E] Stopped 2025-02-06T17:32:18.652Z,1738863138.652 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T17:32:18.652Z,1738863138.652 [Default:CheckIn] Stopped 2025-02-06T17:32:18.652Z,1738863138.652 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T17:32:18.652Z,1738863138.652 [Default:CheckIn](INFO): Running loop #23 2025-02-06T17:32:18.653Z,1738863138.653 [Default:CheckIn] Running Loop=23 2025-02-06T17:32:18.653Z,1738863138.653 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T17:32:18.653Z,1738863138.653 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T17:32:20.652Z,1738863140.652 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173219.00,A,3648.15214,N,12147.21977,W,0.797,231.24,060225,,,D*7B 2025-02-06T17:32:20.655Z,1738863140.655 [NAL9602](INFO): GPS fix at 20250206T173219: (36.802536, -121.786996) 2025-02-06T17:32:20.727Z,1738863140.727 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T17:32:20.727Z,1738863140.727 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T17:32:37.587Z,1738863157.587 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0060.lzma 2025-02-06T17:32:38.590Z,1738863158.590 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0060.lzma.bak 2025-02-06T17:32:38.590Z,1738863158.590 [DataOverHttps](INFO): SBD MOMSN=23960186 2025-02-06T17:32:44.770Z,1738863164.770 [NAL9602](INFO): SBD MO Status=2, MOMSN=62108, MT Status=2, MTMSN=0 2025-02-06T17:32:44.771Z,1738863164.771 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T17:33:09.263Z,1738863189.263 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20250206T153719/Express0061.lzma 2025-02-06T17:33:09.578Z,1738863189.578 [NAL9602](INFO): SBD MO Status=0, MOMSN=62108, MT Status=0, MTMSN=0 2025-02-06T17:33:09.578Z,1738863189.578 [NAL9602](INFO): No messages in MT queue 2025-02-06T17:33:10.266Z,1738863190.266 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0061.lzma.bak 2025-02-06T17:33:10.266Z,1738863190.266 [DataOverHttps](INFO): SBD MOMSN=23960189 2025-02-06T17:33:11.636Z,1738863191.636 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T17:33:11.636Z,1738863191.636 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T17:33:11.636Z,1738863191.636 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T17:33:40.280Z,1738863220.280 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:38:12.412Z,1738863492.412 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T17:38:12.412Z,1738863492.412 [Default:CheckIn:C.Wait] Stopped 2025-02-06T17:38:12.412Z,1738863492.412 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T17:38:12.413Z,1738863492.413 [Default:CheckIn:D] Running Loop=1 2025-02-06T17:38:12.853Z,1738863492.853 [Default:CheckIn:D] Stopped 2025-02-06T17:38:12.853Z,1738863492.853 [Default:CheckIn:E] Running Loop=1 2025-02-06T17:38:13.218Z,1738863493.218 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.684741 min 2025-02-06T17:38:13.218Z,1738863493.218 [Default:CheckIn:E] Stopped 2025-02-06T17:38:13.218Z,1738863493.218 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T17:38:13.218Z,1738863493.218 [Default:CheckIn] Stopped 2025-02-06T17:38:13.219Z,1738863493.219 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T17:38:13.219Z,1738863493.219 [Default:CheckIn](INFO): Running loop #24 2025-02-06T17:38:13.219Z,1738863493.219 [Default:CheckIn] Running Loop=24 2025-02-06T17:38:13.219Z,1738863493.219 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T17:38:13.219Z,1738863493.219 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T17:38:15.214Z,1738863495.214 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173814.00,A,3648.14661,N,12147.25542,W,0.389,353.59,060225,,,D*71 2025-02-06T17:38:15.217Z,1738863495.217 [NAL9602](INFO): GPS fix at 20250206T173814: (36.802444, -121.787590) 2025-02-06T17:38:15.237Z,1738863495.237 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T17:38:15.237Z,1738863495.237 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T17:38:23.487Z,1738863503.487 [NAL9602](INFO): SBD MO Status=0, MOMSN=62109, MT Status=0, MTMSN=0 2025-02-06T17:38:23.488Z,1738863503.488 [NAL9602](INFO): No messages in MT queue 2025-02-06T17:38:32.267Z,1738863512.267 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0063.lzma 2025-02-06T17:38:33.275Z,1738863513.275 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0063.lzma.bak 2025-02-06T17:38:33.275Z,1738863513.275 [DataOverHttps](INFO): SBD MOMSN=23960193 2025-02-06T17:38:53.941Z,1738863533.941 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:39:03.727Z,1738863543.727 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20250206T153719/Express0064.lzma 2025-02-06T17:39:04.730Z,1738863544.730 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0064.lzma.bak 2025-02-06T17:39:04.730Z,1738863544.730 [DataOverHttps](INFO): SBD MOMSN=23960197 2025-02-06T17:39:06.178Z,1738863546.178 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T17:39:06.178Z,1738863546.178 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T17:39:06.178Z,1738863546.178 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T17:43:11.040Z,1738863791.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-06T17:44:06.917Z,1738863846.917 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T17:44:06.918Z,1738863846.918 [Default:CheckIn:C.Wait] Stopped 2025-02-06T17:44:06.918Z,1738863846.918 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T17:44:06.918Z,1738863846.918 [Default:CheckIn:D] Running Loop=1 2025-02-06T17:44:07.326Z,1738863847.326 [Default:CheckIn:D] Stopped 2025-02-06T17:44:07.326Z,1738863847.326 [Default:CheckIn:E] Running Loop=1 2025-02-06T17:44:07.738Z,1738863847.738 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 140.592627 min 2025-02-06T17:44:07.738Z,1738863847.738 [Default:CheckIn:E] Stopped 2025-02-06T17:44:07.738Z,1738863847.738 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T17:44:07.738Z,1738863847.738 [Default:CheckIn] Stopped 2025-02-06T17:44:07.738Z,1738863847.738 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T17:44:07.739Z,1738863847.739 [Default:CheckIn](INFO): Running loop #25 2025-02-06T17:44:07.739Z,1738863847.739 [Default:CheckIn] Running Loop=25 2025-02-06T17:44:07.739Z,1738863847.739 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T17:44:07.739Z,1738863847.739 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T17:44:09.739Z,1738863849.739 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174409.00,A,3648.17747,N,12147.28432,W,0.097,240.07,060225,,,D*7F 2025-02-06T17:44:09.741Z,1738863849.741 [NAL9602](INFO): GPS fix at 20250206T174409: (36.802958, -121.788072) 2025-02-06T17:44:09.756Z,1738863849.756 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T17:44:09.756Z,1738863849.756 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T17:44:24.495Z,1738863864.495 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0066.lzma 2025-02-06T17:44:27.506Z,1738863867.506 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0066.lzma.bak 2025-02-06T17:44:27.506Z,1738863867.506 [DataOverHttps](INFO): SBD MOMSN=23960200 2025-02-06T17:44:57.115Z,1738863897.115 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20250206T153719/Express0067.lzma 2025-02-06T17:44:59.122Z,1738863899.122 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0067.lzma.bak 2025-02-06T17:44:59.122Z,1738863899.122 [DataOverHttps](INFO): SBD MOMSN=23960203 2025-02-06T17:45:00.759Z,1738863900.759 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T17:45:00.759Z,1738863900.759 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T17:45:00.759Z,1738863900.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T17:45:14.446Z,1738863914.446 [NAL9602](INFO): SBD MO Status=2, MOMSN=62110, MT Status=2, MTMSN=0 2025-02-06T17:45:14.446Z,1738863914.446 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T17:45:40.297Z,1738863940.297 [NAL9602](INFO): SBD MO Status=2, MOMSN=62110, MT Status=2, MTMSN=0 2025-02-06T17:45:40.297Z,1738863940.297 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T17:45:50.806Z,1738863950.806 [NAL9602](INFO): SBD MO Status=0, MOMSN=62110, MT Status=0, MTMSN=0 2025-02-06T17:45:50.806Z,1738863950.806 [NAL9602](INFO): No messages in MT queue 2025-02-06T17:46:21.505Z,1738863981.505 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:50:01.350Z,1738864201.350 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T17:50:01.350Z,1738864201.350 [Default:CheckIn:C.Wait] Stopped 2025-02-06T17:50:01.350Z,1738864201.350 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T17:50:01.350Z,1738864201.350 [Default:CheckIn:D] Running Loop=1 2025-02-06T17:50:01.752Z,1738864201.752 [Default:CheckIn:D] Stopped 2025-02-06T17:50:01.752Z,1738864201.752 [Default:CheckIn:E] Running Loop=1 2025-02-06T17:50:02.152Z,1738864202.152 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 146.499723 min 2025-02-06T17:50:02.152Z,1738864202.152 [Default:CheckIn:E] Stopped 2025-02-06T17:50:02.152Z,1738864202.152 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T17:50:02.152Z,1738864202.152 [Default:CheckIn] Stopped 2025-02-06T17:50:02.152Z,1738864202.152 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T17:50:02.152Z,1738864202.152 [Default:CheckIn](INFO): Running loop #26 2025-02-06T17:50:02.153Z,1738864202.153 [Default:CheckIn] Running Loop=26 2025-02-06T17:50:02.153Z,1738864202.153 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T17:50:02.153Z,1738864202.153 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T17:50:04.173Z,1738864204.173 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175003.00,A,3648.17913,N,12147.28397,W,0.253,240.07,060225,,,D*7D 2025-02-06T17:50:04.175Z,1738864204.175 [NAL9602](INFO): GPS fix at 20250206T175003: (36.802985, -121.788066) 2025-02-06T17:50:04.185Z,1738864204.185 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T17:50:04.185Z,1738864204.185 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T17:50:15.275Z,1738864215.275 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0069.lzma 2025-02-06T17:50:16.278Z,1738864216.278 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0069.lzma.bak 2025-02-06T17:50:16.278Z,1738864216.278 [DataOverHttps](INFO): SBD MOMSN=23960208 2025-02-06T17:50:41.386Z,1738864241.386 [NAL9602](INFO): SBD MO Status=2, MOMSN=62111, MT Status=2, MTMSN=0 2025-02-06T17:50:41.386Z,1738864241.386 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T17:50:44.543Z,1738864244.543 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250206T153719/Express0070.lzma 2025-02-06T17:50:45.545Z,1738864245.545 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0070.lzma.bak 2025-02-06T17:50:45.546Z,1738864245.546 [DataOverHttps](INFO): SBD MOMSN=23960213 2025-02-06T17:50:47.058Z,1738864247.058 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T17:50:47.058Z,1738864247.058 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T17:50:47.058Z,1738864247.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T17:51:09.322Z,1738864269.322 [NAL9602](INFO): SBD MO Status=2, MOMSN=62111, MT Status=2, MTMSN=0 2025-02-06T17:51:09.322Z,1738864269.322 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T17:52:14.773Z,1738864334.773 [NAL9602](INFO): SBD MO Status=0, MOMSN=62111, MT Status=0, MTMSN=0 2025-02-06T17:52:14.773Z,1738864334.773 [NAL9602](INFO): No messages in MT queue 2025-02-06T17:52:45.536Z,1738864365.536 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:55:47.494Z,1738864547.494 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T17:55:47.494Z,1738864547.494 [Default:CheckIn:C.Wait] Stopped 2025-02-06T17:55:47.494Z,1738864547.494 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T17:55:47.495Z,1738864547.495 [Default:CheckIn:D] Running Loop=1 2025-02-06T17:55:47.896Z,1738864547.896 [Default:CheckIn:D] Stopped 2025-02-06T17:55:47.896Z,1738864547.896 [Default:CheckIn:E] Running Loop=1 2025-02-06T17:55:48.310Z,1738864548.310 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 152.268783 min 2025-02-06T17:55:48.310Z,1738864548.310 [Default:CheckIn:E] Stopped 2025-02-06T17:55:48.310Z,1738864548.310 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T17:55:48.310Z,1738864548.310 [Default:CheckIn] Stopped 2025-02-06T17:55:48.310Z,1738864548.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T17:55:48.310Z,1738864548.310 [Default:CheckIn](INFO): Running loop #27 2025-02-06T17:55:48.310Z,1738864548.310 [Default:CheckIn] Running Loop=27 2025-02-06T17:55:48.310Z,1738864548.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T17:55:48.310Z,1738864548.310 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T17:55:50.309Z,1738864550.309 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175549.00,A,3648.18066,N,12147.28103,W,0.058,301.69,060225,,,A*7D 2025-02-06T17:55:50.312Z,1738864550.312 [NAL9602](INFO): GPS fix at 20250206T175549: (36.803011, -121.788017) 2025-02-06T17:55:50.325Z,1738864550.325 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T17:55:50.325Z,1738864550.325 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T17:55:57.767Z,1738864557.767 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0072.lzma 2025-02-06T17:55:58.769Z,1738864558.769 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0072.lzma.bak 2025-02-06T17:55:58.770Z,1738864558.770 [DataOverHttps](INFO): SBD MOMSN=23960218 2025-02-06T17:56:04.618Z,1738864564.618 [NAL9602](INFO): SBD MO Status=0, MOMSN=62112, MT Status=0, MTMSN=0 2025-02-06T17:56:04.618Z,1738864564.618 [NAL9602](INFO): No messages in MT queue 2025-02-06T17:56:14.595Z,1738864574.595 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250206T153719/Express0073.lzma 2025-02-06T17:56:15.598Z,1738864575.598 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0073.lzma.bak 2025-02-06T17:56:15.598Z,1738864575.598 [DataOverHttps](INFO): SBD MOMSN=23960221 2025-02-06T17:56:16.749Z,1738864576.749 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T17:56:16.749Z,1738864576.749 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T17:56:16.749Z,1738864576.749 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T17:56:35.339Z,1738864595.339 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T17:57:02.503Z,1738864622.503 [CBIT](IMPORTANT): Beginning ground fault scan 2025-02-06T17:57:05.320Z,1738864625.320 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2025-02-06T17:57:13.399Z,1738864633.399 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002390 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.002003 CHAN A3 (5V): -0.001362 CHAN B0 (3.3V): 0.000107 CHAN B1 (3.15aV): -0.000190 CHAN B2 (3.15bV): -0.000361 CHAN B3 (GND): -0.000047 OPEN: -0.000292 Full Scale: +/- 1 mA 2025-02-06T18:01:17.179Z,1738864877.179 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T18:01:17.179Z,1738864877.179 [Default:CheckIn:C.Wait] Stopped 2025-02-06T18:01:17.179Z,1738864877.179 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T18:01:17.179Z,1738864877.179 [Default:CheckIn:D] Running Loop=1 2025-02-06T18:01:17.627Z,1738864877.627 [Default:CheckIn:D] Stopped 2025-02-06T18:01:17.627Z,1738864877.627 [Default:CheckIn:E] Running Loop=1 2025-02-06T18:01:17.992Z,1738864877.992 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 157.764307 min 2025-02-06T18:01:17.992Z,1738864877.992 [Default:CheckIn:E] Stopped 2025-02-06T18:01:17.992Z,1738864877.992 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T18:01:17.992Z,1738864877.992 [Default:CheckIn] Stopped 2025-02-06T18:01:17.992Z,1738864877.992 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T18:01:17.992Z,1738864877.992 [Default:CheckIn](INFO): Running loop #28 2025-02-06T18:01:17.992Z,1738864877.992 [Default:CheckIn] Running Loop=28 2025-02-06T18:01:17.992Z,1738864877.992 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T18:01:17.992Z,1738864877.992 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T18:01:19.995Z,1738864879.995 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180119.00,A,3648.19316,N,12147.28017,W,0.136,301.69,060225,,,A*7E 2025-02-06T18:01:19.997Z,1738864879.997 [NAL9602](INFO): GPS fix at 20250206T180119: (36.803219, -121.788003) 2025-02-06T18:01:20.007Z,1738864880.007 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T18:01:20.008Z,1738864880.008 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T18:01:28.927Z,1738864888.927 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0075.lzma 2025-02-06T18:01:29.929Z,1738864889.929 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0075.lzma.bak 2025-02-06T18:01:29.930Z,1738864889.930 [DataOverHttps](INFO): SBD MOMSN=23960225 2025-02-06T18:01:52.284Z,1738864912.284 [DataOverHttps](INFO): Sending 351 bytes from file Logs/20250206T153719/Express0076.lzma 2025-02-06T18:01:53.286Z,1738864913.286 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0076.lzma.bak 2025-02-06T18:01:53.286Z,1738864913.286 [DataOverHttps](INFO): SBD MOMSN=23960228 2025-02-06T18:01:54.768Z,1738864914.768 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T18:01:54.768Z,1738864914.768 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T18:01:54.769Z,1738864914.769 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T18:02:00.814Z,1738864920.814 [NAL9602](INFO): SBD MO Status=0, MOMSN=62113, MT Status=0, MTMSN=0 2025-02-06T18:02:00.814Z,1738864920.814 [NAL9602](INFO): No messages in MT queue 2025-02-06T18:02:31.573Z,1738864951.573 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T18:06:55.414Z,1738865215.414 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T18:06:55.414Z,1738865215.414 [Default:CheckIn:C.Wait] Stopped 2025-02-06T18:06:55.414Z,1738865215.414 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T18:06:55.414Z,1738865215.414 [Default:CheckIn:D] Running Loop=1 2025-02-06T18:06:55.829Z,1738865215.829 [Default:CheckIn:D] Stopped 2025-02-06T18:06:55.829Z,1738865215.829 [Default:CheckIn:E] Running Loop=1 2025-02-06T18:06:56.217Z,1738865216.217 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 163.401009 min 2025-02-06T18:06:56.217Z,1738865216.217 [Default:CheckIn:E] Stopped 2025-02-06T18:06:56.217Z,1738865216.217 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T18:06:56.217Z,1738865216.217 [Default:CheckIn] Stopped 2025-02-06T18:06:56.217Z,1738865216.217 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T18:06:56.217Z,1738865216.217 [Default:CheckIn](INFO): Running loop #29 2025-02-06T18:06:56.217Z,1738865216.217 [Default:CheckIn] Running Loop=29 2025-02-06T18:06:56.217Z,1738865216.217 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T18:06:56.218Z,1738865216.218 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T18:06:58.218Z,1738865218.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180657.00,A,3648.18264,N,12147.28143,W,0.156,301.69,060225,,,A*70 2025-02-06T18:06:58.221Z,1738865218.221 [NAL9602](INFO): GPS fix at 20250206T180657: (36.803044, -121.788024) 2025-02-06T18:06:58.248Z,1738865218.248 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T18:06:58.248Z,1738865218.248 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T18:07:05.195Z,1738865225.195 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0078.lzma 2025-02-06T18:07:06.197Z,1738865226.197 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0078.lzma.bak 2025-02-06T18:07:06.198Z,1738865226.198 [DataOverHttps](INFO): SBD MOMSN=23960234 2025-02-06T18:07:11.146Z,1738865231.146 [NAL9602](INFO): SBD MO Status=0, MOMSN=62114, MT Status=0, MTMSN=0 2025-02-06T18:07:11.146Z,1738865231.146 [NAL9602](INFO): No messages in MT queue 2025-02-06T18:07:21.907Z,1738865241.907 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250206T153719/Express0079.lzma 2025-02-06T18:07:22.910Z,1738865242.910 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0079.lzma.bak 2025-02-06T18:07:22.910Z,1738865242.910 [DataOverHttps](INFO): SBD MOMSN=23960238 2025-02-06T18:07:24.083Z,1738865244.083 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T18:07:24.083Z,1738865244.083 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T18:07:24.083Z,1738865244.083 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T18:07:41.852Z,1738865261.852 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T18:12:24.722Z,1738865544.722 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T18:12:24.722Z,1738865544.722 [Default:CheckIn:C.Wait] Stopped 2025-02-06T18:12:24.723Z,1738865544.723 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T18:12:24.723Z,1738865544.723 [Default:CheckIn:D] Running Loop=1 2025-02-06T18:12:25.113Z,1738865545.113 [Default:CheckIn:D] Stopped 2025-02-06T18:12:25.113Z,1738865545.113 [Default:CheckIn:E] Running Loop=1 2025-02-06T18:12:25.518Z,1738865545.518 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 168.889079 min 2025-02-06T18:12:25.518Z,1738865545.518 [Default:CheckIn:E] Stopped 2025-02-06T18:12:25.518Z,1738865545.518 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T18:12:25.518Z,1738865545.518 [Default:CheckIn] Stopped 2025-02-06T18:12:25.518Z,1738865545.518 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T18:12:25.518Z,1738865545.518 [Default:CheckIn](INFO): Running loop #30 2025-02-06T18:12:25.518Z,1738865545.518 [Default:CheckIn] Running Loop=30 2025-02-06T18:12:25.518Z,1738865545.518 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T18:12:25.518Z,1738865545.518 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T18:12:27.526Z,1738865547.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181226.00,A,3648.19076,N,12147.27805,W,0.078,13.08,060225,,,A*4D 2025-02-06T18:12:27.537Z,1738865547.537 [NAL9602](INFO): GPS fix at 20250206T181226: (36.803179, -121.787967) 2025-02-06T18:12:27.552Z,1738865547.552 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T18:12:27.553Z,1738865547.553 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T18:12:36.035Z,1738865556.035 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0081.lzma 2025-02-06T18:12:37.038Z,1738865557.038 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0081.lzma.bak 2025-02-06T18:12:37.038Z,1738865557.038 [DataOverHttps](INFO): SBD MOMSN=23960246 2025-02-06T18:12:54.650Z,1738865574.650 [NAL9602](INFO): SBD MO Status=2, MOMSN=62115, MT Status=2, MTMSN=0 2025-02-06T18:12:54.650Z,1738865574.650 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T18:12:59.651Z,1738865579.651 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250206T153719/Express0082.lzma 2025-02-06T18:13:00.654Z,1738865580.654 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0082.lzma.bak 2025-02-06T18:13:00.654Z,1738865580.654 [DataOverHttps](INFO): SBD MOMSN=23960250 2025-02-06T18:13:01.985Z,1738865581.985 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T18:13:01.986Z,1738865581.986 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T18:13:01.986Z,1738865581.986 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T18:13:20.910Z,1738865600.910 [NAL9602](INFO): SBD MO Status=2, MOMSN=62115, MT Status=2, MTMSN=0 2025-02-06T18:13:20.910Z,1738865600.910 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T18:14:04.142Z,1738865644.142 [NAL9602](INFO): SBD MO Status=2, MOMSN=62115, MT Status=2, MTMSN=0 2025-02-06T18:14:04.142Z,1738865644.142 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T18:14:26.358Z,1738865666.358 [NAL9602](INFO): SBD MO Status=2, MOMSN=62115, MT Status=2, MTMSN=0 2025-02-06T18:14:26.358Z,1738865666.358 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T18:14:36.458Z,1738865676.458 [NAL9602](INFO): SBD MO Status=0, MOMSN=62115, MT Status=0, MTMSN=0 2025-02-06T18:14:36.458Z,1738865676.458 [NAL9602](INFO): No messages in MT queue 2025-02-06T18:15:07.161Z,1738865707.161 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T18:18:02.514Z,1738865882.514 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T18:18:02.514Z,1738865882.514 [Default:CheckIn:C.Wait] Stopped 2025-02-06T18:18:02.514Z,1738865882.514 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T18:18:02.515Z,1738865882.515 [Default:CheckIn:D] Running Loop=1 2025-02-06T18:18:02.915Z,1738865882.915 [Default:CheckIn:D] Stopped 2025-02-06T18:18:02.915Z,1738865882.915 [Default:CheckIn:E] Running Loop=1 2025-02-06T18:18:03.332Z,1738865883.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 174.519108 min 2025-02-06T18:18:03.332Z,1738865883.332 [Default:CheckIn:E] Stopped 2025-02-06T18:18:03.332Z,1738865883.332 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T18:18:03.332Z,1738865883.332 [Default:CheckIn] Stopped 2025-02-06T18:18:03.332Z,1738865883.332 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T18:18:03.333Z,1738865883.333 [Default:CheckIn](INFO): Running loop #31 2025-02-06T18:18:03.333Z,1738865883.333 [Default:CheckIn] Running Loop=31 2025-02-06T18:18:03.333Z,1738865883.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T18:18:03.333Z,1738865883.333 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T18:18:05.335Z,1738865885.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181804.00,A,3648.17445,N,12147.28269,W,0.117,262.84,060225,,,A*7A 2025-02-06T18:18:05.338Z,1738865885.338 [NAL9602](INFO): GPS fix at 20250206T181804: (36.802908, -121.788045) 2025-02-06T18:18:05.369Z,1738865885.369 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T18:18:05.369Z,1738865885.369 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T18:18:12.950Z,1738865892.950 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0084.lzma 2025-02-06T18:18:13.950Z,1738865893.950 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0084.lzma.bak 2025-02-06T18:18:13.950Z,1738865893.950 [DataOverHttps](INFO): SBD MOMSN=23960277 2025-02-06T18:18:34.131Z,1738865914.131 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20250206T153719/Express0085.lzma 2025-02-06T18:18:36.138Z,1738865916.138 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0085.lzma.bak 2025-02-06T18:18:36.138Z,1738865916.138 [DataOverHttps](INFO): SBD MOMSN=23960280 2025-02-06T18:18:37.692Z,1738865917.692 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T18:18:37.693Z,1738865917.693 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T18:18:37.693Z,1738865917.693 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T18:21:16.438Z,1738866076.438 [NAL9602](INFO): SBD MO Status=2, MOMSN=62116, MT Status=2, MTMSN=0 2025-02-06T18:21:16.438Z,1738866076.438 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T18:21:52.830Z,1738866112.830 [NAL9602](INFO): SBD MO Status=0, MOMSN=62116, MT Status=0, MTMSN=0 2025-02-06T18:21:52.830Z,1738866112.830 [NAL9602](INFO): No messages in MT queue 2025-02-06T18:21:56.846Z,1738866116.846 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:22:08.562Z,1738866128.562 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:22:20.682Z,1738866140.682 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:22:23.513Z,1738866143.513 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T18:22:32.398Z,1738866152.398 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:22:44.519Z,1738866164.519 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:22:56.638Z,1738866176.638 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:23:08.354Z,1738866188.354 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:23:21.285Z,1738866201.285 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-06T18:23:38.266Z,1738866218.266 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T18:23:38.266Z,1738866218.266 [Default:CheckIn:C.Wait] Stopped 2025-02-06T18:23:38.267Z,1738866218.267 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T18:23:38.267Z,1738866218.267 [Default:CheckIn:D] Running Loop=1 2025-02-06T18:23:38.667Z,1738866218.667 [Default:CheckIn:D] Stopped 2025-02-06T18:23:38.667Z,1738866218.667 [Default:CheckIn:E] Running Loop=1 2025-02-06T18:23:39.080Z,1738866219.080 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 180.114974 min 2025-02-06T18:23:39.080Z,1738866219.080 [Default:CheckIn:E] Stopped 2025-02-06T18:23:39.080Z,1738866219.080 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T18:23:39.080Z,1738866219.080 [Default:CheckIn] Stopped 2025-02-06T18:23:39.080Z,1738866219.080 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T18:23:39.080Z,1738866219.080 [Default:CheckIn](INFO): Running loop #32 2025-02-06T18:23:39.080Z,1738866219.080 [Default:CheckIn] Running Loop=32 2025-02-06T18:23:39.081Z,1738866219.081 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T18:23:39.081Z,1738866219.081 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T18:23:41.082Z,1738866221.082 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182340.00,A,3648.17303,N,12147.28246,W,0.136,262.84,060225,,,A*79 2025-02-06T18:23:41.094Z,1738866221.094 [NAL9602](INFO): GPS fix at 20250206T182340: (36.802884, -121.788041) 2025-02-06T18:23:41.105Z,1738866221.105 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T18:23:41.105Z,1738866221.105 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T18:23:49.959Z,1738866229.959 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250206T153719/Courier0087.lzma 2025-02-06T18:23:50.962Z,1738866230.962 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0087.lzma.bak 2025-02-06T18:23:50.962Z,1738866230.962 [DataOverHttps](INFO): SBD MOMSN=23960286 2025-02-06T18:23:53.203Z,1738866233.203 [NAL9602](INFO): SBD MO Status=0, MOMSN=62117, MT Status=0, MTMSN=0 2025-02-06T18:23:53.203Z,1738866233.203 [NAL9602](INFO): No messages in MT queue 2025-02-06T18:24:10.879Z,1738866250.879 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250206T153719/Express0088.lzma 2025-02-06T18:24:11.882Z,1738866251.882 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0088.lzma.bak 2025-02-06T18:24:11.882Z,1738866251.882 [DataOverHttps](INFO): SBD MOMSN=23960289 2025-02-06T18:24:13.411Z,1738866253.411 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T18:24:13.411Z,1738866253.411 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T18:24:13.412Z,1738866253.412 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T18:24:23.957Z,1738866263.957 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T18:25:48.789Z,1738866348.789 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:26:02.122Z,1738866362.122 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:26:15.454Z,1738866375.454 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:26:29.194Z,1738866389.194 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:26:43.335Z,1738866403.335 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:26:57.150Z,1738866417.150 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:27:10.550Z,1738866430.550 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:27:23.882Z,1738866443.882 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:27:37.214Z,1738866457.214 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:27:50.950Z,1738866470.950 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:28:05.090Z,1738866485.090 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-02-06T18:28:11.977Z,1738866491.977 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 6. 2025-02-06T18:28:11.979Z,1738866491.979 [BPC1](INFO): Received data from all battery sticks. 2025-02-06T18:29:13.890Z,1738866553.890 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T18:29:13.890Z,1738866553.890 [Default:CheckIn:C.Wait] Stopped 2025-02-06T18:29:13.891Z,1738866553.891 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T18:29:13.891Z,1738866553.891 [Default:CheckIn:D] Running Loop=1 2025-02-06T18:29:14.282Z,1738866554.282 [Default:CheckIn:D] Stopped 2025-02-06T18:29:14.282Z,1738866554.282 [Default:CheckIn:E] Running Loop=1 2025-02-06T18:29:14.684Z,1738866554.684 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 185.708561 min 2025-02-06T18:29:14.684Z,1738866554.684 [Default:CheckIn:E] Stopped 2025-02-06T18:29:14.684Z,1738866554.684 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T18:29:14.684Z,1738866554.684 [Default:CheckIn] Stopped 2025-02-06T18:29:14.684Z,1738866554.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T18:29:14.684Z,1738866554.684 [Default:CheckIn](INFO): Running loop #33 2025-02-06T18:29:14.684Z,1738866554.684 [Default:CheckIn] Running Loop=33 2025-02-06T18:29:14.685Z,1738866554.685 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T18:29:14.685Z,1738866554.685 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T18:29:16.694Z,1738866556.694 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182915.00,A,3648.17678,N,12147.27507,W,0.233,16.36,060225,,,A*49 2025-02-06T18:29:16.706Z,1738866556.706 [NAL9602](INFO): GPS fix at 20250206T182915: (36.802946, -121.787918) 2025-02-06T18:29:16.716Z,1738866556.716 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T18:29:16.716Z,1738866556.716 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T18:29:36.767Z,1738866576.767 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250206T153719/Courier0090.lzma 2025-02-06T18:29:37.770Z,1738866577.770 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0090.lzma.bak 2025-02-06T18:29:37.770Z,1738866577.770 [DataOverHttps](INFO): SBD MOMSN=23960316 2025-02-06T18:29:38.535Z,1738866578.535 [NAL9602](INFO): SBD MO Status=0, MOMSN=62118, MT Status=0, MTMSN=0 2025-02-06T18:29:38.536Z,1738866578.536 [NAL9602](INFO): No messages in MT queue 2025-02-06T18:30:00.411Z,1738866600.411 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250206T153719/Express0091.lzma 2025-02-06T18:30:01.414Z,1738866601.414 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0091.lzma.bak 2025-02-06T18:30:01.414Z,1738866601.414 [DataOverHttps](INFO): SBD MOMSN=23960323 2025-02-06T18:30:03.183Z,1738866603.183 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T18:30:03.200Z,1738866603.200 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T18:30:03.200Z,1738866603.200 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T18:30:09.240Z,1738866609.240 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T18:35:03.794Z,1738866903.794 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T18:35:03.794Z,1738866903.794 [Default:CheckIn:C.Wait] Stopped 2025-02-06T18:35:03.794Z,1738866903.794 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T18:35:03.795Z,1738866903.795 [Default:CheckIn:D] Running Loop=1 2025-02-06T18:35:04.194Z,1738866904.194 [Default:CheckIn:D] Stopped 2025-02-06T18:35:04.194Z,1738866904.194 [Default:CheckIn:E] Running Loop=1 2025-02-06T18:35:04.599Z,1738866904.599 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 191.540430 min 2025-02-06T18:35:04.599Z,1738866904.599 [Default:CheckIn:E] Stopped 2025-02-06T18:35:04.599Z,1738866904.599 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T18:35:04.599Z,1738866904.599 [Default:CheckIn] Stopped 2025-02-06T18:35:04.599Z,1738866904.599 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T18:35:04.599Z,1738866904.599 [Default:CheckIn](INFO): Running loop #34 2025-02-06T18:35:04.599Z,1738866904.599 [Default:CheckIn] Running Loop=34 2025-02-06T18:35:04.599Z,1738866904.599 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T18:35:04.600Z,1738866904.600 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T18:35:06.610Z,1738866906.610 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183505.00,A,3648.16688,N,12147.28062,W,0.078,15.26,060225,,,A*4D 2025-02-06T18:35:06.617Z,1738866906.617 [NAL9602](INFO): GPS fix at 20250206T183505: (36.802781, -121.788010) 2025-02-06T18:35:06.628Z,1738866906.628 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T18:35:06.628Z,1738866906.628 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T18:35:14.236Z,1738866914.236 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T153719/Courier0093.lzma 2025-02-06T18:35:15.234Z,1738866915.234 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0093.lzma.bak 2025-02-06T18:35:15.234Z,1738866915.234 [DataOverHttps](INFO): SBD MOMSN=23960328 2025-02-06T18:35:31.191Z,1738866931.191 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250206T153719/Express0094.lzma 2025-02-06T18:35:32.194Z,1738866932.194 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0094.lzma.bak 2025-02-06T18:35:32.194Z,1738866932.194 [DataOverHttps](INFO): SBD MOMSN=23960331 2025-02-06T18:35:33.688Z,1738866933.688 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T18:35:33.688Z,1738866933.688 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T18:35:33.688Z,1738866933.688 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T18:37:16.309Z,1738867036.309 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-02-06T18:37:16.309Z,1738867036.309 [DropWeight] Hardware Fault, FailCount= 1 2025-02-06T18:37:16.309Z,1738867036.309 [DropWeight](ERROR): Hardware Fault 2025-02-06T18:37:16.340Z,1738867036.340 [CommandExec](FAULT): Scheduling is paused 2025-02-06T18:37:16.340Z,1738867036.340 [CBIT](INFO): Critical error at 20250206T183716 2025-02-06T18:37:16.343Z,1738867036.343 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-02-06T18:37:16.371Z,1738867036.371 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-02-06T18:37:16.736Z,1738867036.736 [CBIT](INFO): Critical error at 20250206T183716 2025-02-06T18:40:08.417Z,1738867208.417 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-02-06T18:40:34.287Z,1738867234.287 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-06T18:40:34.288Z,1738867234.288 [Default:CheckIn:C.Wait] Stopped 2025-02-06T18:40:34.288Z,1738867234.288 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T18:40:34.288Z,1738867234.288 [Default:CheckIn:D] Running Loop=1 2025-02-06T18:40:34.682Z,1738867234.682 [Default:CheckIn:D] Stopped 2025-02-06T18:40:34.682Z,1738867234.682 [Default:CheckIn:E] Running Loop=1 2025-02-06T18:40:35.090Z,1738867235.090 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 197.048551 min 2025-02-06T18:40:35.090Z,1738867235.090 [Default:CheckIn:E] Stopped 2025-02-06T18:40:35.091Z,1738867235.091 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-06T18:40:35.091Z,1738867235.091 [Default:CheckIn] Stopped 2025-02-06T18:40:35.091Z,1738867235.091 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T18:40:35.091Z,1738867235.091 [Default:CheckIn](INFO): Running loop #35 2025-02-06T18:40:35.091Z,1738867235.091 [Default:CheckIn] Running Loop=35 2025-02-06T18:40:35.091Z,1738867235.091 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T18:40:35.091Z,1738867235.091 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T18:40:37.098Z,1738867237.098 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184036.00,A,3648.07993,N,12147.31123,W,6.356,209.74,060225,,,A*77 2025-02-06T18:40:37.100Z,1738867237.100 [NAL9602](INFO): GPS fix at 20250206T184036: (36.801332, -121.788521) 2025-02-06T18:40:37.111Z,1738867237.111 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T18:40:37.112Z,1738867237.112 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T18:40:44.807Z,1738867244.807 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20250206T153719/Courier0096.lzma 2025-02-06T18:40:45.810Z,1738867245.810 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Courier0096.lzma.bak 2025-02-06T18:40:45.810Z,1738867245.810 [DataOverHttps](INFO): SBD MOMSN=23960347 2025-02-06T18:41:04.339Z,1738867264.339 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20250206T153719/Express0097.lzma 2025-02-06T18:41:05.342Z,1738867265.342 [DataOverHttps](INFO): Moved sent file to Logs/20250206T153719/Express0097.lzma.bak 2025-02-06T18:41:05.342Z,1738867265.342 [DataOverHttps](INFO): SBD MOMSN=23960374 2025-02-06T18:41:06.672Z,1738867266.672 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T18:41:06.672Z,1738867266.672 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T18:41:06.672Z,1738867266.672 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T18:41:09.480Z,1738867269.480 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T18:44:08.596Z,1738867448.596 [CommandExec](IMPORTANT): got command quit 2025-02-06T18:44:09.600Z,1738867449.600 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T18:44:09.600Z,1738867449.600 [CommandExec](INFO): Uninitializing the command executive. 2025-02-06T18:44:09.600Z,1738867449.600 [CommandExec](INFO): Uninitializing the command scheduler. 2025-02-06T18:44:09.600Z,1738867449.600 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:09.611Z,1738867449.611 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-02-06T18:44:09.612Z,1738867449.612 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-02-06T18:44:09.612Z,1738867449.612 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:09.612Z,1738867449.612 [NavChartDb](INFO): Join timeout helper Thread ID is 3269 2025-02-06T18:44:09.864Z,1738867449.864 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T18:44:09.864Z,1738867449.864 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:09.888Z,1738867449.888 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-02-06T18:44:09.888Z,1738867449.888 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:09.888Z,1738867449.888 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3270 2025-02-06T18:44:10.392Z,1738867450.392 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T18:44:10.392Z,1738867450.392 [WetLabsBB2FL](INFO): Powering down 2025-02-06T18:44:10.393Z,1738867450.393 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:10.396Z,1738867450.396 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-02-06T18:44:10.396Z,1738867450.396 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:10.396Z,1738867450.396 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3271 2025-02-06T18:44:11.384Z,1738867451.384 [CTD_Seabird](INFO): Powering down 2025-02-06T18:44:11.396Z,1738867451.396 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T18:44:11.396Z,1738867451.396 [CTD_Seabird](INFO): Powering down 2025-02-06T18:44:11.408Z,1738867451.408 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:11.424Z,1738867451.424 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-02-06T18:44:11.424Z,1738867451.424 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:11.424Z,1738867451.424 [Radio_Surface](INFO): Join timeout helper Thread ID is 3272 2025-02-06T18:44:11.812Z,1738867451.812 [Radio_Surface](INFO): Powering down 2025-02-06T18:44:11.813Z,1738867451.813 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T18:44:11.813Z,1738867451.813 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:11.824Z,1738867451.824 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-02-06T18:44:11.824Z,1738867451.824 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:11.824Z,1738867451.824 [Onboard](INFO): Join timeout helper Thread ID is 3273 2025-02-06T18:44:14.732Z,1738867454.732 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T18:44:14.732Z,1738867454.732 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:14.744Z,1738867454.744 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-02-06T18:44:14.744Z,1738867454.744 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:14.745Z,1738867454.745 [DataOverHttps](INFO): Join timeout helper Thread ID is 3274 2025-02-06T18:44:15.268Z,1738867455.268 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T18:44:15.270Z,1738867455.270 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:15.276Z,1738867455.276 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-02-06T18:44:15.276Z,1738867455.276 [logger ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:15.277Z,1738867455.277 [logger](INFO): Join timeout helper Thread ID is 3275 2025-02-06T18:44:15.300Z,1738867455.300 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T18:44:15.300Z,1738867455.300 [logger ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:15.316Z,1738867455.316 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-02-06T18:44:15.316Z,1738867455.316 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:15.317Z,1738867455.317 [CommandLine](INFO): Join timeout helper Thread ID is 3276 2025-02-06T18:44:15.396Z,1738867455.396 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T18:44:15.396Z,1738867455.396 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:15.404Z,1738867455.404 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-02-06T18:44:15.404Z,1738867455.404 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:15.405Z,1738867455.405 [CommandExec](INFO): Join timeout helper Thread ID is 3277 2025-02-06T18:44:15.406Z,1738867455.406 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-02-06T18:44:15.406Z,1738867455.406 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:15.406Z,1738867455.406 [controlThread](INFO): Join timeout helper Thread ID is 3278 2025-02-06T18:44:15.708Z,1738867455.708 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T18:44:15.708Z,1738867455.708 [controlThread](DEBUG): Uninitializing ControlThread 2025-02-06T18:44:15.708Z,1738867455.708 [AHRS_M2](INFO): Powering down 2025-02-06T18:44:15.781Z,1738867455.781 [NAL9602](INFO): Powering down 2025-02-06T18:44:15.782Z,1738867455.782 [Sonardyne_Nano](INFO): Powering down 2025-02-06T18:44:15.901Z,1738867455.901 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-02-06T18:44:15.902Z,1738867455.902 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-02-06T18:44:15.902Z,1738867455.902 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-02-06T18:44:15.903Z,1738867455.903 [MissionManager](INFO): Uninitializing Mission Default 2025-02-06T18:44:15.903Z,1738867455.903 [Default] Stopped 2025-02-06T18:44:15.903Z,1738867455.903 [Default](DEBUG): Aggregate::uninitialize Default 2025-02-06T18:44:15.903Z,1738867455.903 [Default:B.GoToSurface] Stopped 2025-02-06T18:44:15.903Z,1738867455.903 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-06T18:44:15.903Z,1738867455.903 [Default:CheckIn] Stopped 2025-02-06T18:44:15.903Z,1738867455.903 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T18:44:15.903Z,1738867455.903 [Default:CheckIn:C.Wait] Stopped 2025-02-06T18:44:15.904Z,1738867455.904 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T18:44:15.906Z,1738867455.906 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-02-06T18:44:15.907Z,1738867455.907 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-02-06T18:44:15.907Z,1738867455.907 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-02-06T18:44:15.907Z,1738867455.907 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-02-06T18:44:15.908Z,1738867455.908 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-02-06T18:44:15.908Z,1738867455.908 [BuoyancyServo](INFO): Powering down 2025-02-06T18:44:15.920Z,1738867455.920 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-02-06T18:44:15.920Z,1738867455.920 [ElevatorServo](INFO): Powering down 2025-02-06T18:44:15.920Z,1738867455.920 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-02-06T18:44:15.921Z,1738867455.921 [MassServo](INFO): Powering down 2025-02-06T18:44:15.921Z,1738867455.921 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-02-06T18:44:15.921Z,1738867455.921 [RudderServo](INFO): Powering down 2025-02-06T18:44:15.922Z,1738867455.922 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-02-06T18:44:15.922Z,1738867455.922 [ThrusterHE](INFO): Powering down 2025-02-06T18:44:15.923Z,1738867455.923 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-02-06T18:44:15.924Z,1738867455.924 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-02-06T18:44:15.924Z,1738867455.924 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-02-06T18:44:15.924Z,1738867455.924 [CBIT](DEBUG): Powering off loads. 2025-02-06T18:44:15.935Z,1738867455.935 [CBIT](DEBUG): Disabling WDT. 2025-02-06T18:44:15.947Z,1738867455.947 [CBIT](DEBUG): Opening all GF detection circuits. 2025-02-06T18:44:15.948Z,1738867455.948 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:16.009Z,1738867456.009 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:16.012Z,1738867456.012 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:16.018Z,1738867456.018 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:16.090Z,1738867456.090 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:16.095Z,1738867456.095 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:16.137Z,1738867456.137 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-02-06T18:44:16.242Z,1738867456.242 [logger ThreadHandler](INFO): Thread cancelled.