2024-01-22T17:27:23.598Z,1705944443.598 [CommandExec](IMPORTANT): got command restart logs 2024-01-22T17:29:27.549Z,1705944567.549 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,-327+1063.15, +912.21, +299.38, 0.00,136.00 2024-01-22T17:29:46.962Z,1705944586.962 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T17:29:46.962Z,1705944586.962 [Default:CheckIn:C.Wait] Stopped 2024-01-22T17:29:46.962Z,1705944586.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T17:29:46.963Z,1705944586.963 [Default:CheckIn:D] Running Loop=1 2024-01-22T17:29:47.388Z,1705944587.388 [Default:CheckIn:D] Stopped 2024-01-22T17:29:47.388Z,1705944587.388 [Default:CheckIn:E] Running Loop=1 2024-01-22T17:29:47.750Z,1705944587.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.336121 min 2024-01-22T17:29:47.750Z,1705944587.750 [Default:CheckIn:E] Stopped 2024-01-22T17:29:47.751Z,1705944587.751 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T17:29:47.751Z,1705944587.751 [Default:CheckIn] Stopped 2024-01-22T17:29:47.751Z,1705944587.751 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T17:29:47.751Z,1705944587.751 [Default:CheckIn](INFO): Running loop #6 2024-01-22T17:29:47.751Z,1705944587.751 [Default:CheckIn] Running Loop=6 2024-01-22T17:29:47.751Z,1705944587.751 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T17:29:47.751Z,1705944587.751 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T17:29:49.763Z,1705944589.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172949.00,A,3710.76268,N,12225.39330,W,1.108,13.21,220124,,,A*46 2024-01-22T17:29:49.781Z,1705944589.781 [NAL9602](INFO): GPS fix at 20240122T172949: (37.179378, -122.423222) 2024-01-22T17:29:49.804Z,1705944589.804 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T17:29:49.805Z,1705944589.805 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T17:29:57.760Z,1705944597.760 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240122T121741/Courier0085.lzma 2024-01-22T17:29:58.763Z,1705944598.763 [DataOverHttps](INFO): Moved sent file to Logs/20240122T121741/Courier0085.lzma.bak 2024-01-22T17:29:58.763Z,1705944598.763 [DataOverHttps](INFO): SBD MOMSN=19206218 2024-01-22T17:30:01.911Z,1705944601.911 [NAL9602](INFO): SBD MO Status=0, MOMSN=36958, MT Status=0, MTMSN=0 2024-01-22T17:30:01.911Z,1705944601.911 [NAL9602](INFO): No messages in MT queue 2024-01-22T17:30:18.000Z,1705944618.000 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20240122T172723/Courier0000.lzma 2024-01-22T17:30:19.002Z,1705944619.002 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0000.lzma.bak 2024-01-22T17:30:19.003Z,1705944619.003 [DataOverHttps](INFO): SBD MOMSN=19206220 2024-01-22T17:30:32.665Z,1705944632.665 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T17:30:35.536Z,1705944635.536 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240122T172723/Courier0003.lzma 2024-01-22T17:30:36.538Z,1705944636.538 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0003.lzma.bak 2024-01-22T17:30:36.538Z,1705944636.538 [DataOverHttps](INFO): SBD MOMSN=19206224 2024-01-22T17:30:52.748Z,1705944652.748 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20240122T121741/Express0086.lzma 2024-01-22T17:30:53.750Z,1705944653.750 [DataOverHttps](INFO): Moved sent file to Logs/20240122T121741/Express0086.lzma.bak 2024-01-22T17:30:53.751Z,1705944653.751 [DataOverHttps](INFO): SBD MOMSN=19206226 2024-01-22T17:31:11.169Z,1705944671.169 [DataOverHttps](INFO): Sending 460 bytes from file Logs/20240122T172723/Express0001.lzma 2024-01-22T17:31:12.170Z,1705944672.170 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0001.lzma.bak 2024-01-22T17:31:12.170Z,1705944672.170 [DataOverHttps](INFO): SBD MOMSN=19206231 2024-01-22T17:31:28.504Z,1705944688.504 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Express0004.lzma 2024-01-22T17:31:29.506Z,1705944689.506 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0004.lzma.bak 2024-01-22T17:31:29.506Z,1705944689.506 [DataOverHttps](INFO): SBD MOMSN=19206237 2024-01-22T17:31:31.291Z,1705944691.291 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T17:31:31.291Z,1705944691.291 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T17:31:31.292Z,1705944691.292 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T17:36:31.831Z,1705944991.831 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T17:36:31.831Z,1705944991.831 [Default:CheckIn:C.Wait] Stopped 2024-01-22T17:36:31.831Z,1705944991.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T17:36:31.832Z,1705944991.832 [Default:CheckIn:D] Running Loop=1 2024-01-22T17:36:32.235Z,1705944992.235 [Default:CheckIn:D] Stopped 2024-01-22T17:36:32.235Z,1705944992.235 [Default:CheckIn:E] Running Loop=1 2024-01-22T17:36:32.646Z,1705944992.646 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.083577 min 2024-01-22T17:36:32.646Z,1705944992.646 [Default:CheckIn:E] Stopped 2024-01-22T17:36:32.646Z,1705944992.646 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T17:36:32.646Z,1705944992.646 [Default:CheckIn] Stopped 2024-01-22T17:36:32.646Z,1705944992.646 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T17:36:32.646Z,1705944992.646 [Default:CheckIn](INFO): Running loop #7 2024-01-22T17:36:32.647Z,1705944992.647 [Default:CheckIn] Running Loop=7 2024-01-22T17:36:32.647Z,1705944992.647 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T17:36:32.647Z,1705944992.647 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T17:36:34.651Z,1705944994.651 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173634.00,A,3710.04495,N,12224.70336,W,21.382,146.56,220124,,,D*4F 2024-01-22T17:36:34.653Z,1705944994.653 [NAL9602](INFO): GPS fix at 20240122T173634: (37.167416, -122.411723) 2024-01-22T17:36:34.685Z,1705944994.685 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T17:36:34.685Z,1705944994.685 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T17:36:41.812Z,1705945001.812 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0006.lzma 2024-01-22T17:36:42.814Z,1705945002.814 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0006.lzma.bak 2024-01-22T17:36:42.814Z,1705945002.814 [DataOverHttps](INFO): SBD MOMSN=19206254 2024-01-22T17:36:57.459Z,1705945017.459 [NAL9602](INFO): SBD MO Status=0, MOMSN=36959, MT Status=0, MTMSN=0 2024-01-22T17:36:57.459Z,1705945017.459 [NAL9602](INFO): No messages in MT queue 2024-01-22T17:36:59.172Z,1705945019.172 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240122T172723/Express0007.lzma 2024-01-22T17:37:00.175Z,1705945020.175 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0007.lzma.bak 2024-01-22T17:37:00.175Z,1705945020.175 [DataOverHttps](INFO): SBD MOMSN=19206257 2024-01-22T17:37:01.917Z,1705945021.917 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T17:37:01.917Z,1705945021.917 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T17:37:01.917Z,1705945021.917 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T17:37:28.166Z,1705945048.166 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T17:38:49.396Z,1705945129.396 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2024-01-22T17:38:49.396Z,1705945129.396 [RDI_Pathfinder] Communications Fault, FailCount= 1 2024-01-22T17:38:49.396Z,1705945129.396 [RDI_Pathfinder](ERROR): Communications Fault 2024-01-22T17:38:49.455Z,1705945129.455 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2024-01-22T17:38:49.861Z,1705945129.861 [RDI_Pathfinder](INFO): Powering down 2024-01-22T17:38:50.638Z,1705945130.638 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2024-01-22T17:38:50.639Z,1705945130.639 [RDI_Pathfinder] No Fault, FailCount= 1 2024-01-22T17:38:50.998Z,1705945130.998 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2024-01-22T17:42:02.579Z,1705945322.579 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T17:42:02.579Z,1705945322.579 [Default:CheckIn:C.Wait] Stopped 2024-01-22T17:42:02.579Z,1705945322.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T17:42:02.579Z,1705945322.579 [Default:CheckIn:D] Running Loop=1 2024-01-22T17:42:02.945Z,1705945322.945 [Default:CheckIn:D] Stopped 2024-01-22T17:42:02.945Z,1705945322.945 [Default:CheckIn:E] Running Loop=1 2024-01-22T17:42:03.349Z,1705945323.349 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.595414 min 2024-01-22T17:42:03.349Z,1705945323.349 [Default:CheckIn:E] Stopped 2024-01-22T17:42:03.349Z,1705945323.349 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T17:42:03.349Z,1705945323.349 [Default:CheckIn] Stopped 2024-01-22T17:42:03.350Z,1705945323.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T17:42:03.350Z,1705945323.350 [Default:CheckIn](INFO): Running loop #8 2024-01-22T17:42:03.350Z,1705945323.350 [Default:CheckIn] Running Loop=8 2024-01-22T17:42:03.350Z,1705945323.350 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T17:42:03.350Z,1705945323.350 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T17:42:05.359Z,1705945325.359 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174204.00,A,3708.47040,N,12223.52718,W,22.102,140.39,220124,,,D*44 2024-01-22T17:42:05.371Z,1705945325.371 [NAL9602](INFO): GPS fix at 20240122T174204: (37.141173, -122.392120) 2024-01-22T17:42:05.382Z,1705945325.382 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T17:42:05.383Z,1705945325.383 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T17:42:15.888Z,1705945335.888 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0009.lzma 2024-01-22T17:42:16.890Z,1705945336.890 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0009.lzma.bak 2024-01-22T17:42:16.890Z,1705945336.890 [DataOverHttps](INFO): SBD MOMSN=19206365 2024-01-22T17:42:30.831Z,1705945350.831 [NAL9602](INFO): SBD MO Status=0, MOMSN=36960, MT Status=0, MTMSN=0 2024-01-22T17:42:30.831Z,1705945350.831 [NAL9602](INFO): No messages in MT queue 2024-01-22T17:42:38.772Z,1705945358.772 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240122T172723/Express0010.lzma 2024-01-22T17:42:39.774Z,1705945359.774 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0010.lzma.bak 2024-01-22T17:42:39.774Z,1705945359.774 [DataOverHttps](INFO): SBD MOMSN=19206368 2024-01-22T17:42:41.760Z,1705945361.760 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T17:42:41.761Z,1705945361.761 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T17:42:41.761Z,1705945361.761 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T17:43:01.537Z,1705945381.537 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T17:47:42.274Z,1705945662.274 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T17:47:42.275Z,1705945662.275 [Default:CheckIn:C.Wait] Stopped 2024-01-22T17:47:42.275Z,1705945662.275 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T17:47:42.275Z,1705945662.275 [Default:CheckIn:D] Running Loop=1 2024-01-22T17:47:42.712Z,1705945662.712 [Default:CheckIn:D] Stopped 2024-01-22T17:47:42.712Z,1705945662.712 [Default:CheckIn:E] Running Loop=1 2024-01-22T17:47:43.083Z,1705945663.082 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.258187 min 2024-01-22T17:47:43.083Z,1705945663.083 [Default:CheckIn:E] Stopped 2024-01-22T17:47:43.083Z,1705945663.083 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T17:47:43.083Z,1705945663.083 [Default:CheckIn] Stopped 2024-01-22T17:47:43.083Z,1705945663.083 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T17:47:43.087Z,1705945663.087 [Default:CheckIn](INFO): Running loop #9 2024-01-22T17:47:43.087Z,1705945663.087 [Default:CheckIn] Running Loop=9 2024-01-22T17:47:43.087Z,1705945663.087 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T17:47:43.088Z,1705945663.088 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T17:47:45.065Z,1705945665.065 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174744.00,A,3706.96932,N,12222.26935,W,17.767,151.97,220124,,,D*4F 2024-01-22T17:47:45.067Z,1705945665.067 [NAL9602](INFO): GPS fix at 20240122T174744: (37.116155, -122.371156) 2024-01-22T17:47:45.079Z,1705945665.079 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T17:47:45.080Z,1705945665.080 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T17:47:53.163Z,1705945673.163 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0012.lzma 2024-01-22T17:47:54.165Z,1705945674.165 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0012.lzma.bak 2024-01-22T17:47:54.165Z,1705945674.165 [DataOverHttps](INFO): SBD MOMSN=19206373 2024-01-22T17:48:09.818Z,1705945689.818 [NAL9602](INFO): SBD MO Status=2, MOMSN=36961, MT Status=2, MTMSN=0 2024-01-22T17:48:09.818Z,1705945689.818 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-22T17:48:17.603Z,1705945697.603 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240122T172723/Express0013.lzma 2024-01-22T17:48:18.605Z,1705945698.605 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0013.lzma.bak 2024-01-22T17:48:18.605Z,1705945698.605 [DataOverHttps](INFO): SBD MOMSN=19206376 2024-01-22T17:48:20.344Z,1705945700.344 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T17:48:20.344Z,1705945700.344 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T17:48:20.345Z,1705945700.345 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T17:48:27.589Z,1705945707.589 [NAL9602](INFO): SBD MO Status=2, MOMSN=36961, MT Status=2, MTMSN=0 2024-01-22T17:48:27.589Z,1705945707.589 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-22T17:48:37.285Z,1705945717.285 [NAL9602](INFO): SBD MO Status=0, MOMSN=36961, MT Status=0, MTMSN=0 2024-01-22T17:48:37.285Z,1705945717.285 [NAL9602](INFO): No messages in MT queue 2024-01-22T17:49:07.992Z,1705945747.992 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T17:53:20.995Z,1705946000.995 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T17:53:20.996Z,1705946000.996 [Default:CheckIn:C.Wait] Stopped 2024-01-22T17:53:20.996Z,1705946000.996 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T17:53:20.996Z,1705946000.996 [Default:CheckIn:D] Running Loop=1 2024-01-22T17:53:21.392Z,1705946001.392 [Default:CheckIn:D] Stopped 2024-01-22T17:53:21.392Z,1705946001.392 [Default:CheckIn:E] Running Loop=1 2024-01-22T17:53:21.787Z,1705946001.787 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.902861 min 2024-01-22T17:53:21.787Z,1705946001.787 [Default:CheckIn:E] Stopped 2024-01-22T17:53:21.787Z,1705946001.787 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T17:53:21.787Z,1705946001.787 [Default:CheckIn] Stopped 2024-01-22T17:53:21.787Z,1705946001.787 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T17:53:21.788Z,1705946001.788 [Default:CheckIn](INFO): Running loop #10 2024-01-22T17:53:21.788Z,1705946001.788 [Default:CheckIn] Running Loop=10 2024-01-22T17:53:21.788Z,1705946001.788 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T17:53:21.788Z,1705946001.788 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T17:53:23.802Z,1705946003.802 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175323.00,A,3705.48760,N,12221.14687,W,16.892,144.95,220124,,,D*44 2024-01-22T17:53:23.804Z,1705946003.804 [NAL9602](INFO): GPS fix at 20240122T175323: (37.091460, -122.352448) 2024-01-22T17:53:23.815Z,1705946003.815 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T17:53:23.815Z,1705946003.815 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T17:53:30.999Z,1705946010.999 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0015.lzma 2024-01-22T17:53:32.001Z,1705946012.001 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0015.lzma.bak 2024-01-22T17:53:32.001Z,1705946012.001 [DataOverHttps](INFO): SBD MOMSN=19206463 2024-01-22T17:53:38.518Z,1705946018.518 [NAL9602](INFO): SBD MO Status=0, MOMSN=36962, MT Status=0, MTMSN=0 2024-01-22T17:53:38.518Z,1705946018.518 [NAL9602](INFO): No messages in MT queue 2024-01-22T17:53:48.335Z,1705946028.335 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20240122T172723/Express0016.lzma 2024-01-22T17:53:49.337Z,1705946029.337 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0016.lzma.bak 2024-01-22T17:53:49.337Z,1705946029.337 [DataOverHttps](INFO): SBD MOMSN=19206466 2024-01-22T17:53:51.451Z,1705946031.451 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T17:53:51.455Z,1705946031.455 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T17:53:51.455Z,1705946031.455 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T17:54:09.248Z,1705946049.248 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T17:58:52.086Z,1705946332.086 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T17:58:52.086Z,1705946332.086 [Default:CheckIn:C.Wait] Stopped 2024-01-22T17:58:52.086Z,1705946332.086 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T17:58:52.087Z,1705946332.087 [Default:CheckIn:D] Running Loop=1 2024-01-22T17:58:52.510Z,1705946332.510 [Default:CheckIn:D] Stopped 2024-01-22T17:58:52.510Z,1705946332.510 [Default:CheckIn:E] Running Loop=1 2024-01-22T17:58:52.893Z,1705946332.893 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.421488 min 2024-01-22T17:58:52.893Z,1705946332.893 [Default:CheckIn:E] Stopped 2024-01-22T17:58:52.893Z,1705946332.893 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T17:58:52.893Z,1705946332.893 [Default:CheckIn] Stopped 2024-01-22T17:58:52.894Z,1705946332.894 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T17:58:52.894Z,1705946332.894 [Default:CheckIn](INFO): Running loop #11 2024-01-22T17:58:52.894Z,1705946332.894 [Default:CheckIn] Running Loop=11 2024-01-22T17:58:52.894Z,1705946332.894 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T17:58:52.894Z,1705946332.894 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T17:58:54.900Z,1705946334.900 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175854.00,A,3704.06646,N,12219.74452,W,21.324,121.15,220124,,,D*4F 2024-01-22T17:58:54.902Z,1705946334.902 [NAL9602](INFO): GPS fix at 20240122T175854: (37.067774, -122.329075) 2024-01-22T17:58:54.913Z,1705946334.913 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T17:58:54.913Z,1705946334.913 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T17:59:02.303Z,1705946342.303 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0018.lzma 2024-01-22T17:59:03.306Z,1705946343.306 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0018.lzma.bak 2024-01-22T17:59:03.306Z,1705946343.306 [DataOverHttps](INFO): SBD MOMSN=19206472 2024-01-22T17:59:19.931Z,1705946359.931 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20240122T172723/Express0019.lzma 2024-01-22T17:59:20.933Z,1705946360.933 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0019.lzma.bak 2024-01-22T17:59:20.933Z,1705946360.933 [DataOverHttps](INFO): SBD MOMSN=19206475 2024-01-22T17:59:22.800Z,1705946362.800 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T17:59:22.800Z,1705946362.800 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T17:59:22.800Z,1705946362.800 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T17:59:27.626Z,1705946367.626 [NAL9602](INFO): SBD MO Status=0, MOMSN=36963, MT Status=0, MTMSN=0 2024-01-22T17:59:27.626Z,1705946367.626 [NAL9602](INFO): No messages in MT queue 2024-01-22T17:59:58.334Z,1705946398.334 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:02:40.358Z,1705946560.358 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2024-01-22T18:02:40.386Z,1705946560.386 [BPC1](INFO): Received data from all battery sticks. 2024-01-22T18:04:23.271Z,1705946663.271 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T18:04:23.271Z,1705946663.271 [Default:CheckIn:C.Wait] Stopped 2024-01-22T18:04:23.271Z,1705946663.271 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T18:04:23.271Z,1705946663.271 [Default:CheckIn:D] Running Loop=1 2024-01-22T18:04:23.677Z,1705946663.677 [Default:CheckIn:D] Stopped 2024-01-22T18:04:23.677Z,1705946663.677 [Default:CheckIn:E] Running Loop=1 2024-01-22T18:04:24.073Z,1705946664.073 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.940938 min 2024-01-22T18:04:24.073Z,1705946664.073 [Default:CheckIn:E] Stopped 2024-01-22T18:04:24.073Z,1705946664.073 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T18:04:24.074Z,1705946664.074 [Default:CheckIn] Stopped 2024-01-22T18:04:24.074Z,1705946664.074 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T18:04:24.074Z,1705946664.074 [Default:CheckIn](INFO): Running loop #12 2024-01-22T18:04:24.074Z,1705946664.074 [Default:CheckIn] Running Loop=12 2024-01-22T18:04:24.074Z,1705946664.074 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T18:04:24.074Z,1705946664.074 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T18:04:26.086Z,1705946666.086 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180425.00,A,3702.68940,N,12218.19997,W,15.978,140.54,220124,,,D*40 2024-01-22T18:04:26.088Z,1705946666.088 [NAL9602](INFO): GPS fix at 20240122T180425: (37.044823, -122.303333) 2024-01-22T18:04:26.099Z,1705946666.099 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T18:04:26.099Z,1705946666.099 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T18:04:34.467Z,1705946674.467 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240122T172723/Courier0021.lzma 2024-01-22T18:04:35.469Z,1705946675.469 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0021.lzma.bak 2024-01-22T18:04:35.469Z,1705946675.469 [DataOverHttps](INFO): SBD MOMSN=19206572 2024-01-22T18:04:51.855Z,1705946691.855 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240122T172723/Express0022.lzma 2024-01-22T18:04:52.776Z,1705946692.776 [NAL9602](INFO): SBD MO Status=0, MOMSN=36964, MT Status=0, MTMSN=0 2024-01-22T18:04:52.776Z,1705946692.776 [NAL9602](INFO): No messages in MT queue 2024-01-22T18:04:52.861Z,1705946692.861 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0022.lzma.bak 2024-01-22T18:04:52.861Z,1705946692.861 [DataOverHttps](INFO): SBD MOMSN=19206575 2024-01-22T18:04:55.226Z,1705946695.226 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T18:04:55.226Z,1705946695.226 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T18:04:55.227Z,1705946695.227 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T18:05:23.460Z,1705946723.460 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:09:55.854Z,1705946995.854 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T18:09:55.854Z,1705946995.854 [Default:CheckIn:C.Wait] Stopped 2024-01-22T18:09:55.854Z,1705946995.854 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T18:09:55.855Z,1705946995.855 [Default:CheckIn:D] Running Loop=1 2024-01-22T18:09:56.257Z,1705946996.257 [Default:CheckIn:D] Stopped 2024-01-22T18:09:56.257Z,1705946996.257 [Default:CheckIn:E] Running Loop=1 2024-01-22T18:09:56.682Z,1705946996.682 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.483952 min 2024-01-22T18:09:56.682Z,1705946996.682 [Default:CheckIn:E] Stopped 2024-01-22T18:09:56.682Z,1705946996.682 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T18:09:56.682Z,1705946996.682 [Default:CheckIn] Stopped 2024-01-22T18:09:56.683Z,1705946996.683 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T18:09:56.683Z,1705946996.683 [Default:CheckIn](INFO): Running loop #13 2024-01-22T18:09:56.683Z,1705946996.683 [Default:CheckIn] Running Loop=13 2024-01-22T18:09:56.683Z,1705946996.683 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T18:09:56.683Z,1705946996.683 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T18:09:58.678Z,1705946998.678 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180958.00,A,3702.67355,N,12218.00299,W,12.227,128.08,220124,,,D*4A 2024-01-22T18:09:58.680Z,1705946998.680 [NAL9602](INFO): GPS fix at 20240122T180958: (37.044559, -122.300050) 2024-01-22T18:09:58.731Z,1705946998.731 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T18:09:58.731Z,1705946998.731 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T18:10:06.122Z,1705947006.122 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0024.lzma 2024-01-22T18:10:07.125Z,1705947007.125 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0024.lzma.bak 2024-01-22T18:10:07.125Z,1705947007.125 [DataOverHttps](INFO): SBD MOMSN=19206580 2024-01-22T18:10:21.410Z,1705947021.410 [NAL9602](INFO): SBD MO Status=0, MOMSN=36965, MT Status=0, MTMSN=0 2024-01-22T18:10:21.410Z,1705947021.410 [NAL9602](INFO): No messages in MT queue 2024-01-22T18:10:23.319Z,1705947023.319 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240122T172723/Express0025.lzma 2024-01-22T18:10:24.321Z,1705947024.321 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0025.lzma.bak 2024-01-22T18:10:24.321Z,1705947024.321 [DataOverHttps](INFO): SBD MOMSN=19206592 2024-01-22T18:10:26.276Z,1705947026.276 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T18:10:26.276Z,1705947026.276 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T18:10:26.276Z,1705947026.276 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T18:10:52.112Z,1705947052.112 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:15:26.874Z,1705947326.874 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T18:15:26.874Z,1705947326.874 [Default:CheckIn:C.Wait] Stopped 2024-01-22T18:15:26.874Z,1705947326.874 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T18:15:26.874Z,1705947326.874 [Default:CheckIn:D] Running Loop=1 2024-01-22T18:15:27.246Z,1705947327.246 [Default:CheckIn:D] Stopped 2024-01-22T18:15:27.246Z,1705947327.246 [Default:CheckIn:E] Running Loop=1 2024-01-22T18:15:27.674Z,1705947327.674 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.000423 min 2024-01-22T18:15:27.674Z,1705947327.674 [Default:CheckIn:E] Stopped 2024-01-22T18:15:27.674Z,1705947327.674 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T18:15:27.674Z,1705947327.674 [Default:CheckIn] Stopped 2024-01-22T18:15:27.674Z,1705947327.674 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T18:15:27.674Z,1705947327.674 [Default:CheckIn](INFO): Running loop #14 2024-01-22T18:15:27.674Z,1705947327.674 [Default:CheckIn] Running Loop=14 2024-01-22T18:15:27.674Z,1705947327.674 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T18:15:27.675Z,1705947327.675 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T18:15:29.657Z,1705947329.657 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181529.00,A,3701.26442,N,12216.68096,W,20.197,138.35,220124,,,D*4D 2024-01-22T18:15:29.660Z,1705947329.660 [NAL9602](INFO): GPS fix at 20240122T181529: (37.021074, -122.278016) 2024-01-22T18:15:29.691Z,1705947329.691 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T18:15:29.691Z,1705947329.691 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T18:15:37.379Z,1705947337.379 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0027.lzma 2024-01-22T18:15:39.808Z,1705947339.808 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0027.lzma.bak 2024-01-22T18:15:39.809Z,1705947339.809 [DataOverHttps](INFO): SBD MOMSN=19206680 2024-01-22T18:15:49.053Z,1705947349.053 [NAL9602](INFO): SBD MO Status=2, MOMSN=36966, MT Status=2, MTMSN=0 2024-01-22T18:15:49.053Z,1705947349.053 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-22T18:15:58.135Z,1705947358.135 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240122T172723/Express0028.lzma 2024-01-22T18:15:59.137Z,1705947359.137 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0028.lzma.bak 2024-01-22T18:15:59.137Z,1705947359.137 [DataOverHttps](INFO): SBD MOMSN=19206683 2024-01-22T18:16:01.691Z,1705947361.691 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T18:16:01.691Z,1705947361.691 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T18:16:01.691Z,1705947361.691 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T18:16:06.077Z,1705947366.077 [NAL9602](INFO): SBD MO Status=0, MOMSN=36966, MT Status=0, MTMSN=0 2024-01-22T18:16:06.077Z,1705947366.077 [NAL9602](INFO): No messages in MT queue 2024-01-22T18:16:36.776Z,1705947396.776 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:17:08.701Z,1705947428.701 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,24012210181583,35.0, +15.9, 0.0,150 2024-01-22T18:21:02.259Z,1705947662.259 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T18:21:02.259Z,1705947662.259 [Default:CheckIn:C.Wait] Stopped 2024-01-22T18:21:02.259Z,1705947662.259 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T18:21:02.260Z,1705947662.260 [Default:CheckIn:D] Running Loop=1 2024-01-22T18:21:02.693Z,1705947662.693 [Default:CheckIn:D] Stopped 2024-01-22T18:21:02.693Z,1705947662.693 [Default:CheckIn:E] Running Loop=1 2024-01-22T18:21:03.058Z,1705947663.058 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.591203 min 2024-01-22T18:21:03.059Z,1705947663.059 [Default:CheckIn:E] Stopped 2024-01-22T18:21:03.059Z,1705947663.059 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T18:21:03.059Z,1705947663.059 [Default:CheckIn] Stopped 2024-01-22T18:21:03.059Z,1705947663.059 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T18:21:03.059Z,1705947663.059 [Default:CheckIn](INFO): Running loop #15 2024-01-22T18:21:03.059Z,1705947663.059 [Default:CheckIn] Running Loop=15 2024-01-22T18:21:03.059Z,1705947663.059 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T18:21:03.060Z,1705947663.060 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T18:21:05.067Z,1705947665.067 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182104.00,A,3700.04407,N,12215.01882,W,19.516,126.48,220124,,,D*46 2024-01-22T18:21:05.069Z,1705947665.069 [NAL9602](INFO): GPS fix at 20240122T182104: (37.000735, -122.250314) 2024-01-22T18:21:05.080Z,1705947665.080 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T18:21:05.080Z,1705947665.080 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T18:21:13.106Z,1705947673.106 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0030.lzma 2024-01-22T18:21:14.109Z,1705947674.109 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0030.lzma.bak 2024-01-22T18:21:14.109Z,1705947674.109 [DataOverHttps](INFO): SBD MOMSN=19206777 2024-01-22T18:21:18.432Z,1705947678.432 [NAL9602](INFO): SBD MO Status=0, MOMSN=36967, MT Status=0, MTMSN=0 2024-01-22T18:21:18.433Z,1705947678.433 [NAL9602](INFO): No messages in MT queue 2024-01-22T18:21:30.366Z,1705947690.366 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20240122T172723/Express0031.lzma 2024-01-22T18:21:31.357Z,1705947691.357 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0031.lzma.bak 2024-01-22T18:21:31.357Z,1705947691.357 [DataOverHttps](INFO): SBD MOMSN=19206780 2024-01-22T18:21:33.391Z,1705947693.391 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T18:21:33.391Z,1705947693.391 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T18:21:33.391Z,1705947693.391 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T18:21:49.113Z,1705947709.113 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:26:33.797Z,1705947993.797 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T18:26:33.797Z,1705947993.797 [Default:CheckIn:C.Wait] Stopped 2024-01-22T18:26:33.797Z,1705947993.797 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T18:26:33.797Z,1705947993.797 [Default:CheckIn:D] Running Loop=1 2024-01-22T18:26:34.190Z,1705947994.190 [Default:CheckIn:D] Stopped 2024-01-22T18:26:34.190Z,1705947994.190 [Default:CheckIn:E] Running Loop=1 2024-01-22T18:26:34.649Z,1705947994.649 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.116154 min 2024-01-22T18:26:34.649Z,1705947994.649 [Default:CheckIn:E] Stopped 2024-01-22T18:26:34.649Z,1705947994.649 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T18:26:34.649Z,1705947994.649 [Default:CheckIn] Stopped 2024-01-22T18:26:34.649Z,1705947994.649 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T18:26:34.649Z,1705947994.649 [Default:CheckIn](INFO): Running loop #16 2024-01-22T18:26:34.649Z,1705947994.649 [Default:CheckIn] Running Loop=16 2024-01-22T18:26:34.649Z,1705947994.649 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T18:26:34.649Z,1705947994.649 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T18:26:36.613Z,1705947996.613 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182635.00,A,3659.30408,N,12213.09208,W,19.769,103.00,220124,,,D*41 2024-01-22T18:26:36.615Z,1705947996.615 [NAL9602](INFO): GPS fix at 20240122T182635: (36.988401, -122.218201) 2024-01-22T18:26:36.646Z,1705947996.646 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T18:26:36.646Z,1705947996.646 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T18:26:45.376Z,1705948005.376 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240122T172723/Courier0033.lzma 2024-01-22T18:26:45.899Z,1705948005.899 [NAL9602](INFO): SBD MO Status=0, MOMSN=36968, MT Status=0, MTMSN=0 2024-01-22T18:26:45.899Z,1705948005.899 [NAL9602](INFO): No messages in MT queue 2024-01-22T18:26:46.378Z,1705948006.378 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0033.lzma.bak 2024-01-22T18:26:46.378Z,1705948006.378 [DataOverHttps](INFO): SBD MOMSN=19206785 2024-01-22T18:27:02.592Z,1705948022.592 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20240122T172723/Express0034.lzma 2024-01-22T18:27:03.594Z,1705948023.594 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0034.lzma.bak 2024-01-22T18:27:03.595Z,1705948023.595 [DataOverHttps](INFO): SBD MOMSN=19206788 2024-01-22T18:27:05.292Z,1705948025.292 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T18:27:05.292Z,1705948025.292 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T18:27:05.292Z,1705948025.292 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T18:27:16.598Z,1705948036.598 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:31:25.155Z,1705948285.155 [CBIT](IMPORTANT): Beginning ground fault scan 2024-01-22T18:31:36.045Z,1705948296.045 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.001738 CHAN A1 (24V): 0.015433 CHAN A2 (12V): -0.000273 CHAN A3 (5V): 0.000463 CHAN B0 (3.3V): 0.000917 CHAN B1 (3.15aV): 0.001229 CHAN B2 (3.15bV): 0.000398 CHAN B3 (GND): 0.001186 OPEN: 0.005455 Full Scale: +/- 1 mA 2024-01-22T18:32:05.927Z,1705948325.927 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T18:32:05.927Z,1705948325.927 [Default:CheckIn:C.Wait] Stopped 2024-01-22T18:32:05.927Z,1705948325.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T18:32:05.928Z,1705948325.928 [Default:CheckIn:D] Running Loop=1 2024-01-22T18:32:06.336Z,1705948326.336 [Default:CheckIn:D] Stopped 2024-01-22T18:32:06.336Z,1705948326.336 [Default:CheckIn:E] Running Loop=1 2024-01-22T18:32:06.770Z,1705948326.770 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.651921 min 2024-01-22T18:32:06.770Z,1705948326.770 [Default:CheckIn:E] Stopped 2024-01-22T18:32:06.770Z,1705948326.770 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T18:32:06.770Z,1705948326.770 [Default:CheckIn] Stopped 2024-01-22T18:32:06.771Z,1705948326.771 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T18:32:06.771Z,1705948326.771 [Default:CheckIn](INFO): Running loop #17 2024-01-22T18:32:06.771Z,1705948326.771 [Default:CheckIn] Running Loop=17 2024-01-22T18:32:06.771Z,1705948326.771 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T18:32:06.771Z,1705948326.771 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T18:32:08.739Z,1705948328.739 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183208.00,A,3658.49492,N,12211.03329,W,21.460,121.20,220124,,,D*4F 2024-01-22T18:32:08.742Z,1705948328.742 [NAL9602](INFO): GPS fix at 20240122T183208: (36.974915, -122.183888) 2024-01-22T18:32:08.834Z,1705948328.834 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T18:32:08.834Z,1705948328.834 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T18:32:17.748Z,1705948337.748 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0036.lzma 2024-01-22T18:32:18.750Z,1705948338.750 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0036.lzma.bak 2024-01-22T18:32:18.750Z,1705948338.750 [DataOverHttps](INFO): SBD MOMSN=19206882 2024-01-22T18:32:23.319Z,1705948343.319 [NAL9602](INFO): SBD MO Status=0, MOMSN=36969, MT Status=0, MTMSN=0 2024-01-22T18:32:23.319Z,1705948343.319 [NAL9602](INFO): No messages in MT queue 2024-01-22T18:32:38.009Z,1705948358.009 [DataOverHttps](INFO): Sending 354 bytes from file Logs/20240122T172723/Express0037.lzma 2024-01-22T18:32:39.010Z,1705948359.010 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0037.lzma.bak 2024-01-22T18:32:39.010Z,1705948359.010 [DataOverHttps](INFO): SBD MOMSN=19206885 2024-01-22T18:32:41.133Z,1705948361.133 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T18:32:41.133Z,1705948361.133 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T18:32:41.133Z,1705948361.133 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T18:32:54.021Z,1705948374.021 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:37:41.724Z,1705948661.724 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T18:37:41.724Z,1705948661.724 [Default:CheckIn:C.Wait] Stopped 2024-01-22T18:37:41.724Z,1705948661.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T18:37:41.725Z,1705948661.725 [Default:CheckIn:D] Running Loop=1 2024-01-22T18:37:42.133Z,1705948662.133 [Default:CheckIn:D] Stopped 2024-01-22T18:37:42.133Z,1705948662.133 [Default:CheckIn:E] Running Loop=1 2024-01-22T18:37:42.564Z,1705948662.564 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.248543 min 2024-01-22T18:37:42.564Z,1705948662.564 [Default:CheckIn:E] Stopped 2024-01-22T18:37:42.584Z,1705948662.584 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T18:37:42.584Z,1705948662.584 [Default:CheckIn] Stopped 2024-01-22T18:37:42.584Z,1705948662.584 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T18:37:42.585Z,1705948662.585 [Default:CheckIn](INFO): Running loop #18 2024-01-22T18:37:42.585Z,1705948662.585 [Default:CheckIn] Running Loop=18 2024-01-22T18:37:42.585Z,1705948662.585 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T18:37:42.585Z,1705948662.585 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T18:37:44.519Z,1705948664.519 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183744.00,A,3657.51019,N,12208.89523,W,23.248,114.62,220124,,,D*4B 2024-01-22T18:37:44.521Z,1705948664.521 [NAL9602](INFO): GPS fix at 20240122T183744: (36.958503, -122.148254) 2024-01-22T18:37:44.593Z,1705948664.593 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T18:37:44.593Z,1705948664.593 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T18:37:45.582Z,1705948665.582 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0039.lzma 2024-01-22T18:37:46.038Z,1705948666.038 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0039.lzma.bak 2024-01-22T18:37:46.038Z,1705948666.038 [DataOverHttps](INFO): SBD MOMSN=19206891 2024-01-22T18:37:57.922Z,1705948677.922 [NAL9602](INFO): SBD MO Status=0, MOMSN=36970, MT Status=0, MTMSN=0 2024-01-22T18:37:57.923Z,1705948677.923 [NAL9602](INFO): No messages in MT queue 2024-01-22T18:38:03.332Z,1705948683.332 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20240122T172723/Express0040.lzma 2024-01-22T18:38:04.334Z,1705948684.334 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0040.lzma.bak 2024-01-22T18:38:04.334Z,1705948684.334 [DataOverHttps](INFO): SBD MOMSN=19206894 2024-01-22T18:38:06.885Z,1705948686.885 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T18:38:06.885Z,1705948686.885 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T18:38:06.885Z,1705948686.885 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T18:38:28.691Z,1705948708.691 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:41:10.118Z,1705948870.118 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T18:41:15.138Z,1705948875.138 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239594 2024-01-22T18:41:57.921Z,1705948917.921 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002913 2024-01-22T18:43:07.348Z,1705948987.348 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T18:43:07.348Z,1705948987.348 [Default:CheckIn:C.Wait] Stopped 2024-01-22T18:43:07.348Z,1705948987.348 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T18:43:07.350Z,1705948987.350 [Default:CheckIn:D] Running Loop=1 2024-01-22T18:43:07.758Z,1705948987.758 [Default:CheckIn:D] Stopped 2024-01-22T18:43:07.758Z,1705948987.758 [Default:CheckIn:E] Running Loop=1 2024-01-22T18:43:08.153Z,1705948988.153 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.675627 min 2024-01-22T18:43:08.153Z,1705948988.153 [Default:CheckIn:E] Stopped 2024-01-22T18:43:08.169Z,1705948988.169 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T18:43:08.169Z,1705948988.169 [Default:CheckIn] Stopped 2024-01-22T18:43:08.169Z,1705948988.169 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T18:43:08.169Z,1705948988.169 [Default:CheckIn](INFO): Running loop #19 2024-01-22T18:43:08.170Z,1705948988.170 [Default:CheckIn] Running Loop=19 2024-01-22T18:43:08.170Z,1705948988.170 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T18:43:08.170Z,1705948988.170 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T18:43:10.168Z,1705948990.168 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184309.00,A,3656.63323,N,12206.85708,W,22.898,117.73,220124,,,D*47 2024-01-22T18:43:10.170Z,1705948990.170 [NAL9602](INFO): GPS fix at 20240122T184309: (36.943887, -122.114285) 2024-01-22T18:43:10.181Z,1705948990.181 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T18:43:10.182Z,1705948990.182 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T18:43:20.289Z,1705949000.289 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0042.lzma 2024-01-22T18:43:21.292Z,1705949001.292 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0042.lzma.bak 2024-01-22T18:43:21.292Z,1705949001.292 [DataOverHttps](INFO): SBD MOMSN=19206984 2024-01-22T18:43:26.859Z,1705949006.859 [NAL9602](INFO): SBD MO Status=0, MOMSN=36971, MT Status=0, MTMSN=0 2024-01-22T18:43:26.860Z,1705949006.860 [NAL9602](INFO): No messages in MT queue 2024-01-22T18:43:37.461Z,1705949017.461 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20240122T172723/Express0043.lzma 2024-01-22T18:43:38.463Z,1705949018.463 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0043.lzma.bak 2024-01-22T18:43:38.463Z,1705949018.463 [DataOverHttps](INFO): SBD MOMSN=19206987 2024-01-22T18:43:41.025Z,1705949021.025 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T18:43:41.025Z,1705949021.025 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T18:43:41.026Z,1705949021.026 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T18:43:57.570Z,1705949037.570 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:47:58.842Z,1705949278.842 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-3278,-32768,V 2024-01-22T18:48:41.665Z,1705949321.665 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T18:48:41.665Z,1705949321.665 [Default:CheckIn:C.Wait] Stopped 2024-01-22T18:48:41.665Z,1705949321.665 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T18:48:41.666Z,1705949321.666 [Default:CheckIn:D] Running Loop=1 2024-01-22T18:48:42.079Z,1705949322.079 [Default:CheckIn:D] Stopped 2024-01-22T18:48:42.079Z,1705949322.079 [Default:CheckIn:E] Running Loop=1 2024-01-22T18:48:42.470Z,1705949322.470 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.247640 min 2024-01-22T18:48:42.470Z,1705949322.470 [Default:CheckIn:E] Stopped 2024-01-22T18:48:42.470Z,1705949322.470 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T18:48:42.471Z,1705949322.471 [Default:CheckIn] Stopped 2024-01-22T18:48:42.471Z,1705949322.471 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T18:48:42.471Z,1705949322.471 [Default:CheckIn](INFO): Running loop #20 2024-01-22T18:48:42.471Z,1705949322.471 [Default:CheckIn] Running Loop=20 2024-01-22T18:48:42.472Z,1705949322.472 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T18:48:42.472Z,1705949322.472 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T18:48:44.478Z,1705949324.478 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184843.00,A,3655.59155,N,12204.67947,W,21.110,117.55,220124,,,D*4E 2024-01-22T18:48:44.487Z,1705949324.487 [NAL9602](INFO): GPS fix at 20240122T184843: (36.926526, -122.077991) 2024-01-22T18:48:44.537Z,1705949324.537 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T18:48:44.537Z,1705949324.537 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T18:48:50.607Z,1705949330.607 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2024-01-22T18:48:50.607Z,1705949330.607 [RDI_Pathfinder] Communications Fault, FailCount= 1 2024-01-22T18:48:50.607Z,1705949330.607 [RDI_Pathfinder](ERROR): Communications Fault 2024-01-22T18:48:50.648Z,1705949330.648 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2024-01-22T18:48:51.021Z,1705949331.021 [RDI_Pathfinder](INFO): Powering down 2024-01-22T18:48:51.797Z,1705949331.797 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2024-01-22T18:48:51.797Z,1705949331.797 [RDI_Pathfinder] No Fault, FailCount= 1 2024-01-22T18:48:52.163Z,1705949332.163 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2024-01-22T18:48:52.177Z,1705949332.177 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0045.lzma 2024-01-22T18:48:53.179Z,1705949333.179 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0045.lzma.bak 2024-01-22T18:48:53.179Z,1705949333.179 [DataOverHttps](INFO): SBD MOMSN=19207013 2024-01-22T18:49:05.921Z,1705949345.921 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00 2024-01-22T18:49:06.724Z,1705949346.724 [NAL9602](INFO): SBD MO Status=0, MOMSN=36972, MT Status=0, MTMSN=0 2024-01-22T18:49:06.724Z,1705949346.724 [NAL9602](INFO): No messages in MT queue 2024-01-22T18:49:10.477Z,1705949350.477 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20240122T172723/Express0046.lzma 2024-01-22T18:49:11.481Z,1705949351.481 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0046.lzma.bak 2024-01-22T18:49:11.482Z,1705949351.482 [DataOverHttps](INFO): SBD MOMSN=19207016 2024-01-22T18:49:13.186Z,1705949353.186 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T18:49:13.186Z,1705949353.186 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T18:49:13.186Z,1705949353.186 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T18:49:37.414Z,1705949377.414 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:54:13.869Z,1705949653.869 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T18:54:13.869Z,1705949653.869 [Default:CheckIn:C.Wait] Stopped 2024-01-22T18:54:13.873Z,1705949653.873 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T18:54:13.873Z,1705949653.873 [Default:CheckIn:D] Running Loop=1 2024-01-22T18:54:14.192Z,1705949654.192 [Default:CheckIn:D] Stopped 2024-01-22T18:54:14.192Z,1705949654.192 [Default:CheckIn:E] Running Loop=1 2024-01-22T18:54:14.586Z,1705949654.586 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.782861 min 2024-01-22T18:54:14.586Z,1705949654.586 [Default:CheckIn:E] Stopped 2024-01-22T18:54:14.586Z,1705949654.586 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T18:54:14.586Z,1705949654.586 [Default:CheckIn] Stopped 2024-01-22T18:54:14.586Z,1705949654.586 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T18:54:14.587Z,1705949654.587 [Default:CheckIn](INFO): Running loop #21 2024-01-22T18:54:14.587Z,1705949654.587 [Default:CheckIn] Running Loop=21 2024-01-22T18:54:14.587Z,1705949654.587 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T18:54:14.587Z,1705949654.587 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T18:54:16.600Z,1705949656.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185416.00,A,3654.70159,N,12202.25523,W,23.909,125.83,220124,,,D*43 2024-01-22T18:54:16.612Z,1705949656.612 [NAL9602](INFO): GPS fix at 20240122T185416: (36.911693, -122.037587) 2024-01-22T18:54:16.623Z,1705949656.623 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T18:54:16.623Z,1705949656.623 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T18:54:23.980Z,1705949663.980 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0048.lzma 2024-01-22T18:54:24.983Z,1705949664.983 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0048.lzma.bak 2024-01-22T18:54:24.983Z,1705949664.983 [DataOverHttps](INFO): SBD MOMSN=19207110 2024-01-22T18:54:33.216Z,1705949673.216 [NAL9602](INFO): SBD MO Status=0, MOMSN=36973, MT Status=0, MTMSN=0 2024-01-22T18:54:33.216Z,1705949673.216 [NAL9602](INFO): No messages in MT queue 2024-01-22T18:54:57.725Z,1705949697.725 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20240122T172723/Express0049.lzma 2024-01-22T18:54:58.727Z,1705949698.727 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0049.lzma.bak 2024-01-22T18:54:58.727Z,1705949698.727 [DataOverHttps](INFO): SBD MOMSN=19207113 2024-01-22T18:55:00.707Z,1705949700.707 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T18:55:00.708Z,1705949700.708 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T18:55:00.709Z,1705949700.709 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T18:55:03.918Z,1705949703.918 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T18:59:40.545Z,1705949980.545 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-01-22T18:59:53.599Z,1705949993.599 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.776501 2024-01-22T19:00:01.320Z,1705950001.320 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:00:01.320Z,1705950001.320 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:00:01.320Z,1705950001.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:00:01.320Z,1705950001.320 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:00:01.749Z,1705950001.749 [Default:CheckIn:D] Stopped 2024-01-22T19:00:01.749Z,1705950001.749 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:00:02.117Z,1705950002.117 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.575464 min 2024-01-22T19:00:02.117Z,1705950002.117 [Default:CheckIn:E] Stopped 2024-01-22T19:00:02.117Z,1705950002.117 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:00:02.117Z,1705950002.117 [Default:CheckIn] Stopped 2024-01-22T19:00:02.117Z,1705950002.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:00:02.118Z,1705950002.118 [Default:CheckIn](INFO): Running loop #22 2024-01-22T19:00:02.118Z,1705950002.118 [Default:CheckIn] Running Loop=22 2024-01-22T19:00:02.118Z,1705950002.118 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:00:02.118Z,1705950002.118 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:00:04.125Z,1705950004.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190003.00,A,3653.58933,N,12159.97229,W,23.734,111.94,220124,,,D*46 2024-01-22T19:00:04.127Z,1705950004.127 [NAL9602](INFO): GPS fix at 20240122T190003: (36.893155, -121.999538) 2024-01-22T19:00:04.138Z,1705950004.138 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:00:04.138Z,1705950004.138 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:00:26.412Z,1705950026.412 [NAL9602](INFO): SBD MO Status=1, MOMSN=36974, MT Status=0, MTMSN=0 2024-01-22T19:00:26.473Z,1705950026.473 [NAL9602](INFO): Sent 73 bytes from file Logs/20240122T172723/Courier0051.lzma 2024-01-22T19:00:26.473Z,1705950026.473 [NAL9602](INFO): Packets left to send: 0 2024-01-22T19:00:34.539Z,1705950034.539 [NAL9602](INFO): SBD MO Status=1, MOMSN=36975, MT Status=0, MTMSN=0 2024-01-22T19:00:34.591Z,1705950034.591 [NAL9602](INFO): Sent 286 bytes from file Logs/20240122T172723/Express0052.lzma 2024-01-22T19:00:34.592Z,1705950034.592 [NAL9602](INFO): Packets left to send: 0 2024-01-22T19:00:50.833Z,1705950050.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:00:52.603Z,1705950052.603 [NAL9602](INFO): SBD MO Status=0, MOMSN=36976, MT Status=0, MTMSN=0 2024-01-22T19:00:52.683Z,1705950052.683 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T19:00:52.684Z,1705950052.684 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T19:00:52.684Z,1705950052.684 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T19:01:23.311Z,1705950083.311 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:01:53.613Z,1705950113.613 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003044 2024-01-22T19:02:37.513Z,1705950157.513 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-01-22T19:02:54.582Z,1705950174.582 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.971527 2024-01-22T19:03:47.793Z,1705950227.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:04:22.941Z,1705950262.941 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:04:58.097Z,1705950298.097 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:05:33.237Z,1705950333.237 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:05:47.625Z,1705950347.625 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS00, 0.00 2024-01-22T19:05:53.247Z,1705950353.247 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:05:53.247Z,1705950353.247 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:05:53.247Z,1705950353.247 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:05:53.248Z,1705950353.248 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:05:53.674Z,1705950353.674 [Default:CheckIn:D] Stopped 2024-01-22T19:05:53.674Z,1705950353.674 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:05:54.071Z,1705950354.071 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.440885 min 2024-01-22T19:05:54.071Z,1705950354.071 [Default:CheckIn:E] Stopped 2024-01-22T19:05:54.071Z,1705950354.071 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:05:54.071Z,1705950354.071 [Default:CheckIn] Stopped 2024-01-22T19:05:54.071Z,1705950354.071 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:05:54.072Z,1705950354.072 [Default:CheckIn](INFO): Running loop #23 2024-01-22T19:05:54.072Z,1705950354.072 [Default:CheckIn] Running Loop=23 2024-01-22T19:05:54.072Z,1705950354.072 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:05:54.072Z,1705950354.072 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:05:56.068Z,1705950356.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190555.00,A,3652.65754,N,12157.53732,W,16.484,117.85,220124,,,D*41 2024-01-22T19:05:56.070Z,1705950356.070 [NAL9602](INFO): GPS fix at 20240122T190555: (36.877626, -121.958955) 2024-01-22T19:05:56.081Z,1705950356.081 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:05:56.082Z,1705950356.082 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:06:08.381Z,1705950368.381 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:06:25.385Z,1705950385.385 [NAL9602](INFO): SBD MO Status=2, MOMSN=36977, MT Status=2, MTMSN=0 2024-01-22T19:06:25.385Z,1705950385.385 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-22T19:06:38.855Z,1705950398.855 [NAL9602](INFO): SBD MO Status=1, MOMSN=36977, MT Status=0, MTMSN=0 2024-01-22T19:06:38.917Z,1705950398.917 [NAL9602](INFO): Sent 73 bytes from file Logs/20240122T172723/Courier0054.lzma 2024-01-22T19:06:38.917Z,1705950398.917 [NAL9602](INFO): Packets left to send: 0 2024-01-22T19:06:43.521Z,1705950403.521 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:06:56.331Z,1705950416.331 [NAL9602](INFO): SBD MO Status=2, MOMSN=36978, MT Status=2, MTMSN=0 2024-01-22T19:06:56.332Z,1705950416.332 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-22T19:07:18.666Z,1705950438.666 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:07:35.590Z,1705950455.590 [NAL9602](INFO): SBD MO Status=1, MOMSN=36978, MT Status=0, MTMSN=0 2024-01-22T19:07:35.649Z,1705950455.649 [NAL9602](INFO): Sent 197 bytes from file Logs/20240122T172723/Express0055.lzma 2024-01-22T19:07:35.650Z,1705950455.650 [NAL9602](INFO): Packets left to send: 0 2024-01-22T19:07:45.972Z,1705950465.972 [NAL9602](INFO): SBD MO Status=0, MOMSN=36979, MT Status=0, MTMSN=0 2024-01-22T19:07:46.054Z,1705950466.054 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T19:07:46.054Z,1705950466.054 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T19:07:46.054Z,1705950466.054 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T19:07:53.833Z,1705950473.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:08:16.726Z,1705950496.726 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:08:28.973Z,1705950508.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:09:04.150Z,1705950544.150 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:09:39.301Z,1705950579.301 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:10:10.600Z,1705950610.600 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2024-01-22T19:10:10.619Z,1705950610.619 [BPC1](INFO): Received data from all battery sticks. 2024-01-22T19:10:14.441Z,1705950614.441 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:10:30.222Z,1705950630.222 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003478 2024-01-22T19:11:51.609Z,1705950711.609 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00, :WI,-32768,-32768,-32768,-32768,V 2024-01-22T19:12:01.281Z,1705950721.281 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:12:08.310Z,1705950728.310 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251507 2024-01-22T19:12:46.557Z,1705950766.557 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:12:46.557Z,1705950766.557 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:12:46.557Z,1705950766.557 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:12:46.561Z,1705950766.561 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:12:46.974Z,1705950766.974 [Default:CheckIn:D] Stopped 2024-01-22T19:12:46.974Z,1705950766.974 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:12:47.406Z,1705950767.406 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.329224 min 2024-01-22T19:12:47.406Z,1705950767.406 [Default:CheckIn:E] Stopped 2024-01-22T19:12:47.406Z,1705950767.406 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:12:47.406Z,1705950767.406 [Default:CheckIn] Stopped 2024-01-22T19:12:47.406Z,1705950767.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:12:47.406Z,1705950767.406 [Default:CheckIn](INFO): Running loop #24 2024-01-22T19:12:47.407Z,1705950767.407 [Default:CheckIn] Running Loop=24 2024-01-22T19:12:47.407Z,1705950767.407 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:12:47.407Z,1705950767.407 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:12:49.372Z,1705950769.372 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191249.00,A,3651.46210,N,12154.49642,W,25.153,121.83,220124,,,D*4F 2024-01-22T19:12:49.374Z,1705950769.374 [NAL9602](INFO): GPS fix at 20240122T191249: (36.857702, -121.908274) 2024-01-22T19:12:49.420Z,1705950769.420 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:12:49.420Z,1705950769.420 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:12:51.123Z,1705950771.123 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003317 2024-01-22T19:12:58.833Z,1705950778.833 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240122T172723/Courier0057.lzma 2024-01-22T19:12:59.835Z,1705950779.835 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0057.lzma.bak 2024-01-22T19:12:59.835Z,1705950779.835 [DataOverHttps](INFO): SBD MOMSN=19207134 2024-01-22T19:13:03.508Z,1705950783.508 [NAL9602](INFO): SBD MO Status=0, MOMSN=36980, MT Status=0, MTMSN=0 2024-01-22T19:13:03.508Z,1705950783.508 [NAL9602](INFO): No messages in MT queue 2024-01-22T19:13:25.118Z,1705950805.118 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20240122T172723/Express0058.lzma 2024-01-22T19:13:34.209Z,1705950814.209 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:13:38.171Z,1705950818.171 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0058.lzma.bak 2024-01-22T19:13:38.171Z,1705950818.171 [DataOverHttps](INFO): SBD MOMSN=19207137 2024-01-22T19:13:40.315Z,1705950820.315 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T19:13:40.315Z,1705950820.315 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T19:13:40.315Z,1705950820.315 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T19:14:10.305Z,1705950850.305 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:14:38.418Z,1705950878.418 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247265 2024-01-22T19:15:16.569Z,1705950916.569 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:15:51.709Z,1705950951.709 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:16:26.854Z,1705950986.854 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:17:01.997Z,1705951021.997 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:17:37.138Z,1705951057.138 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:18:12.277Z,1705951092.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T19:18:40.911Z,1705951120.911 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:18:40.911Z,1705951120.911 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:18:40.912Z,1705951120.912 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:18:40.912Z,1705951120.912 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:18:41.307Z,1705951121.307 [Default:CheckIn:D] Stopped 2024-01-22T19:18:41.307Z,1705951121.307 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:18:41.756Z,1705951121.756 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.234766 min 2024-01-22T19:18:41.757Z,1705951121.757 [Default:CheckIn:E] Stopped 2024-01-22T19:18:41.757Z,1705951121.757 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:18:41.757Z,1705951121.757 [Default:CheckIn] Stopped 2024-01-22T19:18:41.757Z,1705951121.757 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:18:41.757Z,1705951121.757 [Default:CheckIn](INFO): Running loop #25 2024-01-22T19:18:41.757Z,1705951121.757 [Default:CheckIn] Running Loop=25 2024-01-22T19:18:41.757Z,1705951121.757 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:18:41.757Z,1705951121.757 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:18:42.632Z,1705951122.632 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003160 2024-01-22T19:18:43.722Z,1705951123.722 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191843.00,A,3650.36462,N,12151.97459,W,23.093,117.41,220124,,,D*44 2024-01-22T19:18:43.734Z,1705951123.734 [NAL9602](INFO): GPS fix at 20240122T191843: (36.839410, -121.866243) 2024-01-22T19:18:43.765Z,1705951123.765 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:18:43.765Z,1705951123.765 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:18:57.395Z,1705951137.395 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0060.lzma 2024-01-22T19:18:59.074Z,1705951139.074 [NAL9602](INFO): SBD MO Status=0, MOMSN=36981, MT Status=0, MTMSN=0 2024-01-22T19:18:59.074Z,1705951139.074 [NAL9602](INFO): No messages in MT queue 2024-01-22T19:19:15.464Z,1705951155.464 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-01-22T19:19:29.776Z,1705951169.776 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:19:36.147Z,1705951176.147 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0060.lzma 2024-01-22T19:19:41.165Z,1705951181.165 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0060.lzma.bak 2024-01-22T19:19:41.166Z,1705951181.166 [DataOverHttps](INFO): SBD MOMSN=19207144 2024-01-22T19:20:41.590Z,1705951241.590 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.424847 2024-01-22T19:20:52.340Z,1705951252.340 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003126 2024-01-22T19:20:57.559Z,1705951257.559 [NAL9602](INFO): SBD MO Status=1, MOMSN=36982, MT Status=0, MTMSN=0 2024-01-22T19:20:57.612Z,1705951257.612 [NAL9602](INFO): Sent 332 bytes from file Logs/20240122T172723/Express0061.lzma 2024-01-22T19:20:57.612Z,1705951257.612 [NAL9602](INFO): Packets left to send: 1 2024-01-22T19:21:17.016Z,1705951277.016 [DataOverHttps](INFO): Sending 374 bytes from file Logs/20240122T172723/Express0061.lzma 2024-01-22T19:21:18.014Z,1705951278.014 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0061.lzma.bak 2024-01-22T19:21:18.014Z,1705951278.014 [DataOverHttps](INFO): SBD MOMSN=19207147 2024-01-22T19:21:21.369Z,1705951281.369 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T19:21:21.369Z,1705951281.369 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T19:21:21.369Z,1705951281.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T19:21:28.212Z,1705951288.212 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:26:21.945Z,1705951581.945 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:26:21.945Z,1705951581.945 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:26:21.945Z,1705951581.945 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:26:21.945Z,1705951581.945 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:26:22.356Z,1705951582.356 [Default:CheckIn:D] Stopped 2024-01-22T19:26:22.356Z,1705951582.356 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:26:22.752Z,1705951582.752 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.918929 min 2024-01-22T19:26:22.752Z,1705951582.752 [Default:CheckIn:E] Stopped 2024-01-22T19:26:22.752Z,1705951582.752 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:26:22.752Z,1705951582.752 [Default:CheckIn] Stopped 2024-01-22T19:26:22.752Z,1705951582.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:26:22.752Z,1705951582.752 [Default:CheckIn](INFO): Running loop #26 2024-01-22T19:26:22.752Z,1705951582.752 [Default:CheckIn] Running Loop=26 2024-01-22T19:26:22.752Z,1705951582.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:26:22.753Z,1705951582.753 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:26:24.766Z,1705951584.766 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192624.00,A,3648.98904,N,12148.90053,W,22.393,122.85,220124,,,D*45 2024-01-22T19:26:24.769Z,1705951584.769 [NAL9602](INFO): GPS fix at 20240122T192624: (36.816484, -121.815009) 2024-01-22T19:26:24.780Z,1705951584.780 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:26:24.780Z,1705951584.780 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:26:42.303Z,1705951602.303 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0063.lzma 2024-01-22T19:26:43.305Z,1705951603.305 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0063.lzma.bak 2024-01-22T19:26:43.306Z,1705951603.306 [DataOverHttps](INFO): SBD MOMSN=19207153 2024-01-22T19:26:58.698Z,1705951618.698 [NAL9602](INFO): SBD MO Status=2, MOMSN=36983, MT Status=2, MTMSN=0 2024-01-22T19:26:58.698Z,1705951618.698 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-22T19:26:59.944Z,1705951619.944 [DataOverHttps](INFO): Sending 605 bytes from file Logs/20240122T172723/Express0064.lzma 2024-01-22T19:27:00.946Z,1705951620.946 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0064.lzma.bak 2024-01-22T19:27:00.946Z,1705951620.946 [DataOverHttps](INFO): SBD MOMSN=19207156 2024-01-22T19:27:02.782Z,1705951622.782 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T19:27:02.782Z,1705951622.782 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T19:27:02.782Z,1705951622.782 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T19:27:15.268Z,1705951635.268 [NAL9602](INFO): SBD MO Status=0, MOMSN=36983, MT Status=0, MTMSN=0 2024-01-22T19:27:15.268Z,1705951635.268 [NAL9602](INFO): No messages in MT queue 2024-01-22T19:27:45.972Z,1705951665.972 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:32:03.483Z,1705951923.483 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:32:03.496Z,1705951923.496 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:32:03.496Z,1705951923.496 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:32:03.496Z,1705951923.496 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:32:03.830Z,1705951923.830 [Default:CheckIn:D] Stopped 2024-01-22T19:32:03.830Z,1705951923.830 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:32:04.227Z,1705951924.227 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 150.610156 min 2024-01-22T19:32:04.229Z,1705951924.229 [Default:CheckIn:E] Stopped 2024-01-22T19:32:04.229Z,1705951924.229 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:32:04.229Z,1705951924.229 [Default:CheckIn] Stopped 2024-01-22T19:32:04.229Z,1705951924.229 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:32:04.229Z,1705951924.229 [Default:CheckIn](INFO): Running loop #27 2024-01-22T19:32:04.229Z,1705951924.229 [Default:CheckIn] Running Loop=27 2024-01-22T19:32:04.229Z,1705951924.229 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:32:04.229Z,1705951924.229 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:32:06.230Z,1705951926.230 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193205.00,A,3648.34388,N,12147.47731,W,7.464,235.26,220124,,,D*79 2024-01-22T19:32:06.233Z,1705951926.233 [NAL9602](INFO): GPS fix at 20240122T193205: (36.805731, -121.791288) 2024-01-22T19:32:06.293Z,1705951926.293 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:32:06.293Z,1705951926.293 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:32:16.559Z,1705951936.559 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0066.lzma 2024-01-22T19:32:17.562Z,1705951937.562 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0066.lzma.bak 2024-01-22T19:32:17.562Z,1705951937.562 [DataOverHttps](INFO): SBD MOMSN=19207160 2024-01-22T19:32:22.450Z,1705951942.450 [NAL9602](INFO): SBD MO Status=0, MOMSN=36984, MT Status=0, MTMSN=0 2024-01-22T19:32:22.450Z,1705951942.450 [NAL9602](INFO): No messages in MT queue 2024-01-22T19:32:33.884Z,1705951953.884 [DataOverHttps](INFO): Sending 592 bytes from file Logs/20240122T172723/Express0067.lzma 2024-01-22T19:32:34.886Z,1705951954.886 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0067.lzma.bak 2024-01-22T19:32:34.886Z,1705951954.886 [DataOverHttps](INFO): SBD MOMSN=19207163 2024-01-22T19:32:36.612Z,1705951956.612 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T19:32:36.613Z,1705951956.613 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T19:32:36.613Z,1705951956.613 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T19:32:53.162Z,1705951973.162 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:37:37.285Z,1705952257.285 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:37:37.285Z,1705952257.285 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:37:37.285Z,1705952257.285 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:37:37.286Z,1705952257.286 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:37:37.656Z,1705952257.656 [Default:CheckIn:D] Stopped 2024-01-22T19:37:37.656Z,1705952257.656 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:37:38.036Z,1705952258.036 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 156.173926 min 2024-01-22T19:37:38.036Z,1705952258.036 [Default:CheckIn:E] Stopped 2024-01-22T19:37:38.036Z,1705952258.036 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:37:38.036Z,1705952258.036 [Default:CheckIn] Stopped 2024-01-22T19:37:38.036Z,1705952258.036 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:37:38.037Z,1705952258.037 [Default:CheckIn](INFO): Running loop #28 2024-01-22T19:37:38.037Z,1705952258.037 [Default:CheckIn] Running Loop=28 2024-01-22T19:37:38.037Z,1705952258.037 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:37:38.037Z,1705952258.037 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:37:40.052Z,1705952260.052 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193739.00,A,3648.37037,N,12147.12366,W,3.868,177.36,220124,,,D*71 2024-01-22T19:37:40.054Z,1705952260.054 [NAL9602](INFO): GPS fix at 20240122T193739: (36.806173, -121.785394) 2024-01-22T19:37:40.066Z,1705952260.066 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:37:40.066Z,1705952260.066 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:37:47.995Z,1705952267.995 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0069.lzma 2024-01-22T19:37:48.997Z,1705952268.997 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0069.lzma.bak 2024-01-22T19:37:48.998Z,1705952268.998 [DataOverHttps](INFO): SBD MOMSN=19207168 2024-01-22T19:37:57.022Z,1705952277.022 [NAL9602](INFO): SBD MO Status=0, MOMSN=36985, MT Status=0, MTMSN=0 2024-01-22T19:37:57.022Z,1705952277.022 [NAL9602](INFO): No messages in MT queue 2024-01-22T19:38:07.924Z,1705952287.924 [DataOverHttps](INFO): Sending 491 bytes from file Logs/20240122T172723/Express0070.lzma 2024-01-22T19:38:08.926Z,1705952288.926 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0070.lzma.bak 2024-01-22T19:38:08.926Z,1705952288.926 [DataOverHttps](INFO): SBD MOMSN=19207171 2024-01-22T19:38:11.398Z,1705952291.398 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T19:38:11.399Z,1705952291.399 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T19:38:11.399Z,1705952291.399 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T19:38:27.780Z,1705952307.780 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:43:11.894Z,1705952591.894 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:43:11.894Z,1705952591.894 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:43:11.894Z,1705952591.894 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:43:11.895Z,1705952591.895 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:43:12.272Z,1705952592.272 [Default:CheckIn:D] Stopped 2024-01-22T19:43:12.272Z,1705952592.272 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:43:12.677Z,1705952592.677 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 161.750846 min 2024-01-22T19:43:12.677Z,1705952592.677 [Default:CheckIn:E] Stopped 2024-01-22T19:43:12.677Z,1705952592.677 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:43:12.677Z,1705952592.677 [Default:CheckIn] Stopped 2024-01-22T19:43:12.677Z,1705952592.677 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:43:12.677Z,1705952592.677 [Default:CheckIn](INFO): Running loop #29 2024-01-22T19:43:12.677Z,1705952592.677 [Default:CheckIn] Running Loop=29 2024-01-22T19:43:12.678Z,1705952592.678 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:43:12.678Z,1705952592.678 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:43:14.667Z,1705952594.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194313.00,A,3648.13990,N,12147.20296,W,0.758,358.98,220124,,,D*73 2024-01-22T19:43:14.669Z,1705952594.669 [NAL9602](INFO): GPS fix at 20240122T194313: (36.802332, -121.786716) 2024-01-22T19:43:14.685Z,1705952594.685 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:43:14.685Z,1705952594.685 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:43:22.816Z,1705952602.816 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0072.lzma 2024-01-22T19:43:23.819Z,1705952603.819 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0072.lzma.bak 2024-01-22T19:43:23.819Z,1705952603.819 [DataOverHttps](INFO): SBD MOMSN=19207178 2024-01-22T19:43:40.326Z,1705952620.326 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20240122T172723/Express0073.lzma 2024-01-22T19:43:41.326Z,1705952621.326 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0073.lzma.bak 2024-01-22T19:43:41.326Z,1705952621.326 [DataOverHttps](INFO): SBD MOMSN=19207181 2024-01-22T19:43:43.393Z,1705952623.393 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T19:43:43.394Z,1705952623.394 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T19:43:43.394Z,1705952623.394 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T19:43:54.663Z,1705952634.663 [NAL9602](INFO): SBD MO Status=0, MOMSN=36986, MT Status=0, MTMSN=0 2024-01-22T19:43:54.663Z,1705952634.663 [NAL9602](INFO): No messages in MT queue 2024-01-22T19:44:25.365Z,1705952665.365 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:48:43.937Z,1705952923.937 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:48:43.937Z,1705952923.937 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:48:43.937Z,1705952923.937 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:48:43.937Z,1705952923.937 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:48:44.341Z,1705952924.341 [Default:CheckIn:D] Stopped 2024-01-22T19:48:44.341Z,1705952924.341 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:48:44.753Z,1705952924.753 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 167.285335 min 2024-01-22T19:48:44.753Z,1705952924.753 [Default:CheckIn:E] Stopped 2024-01-22T19:48:44.753Z,1705952924.753 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:48:44.753Z,1705952924.753 [Default:CheckIn] Stopped 2024-01-22T19:48:44.753Z,1705952924.753 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:48:44.753Z,1705952924.753 [Default:CheckIn](INFO): Running loop #30 2024-01-22T19:48:44.753Z,1705952924.753 [Default:CheckIn] Running Loop=30 2024-01-22T19:48:44.753Z,1705952924.753 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:48:44.754Z,1705952924.754 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:48:46.759Z,1705952926.759 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194846.00,A,3648.13718,N,12147.20764,W,0.505,358.98,220124,,,A*71 2024-01-22T19:48:46.762Z,1705952926.762 [NAL9602](INFO): GPS fix at 20240122T194846: (36.802286, -121.786794) 2024-01-22T19:48:46.793Z,1705952926.793 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:48:46.793Z,1705952926.793 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:48:54.688Z,1705952934.688 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0075.lzma 2024-01-22T19:48:55.706Z,1705952935.706 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0075.lzma.bak 2024-01-22T19:48:55.706Z,1705952935.706 [DataOverHttps](INFO): SBD MOMSN=19207186 2024-01-22T19:49:13.384Z,1705952953.384 [DataOverHttps](INFO): Sending 330 bytes from file Logs/20240122T172723/Express0076.lzma 2024-01-22T19:49:14.387Z,1705952954.387 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0076.lzma.bak 2024-01-22T19:49:14.387Z,1705952954.387 [DataOverHttps](INFO): SBD MOMSN=19207189 2024-01-22T19:49:16.265Z,1705952956.265 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T19:49:16.265Z,1705952956.265 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T19:49:16.266Z,1705952956.266 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T19:49:51.001Z,1705952991.001 [NAL9602](INFO): SBD MO Status=2, MOMSN=36987, MT Status=2, MTMSN=0 2024-01-22T19:49:51.001Z,1705952991.001 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-22T19:49:59.477Z,1705952999.477 [NAL9602](INFO): SBD MO Status=0, MOMSN=36987, MT Status=0, MTMSN=0 2024-01-22T19:49:59.477Z,1705952999.477 [NAL9602](INFO): No messages in MT queue 2024-01-22T19:50:30.181Z,1705953030.181 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:50:39.550Z,1705953039.550 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00 2024-01-22T19:54:16.900Z,1705953256.900 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:54:16.900Z,1705953256.900 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:54:16.901Z,1705953256.901 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:54:16.901Z,1705953256.901 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:54:17.321Z,1705953257.321 [Default:CheckIn:D] Stopped 2024-01-22T19:54:17.321Z,1705953257.321 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:54:17.746Z,1705953257.746 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 172.835010 min 2024-01-22T19:54:17.747Z,1705953257.747 [Default:CheckIn:E] Stopped 2024-01-22T19:54:17.747Z,1705953257.747 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:54:17.747Z,1705953257.747 [Default:CheckIn] Stopped 2024-01-22T19:54:17.747Z,1705953257.747 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:54:17.747Z,1705953257.747 [Default:CheckIn](INFO): Running loop #31 2024-01-22T19:54:17.747Z,1705953257.747 [Default:CheckIn] Running Loop=31 2024-01-22T19:54:17.747Z,1705953257.747 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:54:17.747Z,1705953257.747 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:54:19.737Z,1705953259.737 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195419.00,A,3648.14045,N,12147.21084,W,0.039,6.92,220124,,,D*7B 2024-01-22T19:54:19.740Z,1705953259.740 [NAL9602](INFO): GPS fix at 20240122T195419: (36.802341, -121.786847) 2024-01-22T19:54:19.798Z,1705953259.798 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:54:19.798Z,1705953259.798 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:54:28.101Z,1705953268.101 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240122T172723/Courier0078.lzma 2024-01-22T19:54:29.102Z,1705953269.102 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0078.lzma.bak 2024-01-22T19:54:29.103Z,1705953269.103 [DataOverHttps](INFO): SBD MOMSN=19207193 2024-01-22T19:54:43.251Z,1705953283.251 [NAL9602](INFO): SBD MO Status=2, MOMSN=36988, MT Status=2, MTMSN=0 2024-01-22T19:54:43.251Z,1705953283.251 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-22T19:54:45.580Z,1705953285.580 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20240122T172723/Express0079.lzma 2024-01-22T19:54:46.582Z,1705953286.582 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0079.lzma.bak 2024-01-22T19:54:46.583Z,1705953286.583 [DataOverHttps](INFO): SBD MOMSN=19207196 2024-01-22T19:54:48.518Z,1705953288.518 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T19:54:48.519Z,1705953288.519 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T19:54:48.519Z,1705953288.519 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T19:55:02.635Z,1705953302.635 [NAL9602](INFO): SBD MO Status=0, MOMSN=36988, MT Status=0, MTMSN=0 2024-01-22T19:55:02.635Z,1705953302.635 [NAL9602](INFO): No messages in MT queue 2024-01-22T19:55:33.337Z,1705953333.337 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T19:59:49.155Z,1705953589.155 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T19:59:49.155Z,1705953589.155 [Default:CheckIn:C.Wait] Stopped 2024-01-22T19:59:49.155Z,1705953589.155 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T19:59:49.156Z,1705953589.156 [Default:CheckIn:D] Running Loop=1 2024-01-22T19:59:49.571Z,1705953589.571 [Default:CheckIn:D] Stopped 2024-01-22T19:59:49.571Z,1705953589.571 [Default:CheckIn:E] Running Loop=1 2024-01-22T19:59:49.988Z,1705953589.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 178.372510 min 2024-01-22T19:59:49.989Z,1705953589.989 [Default:CheckIn:E] Stopped 2024-01-22T19:59:49.989Z,1705953589.989 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T19:59:49.989Z,1705953589.989 [Default:CheckIn] Stopped 2024-01-22T19:59:49.989Z,1705953589.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T19:59:49.989Z,1705953589.989 [Default:CheckIn](INFO): Running loop #32 2024-01-22T19:59:49.989Z,1705953589.989 [Default:CheckIn] Running Loop=32 2024-01-22T19:59:49.989Z,1705953589.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T19:59:49.989Z,1705953589.989 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T19:59:51.963Z,1705953591.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195951.00,A,3648.17641,N,12147.27932,W,0.078,301.26,220124,,,D*77 2024-01-22T19:59:51.966Z,1705953591.966 [NAL9602](INFO): GPS fix at 20240122T195951: (36.802940, -121.787989) 2024-01-22T19:59:51.978Z,1705953591.978 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T19:59:51.978Z,1705953591.978 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T19:59:59.788Z,1705953599.788 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0081.lzma 2024-01-22T20:00:00.047Z,1705953600.047 [NAL9602](INFO): SBD MO Status=0, MOMSN=36989, MT Status=0, MTMSN=0 2024-01-22T20:00:00.047Z,1705953600.047 [NAL9602](INFO): No messages in MT queue 2024-01-22T20:00:00.790Z,1705953600.790 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0081.lzma.bak 2024-01-22T20:00:00.791Z,1705953600.791 [DataOverHttps](INFO): SBD MOMSN=19207214 2024-01-22T20:00:20.136Z,1705953620.136 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20240122T172723/Express0082.lzma 2024-01-22T20:00:21.138Z,1705953621.138 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0082.lzma.bak 2024-01-22T20:00:21.139Z,1705953621.139 [DataOverHttps](INFO): SBD MOMSN=19207218 2024-01-22T20:00:23.673Z,1705953623.673 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T20:00:23.674Z,1705953623.674 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T20:00:23.674Z,1705953623.674 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T20:00:30.821Z,1705953630.821 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T20:05:24.034Z,1705953924.034 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T20:05:24.034Z,1705953924.034 [Default:CheckIn:C.Wait] Stopped 2024-01-22T20:05:24.034Z,1705953924.034 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T20:05:24.034Z,1705953924.034 [Default:CheckIn:D] Running Loop=1 2024-01-22T20:05:24.438Z,1705953924.438 [Default:CheckIn:D] Stopped 2024-01-22T20:05:24.438Z,1705953924.438 [Default:CheckIn:E] Running Loop=1 2024-01-22T20:05:24.828Z,1705953924.828 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 183.953613 min 2024-01-22T20:05:24.828Z,1705953924.828 [Default:CheckIn:E] Stopped 2024-01-22T20:05:24.828Z,1705953924.828 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T20:05:24.828Z,1705953924.828 [Default:CheckIn] Stopped 2024-01-22T20:05:24.828Z,1705953924.828 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T20:05:24.845Z,1705953924.845 [Default:CheckIn](INFO): Running loop #33 2024-01-22T20:05:24.845Z,1705953924.845 [Default:CheckIn] Running Loop=33 2024-01-22T20:05:24.845Z,1705953924.845 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T20:05:24.845Z,1705953924.845 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T20:05:26.847Z,1705953926.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200526.00,A,3648.17540,N,12147.28061,W,0.156,301.26,220124,,,D*7B 2024-01-22T20:05:26.850Z,1705953926.850 [NAL9602](INFO): GPS fix at 20240122T200526: (36.802923, -121.788010) 2024-01-22T20:05:26.860Z,1705953926.860 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T20:05:26.881Z,1705953926.881 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T20:05:34.888Z,1705953934.888 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0084.lzma 2024-01-22T20:05:35.891Z,1705953935.891 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0084.lzma.bak 2024-01-22T20:05:35.891Z,1705953935.891 [DataOverHttps](INFO): SBD MOMSN=19207223 2024-01-22T20:05:41.379Z,1705953941.379 [NAL9602](INFO): SBD MO Status=0, MOMSN=36990, MT Status=0, MTMSN=0 2024-01-22T20:05:41.379Z,1705953941.379 [NAL9602](INFO): No messages in MT queue 2024-01-22T20:05:52.353Z,1705953952.353 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20240122T172723/Express0085.lzma 2024-01-22T20:05:53.355Z,1705953953.355 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0085.lzma.bak 2024-01-22T20:05:53.355Z,1705953953.355 [DataOverHttps](INFO): SBD MOMSN=19207226 2024-01-22T20:05:55.134Z,1705953955.134 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T20:05:55.134Z,1705953955.134 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T20:05:55.134Z,1705953955.134 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T20:06:12.088Z,1705953972.088 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-22T20:10:55.785Z,1705954255.785 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T20:10:55.785Z,1705954255.785 [Default:CheckIn:C.Wait] Stopped 2024-01-22T20:10:55.785Z,1705954255.785 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T20:10:55.785Z,1705954255.785 [Default:CheckIn:D] Running Loop=1 2024-01-22T20:10:56.156Z,1705954256.156 [Default:CheckIn:D] Stopped 2024-01-22T20:10:56.156Z,1705954256.156 [Default:CheckIn:E] Running Loop=1 2024-01-22T20:10:56.564Z,1705954256.564 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 189.482259 min 2024-01-22T20:10:56.564Z,1705954256.564 [Default:CheckIn:E] Stopped 2024-01-22T20:10:56.564Z,1705954256.564 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T20:10:56.564Z,1705954256.564 [Default:CheckIn] Stopped 2024-01-22T20:10:56.565Z,1705954256.565 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T20:10:56.565Z,1705954256.565 [Default:CheckIn](INFO): Running loop #34 2024-01-22T20:10:56.565Z,1705954256.565 [Default:CheckIn] Running Loop=34 2024-01-22T20:10:56.565Z,1705954256.565 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T20:10:56.565Z,1705954256.565 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T20:10:58.562Z,1705954258.562 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201058.00,A,3648.16938,N,12147.28496,W,1.030,194.09,220124,,,A*7E 2024-01-22T20:10:58.565Z,1705954258.565 [NAL9602](INFO): GPS fix at 20240122T201058: (36.802823, -121.788083) 2024-01-22T20:10:58.592Z,1705954258.592 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T20:10:58.592Z,1705954258.592 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-22T20:11:06.584Z,1705954266.584 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240122T172723/Courier0087.lzma 2024-01-22T20:11:07.587Z,1705954267.587 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Courier0087.lzma.bak 2024-01-22T20:11:07.587Z,1705954267.587 [DataOverHttps](INFO): SBD MOMSN=19207230 2024-01-22T20:11:24.328Z,1705954284.328 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20240122T172723/Express0088.lzma 2024-01-22T20:11:25.331Z,1705954285.331 [DataOverHttps](INFO): Moved sent file to Logs/20240122T172723/Express0088.lzma.bak 2024-01-22T20:11:25.332Z,1705954285.332 [DataOverHttps](INFO): SBD MOMSN=19207233 2024-01-22T20:11:27.280Z,1705954287.280 [Default:CheckIn:Read_Iridium] Stopped 2024-01-22T20:11:27.280Z,1705954287.280 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-22T20:11:27.280Z,1705954287.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-22T20:14:18.344Z,1705954458.344 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T20:14:21.357Z,1705954461.357 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255234 2024-01-22T20:14:53.492Z,1705954493.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T20:15:28.632Z,1705954528.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T20:16:01.287Z,1705954561.287 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-01-22T20:16:03.772Z,1705954563.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T20:16:27.909Z,1705954587.909 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-22T20:16:27.909Z,1705954587.909 [Default:CheckIn:C.Wait] Stopped 2024-01-22T20:16:27.909Z,1705954587.909 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-22T20:16:27.910Z,1705954587.910 [Default:CheckIn:D] Running Loop=1 2024-01-22T20:16:28.335Z,1705954588.335 [Default:CheckIn:D] Stopped 2024-01-22T20:16:28.335Z,1705954588.335 [Default:CheckIn:E] Running Loop=1 2024-01-22T20:16:28.682Z,1705954588.682 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 195.018587 min 2024-01-22T20:16:28.682Z,1705954588.682 [Default:CheckIn:E] Stopped 2024-01-22T20:16:28.682Z,1705954588.682 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-22T20:16:28.682Z,1705954588.682 [Default:CheckIn] Stopped 2024-01-22T20:16:28.682Z,1705954588.682 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T20:16:28.683Z,1705954588.683 [Default:CheckIn](INFO): Running loop #35 2024-01-22T20:16:28.683Z,1705954588.683 [Default:CheckIn] Running Loop=35 2024-01-22T20:16:28.683Z,1705954588.683 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-22T20:16:28.683Z,1705954588.683 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-22T20:16:38.928Z,1705954598.928 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T20:17:14.072Z,1705954634.072 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T20:17:40.995Z,1705954660.995 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2024-01-22T20:17:41.002Z,1705954661.002 [BPC1](INFO): Received data from all battery sticks. 2024-01-22T20:17:49.212Z,1705954669.212 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T20:18:24.361Z,1705954704.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T20:18:59.501Z,1705954739.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T20:19:34.640Z,1705954774.640 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-22T20:19:47.609Z,1705954787.609 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004250 2024-01-22T20:21:14.193Z,1705954874.193 [CommandExec](IMPORTANT): got command quit 2024-01-22T20:21:15.196Z,1705954875.196 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-01-22T20:21:15.196Z,1705954875.196 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:15.196Z,1705954875.196 [CommandExec](INFO): Uninitializing the command executive. 2024-01-22T20:21:15.197Z,1705954875.197 [CommandExec](INFO): Uninitializing the command scheduler. 2024-01-22T20:21:15.197Z,1705954875.197 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:15.260Z,1705954875.260 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-01-22T20:21:15.260Z,1705954875.260 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-01-22T20:21:15.260Z,1705954875.260 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:15.261Z,1705954875.261 [NavChartDb](INFO): Join timeout helper Thread ID is 2836 2024-01-22T20:21:15.632Z,1705954875.632 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:15.633Z,1705954875.633 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:15.652Z,1705954875.652 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2024-01-22T20:21:15.652Z,1705954875.652 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:15.653Z,1705954875.653 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 2837 2024-01-22T20:21:15.880Z,1705954875.880 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:15.880Z,1705954875.880 [WetLabsUBAT](INFO): Powering down 2024-01-22T20:21:15.881Z,1705954875.881 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:15.888Z,1705954875.888 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-01-22T20:21:15.888Z,1705954875.888 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:15.888Z,1705954875.888 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2838 2024-01-22T20:21:16.240Z,1705954876.240 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:16.240Z,1705954876.240 [WetLabsBB2FL](INFO): Powering down 2024-01-22T20:21:16.241Z,1705954876.241 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:16.260Z,1705954876.260 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-01-22T20:21:16.260Z,1705954876.260 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:16.260Z,1705954876.260 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2839 2024-01-22T20:21:16.484Z,1705954876.484 [CTD_Seabird](INFO): Powering down 2024-01-22T20:21:16.496Z,1705954876.496 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:16.496Z,1705954876.496 [CTD_Seabird](INFO): Powering down 2024-01-22T20:21:16.508Z,1705954876.508 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:16.508Z,1705954876.508 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-01-22T20:21:16.508Z,1705954876.508 [DAT ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:16.509Z,1705954876.509 [DAT](INFO): Join timeout helper Thread ID is 2840 2024-01-22T20:21:16.596Z,1705954876.596 [DAT](INFO): Powering down 2024-01-22T20:21:16.669Z,1705954876.669 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:16.669Z,1705954876.669 [DAT](INFO): Powering down 2024-01-22T20:21:16.670Z,1705954876.670 [DAT ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:16.693Z,1705954876.693 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-01-22T20:21:16.693Z,1705954876.693 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:16.693Z,1705954876.693 [Radio_Surface](INFO): Join timeout helper Thread ID is 2841 2024-01-22T20:21:17.024Z,1705954877.024 [Radio_Surface](INFO): Powering down 2024-01-22T20:21:17.025Z,1705954877.025 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:17.025Z,1705954877.025 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:17.045Z,1705954877.045 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-01-22T20:21:17.045Z,1705954877.045 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:17.045Z,1705954877.045 [Onboard](INFO): Join timeout helper Thread ID is 2842 2024-01-22T20:21:17.305Z,1705954877.305 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-01-22T20:21:21.549Z,1705954881.549 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:21.549Z,1705954881.549 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:21.565Z,1705954881.565 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-01-22T20:21:21.565Z,1705954881.565 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:21.565Z,1705954881.565 [DataOverHttps](INFO): Join timeout helper Thread ID is 2843 2024-01-22T20:21:22.324Z,1705954882.324 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:22.324Z,1705954882.324 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:22.345Z,1705954882.345 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-01-22T20:21:22.345Z,1705954882.345 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:22.345Z,1705954882.345 [BackseatComponent](INFO): Join timeout helper Thread ID is 2844 2024-01-22T20:21:22.488Z,1705954882.488 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:22.488Z,1705954882.488 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:22.493Z,1705954882.493 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-01-22T20:21:22.493Z,1705954882.493 [logger ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:22.493Z,1705954882.493 [logger](INFO): Join timeout helper Thread ID is 2845 2024-01-22T20:21:22.536Z,1705954882.536 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:22.536Z,1705954882.536 [logger ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:22.553Z,1705954882.553 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-01-22T20:21:22.553Z,1705954882.553 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:22.553Z,1705954882.553 [CommandLine](INFO): Join timeout helper Thread ID is 2846 2024-01-22T20:21:22.644Z,1705954882.644 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:22.645Z,1705954882.645 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:22.653Z,1705954882.653 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-01-22T20:21:22.653Z,1705954882.653 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:22.653Z,1705954882.653 [CommandExec](INFO): Join timeout helper Thread ID is 2847 2024-01-22T20:21:22.654Z,1705954882.654 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-01-22T20:21:22.654Z,1705954882.654 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:22.655Z,1705954882.655 [controlThread](INFO): Join timeout helper Thread ID is 2848 2024-01-22T20:21:22.760Z,1705954882.760 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-22T20:21:22.760Z,1705954882.760 [controlThread](DEBUG): Uninitializing ControlThread 2024-01-22T20:21:22.761Z,1705954882.761 [AHRS_M2](INFO): Powering down 2024-01-22T20:21:22.833Z,1705954882.833 [NAL9602](INFO): Powering down 2024-01-22T20:21:22.904Z,1705954882.904 [RDI_Pathfinder](INFO): Powering down 2024-01-22T20:21:22.906Z,1705954882.906 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-01-22T20:21:22.906Z,1705954882.906 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-01-22T20:21:22.907Z,1705954882.907 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-01-22T20:21:22.907Z,1705954882.907 [MissionManager](INFO): Uninitializing Mission Default 2024-01-22T20:21:22.907Z,1705954882.907 [Default] Stopped 2024-01-22T20:21:22.907Z,1705954882.907 [Default](DEBUG): Aggregate::uninitialize Default 2024-01-22T20:21:22.908Z,1705954882.908 [Default:B.GoToSurface] Stopped 2024-01-22T20:21:22.908Z,1705954882.908 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-01-22T20:21:22.908Z,1705954882.908 [Default:CheckIn] Stopped 2024-01-22T20:21:22.908Z,1705954882.908 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-22T20:21:22.908Z,1705954882.908 [Default:CheckIn:Read_GPS] Stopped 2024-01-22T20:21:22.911Z,1705954882.911 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-01-22T20:21:22.911Z,1705954882.911 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-01-22T20:21:22.911Z,1705954882.911 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-01-22T20:21:22.912Z,1705954882.912 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-01-22T20:21:22.912Z,1705954882.912 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-01-22T20:21:22.912Z,1705954882.912 [BuoyancyServo](INFO): Powering down 2024-01-22T20:21:22.924Z,1705954882.924 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-01-22T20:21:22.924Z,1705954882.924 [ElevatorServo](INFO): Powering down 2024-01-22T20:21:22.925Z,1705954882.925 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-01-22T20:21:22.925Z,1705954882.925 [MassServo](INFO): Powering down 2024-01-22T20:21:22.926Z,1705954882.926 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-01-22T20:21:22.926Z,1705954882.926 [RudderServo](INFO): Powering down 2024-01-22T20:21:22.927Z,1705954882.927 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2024-01-22T20:21:22.927Z,1705954882.927 [ThrusterServo](INFO): Powering down 2024-01-22T20:21:22.928Z,1705954882.928 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-01-22T20:21:22.928Z,1705954882.928 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-01-22T20:21:22.929Z,1705954882.929 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-01-22T20:21:22.929Z,1705954882.929 [CBIT](DEBUG): Powering off loads. 2024-01-22T20:21:22.940Z,1705954882.940 [CBIT](DEBUG): Disabling WDT. 2024-01-22T20:21:22.952Z,1705954882.952 [CBIT](DEBUG): Opening all GF detection circuits. 2024-01-22T20:21:22.953Z,1705954882.953 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:23.019Z,1705954883.019 [DAT ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:23.026Z,1705954883.026 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:23.028Z,1705954883.028 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:23.034Z,1705954883.034 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:23.077Z,1705954883.077 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:23.138Z,1705954883.138 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:23.140Z,1705954883.140 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:23.144Z,1705954883.144 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:23.153Z,1705954883.153 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-01-22T20:21:23.269Z,1705954883.269 [logger ThreadHandler](INFO): Thread cancelled.