2023-03-20T20:06:22.618Z,1679342782.618 [CommandExec](IMPORTANT): got command restart logs 2023-03-20T20:06:53.192Z,1679342813.192 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T20:10:10.464Z,1679343010.464 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T20:10:10.464Z,1679343010.464 [Default:CheckIn:C.Wait] Stopped 2023-03-20T20:10:10.464Z,1679343010.464 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T20:10:10.464Z,1679343010.464 [Default:CheckIn:D] Running Loop=1 2023-03-20T20:10:10.836Z,1679343010.836 [Default:CheckIn:D] Stopped 2023-03-20T20:10:10.836Z,1679343010.836 [Default:CheckIn:E] Running Loop=1 2023-03-20T20:10:11.250Z,1679343011.250 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.118040 min 2023-03-20T20:10:11.250Z,1679343011.250 [Default:CheckIn:E] Stopped 2023-03-20T20:10:11.250Z,1679343011.250 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T20:10:11.250Z,1679343011.250 [Default:CheckIn] Stopped 2023-03-20T20:10:11.250Z,1679343011.250 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T20:10:11.250Z,1679343011.250 [Default:CheckIn](INFO): Running loop #4 2023-03-20T20:10:11.250Z,1679343011.250 [Default:CheckIn] Running Loop=4 2023-03-20T20:10:11.251Z,1679343011.251 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T20:10:11.251Z,1679343011.251 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T20:10:13.252Z,1679343013.252 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201014.00,A,3648.06155,N,12147.69362,W,18.097,46.32,200323,,,D*7C 2023-03-20T20:10:13.254Z,1679343013.254 [NAL9602](INFO): GPS fix at 20230320T201014: (36.801026, -121.794894) 2023-03-20T20:10:13.302Z,1679343013.302 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T20:10:13.302Z,1679343013.302 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T20:10:23.708Z,1679343023.708 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230320T184615/Courier0025.lzma 2023-03-20T20:10:24.714Z,1679343024.714 [DataOverHttps](INFO): Moved sent file to Logs/20230320T184615/Courier0025.lzma.bak 2023-03-20T20:10:24.715Z,1679343024.715 [DataOverHttps](INFO): SBD MOMSN=17533999 2023-03-20T20:10:39.567Z,1679343039.567 [NAL9602](INFO): SBD MO Status=2, MOMSN=39515, MT Status=2, MTMSN=0 2023-03-20T20:10:39.568Z,1679343039.568 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T20:10:40.728Z,1679343040.728 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20230320T200622/Courier0000.lzma 2023-03-20T20:10:41.730Z,1679343041.730 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0000.lzma.bak 2023-03-20T20:10:41.731Z,1679343041.731 [DataOverHttps](INFO): SBD MOMSN=17534010 2023-03-20T20:10:57.384Z,1679343057.384 [NAL9602](INFO): SBD MO Status=0, MOMSN=39515, MT Status=0, MTMSN=0 2023-03-20T20:10:57.384Z,1679343057.384 [NAL9602](INFO): No messages in MT queue 2023-03-20T20:11:01.376Z,1679343061.376 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20230320T184615/Express0026.lzma 2023-03-20T20:11:02.378Z,1679343062.378 [DataOverHttps](INFO): Moved sent file to Logs/20230320T184615/Express0026.lzma.bak 2023-03-20T20:11:02.379Z,1679343062.379 [DataOverHttps](INFO): SBD MOMSN=17534014 2023-03-20T20:11:18.317Z,1679343078.317 [DataOverHttps](INFO): Sending 401 bytes from file Logs/20230320T200622/Express0001.lzma 2023-03-20T20:11:19.319Z,1679343079.319 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0001.lzma.bak 2023-03-20T20:11:19.319Z,1679343079.319 [DataOverHttps](INFO): SBD MOMSN=17534017 2023-03-20T20:11:20.839Z,1679343080.839 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T20:11:20.839Z,1679343080.839 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T20:11:20.839Z,1679343080.839 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T20:11:28.090Z,1679343088.090 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T20:16:21.404Z,1679343381.404 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T20:16:21.404Z,1679343381.404 [Default:CheckIn:C.Wait] Stopped 2023-03-20T20:16:21.404Z,1679343381.404 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T20:16:21.405Z,1679343381.405 [Default:CheckIn:D] Running Loop=1 2023-03-20T20:16:21.830Z,1679343381.830 [Default:CheckIn:D] Stopped 2023-03-20T20:16:21.830Z,1679343381.830 [Default:CheckIn:E] Running Loop=1 2023-03-20T20:16:22.269Z,1679343382.269 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.301278 min 2023-03-20T20:16:22.269Z,1679343382.269 [Default:CheckIn:E] Stopped 2023-03-20T20:16:22.269Z,1679343382.269 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T20:16:22.269Z,1679343382.269 [Default:CheckIn] Stopped 2023-03-20T20:16:22.269Z,1679343382.269 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T20:16:22.269Z,1679343382.269 [Default:CheckIn](INFO): Running loop #5 2023-03-20T20:16:22.269Z,1679343382.269 [Default:CheckIn] Running Loop=5 2023-03-20T20:16:22.270Z,1679343382.270 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T20:16:22.270Z,1679343382.270 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T20:16:24.228Z,1679343384.228 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201625.00,A,3648.28925,N,12147.13055,W,4.898,192.29,200323,,,D*79 2023-03-20T20:16:24.230Z,1679343384.230 [NAL9602](INFO): GPS fix at 20230320T201625: (36.804821, -121.785509) 2023-03-20T20:16:24.242Z,1679343384.242 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T20:16:24.242Z,1679343384.242 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T20:16:32.632Z,1679343392.632 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T200622/Courier0003.lzma 2023-03-20T20:16:33.634Z,1679343393.634 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0003.lzma.bak 2023-03-20T20:16:33.634Z,1679343393.634 [DataOverHttps](INFO): SBD MOMSN=17534022 2023-03-20T20:16:52.269Z,1679343412.269 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20230320T200622/Express0004.lzma 2023-03-20T20:16:53.270Z,1679343413.270 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0004.lzma.bak 2023-03-20T20:16:53.271Z,1679343413.271 [DataOverHttps](INFO): SBD MOMSN=17534025 2023-03-20T20:16:54.598Z,1679343414.598 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T20:16:54.598Z,1679343414.598 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T20:16:54.599Z,1679343414.599 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T20:16:54.975Z,1679343414.975 [NAL9602](INFO): SBD MO Status=2, MOMSN=39516, MT Status=2, MTMSN=0 2023-03-20T20:16:54.976Z,1679343414.976 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T20:17:41.035Z,1679343461.035 [NAL9602](INFO): SBD MO Status=0, MOMSN=39516, MT Status=0, MTMSN=0 2023-03-20T20:17:41.036Z,1679343461.036 [NAL9602](INFO): No messages in MT queue 2023-03-20T20:18:11.734Z,1679343491.734 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T20:21:55.160Z,1679343715.160 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T20:21:55.161Z,1679343715.161 [Default:CheckIn:C.Wait] Stopped 2023-03-20T20:21:55.161Z,1679343715.161 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T20:21:55.161Z,1679343715.161 [Default:CheckIn:D] Running Loop=1 2023-03-20T20:21:55.571Z,1679343715.571 [Default:CheckIn:D] Stopped 2023-03-20T20:21:55.571Z,1679343715.571 [Default:CheckIn:E] Running Loop=1 2023-03-20T20:21:55.976Z,1679343715.976 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.863619 min 2023-03-20T20:21:55.976Z,1679343715.976 [Default:CheckIn:E] Stopped 2023-03-20T20:21:55.976Z,1679343715.976 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T20:21:55.976Z,1679343715.976 [Default:CheckIn] Stopped 2023-03-20T20:21:55.976Z,1679343715.976 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T20:21:55.976Z,1679343715.976 [Default:CheckIn](INFO): Running loop #6 2023-03-20T20:21:55.976Z,1679343715.976 [Default:CheckIn] Running Loop=6 2023-03-20T20:21:55.977Z,1679343715.977 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T20:21:55.977Z,1679343715.977 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T20:21:57.978Z,1679343717.978 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202159.00,A,3648.15117,N,12147.22104,W,0.505,120.82,200323,,,A*76 2023-03-20T20:21:57.981Z,1679343717.981 [NAL9602](INFO): GPS fix at 20230320T202159: (36.802520, -121.787017) 2023-03-20T20:21:58.015Z,1679343718.015 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T20:21:58.015Z,1679343718.015 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T20:22:05.044Z,1679343725.044 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T200622/Courier0006.lzma 2023-03-20T20:22:06.046Z,1679343726.046 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0006.lzma.bak 2023-03-20T20:22:06.046Z,1679343726.046 [DataOverHttps](INFO): SBD MOMSN=17534029 2023-03-20T20:22:22.779Z,1679343742.779 [NAL9602](INFO): SBD MO Status=0, MOMSN=39517, MT Status=0, MTMSN=0 2023-03-20T20:22:22.779Z,1679343742.779 [NAL9602](INFO): No messages in MT queue 2023-03-20T20:22:24.560Z,1679343744.560 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230320T200622/Express0007.lzma 2023-03-20T20:22:25.563Z,1679343745.563 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0007.lzma.bak 2023-03-20T20:22:25.563Z,1679343745.563 [DataOverHttps](INFO): SBD MOMSN=17534032 2023-03-20T20:22:26.862Z,1679343746.862 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T20:22:26.862Z,1679343746.862 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T20:22:26.863Z,1679343746.863 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T20:22:53.483Z,1679343773.483 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T20:27:27.400Z,1679344047.400 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T20:27:27.400Z,1679344047.400 [Default:CheckIn:C.Wait] Stopped 2023-03-20T20:27:27.400Z,1679344047.400 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T20:27:27.401Z,1679344047.401 [Default:CheckIn:D] Running Loop=1 2023-03-20T20:27:27.815Z,1679344047.815 [Default:CheckIn:D] Stopped 2023-03-20T20:27:27.815Z,1679344047.815 [Default:CheckIn:E] Running Loop=1 2023-03-20T20:27:28.207Z,1679344048.207 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.401017 min 2023-03-20T20:27:28.208Z,1679344048.208 [Default:CheckIn:E] Stopped 2023-03-20T20:27:28.208Z,1679344048.208 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T20:27:28.208Z,1679344048.208 [Default:CheckIn] Stopped 2023-03-20T20:27:28.208Z,1679344048.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T20:27:28.208Z,1679344048.208 [Default:CheckIn](INFO): Running loop #7 2023-03-20T20:27:28.208Z,1679344048.208 [Default:CheckIn] Running Loop=7 2023-03-20T20:27:28.208Z,1679344048.208 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T20:27:28.208Z,1679344048.208 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T20:27:30.224Z,1679344050.224 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202731.00,A,3648.15060,N,12147.22041,W,0.078,120.82,200323,,,D*75 2023-03-20T20:27:30.226Z,1679344050.226 [NAL9602](INFO): GPS fix at 20230320T202731: (36.802510, -121.787007) 2023-03-20T20:27:30.262Z,1679344050.262 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T20:27:30.262Z,1679344050.262 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T20:27:37.848Z,1679344057.848 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230320T200622/Courier0009.lzma 2023-03-20T20:27:39.169Z,1679344059.169 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0009.lzma.bak 2023-03-20T20:27:39.169Z,1679344059.169 [DataOverHttps](INFO): SBD MOMSN=17534036 2023-03-20T20:27:54.832Z,1679344074.832 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230320T200622/Express0010.lzma 2023-03-20T20:27:55.834Z,1679344075.834 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0010.lzma.bak 2023-03-20T20:27:55.835Z,1679344075.835 [DataOverHttps](INFO): SBD MOMSN=17534039 2023-03-20T20:27:57.314Z,1679344077.314 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T20:27:57.314Z,1679344077.314 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T20:27:57.315Z,1679344077.315 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T20:28:00.123Z,1679344080.123 [NAL9602](INFO): SBD MO Status=2, MOMSN=39518, MT Status=2, MTMSN=0 2023-03-20T20:28:00.124Z,1679344080.124 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T20:29:39.096Z,1679344179.096 [NAL9602](INFO): SBD MO Status=0, MOMSN=39518, MT Status=0, MTMSN=0 2023-03-20T20:29:39.096Z,1679344179.096 [NAL9602](INFO): No messages in MT queue 2023-03-20T20:30:09.798Z,1679344209.798 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T20:32:57.897Z,1679344377.897 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T20:32:57.897Z,1679344377.897 [Default:CheckIn:C.Wait] Stopped 2023-03-20T20:32:57.897Z,1679344377.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T20:32:57.897Z,1679344377.897 [Default:CheckIn:D] Running Loop=1 2023-03-20T20:32:58.297Z,1679344378.297 [Default:CheckIn:D] Stopped 2023-03-20T20:32:58.297Z,1679344378.297 [Default:CheckIn:E] Running Loop=1 2023-03-20T20:32:58.705Z,1679344378.705 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.909045 min 2023-03-20T20:32:58.706Z,1679344378.706 [Default:CheckIn:E] Stopped 2023-03-20T20:32:58.706Z,1679344378.706 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T20:32:58.706Z,1679344378.706 [Default:CheckIn] Stopped 2023-03-20T20:32:58.706Z,1679344378.706 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T20:32:58.706Z,1679344378.706 [Default:CheckIn](INFO): Running loop #8 2023-03-20T20:32:58.706Z,1679344378.706 [Default:CheckIn] Running Loop=8 2023-03-20T20:32:58.706Z,1679344378.706 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T20:32:58.706Z,1679344378.706 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T20:33:00.716Z,1679344380.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203301.00,A,3648.15967,N,12147.24891,W,2.546,26.54,200323,,,D*48 2023-03-20T20:33:00.726Z,1679344380.726 [NAL9602](INFO): GPS fix at 20230320T203301: (36.802661, -121.787482) 2023-03-20T20:33:00.738Z,1679344380.738 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T20:33:00.738Z,1679344380.738 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T20:33:08.512Z,1679344388.512 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T200622/Courier0012.lzma 2023-03-20T20:33:09.515Z,1679344389.515 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0012.lzma.bak 2023-03-20T20:33:09.515Z,1679344389.515 [DataOverHttps](INFO): SBD MOMSN=17534049 2023-03-20T20:33:18.088Z,1679344398.088 [NAL9602](INFO): SBD MO Status=0, MOMSN=39519, MT Status=0, MTMSN=0 2023-03-20T20:33:18.088Z,1679344398.088 [NAL9602](INFO): No messages in MT queue 2023-03-20T20:33:25.314Z,1679344405.314 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230320T200622/Express0013.lzma 2023-03-20T20:33:26.314Z,1679344406.314 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0013.lzma.bak 2023-03-20T20:33:26.315Z,1679344406.315 [DataOverHttps](INFO): SBD MOMSN=17534052 2023-03-20T20:33:27.798Z,1679344407.797 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T20:33:27.798Z,1679344407.798 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T20:33:27.798Z,1679344407.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T20:33:48.786Z,1679344428.786 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T20:38:28.378Z,1679344708.378 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T20:38:28.378Z,1679344708.378 [Default:CheckIn:C.Wait] Stopped 2023-03-20T20:38:28.378Z,1679344708.378 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T20:38:28.379Z,1679344708.379 [Default:CheckIn:D] Running Loop=1 2023-03-20T20:38:28.796Z,1679344708.796 [Default:CheckIn:D] Stopped 2023-03-20T20:38:28.796Z,1679344708.796 [Default:CheckIn:E] Running Loop=1 2023-03-20T20:38:29.182Z,1679344709.182 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.417371 min 2023-03-20T20:38:29.182Z,1679344709.182 [Default:CheckIn:E] Stopped 2023-03-20T20:38:29.182Z,1679344709.182 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T20:38:29.182Z,1679344709.182 [Default:CheckIn] Stopped 2023-03-20T20:38:29.182Z,1679344709.182 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T20:38:29.182Z,1679344709.182 [Default:CheckIn](INFO): Running loop #9 2023-03-20T20:38:29.182Z,1679344709.182 [Default:CheckIn] Running Loop=9 2023-03-20T20:38:29.182Z,1679344709.182 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T20:38:29.182Z,1679344709.182 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T20:38:31.192Z,1679344711.192 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203832.00,A,3648.16803,N,12147.28128,W,0.467,222.87,200323,,,D*7C 2023-03-20T20:38:31.194Z,1679344711.194 [NAL9602](INFO): GPS fix at 20230320T203832: (36.802801, -121.788021) 2023-03-20T20:38:31.206Z,1679344711.206 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T20:38:31.206Z,1679344711.206 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T20:38:38.776Z,1679344718.776 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230320T200622/Courier0015.lzma 2023-03-20T20:38:39.778Z,1679344719.778 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0015.lzma.bak 2023-03-20T20:38:39.778Z,1679344719.778 [DataOverHttps](INFO): SBD MOMSN=17534056 2023-03-20T20:38:58.756Z,1679344738.756 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230320T200622/Express0016.lzma 2023-03-20T20:38:59.759Z,1679344739.759 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0016.lzma.bak 2023-03-20T20:38:59.760Z,1679344739.760 [DataOverHttps](INFO): SBD MOMSN=17534059 2023-03-20T20:39:01.180Z,1679344741.180 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T20:39:01.182Z,1679344741.182 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T20:39:01.183Z,1679344741.183 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T20:41:41.136Z,1679344901.136 [NAL9602](INFO): SBD MO Status=0, MOMSN=39520, MT Status=0, MTMSN=0 2023-03-20T20:41:41.136Z,1679344901.136 [NAL9602](INFO): No messages in MT queue 2023-03-20T20:42:11.842Z,1679344931.842 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T20:44:01.757Z,1679345041.757 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T20:44:01.757Z,1679345041.757 [Default:CheckIn:C.Wait] Stopped 2023-03-20T20:44:01.757Z,1679345041.757 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T20:44:01.757Z,1679345041.757 [Default:CheckIn:D] Running Loop=1 2023-03-20T20:44:02.171Z,1679345042.171 [Default:CheckIn:D] Stopped 2023-03-20T20:44:02.171Z,1679345042.171 [Default:CheckIn:E] Running Loop=1 2023-03-20T20:44:02.563Z,1679345042.563 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.973621 min 2023-03-20T20:44:02.563Z,1679345042.563 [Default:CheckIn:E] Stopped 2023-03-20T20:44:02.563Z,1679345042.563 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T20:44:02.564Z,1679345042.564 [Default:CheckIn] Stopped 2023-03-20T20:44:02.564Z,1679345042.564 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T20:44:02.564Z,1679345042.564 [Default:CheckIn](INFO): Running loop #10 2023-03-20T20:44:02.564Z,1679345042.564 [Default:CheckIn] Running Loop=10 2023-03-20T20:44:02.564Z,1679345042.564 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T20:44:02.564Z,1679345042.564 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T20:44:04.568Z,1679345044.568 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204405.00,A,3648.17104,N,12147.28120,W,0.253,222.87,200323,,,A*70 2023-03-20T20:44:04.570Z,1679345044.570 [NAL9602](INFO): GPS fix at 20230320T204405: (36.802851, -121.788020) 2023-03-20T20:44:04.582Z,1679345044.582 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T20:44:04.582Z,1679345044.582 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T20:44:11.720Z,1679345051.720 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T200622/Courier0018.lzma 2023-03-20T20:44:12.757Z,1679345052.757 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0018.lzma.bak 2023-03-20T20:44:12.758Z,1679345052.758 [DataOverHttps](INFO): SBD MOMSN=17534062 2023-03-20T20:44:32.188Z,1679345072.188 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230320T200622/Express0019.lzma 2023-03-20T20:44:33.190Z,1679345073.190 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0019.lzma.bak 2023-03-20T20:44:33.190Z,1679345073.190 [DataOverHttps](INFO): SBD MOMSN=17534066 2023-03-20T20:44:34.518Z,1679345074.518 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T20:44:34.519Z,1679345074.519 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T20:44:34.519Z,1679345074.519 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T20:46:43.622Z,1679345203.622 [DataOverHttps](IMPORTANT): SBD MTMSN=20230320T204644 2023-03-20T20:46:54.313Z,1679345214.313 [DataOverHttps](INFO): Received command: strobe off 2023-03-20T20:46:54.362Z,1679345214.362 [CommandExec](IMPORTANT): got command strobe off 2023-03-20T20:46:54.362Z,1679345214.362 [CommandExec](IMPORTANT): Deactivating strobe 2023-03-20T20:49:06.962Z,1679345346.962 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-03-20T20:49:35.257Z,1679345375.257 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T20:49:35.257Z,1679345375.257 [Default:CheckIn:C.Wait] Stopped 2023-03-20T20:49:35.257Z,1679345375.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T20:49:35.257Z,1679345375.257 [Default:CheckIn:D] Running Loop=1 2023-03-20T20:49:35.661Z,1679345375.661 [Default:CheckIn:D] Stopped 2023-03-20T20:49:35.661Z,1679345375.661 [Default:CheckIn:E] Running Loop=1 2023-03-20T20:49:36.063Z,1679345376.063 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.531791 min 2023-03-20T20:49:36.063Z,1679345376.063 [Default:CheckIn:E] Stopped 2023-03-20T20:49:36.064Z,1679345376.064 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T20:49:36.064Z,1679345376.064 [Default:CheckIn] Stopped 2023-03-20T20:49:36.064Z,1679345376.064 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T20:49:36.064Z,1679345376.064 [Default:CheckIn](INFO): Running loop #11 2023-03-20T20:49:36.064Z,1679345376.064 [Default:CheckIn] Running Loop=11 2023-03-20T20:49:36.064Z,1679345376.064 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T20:49:36.064Z,1679345376.064 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T20:49:38.071Z,1679345378.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204939.00,A,3648.16937,N,12147.27914,W,0.544,178.29,200323,,,A*72 2023-03-20T20:49:38.073Z,1679345378.073 [NAL9602](INFO): GPS fix at 20230320T204939: (36.802823, -121.787986) 2023-03-20T20:49:38.104Z,1679345378.104 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T20:49:38.104Z,1679345378.104 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T20:49:45.256Z,1679345385.256 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T200622/Courier0021.lzma 2023-03-20T20:49:46.258Z,1679345386.258 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0021.lzma.bak 2023-03-20T20:49:46.259Z,1679345386.259 [DataOverHttps](INFO): SBD MOMSN=17534071 2023-03-20T20:50:01.917Z,1679345401.917 [DataOverHttps](INFO): Sending 324 bytes from file Logs/20230320T200622/Express0022.lzma 2023-03-20T20:50:02.918Z,1679345402.918 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0022.lzma.bak 2023-03-20T20:50:02.919Z,1679345402.919 [DataOverHttps](INFO): SBD MOMSN=17534074 2023-03-20T20:50:10.818Z,1679345410.818 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T20:50:22.044Z,1679345422.044 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20230320T200622/Express0023.lzma 2023-03-20T20:50:23.050Z,1679345423.050 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0023.lzma.bak 2023-03-20T20:50:23.051Z,1679345423.051 [DataOverHttps](INFO): SBD MOMSN=17534081 2023-03-20T20:50:24.670Z,1679345424.670 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T20:50:24.670Z,1679345424.670 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T20:50:24.670Z,1679345424.670 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T20:55:25.323Z,1679345725.323 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T20:55:25.323Z,1679345725.323 [Default:CheckIn:C.Wait] Stopped 2023-03-20T20:55:25.324Z,1679345725.324 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T20:55:25.324Z,1679345725.324 [Default:CheckIn:D] Running Loop=1 2023-03-20T20:55:25.713Z,1679345725.713 [Default:CheckIn:D] Stopped 2023-03-20T20:55:25.713Z,1679345725.713 [Default:CheckIn:E] Running Loop=1 2023-03-20T20:55:26.127Z,1679345726.127 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.365983 min 2023-03-20T20:55:26.127Z,1679345726.127 [Default:CheckIn:E] Stopped 2023-03-20T20:55:26.127Z,1679345726.127 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T20:55:26.127Z,1679345726.127 [Default:CheckIn] Stopped 2023-03-20T20:55:26.127Z,1679345726.127 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T20:55:26.127Z,1679345726.127 [Default:CheckIn](INFO): Running loop #12 2023-03-20T20:55:26.127Z,1679345726.127 [Default:CheckIn] Running Loop=12 2023-03-20T20:55:26.128Z,1679345726.128 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T20:55:26.128Z,1679345726.128 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T20:55:28.130Z,1679345728.130 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205529.00,A,3648.16621,N,12147.28149,W,0.136,350.35,200323,,,A*7D 2023-03-20T20:55:28.132Z,1679345728.132 [NAL9602](INFO): GPS fix at 20230320T205529: (36.802770, -121.788025) 2023-03-20T20:55:28.143Z,1679345728.143 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T20:55:28.143Z,1679345728.143 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T20:55:35.140Z,1679345735.140 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T200622/Courier0026.lzma 2023-03-20T20:55:36.142Z,1679345736.142 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0026.lzma.bak 2023-03-20T20:55:36.142Z,1679345736.142 [DataOverHttps](INFO): SBD MOMSN=17534090 2023-03-20T20:55:52.000Z,1679345752.000 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230320T200622/Express0028.lzma 2023-03-20T20:55:53.002Z,1679345753.002 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0028.lzma.bak 2023-03-20T20:55:53.003Z,1679345753.003 [DataOverHttps](INFO): SBD MOMSN=17534093 2023-03-20T20:55:54.483Z,1679345754.483 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T20:55:54.483Z,1679345754.483 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T20:55:54.483Z,1679345754.483 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T20:57:30.215Z,1679345850.215 [NAL9602](INFO): SBD MO Status=2, MOMSN=39521, MT Status=2, MTMSN=0 2023-03-20T20:57:30.216Z,1679345850.216 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T20:58:39.300Z,1679345919.300 [NAL9602](INFO): SBD MO Status=0, MOMSN=39521, MT Status=0, MTMSN=0 2023-03-20T20:58:39.300Z,1679345919.300 [NAL9602](INFO): No messages in MT queue 2023-03-20T20:59:10.006Z,1679345950.006 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T21:00:55.053Z,1679346055.053 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T21:00:55.053Z,1679346055.053 [Default:CheckIn:C.Wait] Stopped 2023-03-20T21:00:55.054Z,1679346055.054 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T21:00:55.054Z,1679346055.054 [Default:CheckIn:D] Running Loop=1 2023-03-20T21:00:55.478Z,1679346055.478 [Default:CheckIn:D] Stopped 2023-03-20T21:00:55.478Z,1679346055.478 [Default:CheckIn:E] Running Loop=1 2023-03-20T21:00:55.865Z,1679346055.865 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.862069 min 2023-03-20T21:00:55.865Z,1679346055.865 [Default:CheckIn:E] Stopped 2023-03-20T21:00:55.865Z,1679346055.865 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T21:00:55.865Z,1679346055.865 [Default:CheckIn] Stopped 2023-03-20T21:00:55.865Z,1679346055.865 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T21:00:55.866Z,1679346055.866 [Default:CheckIn](INFO): Running loop #13 2023-03-20T21:00:55.866Z,1679346055.866 [Default:CheckIn] Running Loop=13 2023-03-20T21:00:55.866Z,1679346055.866 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T21:00:55.866Z,1679346055.866 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T21:00:57.876Z,1679346057.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210058.00,A,3648.16650,N,12147.28222,W,0.156,350.35,200323,,,A*74 2023-03-20T21:00:57.878Z,1679346057.878 [NAL9602](INFO): GPS fix at 20230320T210058: (36.802775, -121.788037) 2023-03-20T21:00:57.890Z,1679346057.890 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T21:00:57.890Z,1679346057.890 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T21:01:05.216Z,1679346065.216 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T200622/Courier0030.lzma 2023-03-20T21:01:06.218Z,1679346066.218 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0030.lzma.bak 2023-03-20T21:01:06.219Z,1679346066.219 [DataOverHttps](INFO): SBD MOMSN=17534097 2023-03-20T21:01:16.860Z,1679346076.860 [NAL9602](INFO): SBD MO Status=2, MOMSN=39522, MT Status=2, MTMSN=0 2023-03-20T21:01:16.860Z,1679346076.860 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T21:01:22.584Z,1679346082.584 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230320T200622/Express0031.lzma 2023-03-20T21:01:23.587Z,1679346083.587 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0031.lzma.bak 2023-03-20T21:01:23.587Z,1679346083.587 [DataOverHttps](INFO): SBD MOMSN=17534100 2023-03-20T21:01:24.974Z,1679346084.974 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T21:01:24.974Z,1679346084.974 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T21:01:24.974Z,1679346084.974 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T21:05:27.801Z,1679346327.801 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2023-03-20T21:06:00.517Z,1679346360.517 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-03-20T21:06:06.979Z,1679346366.979 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-03-20T21:06:08.191Z,1679346368.191 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-20T21:06:12.234Z,1679346372.234 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-03-20T21:06:23.951Z,1679346383.951 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-20T21:06:24.367Z,1679346384.367 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-03-20T21:06:25.602Z,1679346385.602 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T21:06:25.602Z,1679346385.602 [Default:CheckIn:C.Wait] Stopped 2023-03-20T21:06:25.602Z,1679346385.602 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T21:06:25.602Z,1679346385.602 [Default:CheckIn:D] Running Loop=1 2023-03-20T21:06:25.989Z,1679346385.989 [Default:CheckIn:D] Stopped 2023-03-20T21:06:25.989Z,1679346385.989 [Default:CheckIn:E] Running Loop=1 2023-03-20T21:06:26.389Z,1679346386.389 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.370589 min 2023-03-20T21:06:26.389Z,1679346386.389 [Default:CheckIn:E] Stopped 2023-03-20T21:06:26.389Z,1679346386.389 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T21:06:26.389Z,1679346386.389 [Default:CheckIn] Stopped 2023-03-20T21:06:26.390Z,1679346386.390 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T21:06:26.390Z,1679346386.390 [Default:CheckIn](INFO): Running loop #14 2023-03-20T21:06:26.390Z,1679346386.390 [Default:CheckIn] Running Loop=14 2023-03-20T21:06:26.390Z,1679346386.390 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T21:06:26.390Z,1679346386.390 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T21:06:28.395Z,1679346388.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210629.00,A,3648.16626,N,12147.28214,W,0.058,350.35,200323,,,A*7F 2023-03-20T21:06:28.397Z,1679346388.397 [NAL9602](INFO): GPS fix at 20230320T210629: (36.802771, -121.788036) 2023-03-20T21:06:28.409Z,1679346388.409 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T21:06:28.409Z,1679346388.409 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T21:06:35.908Z,1679346395.908 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T200622/Courier0033.lzma 2023-03-20T21:06:36.875Z,1679346396.875 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-03-20T21:06:36.911Z,1679346396.911 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0033.lzma.bak 2023-03-20T21:06:36.911Z,1679346396.911 [DataOverHttps](INFO): SBD MOMSN=17534103 2023-03-20T21:06:39.702Z,1679346399.702 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-20T21:06:49.011Z,1679346409.011 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-03-20T21:06:55.107Z,1679346415.107 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-20T21:06:55.608Z,1679346415.608 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230320T200622/Express0034.lzma 2023-03-20T21:06:56.611Z,1679346416.611 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0034.lzma.bak 2023-03-20T21:06:56.611Z,1679346416.611 [DataOverHttps](INFO): SBD MOMSN=17534106 2023-03-20T21:06:57.951Z,1679346417.951 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T21:06:57.951Z,1679346417.951 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T21:06:57.951Z,1679346417.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T21:07:00.774Z,1679346420.774 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T21:07:10.463Z,1679346430.463 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-20T21:07:25.409Z,1679346445.409 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-20T21:07:40.759Z,1679346460.759 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-20T21:07:56.531Z,1679346476.531 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-20T21:08:11.463Z,1679346491.463 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-20T21:08:26.411Z,1679346506.411 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-03-20T21:08:36.520Z,1679346516.520 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5. 2023-03-20T21:08:36.523Z,1679346516.523 [BPC1](INFO): Received data from all battery sticks. 2023-03-20T21:11:58.535Z,1679346718.535 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T21:11:58.535Z,1679346718.535 [Default:CheckIn:C.Wait] Stopped 2023-03-20T21:11:58.535Z,1679346718.535 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T21:11:58.535Z,1679346718.535 [Default:CheckIn:D] Running Loop=1 2023-03-20T21:11:58.948Z,1679346718.948 [Default:CheckIn:D] Stopped 2023-03-20T21:11:58.948Z,1679346718.948 [Default:CheckIn:E] Running Loop=1 2023-03-20T21:11:59.333Z,1679346719.333 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.919906 min 2023-03-20T21:11:59.333Z,1679346719.333 [Default:CheckIn:E] Stopped 2023-03-20T21:11:59.333Z,1679346719.333 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T21:11:59.333Z,1679346719.333 [Default:CheckIn] Stopped 2023-03-20T21:11:59.333Z,1679346719.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T21:11:59.333Z,1679346719.333 [Default:CheckIn](INFO): Running loop #15 2023-03-20T21:11:59.333Z,1679346719.333 [Default:CheckIn] Running Loop=15 2023-03-20T21:11:59.333Z,1679346719.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T21:11:59.334Z,1679346719.334 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T21:12:01.344Z,1679346721.344 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211202.00,A,3648.16736,N,12147.28076,W,0.097,210.40,200323,,,A*71 2023-03-20T21:12:01.346Z,1679346721.346 [NAL9602](INFO): GPS fix at 20230320T211202: (36.802789, -121.788013) 2023-03-20T21:12:01.385Z,1679346721.385 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T21:12:01.385Z,1679346721.385 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T21:12:09.160Z,1679346729.160 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230320T200622/Courier0036.lzma 2023-03-20T21:12:10.162Z,1679346730.162 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0036.lzma.bak 2023-03-20T21:12:10.163Z,1679346730.163 [DataOverHttps](INFO): SBD MOMSN=17534111 2023-03-20T21:12:29.560Z,1679346749.560 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230320T200622/Express0037.lzma 2023-03-20T21:12:30.562Z,1679346750.562 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0037.lzma.bak 2023-03-20T21:12:30.563Z,1679346750.563 [DataOverHttps](INFO): SBD MOMSN=17534114 2023-03-20T21:12:32.081Z,1679346752.081 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-03-20T21:12:32.165Z,1679346752.165 [NAL9602](ERROR): received: +CSQ:0 OK522, 2, 0, 0, 0 OK 2023-03-20T21:12:32.183Z,1679346752.183 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T21:12:32.183Z,1679346752.183 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T21:12:32.183Z,1679346752.183 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T21:16:22.771Z,1679346982.771 [NAL9602](INFO): SBD MO Status=2, MOMSN=39522, MT Status=2, MTMSN=0 2023-03-20T21:16:22.771Z,1679346982.771 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T21:16:33.681Z,1679346993.681 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-03-20T21:16:33.681Z,1679346993.681 [DropWeight] Hardware Fault, FailCount= 1 2023-03-20T21:16:33.682Z,1679346993.682 [DropWeight](ERROR): Hardware Fault 2023-03-20T21:16:33.700Z,1679346993.700 [CommandExec](FAULT): Scheduling is paused 2023-03-20T21:16:33.701Z,1679346993.701 [CBIT](INFO): Critical error at 20230320T211633 2023-03-20T21:16:33.703Z,1679346993.703 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-03-20T21:16:33.704Z,1679346993.704 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-03-20T21:16:34.102Z,1679346994.102 [CBIT](INFO): Critical error at 20230320T211633 2023-03-20T21:16:53.883Z,1679347013.883 [NAL9602](INFO): SBD MO Status=2, MOMSN=39522, MT Status=2, MTMSN=0 2023-03-20T21:16:53.883Z,1679347013.883 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T21:17:18.528Z,1679347038.528 [NAL9602](INFO): SBD MO Status=2, MOMSN=39522, MT Status=2, MTMSN=0 2023-03-20T21:17:18.528Z,1679347038.528 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T21:17:18.926Z,1679347038.926 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-03-20T21:17:32.672Z,1679347052.672 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T21:17:32.672Z,1679347052.672 [Default:CheckIn:C.Wait] Stopped 2023-03-20T21:17:32.672Z,1679347052.672 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T21:17:32.672Z,1679347052.672 [Default:CheckIn:D] Running Loop=1 2023-03-20T21:17:33.081Z,1679347053.081 [Default:CheckIn:D] Stopped 2023-03-20T21:17:33.081Z,1679347053.081 [Default:CheckIn:E] Running Loop=1 2023-03-20T21:17:33.495Z,1679347053.495 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.488786 min 2023-03-20T21:17:33.495Z,1679347053.495 [Default:CheckIn:E] Stopped 2023-03-20T21:17:33.495Z,1679347053.495 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T21:17:33.495Z,1679347053.495 [Default:CheckIn] Stopped 2023-03-20T21:17:33.495Z,1679347053.495 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T21:17:33.496Z,1679347053.496 [Default:CheckIn](INFO): Running loop #16 2023-03-20T21:17:33.496Z,1679347053.496 [Default:CheckIn] Running Loop=16 2023-03-20T21:17:33.496Z,1679347053.496 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T21:17:33.496Z,1679347053.496 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T21:17:35.496Z,1679347055.496 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211736.00,A,3648.16570,N,12147.28637,W,0.019,210.40,200323,,,A*76 2023-03-20T21:17:35.498Z,1679347055.498 [NAL9602](INFO): GPS fix at 20230320T211736: (36.802762, -121.788106) 2023-03-20T21:17:35.532Z,1679347055.532 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T21:17:35.532Z,1679347055.532 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T21:17:42.768Z,1679347062.768 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20230320T200622/Courier0039.lzma 2023-03-20T21:17:43.770Z,1679347063.770 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0039.lzma.bak 2023-03-20T21:17:43.770Z,1679347063.770 [DataOverHttps](INFO): SBD MOMSN=17534117 2023-03-20T21:17:59.580Z,1679347079.580 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230320T200622/Express0040.lzma 2023-03-20T21:18:00.582Z,1679347080.582 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0040.lzma.bak 2023-03-20T21:18:00.583Z,1679347080.583 [DataOverHttps](INFO): SBD MOMSN=17534122 2023-03-20T21:18:01.771Z,1679347081.771 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T21:18:01.771Z,1679347081.771 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T21:18:01.771Z,1679347081.771 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T21:18:07.819Z,1679347087.819 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T21:23:02.362Z,1679347382.362 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T21:23:02.362Z,1679347382.362 [Default:CheckIn:C.Wait] Stopped 2023-03-20T21:23:02.362Z,1679347382.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T21:23:02.363Z,1679347382.363 [Default:CheckIn:D] Running Loop=1 2023-03-20T21:23:02.778Z,1679347382.778 [Default:CheckIn:D] Stopped 2023-03-20T21:23:02.778Z,1679347382.778 [Default:CheckIn:E] Running Loop=1 2023-03-20T21:23:03.173Z,1679347383.173 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.983732 min 2023-03-20T21:23:03.173Z,1679347383.173 [Default:CheckIn:E] Stopped 2023-03-20T21:23:03.173Z,1679347383.173 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T21:23:03.173Z,1679347383.173 [Default:CheckIn] Stopped 2023-03-20T21:23:03.173Z,1679347383.173 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T21:23:03.173Z,1679347383.173 [Default:CheckIn](INFO): Running loop #17 2023-03-20T21:23:03.174Z,1679347383.174 [Default:CheckIn] Running Loop=17 2023-03-20T21:23:03.174Z,1679347383.174 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T21:23:03.174Z,1679347383.174 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T21:23:05.186Z,1679347385.186 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212306.00,A,3648.16943,N,12147.29052,W,0.097,210.40,200323,,,A*7C 2023-03-20T21:23:05.188Z,1679347385.188 [NAL9602](INFO): GPS fix at 20230320T212306: (36.802824, -121.788175) 2023-03-20T21:23:05.199Z,1679347385.199 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T21:23:05.200Z,1679347385.200 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T21:23:12.604Z,1679347392.604 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T200622/Courier0042.lzma 2023-03-20T21:23:13.606Z,1679347393.606 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0042.lzma.bak 2023-03-20T21:23:13.607Z,1679347393.607 [DataOverHttps](INFO): SBD MOMSN=17534127 2023-03-20T21:23:30.228Z,1679347410.228 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230320T200622/Express0043.lzma 2023-03-20T21:23:31.231Z,1679347411.231 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0043.lzma.bak 2023-03-20T21:23:31.231Z,1679347411.231 [DataOverHttps](INFO): SBD MOMSN=17534130 2023-03-20T21:23:32.679Z,1679347412.679 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T21:23:32.679Z,1679347412.679 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T21:23:32.679Z,1679347412.679 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T21:27:09.223Z,1679347629.223 [NAL9602](INFO): SBD MO Status=2, MOMSN=39522, MT Status=2, MTMSN=0 2023-03-20T21:27:09.224Z,1679347629.224 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T21:27:34.675Z,1679347654.675 [NAL9602](INFO): SBD MO Status=2, MOMSN=39522, MT Status=2, MTMSN=0 2023-03-20T21:27:34.676Z,1679347654.676 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T21:28:12.655Z,1679347692.655 [NAL9602](INFO): SBD MO Status=2, MOMSN=39522, MT Status=2, MTMSN=0 2023-03-20T21:28:12.656Z,1679347692.656 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-20T21:28:13.055Z,1679347693.055 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-03-20T21:28:33.267Z,1679347713.267 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T21:28:33.267Z,1679347713.267 [Default:CheckIn:C.Wait] Stopped 2023-03-20T21:28:33.267Z,1679347713.267 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T21:28:33.267Z,1679347713.267 [Default:CheckIn:D] Running Loop=1 2023-03-20T21:28:33.680Z,1679347713.680 [Default:CheckIn:D] Stopped 2023-03-20T21:28:33.681Z,1679347713.681 [Default:CheckIn:E] Running Loop=1 2023-03-20T21:28:34.068Z,1679347714.068 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.498779 min 2023-03-20T21:28:34.068Z,1679347714.068 [Default:CheckIn:E] Stopped 2023-03-20T21:28:34.068Z,1679347714.068 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T21:28:34.068Z,1679347714.068 [Default:CheckIn] Stopped 2023-03-20T21:28:34.068Z,1679347714.068 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T21:28:34.069Z,1679347714.069 [Default:CheckIn](INFO): Running loop #18 2023-03-20T21:28:34.069Z,1679347714.069 [Default:CheckIn] Running Loop=18 2023-03-20T21:28:34.069Z,1679347714.069 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T21:28:34.069Z,1679347714.069 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T21:28:36.090Z,1679347716.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212837.00,A,3648.16766,N,12147.28196,W,0.175,0.00,200323,,,A*7E 2023-03-20T21:28:36.092Z,1679347716.092 [NAL9602](INFO): GPS fix at 20230320T212837: (36.802794, -121.788033) 2023-03-20T21:28:36.105Z,1679347716.105 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T21:28:36.105Z,1679347716.105 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T21:28:44.076Z,1679347724.076 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230320T200622/Courier0045.lzma 2023-03-20T21:28:45.078Z,1679347725.078 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0045.lzma.bak 2023-03-20T21:28:45.079Z,1679347725.079 [DataOverHttps](INFO): SBD MOMSN=17534133 2023-03-20T21:29:03.892Z,1679347743.892 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230320T200622/Express0046.lzma 2023-03-20T21:29:04.895Z,1679347744.895 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0046.lzma.bak 2023-03-20T21:29:04.895Z,1679347744.895 [DataOverHttps](INFO): SBD MOMSN=17534136 2023-03-20T21:29:06.453Z,1679347746.453 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T21:29:06.454Z,1679347746.454 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T21:29:06.454Z,1679347746.454 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T21:29:08.876Z,1679347748.876 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T21:31:34.012Z,1679347894.012 [CBIT](INFO): Clearing failed state for component DropWeight 2023-03-20T21:31:34.012Z,1679347894.012 [DropWeight] No Fault, FailCount= 1 2023-03-20T21:34:07.053Z,1679348047.053 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T21:34:07.053Z,1679348047.053 [Default:CheckIn:C.Wait] Stopped 2023-03-20T21:34:07.053Z,1679348047.053 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T21:34:07.054Z,1679348047.054 [Default:CheckIn:D] Running Loop=1 2023-03-20T21:34:07.444Z,1679348047.444 [Default:CheckIn:D] Stopped 2023-03-20T21:34:07.444Z,1679348047.444 [Default:CheckIn:E] Running Loop=1 2023-03-20T21:34:07.849Z,1679348047.849 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.061507 min 2023-03-20T21:34:07.849Z,1679348047.849 [Default:CheckIn:E] Stopped 2023-03-20T21:34:07.849Z,1679348047.849 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T21:34:07.849Z,1679348047.849 [Default:CheckIn] Stopped 2023-03-20T21:34:07.849Z,1679348047.849 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T21:34:07.850Z,1679348047.850 [Default:CheckIn](INFO): Running loop #19 2023-03-20T21:34:07.850Z,1679348047.850 [Default:CheckIn] Running Loop=19 2023-03-20T21:34:07.850Z,1679348047.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T21:34:07.850Z,1679348047.850 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T21:34:09.892Z,1679348049.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213410.00,A,3648.16292,N,12147.28239,W,0.467,0.00,200323,,,A*78 2023-03-20T21:34:09.894Z,1679348049.894 [NAL9602](INFO): GPS fix at 20230320T213410: (36.802715, -121.788040) 2023-03-20T21:34:09.978Z,1679348049.978 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T21:34:09.978Z,1679348049.978 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T21:34:18.936Z,1679348058.936 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T200622/Courier0048.lzma 2023-03-20T21:34:19.940Z,1679348059.940 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0048.lzma.bak 2023-03-20T21:34:19.940Z,1679348059.940 [DataOverHttps](INFO): SBD MOMSN=17534147 2023-03-20T21:34:38.168Z,1679348078.168 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230320T200622/Express0049.lzma 2023-03-20T21:34:39.170Z,1679348079.170 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0049.lzma.bak 2023-03-20T21:34:39.171Z,1679348079.171 [DataOverHttps](INFO): SBD MOMSN=17534150 2023-03-20T21:34:40.647Z,1679348080.647 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T21:34:40.647Z,1679348080.647 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T21:34:40.647Z,1679348080.647 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T21:39:12.361Z,1679348352.361 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-03-20T21:39:41.058Z,1679348381.058 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-20T21:39:41.058Z,1679348381.058 [Default:CheckIn:C.Wait] Stopped 2023-03-20T21:39:41.058Z,1679348381.058 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T21:39:41.058Z,1679348381.058 [Default:CheckIn:D] Running Loop=1 2023-03-20T21:39:41.468Z,1679348381.468 [Default:CheckIn:D] Stopped 2023-03-20T21:39:41.468Z,1679348381.468 [Default:CheckIn:E] Running Loop=1 2023-03-20T21:39:41.869Z,1679348381.869 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.628573 min 2023-03-20T21:39:41.869Z,1679348381.869 [Default:CheckIn:E] Stopped 2023-03-20T21:39:41.869Z,1679348381.869 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-20T21:39:41.869Z,1679348381.869 [Default:CheckIn] Stopped 2023-03-20T21:39:41.869Z,1679348381.869 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T21:39:41.870Z,1679348381.870 [Default:CheckIn](INFO): Running loop #20 2023-03-20T21:39:41.870Z,1679348381.870 [Default:CheckIn] Running Loop=20 2023-03-20T21:39:41.870Z,1679348381.870 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-20T21:39:41.870Z,1679348381.870 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-20T21:39:43.882Z,1679348383.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213945.00,A,3648.16337,N,12147.27389,W,0.330,297.84,200323,,,A*7B 2023-03-20T21:39:43.884Z,1679348383.884 [NAL9602](INFO): GPS fix at 20230320T213945: (36.802723, -121.787898) 2023-03-20T21:39:43.896Z,1679348383.896 [Default:CheckIn:Read_GPS] Stopped 2023-03-20T21:39:43.896Z,1679348383.896 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-20T21:39:51.321Z,1679348391.321 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230320T200622/Courier0051.lzma 2023-03-20T21:39:52.323Z,1679348392.323 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Courier0051.lzma.bak 2023-03-20T21:39:52.323Z,1679348392.323 [DataOverHttps](INFO): SBD MOMSN=17534153 2023-03-20T21:40:08.125Z,1679348408.125 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230320T200622/Express0052.lzma 2023-03-20T21:40:09.126Z,1679348409.126 [DataOverHttps](INFO): Moved sent file to Logs/20230320T200622/Express0052.lzma.bak 2023-03-20T21:40:09.127Z,1679348409.127 [DataOverHttps](INFO): SBD MOMSN=17534156 2023-03-20T21:40:10.575Z,1679348410.575 [Default:CheckIn:Read_Iridium] Stopped 2023-03-20T21:40:10.575Z,1679348410.575 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-20T21:40:10.575Z,1679348410.575 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-20T21:40:16.613Z,1679348416.613 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-20T21:42:21.165Z,1679348541.165 [CommandExec](IMPORTANT): got command quit 2023-03-20T21:42:22.169Z,1679348542.169 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-20T21:42:22.169Z,1679348542.169 [CommandExec](INFO): Uninitializing the command executive. 2023-03-20T21:42:22.169Z,1679348542.169 [CommandExec](INFO): Uninitializing the command scheduler. 2023-03-20T21:42:22.169Z,1679348542.169 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:22.273Z,1679348542.273 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-03-20T21:42:22.273Z,1679348542.273 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-03-20T21:42:22.273Z,1679348542.273 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:22.274Z,1679348542.274 [NavChartDb](INFO): Join timeout helper Thread ID is 1841 2023-03-20T21:42:22.569Z,1679348542.569 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-20T21:42:22.569Z,1679348542.569 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:22.573Z,1679348542.573 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-03-20T21:42:22.573Z,1679348542.573 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:22.573Z,1679348542.573 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1842 2023-03-20T21:42:22.957Z,1679348542.957 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-20T21:42:22.957Z,1679348542.957 [WetLabsBB2FL](INFO): Powering down 2023-03-20T21:42:22.958Z,1679348542.958 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:22.974Z,1679348542.974 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-03-20T21:42:22.975Z,1679348542.975 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:22.975Z,1679348542.975 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1843 2023-03-20T21:42:23.741Z,1679348543.741 [CTD_Seabird](INFO): Powering down 2023-03-20T21:42:23.753Z,1679348543.753 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-20T21:42:23.753Z,1679348543.753 [CTD_Seabird](INFO): Powering down 2023-03-20T21:42:23.765Z,1679348543.765 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:23.789Z,1679348543.789 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-03-20T21:42:23.789Z,1679348543.789 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:23.790Z,1679348543.790 [Radio_Surface](INFO): Join timeout helper Thread ID is 1846 2023-03-20T21:42:24.069Z,1679348544.069 [Radio_Surface](INFO): Powering down 2023-03-20T21:42:24.070Z,1679348544.070 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-20T21:42:24.070Z,1679348544.070 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:24.073Z,1679348544.073 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-03-20T21:42:24.073Z,1679348544.073 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:24.073Z,1679348544.073 [Onboard](INFO): Join timeout helper Thread ID is 1847 2023-03-20T21:42:24.509Z,1679348544.509 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-20T21:42:24.509Z,1679348544.509 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:24.518Z,1679348544.518 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-03-20T21:42:24.518Z,1679348544.518 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:24.518Z,1679348544.518 [DataOverHttps](INFO): Join timeout helper Thread ID is 1848 2023-03-20T21:42:24.941Z,1679348544.941 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-20T21:42:24.941Z,1679348544.941 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:24.961Z,1679348544.961 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-03-20T21:42:24.961Z,1679348544.961 [logger ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:24.962Z,1679348544.962 [logger](INFO): Join timeout helper Thread ID is 1849 2023-03-20T21:42:24.977Z,1679348544.977 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-20T21:42:24.977Z,1679348544.977 [logger ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:24.981Z,1679348544.981 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-03-20T21:42:24.981Z,1679348544.981 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:24.982Z,1679348544.982 [CommandLine](INFO): Join timeout helper Thread ID is 1850 2023-03-20T21:42:25.065Z,1679348545.065 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-20T21:42:25.065Z,1679348545.065 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:25.081Z,1679348545.081 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-03-20T21:42:25.081Z,1679348545.081 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:25.082Z,1679348545.082 [CommandExec](INFO): Join timeout helper Thread ID is 1851 2023-03-20T21:42:25.083Z,1679348545.083 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-03-20T21:42:25.083Z,1679348545.083 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:25.083Z,1679348545.083 [controlThread](INFO): Join timeout helper Thread ID is 1852 2023-03-20T21:42:25.153Z,1679348545.153 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-20T21:42:25.153Z,1679348545.153 [controlThread](DEBUG): Uninitializing ControlThread 2023-03-20T21:42:25.153Z,1679348545.153 [AHRS_M2](INFO): Powering down 2023-03-20T21:42:25.225Z,1679348545.225 [MultiRay](INFO): Powering down. 2023-03-20T21:42:25.226Z,1679348545.226 [NAL9602](INFO): Powering down 2023-03-20T21:42:25.229Z,1679348545.229 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-03-20T21:42:25.230Z,1679348545.230 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-03-20T21:42:25.231Z,1679348545.231 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-03-20T21:42:25.231Z,1679348545.231 [MissionManager](INFO): Uninitializing Mission Default 2023-03-20T21:42:25.231Z,1679348545.231 [Default] Stopped 2023-03-20T21:42:25.231Z,1679348545.231 [Default](DEBUG): Aggregate::uninitialize Default 2023-03-20T21:42:25.231Z,1679348545.231 [Default:B.GoToSurface] Stopped 2023-03-20T21:42:25.231Z,1679348545.231 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-03-20T21:42:25.231Z,1679348545.231 [Default:CheckIn] Stopped 2023-03-20T21:42:25.232Z,1679348545.232 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-20T21:42:25.232Z,1679348545.232 [Default:CheckIn:C.Wait] Stopped 2023-03-20T21:42:25.232Z,1679348545.232 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-20T21:42:25.234Z,1679348545.234 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-03-20T21:42:25.235Z,1679348545.235 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-03-20T21:42:25.235Z,1679348545.235 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-03-20T21:42:25.235Z,1679348545.235 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-03-20T21:42:25.236Z,1679348545.236 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-03-20T21:42:25.236Z,1679348545.236 [BuoyancyServo](INFO): Powering down 2023-03-20T21:42:25.249Z,1679348545.249 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-03-20T21:42:25.249Z,1679348545.249 [ElevatorServo](INFO): Powering down 2023-03-20T21:42:25.250Z,1679348545.250 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-03-20T21:42:25.250Z,1679348545.250 [MassServo](INFO): Powering down 2023-03-20T21:42:25.251Z,1679348545.251 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-03-20T21:42:25.251Z,1679348545.251 [RudderServo](INFO): Powering down 2023-03-20T21:42:25.251Z,1679348545.251 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-03-20T21:42:25.252Z,1679348545.252 [ThrusterServo](INFO): Powering down 2023-03-20T21:42:25.252Z,1679348545.252 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-03-20T21:42:25.253Z,1679348545.253 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-03-20T21:42:25.253Z,1679348545.253 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-03-20T21:42:25.253Z,1679348545.253 [CBIT](DEBUG): Powering off loads. 2023-03-20T21:42:25.264Z,1679348545.264 [CBIT](DEBUG): Disabling WDT. 2023-03-20T21:42:25.276Z,1679348545.276 [CBIT](DEBUG): Opening all GF detection circuits. 2023-03-20T21:42:25.277Z,1679348545.277 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:25.321Z,1679348545.321 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:25.324Z,1679348545.324 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:25.331Z,1679348545.331 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:25.406Z,1679348545.406 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:25.410Z,1679348545.410 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:25.454Z,1679348545.454 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-03-20T21:42:25.528Z,1679348545.528 [logger ThreadHandler](INFO): Thread cancelled.