2024-05-02T19:26:09.107Z,1714677969.107 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T19:29:55.342Z,1714678195.342 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-02T19:30:21.273Z,1714678221.273 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T19:30:21.273Z,1714678221.273 [Default:CheckIn:C.Wait] Stopped 2024-05-02T19:30:21.273Z,1714678221.273 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T19:30:21.273Z,1714678221.273 [Default:CheckIn:D] Running Loop=1 2024-05-02T19:30:21.677Z,1714678221.677 [Default:CheckIn:D] Stopped 2024-05-02T19:30:21.677Z,1714678221.677 [Default:CheckIn:E] Running Loop=1 2024-05-02T19:30:22.087Z,1714678222.087 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.020308 min 2024-05-02T19:30:22.087Z,1714678222.087 [Default:CheckIn:E] Stopped 2024-05-02T19:30:22.087Z,1714678222.087 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T19:30:22.087Z,1714678222.087 [Default:CheckIn] Stopped 2024-05-02T19:30:22.087Z,1714678222.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T19:30:22.087Z,1714678222.087 [Default:CheckIn](INFO): Running loop #7 2024-05-02T19:30:22.087Z,1714678222.087 [Default:CheckIn] Running Loop=7 2024-05-02T19:30:22.087Z,1714678222.087 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T19:30:22.087Z,1714678222.087 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T19:30:24.088Z,1714678224.088 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193023.00,A,3648.32899,N,12147.82431,W,22.063,97.86,020524,,,D*7A 2024-05-02T19:30:24.091Z,1714678224.091 [NAL9602](INFO): GPS fix at 20240502T193023: (36.805483, -121.797072) 2024-05-02T19:30:24.134Z,1714678224.134 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T19:30:24.134Z,1714678224.134 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T19:30:26.466Z,1714678226.466 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-02T19:30:41.460Z,1714678241.460 [NAL9602](INFO): SBD MO Status=0, MOMSN=51277, MT Status=0, MTMSN=0 2024-05-02T19:30:41.460Z,1714678241.460 [NAL9602](INFO): No messages in MT queue 2024-05-02T19:30:57.590Z,1714678257.590 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-02T19:31:12.160Z,1714678272.160 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T19:31:28.714Z,1714678288.714 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-02T19:31:42.148Z,1714678302.148 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.108963 2024-05-02T19:31:49.682Z,1714678309.682 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003167 2024-05-02T19:31:57.053Z,1714678317.053 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240502T050033/Courier0142.lzma 2024-05-02T19:31:58.056Z,1714678318.056 [DataOverHttps](INFO): Moved sent file to Logs/20240502T050033/Courier0142.lzma.bak 2024-05-02T19:31:58.056Z,1714678318.056 [DataOverHttps](INFO): SBD MOMSN=19816987 2024-05-02T19:32:08.783Z,1714678328.783 [NAL9602](INFO): SBD MO Status=1, MOMSN=51278, MT Status=0, MTMSN=0 2024-05-02T19:32:08.850Z,1714678328.850 [NAL9602](INFO): Sent 39 bytes from file Logs/20240502T050033/Courier0142.lzma 2024-05-02T19:32:08.850Z,1714678328.850 [NAL9602](INFO): Packets left to send: 0 2024-05-02T19:32:14.130Z,1714678334.130 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20240502T192538/Courier0000.lzma 2024-05-02T19:32:15.131Z,1714678335.131 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0000.lzma.bak 2024-05-02T19:32:15.131Z,1714678335.131 [DataOverHttps](INFO): SBD MOMSN=19816992 2024-05-02T19:32:35.433Z,1714678355.433 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20240502T050033/Express0143.lzma 2024-05-02T19:32:36.436Z,1714678356.436 [DataOverHttps](INFO): Moved sent file to Logs/20240502T050033/Express0143.lzma.bak 2024-05-02T19:32:36.436Z,1714678356.436 [DataOverHttps](INFO): SBD MOMSN=19816998 2024-05-02T19:32:39.762Z,1714678359.762 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T19:32:55.698Z,1714678375.698 [DataOverHttps](INFO): Sending 419 bytes from file Logs/20240502T192538/Express0001.lzma 2024-05-02T19:32:56.699Z,1714678376.699 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0001.lzma.bak 2024-05-02T19:32:56.699Z,1714678376.699 [DataOverHttps](INFO): SBD MOMSN=19817007 2024-05-02T19:32:58.441Z,1714678378.441 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T19:32:58.441Z,1714678378.441 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T19:32:58.441Z,1714678378.441 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T19:37:59.022Z,1714678679.022 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T19:37:59.023Z,1714678679.023 [Default:CheckIn:C.Wait] Stopped 2024-05-02T19:37:59.023Z,1714678679.023 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T19:37:59.023Z,1714678679.023 [Default:CheckIn:D] Running Loop=1 2024-05-02T19:37:59.435Z,1714678679.435 [Default:CheckIn:D] Stopped 2024-05-02T19:37:59.435Z,1714678679.435 [Default:CheckIn:E] Running Loop=1 2024-05-02T19:37:59.830Z,1714678679.830 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.649605 min 2024-05-02T19:37:59.830Z,1714678679.830 [Default:CheckIn:E] Stopped 2024-05-02T19:37:59.830Z,1714678679.830 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T19:37:59.830Z,1714678679.830 [Default:CheckIn] Stopped 2024-05-02T19:37:59.830Z,1714678679.830 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T19:37:59.830Z,1714678679.830 [Default:CheckIn](INFO): Running loop #8 2024-05-02T19:37:59.830Z,1714678679.830 [Default:CheckIn] Running Loop=8 2024-05-02T19:37:59.830Z,1714678679.830 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T19:37:59.830Z,1714678679.830 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T19:38:01.840Z,1714678681.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193801.00,A,3648.18122,N,12147.17768,W,4.393,195.46,020524,,,D*77 2024-05-02T19:38:01.843Z,1714678681.843 [NAL9602](INFO): GPS fix at 20240502T193801: (36.803020, -121.786295) 2024-05-02T19:38:01.859Z,1714678681.859 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T19:38:01.859Z,1714678681.859 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T19:38:09.633Z,1714678689.633 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0003.lzma 2024-05-02T19:38:10.635Z,1714678690.635 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0003.lzma.bak 2024-05-02T19:38:10.635Z,1714678690.635 [DataOverHttps](INFO): SBD MOMSN=19817127 2024-05-02T19:38:18.005Z,1714678698.005 [NAL9602](INFO): SBD MO Status=0, MOMSN=51279, MT Status=0, MTMSN=0 2024-05-02T19:38:18.005Z,1714678698.005 [NAL9602](INFO): No messages in MT queue 2024-05-02T19:38:29.425Z,1714678709.425 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240502T192538/Express0004.lzma 2024-05-02T19:38:30.427Z,1714678710.427 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0004.lzma.bak 2024-05-02T19:38:30.428Z,1714678710.428 [DataOverHttps](INFO): SBD MOMSN=19817130 2024-05-02T19:38:32.218Z,1714678712.218 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T19:38:32.218Z,1714678712.218 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T19:38:32.218Z,1714678712.218 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T19:38:48.864Z,1714678728.864 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T19:43:32.842Z,1714679012.842 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T19:43:32.842Z,1714679012.842 [Default:CheckIn:C.Wait] Stopped 2024-05-02T19:43:32.842Z,1714679012.842 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T19:43:32.842Z,1714679012.842 [Default:CheckIn:D] Running Loop=1 2024-05-02T19:43:33.248Z,1714679013.248 [Default:CheckIn:D] Stopped 2024-05-02T19:43:33.248Z,1714679013.248 [Default:CheckIn:E] Running Loop=1 2024-05-02T19:43:33.658Z,1714679013.658 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.213155 min 2024-05-02T19:43:33.659Z,1714679013.659 [Default:CheckIn:E] Stopped 2024-05-02T19:43:33.659Z,1714679013.659 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T19:43:33.659Z,1714679013.659 [Default:CheckIn] Stopped 2024-05-02T19:43:33.659Z,1714679013.659 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T19:43:33.659Z,1714679013.659 [Default:CheckIn](INFO): Running loop #9 2024-05-02T19:43:33.659Z,1714679013.659 [Default:CheckIn] Running Loop=9 2024-05-02T19:43:33.659Z,1714679013.659 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T19:43:33.659Z,1714679013.659 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T19:43:35.660Z,1714679015.660 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194334.00,A,3648.13854,N,12147.20510,W,0.019,237.44,020524,,,D*7B 2024-05-02T19:43:35.663Z,1714679015.663 [NAL9602](INFO): GPS fix at 20240502T194334: (36.802309, -121.786752) 2024-05-02T19:43:35.690Z,1714679015.690 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T19:43:35.690Z,1714679015.690 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T19:43:42.653Z,1714679022.653 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0006.lzma 2024-05-02T19:43:43.656Z,1714679023.656 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0006.lzma.bak 2024-05-02T19:43:43.656Z,1714679023.656 [DataOverHttps](INFO): SBD MOMSN=19817152 2024-05-02T19:43:59.645Z,1714679039.645 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20240502T192538/Express0007.lzma 2024-05-02T19:44:00.648Z,1714679040.648 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0007.lzma.bak 2024-05-02T19:44:00.648Z,1714679040.648 [DataOverHttps](INFO): SBD MOMSN=19817155 2024-05-02T19:44:02.361Z,1714679042.361 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T19:44:02.361Z,1714679042.361 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T19:44:02.361Z,1714679042.361 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T19:44:36.692Z,1714679076.692 [NAL9602](INFO): SBD MO Status=2, MOMSN=51280, MT Status=2, MTMSN=0 2024-05-02T19:44:36.692Z,1714679076.692 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T19:44:56.084Z,1714679096.084 [NAL9602](INFO): SBD MO Status=2, MOMSN=51280, MT Status=2, MTMSN=0 2024-05-02T19:44:56.084Z,1714679096.084 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T19:45:30.434Z,1714679130.434 [NAL9602](INFO): SBD MO Status=2, MOMSN=51280, MT Status=2, MTMSN=0 2024-05-02T19:45:30.434Z,1714679130.434 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T19:46:54.860Z,1714679214.860 [NAL9602](INFO): SBD MO Status=0, MOMSN=51280, MT Status=0, MTMSN=0 2024-05-02T19:46:54.860Z,1714679214.860 [NAL9602](INFO): No messages in MT queue 2024-05-02T19:47:25.564Z,1714679245.564 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T19:49:02.937Z,1714679342.937 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T19:49:02.937Z,1714679342.937 [Default:CheckIn:C.Wait] Stopped 2024-05-02T19:49:02.937Z,1714679342.937 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T19:49:02.938Z,1714679342.938 [Default:CheckIn:D] Running Loop=1 2024-05-02T19:49:03.346Z,1714679343.346 [Default:CheckIn:D] Stopped 2024-05-02T19:49:03.346Z,1714679343.346 [Default:CheckIn:E] Running Loop=1 2024-05-02T19:49:03.759Z,1714679343.759 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.714795 min 2024-05-02T19:49:03.759Z,1714679343.759 [Default:CheckIn:E] Stopped 2024-05-02T19:49:03.759Z,1714679343.759 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T19:49:03.759Z,1714679343.759 [Default:CheckIn] Stopped 2024-05-02T19:49:03.759Z,1714679343.759 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T19:49:03.759Z,1714679343.759 [Default:CheckIn](INFO): Running loop #10 2024-05-02T19:49:03.760Z,1714679343.760 [Default:CheckIn] Running Loop=10 2024-05-02T19:49:03.760Z,1714679343.760 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T19:49:03.760Z,1714679343.760 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T19:49:05.756Z,1714679345.756 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194904.00,A,3648.14094,N,12147.20862,W,0.467,237.44,020524,,,A*71 2024-05-02T19:49:05.759Z,1714679345.759 [NAL9602](INFO): GPS fix at 20240502T194904: (36.802349, -121.786810) 2024-05-02T19:49:05.794Z,1714679345.794 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T19:49:05.794Z,1714679345.794 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T19:49:13.117Z,1714679353.117 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240502T192538/Courier0009.lzma 2024-05-02T19:49:14.120Z,1714679354.120 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0009.lzma.bak 2024-05-02T19:49:14.121Z,1714679354.121 [DataOverHttps](INFO): SBD MOMSN=19817173 2024-05-02T19:49:30.393Z,1714679370.393 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240502T192538/Express0010.lzma 2024-05-02T19:49:31.395Z,1714679371.395 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0010.lzma.bak 2024-05-02T19:49:31.395Z,1714679371.395 [DataOverHttps](INFO): SBD MOMSN=19817176 2024-05-02T19:49:32.838Z,1714679372.838 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T19:49:32.838Z,1714679372.838 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T19:49:32.838Z,1714679372.838 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T19:49:34.856Z,1714679374.856 [NAL9602](INFO): SBD MO Status=2, MOMSN=51281, MT Status=2, MTMSN=0 2024-05-02T19:49:34.856Z,1714679374.856 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T19:50:00.292Z,1714679400.292 [NAL9602](INFO): SBD MO Status=2, MOMSN=51281, MT Status=2, MTMSN=0 2024-05-02T19:50:00.293Z,1714679400.293 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T19:50:21.704Z,1714679421.704 [NAL9602](INFO): SBD MO Status=2, MOMSN=51281, MT Status=2, MTMSN=0 2024-05-02T19:50:21.704Z,1714679421.704 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T19:50:32.212Z,1714679432.212 [NAL9602](INFO): SBD MO Status=0, MOMSN=51281, MT Status=0, MTMSN=0 2024-05-02T19:50:32.212Z,1714679432.212 [NAL9602](INFO): No messages in MT queue 2024-05-02T19:51:02.915Z,1714679462.915 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T19:54:33.442Z,1714679673.442 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T19:54:33.442Z,1714679673.442 [Default:CheckIn:C.Wait] Stopped 2024-05-02T19:54:33.442Z,1714679673.442 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T19:54:33.443Z,1714679673.443 [Default:CheckIn:D] Running Loop=1 2024-05-02T19:54:33.847Z,1714679673.847 [Default:CheckIn:D] Stopped 2024-05-02T19:54:33.847Z,1714679673.847 [Default:CheckIn:E] Running Loop=1 2024-05-02T19:54:34.242Z,1714679674.242 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.223136 min 2024-05-02T19:54:34.242Z,1714679674.242 [Default:CheckIn:E] Stopped 2024-05-02T19:54:34.243Z,1714679674.243 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T19:54:34.243Z,1714679674.243 [Default:CheckIn] Stopped 2024-05-02T19:54:34.243Z,1714679674.243 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T19:54:34.243Z,1714679674.243 [Default:CheckIn](INFO): Running loop #11 2024-05-02T19:54:34.243Z,1714679674.243 [Default:CheckIn] Running Loop=11 2024-05-02T19:54:34.243Z,1714679674.243 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T19:54:34.243Z,1714679674.243 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T19:54:36.249Z,1714679676.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195435.00,A,3648.13964,N,12147.21221,W,0.019,222.82,020524,,,A*71 2024-05-02T19:54:36.251Z,1714679676.251 [NAL9602](INFO): GPS fix at 20240502T195435: (36.802327, -121.786870) 2024-05-02T19:54:36.263Z,1714679676.263 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T19:54:36.263Z,1714679676.263 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T19:54:43.858Z,1714679683.858 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0012.lzma 2024-05-02T19:54:44.447Z,1714679684.447 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0012.lzma.bak 2024-05-02T19:54:44.447Z,1714679684.447 [DataOverHttps](INFO): SBD MOMSN=19817198 2024-05-02T19:55:01.752Z,1714679701.752 [NAL9602](INFO): SBD MO Status=2, MOMSN=51282, MT Status=2, MTMSN=0 2024-05-02T19:55:01.752Z,1714679701.752 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T19:55:03.649Z,1714679703.649 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20240502T192538/Express0013.lzma 2024-05-02T19:55:04.651Z,1714679704.651 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0013.lzma.bak 2024-05-02T19:55:04.651Z,1714679704.651 [DataOverHttps](INFO): SBD MOMSN=19817201 2024-05-02T19:55:06.243Z,1714679706.243 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T19:55:06.243Z,1714679706.243 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T19:55:06.243Z,1714679706.243 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T19:55:27.608Z,1714679727.608 [NAL9602](INFO): SBD MO Status=2, MOMSN=51282, MT Status=2, MTMSN=0 2024-05-02T19:55:27.608Z,1714679727.608 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T19:55:36.905Z,1714679736.905 [NAL9602](INFO): SBD MO Status=0, MOMSN=51282, MT Status=0, MTMSN=0 2024-05-02T19:55:36.905Z,1714679736.905 [NAL9602](INFO): No messages in MT queue 2024-05-02T19:56:07.602Z,1714679767.602 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:00:06.809Z,1714680006.809 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:00:06.809Z,1714680006.809 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:00:06.809Z,1714680006.809 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:00:06.809Z,1714680006.809 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:00:07.231Z,1714680007.231 [Default:CheckIn:D] Stopped 2024-05-02T20:00:07.231Z,1714680007.231 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:00:07.618Z,1714680007.618 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.779537 min 2024-05-02T20:00:07.619Z,1714680007.619 [Default:CheckIn:E] Stopped 2024-05-02T20:00:07.619Z,1714680007.619 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:00:07.619Z,1714680007.619 [Default:CheckIn] Stopped 2024-05-02T20:00:07.619Z,1714680007.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:00:07.619Z,1714680007.619 [Default:CheckIn](INFO): Running loop #12 2024-05-02T20:00:07.619Z,1714680007.619 [Default:CheckIn] Running Loop=12 2024-05-02T20:00:07.619Z,1714680007.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:00:07.619Z,1714680007.619 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:00:09.629Z,1714680009.629 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200008.00,A,3648.17772,N,12147.28004,W,0.194,287.56,020524,,,A*77 2024-05-02T20:00:09.631Z,1714680009.631 [NAL9602](INFO): GPS fix at 20240502T200008: (36.802962, -121.788001) 2024-05-02T20:00:09.643Z,1714680009.643 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:00:09.643Z,1714680009.643 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:00:17.265Z,1714680017.265 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240502T192538/Courier0015.lzma 2024-05-02T20:00:18.267Z,1714680018.267 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0015.lzma.bak 2024-05-02T20:00:18.268Z,1714680018.268 [DataOverHttps](INFO): SBD MOMSN=19817218 2024-05-02T20:00:30.712Z,1714680030.712 [NAL9602](INFO): SBD MO Status=0, MOMSN=51283, MT Status=0, MTMSN=0 2024-05-02T20:00:30.712Z,1714680030.712 [NAL9602](INFO): No messages in MT queue 2024-05-02T20:00:36.993Z,1714680036.993 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20240502T192538/Express0016.lzma 2024-05-02T20:00:37.995Z,1714680037.995 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0016.lzma.bak 2024-05-02T20:00:37.995Z,1714680037.995 [DataOverHttps](INFO): SBD MOMSN=19817221 2024-05-02T20:00:39.622Z,1714680039.622 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:00:39.622Z,1714680039.622 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:00:39.622Z,1714680039.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:01:01.428Z,1714680061.428 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:05:40.285Z,1714680340.285 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:05:40.285Z,1714680340.285 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:05:40.286Z,1714680340.286 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:05:40.286Z,1714680340.286 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:05:40.702Z,1714680340.702 [Default:CheckIn:D] Stopped 2024-05-02T20:05:40.702Z,1714680340.702 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:05:41.119Z,1714680341.119 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.337386 min 2024-05-02T20:05:41.119Z,1714680341.119 [Default:CheckIn:E] Stopped 2024-05-02T20:05:41.119Z,1714680341.119 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:05:41.119Z,1714680341.119 [Default:CheckIn] Stopped 2024-05-02T20:05:41.119Z,1714680341.119 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:05:41.120Z,1714680341.120 [Default:CheckIn](INFO): Running loop #13 2024-05-02T20:05:41.120Z,1714680341.120 [Default:CheckIn] Running Loop=13 2024-05-02T20:05:41.120Z,1714680341.120 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:05:41.120Z,1714680341.120 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:05:43.104Z,1714680343.104 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200542.00,A,3648.17936,N,12147.28445,W,0.719,1.69,020524,,,A*70 2024-05-02T20:05:43.107Z,1714680343.107 [NAL9602](INFO): GPS fix at 20240502T200542: (36.802989, -121.788074) 2024-05-02T20:05:43.120Z,1714680343.120 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:05:43.120Z,1714680343.120 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:05:50.001Z,1714680350.001 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0018.lzma 2024-05-02T20:05:51.003Z,1714680351.003 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0018.lzma.bak 2024-05-02T20:05:51.003Z,1714680351.003 [DataOverHttps](INFO): SBD MOMSN=19817257 2024-05-02T20:06:06.969Z,1714680366.969 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20240502T192538/Express0019.lzma 2024-05-02T20:06:07.971Z,1714680367.971 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0019.lzma.bak 2024-05-02T20:06:07.971Z,1714680367.971 [DataOverHttps](INFO): SBD MOMSN=19817261 2024-05-02T20:06:09.389Z,1714680369.389 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:06:09.390Z,1714680369.390 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:06:09.390Z,1714680369.390 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:09:45.104Z,1714680585.104 [NAL9602](INFO): SBD MO Status=0, MOMSN=51284, MT Status=0, MTMSN=0 2024-05-02T20:09:45.104Z,1714680585.104 [NAL9602](INFO): No messages in MT queue 2024-05-02T20:10:15.807Z,1714680615.807 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:11:10.012Z,1714680670.012 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:11:10.012Z,1714680670.012 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:11:10.012Z,1714680670.012 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:11:10.012Z,1714680670.012 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:11:10.439Z,1714680670.439 [Default:CheckIn:D] Stopped 2024-05-02T20:11:10.439Z,1714680670.439 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:11:10.820Z,1714680670.820 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.833000 min 2024-05-02T20:11:10.821Z,1714680670.821 [Default:CheckIn:E] Stopped 2024-05-02T20:11:10.821Z,1714680670.821 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:11:10.821Z,1714680670.821 [Default:CheckIn] Stopped 2024-05-02T20:11:10.821Z,1714680670.821 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:11:10.821Z,1714680670.821 [Default:CheckIn](INFO): Running loop #14 2024-05-02T20:11:10.821Z,1714680670.821 [Default:CheckIn] Running Loop=14 2024-05-02T20:11:10.821Z,1714680670.821 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:11:10.822Z,1714680670.822 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:11:12.822Z,1714680672.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201112.00,A,3648.17531,N,12147.27954,W,0.078,1.69,020524,,,A*79 2024-05-02T20:11:12.824Z,1714680672.824 [NAL9602](INFO): GPS fix at 20240502T201112: (36.802922, -121.787992) 2024-05-02T20:11:12.836Z,1714680672.836 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:11:12.837Z,1714680672.837 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:11:20.025Z,1714680680.025 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240502T192538/Courier0021.lzma 2024-05-02T20:11:21.027Z,1714680681.027 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0021.lzma.bak 2024-05-02T20:11:21.027Z,1714680681.027 [DataOverHttps](INFO): SBD MOMSN=19817277 2024-05-02T20:11:34.688Z,1714680694.688 [NAL9602](INFO): SBD MO Status=0, MOMSN=51285, MT Status=0, MTMSN=0 2024-05-02T20:11:34.688Z,1714680694.688 [NAL9602](INFO): No messages in MT queue 2024-05-02T20:11:37.117Z,1714680697.117 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20240502T192538/Express0022.lzma 2024-05-02T20:11:38.119Z,1714680698.119 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0022.lzma.bak 2024-05-02T20:11:38.119Z,1714680698.119 [DataOverHttps](INFO): SBD MOMSN=19817280 2024-05-02T20:11:39.541Z,1714680699.541 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:11:39.541Z,1714680699.541 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:11:39.541Z,1714680699.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:12:05.414Z,1714680725.414 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:13:21.774Z,1714680801.774 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T20:13:34.701Z,1714680814.701 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T20:13:47.634Z,1714680827.634 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T20:14:00.557Z,1714680840.557 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T20:14:13.485Z,1714680853.485 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T20:14:26.817Z,1714680866.817 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T20:14:39.341Z,1714680879.341 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T20:16:40.209Z,1714681000.209 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:16:40.209Z,1714681000.209 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:16:40.209Z,1714681000.209 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:16:40.210Z,1714681000.210 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:16:40.605Z,1714681000.605 [Default:CheckIn:D] Stopped 2024-05-02T20:16:40.605Z,1714681000.605 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:16:41.011Z,1714681001.011 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.335775 min 2024-05-02T20:16:41.011Z,1714681001.011 [Default:CheckIn:E] Stopped 2024-05-02T20:16:41.011Z,1714681001.011 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:16:41.011Z,1714681001.011 [Default:CheckIn] Stopped 2024-05-02T20:16:41.011Z,1714681001.011 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:16:41.012Z,1714681001.012 [Default:CheckIn](INFO): Running loop #15 2024-05-02T20:16:41.012Z,1714681001.012 [Default:CheckIn] Running Loop=15 2024-05-02T20:16:41.012Z,1714681001.012 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:16:41.012Z,1714681001.012 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:16:43.012Z,1714681003.012 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201642.00,A,3648.17462,N,12147.28002,W,0.272,1.69,020524,,,A*71 2024-05-02T20:16:43.015Z,1714681003.015 [NAL9602](INFO): GPS fix at 20240502T201642: (36.802910, -121.788000) 2024-05-02T20:16:43.026Z,1714681003.026 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:16:43.026Z,1714681003.026 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:16:52.833Z,1714681012.833 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0024.lzma 2024-05-02T20:16:53.835Z,1714681013.835 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0024.lzma.bak 2024-05-02T20:16:53.836Z,1714681013.836 [DataOverHttps](INFO): SBD MOMSN=19817297 2024-05-02T20:17:12.214Z,1714681032.214 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20240502T192538/Express0025.lzma 2024-05-02T20:17:13.215Z,1714681033.215 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0025.lzma.bak 2024-05-02T20:17:13.215Z,1714681033.215 [DataOverHttps](INFO): SBD MOMSN=19817300 2024-05-02T20:17:14.582Z,1714681034.582 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:17:14.582Z,1714681034.582 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:17:14.582Z,1714681034.582 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:17:20.626Z,1714681040.626 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6. 2024-05-02T20:17:20.628Z,1714681040.628 [BPC1](INFO): Received data from all battery sticks. 2024-05-02T20:19:31.221Z,1714681171.221 [NAL9602](INFO): SBD MO Status=0, MOMSN=51286, MT Status=0, MTMSN=0 2024-05-02T20:19:31.221Z,1714681171.221 [NAL9602](INFO): No messages in MT queue 2024-05-02T20:20:01.931Z,1714681201.931 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:22:15.317Z,1714681335.317 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:22:15.317Z,1714681335.317 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:22:15.317Z,1714681335.317 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:22:15.317Z,1714681335.317 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:22:15.719Z,1714681335.719 [Default:CheckIn:D] Stopped 2024-05-02T20:22:15.719Z,1714681335.719 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:22:16.134Z,1714681336.134 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.921012 min 2024-05-02T20:22:16.134Z,1714681336.134 [Default:CheckIn:E] Stopped 2024-05-02T20:22:16.134Z,1714681336.134 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:22:16.134Z,1714681336.134 [Default:CheckIn] Stopped 2024-05-02T20:22:16.134Z,1714681336.134 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:22:16.135Z,1714681336.135 [Default:CheckIn](INFO): Running loop #16 2024-05-02T20:22:16.135Z,1714681336.135 [Default:CheckIn] Running Loop=16 2024-05-02T20:22:16.135Z,1714681336.135 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:22:16.135Z,1714681336.135 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:22:18.132Z,1714681338.132 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202217.00,A,3648.17230,N,12147.28163,W,0.117,1.69,020524,,,A*71 2024-05-02T20:22:18.135Z,1714681338.135 [NAL9602](INFO): GPS fix at 20240502T202217: (36.802872, -121.788027) 2024-05-02T20:22:18.175Z,1714681338.175 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:22:18.175Z,1714681338.175 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:22:26.182Z,1714681346.182 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20240502T192538/Courier0027.lzma 2024-05-02T20:22:27.183Z,1714681347.183 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0027.lzma.bak 2024-05-02T20:22:27.183Z,1714681347.183 [DataOverHttps](INFO): SBD MOMSN=19817326 2024-05-02T20:22:43.285Z,1714681363.285 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20240502T192538/Express0028.lzma 2024-05-02T20:22:44.287Z,1714681364.287 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0028.lzma.bak 2024-05-02T20:22:44.287Z,1714681364.287 [DataOverHttps](INFO): SBD MOMSN=19817329 2024-05-02T20:22:45.614Z,1714681365.614 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:22:45.614Z,1714681365.614 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:22:45.614Z,1714681365.614 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:22:46.826Z,1714681366.826 [NAL9602](INFO): SBD MO Status=2, MOMSN=51287, MT Status=2, MTMSN=0 2024-05-02T20:22:46.826Z,1714681366.826 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T20:22:56.598Z,1714681376.598 [NAL9602](INFO): SBD MO Status=0, MOMSN=51287, MT Status=0, MTMSN=0 2024-05-02T20:22:56.598Z,1714681376.598 [NAL9602](INFO): No messages in MT queue 2024-05-02T20:23:27.271Z,1714681407.271 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:27:46.093Z,1714681666.093 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:27:46.094Z,1714681666.094 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:27:46.094Z,1714681666.094 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:27:46.094Z,1714681666.094 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:27:46.505Z,1714681666.505 [Default:CheckIn:D] Stopped 2024-05-02T20:27:46.506Z,1714681666.506 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:27:46.914Z,1714681666.914 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.434115 min 2024-05-02T20:27:46.914Z,1714681666.914 [Default:CheckIn:E] Stopped 2024-05-02T20:27:46.914Z,1714681666.914 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:27:46.914Z,1714681666.914 [Default:CheckIn] Stopped 2024-05-02T20:27:46.915Z,1714681666.915 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:27:46.915Z,1714681666.915 [Default:CheckIn](INFO): Running loop #17 2024-05-02T20:27:46.915Z,1714681666.915 [Default:CheckIn] Running Loop=17 2024-05-02T20:27:46.915Z,1714681666.915 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:27:46.915Z,1714681666.915 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:27:48.912Z,1714681668.912 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202748.00,A,3648.17648,N,12147.28074,W,0.000,0.00,020524,,,A*7B 2024-05-02T20:27:48.915Z,1714681668.915 [NAL9602](INFO): GPS fix at 20240502T202748: (36.802941, -121.788012) 2024-05-02T20:27:48.961Z,1714681668.961 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:27:48.961Z,1714681668.961 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:27:56.885Z,1714681676.885 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240502T192538/Courier0030.lzma 2024-05-02T20:27:57.887Z,1714681677.887 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0030.lzma.bak 2024-05-02T20:27:57.887Z,1714681677.887 [DataOverHttps](INFO): SBD MOMSN=19817345 2024-05-02T20:28:06.288Z,1714681686.288 [NAL9602](INFO): SBD MO Status=0, MOMSN=51288, MT Status=0, MTMSN=0 2024-05-02T20:28:06.288Z,1714681686.288 [NAL9602](INFO): No messages in MT queue 2024-05-02T20:28:13.869Z,1714681693.869 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240502T192538/Express0031.lzma 2024-05-02T20:28:14.871Z,1714681694.871 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0031.lzma.bak 2024-05-02T20:28:14.871Z,1714681694.871 [DataOverHttps](INFO): SBD MOMSN=19817348 2024-05-02T20:28:16.453Z,1714681696.453 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:28:16.453Z,1714681696.453 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:28:16.457Z,1714681696.457 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:28:37.063Z,1714681717.063 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:33:17.123Z,1714681997.123 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:33:17.123Z,1714681997.123 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:33:17.123Z,1714681997.123 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:33:17.124Z,1714681997.124 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:33:17.517Z,1714681997.517 [Default:CheckIn:D] Stopped 2024-05-02T20:33:17.517Z,1714681997.517 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:33:17.931Z,1714681997.931 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.950968 min 2024-05-02T20:33:17.931Z,1714681997.931 [Default:CheckIn:E] Stopped 2024-05-02T20:33:17.931Z,1714681997.931 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:33:17.931Z,1714681997.931 [Default:CheckIn] Stopped 2024-05-02T20:33:17.931Z,1714681997.931 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:33:17.931Z,1714681997.931 [Default:CheckIn](INFO): Running loop #18 2024-05-02T20:33:17.931Z,1714681997.931 [Default:CheckIn] Running Loop=18 2024-05-02T20:33:17.931Z,1714681997.931 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:33:17.931Z,1714681997.931 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:33:19.938Z,1714681999.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203319.00,A,3648.17776,N,12147.27951,W,0.058,0.00,020524,,,A*7A 2024-05-02T20:33:19.940Z,1714681999.940 [NAL9602](INFO): GPS fix at 20240502T203319: (36.802963, -121.787992) 2024-05-02T20:33:19.951Z,1714681999.951 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:33:19.952Z,1714681999.952 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:33:27.489Z,1714682007.489 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0033.lzma 2024-05-02T20:33:28.491Z,1714682008.491 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0033.lzma.bak 2024-05-02T20:33:28.491Z,1714682008.491 [DataOverHttps](INFO): SBD MOMSN=19817368 2024-05-02T20:33:45.141Z,1714682025.141 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240502T192538/Express0034.lzma 2024-05-02T20:33:46.143Z,1714682026.143 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0034.lzma.bak 2024-05-02T20:33:46.143Z,1714682026.143 [DataOverHttps](INFO): SBD MOMSN=19817371 2024-05-02T20:33:47.837Z,1714682027.837 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:33:47.837Z,1714682027.837 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:33:47.837Z,1714682027.837 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:33:52.656Z,1714682032.656 [NAL9602](INFO): SBD MO Status=2, MOMSN=51289, MT Status=2, MTMSN=0 2024-05-02T20:33:52.656Z,1714682032.656 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T20:37:40.272Z,1714682260.272 [NAL9602](INFO): SBD MO Status=0, MOMSN=51289, MT Status=0, MTMSN=0 2024-05-02T20:37:40.272Z,1714682260.272 [NAL9602](INFO): No messages in MT queue 2024-05-02T20:38:10.970Z,1714682290.970 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:38:48.565Z,1714682328.565 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:38:48.565Z,1714682328.565 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:38:48.566Z,1714682328.566 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:38:48.566Z,1714682328.566 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:38:48.974Z,1714682328.974 [Default:CheckIn:D] Stopped 2024-05-02T20:38:48.975Z,1714682328.975 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:38:49.385Z,1714682329.385 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.475269 min 2024-05-02T20:38:49.393Z,1714682329.393 [Default:CheckIn:E] Stopped 2024-05-02T20:38:49.394Z,1714682329.394 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:38:49.394Z,1714682329.394 [Default:CheckIn] Stopped 2024-05-02T20:38:49.394Z,1714682329.394 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:38:49.394Z,1714682329.394 [Default:CheckIn](INFO): Running loop #19 2024-05-02T20:38:49.394Z,1714682329.394 [Default:CheckIn] Running Loop=19 2024-05-02T20:38:49.394Z,1714682329.394 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:38:49.394Z,1714682329.394 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:38:51.393Z,1714682331.393 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203850.00,A,3648.17246,N,12147.28071,W,0.097,165.17,020524,,,A*79 2024-05-02T20:38:51.395Z,1714682331.395 [NAL9602](INFO): GPS fix at 20240502T203850: (36.802874, -121.788012) 2024-05-02T20:38:51.417Z,1714682331.417 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:38:51.417Z,1714682331.417 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:38:58.664Z,1714682338.664 [NAL9602](INFO): SBD MO Status=0, MOMSN=51290, MT Status=0, MTMSN=0 2024-05-02T20:38:58.664Z,1714682338.664 [NAL9602](INFO): No messages in MT queue 2024-05-02T20:39:00.789Z,1714682340.789 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0036.lzma 2024-05-02T20:39:01.791Z,1714682341.791 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0036.lzma.bak 2024-05-02T20:39:01.791Z,1714682341.791 [DataOverHttps](INFO): SBD MOMSN=19817438 2024-05-02T20:39:17.663Z,1714682357.663 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240502T192538/Express0037.lzma 2024-05-02T20:39:18.663Z,1714682358.663 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0037.lzma.bak 2024-05-02T20:39:18.663Z,1714682358.663 [DataOverHttps](INFO): SBD MOMSN=19817441 2024-05-02T20:39:20.099Z,1714682360.099 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:39:20.099Z,1714682360.099 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:39:20.099Z,1714682360.099 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:39:29.366Z,1714682369.366 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:44:20.685Z,1714682660.685 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:44:20.685Z,1714682660.685 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:44:20.685Z,1714682660.685 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:44:20.685Z,1714682660.685 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:44:21.095Z,1714682661.095 [Default:CheckIn:D] Stopped 2024-05-02T20:44:21.095Z,1714682661.095 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:44:21.489Z,1714682661.489 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.010604 min 2024-05-02T20:44:21.490Z,1714682661.490 [Default:CheckIn:E] Stopped 2024-05-02T20:44:21.490Z,1714682661.490 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:44:21.490Z,1714682661.490 [Default:CheckIn] Stopped 2024-05-02T20:44:21.490Z,1714682661.490 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:44:21.490Z,1714682661.490 [Default:CheckIn](INFO): Running loop #20 2024-05-02T20:44:21.490Z,1714682661.490 [Default:CheckIn] Running Loop=20 2024-05-02T20:44:21.490Z,1714682661.490 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:44:21.490Z,1714682661.490 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:44:23.501Z,1714682663.501 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204422.00,A,3648.17639,N,12147.27923,W,0.019,16.90,020524,,,A*46 2024-05-02T20:44:23.503Z,1714682663.503 [NAL9602](INFO): GPS fix at 20240502T204422: (36.802940, -121.787987) 2024-05-02T20:44:23.518Z,1714682663.518 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:44:23.518Z,1714682663.518 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:44:30.890Z,1714682670.890 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0039.lzma 2024-05-02T20:44:31.891Z,1714682671.891 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0039.lzma.bak 2024-05-02T20:44:31.891Z,1714682671.891 [DataOverHttps](INFO): SBD MOMSN=19817503 2024-05-02T20:44:47.838Z,1714682687.838 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240502T192538/Express0040.lzma 2024-05-02T20:44:48.841Z,1714682688.841 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0040.lzma.bak 2024-05-02T20:44:48.841Z,1714682688.841 [DataOverHttps](INFO): SBD MOMSN=19817506 2024-05-02T20:44:50.586Z,1714682690.586 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:44:50.586Z,1714682690.586 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:44:50.586Z,1714682690.586 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:46:01.325Z,1714682761.325 [NAL9602](INFO): SBD MO Status=0, MOMSN=51291, MT Status=0, MTMSN=0 2024-05-02T20:46:01.325Z,1714682761.325 [NAL9602](INFO): No messages in MT queue 2024-05-02T20:46:32.043Z,1714682792.043 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:49:51.230Z,1714682991.230 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:49:51.231Z,1714682991.231 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:49:51.231Z,1714682991.231 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:49:51.231Z,1714682991.231 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:49:51.622Z,1714682991.622 [Default:CheckIn:D] Stopped 2024-05-02T20:49:51.623Z,1714682991.623 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:49:52.038Z,1714682992.038 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.519401 min 2024-05-02T20:49:52.038Z,1714682992.038 [Default:CheckIn:E] Stopped 2024-05-02T20:49:52.038Z,1714682992.038 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:49:52.038Z,1714682992.038 [Default:CheckIn] Stopped 2024-05-02T20:49:52.038Z,1714682992.038 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:49:52.038Z,1714682992.038 [Default:CheckIn](INFO): Running loop #21 2024-05-02T20:49:52.038Z,1714682992.038 [Default:CheckIn] Running Loop=21 2024-05-02T20:49:52.039Z,1714682992.039 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:49:52.039Z,1714682992.039 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:49:54.040Z,1714682994.040 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204953.00,A,3648.17833,N,12147.28073,W,0.214,158.54,020524,,,A*76 2024-05-02T20:49:54.043Z,1714682994.043 [NAL9602](INFO): GPS fix at 20240502T204953: (36.802972, -121.788012) 2024-05-02T20:49:54.078Z,1714682994.078 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:49:54.078Z,1714682994.078 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:50:00.997Z,1714683000.997 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0042.lzma 2024-05-02T20:50:01.999Z,1714683001.999 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0042.lzma.bak 2024-05-02T20:50:01.999Z,1714683001.999 [DataOverHttps](INFO): SBD MOMSN=19817516 2024-05-02T20:50:16.285Z,1714683016.285 [NAL9602](INFO): SBD MO Status=2, MOMSN=51292, MT Status=2, MTMSN=0 2024-05-02T20:50:16.285Z,1714683016.285 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T20:50:19.650Z,1714683019.650 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240502T192538/Express0043.lzma 2024-05-02T20:50:20.651Z,1714683020.651 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0043.lzma.bak 2024-05-02T20:50:20.652Z,1714683020.652 [DataOverHttps](INFO): SBD MOMSN=19817519 2024-05-02T20:50:22.394Z,1714683022.394 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:50:22.395Z,1714683022.395 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:50:22.395Z,1714683022.395 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:51:01.164Z,1714683061.164 [NAL9602](INFO): SBD MO Status=0, MOMSN=51292, MT Status=0, MTMSN=0 2024-05-02T20:51:01.165Z,1714683061.165 [NAL9602](INFO): No messages in MT queue 2024-05-02T20:51:31.874Z,1714683091.874 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T20:55:22.985Z,1714683322.985 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T20:55:22.985Z,1714683322.985 [Default:CheckIn:C.Wait] Stopped 2024-05-02T20:55:22.985Z,1714683322.985 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T20:55:22.985Z,1714683322.985 [Default:CheckIn:D] Running Loop=1 2024-05-02T20:55:23.441Z,1714683323.441 [Default:CheckIn:D] Stopped 2024-05-02T20:55:23.441Z,1714683323.441 [Default:CheckIn:E] Running Loop=1 2024-05-02T20:55:23.811Z,1714683323.811 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.049707 min 2024-05-02T20:55:23.811Z,1714683323.811 [Default:CheckIn:E] Stopped 2024-05-02T20:55:23.811Z,1714683323.811 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T20:55:23.812Z,1714683323.812 [Default:CheckIn] Stopped 2024-05-02T20:55:23.812Z,1714683323.812 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T20:55:23.812Z,1714683323.812 [Default:CheckIn](INFO): Running loop #22 2024-05-02T20:55:23.812Z,1714683323.812 [Default:CheckIn] Running Loop=22 2024-05-02T20:55:23.812Z,1714683323.812 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T20:55:23.812Z,1714683323.812 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T20:55:25.808Z,1714683325.808 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205525.00,A,3648.17210,N,12147.28520,W,0.603,158.54,020524,,,A*70 2024-05-02T20:55:25.811Z,1714683325.811 [NAL9602](INFO): GPS fix at 20240502T205525: (36.802868, -121.788087) 2024-05-02T20:55:25.850Z,1714683325.850 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T20:55:25.850Z,1714683325.850 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T20:55:33.753Z,1714683333.753 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0045.lzma 2024-05-02T20:55:34.755Z,1714683334.755 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0045.lzma.bak 2024-05-02T20:55:34.755Z,1714683334.755 [DataOverHttps](INFO): SBD MOMSN=19817540 2024-05-02T20:55:50.797Z,1714683350.797 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240502T192538/Express0046.lzma 2024-05-02T20:55:51.800Z,1714683351.800 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0046.lzma.bak 2024-05-02T20:55:51.800Z,1714683351.800 [DataOverHttps](INFO): SBD MOMSN=19817543 2024-05-02T20:55:53.294Z,1714683353.294 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T20:55:53.294Z,1714683353.294 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T20:55:53.294Z,1714683353.294 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T20:55:54.896Z,1714683354.896 [NAL9602](INFO): SBD MO Status=2, MOMSN=51293, MT Status=2, MTMSN=0 2024-05-02T20:55:54.896Z,1714683354.896 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T20:56:37.313Z,1714683397.313 [NAL9602](INFO): SBD MO Status=2, MOMSN=51293, MT Status=2, MTMSN=0 2024-05-02T20:56:37.314Z,1714683397.314 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T20:56:51.049Z,1714683411.049 [NAL9602](INFO): SBD MO Status=2, MOMSN=51293, MT Status=2, MTMSN=0 2024-05-02T20:56:51.049Z,1714683411.049 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T20:57:29.836Z,1714683449.836 [NAL9602](INFO): SBD MO Status=2, MOMSN=51293, MT Status=2, MTMSN=0 2024-05-02T20:57:29.836Z,1714683449.836 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T20:57:51.249Z,1714683471.249 [NAL9602](INFO): SBD MO Status=2, MOMSN=51293, MT Status=2, MTMSN=0 2024-05-02T20:57:51.252Z,1714683471.252 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T20:58:09.829Z,1714683489.829 [NAL9602](INFO): SBD MO Status=2, MOMSN=51293, MT Status=2, MTMSN=0 2024-05-02T20:58:09.829Z,1714683489.829 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T21:00:28.438Z,1714683628.438 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-02T21:00:53.915Z,1714683653.915 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T21:00:53.915Z,1714683653.915 [Default:CheckIn:C.Wait] Stopped 2024-05-02T21:00:53.915Z,1714683653.915 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T21:00:53.915Z,1714683653.915 [Default:CheckIn:D] Running Loop=1 2024-05-02T21:00:54.278Z,1714683654.278 [Default:CheckIn:D] Stopped 2024-05-02T21:00:54.278Z,1714683654.278 [Default:CheckIn:E] Running Loop=1 2024-05-02T21:00:54.687Z,1714683654.687 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.563664 min 2024-05-02T21:00:54.687Z,1714683654.687 [Default:CheckIn:E] Stopped 2024-05-02T21:00:54.687Z,1714683654.687 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T21:00:54.687Z,1714683654.687 [Default:CheckIn] Stopped 2024-05-02T21:00:54.687Z,1714683654.687 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T21:00:54.688Z,1714683654.688 [Default:CheckIn](INFO): Running loop #23 2024-05-02T21:00:54.688Z,1714683654.688 [Default:CheckIn] Running Loop=23 2024-05-02T21:00:54.688Z,1714683654.688 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T21:00:54.688Z,1714683654.688 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T21:00:56.685Z,1714683656.685 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210055.00,A,3648.16313,N,12147.28543,W,0.039,77.37,020524,,,A*46 2024-05-02T21:00:56.688Z,1714683656.688 [NAL9602](INFO): GPS fix at 20240502T210055: (36.802719, -121.788090) 2024-05-02T21:00:56.699Z,1714683656.699 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T21:00:56.699Z,1714683656.699 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T21:01:04.509Z,1714683664.509 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0048.lzma 2024-05-02T21:01:05.511Z,1714683665.511 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0048.lzma.bak 2024-05-02T21:01:05.511Z,1714683665.511 [DataOverHttps](INFO): SBD MOMSN=19817571 2024-05-02T21:01:21.418Z,1714683681.418 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20240502T192538/Express0049.lzma 2024-05-02T21:01:22.419Z,1714683682.419 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0049.lzma.bak 2024-05-02T21:01:22.420Z,1714683682.420 [DataOverHttps](INFO): SBD MOMSN=19817574 2024-05-02T21:01:24.165Z,1714683684.165 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T21:01:24.165Z,1714683684.165 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T21:01:24.165Z,1714683684.165 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T21:01:29.402Z,1714683689.402 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T21:01:32.231Z,1714683692.231 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-05-02T21:01:32.231Z,1714683692.231 [DropWeight] Hardware Fault, FailCount= 1 2024-05-02T21:01:32.231Z,1714683692.231 [DropWeight](ERROR): Hardware Fault 2024-05-02T21:01:32.249Z,1714683692.249 [CommandExec](FAULT): Scheduling is paused 2024-05-02T21:01:32.273Z,1714683692.273 [CBIT](INFO): Critical error at 20240502T210132 2024-05-02T21:01:32.276Z,1714683692.276 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-05-02T21:01:32.276Z,1714683692.276 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-05-02T21:01:32.658Z,1714683692.658 [CBIT](INFO): Critical error at 20240502T210132 2024-05-02T21:06:24.777Z,1714683984.777 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T21:06:24.778Z,1714683984.778 [Default:CheckIn:C.Wait] Stopped 2024-05-02T21:06:24.778Z,1714683984.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T21:06:24.778Z,1714683984.778 [Default:CheckIn:D] Running Loop=1 2024-05-02T21:06:25.158Z,1714683985.158 [Default:CheckIn:D] Stopped 2024-05-02T21:06:25.159Z,1714683985.159 [Default:CheckIn:E] Running Loop=1 2024-05-02T21:06:25.582Z,1714683985.582 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.078337 min 2024-05-02T21:06:25.582Z,1714683985.582 [Default:CheckIn:E] Stopped 2024-05-02T21:06:25.582Z,1714683985.582 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T21:06:25.582Z,1714683985.582 [Default:CheckIn] Stopped 2024-05-02T21:06:25.582Z,1714683985.582 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T21:06:25.582Z,1714683985.582 [Default:CheckIn](INFO): Running loop #24 2024-05-02T21:06:25.582Z,1714683985.582 [Default:CheckIn] Running Loop=24 2024-05-02T21:06:25.582Z,1714683985.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T21:06:25.582Z,1714683985.582 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T21:06:27.565Z,1714683987.565 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210626.00,A,3648.16427,N,12147.28767,W,0.272,301.76,020524,,,A*7A 2024-05-02T21:06:27.567Z,1714683987.567 [NAL9602](INFO): GPS fix at 20240502T210626: (36.802738, -121.788128) 2024-05-02T21:06:27.579Z,1714683987.579 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T21:06:27.579Z,1714683987.579 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T21:06:34.673Z,1714683994.673 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20240502T192538/Courier0051.lzma 2024-05-02T21:06:35.675Z,1714683995.675 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0051.lzma.bak 2024-05-02T21:06:35.676Z,1714683995.676 [DataOverHttps](INFO): SBD MOMSN=19817598 2024-05-02T21:06:55.382Z,1714684015.382 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20240502T192538/Express0052.lzma 2024-05-02T21:06:56.384Z,1714684016.384 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0052.lzma.bak 2024-05-02T21:06:56.384Z,1714684016.384 [DataOverHttps](INFO): SBD MOMSN=19817603 2024-05-02T21:06:58.079Z,1714684018.079 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-02T21:06:58.158Z,1714684018.158 [NAL9602](ERROR): received: +CSQ:0 OK293, 2, 0, 0, 0 OK 2024-05-02T21:06:58.222Z,1714684018.222 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T21:06:58.222Z,1714684018.222 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T21:06:58.223Z,1714684018.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T21:08:10.381Z,1714684090.381 [NAL9602](INFO): SBD MO Status=2, MOMSN=51293, MT Status=2, MTMSN=0 2024-05-02T21:08:10.381Z,1714684090.381 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T21:08:58.464Z,1714684138.464 [NAL9602](INFO): SBD MO Status=2, MOMSN=51293, MT Status=2, MTMSN=0 2024-05-02T21:08:58.464Z,1714684138.464 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T21:09:41.685Z,1714684181.685 [NAL9602](INFO): SBD MO Status=2, MOMSN=51293, MT Status=2, MTMSN=0 2024-05-02T21:09:41.685Z,1714684181.685 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-02T21:11:29.546Z,1714684289.546 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-02T21:11:58.645Z,1714684318.645 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T21:11:58.645Z,1714684318.645 [Default:CheckIn:C.Wait] Stopped 2024-05-02T21:11:58.645Z,1714684318.645 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T21:11:58.646Z,1714684318.646 [Default:CheckIn:D] Running Loop=1 2024-05-02T21:11:59.056Z,1714684319.056 [Default:CheckIn:D] Stopped 2024-05-02T21:11:59.057Z,1714684319.057 [Default:CheckIn:E] Running Loop=1 2024-05-02T21:11:59.463Z,1714684319.463 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.643294 min 2024-05-02T21:11:59.463Z,1714684319.463 [Default:CheckIn:E] Stopped 2024-05-02T21:11:59.463Z,1714684319.463 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T21:11:59.463Z,1714684319.463 [Default:CheckIn] Stopped 2024-05-02T21:11:59.463Z,1714684319.463 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T21:11:59.464Z,1714684319.464 [Default:CheckIn](INFO): Running loop #25 2024-05-02T21:11:59.464Z,1714684319.464 [Default:CheckIn] Running Loop=25 2024-05-02T21:11:59.464Z,1714684319.464 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T21:11:59.464Z,1714684319.464 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T21:12:01.472Z,1714684321.472 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211200.00,A,3648.16757,N,12147.28395,W,0.369,301.76,020524,,,A*7D 2024-05-02T21:12:01.486Z,1714684321.486 [NAL9602](INFO): GPS fix at 20240502T211200: (36.802793, -121.788066) 2024-05-02T21:12:01.496Z,1714684321.496 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T21:12:01.497Z,1714684321.497 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T21:12:09.249Z,1714684329.249 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240502T192538/Courier0054.lzma 2024-05-02T21:12:10.251Z,1714684330.251 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0054.lzma.bak 2024-05-02T21:12:10.251Z,1714684330.251 [DataOverHttps](INFO): SBD MOMSN=19817621 2024-05-02T21:12:29.046Z,1714684349.046 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20240502T192538/Express0055.lzma 2024-05-02T21:12:30.048Z,1714684350.048 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0055.lzma.bak 2024-05-02T21:12:30.048Z,1714684350.048 [DataOverHttps](INFO): SBD MOMSN=19817624 2024-05-02T21:12:31.450Z,1714684351.450 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T21:12:31.450Z,1714684351.450 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T21:12:31.450Z,1714684351.450 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T21:12:33.446Z,1714684353.446 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T21:16:32.639Z,1714684592.639 [CBIT](INFO): Clearing failed state for component DropWeight 2024-05-02T21:16:32.639Z,1714684592.639 [DropWeight] No Fault, FailCount= 1 2024-05-02T21:17:32.038Z,1714684652.038 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-02T21:17:32.038Z,1714684652.038 [Default:CheckIn:C.Wait] Stopped 2024-05-02T21:17:32.038Z,1714684652.038 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T21:17:32.038Z,1714684652.038 [Default:CheckIn:D] Running Loop=1 2024-05-02T21:17:32.413Z,1714684652.413 [Default:CheckIn:D] Stopped 2024-05-02T21:17:32.413Z,1714684652.413 [Default:CheckIn:E] Running Loop=1 2024-05-02T21:17:32.826Z,1714684652.826 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.199235 min 2024-05-02T21:17:32.826Z,1714684652.826 [Default:CheckIn:E] Stopped 2024-05-02T21:17:32.827Z,1714684652.827 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-02T21:17:32.827Z,1714684652.827 [Default:CheckIn] Stopped 2024-05-02T21:17:32.827Z,1714684652.827 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T21:17:32.827Z,1714684652.827 [Default:CheckIn](INFO): Running loop #26 2024-05-02T21:17:32.827Z,1714684652.827 [Default:CheckIn] Running Loop=26 2024-05-02T21:17:32.827Z,1714684652.827 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-02T21:17:32.827Z,1714684652.827 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-02T21:17:34.832Z,1714684654.832 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211734.00,A,3648.16320,N,12147.28533,W,0.933,183.49,020524,,,A*70 2024-05-02T21:17:34.844Z,1714684654.844 [NAL9602](INFO): GPS fix at 20240502T211734: (36.802720, -121.788089) 2024-05-02T21:17:34.856Z,1714684654.856 [Default:CheckIn:Read_GPS] Stopped 2024-05-02T21:17:34.856Z,1714684654.856 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-02T21:17:42.637Z,1714684662.637 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240502T192538/Courier0057.lzma 2024-05-02T21:17:44.182Z,1714684664.182 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Courier0057.lzma.bak 2024-05-02T21:17:44.182Z,1714684664.182 [DataOverHttps](INFO): SBD MOMSN=19817675 2024-05-02T21:17:59.553Z,1714684679.553 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240502T192538/Express0058.lzma 2024-05-02T21:18:00.555Z,1714684680.555 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192538/Express0058.lzma.bak 2024-05-02T21:18:00.556Z,1714684680.556 [DataOverHttps](INFO): SBD MOMSN=19817678 2024-05-02T21:18:01.914Z,1714684681.914 [Default:CheckIn:Read_Iridium] Stopped 2024-05-02T21:18:01.914Z,1714684681.914 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-02T21:18:01.914Z,1714684681.914 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-02T21:18:05.535Z,1714684685.535 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-02T21:18:05.617Z,1714684685.617 [NAL9602](ERROR): received: +CSQ:0 OK293, 2, 0, 0, 0 OK 2024-05-02T21:18:23.725Z,1714684703.725 [NAL9602](INFO): SBD MO Status=0, MOMSN=51293, MT Status=0, MTMSN=0 2024-05-02T21:18:23.725Z,1714684703.725 [NAL9602](INFO): No messages in MT queue 2024-05-02T21:18:27.765Z,1714684707.765 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T21:18:40.276Z,1714684720.276 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T21:18:53.657Z,1714684733.657 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T21:18:54.491Z,1714684734.491 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-02T21:19:06.129Z,1714684746.129 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T21:19:19.058Z,1714684759.058 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T21:19:31.581Z,1714684771.581 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T21:19:44.509Z,1714684784.509 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-02T21:21:08.196Z,1714684868.196 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-05-02T21:22:13.454Z,1714684933.454 [CommandExec](IMPORTANT): got command quit 2024-05-02T21:22:14.461Z,1714684934.461 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:14.462Z,1714684934.462 [CommandExec](INFO): Uninitializing the command executive. 2024-05-02T21:22:14.462Z,1714684934.462 [CommandExec](INFO): Uninitializing the command scheduler. 2024-05-02T21:22:14.462Z,1714684934.462 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:14.582Z,1714684934.582 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-05-02T21:22:14.582Z,1714684934.582 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-05-02T21:22:14.582Z,1714684934.582 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:14.583Z,1714684934.583 [NavChartDb](INFO): Join timeout helper Thread ID is 8903 2024-05-02T21:22:14.734Z,1714684934.734 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:14.734Z,1714684934.734 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:14.753Z,1714684934.753 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-05-02T21:22:14.753Z,1714684934.753 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:14.754Z,1714684934.754 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8904 2024-05-02T21:22:15.142Z,1714684935.142 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:15.142Z,1714684935.142 [WetLabsBB2FL](INFO): Powering down 2024-05-02T21:22:15.143Z,1714684935.143 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:15.145Z,1714684935.145 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-05-02T21:22:15.146Z,1714684935.146 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:15.146Z,1714684935.146 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8905 2024-05-02T21:22:15.446Z,1714684935.446 [CTD_Seabird](INFO): Powering down 2024-05-02T21:22:15.461Z,1714684935.461 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:15.462Z,1714684935.462 [CTD_Seabird](INFO): Powering down 2024-05-02T21:22:15.474Z,1714684935.474 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:15.481Z,1714684935.481 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-05-02T21:22:15.482Z,1714684935.482 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:15.482Z,1714684935.482 [DAT](INFO): Join timeout helper Thread ID is 8906 2024-05-02T21:22:15.622Z,1714684935.622 [DAT](INFO): Powering down 2024-05-02T21:22:15.694Z,1714684935.694 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:15.694Z,1714684935.694 [DAT](INFO): Powering down 2024-05-02T21:22:15.695Z,1714684935.695 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:15.696Z,1714684935.696 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-05-02T21:22:15.696Z,1714684935.696 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:15.696Z,1714684935.696 [Radio_Surface](INFO): Join timeout helper Thread ID is 8907 2024-05-02T21:22:16.026Z,1714684936.026 [Radio_Surface](INFO): Powering down 2024-05-02T21:22:16.026Z,1714684936.026 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:16.027Z,1714684936.027 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:16.030Z,1714684936.030 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-05-02T21:22:16.031Z,1714684936.031 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:16.031Z,1714684936.031 [Onboard](INFO): Join timeout helper Thread ID is 8908 2024-05-02T21:22:19.545Z,1714684939.545 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:19.546Z,1714684939.546 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:19.558Z,1714684939.558 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-05-02T21:22:19.558Z,1714684939.558 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:19.559Z,1714684939.559 [DataOverHttps](INFO): Join timeout helper Thread ID is 8909 2024-05-02T21:22:20.337Z,1714684940.337 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:20.341Z,1714684940.341 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.350Z,1714684940.350 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-05-02T21:22:20.350Z,1714684940.350 [logger ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.351Z,1714684940.351 [logger](INFO): Join timeout helper Thread ID is 8910 2024-05-02T21:22:20.373Z,1714684940.373 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:20.374Z,1714684940.374 [logger ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.390Z,1714684940.390 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-05-02T21:22:20.390Z,1714684940.390 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.390Z,1714684940.390 [CommandLine](INFO): Join timeout helper Thread ID is 8911 2024-05-02T21:22:20.465Z,1714684940.465 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:20.466Z,1714684940.466 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.470Z,1714684940.470 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-05-02T21:22:20.470Z,1714684940.470 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.471Z,1714684940.471 [CommandExec](INFO): Join timeout helper Thread ID is 8912 2024-05-02T21:22:20.472Z,1714684940.472 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-05-02T21:22:20.472Z,1714684940.472 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.472Z,1714684940.472 [controlThread](INFO): Join timeout helper Thread ID is 8913 2024-05-02T21:22:20.557Z,1714684940.557 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-02T21:22:20.558Z,1714684940.558 [controlThread](DEBUG): Uninitializing ControlThread 2024-05-02T21:22:20.558Z,1714684940.558 [AHRS_M2](INFO): Powering down 2024-05-02T21:22:20.630Z,1714684940.630 [NAL9602](INFO): Powering down 2024-05-02T21:22:20.633Z,1714684940.633 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-05-02T21:22:20.634Z,1714684940.634 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-05-02T21:22:20.634Z,1714684940.634 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-05-02T21:22:20.635Z,1714684940.635 [MissionManager](INFO): Uninitializing Mission Default 2024-05-02T21:22:20.635Z,1714684940.635 [Default] Stopped 2024-05-02T21:22:20.635Z,1714684940.635 [Default](DEBUG): Aggregate::uninitialize Default 2024-05-02T21:22:20.635Z,1714684940.635 [Default:B.GoToSurface] Stopped 2024-05-02T21:22:20.635Z,1714684940.635 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-02T21:22:20.635Z,1714684940.635 [Default:CheckIn] Stopped 2024-05-02T21:22:20.635Z,1714684940.635 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-02T21:22:20.636Z,1714684940.636 [Default:CheckIn:C.Wait] Stopped 2024-05-02T21:22:20.636Z,1714684940.636 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-02T21:22:20.639Z,1714684940.639 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-05-02T21:22:20.639Z,1714684940.639 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-05-02T21:22:20.639Z,1714684940.639 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-05-02T21:22:20.639Z,1714684940.639 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-05-02T21:22:20.640Z,1714684940.640 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-05-02T21:22:20.640Z,1714684940.640 [BuoyancyServo](INFO): Powering down 2024-05-02T21:22:20.653Z,1714684940.653 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-05-02T21:22:20.654Z,1714684940.654 [ElevatorServo](INFO): Powering down 2024-05-02T21:22:20.654Z,1714684940.654 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-05-02T21:22:20.655Z,1714684940.655 [MassServo](INFO): Powering down 2024-05-02T21:22:20.655Z,1714684940.655 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-02T21:22:20.655Z,1714684940.655 [RudderServo](INFO): Powering down 2024-05-02T21:22:20.656Z,1714684940.656 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-05-02T21:22:20.656Z,1714684940.656 [ThrusterHE](INFO): Powering down 2024-05-02T21:22:20.657Z,1714684940.657 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-05-02T21:22:20.658Z,1714684940.658 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-05-02T21:22:20.658Z,1714684940.658 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-05-02T21:22:20.658Z,1714684940.658 [CBIT](DEBUG): Powering off loads. 2024-05-02T21:22:20.669Z,1714684940.669 [CBIT](DEBUG): Disabling WDT. 2024-05-02T21:22:20.681Z,1714684940.681 [CBIT](DEBUG): Opening all GF detection circuits. 2024-05-02T21:22:20.682Z,1714684940.682 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.728Z,1714684940.728 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.734Z,1714684940.734 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.736Z,1714684940.736 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.742Z,1714684940.742 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.815Z,1714684940.815 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.819Z,1714684940.819 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.861Z,1714684940.861 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-02T21:22:20.951Z,1714684940.951 [logger ThreadHandler](INFO): Thread cancelled.