2024-05-09T17:20:31.367Z,1715275231.367 [CommandExec](IMPORTANT): got command restart logs 2024-05-09T17:20:32.646Z,1715275232.646 [DataOverHttps](INFO): Moved sent file to Logs/20240509T041806/Courier0106.lzma.bak 2024-05-09T17:20:32.646Z,1715275232.646 [DataOverHttps](INFO): SBD MOMSN=20998225 2024-05-09T17:20:51.385Z,1715275251.385 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240509T041806/Courier0109.lzma 2024-05-09T17:20:52.388Z,1715275252.388 [DataOverHttps](INFO): Moved sent file to Logs/20240509T041806/Courier0109.lzma.bak 2024-05-09T17:20:52.388Z,1715275252.388 [DataOverHttps](INFO): SBD MOMSN=20998233 2024-05-09T17:21:00.751Z,1715275260.751 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T17:21:09.169Z,1715275269.169 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20240509T041806/Express0107.lzma 2024-05-09T17:21:10.172Z,1715275270.172 [DataOverHttps](INFO): Moved sent file to Logs/20240509T041806/Express0107.lzma.bak 2024-05-09T17:21:10.172Z,1715275270.172 [DataOverHttps](INFO): SBD MOMSN=20998235 2024-05-09T17:21:27.073Z,1715275287.073 [DataOverHttps](INFO): Sending 60 bytes from file Logs/20240509T041806/Express0110.lzma 2024-05-09T17:21:28.076Z,1715275288.076 [DataOverHttps](INFO): Moved sent file to Logs/20240509T041806/Express0110.lzma.bak 2024-05-09T17:21:28.076Z,1715275288.076 [DataOverHttps](INFO): SBD MOMSN=20998239 2024-05-09T17:21:29.465Z,1715275289.465 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T17:21:29.466Z,1715275289.466 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T17:21:29.466Z,1715275289.466 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T17:23:56.780Z,1715275436.780 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=61.985382 2024-05-09T17:24:34.338Z,1715275474.338 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003109 2024-05-09T17:26:30.089Z,1715275590.089 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T17:26:30.090Z,1715275590.090 [Default:CheckIn:C.Wait] Stopped 2024-05-09T17:26:30.090Z,1715275590.090 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T17:26:30.090Z,1715275590.090 [Default:CheckIn:D] Running Loop=1 2024-05-09T17:26:30.483Z,1715275590.483 [Default:CheckIn:D] Stopped 2024-05-09T17:26:30.483Z,1715275590.483 [Default:CheckIn:E] Running Loop=1 2024-05-09T17:26:30.891Z,1715275590.891 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.611094 min 2024-05-09T17:26:30.891Z,1715275590.891 [Default:CheckIn:E] Stopped 2024-05-09T17:26:30.891Z,1715275590.891 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T17:26:30.891Z,1715275590.891 [Default:CheckIn] Stopped 2024-05-09T17:26:30.891Z,1715275590.891 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T17:26:30.891Z,1715275590.891 [Default:CheckIn](INFO): Running loop #6 2024-05-09T17:26:30.891Z,1715275590.891 [Default:CheckIn] Running Loop=6 2024-05-09T17:26:30.891Z,1715275590.891 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T17:26:30.892Z,1715275590.892 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T17:26:32.898Z,1715275592.898 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172632.00,A,3648.17431,N,12150.66140,W,0.622,309.90,090524,,,D*7E 2024-05-09T17:26:32.900Z,1715275592.900 [NAL9602](INFO): GPS fix at 20240509T172632: (36.802905, -121.844357) 2024-05-09T17:26:32.913Z,1715275592.913 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T17:26:32.913Z,1715275592.913 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T17:26:40.845Z,1715275600.845 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240509T172031/Courier0003.lzma 2024-05-09T17:26:41.847Z,1715275601.847 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0003.lzma.bak 2024-05-09T17:26:41.847Z,1715275601.847 [DataOverHttps](INFO): SBD MOMSN=20998262 2024-05-09T17:26:52.300Z,1715275612.300 [NAL9602](INFO): SBD MO Status=0, MOMSN=17419, MT Status=0, MTMSN=0 2024-05-09T17:26:52.300Z,1715275612.300 [NAL9602](INFO): No messages in MT queue 2024-05-09T17:27:09.166Z,1715275629.166 [DataOverHttps](INFO): Sending 382 bytes from file Logs/20240509T172031/Express0004.lzma 2024-05-09T17:27:10.167Z,1715275630.167 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0004.lzma.bak 2024-05-09T17:27:10.167Z,1715275630.167 [DataOverHttps](INFO): SBD MOMSN=20998266 2024-05-09T17:27:11.692Z,1715275631.692 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T17:27:11.692Z,1715275631.692 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T17:27:11.692Z,1715275631.692 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T17:27:23.011Z,1715275643.011 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T17:32:12.279Z,1715275932.279 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T17:32:12.279Z,1715275932.279 [Default:CheckIn:C.Wait] Stopped 2024-05-09T17:32:12.279Z,1715275932.279 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T17:32:12.280Z,1715275932.280 [Default:CheckIn:D] Running Loop=1 2024-05-09T17:32:12.677Z,1715275932.677 [Default:CheckIn:D] Stopped 2024-05-09T17:32:12.677Z,1715275932.677 [Default:CheckIn:E] Running Loop=1 2024-05-09T17:32:13.094Z,1715275933.094 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.314323 min 2024-05-09T17:32:13.094Z,1715275933.094 [Default:CheckIn:E] Stopped 2024-05-09T17:32:13.094Z,1715275933.094 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T17:32:13.094Z,1715275933.094 [Default:CheckIn] Stopped 2024-05-09T17:32:13.094Z,1715275933.094 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T17:32:13.094Z,1715275933.094 [Default:CheckIn](INFO): Running loop #7 2024-05-09T17:32:13.094Z,1715275933.094 [Default:CheckIn] Running Loop=7 2024-05-09T17:32:13.094Z,1715275933.094 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T17:32:13.094Z,1715275933.094 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T17:32:15.085Z,1715275935.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173214.00,A,3648.22723,N,12150.64745,W,1.011,57.27,090524,,,D*4B 2024-05-09T17:32:15.087Z,1715275935.087 [NAL9602](INFO): GPS fix at 20240509T173214: (36.803787, -121.844124) 2024-05-09T17:32:15.099Z,1715275935.099 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T17:32:15.099Z,1715275935.099 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T17:32:34.106Z,1715275954.106 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251530 2024-05-09T17:33:05.230Z,1715275985.230 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:33:40.378Z,1715276020.378 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:34:12.252Z,1715276052.252 [NAL9602](INFO): SBD MO Status=0, MOMSN=17420, MT Status=0, MTMSN=0 2024-05-09T17:34:12.253Z,1715276052.253 [NAL9602](INFO): No messages in MT queue 2024-05-09T17:34:15.518Z,1715276055.518 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:34:37.379Z,1715276077.379 [NAL9602](INFO): SBD MO Status=1, MOMSN=17421, MT Status=0, MTMSN=0 2024-05-09T17:34:37.430Z,1715276077.430 [NAL9602](INFO): Sent 73 bytes from file Logs/20240509T172031/Courier0006.lzma 2024-05-09T17:34:37.430Z,1715276077.430 [NAL9602](INFO): Packets left to send: 0 2024-05-09T17:34:50.658Z,1715276090.658 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:34:53.568Z,1715276093.568 [NAL9602](INFO): SBD MO Status=2, MOMSN=17422, MT Status=2, MTMSN=0 2024-05-09T17:34:53.568Z,1715276093.568 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T17:35:25.798Z,1715276125.798 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:35:28.374Z,1715276128.374 [NAL9602](INFO): SBD MO Status=2, MOMSN=17422, MT Status=2, MTMSN=0 2024-05-09T17:35:28.374Z,1715276128.374 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T17:35:58.765Z,1715276158.765 [NAL9602](INFO): SBD MO Status=1, MOMSN=17422, MT Status=0, MTMSN=0 2024-05-09T17:35:58.826Z,1715276158.826 [NAL9602](INFO): Sent 131 bytes from file Logs/20240509T172031/Express0007.lzma 2024-05-09T17:35:58.826Z,1715276158.826 [NAL9602](INFO): Packets left to send: 0 2024-05-09T17:36:00.938Z,1715276160.938 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:36:09.268Z,1715276169.268 [NAL9602](INFO): SBD MO Status=0, MOMSN=17423, MT Status=0, MTMSN=0 2024-05-09T17:36:09.353Z,1715276169.353 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T17:36:09.353Z,1715276169.353 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T17:36:09.353Z,1715276169.353 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T17:36:36.078Z,1715276196.078 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:36:39.971Z,1715276199.971 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T17:37:11.226Z,1715276231.226 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:37:46.386Z,1715276266.386 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:38:21.538Z,1715276301.538 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:38:57.083Z,1715276337.083 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004118 2024-05-09T17:41:09.864Z,1715276469.864 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T17:41:09.865Z,1715276469.865 [Default:CheckIn:C.Wait] Stopped 2024-05-09T17:41:09.865Z,1715276469.865 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T17:41:09.866Z,1715276469.866 [Default:CheckIn:D] Running Loop=1 2024-05-09T17:41:10.259Z,1715276470.259 [Default:CheckIn:D] Stopped 2024-05-09T17:41:10.259Z,1715276470.259 [Default:CheckIn:E] Running Loop=1 2024-05-09T17:41:10.672Z,1715276470.672 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.274028 min 2024-05-09T17:41:10.672Z,1715276470.672 [Default:CheckIn:E] Stopped 2024-05-09T17:41:10.672Z,1715276470.672 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T17:41:10.672Z,1715276470.672 [Default:CheckIn] Stopped 2024-05-09T17:41:10.672Z,1715276470.672 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T17:41:10.672Z,1715276470.672 [Default:CheckIn](INFO): Running loop #8 2024-05-09T17:41:10.672Z,1715276470.672 [Default:CheckIn] Running Loop=8 2024-05-09T17:41:10.672Z,1715276470.672 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T17:41:10.673Z,1715276470.673 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T17:41:12.699Z,1715276472.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174111.00,A,3648.47491,N,12149.00935,W,1.186,344.29,090524,,,A*75 2024-05-09T17:41:12.702Z,1715276472.702 [NAL9602](INFO): GPS fix at 20240509T174111: (36.807915, -121.816823) 2024-05-09T17:41:12.714Z,1715276472.714 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T17:41:12.714Z,1715276472.714 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T17:41:20.097Z,1715276480.097 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240509T172031/Courier0009.lzma 2024-05-09T17:41:21.099Z,1715276481.099 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0009.lzma.bak 2024-05-09T17:41:21.099Z,1715276481.099 [DataOverHttps](INFO): SBD MOMSN=20998354 2024-05-09T17:41:41.360Z,1715276501.360 [NAL9602](INFO): SBD MO Status=2, MOMSN=17424, MT Status=2, MTMSN=0 2024-05-09T17:41:41.360Z,1715276501.360 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T17:42:00.650Z,1715276520.650 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:42:02.372Z,1715276522.372 [NAL9602](INFO): SBD MO Status=0, MOMSN=17424, MT Status=0, MTMSN=0 2024-05-09T17:42:02.372Z,1715276522.372 [NAL9602](INFO): No messages in MT queue 2024-05-09T17:42:10.121Z,1715276530.121 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240509T172031/Express0010.lzma 2024-05-09T17:42:11.123Z,1715276531.123 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0010.lzma.bak 2024-05-09T17:42:11.123Z,1715276531.123 [DataOverHttps](INFO): SBD MOMSN=20998369 2024-05-09T17:42:12.510Z,1715276532.510 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T17:42:12.511Z,1715276532.511 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T17:42:12.511Z,1715276532.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T17:42:31.452Z,1715276551.452 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2024-05-09T17:42:33.071Z,1715276553.071 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T17:43:18.218Z,1715276598.218 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:43:20.226Z,1715276600.226 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247372 2024-05-09T17:43:40.129Z,1715276620.129 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5. 2024-05-09T17:43:40.131Z,1715276620.131 [BPC1](FAULT): Failed to receive data from 2 sticks prior to timeout. Missing stick IDs are: 42, 44. 2024-05-09T17:43:40.132Z,1715276620.132 [BPC1] Data Fault, FailCount= 1 2024-05-09T17:43:40.132Z,1715276620.132 [BPC1](ERROR): Data Fault 2024-05-09T17:43:40.192Z,1715276620.192 [CBIT](ERROR): Data Fault in component: BPC1 2024-05-09T17:43:53.358Z,1715276633.358 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:44:28.499Z,1715276668.499 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:44:46.990Z,1715276686.990 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003125 2024-05-09T17:45:39.214Z,1715276739.214 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:45:47.246Z,1715276747.246 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259357 2024-05-09T17:46:49.518Z,1715276809.518 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:47:13.059Z,1715276833.059 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T17:47:13.059Z,1715276833.059 [Default:CheckIn:C.Wait] Stopped 2024-05-09T17:47:13.059Z,1715276833.059 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T17:47:13.059Z,1715276833.059 [Default:CheckIn:D] Running Loop=1 2024-05-09T17:47:13.464Z,1715276833.464 [Default:CheckIn:D] Stopped 2024-05-09T17:47:13.464Z,1715276833.464 [Default:CheckIn:E] Running Loop=1 2024-05-09T17:47:13.880Z,1715276833.880 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.327445 min 2024-05-09T17:47:13.880Z,1715276833.880 [Default:CheckIn:E] Stopped 2024-05-09T17:47:13.880Z,1715276833.880 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T17:47:13.880Z,1715276833.880 [Default:CheckIn] Stopped 2024-05-09T17:47:13.880Z,1715276833.880 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T17:47:13.880Z,1715276833.880 [Default:CheckIn](INFO): Running loop #9 2024-05-09T17:47:13.880Z,1715276833.880 [Default:CheckIn] Running Loop=9 2024-05-09T17:47:13.880Z,1715276833.880 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T17:47:13.880Z,1715276833.880 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T17:47:15.868Z,1715276835.868 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174714.00,A,3648.32006,N,12147.60062,W,13.724,85.68,090524,,,D*7E 2024-05-09T17:47:15.871Z,1715276835.871 [NAL9602](INFO): GPS fix at 20240509T174714: (36.805334, -121.793344) 2024-05-09T17:47:15.883Z,1715276835.883 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T17:47:15.883Z,1715276835.883 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T17:47:24.658Z,1715276844.658 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-09T17:47:39.764Z,1715276859.764 [NAL9602](INFO): SBD MO Status=1, MOMSN=17425, MT Status=0, MTMSN=0 2024-05-09T17:47:39.814Z,1715276859.814 [NAL9602](INFO): Sent 202 bytes from file Logs/20240509T172031/Courier0012.lzma 2024-05-09T17:47:39.814Z,1715276859.814 [NAL9602](INFO): Packets left to send: 0 2024-05-09T17:47:47.572Z,1715276867.572 [NAL9602](INFO): SBD MO Status=1, MOMSN=17426, MT Status=0, MTMSN=0 2024-05-09T17:47:47.626Z,1715276867.626 [NAL9602](INFO): Sent 241 bytes from file Logs/20240509T172031/Express0013.lzma 2024-05-09T17:47:47.626Z,1715276867.626 [NAL9602](INFO): Packets left to send: 0 2024-05-09T17:47:48.163Z,1715276868.163 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004121 2024-05-09T17:47:49.626Z,1715276869.626 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T17:47:49.627Z,1715276869.627 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T17:47:49.627Z,1715276869.627 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T17:48:04.548Z,1715276884.548 [NAL9602](INFO): SBD MO Status=0, MOMSN=17427, MT Status=0, MTMSN=0 2024-05-09T17:48:35.254Z,1715276915.254 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T17:52:50.187Z,1715277170.187 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T17:52:50.188Z,1715277170.188 [Default:CheckIn:C.Wait] Stopped 2024-05-09T17:52:50.188Z,1715277170.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T17:52:50.188Z,1715277170.188 [Default:CheckIn:D] Running Loop=1 2024-05-09T17:52:50.592Z,1715277170.592 [Default:CheckIn:D] Stopped 2024-05-09T17:52:50.592Z,1715277170.592 [Default:CheckIn:E] Running Loop=1 2024-05-09T17:52:51.006Z,1715277171.006 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.946244 min 2024-05-09T17:52:51.007Z,1715277171.007 [Default:CheckIn:E] Stopped 2024-05-09T17:52:51.007Z,1715277171.007 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T17:52:51.007Z,1715277171.007 [Default:CheckIn] Stopped 2024-05-09T17:52:51.007Z,1715277171.007 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T17:52:51.007Z,1715277171.007 [Default:CheckIn](INFO): Running loop #10 2024-05-09T17:52:51.007Z,1715277171.007 [Default:CheckIn] Running Loop=10 2024-05-09T17:52:51.007Z,1715277171.007 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T17:52:51.007Z,1715277171.007 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T17:52:53.008Z,1715277173.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175252.00,A,3648.40618,N,12147.12146,W,4.257,178.30,090524,,,D*7F 2024-05-09T17:52:53.010Z,1715277173.010 [NAL9602](INFO): GPS fix at 20240509T175252: (36.806770, -121.785358) 2024-05-09T17:52:53.040Z,1715277173.040 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T17:52:53.040Z,1715277173.040 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T17:52:59.969Z,1715277179.969 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240509T172031/Courier0015.lzma 2024-05-09T17:53:00.971Z,1715277180.971 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0015.lzma.bak 2024-05-09T17:53:00.971Z,1715277180.971 [DataOverHttps](INFO): SBD MOMSN=20998427 2024-05-09T17:53:12.404Z,1715277192.404 [NAL9602](INFO): SBD MO Status=0, MOMSN=17428, MT Status=0, MTMSN=0 2024-05-09T17:53:12.404Z,1715277192.404 [NAL9602](INFO): No messages in MT queue 2024-05-09T17:53:16.849Z,1715277196.849 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20240509T172031/Express0016.lzma 2024-05-09T17:53:17.852Z,1715277197.852 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0016.lzma.bak 2024-05-09T17:53:17.852Z,1715277197.852 [DataOverHttps](INFO): SBD MOMSN=20998433 2024-05-09T17:53:19.281Z,1715277199.281 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T17:53:19.281Z,1715277199.281 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T17:53:19.281Z,1715277199.281 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T17:53:41.096Z,1715277221.096 [CBIT](INFO): Clearing failed state for component BPC1 2024-05-09T17:53:41.096Z,1715277221.096 [BPC1] No Fault, FailCount= 1 2024-05-09T17:53:43.100Z,1715277223.100 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T17:58:19.848Z,1715277499.848 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T17:58:19.848Z,1715277499.848 [Default:CheckIn:C.Wait] Stopped 2024-05-09T17:58:19.848Z,1715277499.848 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T17:58:19.849Z,1715277499.849 [Default:CheckIn:D] Running Loop=1 2024-05-09T17:58:20.259Z,1715277500.259 [Default:CheckIn:D] Stopped 2024-05-09T17:58:20.259Z,1715277500.259 [Default:CheckIn:E] Running Loop=1 2024-05-09T17:58:20.662Z,1715277500.662 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.440666 min 2024-05-09T17:58:20.662Z,1715277500.662 [Default:CheckIn:E] Stopped 2024-05-09T17:58:20.662Z,1715277500.662 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T17:58:20.662Z,1715277500.662 [Default:CheckIn] Stopped 2024-05-09T17:58:20.662Z,1715277500.662 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T17:58:20.662Z,1715277500.662 [Default:CheckIn](INFO): Running loop #11 2024-05-09T17:58:20.662Z,1715277500.662 [Default:CheckIn] Running Loop=11 2024-05-09T17:58:20.662Z,1715277500.662 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T17:58:20.662Z,1715277500.662 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T17:58:22.674Z,1715277502.674 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175821.00,A,3648.15772,N,12147.21885,W,0.603,218.96,090524,,,D*72 2024-05-09T17:58:22.676Z,1715277502.676 [NAL9602](INFO): GPS fix at 20240509T175821: (36.802629, -121.786981) 2024-05-09T17:58:22.689Z,1715277502.689 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T17:58:22.689Z,1715277502.689 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T17:58:30.493Z,1715277510.493 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240509T172031/Courier0018.lzma 2024-05-09T17:58:31.495Z,1715277511.495 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0018.lzma.bak 2024-05-09T17:58:31.496Z,1715277511.496 [DataOverHttps](INFO): SBD MOMSN=20998457 2024-05-09T17:58:47.413Z,1715277527.413 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240509T172031/Express0019.lzma 2024-05-09T17:58:48.415Z,1715277528.415 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0019.lzma.bak 2024-05-09T17:58:48.415Z,1715277528.415 [DataOverHttps](INFO): SBD MOMSN=20998463 2024-05-09T17:58:49.760Z,1715277529.760 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T17:58:49.760Z,1715277529.760 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T17:58:49.760Z,1715277529.760 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T17:58:59.440Z,1715277539.440 [NAL9602](INFO): SBD MO Status=2, MOMSN=17429, MT Status=2, MTMSN=0 2024-05-09T17:58:59.440Z,1715277539.440 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T17:59:06.708Z,1715277546.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=17429, MT Status=2, MTMSN=0 2024-05-09T17:59:06.708Z,1715277546.708 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T17:59:18.014Z,1715277558.014 [NAL9602](INFO): SBD MO Status=0, MOMSN=17429, MT Status=0, MTMSN=0 2024-05-09T17:59:18.014Z,1715277558.014 [NAL9602](INFO): No messages in MT queue 2024-05-09T17:59:48.726Z,1715277588.726 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:03:50.341Z,1715277830.341 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:03:50.341Z,1715277830.341 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:03:50.341Z,1715277830.341 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:03:50.341Z,1715277830.341 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:03:50.739Z,1715277830.739 [Default:CheckIn:D] Stopped 2024-05-09T18:03:50.739Z,1715277830.739 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:03:51.142Z,1715277831.142 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.948702 min 2024-05-09T18:03:51.143Z,1715277831.143 [Default:CheckIn:E] Stopped 2024-05-09T18:03:51.143Z,1715277831.143 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:03:51.143Z,1715277831.143 [Default:CheckIn] Stopped 2024-05-09T18:03:51.143Z,1715277831.143 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:03:51.143Z,1715277831.143 [Default:CheckIn](INFO): Running loop #12 2024-05-09T18:03:51.143Z,1715277831.143 [Default:CheckIn] Running Loop=12 2024-05-09T18:03:51.143Z,1715277831.143 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:03:51.143Z,1715277831.143 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:03:53.155Z,1715277833.155 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180352.00,A,3648.14968,N,12147.22310,W,0.019,218.96,090524,,,D*7A 2024-05-09T18:03:53.158Z,1715277833.158 [NAL9602](INFO): GPS fix at 20240509T180352: (36.802495, -121.787052) 2024-05-09T18:03:53.171Z,1715277833.171 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:03:53.171Z,1715277833.171 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:04:00.941Z,1715277840.941 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240509T172031/Courier0021.lzma 2024-05-09T18:04:01.943Z,1715277841.943 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0021.lzma.bak 2024-05-09T18:04:01.943Z,1715277841.943 [DataOverHttps](INFO): SBD MOMSN=20998488 2024-05-09T18:04:09.712Z,1715277849.712 [NAL9602](INFO): SBD MO Status=2, MOMSN=17430, MT Status=2, MTMSN=0 2024-05-09T18:04:09.712Z,1715277849.712 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T18:04:18.149Z,1715277858.149 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240509T172031/Express0022.lzma 2024-05-09T18:04:19.151Z,1715277859.151 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0022.lzma.bak 2024-05-09T18:04:19.151Z,1715277859.151 [DataOverHttps](INFO): SBD MOMSN=20998494 2024-05-09T18:04:20.671Z,1715277860.671 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:04:20.671Z,1715277860.671 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:04:20.671Z,1715277860.671 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:04:35.593Z,1715277875.593 [NAL9602](INFO): SBD MO Status=2, MOMSN=17430, MT Status=2, MTMSN=0 2024-05-09T18:04:35.593Z,1715277875.593 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T18:04:48.521Z,1715277888.521 [NAL9602](INFO): SBD MO Status=2, MOMSN=17430, MT Status=2, MTMSN=0 2024-05-09T18:04:48.521Z,1715277888.521 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T18:05:14.380Z,1715277914.380 [NAL9602](INFO): SBD MO Status=2, MOMSN=17430, MT Status=2, MTMSN=0 2024-05-09T18:05:14.380Z,1715277914.380 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T18:05:24.080Z,1715277924.080 [NAL9602](INFO): SBD MO Status=0, MOMSN=17430, MT Status=0, MTMSN=0 2024-05-09T18:05:24.080Z,1715277924.080 [NAL9602](INFO): No messages in MT queue 2024-05-09T18:05:54.776Z,1715277954.776 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:09:21.242Z,1715278161.242 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:09:21.242Z,1715278161.242 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:09:21.242Z,1715278161.242 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:09:21.242Z,1715278161.242 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:09:21.660Z,1715278161.660 [Default:CheckIn:D] Stopped 2024-05-09T18:09:21.660Z,1715278161.660 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:09:22.039Z,1715278162.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.464038 min 2024-05-09T18:09:22.039Z,1715278162.039 [Default:CheckIn:E] Stopped 2024-05-09T18:09:22.039Z,1715278162.039 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:09:22.039Z,1715278162.039 [Default:CheckIn] Stopped 2024-05-09T18:09:22.039Z,1715278162.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:09:22.039Z,1715278162.039 [Default:CheckIn](INFO): Running loop #13 2024-05-09T18:09:22.039Z,1715278162.039 [Default:CheckIn] Running Loop=13 2024-05-09T18:09:22.040Z,1715278162.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:09:22.040Z,1715278162.040 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:09:24.072Z,1715278164.072 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180923.00,A,3648.14788,N,12147.22516,W,0.369,218.96,090524,,,A*77 2024-05-09T18:09:24.075Z,1715278164.075 [NAL9602](INFO): GPS fix at 20240509T180923: (36.802465, -121.787086) 2024-05-09T18:09:24.132Z,1715278164.132 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:09:24.132Z,1715278164.132 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:09:31.101Z,1715278171.101 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240509T172031/Courier0024.lzma 2024-05-09T18:09:32.103Z,1715278172.103 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0024.lzma.bak 2024-05-09T18:09:32.103Z,1715278172.103 [DataOverHttps](INFO): SBD MOMSN=20998515 2024-05-09T18:09:48.297Z,1715278188.297 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240509T172031/Express0025.lzma 2024-05-09T18:09:49.299Z,1715278189.299 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0025.lzma.bak 2024-05-09T18:09:49.300Z,1715278189.300 [DataOverHttps](INFO): SBD MOMSN=20998521 2024-05-09T18:09:50.774Z,1715278190.774 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:09:50.775Z,1715278190.775 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:09:50.775Z,1715278190.775 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:09:53.985Z,1715278193.985 [NAL9602](INFO): SBD MO Status=0, MOMSN=17431, MT Status=0, MTMSN=0 2024-05-09T18:09:53.986Z,1715278193.986 [NAL9602](INFO): No messages in MT queue 2024-05-09T18:10:24.699Z,1715278224.699 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:14:51.333Z,1715278491.333 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:14:51.333Z,1715278491.333 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:14:51.333Z,1715278491.333 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:14:51.333Z,1715278491.333 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:14:51.744Z,1715278491.744 [Default:CheckIn:D] Stopped 2024-05-09T18:14:51.744Z,1715278491.744 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:14:52.208Z,1715278492.208 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.965446 min 2024-05-09T18:14:52.208Z,1715278492.208 [Default:CheckIn:E] Stopped 2024-05-09T18:14:52.208Z,1715278492.208 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:14:52.208Z,1715278492.208 [Default:CheckIn] Stopped 2024-05-09T18:14:52.208Z,1715278492.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:14:52.208Z,1715278492.208 [Default:CheckIn](INFO): Running loop #14 2024-05-09T18:14:52.208Z,1715278492.208 [Default:CheckIn] Running Loop=14 2024-05-09T18:14:52.208Z,1715278492.208 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:14:52.208Z,1715278492.208 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:14:54.149Z,1715278494.149 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181453.00,A,3648.14311,N,12147.22901,W,0.078,215.66,090524,,,A*73 2024-05-09T18:14:54.151Z,1715278494.151 [NAL9602](INFO): GPS fix at 20240509T181453: (36.802385, -121.787150) 2024-05-09T18:14:54.168Z,1715278494.168 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:14:54.169Z,1715278494.169 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:15:01.553Z,1715278501.553 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240509T172031/Courier0027.lzma 2024-05-09T18:15:02.555Z,1715278502.555 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0027.lzma.bak 2024-05-09T18:15:02.555Z,1715278502.555 [DataOverHttps](INFO): SBD MOMSN=20998544 2024-05-09T18:15:14.756Z,1715278514.756 [NAL9602](INFO): SBD MO Status=2, MOMSN=17432, MT Status=2, MTMSN=0 2024-05-09T18:15:14.756Z,1715278514.756 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T18:15:18.437Z,1715278518.437 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240509T172031/Express0028.lzma 2024-05-09T18:15:19.440Z,1715278519.440 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0028.lzma.bak 2024-05-09T18:15:19.440Z,1715278519.440 [DataOverHttps](INFO): SBD MOMSN=20998550 2024-05-09T18:15:20.827Z,1715278520.827 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:15:20.827Z,1715278520.827 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:15:20.827Z,1715278520.827 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:15:49.908Z,1715278549.908 [NAL9602](INFO): SBD MO Status=0, MOMSN=17432, MT Status=0, MTMSN=0 2024-05-09T18:15:49.908Z,1715278549.908 [NAL9602](INFO): No messages in MT queue 2024-05-09T18:16:20.606Z,1715278580.606 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:20:21.450Z,1715278821.450 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:20:21.451Z,1715278821.451 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:20:21.451Z,1715278821.451 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:20:21.451Z,1715278821.451 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:20:21.849Z,1715278821.849 [Default:CheckIn:D] Stopped 2024-05-09T18:20:21.849Z,1715278821.849 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:20:22.257Z,1715278822.257 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.467196 min 2024-05-09T18:20:22.257Z,1715278822.257 [Default:CheckIn:E] Stopped 2024-05-09T18:20:22.258Z,1715278822.258 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:20:22.258Z,1715278822.258 [Default:CheckIn] Stopped 2024-05-09T18:20:22.258Z,1715278822.258 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:20:22.258Z,1715278822.258 [Default:CheckIn](INFO): Running loop #15 2024-05-09T18:20:22.258Z,1715278822.258 [Default:CheckIn] Running Loop=15 2024-05-09T18:20:22.258Z,1715278822.258 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:20:22.258Z,1715278822.258 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:20:24.267Z,1715278824.267 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182023.00,A,3648.14348,N,12147.24901,W,1.283,327.16,090524,,,A*79 2024-05-09T18:20:24.269Z,1715278824.269 [NAL9602](INFO): GPS fix at 20240509T182023: (36.802391, -121.787483) 2024-05-09T18:20:24.301Z,1715278824.301 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:20:24.301Z,1715278824.301 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:20:31.921Z,1715278831.921 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240509T172031/Courier0030.lzma 2024-05-09T18:20:32.923Z,1715278832.923 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0030.lzma.bak 2024-05-09T18:20:32.923Z,1715278832.923 [DataOverHttps](INFO): SBD MOMSN=20998576 2024-05-09T18:20:42.848Z,1715278842.848 [NAL9602](INFO): SBD MO Status=0, MOMSN=17433, MT Status=0, MTMSN=0 2024-05-09T18:20:42.848Z,1715278842.848 [NAL9602](INFO): No messages in MT queue 2024-05-09T18:20:48.969Z,1715278848.969 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240509T172031/Express0031.lzma 2024-05-09T18:20:49.971Z,1715278849.971 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0031.lzma.bak 2024-05-09T18:20:49.972Z,1715278849.972 [DataOverHttps](INFO): SBD MOMSN=20998583 2024-05-09T18:20:51.347Z,1715278851.347 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:20:51.347Z,1715278851.347 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:20:51.347Z,1715278851.347 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:21:13.612Z,1715278873.612 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:24:42.015Z,1715279082.015 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 6. 2024-05-09T18:24:42.018Z,1715279082.018 [BPC1](FAULT): Failed to receive data from 2 sticks prior to timeout. Missing stick IDs are: 42, 44. 2024-05-09T18:24:42.018Z,1715279082.018 [BPC1] Data Fault, FailCount= 2 2024-05-09T18:24:42.018Z,1715279082.018 [BPC1](ERROR): Data Fault 2024-05-09T18:24:42.042Z,1715279082.042 [CBIT](ERROR): Data Fault in component: BPC1 2024-05-09T18:25:51.928Z,1715279151.928 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:25:51.929Z,1715279151.929 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:25:51.929Z,1715279151.929 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:25:51.929Z,1715279151.929 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:25:52.326Z,1715279152.326 [Default:CheckIn:D] Stopped 2024-05-09T18:25:52.326Z,1715279152.326 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:25:52.727Z,1715279152.727 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.975146 min 2024-05-09T18:25:52.727Z,1715279152.727 [Default:CheckIn:E] Stopped 2024-05-09T18:25:52.728Z,1715279152.728 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:25:52.728Z,1715279152.728 [Default:CheckIn] Stopped 2024-05-09T18:25:52.728Z,1715279152.728 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:25:52.728Z,1715279152.728 [Default:CheckIn](INFO): Running loop #16 2024-05-09T18:25:52.728Z,1715279152.728 [Default:CheckIn] Running Loop=16 2024-05-09T18:25:52.728Z,1715279152.728 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:25:52.728Z,1715279152.728 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:25:54.737Z,1715279154.737 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182553.00,A,3648.18026,N,12147.27870,W,0.447,294.00,090524,,,A*79 2024-05-09T18:25:54.740Z,1715279154.740 [NAL9602](INFO): GPS fix at 20240509T182553: (36.803004, -121.787978) 2024-05-09T18:25:54.752Z,1715279154.752 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:25:54.752Z,1715279154.752 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:26:02.069Z,1715279162.069 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240509T172031/Courier0033.lzma 2024-05-09T18:26:03.072Z,1715279163.072 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0033.lzma.bak 2024-05-09T18:26:03.072Z,1715279163.072 [DataOverHttps](INFO): SBD MOMSN=20998596 2024-05-09T18:26:18.994Z,1715279178.994 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20240509T172031/Express0034.lzma 2024-05-09T18:26:19.995Z,1715279179.995 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0034.lzma.bak 2024-05-09T18:26:19.996Z,1715279179.996 [DataOverHttps](INFO): SBD MOMSN=20998603 2024-05-09T18:26:21.419Z,1715279181.419 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:26:21.420Z,1715279181.420 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:26:21.420Z,1715279181.420 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:26:35.574Z,1715279195.574 [NAL9602](INFO): SBD MO Status=2, MOMSN=17434, MT Status=2, MTMSN=0 2024-05-09T18:26:35.575Z,1715279195.575 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T18:27:15.136Z,1715279235.136 [NAL9602](INFO): SBD MO Status=2, MOMSN=17434, MT Status=2, MTMSN=0 2024-05-09T18:27:15.136Z,1715279235.136 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T18:28:55.328Z,1715279335.328 [NAL9602](INFO): SBD MO Status=0, MOMSN=17434, MT Status=0, MTMSN=0 2024-05-09T18:28:55.328Z,1715279335.328 [NAL9602](INFO): No messages in MT queue 2024-05-09T18:29:26.027Z,1715279366.027 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:31:21.990Z,1715279481.990 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:31:21.990Z,1715279481.990 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:31:21.990Z,1715279481.990 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:31:21.990Z,1715279481.990 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:31:22.392Z,1715279482.392 [Default:CheckIn:D] Stopped 2024-05-09T18:31:22.392Z,1715279482.392 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:31:22.807Z,1715279482.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.476253 min 2024-05-09T18:31:22.807Z,1715279482.807 [Default:CheckIn:E] Stopped 2024-05-09T18:31:22.807Z,1715279482.807 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:31:22.807Z,1715279482.807 [Default:CheckIn] Stopped 2024-05-09T18:31:22.808Z,1715279482.808 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:31:22.808Z,1715279482.808 [Default:CheckIn](INFO): Running loop #17 2024-05-09T18:31:22.808Z,1715279482.808 [Default:CheckIn] Running Loop=17 2024-05-09T18:31:22.808Z,1715279482.808 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:31:22.808Z,1715279482.808 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:31:24.809Z,1715279484.809 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183123.00,A,3648.17876,N,12147.28112,W,0.253,294.00,090524,,,A*78 2024-05-09T18:31:24.811Z,1715279484.811 [NAL9602](INFO): GPS fix at 20240509T183123: (36.802979, -121.788019) 2024-05-09T18:31:24.844Z,1715279484.844 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:31:24.844Z,1715279484.844 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:31:32.385Z,1715279492.385 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240509T172031/Courier0036.lzma 2024-05-09T18:31:33.387Z,1715279493.387 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0036.lzma.bak 2024-05-09T18:31:33.387Z,1715279493.387 [DataOverHttps](INFO): SBD MOMSN=20998846 2024-05-09T18:31:49.345Z,1715279509.345 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240509T172031/Express0037.lzma 2024-05-09T18:31:50.347Z,1715279510.347 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0037.lzma.bak 2024-05-09T18:31:50.348Z,1715279510.348 [DataOverHttps](INFO): SBD MOMSN=20998852 2024-05-09T18:31:51.901Z,1715279511.901 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:31:51.901Z,1715279511.901 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:31:51.901Z,1715279511.901 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:31:55.916Z,1715279515.916 [NAL9602](INFO): SBD MO Status=2, MOMSN=17435, MT Status=2, MTMSN=0 2024-05-09T18:31:55.916Z,1715279515.916 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T18:32:10.862Z,1715279530.862 [NAL9602](INFO): SBD MO Status=2, MOMSN=17435, MT Status=2, MTMSN=0 2024-05-09T18:32:10.862Z,1715279530.862 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T18:32:47.625Z,1715279567.625 [NAL9602](INFO): SBD MO Status=0, MOMSN=17435, MT Status=0, MTMSN=0 2024-05-09T18:32:47.625Z,1715279567.625 [NAL9602](INFO): No messages in MT queue 2024-05-09T18:33:18.328Z,1715279598.328 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:34:43.216Z,1715279683.216 [CBIT](INFO): Clearing failed state for component BPC1 2024-05-09T18:34:43.216Z,1715279683.216 [BPC1] No Fault, FailCount= 2 2024-05-09T18:36:52.466Z,1715279812.466 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:36:52.466Z,1715279812.466 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:36:52.466Z,1715279812.466 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:36:52.467Z,1715279812.467 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:36:52.881Z,1715279812.881 [Default:CheckIn:D] Stopped 2024-05-09T18:36:52.881Z,1715279812.881 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:36:53.271Z,1715279813.271 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.984391 min 2024-05-09T18:36:53.271Z,1715279813.271 [Default:CheckIn:E] Stopped 2024-05-09T18:36:53.272Z,1715279813.272 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:36:53.272Z,1715279813.272 [Default:CheckIn] Stopped 2024-05-09T18:36:53.272Z,1715279813.272 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:36:53.272Z,1715279813.272 [Default:CheckIn](INFO): Running loop #18 2024-05-09T18:36:53.272Z,1715279813.272 [Default:CheckIn] Running Loop=18 2024-05-09T18:36:53.272Z,1715279813.272 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:36:53.272Z,1715279813.272 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:36:55.279Z,1715279815.279 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183654.00,A,3648.18026,N,12147.27707,W,0.330,0.00,090524,,,A*7B 2024-05-09T18:36:55.282Z,1715279815.282 [NAL9602](INFO): GPS fix at 20240509T183654: (36.803004, -121.787951) 2024-05-09T18:36:55.294Z,1715279815.294 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:36:55.294Z,1715279815.294 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:37:03.073Z,1715279823.073 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240509T172031/Courier0039.lzma 2024-05-09T18:37:04.075Z,1715279824.075 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0039.lzma.bak 2024-05-09T18:37:04.075Z,1715279824.075 [DataOverHttps](INFO): SBD MOMSN=20998873 2024-05-09T18:37:19.953Z,1715279839.953 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240509T172031/Express0040.lzma 2024-05-09T18:37:20.957Z,1715279840.957 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0040.lzma.bak 2024-05-09T18:37:20.958Z,1715279840.958 [DataOverHttps](INFO): SBD MOMSN=20998879 2024-05-09T18:37:22.384Z,1715279842.384 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:37:22.384Z,1715279842.384 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:37:22.384Z,1715279842.384 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:38:43.954Z,1715279923.954 [NAL9602](INFO): SBD MO Status=0, MOMSN=17436, MT Status=0, MTMSN=0 2024-05-09T18:38:43.954Z,1715279923.954 [NAL9602](INFO): No messages in MT queue 2024-05-09T18:39:14.656Z,1715279954.656 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:42:22.948Z,1715280142.948 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:42:22.948Z,1715280142.948 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:42:22.948Z,1715280142.948 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:42:22.948Z,1715280142.948 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:42:23.362Z,1715280143.362 [Default:CheckIn:D] Stopped 2024-05-09T18:42:23.362Z,1715280143.362 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:42:23.748Z,1715280143.748 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.492415 min 2024-05-09T18:42:23.748Z,1715280143.748 [Default:CheckIn:E] Stopped 2024-05-09T18:42:23.749Z,1715280143.749 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:42:23.749Z,1715280143.749 [Default:CheckIn] Stopped 2024-05-09T18:42:23.749Z,1715280143.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:42:23.749Z,1715280143.749 [Default:CheckIn](INFO): Running loop #19 2024-05-09T18:42:23.749Z,1715280143.749 [Default:CheckIn] Running Loop=19 2024-05-09T18:42:23.749Z,1715280143.749 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:42:23.749Z,1715280143.749 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:42:25.764Z,1715280145.764 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184224.00,A,3648.17502,N,12147.28377,W,0.194,334.99,090524,,,A*77 2024-05-09T18:42:25.766Z,1715280145.766 [NAL9602](INFO): GPS fix at 20240509T184224: (36.802917, -121.788063) 2024-05-09T18:42:25.802Z,1715280145.802 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:42:25.802Z,1715280145.802 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:42:33.473Z,1715280153.473 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240509T172031/Courier0042.lzma 2024-05-09T18:42:34.475Z,1715280154.475 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0042.lzma.bak 2024-05-09T18:42:34.476Z,1715280154.476 [DataOverHttps](INFO): SBD MOMSN=20998901 2024-05-09T18:42:34.644Z,1715280154.644 [NAL9602](INFO): SBD MO Status=0, MOMSN=17437, MT Status=0, MTMSN=0 2024-05-09T18:42:34.644Z,1715280154.644 [NAL9602](INFO): No messages in MT queue 2024-05-09T18:42:50.485Z,1715280170.485 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240509T172031/Express0043.lzma 2024-05-09T18:42:51.488Z,1715280171.488 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0043.lzma.bak 2024-05-09T18:42:51.488Z,1715280171.488 [DataOverHttps](INFO): SBD MOMSN=20998907 2024-05-09T18:42:52.839Z,1715280172.839 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:42:52.839Z,1715280172.839 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:42:52.839Z,1715280172.839 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:43:05.356Z,1715280185.356 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:47:53.422Z,1715280473.422 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:47:53.422Z,1715280473.422 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:47:53.422Z,1715280473.422 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:47:53.423Z,1715280473.423 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:47:53.817Z,1715280473.817 [Default:CheckIn:D] Stopped 2024-05-09T18:47:53.817Z,1715280473.817 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:47:54.230Z,1715280474.230 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.999992 min 2024-05-09T18:47:54.230Z,1715280474.230 [Default:CheckIn:E] Stopped 2024-05-09T18:47:54.230Z,1715280474.230 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:47:54.230Z,1715280474.230 [Default:CheckIn] Stopped 2024-05-09T18:47:54.230Z,1715280474.230 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:47:54.230Z,1715280474.230 [Default:CheckIn](INFO): Running loop #20 2024-05-09T18:47:54.230Z,1715280474.230 [Default:CheckIn] Running Loop=20 2024-05-09T18:47:54.231Z,1715280474.231 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:47:54.231Z,1715280474.231 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:47:56.228Z,1715280476.228 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184755.00,A,3648.17605,N,12147.28216,W,0.564,351.97,090524,,,A*70 2024-05-09T18:47:56.231Z,1715280476.231 [NAL9602](INFO): GPS fix at 20240509T184755: (36.802934, -121.788036) 2024-05-09T18:47:56.263Z,1715280476.263 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:47:56.263Z,1715280476.263 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:48:03.925Z,1715280483.925 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240509T172031/Courier0045.lzma 2024-05-09T18:48:04.927Z,1715280484.927 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0045.lzma.bak 2024-05-09T18:48:04.927Z,1715280484.927 [DataOverHttps](INFO): SBD MOMSN=20998935 2024-05-09T18:48:20.877Z,1715280500.877 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240509T172031/Express0046.lzma 2024-05-09T18:48:21.879Z,1715280501.879 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0046.lzma.bak 2024-05-09T18:48:21.880Z,1715280501.880 [DataOverHttps](INFO): SBD MOMSN=20998941 2024-05-09T18:48:23.315Z,1715280503.315 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:48:23.315Z,1715280503.315 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:48:23.315Z,1715280503.315 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:49:00.067Z,1715280540.067 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-05-09T18:49:00.067Z,1715280540.067 [DropWeight] Hardware Fault, FailCount= 1 2024-05-09T18:49:00.067Z,1715280540.067 [DropWeight](ERROR): Hardware Fault 2024-05-09T18:49:00.087Z,1715280540.087 [CBIT](INFO): Critical error at 20240509T184900 2024-05-09T18:49:00.089Z,1715280540.089 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-05-09T18:49:00.090Z,1715280540.090 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-05-09T18:49:00.503Z,1715280540.503 [CBIT](INFO): Critical error at 20240509T184900 2024-05-09T18:51:28.791Z,1715280688.791 [NAL9602](INFO): SBD MO Status=2, MOMSN=17438, MT Status=2, MTMSN=0 2024-05-09T18:51:28.791Z,1715280688.791 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T18:51:57.420Z,1715280717.420 [NAL9602](INFO): SBD MO Status=0, MOMSN=17438, MT Status=0, MTMSN=0 2024-05-09T18:51:57.420Z,1715280717.420 [NAL9602](INFO): No messages in MT queue 2024-05-09T18:52:28.121Z,1715280748.121 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:53:23.893Z,1715280803.893 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:53:23.893Z,1715280803.893 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:53:23.893Z,1715280803.893 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:53:23.894Z,1715280803.894 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:53:24.312Z,1715280804.312 [Default:CheckIn:D] Stopped 2024-05-09T18:53:24.312Z,1715280804.312 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:53:24.712Z,1715280804.712 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.508244 min 2024-05-09T18:53:24.712Z,1715280804.712 [Default:CheckIn:E] Stopped 2024-05-09T18:53:24.712Z,1715280804.712 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:53:24.712Z,1715280804.712 [Default:CheckIn] Stopped 2024-05-09T18:53:24.712Z,1715280804.712 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:53:24.712Z,1715280804.712 [Default:CheckIn](INFO): Running loop #21 2024-05-09T18:53:24.712Z,1715280804.712 [Default:CheckIn] Running Loop=21 2024-05-09T18:53:24.712Z,1715280804.712 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:53:24.713Z,1715280804.713 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:53:26.704Z,1715280806.704 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185325.00,A,3648.17540,N,12147.27945,W,0.117,351.97,090524,,,A*72 2024-05-09T18:53:26.706Z,1715280806.706 [NAL9602](INFO): GPS fix at 20240509T185325: (36.802923, -121.787991) 2024-05-09T18:53:26.739Z,1715280806.739 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:53:26.739Z,1715280806.739 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:53:33.694Z,1715280813.694 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20240509T172031/Courier0048.lzma 2024-05-09T18:53:34.696Z,1715280814.696 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0048.lzma.bak 2024-05-09T18:53:34.696Z,1715280814.696 [DataOverHttps](INFO): SBD MOMSN=20998961 2024-05-09T18:53:53.574Z,1715280833.574 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240509T172031/Express0049.lzma 2024-05-09T18:53:54.576Z,1715280834.576 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0049.lzma.bak 2024-05-09T18:53:54.576Z,1715280834.576 [DataOverHttps](INFO): SBD MOMSN=20998969 2024-05-09T18:53:55.949Z,1715280835.949 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:53:55.950Z,1715280835.950 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:53:55.950Z,1715280835.950 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:58:05.996Z,1715281085.996 [NAL9602](INFO): SBD MO Status=0, MOMSN=17439, MT Status=0, MTMSN=0 2024-05-09T18:58:05.996Z,1715281085.996 [NAL9602](INFO): No messages in MT queue 2024-05-09T18:58:36.702Z,1715281116.702 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T18:58:56.506Z,1715281136.506 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T18:58:56.506Z,1715281136.506 [Default:CheckIn:C.Wait] Stopped 2024-05-09T18:58:56.506Z,1715281136.506 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T18:58:56.507Z,1715281136.507 [Default:CheckIn:D] Running Loop=1 2024-05-09T18:58:56.972Z,1715281136.972 [Default:CheckIn:D] Stopped 2024-05-09T18:58:56.972Z,1715281136.972 [Default:CheckIn:E] Running Loop=1 2024-05-09T18:58:57.319Z,1715281137.319 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.052572 min 2024-05-09T18:58:57.319Z,1715281137.319 [Default:CheckIn:E] Stopped 2024-05-09T18:58:57.319Z,1715281137.319 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T18:58:57.319Z,1715281137.319 [Default:CheckIn] Stopped 2024-05-09T18:58:57.319Z,1715281137.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T18:58:57.320Z,1715281137.320 [Default:CheckIn](INFO): Running loop #22 2024-05-09T18:58:57.320Z,1715281137.320 [Default:CheckIn] Running Loop=22 2024-05-09T18:58:57.320Z,1715281137.320 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T18:58:57.320Z,1715281137.320 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T18:58:59.334Z,1715281139.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185858.00,A,3648.17733,N,12147.27851,W,0.214,0.00,090524,,,A*78 2024-05-09T18:58:59.336Z,1715281139.336 [NAL9602](INFO): GPS fix at 20240509T185858: (36.802956, -121.787975) 2024-05-09T18:58:59.348Z,1715281139.348 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T18:58:59.348Z,1715281139.348 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T18:59:06.752Z,1715281146.752 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240509T172031/Courier0051.lzma 2024-05-09T18:59:07.752Z,1715281147.752 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0051.lzma.bak 2024-05-09T18:59:07.752Z,1715281147.752 [DataOverHttps](INFO): SBD MOMSN=20998996 2024-05-09T18:59:23.677Z,1715281163.677 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240509T172031/Express0052.lzma 2024-05-09T18:59:24.680Z,1715281164.680 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0052.lzma.bak 2024-05-09T18:59:24.680Z,1715281164.680 [DataOverHttps](INFO): SBD MOMSN=20999003 2024-05-09T18:59:26.008Z,1715281166.008 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T18:59:26.008Z,1715281166.008 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T18:59:26.008Z,1715281166.008 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T18:59:44.168Z,1715281184.168 [NAL9602](INFO): SBD MO Status=2, MOMSN=17440, MT Status=2, MTMSN=0 2024-05-09T18:59:44.168Z,1715281184.168 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T19:00:07.597Z,1715281207.597 [NAL9602](INFO): SBD MO Status=2, MOMSN=17440, MT Status=2, MTMSN=0 2024-05-09T19:00:07.597Z,1715281207.597 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T19:00:24.968Z,1715281224.968 [NAL9602](INFO): SBD MO Status=2, MOMSN=17440, MT Status=2, MTMSN=0 2024-05-09T19:00:24.969Z,1715281224.969 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T19:04:00.322Z,1715281440.322 [CBIT](INFO): Clearing failed state for component DropWeight 2024-05-09T19:04:00.322Z,1715281440.322 [DropWeight] No Fault, FailCount= 1 2024-05-09T19:04:01.515Z,1715281441.515 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-09T19:04:26.590Z,1715281466.590 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T19:04:26.590Z,1715281466.590 [Default:CheckIn:C.Wait] Stopped 2024-05-09T19:04:26.590Z,1715281466.590 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T19:04:26.590Z,1715281466.590 [Default:CheckIn:D] Running Loop=1 2024-05-09T19:04:26.975Z,1715281466.975 [Default:CheckIn:D] Stopped 2024-05-09T19:04:26.976Z,1715281466.976 [Default:CheckIn:E] Running Loop=1 2024-05-09T19:04:27.386Z,1715281467.386 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.552637 min 2024-05-09T19:04:27.387Z,1715281467.387 [Default:CheckIn:E] Stopped 2024-05-09T19:04:27.387Z,1715281467.387 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T19:04:27.387Z,1715281467.387 [Default:CheckIn] Stopped 2024-05-09T19:04:27.387Z,1715281467.387 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T19:04:27.387Z,1715281467.387 [Default:CheckIn](INFO): Running loop #23 2024-05-09T19:04:27.387Z,1715281467.387 [Default:CheckIn] Running Loop=23 2024-05-09T19:04:27.387Z,1715281467.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T19:04:27.387Z,1715281467.387 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T19:04:29.395Z,1715281469.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190428.00,A,3648.16994,N,12147.26960,W,0.233,329.51,090524,,,A*7E 2024-05-09T19:04:29.398Z,1715281469.398 [NAL9602](INFO): GPS fix at 20240509T190428: (36.802832, -121.787827) 2024-05-09T19:04:29.410Z,1715281469.410 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T19:04:29.410Z,1715281469.410 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T19:04:37.149Z,1715281477.149 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240509T172031/Courier0054.lzma 2024-05-09T19:04:38.151Z,1715281478.151 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0054.lzma.bak 2024-05-09T19:04:38.151Z,1715281478.151 [DataOverHttps](INFO): SBD MOMSN=20999022 2024-05-09T19:04:54.065Z,1715281494.065 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20240509T172031/Express0055.lzma 2024-05-09T19:04:55.067Z,1715281495.067 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0055.lzma.bak 2024-05-09T19:04:55.068Z,1715281495.068 [DataOverHttps](INFO): SBD MOMSN=20999028 2024-05-09T19:04:56.476Z,1715281496.476 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T19:04:56.476Z,1715281496.476 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T19:04:56.476Z,1715281496.476 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T19:05:01.719Z,1715281501.719 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T19:05:44.127Z,1715281544.127 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5. 2024-05-09T19:05:44.130Z,1715281544.130 [BPC1](FAULT): Failed to receive data from 1 sticks prior to timeout. Missing stick IDs are: 42. 2024-05-09T19:05:44.130Z,1715281544.130 [BPC1] Data Fault, FailCount= 3 2024-05-09T19:05:44.130Z,1715281544.130 [BPC1](ERROR): Data Fault 2024-05-09T19:05:44.203Z,1715281544.203 [CBIT](ERROR): Data Fault in component: BPC1 2024-05-09T19:09:57.048Z,1715281797.048 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T19:09:57.048Z,1715281797.048 [Default:CheckIn:C.Wait] Stopped 2024-05-09T19:09:57.048Z,1715281797.048 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T19:09:57.048Z,1715281797.048 [Default:CheckIn:D] Running Loop=1 2024-05-09T19:09:57.467Z,1715281797.467 [Default:CheckIn:D] Stopped 2024-05-09T19:09:57.467Z,1715281797.467 [Default:CheckIn:E] Running Loop=1 2024-05-09T19:09:57.872Z,1715281797.872 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.060824 min 2024-05-09T19:09:57.872Z,1715281797.872 [Default:CheckIn:E] Stopped 2024-05-09T19:09:57.872Z,1715281797.872 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T19:09:57.873Z,1715281797.873 [Default:CheckIn] Stopped 2024-05-09T19:09:57.873Z,1715281797.873 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T19:09:57.873Z,1715281797.873 [Default:CheckIn](INFO): Running loop #24 2024-05-09T19:09:57.873Z,1715281797.873 [Default:CheckIn] Running Loop=24 2024-05-09T19:09:57.873Z,1715281797.873 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T19:09:57.873Z,1715281797.873 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T19:09:59.867Z,1715281799.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190958.00,A,3648.17497,N,12147.27855,W,0.214,247.65,090524,,,A*76 2024-05-09T19:09:59.869Z,1715281799.869 [NAL9602](INFO): GPS fix at 20240509T190958: (36.802916, -121.787976) 2024-05-09T19:09:59.882Z,1715281799.882 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T19:09:59.882Z,1715281799.882 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T19:10:07.445Z,1715281807.445 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240509T172031/Courier0057.lzma 2024-05-09T19:10:08.447Z,1715281808.447 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0057.lzma.bak 2024-05-09T19:10:08.448Z,1715281808.448 [DataOverHttps](INFO): SBD MOMSN=20999042 2024-05-09T19:10:24.417Z,1715281824.417 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240509T172031/Express0058.lzma 2024-05-09T19:10:25.419Z,1715281825.419 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0058.lzma.bak 2024-05-09T19:10:25.420Z,1715281825.420 [DataOverHttps](INFO): SBD MOMSN=20999048 2024-05-09T19:10:26.963Z,1715281826.963 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T19:10:26.963Z,1715281826.963 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T19:10:26.964Z,1715281826.964 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T19:10:30.567Z,1715281830.567 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-09T19:10:30.646Z,1715281830.646 [NAL9602](ERROR): received: +CSQ:0 OK440, 2, 0, 0, 0 OK 2024-05-09T19:15:02.462Z,1715282102.462 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-09T19:15:27.526Z,1715282127.526 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T19:15:27.526Z,1715282127.526 [Default:CheckIn:C.Wait] Stopped 2024-05-09T19:15:27.526Z,1715282127.526 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T19:15:27.526Z,1715282127.526 [Default:CheckIn:D] Running Loop=1 2024-05-09T19:15:27.922Z,1715282127.922 [Default:CheckIn:D] Stopped 2024-05-09T19:15:27.922Z,1715282127.922 [Default:CheckIn:E] Running Loop=1 2024-05-09T19:15:28.327Z,1715282128.327 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.568424 min 2024-05-09T19:15:28.327Z,1715282128.327 [Default:CheckIn:E] Stopped 2024-05-09T19:15:28.327Z,1715282128.327 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T19:15:28.327Z,1715282128.327 [Default:CheckIn] Stopped 2024-05-09T19:15:28.327Z,1715282128.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T19:15:28.327Z,1715282128.327 [Default:CheckIn](INFO): Running loop #25 2024-05-09T19:15:28.327Z,1715282128.327 [Default:CheckIn] Running Loop=25 2024-05-09T19:15:28.327Z,1715282128.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T19:15:28.327Z,1715282128.327 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T19:15:30.334Z,1715282130.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191529.00,A,3648.17400,N,12147.27639,W,0.136,247.65,090524,,,A*74 2024-05-09T19:15:30.336Z,1715282130.336 [NAL9602](INFO): GPS fix at 20240509T191529: (36.802900, -121.787940) 2024-05-09T19:15:30.349Z,1715282130.349 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T19:15:30.349Z,1715282130.349 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T19:15:37.769Z,1715282137.769 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240509T172031/Courier0060.lzma 2024-05-09T19:15:38.771Z,1715282138.771 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0060.lzma.bak 2024-05-09T19:15:38.772Z,1715282138.772 [DataOverHttps](INFO): SBD MOMSN=20999063 2024-05-09T19:15:45.314Z,1715282145.314 [CBIT](INFO): Clearing failed state for component BPC1 2024-05-09T19:15:45.314Z,1715282145.314 [BPC1] No Fault, FailCount= 3 2024-05-09T19:15:55.030Z,1715282155.030 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240509T172031/Express0061.lzma 2024-05-09T19:15:56.031Z,1715282156.031 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0061.lzma.bak 2024-05-09T19:15:56.032Z,1715282156.032 [DataOverHttps](INFO): SBD MOMSN=20999069 2024-05-09T19:15:57.419Z,1715282157.419 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T19:15:57.419Z,1715282157.419 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T19:15:57.419Z,1715282157.419 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T19:16:02.650Z,1715282162.650 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T19:20:58.104Z,1715282458.104 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T19:20:58.104Z,1715282458.104 [Default:CheckIn:C.Wait] Stopped 2024-05-09T19:20:58.104Z,1715282458.104 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T19:20:58.104Z,1715282458.104 [Default:CheckIn:D] Running Loop=1 2024-05-09T19:20:58.508Z,1715282458.508 [Default:CheckIn:D] Stopped 2024-05-09T19:20:58.508Z,1715282458.508 [Default:CheckIn:E] Running Loop=1 2024-05-09T19:20:58.915Z,1715282458.915 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 143.078174 min 2024-05-09T19:20:58.916Z,1715282458.916 [Default:CheckIn:E] Stopped 2024-05-09T19:20:58.916Z,1715282458.916 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T19:20:58.917Z,1715282458.917 [Default:CheckIn] Stopped 2024-05-09T19:20:58.917Z,1715282458.917 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T19:20:58.918Z,1715282458.918 [Default:CheckIn](INFO): Running loop #26 2024-05-09T19:20:58.919Z,1715282458.919 [Default:CheckIn] Running Loop=26 2024-05-09T19:20:58.919Z,1715282458.919 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T19:20:58.919Z,1715282458.919 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T19:21:00.943Z,1715282460.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192100.00,A,3648.17086,N,12147.27195,W,1.030,117.85,090524,,,A*7D 2024-05-09T19:21:00.945Z,1715282460.945 [NAL9602](INFO): GPS fix at 20240509T192100: (36.802848, -121.787866) 2024-05-09T19:21:00.958Z,1715282460.958 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T19:21:00.958Z,1715282460.958 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T19:21:08.649Z,1715282468.649 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240509T172031/Courier0063.lzma 2024-05-09T19:21:09.651Z,1715282469.651 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0063.lzma.bak 2024-05-09T19:21:09.652Z,1715282469.652 [DataOverHttps](INFO): SBD MOMSN=20999084 2024-05-09T19:21:19.906Z,1715282479.906 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-05-09T19:21:25.759Z,1715282485.759 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240509T172031/Express0064.lzma 2024-05-09T19:21:26.632Z,1715282486.632 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0064.lzma.bak 2024-05-09T19:21:26.632Z,1715282486.632 [DataOverHttps](INFO): SBD MOMSN=20999090 2024-05-09T19:21:28.068Z,1715282488.068 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T19:21:28.068Z,1715282488.068 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T19:21:28.068Z,1715282488.068 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T19:21:31.631Z,1715282491.631 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-09T19:21:31.709Z,1715282491.709 [NAL9602](ERROR): received: +CSQ:0 OK440, 2, 0, 0, 0 OK 2024-05-09T19:21:42.938Z,1715282502.938 [NAL9602](INFO): SBD MO Status=0, MOMSN=17440, MT Status=0, MTMSN=0 2024-05-09T19:21:42.938Z,1715282502.938 [NAL9602](INFO): No messages in MT queue 2024-05-09T19:22:13.658Z,1715282533.658 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T19:26:28.591Z,1715282788.591 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T19:26:28.591Z,1715282788.591 [Default:CheckIn:C.Wait] Stopped 2024-05-09T19:26:28.591Z,1715282788.591 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T19:26:28.591Z,1715282788.591 [Default:CheckIn:D] Running Loop=1 2024-05-09T19:26:28.987Z,1715282788.987 [Default:CheckIn:D] Stopped 2024-05-09T19:26:28.987Z,1715282788.987 [Default:CheckIn:E] Running Loop=1 2024-05-09T19:26:29.387Z,1715282789.387 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.586165 min 2024-05-09T19:26:29.388Z,1715282789.388 [Default:CheckIn:E] Stopped 2024-05-09T19:26:29.388Z,1715282789.388 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T19:26:29.388Z,1715282789.388 [Default:CheckIn] Stopped 2024-05-09T19:26:29.388Z,1715282789.388 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T19:26:29.388Z,1715282789.388 [Default:CheckIn](INFO): Running loop #27 2024-05-09T19:26:29.388Z,1715282789.388 [Default:CheckIn] Running Loop=27 2024-05-09T19:26:29.388Z,1715282789.388 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T19:26:29.388Z,1715282789.388 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T19:26:31.399Z,1715282791.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192630.00,A,3648.17447,N,12147.28357,W,1.244,117.85,090524,,,A*72 2024-05-09T19:26:31.401Z,1715282791.401 [NAL9602](INFO): GPS fix at 20240509T192630: (36.802908, -121.788060) 2024-05-09T19:26:31.414Z,1715282791.414 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T19:26:31.414Z,1715282791.414 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T19:26:38.429Z,1715282798.429 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240509T172031/Courier0066.lzma 2024-05-09T19:26:39.432Z,1715282799.432 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0066.lzma.bak 2024-05-09T19:26:39.432Z,1715282799.432 [DataOverHttps](INFO): SBD MOMSN=20999118 2024-05-09T19:26:55.569Z,1715282815.569 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240509T172031/Express0067.lzma 2024-05-09T19:26:56.576Z,1715282816.576 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0067.lzma.bak 2024-05-09T19:26:56.576Z,1715282816.576 [DataOverHttps](INFO): SBD MOMSN=20999124 2024-05-09T19:26:58.109Z,1715282818.109 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T19:26:58.109Z,1715282818.109 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T19:26:58.109Z,1715282818.109 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T19:30:08.008Z,1715283008.008 [NAL9602](INFO): SBD MO Status=0, MOMSN=17441, MT Status=0, MTMSN=0 2024-05-09T19:30:08.009Z,1715283008.009 [NAL9602](INFO): No messages in MT queue 2024-05-09T19:30:38.650Z,1715283038.650 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T19:31:58.647Z,1715283118.647 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T19:31:58.648Z,1715283118.648 [Default:CheckIn:C.Wait] Stopped 2024-05-09T19:31:58.648Z,1715283118.648 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T19:31:58.648Z,1715283118.648 [Default:CheckIn:D] Running Loop=1 2024-05-09T19:31:59.053Z,1715283119.053 [Default:CheckIn:D] Stopped 2024-05-09T19:31:59.053Z,1715283119.053 [Default:CheckIn:E] Running Loop=1 2024-05-09T19:31:59.461Z,1715283119.461 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.087256 min 2024-05-09T19:31:59.461Z,1715283119.461 [Default:CheckIn:E] Stopped 2024-05-09T19:31:59.461Z,1715283119.461 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T19:31:59.462Z,1715283119.462 [Default:CheckIn] Stopped 2024-05-09T19:31:59.462Z,1715283119.462 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T19:31:59.462Z,1715283119.462 [Default:CheckIn](INFO): Running loop #28 2024-05-09T19:31:59.462Z,1715283119.462 [Default:CheckIn] Running Loop=28 2024-05-09T19:31:59.462Z,1715283119.462 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T19:31:59.462Z,1715283119.462 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T19:32:01.466Z,1715283121.466 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193200.00,A,3648.19622,N,12147.27873,W,0.058,107.55,090524,,,A*7B 2024-05-09T19:32:01.468Z,1715283121.468 [NAL9602](INFO): GPS fix at 20240509T193200: (36.803270, -121.787979) 2024-05-09T19:32:01.492Z,1715283121.492 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T19:32:01.492Z,1715283121.492 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T19:32:08.477Z,1715283128.477 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240509T172031/Courier0069.lzma 2024-05-09T19:32:09.479Z,1715283129.479 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0069.lzma.bak 2024-05-09T19:32:09.480Z,1715283129.480 [DataOverHttps](INFO): SBD MOMSN=20999138 2024-05-09T19:32:15.200Z,1715283135.200 [NAL9602](INFO): SBD MO Status=0, MOMSN=17442, MT Status=0, MTMSN=0 2024-05-09T19:32:15.200Z,1715283135.200 [NAL9602](INFO): No messages in MT queue 2024-05-09T19:32:25.393Z,1715283145.393 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240509T172031/Express0070.lzma 2024-05-09T19:32:26.395Z,1715283146.395 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0070.lzma.bak 2024-05-09T19:32:26.396Z,1715283146.396 [DataOverHttps](INFO): SBD MOMSN=20999144 2024-05-09T19:32:27.747Z,1715283147.747 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T19:32:27.747Z,1715283147.747 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T19:32:27.748Z,1715283147.748 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T19:32:45.916Z,1715283165.916 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T19:34:01.875Z,1715283241.875 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-05-09T19:34:01.875Z,1715283241.875 [DropWeight] Hardware Fault, FailCount= 1 2024-05-09T19:34:01.875Z,1715283241.875 [DropWeight](ERROR): Hardware Fault 2024-05-09T19:34:01.895Z,1715283241.895 [CBIT](INFO): Critical error at 20240509T193401 2024-05-09T19:34:01.898Z,1715283241.898 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-05-09T19:34:01.898Z,1715283241.898 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-05-09T19:34:02.352Z,1715283242.352 [CBIT](INFO): Critical error at 20240509T193401 2024-05-09T19:37:28.313Z,1715283448.313 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T19:37:28.313Z,1715283448.313 [Default:CheckIn:C.Wait] Stopped 2024-05-09T19:37:28.313Z,1715283448.313 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T19:37:28.313Z,1715283448.313 [Default:CheckIn:D] Running Loop=1 2024-05-09T19:37:28.727Z,1715283448.727 [Default:CheckIn:D] Stopped 2024-05-09T19:37:28.727Z,1715283448.727 [Default:CheckIn:E] Running Loop=1 2024-05-09T19:37:29.122Z,1715283449.122 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.581820 min 2024-05-09T19:37:29.122Z,1715283449.122 [Default:CheckIn:E] Stopped 2024-05-09T19:37:29.123Z,1715283449.123 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T19:37:29.123Z,1715283449.123 [Default:CheckIn] Stopped 2024-05-09T19:37:29.123Z,1715283449.123 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T19:37:29.123Z,1715283449.123 [Default:CheckIn](INFO): Running loop #29 2024-05-09T19:37:29.123Z,1715283449.123 [Default:CheckIn] Running Loop=29 2024-05-09T19:37:29.123Z,1715283449.123 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T19:37:29.123Z,1715283449.123 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T19:37:31.136Z,1715283451.136 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193730.00,A,3648.16745,N,12147.28472,W,0.272,133.06,090524,,,A*7B 2024-05-09T19:37:31.138Z,1715283451.138 [NAL9602](INFO): GPS fix at 20240509T193730: (36.802791, -121.788079) 2024-05-09T19:37:31.150Z,1715283451.150 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T19:37:31.151Z,1715283451.151 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T19:37:38.793Z,1715283458.793 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20240509T172031/Courier0072.lzma 2024-05-09T19:37:39.796Z,1715283459.796 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0072.lzma.bak 2024-05-09T19:37:39.796Z,1715283459.796 [DataOverHttps](INFO): SBD MOMSN=20999157 2024-05-09T19:37:55.857Z,1715283475.857 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240509T172031/Express0073.lzma 2024-05-09T19:37:56.860Z,1715283476.860 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0073.lzma.bak 2024-05-09T19:37:56.860Z,1715283476.860 [DataOverHttps](INFO): SBD MOMSN=20999166 2024-05-09T19:37:58.279Z,1715283478.279 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T19:37:58.279Z,1715283478.279 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T19:37:58.279Z,1715283478.279 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T19:40:01.064Z,1715283601.064 [NAL9602](INFO): SBD MO Status=2, MOMSN=17443, MT Status=2, MTMSN=0 2024-05-09T19:40:01.064Z,1715283601.064 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T19:40:27.015Z,1715283627.015 [NAL9602](INFO): SBD MO Status=2, MOMSN=17443, MT Status=2, MTMSN=0 2024-05-09T19:40:27.015Z,1715283627.015 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-09T19:42:33.379Z,1715283753.379 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-09T19:42:58.840Z,1715283778.840 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T19:42:58.840Z,1715283778.840 [Default:CheckIn:C.Wait] Stopped 2024-05-09T19:42:58.840Z,1715283778.840 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T19:42:58.841Z,1715283778.841 [Default:CheckIn:D] Running Loop=1 2024-05-09T19:42:59.252Z,1715283779.252 [Default:CheckIn:D] Stopped 2024-05-09T19:42:59.252Z,1715283779.252 [Default:CheckIn:E] Running Loop=1 2024-05-09T19:42:59.647Z,1715283779.647 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.090576 min 2024-05-09T19:42:59.647Z,1715283779.647 [Default:CheckIn:E] Stopped 2024-05-09T19:42:59.647Z,1715283779.647 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T19:42:59.647Z,1715283779.647 [Default:CheckIn] Stopped 2024-05-09T19:42:59.647Z,1715283779.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T19:42:59.647Z,1715283779.647 [Default:CheckIn](INFO): Running loop #30 2024-05-09T19:42:59.648Z,1715283779.648 [Default:CheckIn] Running Loop=30 2024-05-09T19:42:59.648Z,1715283779.648 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T19:42:59.648Z,1715283779.648 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T19:44:26.906Z,1715283866.906 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.679253 2024-05-09T19:44:37.354Z,1715283877.354 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003140 2024-05-09T19:46:46.284Z,1715284006.284 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 6. 2024-05-09T19:46:46.287Z,1715284006.287 [BPC1](FAULT): Failed to receive data from 2 sticks prior to timeout. Missing stick IDs are: 42, 46. 2024-05-09T19:46:46.287Z,1715284006.287 [BPC1] Data Fault, FailCount= 4 2024-05-09T19:46:46.287Z,1715284006.287 [BPC1](ERROR): Data Fault 2024-05-09T19:46:46.352Z,1715284006.352 [CBIT](ERROR): Data Fault in component: BPC1 2024-05-09T19:47:59.841Z,1715284079.841 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-09T19:42:59.6Z 2024-05-09T19:47:59.842Z,1715284079.842 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T19:47:59.842Z,1715284079.842 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-09T19:48:06.868Z,1715284086.868 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20240509T172031/Courier0075.lzma 2024-05-09T19:48:07.868Z,1715284087.868 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Courier0075.lzma.bak 2024-05-09T19:48:07.868Z,1715284087.868 [DataOverHttps](INFO): SBD MOMSN=20999219 2024-05-09T19:48:23.658Z,1715284103.658 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20240509T172031/Express0076.lzma 2024-05-09T19:48:24.660Z,1715284104.660 [DataOverHttps](INFO): Moved sent file to Logs/20240509T172031/Express0076.lzma.bak 2024-05-09T19:48:24.660Z,1715284104.660 [DataOverHttps](INFO): SBD MOMSN=20999221 2024-05-09T19:48:26.104Z,1715284106.104 [Default:CheckIn:Read_Iridium] Stopped 2024-05-09T19:48:26.104Z,1715284106.104 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-09T19:48:26.104Z,1715284106.104 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-09T19:48:30.535Z,1715284110.535 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-09T19:49:02.061Z,1715284142.061 [CBIT](INFO): Clearing failed state for component DropWeight 2024-05-09T19:49:02.061Z,1715284142.061 [DropWeight] No Fault, FailCount= 1 2024-05-09T19:53:26.686Z,1715284406.686 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-09T19:53:26.686Z,1715284406.686 [Default:CheckIn:C.Wait] Stopped 2024-05-09T19:53:26.686Z,1715284406.686 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-09T19:53:26.687Z,1715284406.687 [Default:CheckIn:D] Running Loop=1 2024-05-09T19:53:27.083Z,1715284407.083 [Default:CheckIn:D] Stopped 2024-05-09T19:53:27.083Z,1715284407.083 [Default:CheckIn:E] Running Loop=1 2024-05-09T19:53:27.487Z,1715284407.487 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 175.554427 min 2024-05-09T19:53:27.487Z,1715284407.487 [Default:CheckIn:E] Stopped 2024-05-09T19:53:27.487Z,1715284407.487 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-09T19:53:27.487Z,1715284407.487 [Default:CheckIn] Stopped 2024-05-09T19:53:27.488Z,1715284407.488 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T19:53:27.488Z,1715284407.488 [Default:CheckIn](INFO): Running loop #31 2024-05-09T19:53:27.488Z,1715284407.488 [Default:CheckIn] Running Loop=31 2024-05-09T19:53:27.488Z,1715284407.488 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-09T19:53:27.488Z,1715284407.488 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-09T19:53:29.095Z,1715284409.095 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-05-09T19:53:29.095Z,1715284409.095 [NAL9602] Data Fault, FailCount= 1 2024-05-09T19:53:29.095Z,1715284409.095 [NAL9602](ERROR): Data Fault 2024-05-09T19:53:29.116Z,1715284409.116 [CBIT](ERROR): Data Fault in component: NAL9602 2024-05-09T19:53:29.502Z,1715284409.502 [NAL9602](INFO): Powering down 2024-05-09T19:53:30.349Z,1715284410.349 [CBIT](INFO): Clearing failed state for component NAL9602 2024-05-09T19:53:30.349Z,1715284410.349 [NAL9602] No Fault, FailCount= 1 2024-05-09T19:53:59.839Z,1715284439.839 [NAL9602](INFO): Powering up NAL9602 2024-05-09T19:54:10.747Z,1715284450.747 [NAL9602](INFO): NAL9602 initialized 2024-05-09T19:56:04.946Z,1715284564.946 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.641830 2024-05-09T19:56:15.378Z,1715284575.378 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003139 2024-05-09T19:56:47.181Z,1715284607.181 [CBIT](INFO): Clearing failed state for component BPC1 2024-05-09T19:56:47.181Z,1715284607.181 [BPC1] No Fault, FailCount= 4 2024-05-09T19:57:24.774Z,1715284644.774 [CommandExec](IMPORTANT): got command quit 2024-05-09T19:57:25.778Z,1715284645.778 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:25.778Z,1715284645.778 [CommandExec](INFO): Uninitializing the command executive. 2024-05-09T19:57:25.778Z,1715284645.778 [CommandExec](INFO): Uninitializing the command scheduler. 2024-05-09T19:57:25.778Z,1715284645.778 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:25.840Z,1715284645.840 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-05-09T19:57:25.841Z,1715284645.841 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-05-09T19:57:25.841Z,1715284645.841 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:25.841Z,1715284645.841 [NavChartDb](INFO): Join timeout helper Thread ID is 7500 2024-05-09T19:57:26.026Z,1715284646.026 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:26.026Z,1715284646.026 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:26.045Z,1715284646.045 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-05-09T19:57:26.046Z,1715284646.046 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:26.046Z,1715284646.046 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7501 2024-05-09T19:57:26.358Z,1715284646.358 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:26.358Z,1715284646.358 [WetLabsBB2FL](INFO): Powering down 2024-05-09T19:57:26.359Z,1715284646.359 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:26.359Z,1715284646.359 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-05-09T19:57:26.359Z,1715284646.359 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:26.360Z,1715284646.360 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7502 2024-05-09T19:57:26.360Z,1715284646.360 [CTD_Seabird](INFO): Powering down 2024-05-09T19:57:26.374Z,1715284646.374 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:26.374Z,1715284646.374 [CTD_Seabird](INFO): Powering down 2024-05-09T19:57:26.386Z,1715284646.386 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:26.386Z,1715284646.386 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-05-09T19:57:26.386Z,1715284646.386 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:26.387Z,1715284646.387 [DAT](INFO): Join timeout helper Thread ID is 7503 2024-05-09T19:57:26.618Z,1715284646.618 [DAT](INFO): Powering down 2024-05-09T19:57:26.698Z,1715284646.698 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:26.699Z,1715284646.699 [DAT](INFO): Powering down 2024-05-09T19:57:26.699Z,1715284646.699 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:26.717Z,1715284646.717 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-05-09T19:57:26.718Z,1715284646.718 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:26.718Z,1715284646.718 [Radio_Surface](INFO): Join timeout helper Thread ID is 7504 2024-05-09T19:57:26.838Z,1715284646.838 [Radio_Surface](INFO): Powering down 2024-05-09T19:57:26.838Z,1715284646.838 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:26.839Z,1715284646.839 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:26.846Z,1715284646.846 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-05-09T19:57:26.846Z,1715284646.846 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:26.847Z,1715284646.847 [Onboard](INFO): Join timeout helper Thread ID is 7505 2024-05-09T19:57:27.882Z,1715284647.882 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-05-09T19:57:29.570Z,1715284649.570 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:29.570Z,1715284649.570 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:29.574Z,1715284649.574 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-05-09T19:57:29.574Z,1715284649.574 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:29.575Z,1715284649.575 [DataOverHttps](INFO): Join timeout helper Thread ID is 7506 2024-05-09T19:57:29.890Z,1715284649.890 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:29.890Z,1715284649.890 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:29.902Z,1715284649.902 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-05-09T19:57:29.902Z,1715284649.902 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:29.903Z,1715284649.903 [BackseatComponent](INFO): Join timeout helper Thread ID is 7507 2024-05-09T19:57:30.010Z,1715284650.010 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:30.010Z,1715284650.010 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.018Z,1715284650.018 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-05-09T19:57:30.018Z,1715284650.018 [logger ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.019Z,1715284650.019 [logger](INFO): Join timeout helper Thread ID is 7508 2024-05-09T19:57:30.047Z,1715284650.047 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:30.047Z,1715284650.047 [logger ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.058Z,1715284650.058 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-05-09T19:57:30.058Z,1715284650.058 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.059Z,1715284650.059 [CommandLine](INFO): Join timeout helper Thread ID is 7509 2024-05-09T19:57:30.118Z,1715284650.118 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:30.118Z,1715284650.118 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.138Z,1715284650.138 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-05-09T19:57:30.138Z,1715284650.138 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.139Z,1715284650.139 [CommandExec](INFO): Join timeout helper Thread ID is 7510 2024-05-09T19:57:30.140Z,1715284650.140 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-05-09T19:57:30.140Z,1715284650.140 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.140Z,1715284650.140 [controlThread](INFO): Join timeout helper Thread ID is 7511 2024-05-09T19:57:30.310Z,1715284650.310 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-09T19:57:30.310Z,1715284650.310 [controlThread](DEBUG): Uninitializing ControlThread 2024-05-09T19:57:30.310Z,1715284650.310 [AHRS_M2](INFO): Powering down 2024-05-09T19:57:30.382Z,1715284650.382 [NAL9602](INFO): Powering down 2024-05-09T19:57:30.384Z,1715284650.384 [Sonardyne_Nano](INFO): Powering down 2024-05-09T19:57:30.502Z,1715284650.502 [Waterlinked](INFO): Powering down 2024-05-09T19:57:30.503Z,1715284650.503 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-05-09T19:57:30.504Z,1715284650.504 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-05-09T19:57:30.505Z,1715284650.505 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-05-09T19:57:30.505Z,1715284650.505 [MissionManager](INFO): Uninitializing Mission Default 2024-05-09T19:57:30.505Z,1715284650.505 [Default] Stopped 2024-05-09T19:57:30.505Z,1715284650.505 [Default](DEBUG): Aggregate::uninitialize Default 2024-05-09T19:57:30.505Z,1715284650.505 [Default:B.GoToSurface] Stopped 2024-05-09T19:57:30.505Z,1715284650.505 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-09T19:57:30.506Z,1715284650.506 [Default:CheckIn] Stopped 2024-05-09T19:57:30.506Z,1715284650.506 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-09T19:57:30.506Z,1715284650.506 [Default:CheckIn:Read_GPS] Stopped 2024-05-09T19:57:30.508Z,1715284650.508 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-05-09T19:57:30.509Z,1715284650.509 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-05-09T19:57:30.509Z,1715284650.509 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-05-09T19:57:30.509Z,1715284650.509 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-05-09T19:57:30.510Z,1715284650.510 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-05-09T19:57:30.510Z,1715284650.510 [BuoyancyServo](INFO): Powering down 2024-05-09T19:57:30.521Z,1715284650.521 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-05-09T19:57:30.522Z,1715284650.522 [ElevatorServo](INFO): Powering down 2024-05-09T19:57:30.522Z,1715284650.522 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-05-09T19:57:30.523Z,1715284650.523 [MassServo](INFO): Powering down 2024-05-09T19:57:30.523Z,1715284650.523 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-09T19:57:30.523Z,1715284650.523 [RudderServo](INFO): Powering down 2024-05-09T19:57:30.524Z,1715284650.524 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-05-09T19:57:30.524Z,1715284650.524 [ThrusterHE](INFO): Powering down 2024-05-09T19:57:30.525Z,1715284650.525 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-05-09T19:57:30.526Z,1715284650.526 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-05-09T19:57:30.526Z,1715284650.526 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-05-09T19:57:30.526Z,1715284650.526 [CBIT](DEBUG): Powering off loads. 2024-05-09T19:57:30.537Z,1715284650.537 [CBIT](DEBUG): Disabling WDT. 2024-05-09T19:57:30.549Z,1715284650.549 [CBIT](DEBUG): Opening all GF detection circuits. 2024-05-09T19:57:30.550Z,1715284650.550 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.580Z,1715284650.580 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.586Z,1715284650.586 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.588Z,1715284650.588 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.594Z,1715284650.594 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.636Z,1715284650.636 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.687Z,1715284650.687 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.691Z,1715284650.691 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.744Z,1715284650.744 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-09T19:57:30.793Z,1715284650.793 [logger ThreadHandler](INFO): Thread cancelled.