2022-04-02T01:18:30.649Z,1648862310.649 [CommandExec](IMPORTANT): got command restart logs 2022-04-02T01:19:01.181Z,1648862341.181 [NAL9602](INFO): Not Powering down - fast GPS 2022-04-02T01:20:13.134Z,1648862413.134 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-04-02T01:20:13.134Z,1648862413.134 [Default:CheckIn:C.Wait] Stopped 2022-04-02T01:20:13.134Z,1648862413.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-04-02T01:20:13.134Z,1648862413.134 [Default:CheckIn:D] Running Loop=1 2022-04-02T01:20:13.548Z,1648862413.548 [Default:CheckIn:D] Stopped 2022-04-02T01:20:13.548Z,1648862413.548 [Default:CheckIn:E] Running Loop=1 2022-04-02T01:20:13.940Z,1648862413.940 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.057806 min 2022-04-02T01:20:13.940Z,1648862413.940 [Default:CheckIn:E] Stopped 2022-04-02T01:20:13.940Z,1648862413.940 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-04-02T01:20:13.941Z,1648862413.941 [Default:CheckIn] Stopped 2022-04-02T01:20:13.941Z,1648862413.941 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-04-02T01:20:13.941Z,1648862413.941 [Default:CheckIn](INFO): Running loop #4 2022-04-02T01:20:13.941Z,1648862413.941 [Default:CheckIn] Running Loop=4 2022-04-02T01:20:13.941Z,1648862413.941 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-04-02T01:20:13.941Z,1648862413.941 [Default:CheckIn:Read_GPS] Running Loop=1 2022-04-02T01:20:15.919Z,1648862415.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012015.00,A,3648.16461,N,12147.21693,W,0.292,259.49,020422,,,A*74 2022-04-02T01:20:15.921Z,1648862415.921 [NAL9602](INFO): GPS fix at 20220402T012015: (36.802743, -121.786949) 2022-04-02T01:20:15.961Z,1648862415.961 [Default:CheckIn:Read_GPS] Stopped 2022-04-02T01:20:15.961Z,1648862415.961 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-04-02T01:20:17.241Z,1648862417.241 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220401T165853/Courier0075.lzma 2022-04-02T01:20:17.602Z,1648862417.602 [DataOverHttps](INFO): Moved sent file to Logs/20220401T165853/Courier0075.lzma.bak 2022-04-02T01:20:17.602Z,1648862417.602 [DataOverHttps](INFO): SBD MOMSN=16623255 2022-04-02T01:20:33.872Z,1648862433.872 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20220402T011830/Courier0000.lzma 2022-04-02T01:20:34.874Z,1648862434.874 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Courier0000.lzma.bak 2022-04-02T01:20:34.874Z,1648862434.874 [DataOverHttps](INFO): SBD MOMSN=16623261 2022-04-02T01:20:52.540Z,1648862452.540 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20220401T165853/Express0076.lzma 2022-04-02T01:20:53.542Z,1648862453.542 [DataOverHttps](INFO): Moved sent file to Logs/20220401T165853/Express0076.lzma.bak 2022-04-02T01:20:53.542Z,1648862453.542 [DataOverHttps](INFO): SBD MOMSN=16623265 2022-04-02T01:21:11.044Z,1648862471.044 [DataOverHttps](INFO): Sending 380 bytes from file Logs/20220402T011830/Express0001.lzma 2022-04-02T01:21:12.046Z,1648862472.046 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Express0001.lzma.bak 2022-04-02T01:21:12.046Z,1648862472.046 [DataOverHttps](INFO): SBD MOMSN=16623270 2022-04-02T01:21:14.348Z,1648862474.348 [Default:CheckIn:Read_Iridium] Stopped 2022-04-02T01:21:14.349Z,1648862474.349 [Default:CheckIn:C.Wait] Running Loop=1 2022-04-02T01:21:14.349Z,1648862474.349 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-04-02T01:21:35.723Z,1648862495.723 [NAL9602](INFO): SBD MO Status=2, MOMSN=18633, MT Status=2, MTMSN=0 2022-04-02T01:21:35.723Z,1648862495.723 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-04-02T01:25:18.354Z,1648862718.354 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-04-02T01:25:49.053Z,1648862749.053 [NAL9602](INFO): Not Powering down - fast GPS 2022-04-02T01:26:14.987Z,1648862774.987 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-04-02T01:26:14.987Z,1648862774.987 [Default:CheckIn:C.Wait] Stopped 2022-04-02T01:26:14.987Z,1648862774.987 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-04-02T01:26:14.987Z,1648862774.987 [Default:CheckIn:D] Running Loop=1 2022-04-02T01:26:15.353Z,1648862775.353 [Default:CheckIn:D] Stopped 2022-04-02T01:26:15.353Z,1648862775.353 [Default:CheckIn:E] Running Loop=1 2022-04-02T01:26:15.752Z,1648862775.752 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.088289 min 2022-04-02T01:26:15.752Z,1648862775.752 [Default:CheckIn:E] Stopped 2022-04-02T01:26:15.752Z,1648862775.752 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-04-02T01:26:15.752Z,1648862775.752 [Default:CheckIn] Stopped 2022-04-02T01:26:15.753Z,1648862775.753 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-04-02T01:26:15.753Z,1648862775.753 [Default:CheckIn](INFO): Running loop #5 2022-04-02T01:26:15.753Z,1648862775.753 [Default:CheckIn] Running Loop=5 2022-04-02T01:26:15.753Z,1648862775.753 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-04-02T01:26:15.753Z,1648862775.753 [Default:CheckIn:Read_GPS] Running Loop=1 2022-04-02T01:26:17.743Z,1648862777.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012617.00,A,3648.16250,N,12147.21808,W,0.019,194.91,020422,,,A*7E 2022-04-02T01:26:17.745Z,1648862777.745 [NAL9602](INFO): GPS fix at 20220402T012617: (36.802708, -121.786968) 2022-04-02T01:26:17.776Z,1648862777.776 [Default:CheckIn:Read_GPS] Stopped 2022-04-02T01:26:17.776Z,1648862777.776 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-04-02T01:26:25.763Z,1648862785.763 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220402T011830/Courier0003.lzma 2022-04-02T01:26:26.766Z,1648862786.766 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Courier0003.lzma.bak 2022-04-02T01:26:26.766Z,1648862786.766 [DataOverHttps](INFO): SBD MOMSN=16623287 2022-04-02T01:26:44.712Z,1648862804.712 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20220402T011830/Express0004.lzma 2022-04-02T01:26:45.714Z,1648862805.714 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Express0004.lzma.bak 2022-04-02T01:26:45.714Z,1648862805.714 [DataOverHttps](INFO): SBD MOMSN=16623290 2022-04-02T01:26:48.064Z,1648862808.064 [Default:CheckIn:Read_Iridium] Stopped 2022-04-02T01:26:48.064Z,1648862808.064 [Default:CheckIn:C.Wait] Running Loop=1 2022-04-02T01:26:48.065Z,1648862808.065 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-04-02T01:26:48.451Z,1648862808.451 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-04-02T01:26:48.524Z,1648862808.524 [NAL9602](ERROR): received: +CSQ:0 OK633, 2, 0, 0, 0 OK 2022-04-02T01:26:57.393Z,1648862817.393 [NAL9602](INFO): SBD MO Status=1, MOMSN=18633, MT Status=0, MTMSN=0 2022-04-02T01:26:57.393Z,1648862817.393 [NAL9602](INFO): No messages in MT queue 2022-04-02T01:27:28.029Z,1648862848.029 [NAL9602](INFO): Not Powering down - fast GPS 2022-04-02T01:29:57.942Z,1648862997.942 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-04-02T01:29:57.944Z,1648862997.944 [BPC1](INFO): Received data from all battery sticks. 2022-04-02T01:31:48.679Z,1648863108.679 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-04-02T01:31:48.679Z,1648863108.679 [Default:CheckIn:C.Wait] Stopped 2022-04-02T01:31:48.679Z,1648863108.679 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-04-02T01:31:48.680Z,1648863108.680 [Default:CheckIn:D] Running Loop=1 2022-04-02T01:31:49.043Z,1648863109.043 [Default:CheckIn:D] Stopped 2022-04-02T01:31:49.044Z,1648863109.044 [Default:CheckIn:E] Running Loop=1 2022-04-02T01:31:49.452Z,1648863109.452 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.649805 min 2022-04-02T01:31:49.452Z,1648863109.452 [Default:CheckIn:E] Stopped 2022-04-02T01:31:49.452Z,1648863109.452 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-04-02T01:31:49.452Z,1648863109.452 [Default:CheckIn] Stopped 2022-04-02T01:31:49.453Z,1648863109.453 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-04-02T01:31:49.453Z,1648863109.453 [Default:CheckIn](INFO): Running loop #6 2022-04-02T01:31:49.453Z,1648863109.453 [Default:CheckIn] Running Loop=6 2022-04-02T01:31:49.453Z,1648863109.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-04-02T01:31:49.453Z,1648863109.453 [Default:CheckIn:Read_GPS] Running Loop=1 2022-04-02T01:31:51.459Z,1648863111.459 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013150.00,A,3648.16906,N,12147.28124,W,0.272,311.07,020422,,,A*72 2022-04-02T01:31:51.461Z,1648863111.461 [NAL9602](INFO): GPS fix at 20220402T013150: (36.802818, -121.788021) 2022-04-02T01:31:51.490Z,1648863111.490 [Default:CheckIn:Read_GPS] Stopped 2022-04-02T01:31:51.490Z,1648863111.490 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-04-02T01:31:59.548Z,1648863119.548 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20220402T011830/Courier0006.lzma 2022-04-02T01:32:00.550Z,1648863120.550 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Courier0006.lzma.bak 2022-04-02T01:32:00.550Z,1648863120.550 [DataOverHttps](INFO): SBD MOMSN=16623304 2022-04-02T01:32:21.704Z,1648863141.704 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220402T011830/Express0007.lzma 2022-04-02T01:32:22.706Z,1648863142.706 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Express0007.lzma.bak 2022-04-02T01:32:22.706Z,1648863142.706 [DataOverHttps](INFO): SBD MOMSN=16623307 2022-04-02T01:32:24.685Z,1648863144.685 [Default:CheckIn:Read_Iridium] Stopped 2022-04-02T01:32:24.685Z,1648863144.685 [Default:CheckIn:C.Wait] Running Loop=1 2022-04-02T01:32:24.685Z,1648863144.685 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-04-02T01:35:50.695Z,1648863350.695 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-04-02T01:36:53.350Z,1648863413.350 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-04-02T01:37:24.051Z,1648863444.051 [NAL9602](INFO): Not Powering down - fast GPS 2022-04-02T01:37:25.286Z,1648863445.286 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-04-02T01:37:25.286Z,1648863445.286 [Default:CheckIn:C.Wait] Stopped 2022-04-02T01:37:25.286Z,1648863445.286 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-04-02T01:37:25.287Z,1648863445.287 [Default:CheckIn:D] Running Loop=1 2022-04-02T01:37:25.684Z,1648863445.684 [Default:CheckIn:D] Stopped 2022-04-02T01:37:25.685Z,1648863445.685 [Default:CheckIn:E] Running Loop=1 2022-04-02T01:37:26.085Z,1648863446.085 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.260486 min 2022-04-02T01:37:26.085Z,1648863446.085 [Default:CheckIn:E] Stopped 2022-04-02T01:37:26.085Z,1648863446.085 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-04-02T01:37:26.085Z,1648863446.085 [Default:CheckIn] Stopped 2022-04-02T01:37:26.085Z,1648863446.085 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-04-02T01:37:26.086Z,1648863446.086 [Default:CheckIn](INFO): Running loop #7 2022-04-02T01:37:26.086Z,1648863446.086 [Default:CheckIn] Running Loop=7 2022-04-02T01:37:26.086Z,1648863446.086 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-04-02T01:37:26.086Z,1648863446.086 [Default:CheckIn:Read_GPS] Running Loop=1 2022-04-02T01:37:28.099Z,1648863448.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013727.00,A,3648.16656,N,12147.28182,W,0.700,311.07,020422,,,A*72 2022-04-02T01:37:28.102Z,1648863448.102 [NAL9602](INFO): GPS fix at 20220402T013727: (36.802776, -121.788030) 2022-04-02T01:37:28.113Z,1648863448.113 [Default:CheckIn:Read_GPS] Stopped 2022-04-02T01:37:28.113Z,1648863448.113 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-04-02T01:37:36.224Z,1648863456.224 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220402T011830/Courier0009.lzma 2022-04-02T01:37:37.226Z,1648863457.226 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Courier0009.lzma.bak 2022-04-02T01:37:37.226Z,1648863457.226 [DataOverHttps](INFO): SBD MOMSN=16623324 2022-04-02T01:37:54.904Z,1648863474.904 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20220402T011830/Express0010.lzma 2022-04-02T01:37:55.906Z,1648863475.906 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Express0010.lzma.bak 2022-04-02T01:37:55.906Z,1648863475.906 [DataOverHttps](INFO): SBD MOMSN=16623327 2022-04-02T01:37:58.023Z,1648863478.023 [Default:CheckIn:Read_Iridium] Stopped 2022-04-02T01:37:58.023Z,1648863478.023 [Default:CheckIn:C.Wait] Running Loop=1 2022-04-02T01:37:58.023Z,1648863478.023 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-04-02T01:37:58.798Z,1648863478.798 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-04-02T01:37:58.876Z,1648863478.876 [NAL9602](ERROR): received: +CSQ:0 OK633, 0, 0, 0, 0 OK 2022-04-02T01:40:14.160Z,1648863614.160 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-04-02T01:40:14.160Z,1648863614.160 [DropWeight] Hardware Fault, FailCount= 1 2022-04-02T01:40:14.160Z,1648863614.160 [DropWeight](ERROR): Hardware Fault 2022-04-02T01:40:14.178Z,1648863614.178 [CommandExec](FAULT): Scheduling is paused 2022-04-02T01:40:14.178Z,1648863614.178 [CBIT](INFO): Critical error at 20220402T014014 2022-04-02T01:40:14.181Z,1648863614.181 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-04-02T01:40:14.181Z,1648863614.181 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-04-02T01:40:14.621Z,1648863614.621 [CBIT](INFO): Critical error at 20220402T014014 2022-04-02T01:42:30.325Z,1648863750.325 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-04-02T01:42:58.672Z,1648863778.672 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-04-02T01:42:58.672Z,1648863778.672 [Default:CheckIn:C.Wait] Stopped 2022-04-02T01:42:58.673Z,1648863778.673 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-04-02T01:42:58.673Z,1648863778.673 [Default:CheckIn:D] Running Loop=1 2022-04-02T01:42:59.033Z,1648863779.033 [Default:CheckIn:D] Stopped 2022-04-02T01:42:59.033Z,1648863779.033 [Default:CheckIn:E] Running Loop=1 2022-04-02T01:42:59.442Z,1648863779.442 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.816292 min 2022-04-02T01:42:59.442Z,1648863779.442 [Default:CheckIn:E] Stopped 2022-04-02T01:42:59.442Z,1648863779.442 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-04-02T01:42:59.442Z,1648863779.442 [Default:CheckIn] Stopped 2022-04-02T01:42:59.442Z,1648863779.442 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-04-02T01:42:59.443Z,1648863779.443 [Default:CheckIn](INFO): Running loop #8 2022-04-02T01:42:59.443Z,1648863779.443 [Default:CheckIn] Running Loop=8 2022-04-02T01:42:59.443Z,1648863779.443 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-04-02T01:42:59.443Z,1648863779.443 [Default:CheckIn:Read_GPS] Running Loop=1 2022-04-02T01:43:01.431Z,1648863781.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014300.00,A,3648.16691,N,12147.27813,W,0.311,191.90,020422,,,A*71 2022-04-02T01:43:01.433Z,1648863781.433 [NAL9602](INFO): GPS fix at 20220402T014300: (36.802782, -121.787969) 2022-04-02T01:43:01.448Z,1648863781.448 [Default:CheckIn:Read_GPS] Stopped 2022-04-02T01:43:01.448Z,1648863781.448 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-04-02T01:43:09.576Z,1648863789.576 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20220402T011830/Courier0012.lzma 2022-04-02T01:43:10.578Z,1648863790.578 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Courier0012.lzma.bak 2022-04-02T01:43:10.578Z,1648863790.578 [DataOverHttps](INFO): SBD MOMSN=16623339 2022-04-02T01:43:27.698Z,1648863807.698 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20220402T011830/Express0013.lzma 2022-04-02T01:43:28.698Z,1648863808.698 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Express0013.lzma.bak 2022-04-02T01:43:28.698Z,1648863808.698 [DataOverHttps](INFO): SBD MOMSN=16623345 2022-04-02T01:43:30.658Z,1648863810.658 [Default:CheckIn:Read_Iridium] Stopped 2022-04-02T01:43:30.658Z,1648863810.658 [Default:CheckIn:C.Wait] Running Loop=1 2022-04-02T01:43:30.658Z,1648863810.658 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-04-02T01:43:33.438Z,1648863813.438 [NAL9602](INFO): Not Powering down - fast GPS 2022-04-02T01:48:31.213Z,1648864111.213 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-04-02T01:48:31.213Z,1648864111.213 [Default:CheckIn:C.Wait] Stopped 2022-04-02T01:48:31.213Z,1648864111.213 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-04-02T01:48:31.213Z,1648864111.213 [Default:CheckIn:D] Running Loop=1 2022-04-02T01:48:31.615Z,1648864111.615 [Default:CheckIn:D] Stopped 2022-04-02T01:48:31.615Z,1648864111.615 [Default:CheckIn:E] Running Loop=1 2022-04-02T01:48:32.023Z,1648864112.023 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.359330 min 2022-04-02T01:48:32.023Z,1648864112.023 [Default:CheckIn:E] Stopped 2022-04-02T01:48:32.023Z,1648864112.023 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-04-02T01:48:32.023Z,1648864112.023 [Default:CheckIn] Stopped 2022-04-02T01:48:32.023Z,1648864112.023 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-04-02T01:48:32.023Z,1648864112.023 [Default:CheckIn](INFO): Running loop #9 2022-04-02T01:48:32.024Z,1648864112.024 [Default:CheckIn] Running Loop=9 2022-04-02T01:48:32.024Z,1648864112.024 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-04-02T01:48:32.024Z,1648864112.024 [Default:CheckIn:Read_GPS] Running Loop=1 2022-04-02T01:48:34.028Z,1648864114.028 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014833.00,A,3648.17190,N,12147.28623,W,1.438,191.90,020422,,,A*72 2022-04-02T01:48:34.030Z,1648864114.030 [NAL9602](INFO): GPS fix at 20220402T014833: (36.802865, -121.788104) 2022-04-02T01:48:34.061Z,1648864114.061 [Default:CheckIn:Read_GPS] Stopped 2022-04-02T01:48:34.061Z,1648864114.061 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-04-02T01:48:41.143Z,1648864121.143 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220402T011830/Courier0015.lzma 2022-04-02T01:48:42.146Z,1648864122.146 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Courier0015.lzma.bak 2022-04-02T01:48:42.146Z,1648864122.146 [DataOverHttps](INFO): SBD MOMSN=16623359 2022-04-02T01:48:58.871Z,1648864138.871 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220402T011830/Express0016.lzma 2022-04-02T01:48:59.874Z,1648864139.874 [DataOverHttps](INFO): Moved sent file to Logs/20220402T011830/Express0016.lzma.bak 2022-04-02T01:48:59.874Z,1648864139.874 [DataOverHttps](INFO): SBD MOMSN=16623366 2022-04-02T01:49:02.014Z,1648864142.014 [Default:CheckIn:Read_Iridium] Stopped 2022-04-02T01:49:02.014Z,1648864142.014 [Default:CheckIn:C.Wait] Running Loop=1 2022-04-02T01:49:02.014Z,1648864142.014 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-04-02T01:49:04.477Z,1648864144.477 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-04-02T01:49:04.560Z,1648864144.560 [NAL9602](ERROR): received: +CSQ:0 OK633, 0, 0, 0, 0 OK 2022-04-02T01:53:36.329Z,1648864416.329 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-04-02T01:54:02.652Z,1648864442.652 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-04-02T01:54:02.653Z,1648864442.653 [Default:CheckIn:C.Wait] Stopped 2022-04-02T01:54:02.653Z,1648864442.653 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-04-02T01:54:02.653Z,1648864442.653 [Default:CheckIn:D] Running Loop=1 2022-04-02T01:54:03.004Z,1648864443.004 [Default:CheckIn:D] Stopped 2022-04-02T01:54:03.004Z,1648864443.004 [Default:CheckIn:E] Running Loop=1 2022-04-02T01:54:03.422Z,1648864443.422 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.882487 min 2022-04-02T01:54:03.422Z,1648864443.422 [Default:CheckIn:E] Stopped 2022-04-02T01:54:03.423Z,1648864443.423 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-04-02T01:54:03.423Z,1648864443.423 [Default:CheckIn] Stopped 2022-04-02T01:54:03.423Z,1648864443.423 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-04-02T01:54:03.423Z,1648864443.423 [Default:CheckIn](INFO): Running loop #10 2022-04-02T01:54:03.423Z,1648864443.423 [Default:CheckIn] Running Loop=10 2022-04-02T01:54:03.423Z,1648864443.423 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-04-02T01:54:03.423Z,1648864443.423 [Default:CheckIn:Read_GPS] Running Loop=1 2022-04-02T01:54:05.415Z,1648864445.415 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015404.00,A,3648.17158,N,12147.29418,W,0.544,28.36,020422,,,A*40 2022-04-02T01:54:05.417Z,1648864445.417 [NAL9602](INFO): GPS fix at 20220402T015404: (36.802860, -121.788236) 2022-04-02T01:54:05.490Z,1648864445.490 [Default:CheckIn:Read_GPS] Stopped 2022-04-02T01:54:05.490Z,1648864445.490 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-04-02T01:54:07.433Z,1648864447.433 [CommandExec](IMPORTANT): got command quit 2022-04-02T01:54:08.436Z,1648864448.436 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:08.437Z,1648864448.437 [CommandExec](INFO): Uninitializing the command executive. 2022-04-02T01:54:08.437Z,1648864448.437 [CommandExec](INFO): Uninitializing the command scheduler. 2022-04-02T01:54:08.438Z,1648864448.438 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:08.612Z,1648864448.612 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-04-02T01:54:08.612Z,1648864448.612 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-04-02T01:54:08.612Z,1648864448.612 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:08.612Z,1648864448.612 [NavChartDb](INFO): Join timeout helper Thread ID is 8637 2022-04-02T01:54:08.630Z,1648864448.630 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:08.631Z,1648864448.631 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:08.632Z,1648864448.632 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2022-04-02T01:54:08.632Z,1648864448.632 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:08.632Z,1648864448.632 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 8638 2022-04-02T01:54:08.840Z,1648864448.840 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:08.840Z,1648864448.840 [WetLabsUBAT](INFO): Powering down 2022-04-02T01:54:08.841Z,1648864448.841 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:08.852Z,1648864448.852 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-04-02T01:54:08.852Z,1648864448.852 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:08.852Z,1648864448.852 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8639 2022-04-02T01:54:08.884Z,1648864448.884 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:08.885Z,1648864448.885 [WetLabsBB2FL](INFO): Powering down 2022-04-02T01:54:08.885Z,1648864448.885 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:08.888Z,1648864448.888 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-04-02T01:54:08.888Z,1648864448.888 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:08.888Z,1648864448.888 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8640 2022-04-02T01:54:09.748Z,1648864449.748 [CTD_Seabird](INFO): Powering down 2022-04-02T01:54:09.760Z,1648864449.760 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:09.760Z,1648864449.760 [CTD_Seabird](INFO): Powering down 2022-04-02T01:54:09.773Z,1648864449.773 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:09.782Z,1648864449.782 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2022-04-02T01:54:09.782Z,1648864449.782 [DAT ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:09.783Z,1648864449.783 [DAT](INFO): Join timeout helper Thread ID is 8641 2022-04-02T01:54:09.956Z,1648864449.956 [DAT](INFO): Powering down 2022-04-02T01:54:10.024Z,1648864450.024 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:10.025Z,1648864450.025 [DAT](INFO): Powering down 2022-04-02T01:54:10.025Z,1648864450.025 [DAT ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:10.041Z,1648864450.041 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-04-02T01:54:10.041Z,1648864450.041 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:10.041Z,1648864450.041 [Radio_Surface](INFO): Join timeout helper Thread ID is 8642 2022-04-02T01:54:10.076Z,1648864450.076 [Radio_Surface](INFO): Powering down 2022-04-02T01:54:10.077Z,1648864450.077 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:10.077Z,1648864450.077 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:10.081Z,1648864450.081 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-04-02T01:54:10.081Z,1648864450.081 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:10.081Z,1648864450.081 [Onboard](INFO): Join timeout helper Thread ID is 8643 2022-04-02T01:54:11.312Z,1648864451.312 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:11.312Z,1648864451.312 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:11.329Z,1648864451.329 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-04-02T01:54:11.329Z,1648864451.329 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:11.329Z,1648864451.329 [DataOverHttps](INFO): Join timeout helper Thread ID is 8644 2022-04-02T01:54:13.457Z,1648864453.457 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:13.461Z,1648864453.461 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:13.473Z,1648864453.473 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2022-04-02T01:54:13.473Z,1648864453.473 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:13.473Z,1648864453.473 [BackseatComponent](INFO): Join timeout helper Thread ID is 8645 2022-04-02T01:54:13.592Z,1648864453.592 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:13.592Z,1648864453.592 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:13.613Z,1648864453.613 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-04-02T01:54:13.613Z,1648864453.613 [logger ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:13.613Z,1648864453.613 [logger](INFO): Join timeout helper Thread ID is 8646 2022-04-02T01:54:13.656Z,1648864453.656 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:13.656Z,1648864453.656 [logger ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:13.665Z,1648864453.665 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-04-02T01:54:13.665Z,1648864453.665 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:13.665Z,1648864453.665 [CommandLine](INFO): Join timeout helper Thread ID is 8647 2022-04-02T01:54:13.740Z,1648864453.740 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:13.740Z,1648864453.740 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:13.745Z,1648864453.745 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-04-02T01:54:13.745Z,1648864453.745 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:13.745Z,1648864453.745 [CommandExec](INFO): Join timeout helper Thread ID is 8648 2022-04-02T01:54:13.746Z,1648864453.746 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-04-02T01:54:13.746Z,1648864453.746 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:13.747Z,1648864453.747 [controlThread](INFO): Join timeout helper Thread ID is 8649 2022-04-02T01:54:14.028Z,1648864454.028 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-02T01:54:14.028Z,1648864454.028 [controlThread](DEBUG): Uninitializing ControlThread 2022-04-02T01:54:14.029Z,1648864454.029 [AHRS_M2](INFO): Powering down 2022-04-02T01:54:14.101Z,1648864454.101 [NAL9602](INFO): Powering down 2022-04-02T01:54:14.172Z,1648864454.172 [RDI_Pathfinder](INFO): Powering down 2022-04-02T01:54:14.173Z,1648864454.173 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2022-04-02T01:54:14.174Z,1648864454.174 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2022-04-02T01:54:14.175Z,1648864454.175 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2022-04-02T01:54:14.175Z,1648864454.175 [MissionManager](INFO): Uninitializing Mission Default 2022-04-02T01:54:14.175Z,1648864454.175 [Default] Stopped 2022-04-02T01:54:14.175Z,1648864454.175 [Default](DEBUG): Aggregate::uninitialize Default 2022-04-02T01:54:14.175Z,1648864454.175 [Default:B.GoToSurface] Stopped 2022-04-02T01:54:14.175Z,1648864454.175 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-04-02T01:54:14.175Z,1648864454.175 [Default:CheckIn] Stopped 2022-04-02T01:54:14.176Z,1648864454.176 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-04-02T01:54:14.176Z,1648864454.176 [Default:CheckIn:Read_Iridium] Stopped 2022-04-02T01:54:14.178Z,1648864454.178 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2022-04-02T01:54:14.179Z,1648864454.179 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2022-04-02T01:54:14.179Z,1648864454.179 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2022-04-02T01:54:14.179Z,1648864454.179 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2022-04-02T01:54:14.180Z,1648864454.180 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-04-02T01:54:14.180Z,1648864454.180 [BuoyancyServo](INFO): Powering down 2022-04-02T01:54:14.192Z,1648864454.192 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-04-02T01:54:14.192Z,1648864454.192 [ElevatorServo](INFO): Powering down 2022-04-02T01:54:14.193Z,1648864454.193 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-04-02T01:54:14.193Z,1648864454.193 [MassServo](INFO): Powering down 2022-04-02T01:54:14.194Z,1648864454.194 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-04-02T01:54:14.194Z,1648864454.194 [RudderServo](INFO): Powering down 2022-04-02T01:54:14.195Z,1648864454.195 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2022-04-02T01:54:14.195Z,1648864454.195 [ThrusterServo](INFO): Powering down 2022-04-02T01:54:14.196Z,1648864454.196 [SBIT](DEBUG): Uninitialize SBIT Component. 2022-04-02T01:54:14.196Z,1648864454.196 [IBIT](DEBUG): Uninitialize IBIT Component. 2022-04-02T01:54:14.196Z,1648864454.196 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-04-02T01:54:14.197Z,1648864454.197 [CBIT](DEBUG): Powering off loads. 2022-04-02T01:54:14.208Z,1648864454.208 [CBIT](DEBUG): Disabling WDT. 2022-04-02T01:54:14.220Z,1648864454.220 [CBIT](DEBUG): Opening all GF detection circuits. 2022-04-02T01:54:14.221Z,1648864454.221 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:14.279Z,1648864454.279 [DAT ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:14.287Z,1648864454.287 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:14.289Z,1648864454.289 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:14.295Z,1648864454.295 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:14.341Z,1648864454.341 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:14.399Z,1648864454.399 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:14.401Z,1648864454.401 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:14.405Z,1648864454.405 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:14.413Z,1648864454.413 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-04-02T01:54:14.500Z,1648864454.500 [logger ThreadHandler](INFO): Thread cancelled.