2023-04-25T19:20:43.695Z,1682450443.695 [CommandExec](IMPORTANT): got command restart logs 2023-04-25T19:20:47.952Z,1682450447.952 [NAL9602](INFO): SBD MO Status=0, MOMSN=42673, MT Status=0, MTMSN=0 2023-04-25T19:20:47.952Z,1682450447.952 [NAL9602](INFO): No messages in MT queue 2023-04-25T19:21:18.661Z,1682450478.661 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T19:25:27.935Z,1682450727.935 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T19:25:27.935Z,1682450727.935 [Default:CheckIn:C.Wait] Stopped 2023-04-25T19:25:27.935Z,1682450727.935 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T19:25:27.936Z,1682450727.936 [Default:CheckIn:D] Running Loop=1 2023-04-25T19:25:28.347Z,1682450728.347 [Default:CheckIn:D] Stopped 2023-04-25T19:25:28.348Z,1682450728.348 [Default:CheckIn:E] Running Loop=1 2023-04-25T19:25:28.741Z,1682450728.741 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.072437 min 2023-04-25T19:25:28.741Z,1682450728.741 [Default:CheckIn:E] Stopped 2023-04-25T19:25:28.741Z,1682450728.741 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T19:25:28.741Z,1682450728.741 [Default:CheckIn] Stopped 2023-04-25T19:25:28.742Z,1682450728.742 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T19:25:28.742Z,1682450728.742 [Default:CheckIn](INFO): Running loop #10 2023-04-25T19:25:28.742Z,1682450728.742 [Default:CheckIn] Running Loop=10 2023-04-25T19:25:28.742Z,1682450728.742 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T19:25:28.742Z,1682450728.742 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T19:25:30.759Z,1682450730.759 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192526.00,A,3648.10436,N,12148.90487,W,1.050,4.86,250423,,,D*7B 2023-04-25T19:25:30.761Z,1682450730.761 [NAL9602](INFO): GPS fix at 20230425T192526: (36.801739, -121.815081) 2023-04-25T19:25:30.771Z,1682450730.771 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T19:25:30.771Z,1682450730.771 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T19:25:43.027Z,1682450743.027 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230425T170427/Courier0055.lzma 2023-04-25T19:25:44.030Z,1682450744.030 [DataOverHttps](INFO): Moved sent file to Logs/20230425T170427/Courier0055.lzma.bak 2023-04-25T19:25:44.030Z,1682450744.030 [DataOverHttps](INFO): SBD MOMSN=18265652 2023-04-25T19:25:47.722Z,1682450747.722 [NAL9602](INFO): SBD MO Status=0, MOMSN=42674, MT Status=0, MTMSN=0 2023-04-25T19:25:47.723Z,1682450747.723 [NAL9602](INFO): No messages in MT queue 2023-04-25T19:26:01.040Z,1682450761.040 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230425T192043/Courier0000.lzma 2023-04-25T19:26:02.038Z,1682450762.038 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0000.lzma.bak 2023-04-25T19:26:02.038Z,1682450762.038 [DataOverHttps](INFO): SBD MOMSN=18265655 2023-04-25T19:26:18.429Z,1682450778.429 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T19:27:06.542Z,1682450826.542 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:27:37.668Z,1682450857.668 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:28:08.812Z,1682450888.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:28:39.936Z,1682450919.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:29:03.029Z,1682450943.029 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.991241 2023-04-25T19:29:31.231Z,1682450971.231 [NAL9602](INFO): SBD MO Status=1, MOMSN=42675, MT Status=0, MTMSN=0 2023-04-25T19:29:31.280Z,1682450971.280 [NAL9602](INFO): Sent 105 bytes from file Logs/20230425T170427/Express0056.lzma 2023-04-25T19:29:31.280Z,1682450971.280 [NAL9602](INFO): Packets left to send: 0 2023-04-25T19:29:34.160Z,1682450974.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:29:39.359Z,1682450979.359 [NAL9602](INFO): SBD MO Status=1, MOMSN=42676, MT Status=0, MTMSN=0 2023-04-25T19:29:39.412Z,1682450979.412 [NAL9602](INFO): Sent 332 bytes from file Logs/20230425T192043/Express0001.lzma 2023-04-25T19:29:39.412Z,1682450979.412 [NAL9602](INFO): Packets left to send: 1 2023-04-25T19:29:52.774Z,1682450992.774 [NAL9602](INFO): SBD MO Status=1, MOMSN=42677, MT Status=0, MTMSN=0 2023-04-25T19:29:52.828Z,1682450992.828 [NAL9602](INFO): Sent 67 bytes from file Logs/20230425T192043/Express0001.lzma 2023-04-25T19:29:52.829Z,1682450992.829 [NAL9602](INFO): Packets left to send: 0 2023-04-25T19:30:01.536Z,1682451001.536 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003048 2023-04-25T19:30:14.227Z,1682451014.227 [NAL9602](INFO): SBD MO Status=2, MOMSN=42678, MT Status=2, MTMSN=0 2023-04-25T19:30:14.227Z,1682451014.227 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-25T19:30:18.123Z,1682451018.123 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20230425T192043/Express0004.lzma 2023-04-25T19:30:19.126Z,1682451019.126 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0004.lzma.bak 2023-04-25T19:30:19.126Z,1682451019.126 [DataOverHttps](INFO): SBD MOMSN=18265710 2023-04-25T19:30:20.695Z,1682451020.695 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T19:30:20.695Z,1682451020.695 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T19:30:20.695Z,1682451020.695 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T19:30:44.921Z,1682451044.921 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T19:33:24.792Z,1682451204.792 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:35:21.454Z,1682451321.454 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T19:35:21.454Z,1682451321.454 [Default:CheckIn:C.Wait] Stopped 2023-04-25T19:35:21.454Z,1682451321.454 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T19:35:21.454Z,1682451321.454 [Default:CheckIn:D] Running Loop=1 2023-04-25T19:35:21.865Z,1682451321.865 [Default:CheckIn:D] Stopped 2023-04-25T19:35:21.865Z,1682451321.865 [Default:CheckIn:E] Running Loop=1 2023-04-25T19:35:22.273Z,1682451322.273 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.964388 min 2023-04-25T19:35:22.273Z,1682451322.273 [Default:CheckIn:E] Stopped 2023-04-25T19:35:22.273Z,1682451322.273 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T19:35:22.274Z,1682451322.274 [Default:CheckIn] Stopped 2023-04-25T19:35:22.274Z,1682451322.274 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T19:35:22.274Z,1682451322.274 [Default:CheckIn](INFO): Running loop #11 2023-04-25T19:35:22.274Z,1682451322.274 [Default:CheckIn] Running Loop=11 2023-04-25T19:35:22.274Z,1682451322.274 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T19:35:22.274Z,1682451322.274 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T19:35:24.284Z,1682451324.284 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193520.00,A,3648.14542,N,12148.55791,W,0.622,219.28,250423,,,D*7F 2023-04-25T19:35:24.286Z,1682451324.286 [NAL9602](INFO): GPS fix at 20230425T193520: (36.802424, -121.809298) 2023-04-25T19:35:24.301Z,1682451324.301 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T19:35:24.301Z,1682451324.301 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T19:35:34.535Z,1682451334.535 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230425T192043/Courier0006.lzma 2023-04-25T19:35:42.056Z,1682451342.056 [NAL9602](INFO): SBD MO Status=1, MOMSN=42678, MT Status=0, MTMSN=0 2023-04-25T19:35:42.056Z,1682451342.056 [NAL9602](INFO): No messages in MT queue 2023-04-25T19:35:51.602Z,1682451351.602 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0006.lzma.bak 2023-04-25T19:35:51.602Z,1682451351.602 [DataOverHttps](INFO): SBD MOMSN=18265721 2023-04-25T19:36:12.757Z,1682451372.757 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T19:36:39.943Z,1682451399.943 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230425T192043/Express0007.lzma 2023-04-25T19:36:40.946Z,1682451400.946 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0007.lzma.bak 2023-04-25T19:36:40.946Z,1682451400.946 [DataOverHttps](INFO): SBD MOMSN=18265727 2023-04-25T19:36:42.334Z,1682451402.334 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T19:36:42.334Z,1682451402.334 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T19:36:42.334Z,1682451402.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T19:40:45.497Z,1682451645.497 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:41:16.620Z,1682451676.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:41:42.916Z,1682451702.916 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T19:41:42.917Z,1682451702.917 [Default:CheckIn:C.Wait] Stopped 2023-04-25T19:41:42.917Z,1682451702.917 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T19:41:42.917Z,1682451702.917 [Default:CheckIn:D] Running Loop=1 2023-04-25T19:41:43.306Z,1682451703.306 [Default:CheckIn:D] Stopped 2023-04-25T19:41:43.306Z,1682451703.306 [Default:CheckIn:E] Running Loop=1 2023-04-25T19:41:43.715Z,1682451703.715 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.321745 min 2023-04-25T19:41:43.715Z,1682451703.715 [Default:CheckIn:E] Stopped 2023-04-25T19:41:43.715Z,1682451703.715 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T19:41:43.715Z,1682451703.715 [Default:CheckIn] Stopped 2023-04-25T19:41:43.715Z,1682451703.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T19:41:43.715Z,1682451703.715 [Default:CheckIn](INFO): Running loop #12 2023-04-25T19:41:43.715Z,1682451703.715 [Default:CheckIn] Running Loop=12 2023-04-25T19:41:43.716Z,1682451703.716 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T19:41:43.716Z,1682451703.716 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T19:41:45.722Z,1682451705.722 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194141.00,A,3647.99956,N,12148.41434,W,3.382,29.96,250423,,,D*49 2023-04-25T19:41:45.724Z,1682451705.724 [NAL9602](INFO): GPS fix at 20230425T194141: (36.799993, -121.806906) 2023-04-25T19:41:45.735Z,1682451705.735 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T19:41:45.735Z,1682451705.735 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T19:41:47.744Z,1682451707.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:42:12.791Z,1682451732.791 [NAL9602](INFO): SBD MO Status=1, MOMSN=42679, MT Status=0, MTMSN=0 2023-04-25T19:42:12.791Z,1682451732.791 [NAL9602](INFO): No messages in MT queue 2023-04-25T19:42:18.868Z,1682451738.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:42:43.505Z,1682451763.505 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T19:42:49.992Z,1682451769.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:42:53.005Z,1682451773.005 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743942 2023-04-25T19:43:09.811Z,1682451789.811 [NAL9602](INFO): SBD MO Status=1, MOMSN=42680, MT Status=0, MTMSN=0 2023-04-25T19:43:09.864Z,1682451789.864 [NAL9602](INFO): Sent 72 bytes from file Logs/20230425T192043/Courier0009.lzma 2023-04-25T19:43:09.864Z,1682451789.864 [NAL9602](INFO): Packets left to send: 0 2023-04-25T19:43:19.537Z,1682451799.537 [NAL9602](INFO): SBD MO Status=1, MOMSN=42681, MT Status=0, MTMSN=0 2023-04-25T19:43:19.592Z,1682451799.592 [NAL9602](INFO): Sent 142 bytes from file Logs/20230425T192043/Express0010.lzma 2023-04-25T19:43:19.592Z,1682451799.592 [NAL9602](INFO): Packets left to send: 0 2023-04-25T19:43:24.128Z,1682451804.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:43:27.214Z,1682451807.214 [NAL9602](INFO): SBD MO Status=0, MOMSN=42682, MT Status=0, MTMSN=0 2023-04-25T19:43:27.301Z,1682451807.301 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T19:43:27.301Z,1682451807.301 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T19:43:27.301Z,1682451807.301 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T19:43:55.252Z,1682451835.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:43:57.916Z,1682451837.916 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T19:44:26.396Z,1682451866.396 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:44:57.520Z,1682451897.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:45:28.648Z,1682451928.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:45:59.772Z,1682451959.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:46:30.908Z,1682451990.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:47:02.032Z,1682452022.032 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:47:33.156Z,1682452053.156 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:48:04.284Z,1682452084.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:48:27.805Z,1682452107.805 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T19:48:27.805Z,1682452107.805 [Default:CheckIn:C.Wait] Stopped 2023-04-25T19:48:27.805Z,1682452107.805 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T19:48:27.805Z,1682452107.805 [Default:CheckIn:D] Running Loop=1 2023-04-25T19:48:28.201Z,1682452108.201 [Default:CheckIn:D] Stopped 2023-04-25T19:48:28.201Z,1682452108.201 [Default:CheckIn:E] Running Loop=1 2023-04-25T19:48:28.611Z,1682452108.611 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.070003 min 2023-04-25T19:48:28.611Z,1682452108.611 [Default:CheckIn:E] Stopped 2023-04-25T19:48:28.611Z,1682452108.611 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T19:48:28.611Z,1682452108.611 [Default:CheckIn] Stopped 2023-04-25T19:48:28.612Z,1682452108.612 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T19:48:28.612Z,1682452108.612 [Default:CheckIn](INFO): Running loop #13 2023-04-25T19:48:28.612Z,1682452108.612 [Default:CheckIn] Running Loop=13 2023-04-25T19:48:28.612Z,1682452108.612 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T19:48:28.612Z,1682452108.612 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T19:48:30.623Z,1682452110.623 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194826.00,A,3648.02335,N,12148.41861,W,0.447,143.63,250423,,,D*75 2023-04-25T19:48:30.625Z,1682452110.625 [NAL9602](INFO): GPS fix at 20230425T194826: (36.800389, -121.806977) 2023-04-25T19:48:30.653Z,1682452110.653 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T19:48:30.654Z,1682452110.654 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T19:48:35.472Z,1682452115.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:49:06.620Z,1682452146.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:49:08.649Z,1682452148.649 [NAL9602](INFO): SBD MO Status=2, MOMSN=42683, MT Status=2, MTMSN=0 2023-04-25T19:49:08.650Z,1682452148.650 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-25T19:49:34.962Z,1682452174.962 [NAL9602](INFO): SBD MO Status=2, MOMSN=42683, MT Status=2, MTMSN=0 2023-04-25T19:49:34.962Z,1682452174.962 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-25T19:49:37.752Z,1682452177.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:50:00.487Z,1682452200.487 [NAL9602](INFO): SBD MO Status=2, MOMSN=42683, MT Status=2, MTMSN=0 2023-04-25T19:50:00.488Z,1682452200.488 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-25T19:50:08.892Z,1682452208.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:50:29.609Z,1682452229.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=42683, MT Status=2, MTMSN=0 2023-04-25T19:50:29.609Z,1682452229.609 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-25T19:50:40.016Z,1682452240.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:50:58.735Z,1682452258.735 [NAL9602](INFO): SBD MO Status=1, MOMSN=42683, MT Status=0, MTMSN=0 2023-04-25T19:50:58.788Z,1682452258.788 [NAL9602](INFO): Sent 72 bytes from file Logs/20230425T192043/Courier0012.lzma 2023-04-25T19:50:58.788Z,1682452258.788 [NAL9602](INFO): Packets left to send: 0 2023-04-25T19:51:11.140Z,1682452271.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-25T19:51:12.510Z,1682452272.510 [NAL9602](INFO): SBD MO Status=2, MOMSN=42684, MT Status=2, MTMSN=0 2023-04-25T19:51:12.510Z,1682452272.510 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-25T19:51:23.201Z,1682452283.201 [NAL9602](INFO): SBD MO Status=1, MOMSN=42684, MT Status=0, MTMSN=0 2023-04-25T19:51:23.248Z,1682452283.248 [NAL9602](INFO): Sent 137 bytes from file Logs/20230425T192043/Express0013.lzma 2023-04-25T19:51:23.248Z,1682452283.248 [NAL9602](INFO): Packets left to send: 0 2023-04-25T19:51:32.118Z,1682452292.118 [NAL9602](INFO): SBD MO Status=0, MOMSN=42685, MT Status=0, MTMSN=0 2023-04-25T19:51:32.248Z,1682452292.248 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T19:51:32.249Z,1682452292.249 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T19:51:32.249Z,1682452292.249 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T19:51:36.844Z,1682452296.844 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003059 2023-04-25T19:52:02.824Z,1682452322.824 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T19:55:33.431Z,1682452533.431 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-04-25T19:55:38.658Z,1682452538.658 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T19:55:39.076Z,1682452539.076 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:55:51.183Z,1682452551.183 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:55:53.607Z,1682452553.607 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T19:56:02.901Z,1682452562.901 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:56:08.554Z,1682452568.554 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T19:56:15.018Z,1682452575.018 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:56:23.545Z,1682452583.545 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T19:56:27.606Z,1682452587.606 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:56:32.893Z,1682452592.893 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T19:56:32.893Z,1682452592.893 [Default:CheckIn:C.Wait] Stopped 2023-04-25T19:56:32.893Z,1682452592.893 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T19:56:32.894Z,1682452592.894 [Default:CheckIn:D] Running Loop=1 2023-04-25T19:56:33.274Z,1682452593.274 [Default:CheckIn:D] Stopped 2023-04-25T19:56:33.274Z,1682452593.274 [Default:CheckIn:E] Running Loop=1 2023-04-25T19:56:33.679Z,1682452593.679 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.154541 min 2023-04-25T19:56:33.679Z,1682452593.679 [Default:CheckIn:E] Stopped 2023-04-25T19:56:33.679Z,1682452593.679 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T19:56:33.679Z,1682452593.679 [Default:CheckIn] Stopped 2023-04-25T19:56:33.679Z,1682452593.679 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T19:56:33.679Z,1682452593.679 [Default:CheckIn](INFO): Running loop #14 2023-04-25T19:56:33.679Z,1682452593.679 [Default:CheckIn] Running Loop=14 2023-04-25T19:56:33.679Z,1682452593.679 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T19:56:33.679Z,1682452593.679 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T19:56:35.699Z,1682452595.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195631.00,A,3648.39748,N,12147.40242,W,6.881,59.61,250423,,,D*47 2023-04-25T19:56:35.702Z,1682452595.702 [NAL9602](INFO): GPS fix at 20230425T195631: (36.806625, -121.790040) 2023-04-25T19:56:35.712Z,1682452595.712 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T19:56:35.712Z,1682452595.712 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T19:56:38.517Z,1682452598.517 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T19:56:39.726Z,1682452599.726 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:56:43.427Z,1682452603.427 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230425T192043/Courier0015.lzma 2023-04-25T19:56:44.430Z,1682452604.430 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0015.lzma.bak 2023-04-25T19:56:44.430Z,1682452604.430 [DataOverHttps](INFO): SBD MOMSN=18266687 2023-04-25T19:56:51.047Z,1682452611.047 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:56:53.470Z,1682452613.470 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T19:57:03.187Z,1682452623.187 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:57:03.512Z,1682452623.512 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230425T192043/Express0016.lzma 2023-04-25T19:57:04.657Z,1682452624.657 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0016.lzma.bak 2023-04-25T19:57:04.657Z,1682452624.657 [DataOverHttps](INFO): SBD MOMSN=18266691 2023-04-25T19:57:06.040Z,1682452626.040 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T19:57:06.040Z,1682452626.040 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T19:57:06.041Z,1682452626.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T19:57:08.842Z,1682452628.842 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T19:57:15.306Z,1682452635.306 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:57:23.437Z,1682452643.437 [NAL9602](INFO): SBD MO Status=0, MOMSN=42686, MT Status=0, MTMSN=0 2023-04-25T19:57:23.438Z,1682452643.438 [NAL9602](INFO): No messages in MT queue 2023-04-25T19:57:23.791Z,1682452643.791 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T19:57:27.426Z,1682452647.426 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:57:39.142Z,1682452659.142 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T19:57:39.964Z,1682452659.964 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:57:52.070Z,1682452672.070 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T19:57:54.102Z,1682452674.102 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T20:02:06.628Z,1682452926.628 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T20:02:06.628Z,1682452926.628 [Default:CheckIn:C.Wait] Stopped 2023-04-25T20:02:06.628Z,1682452926.628 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T20:02:06.629Z,1682452926.629 [Default:CheckIn:D] Running Loop=1 2023-04-25T20:02:07.019Z,1682452927.019 [Default:CheckIn:D] Stopped 2023-04-25T20:02:07.019Z,1682452927.019 [Default:CheckIn:E] Running Loop=1 2023-04-25T20:02:07.428Z,1682452927.428 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.716960 min 2023-04-25T20:02:07.428Z,1682452927.428 [Default:CheckIn:E] Stopped 2023-04-25T20:02:07.428Z,1682452927.428 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T20:02:07.428Z,1682452927.428 [Default:CheckIn] Stopped 2023-04-25T20:02:07.428Z,1682452927.428 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T20:02:07.429Z,1682452927.429 [Default:CheckIn](INFO): Running loop #15 2023-04-25T20:02:07.429Z,1682452927.429 [Default:CheckIn] Running Loop=15 2023-04-25T20:02:07.429Z,1682452927.429 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T20:02:07.429Z,1682452927.429 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T20:02:09.455Z,1682452929.455 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200205.00,A,3648.23416,N,12147.14585,W,4.587,193.83,250423,,,D*77 2023-04-25T20:02:09.457Z,1682452929.457 [NAL9602](INFO): GPS fix at 20230425T200205: (36.803903, -121.785764) 2023-04-25T20:02:09.468Z,1682452929.468 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T20:02:09.468Z,1682452929.468 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T20:02:16.459Z,1682452936.459 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230425T192043/Courier0018.lzma 2023-04-25T20:02:17.462Z,1682452937.462 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0018.lzma.bak 2023-04-25T20:02:17.462Z,1682452937.462 [DataOverHttps](INFO): SBD MOMSN=18266806 2023-04-25T20:02:22.007Z,1682452942.007 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2023-04-25T20:02:37.267Z,1682452957.267 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230425T192043/Express0019.lzma 2023-04-25T20:02:38.182Z,1682452958.182 [NAL9602](INFO): SBD MO Status=2, MOMSN=42687, MT Status=2, MTMSN=0 2023-04-25T20:02:38.182Z,1682452958.182 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-25T20:02:38.270Z,1682452958.270 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0019.lzma.bak 2023-04-25T20:02:38.270Z,1682452958.270 [DataOverHttps](INFO): SBD MOMSN=18266816 2023-04-25T20:02:39.802Z,1682452959.802 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T20:02:39.802Z,1682452959.802 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T20:02:39.803Z,1682452959.803 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T20:02:53.126Z,1682452973.126 [NAL9602](INFO): SBD MO Status=2, MOMSN=42687, MT Status=2, MTMSN=0 2023-04-25T20:02:53.126Z,1682452973.126 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-25T20:03:03.657Z,1682452983.657 [NAL9602](INFO): SBD MO Status=0, MOMSN=42687, MT Status=0, MTMSN=0 2023-04-25T20:03:03.657Z,1682452983.657 [NAL9602](INFO): No messages in MT queue 2023-04-25T20:03:34.338Z,1682453014.338 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T20:05:02.806Z,1682453102.806 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:05:10.886Z,1682453110.886 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:05:17.754Z,1682453117.754 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:05:23.006Z,1682453123.006 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:05:32.704Z,1682453132.704 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:05:35.136Z,1682453135.136 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:05:47.650Z,1682453147.650 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:05:47.660Z,1682453147.660 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:06:00.174Z,1682453160.174 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:06:02.599Z,1682453162.599 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:06:12.294Z,1682453172.294 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:06:17.950Z,1682453177.950 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:06:23.614Z,1682453183.614 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:06:32.910Z,1682453192.910 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:06:35.738Z,1682453195.738 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:06:47.454Z,1682453207.454 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:06:47.459Z,1682453207.459 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:06:59.986Z,1682453219.986 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:07:02.814Z,1682453222.814 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:07:12.106Z,1682453232.106 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:07:17.763Z,1682453237.763 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:07:23.836Z,1682453243.836 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:07:40.414Z,1682453260.414 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T20:07:40.414Z,1682453260.414 [Default:CheckIn:C.Wait] Stopped 2023-04-25T20:07:40.414Z,1682453260.414 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T20:07:40.414Z,1682453260.414 [Default:CheckIn:D] Running Loop=1 2023-04-25T20:07:40.807Z,1682453260.807 [Default:CheckIn:D] Stopped 2023-04-25T20:07:40.807Z,1682453260.807 [Default:CheckIn:E] Running Loop=1 2023-04-25T20:07:41.211Z,1682453261.211 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.280086 min 2023-04-25T20:07:41.211Z,1682453261.211 [Default:CheckIn:E] Stopped 2023-04-25T20:07:41.211Z,1682453261.211 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T20:07:41.211Z,1682453261.211 [Default:CheckIn] Stopped 2023-04-25T20:07:41.211Z,1682453261.211 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T20:07:41.211Z,1682453261.211 [Default:CheckIn](INFO): Running loop #16 2023-04-25T20:07:41.211Z,1682453261.211 [Default:CheckIn] Running Loop=16 2023-04-25T20:07:41.212Z,1682453261.212 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T20:07:41.212Z,1682453261.212 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T20:07:43.227Z,1682453263.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200739.00,A,3648.15034,N,12147.22047,W,0.058,332.56,250423,,,A*75 2023-04-25T20:07:43.229Z,1682453263.229 [NAL9602](INFO): GPS fix at 20230425T200739: (36.802506, -121.787008) 2023-04-25T20:07:43.240Z,1682453263.240 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T20:07:43.240Z,1682453263.240 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T20:07:50.811Z,1682453270.811 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230425T192043/Courier0021.lzma 2023-04-25T20:07:51.814Z,1682453271.814 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0021.lzma.bak 2023-04-25T20:07:51.814Z,1682453271.814 [DataOverHttps](INFO): SBD MOMSN=18266826 2023-04-25T20:08:07.567Z,1682453287.567 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20230425T192043/Express0022.lzma 2023-04-25T20:08:08.570Z,1682453288.570 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0022.lzma.bak 2023-04-25T20:08:08.570Z,1682453288.570 [DataOverHttps](INFO): SBD MOMSN=18266829 2023-04-25T20:08:09.896Z,1682453289.896 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T20:08:09.896Z,1682453289.896 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T20:08:09.897Z,1682453289.897 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T20:08:30.898Z,1682453310.898 [NAL9602](INFO): SBD MO Status=0, MOMSN=42688, MT Status=0, MTMSN=0 2023-04-25T20:08:30.898Z,1682453310.898 [NAL9602](INFO): No messages in MT queue 2023-04-25T20:09:01.625Z,1682453341.625 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T20:10:00.182Z,1682453400.182 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2023-04-25T20:10:30.488Z,1682453430.488 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2023-04-25T20:13:10.499Z,1682453590.499 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T20:13:10.499Z,1682453590.499 [Default:CheckIn:C.Wait] Stopped 2023-04-25T20:13:10.499Z,1682453590.499 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T20:13:10.500Z,1682453590.500 [Default:CheckIn:D] Running Loop=1 2023-04-25T20:13:10.897Z,1682453590.897 [Default:CheckIn:D] Stopped 2023-04-25T20:13:10.897Z,1682453590.897 [Default:CheckIn:E] Running Loop=1 2023-04-25T20:13:11.299Z,1682453591.299 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.781592 min 2023-04-25T20:13:11.299Z,1682453591.299 [Default:CheckIn:E] Stopped 2023-04-25T20:13:11.299Z,1682453591.299 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T20:13:11.299Z,1682453591.299 [Default:CheckIn] Stopped 2023-04-25T20:13:11.300Z,1682453591.300 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T20:13:11.300Z,1682453591.300 [Default:CheckIn](INFO): Running loop #17 2023-04-25T20:13:11.300Z,1682453591.300 [Default:CheckIn] Running Loop=17 2023-04-25T20:13:11.300Z,1682453591.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T20:13:11.300Z,1682453591.300 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T20:13:13.315Z,1682453593.315 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201309.00,A,3648.15068,N,12147.22354,W,0.058,271.21,250423,,,A*7D 2023-04-25T20:13:13.317Z,1682453593.317 [NAL9602](INFO): GPS fix at 20230425T201309: (36.802511, -121.787059) 2023-04-25T20:13:13.327Z,1682453593.327 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T20:13:13.327Z,1682453593.327 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T20:13:20.323Z,1682453600.323 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230425T192043/Courier0024.lzma 2023-04-25T20:13:21.326Z,1682453601.326 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0024.lzma.bak 2023-04-25T20:13:21.326Z,1682453601.326 [DataOverHttps](INFO): SBD MOMSN=18266840 2023-04-25T20:13:37.159Z,1682453617.159 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230425T192043/Express0025.lzma 2023-04-25T20:13:37.951Z,1682453617.951 [NAL9602](INFO): SBD MO Status=0, MOMSN=42689, MT Status=0, MTMSN=0 2023-04-25T20:13:37.951Z,1682453617.951 [NAL9602](INFO): No messages in MT queue 2023-04-25T20:13:38.162Z,1682453618.162 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0025.lzma.bak 2023-04-25T20:13:38.162Z,1682453618.162 [DataOverHttps](INFO): SBD MOMSN=18266843 2023-04-25T20:13:39.631Z,1682453619.631 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T20:13:39.631Z,1682453619.631 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T20:13:39.631Z,1682453619.631 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T20:14:08.716Z,1682453648.716 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T20:14:32.490Z,1682453672.490 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:14:48.246Z,1682453688.246 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:14:49.472Z,1682453689.472 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-04-25T20:14:55.115Z,1682453695.115 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:15:03.601Z,1682453703.601 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:15:07.638Z,1682453707.638 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:15:18.950Z,1682453718.950 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:15:19.772Z,1682453719.772 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:15:32.282Z,1682453732.282 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:15:34.304Z,1682453734.304 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:15:44.405Z,1682453744.405 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:15:49.255Z,1682453749.255 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:15:56.119Z,1682453756.119 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:16:04.602Z,1682453764.602 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:16:08.238Z,1682453768.238 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:16:19.954Z,1682453779.954 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:16:19.960Z,1682453779.960 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:16:31.266Z,1682453791.266 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:16:34.912Z,1682453794.912 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-04-25T20:16:42.983Z,1682453802.983 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:16:49.851Z,1682453809.851 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-04-25T20:16:55.103Z,1682453815.103 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:17:06.820Z,1682453826.820 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-04-25T20:18:40.163Z,1682453920.163 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T20:18:40.163Z,1682453920.163 [Default:CheckIn:C.Wait] Stopped 2023-04-25T20:18:40.163Z,1682453920.163 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T20:18:40.164Z,1682453920.164 [Default:CheckIn:D] Running Loop=1 2023-04-25T20:18:40.562Z,1682453920.562 [Default:CheckIn:D] Stopped 2023-04-25T20:18:40.563Z,1682453920.563 [Default:CheckIn:E] Running Loop=1 2023-04-25T20:18:40.962Z,1682453920.962 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.276017 min 2023-04-25T20:18:40.962Z,1682453920.962 [Default:CheckIn:E] Stopped 2023-04-25T20:18:40.962Z,1682453920.962 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T20:18:40.962Z,1682453920.962 [Default:CheckIn] Stopped 2023-04-25T20:18:40.963Z,1682453920.963 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T20:18:40.963Z,1682453920.963 [Default:CheckIn](INFO): Running loop #18 2023-04-25T20:18:40.963Z,1682453920.963 [Default:CheckIn] Running Loop=18 2023-04-25T20:18:40.963Z,1682453920.963 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T20:18:40.963Z,1682453920.963 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T20:18:42.975Z,1682453922.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201839.00,A,3648.16816,N,12147.25230,W,2.760,290.01,250423,,,A*70 2023-04-25T20:18:42.977Z,1682453922.977 [NAL9602](INFO): GPS fix at 20230425T201839: (36.802803, -121.787538) 2023-04-25T20:18:43.011Z,1682453923.011 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T20:18:43.011Z,1682453923.011 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T20:18:50.043Z,1682453930.043 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230425T192043/Courier0027.lzma 2023-04-25T20:18:51.046Z,1682453931.046 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0027.lzma.bak 2023-04-25T20:18:51.046Z,1682453931.046 [DataOverHttps](INFO): SBD MOMSN=18266851 2023-04-25T20:19:09.735Z,1682453949.735 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230425T192043/Express0028.lzma 2023-04-25T20:19:10.738Z,1682453950.738 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0028.lzma.bak 2023-04-25T20:19:10.738Z,1682453950.738 [DataOverHttps](INFO): SBD MOMSN=18266854 2023-04-25T20:19:12.136Z,1682453952.136 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T20:19:12.136Z,1682453952.136 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T20:19:12.136Z,1682453952.136 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T20:19:12.926Z,1682453952.926 [NAL9602](INFO): SBD MO Status=0, MOMSN=42690, MT Status=0, MTMSN=0 2023-04-25T20:19:12.927Z,1682453952.927 [NAL9602](INFO): No messages in MT queue 2023-04-25T20:19:43.688Z,1682453983.688 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T20:21:21.165Z,1682454081.165 [DataOverHttps](IMPORTANT): SBD MTMSN=20230425T202116 2023-04-25T20:21:28.491Z,1682454088.491 [DataOverHttps](INFO): Received command: strobe off 2023-04-25T20:21:28.569Z,1682454088.569 [CommandExec](IMPORTANT): got command strobe off 2023-04-25T20:21:28.569Z,1682454088.569 [CommandExec](IMPORTANT): Deactivating strobe 2023-04-25T20:21:37.567Z,1682454097.567 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5. 2023-04-25T20:21:37.570Z,1682454097.570 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2023-04-25T20:24:12.801Z,1682454252.801 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T20:24:12.801Z,1682454252.801 [Default:CheckIn:C.Wait] Stopped 2023-04-25T20:24:12.801Z,1682454252.801 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T20:24:12.802Z,1682454252.802 [Default:CheckIn:D] Running Loop=1 2023-04-25T20:24:13.206Z,1682454253.206 [Default:CheckIn:D] Stopped 2023-04-25T20:24:13.206Z,1682454253.206 [Default:CheckIn:E] Running Loop=1 2023-04-25T20:24:13.615Z,1682454253.615 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.820085 min 2023-04-25T20:24:13.615Z,1682454253.615 [Default:CheckIn:E] Stopped 2023-04-25T20:24:13.615Z,1682454253.615 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T20:24:13.615Z,1682454253.615 [Default:CheckIn] Stopped 2023-04-25T20:24:13.616Z,1682454253.616 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T20:24:13.616Z,1682454253.616 [Default:CheckIn](INFO): Running loop #19 2023-04-25T20:24:13.616Z,1682454253.616 [Default:CheckIn] Running Loop=19 2023-04-25T20:24:13.616Z,1682454253.616 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T20:24:13.616Z,1682454253.616 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T20:24:15.622Z,1682454255.622 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202411.00,A,3648.16607,N,12147.28325,W,0.117,317.03,250423,,,A*7B 2023-04-25T20:24:15.624Z,1682454255.624 [NAL9602](INFO): GPS fix at 20230425T202411: (36.802768, -121.788054) 2023-04-25T20:24:15.635Z,1682454255.635 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T20:24:15.635Z,1682454255.635 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T20:24:25.720Z,1682454265.720 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230425T192043/Courier0030.lzma 2023-04-25T20:24:26.722Z,1682454266.722 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0030.lzma.bak 2023-04-25T20:24:26.722Z,1682454266.722 [DataOverHttps](INFO): SBD MOMSN=18266862 2023-04-25T20:24:44.032Z,1682454284.032 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20230425T192043/Express0031.lzma 2023-04-25T20:24:45.034Z,1682454285.034 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0031.lzma.bak 2023-04-25T20:24:45.034Z,1682454285.034 [DataOverHttps](INFO): SBD MOMSN=18266865 2023-04-25T20:25:04.151Z,1682454304.151 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20230425T192043/Express0034.lzma 2023-04-25T20:25:05.154Z,1682454305.154 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0034.lzma.bak 2023-04-25T20:25:05.154Z,1682454305.154 [DataOverHttps](INFO): SBD MOMSN=18266871 2023-04-25T20:25:06.633Z,1682454306.633 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T20:25:06.633Z,1682454306.633 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T20:25:06.633Z,1682454306.633 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T20:27:38.515Z,1682454458.515 [NAL9602](INFO): SBD MO Status=2, MOMSN=42691, MT Status=2, MTMSN=0 2023-04-25T20:27:38.515Z,1682454458.515 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-25T20:29:17.502Z,1682454557.502 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-25T20:29:48.207Z,1682454588.207 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T20:30:07.195Z,1682454607.195 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T20:30:07.195Z,1682454607.195 [Default:CheckIn:C.Wait] Stopped 2023-04-25T20:30:07.195Z,1682454607.195 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T20:30:07.196Z,1682454607.196 [Default:CheckIn:D] Running Loop=1 2023-04-25T20:30:07.609Z,1682454607.609 [Default:CheckIn:D] Stopped 2023-04-25T20:30:07.609Z,1682454607.609 [Default:CheckIn:E] Running Loop=1 2023-04-25T20:30:08.005Z,1682454608.005 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.726799 min 2023-04-25T20:30:08.005Z,1682454608.005 [Default:CheckIn:E] Stopped 2023-04-25T20:30:08.005Z,1682454608.005 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T20:30:08.005Z,1682454608.005 [Default:CheckIn] Stopped 2023-04-25T20:30:08.005Z,1682454608.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T20:30:08.006Z,1682454608.006 [Default:CheckIn](INFO): Running loop #20 2023-04-25T20:30:08.006Z,1682454608.006 [Default:CheckIn] Running Loop=20 2023-04-25T20:30:08.006Z,1682454608.006 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T20:30:08.006Z,1682454608.006 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T20:30:10.019Z,1682454610.019 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203006.00,A,3648.16559,N,12147.28268,W,0.156,317.03,250423,,,A*7D 2023-04-25T20:30:10.021Z,1682454610.021 [NAL9602](INFO): GPS fix at 20230425T203006: (36.802760, -121.788045) 2023-04-25T20:30:10.032Z,1682454610.032 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T20:30:10.032Z,1682454610.032 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T20:30:17.032Z,1682454617.032 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230425T192043/Courier0036.lzma 2023-04-25T20:30:18.034Z,1682454618.034 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0036.lzma.bak 2023-04-25T20:30:18.034Z,1682454618.034 [DataOverHttps](INFO): SBD MOMSN=18266902 2023-04-25T20:30:38.896Z,1682454638.896 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20230425T192043/Express0037.lzma 2023-04-25T20:30:39.898Z,1682454639.898 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0037.lzma.bak 2023-04-25T20:30:39.898Z,1682454639.898 [DataOverHttps](INFO): SBD MOMSN=18266905 2023-04-25T20:30:40.793Z,1682454640.793 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-04-25T20:30:40.872Z,1682454640.872 [NAL9602](ERROR): received: +CSQ:0 OK691, 2, 0, 0, 0 OK 2023-04-25T20:30:41.241Z,1682454641.241 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T20:30:41.241Z,1682454641.241 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T20:30:41.241Z,1682454641.241 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T20:34:50.129Z,1682454890.129 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-04-25T20:34:50.129Z,1682454890.129 [DropWeight] Hardware Fault, FailCount= 1 2023-04-25T20:34:50.129Z,1682454890.129 [DropWeight](ERROR): Hardware Fault 2023-04-25T20:34:50.155Z,1682454890.155 [CommandExec](FAULT): Scheduling is paused 2023-04-25T20:34:50.192Z,1682454890.192 [CBIT](INFO): Critical error at 20230425T203450 2023-04-25T20:34:50.194Z,1682454890.194 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-04-25T20:34:50.195Z,1682454890.195 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-04-25T20:34:50.636Z,1682454890.636 [CBIT](INFO): Critical error at 20230425T203450 2023-04-25T20:35:12.748Z,1682454912.748 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-25T20:35:41.846Z,1682454941.846 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T20:35:41.846Z,1682454941.846 [Default:CheckIn:C.Wait] Stopped 2023-04-25T20:35:41.846Z,1682454941.846 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T20:35:41.846Z,1682454941.846 [Default:CheckIn:D] Running Loop=1 2023-04-25T20:35:42.257Z,1682454942.257 [Default:CheckIn:D] Stopped 2023-04-25T20:35:42.257Z,1682454942.257 [Default:CheckIn:E] Running Loop=1 2023-04-25T20:35:42.653Z,1682454942.653 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.304264 min 2023-04-25T20:35:42.653Z,1682454942.653 [Default:CheckIn:E] Stopped 2023-04-25T20:35:42.653Z,1682454942.653 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T20:35:42.654Z,1682454942.654 [Default:CheckIn] Stopped 2023-04-25T20:35:42.654Z,1682454942.654 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T20:35:42.654Z,1682454942.654 [Default:CheckIn](INFO): Running loop #21 2023-04-25T20:35:42.654Z,1682454942.654 [Default:CheckIn] Running Loop=21 2023-04-25T20:35:42.654Z,1682454942.654 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T20:35:42.654Z,1682454942.654 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T20:35:44.670Z,1682454944.670 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203540.00,A,3648.16598,N,12147.28401,W,0.272,0.00,250423,,,A*7D 2023-04-25T20:35:44.673Z,1682454944.673 [NAL9602](INFO): GPS fix at 20230425T203540: (36.802766, -121.788067) 2023-04-25T20:35:44.683Z,1682454944.683 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T20:35:44.683Z,1682454944.683 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T20:35:52.287Z,1682454952.287 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20230425T192043/Courier0039.lzma 2023-04-25T20:35:53.290Z,1682454953.290 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0039.lzma.bak 2023-04-25T20:35:53.290Z,1682454953.290 [DataOverHttps](INFO): SBD MOMSN=18266963 2023-04-25T20:36:09.191Z,1682454969.191 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20230425T192043/Express0040.lzma 2023-04-25T20:36:10.190Z,1682454970.190 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0040.lzma.bak 2023-04-25T20:36:10.190Z,1682454970.190 [DataOverHttps](INFO): SBD MOMSN=18266968 2023-04-25T20:36:11.756Z,1682454971.756 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T20:36:11.756Z,1682454971.756 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T20:36:11.756Z,1682454971.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T20:36:16.581Z,1682454976.581 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-25T20:41:12.345Z,1682455272.345 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-25T20:41:12.345Z,1682455272.345 [Default:CheckIn:C.Wait] Stopped 2023-04-25T20:41:12.345Z,1682455272.345 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T20:41:12.345Z,1682455272.345 [Default:CheckIn:D] Running Loop=1 2023-04-25T20:41:12.743Z,1682455272.743 [Default:CheckIn:D] Stopped 2023-04-25T20:41:12.743Z,1682455272.743 [Default:CheckIn:E] Running Loop=1 2023-04-25T20:41:13.147Z,1682455273.147 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 135.812362 min 2023-04-25T20:41:13.147Z,1682455273.147 [Default:CheckIn:E] Stopped 2023-04-25T20:41:13.147Z,1682455273.147 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-25T20:41:13.147Z,1682455273.147 [Default:CheckIn] Stopped 2023-04-25T20:41:13.147Z,1682455273.147 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T20:41:13.147Z,1682455273.147 [Default:CheckIn](INFO): Running loop #22 2023-04-25T20:41:13.148Z,1682455273.148 [Default:CheckIn] Running Loop=22 2023-04-25T20:41:13.148Z,1682455273.148 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-25T20:41:13.148Z,1682455273.148 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-25T20:41:15.155Z,1682455275.155 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204111.00,A,3648.17432,N,12147.26033,W,2.508,352.08,250423,,,A*75 2023-04-25T20:41:15.157Z,1682455275.157 [NAL9602](INFO): GPS fix at 20230425T204111: (36.802905, -121.787672) 2023-04-25T20:41:15.168Z,1682455275.168 [Default:CheckIn:Read_GPS] Stopped 2023-04-25T20:41:15.168Z,1682455275.168 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-25T20:41:22.967Z,1682455282.967 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230425T192043/Courier0042.lzma 2023-04-25T20:41:23.970Z,1682455283.970 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Courier0042.lzma.bak 2023-04-25T20:41:23.970Z,1682455283.970 [DataOverHttps](INFO): SBD MOMSN=18266974 2023-04-25T20:41:39.747Z,1682455299.747 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230425T192043/Express0043.lzma 2023-04-25T20:41:40.750Z,1682455300.750 [DataOverHttps](INFO): Moved sent file to Logs/20230425T192043/Express0043.lzma.bak 2023-04-25T20:41:40.750Z,1682455300.750 [DataOverHttps](INFO): SBD MOMSN=18266977 2023-04-25T20:41:42.245Z,1682455302.245 [Default:CheckIn:Read_Iridium] Stopped 2023-04-25T20:41:42.245Z,1682455302.245 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-25T20:41:42.245Z,1682455302.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-25T20:41:45.861Z,1682455305.861 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-04-25T20:41:45.944Z,1682455305.944 [NAL9602](ERROR): received: +CSQ:0 OK691, 2, 0, 0, 0 OK 2023-04-25T20:42:13.604Z,1682455333.604 [CommandExec](IMPORTANT): got command quit 2023-04-25T20:42:14.608Z,1682455334.608 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-25T20:42:14.608Z,1682455334.608 [CommandExec](INFO): Uninitializing the command executive. 2023-04-25T20:42:14.608Z,1682455334.608 [CommandExec](INFO): Uninitializing the command scheduler. 2023-04-25T20:42:14.608Z,1682455334.608 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:14.688Z,1682455334.688 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-04-25T20:42:14.688Z,1682455334.688 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-04-25T20:42:14.688Z,1682455334.688 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:14.689Z,1682455334.689 [NavChartDb](INFO): Join timeout helper Thread ID is 2460 2023-04-25T20:42:14.740Z,1682455334.740 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-25T20:42:14.741Z,1682455334.741 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:14.748Z,1682455334.748 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-04-25T20:42:14.748Z,1682455334.748 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:14.749Z,1682455334.749 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2461 2023-04-25T20:42:14.820Z,1682455334.820 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-25T20:42:14.820Z,1682455334.820 [WetLabsBB2FL](INFO): Powering down 2023-04-25T20:42:14.821Z,1682455334.821 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:14.836Z,1682455334.836 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-04-25T20:42:14.836Z,1682455334.836 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:14.836Z,1682455334.836 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2462 2023-04-25T20:42:14.972Z,1682455334.972 [CTD_Seabird](INFO): Powering down 2023-04-25T20:42:14.984Z,1682455334.984 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-25T20:42:14.984Z,1682455334.984 [CTD_Seabird](INFO): Powering down 2023-04-25T20:42:14.996Z,1682455334.996 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:15.008Z,1682455335.008 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-04-25T20:42:15.008Z,1682455335.008 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:15.009Z,1682455335.009 [Radio_Surface](INFO): Join timeout helper Thread ID is 2463 2023-04-25T20:42:15.228Z,1682455335.228 [Radio_Surface](INFO): Powering down 2023-04-25T20:42:15.229Z,1682455335.229 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-25T20:42:15.229Z,1682455335.229 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:15.240Z,1682455335.240 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-04-25T20:42:15.240Z,1682455335.240 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:15.240Z,1682455335.240 [Onboard](INFO): Join timeout helper Thread ID is 2464 2023-04-25T20:42:15.804Z,1682455335.804 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-25T20:42:15.804Z,1682455335.804 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:15.809Z,1682455335.809 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-04-25T20:42:15.809Z,1682455335.809 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:15.809Z,1682455335.809 [DataOverHttps](INFO): Join timeout helper Thread ID is 2465 2023-04-25T20:42:17.896Z,1682455337.896 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-25T20:42:17.899Z,1682455337.899 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:17.905Z,1682455337.905 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-04-25T20:42:17.905Z,1682455337.905 [logger ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:17.905Z,1682455337.905 [logger](INFO): Join timeout helper Thread ID is 2466 2023-04-25T20:42:17.924Z,1682455337.924 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-25T20:42:17.924Z,1682455337.924 [logger ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:17.944Z,1682455337.944 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-04-25T20:42:17.945Z,1682455337.945 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:17.945Z,1682455337.945 [CommandLine](INFO): Join timeout helper Thread ID is 2467 2023-04-25T20:42:17.964Z,1682455337.964 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-25T20:42:17.964Z,1682455337.964 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:17.984Z,1682455337.984 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-04-25T20:42:17.985Z,1682455337.985 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:17.985Z,1682455337.985 [CommandExec](INFO): Join timeout helper Thread ID is 2468 2023-04-25T20:42:17.986Z,1682455337.986 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-04-25T20:42:17.986Z,1682455337.986 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:17.987Z,1682455337.987 [controlThread](INFO): Join timeout helper Thread ID is 2469 2023-04-25T20:42:18.160Z,1682455338.160 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-25T20:42:18.160Z,1682455338.160 [controlThread](DEBUG): Uninitializing ControlThread 2023-04-25T20:42:18.161Z,1682455338.161 [AHRS_M2](INFO): Powering down 2023-04-25T20:42:18.233Z,1682455338.233 [NAL9602](INFO): Powering down 2023-04-25T20:42:18.235Z,1682455338.235 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-04-25T20:42:18.236Z,1682455338.236 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-04-25T20:42:18.237Z,1682455338.237 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-04-25T20:42:18.237Z,1682455338.237 [MissionManager](INFO): Uninitializing Mission Default 2023-04-25T20:42:18.237Z,1682455338.237 [Default] Stopped 2023-04-25T20:42:18.237Z,1682455338.237 [Default](DEBUG): Aggregate::uninitialize Default 2023-04-25T20:42:18.237Z,1682455338.237 [Default:B.GoToSurface] Stopped 2023-04-25T20:42:18.238Z,1682455338.238 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-04-25T20:42:18.238Z,1682455338.238 [Default:CheckIn] Stopped 2023-04-25T20:42:18.238Z,1682455338.238 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-25T20:42:18.238Z,1682455338.238 [Default:CheckIn:C.Wait] Stopped 2023-04-25T20:42:18.238Z,1682455338.238 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-25T20:42:18.241Z,1682455338.241 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-04-25T20:42:18.241Z,1682455338.241 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-04-25T20:42:18.241Z,1682455338.241 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-04-25T20:42:18.242Z,1682455338.242 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-04-25T20:42:18.242Z,1682455338.242 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-04-25T20:42:18.242Z,1682455338.242 [BuoyancyServo](INFO): Powering down 2023-04-25T20:42:18.256Z,1682455338.256 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-04-25T20:42:18.256Z,1682455338.256 [ElevatorServo](INFO): Powering down 2023-04-25T20:42:18.257Z,1682455338.257 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-04-25T20:42:18.257Z,1682455338.257 [MassServo](INFO): Powering down 2023-04-25T20:42:18.258Z,1682455338.258 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-04-25T20:42:18.258Z,1682455338.258 [RudderServo](INFO): Powering down 2023-04-25T20:42:18.259Z,1682455338.259 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-04-25T20:42:18.259Z,1682455338.259 [ThrusterServo](INFO): Powering down 2023-04-25T20:42:18.260Z,1682455338.260 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-04-25T20:42:18.260Z,1682455338.260 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-04-25T20:42:18.260Z,1682455338.260 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-04-25T20:42:18.260Z,1682455338.260 [CBIT](DEBUG): Powering off loads. 2023-04-25T20:42:18.272Z,1682455338.272 [CBIT](DEBUG): Disabling WDT. 2023-04-25T20:42:18.284Z,1682455338.284 [CBIT](DEBUG): Opening all GF detection circuits. 2023-04-25T20:42:18.285Z,1682455338.285 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:18.341Z,1682455338.341 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:18.345Z,1682455338.345 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:18.351Z,1682455338.351 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:18.423Z,1682455338.423 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:18.427Z,1682455338.427 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:18.467Z,1682455338.467 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-25T20:42:18.545Z,1682455338.545 [logger ThreadHandler](INFO): Thread cancelled.