2021-10-08T21:28:22.153Z,1633728502.153 [Default:E.Execute](INFO): Executing command restart logs 2021-10-08T21:28:22.166Z,1633728502.166 [Default:E.Execute] Stopped 2021-10-08T21:28:22.166Z,1633728502.166 [Default](INFO): Completed Default 2021-10-08T21:28:22.166Z,1633728502.166 [Default] Stopped 2021-10-08T21:28:22.167Z,1633728502.167 [Default](DEBUG): Aggregate::uninitialize Default 2021-10-08T21:28:22.167Z,1633728502.167 [Default:B.GoToSurface] Stopped 2021-10-08T21:28:22.167Z,1633728502.167 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-10-08T21:28:22.191Z,1633728502.191 [CommandExec](IMPORTANT): got command restart logs 2021-10-08T21:28:22.565Z,1633728502.565 [MissionManager](IMPORTANT): Started mission Default 2021-10-08T21:28:22.569Z,1633728502.569 [Default] Running Loop=1 2021-10-08T21:28:22.570Z,1633728502.570 [Default](DEBUG): Aggregate::initialize Default 2021-10-08T21:28:22.570Z,1633728502.570 [Default:B.GoToSurface] Running Loop=1 2021-10-08T21:28:22.570Z,1633728502.570 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-10-08T21:28:22.570Z,1633728502.570 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-10-08T21:28:22.570Z,1633728502.570 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-10-08T21:28:22.571Z,1633728502.571 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-10-08T21:28:22.571Z,1633728502.571 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-10-08T21:28:22.571Z,1633728502.571 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-10-08T21:28:22.572Z,1633728502.572 [Default:A.Wait] Running Loop=1 2021-10-08T21:28:22.572Z,1633728502.572 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-10-08T21:28:24.971Z,1633728504.971 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T21:28:35.902Z,1633728515.902 [Default:A.Wait](INFO): Done Waiting. 2021-10-08T21:28:35.902Z,1633728515.902 [Default:A.Wait] Stopped 2021-10-08T21:28:35.902Z,1633728515.902 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T21:28:36.314Z,1633728516.314 [Default:CheckIn] Running Loop=1 2021-10-08T21:28:36.314Z,1633728516.314 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T21:28:36.314Z,1633728516.314 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T21:28:38.305Z,1633728518.305 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212837.00,A,3648.17135,N,12147.27831,W,0.175,211.55,081021,,,A*7C 2021-10-08T21:28:38.311Z,1633728518.311 [NAL9602](INFO): GPS fix at 20211008T212837: (36.802856, -121.787972) 2021-10-08T21:28:38.331Z,1633728518.331 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T21:28:38.331Z,1633728518.331 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T21:28:45.717Z,1633728525.717 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211007T184752/Courier0864.lzma 2021-10-08T21:28:46.720Z,1633728526.720 [DataOverHttps](INFO): Moved sent file to Logs/20211007T184752/Courier0864.lzma.bak 2021-10-08T21:28:46.720Z,1633728526.720 [DataOverHttps](INFO): SBD MOMSN=15991527 2021-10-08T21:29:05.425Z,1633728545.425 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20211008T212822/Courier0000.lzma 2021-10-08T21:29:06.427Z,1633728546.427 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0000.lzma.bak 2021-10-08T21:29:06.427Z,1633728546.427 [DataOverHttps](INFO): SBD MOMSN=15991543 2021-10-08T21:29:09.077Z,1633728549.077 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T21:29:09.161Z,1633728549.161 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T21:29:22.717Z,1633728562.717 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20211007T184752/Express0865.lzma 2021-10-08T21:29:23.720Z,1633728563.720 [DataOverHttps](INFO): Moved sent file to Logs/20211007T184752/Express0865.lzma.bak 2021-10-08T21:29:23.720Z,1633728563.720 [DataOverHttps](INFO): SBD MOMSN=15991547 2021-10-08T21:29:39.818Z,1633728579.818 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20211008T212822/Express0001.lzma 2021-10-08T21:29:40.819Z,1633728580.819 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0001.lzma.bak 2021-10-08T21:29:40.819Z,1633728580.819 [DataOverHttps](INFO): SBD MOMSN=15991555 2021-10-08T21:29:42.215Z,1633728582.215 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T21:29:42.215Z,1633728582.215 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T21:29:42.215Z,1633728582.215 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T21:31:53.520Z,1633728713.520 [NAL9602](INFO): SBD MO Status=2, MOMSN=30084, MT Status=2, MTMSN=0 2021-10-08T21:31:53.521Z,1633728713.520 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-10-08T21:33:40.639Z,1633728820.639 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T21:34:11.347Z,1633728851.347 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T21:34:42.865Z,1633728882.865 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T21:34:42.865Z,1633728882.865 [Default:CheckIn:C.Wait] Stopped 2021-10-08T21:34:42.865Z,1633728882.865 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T21:34:42.866Z,1633728882.866 [Default:CheckIn:D] Running Loop=1 2021-10-08T21:34:43.279Z,1633728883.279 [Default:CheckIn:D] Stopped 2021-10-08T21:34:43.279Z,1633728883.279 [Default:CheckIn:E] Running Loop=1 2021-10-08T21:34:43.675Z,1633728883.675 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.345231 min 2021-10-08T21:34:43.675Z,1633728883.675 [Default:CheckIn:E] Stopped 2021-10-08T21:34:43.675Z,1633728883.675 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T21:34:43.676Z,1633728883.676 [Default:CheckIn] Stopped 2021-10-08T21:34:43.676Z,1633728883.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T21:34:43.676Z,1633728883.676 [Default:CheckIn](INFO): Running loop #2 2021-10-08T21:34:43.676Z,1633728883.676 [Default:CheckIn] Running Loop=2 2021-10-08T21:34:43.676Z,1633728883.676 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T21:34:43.676Z,1633728883.676 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T21:34:45.685Z,1633728885.685 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213444.00,A,3648.16558,N,12147.28441,W,0.564,211.55,081021,,,A*7B 2021-10-08T21:34:45.687Z,1633728885.687 [NAL9602](INFO): GPS fix at 20211008T213444: (36.802760, -121.788073) 2021-10-08T21:34:45.699Z,1633728885.699 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T21:34:45.699Z,1633728885.699 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T21:34:56.585Z,1633728896.585 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20211008T212822/Courier0003.lzma 2021-10-08T21:34:57.588Z,1633728897.588 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0003.lzma.bak 2021-10-08T21:34:57.588Z,1633728897.588 [DataOverHttps](INFO): SBD MOMSN=15991606 2021-10-08T21:35:13.654Z,1633728913.654 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20211008T212822/Express0004.lzma 2021-10-08T21:35:14.655Z,1633728914.655 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0004.lzma.bak 2021-10-08T21:35:14.655Z,1633728914.655 [DataOverHttps](INFO): SBD MOMSN=15991609 2021-10-08T21:35:16.074Z,1633728916.074 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T21:35:16.074Z,1633728916.074 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T21:35:16.074Z,1633728916.074 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T21:35:16.535Z,1633728916.535 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T21:35:16.617Z,1633728916.617 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T21:39:48.151Z,1633729188.151 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T21:40:16.845Z,1633729216.845 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T21:40:16.845Z,1633729216.845 [Default:CheckIn:C.Wait] Stopped 2021-10-08T21:40:16.845Z,1633729216.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T21:40:16.862Z,1633729216.862 [Default:CheckIn:D] Running Loop=1 2021-10-08T21:40:17.251Z,1633729217.251 [Default:CheckIn:D] Stopped 2021-10-08T21:40:17.251Z,1633729217.251 [Default:CheckIn:E] Running Loop=1 2021-10-08T21:40:17.671Z,1633729217.671 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.911429 min 2021-10-08T21:40:17.672Z,1633729217.672 [Default:CheckIn:E] Stopped 2021-10-08T21:40:17.672Z,1633729217.672 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T21:40:17.672Z,1633729217.672 [Default:CheckIn] Stopped 2021-10-08T21:40:17.672Z,1633729217.672 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T21:40:17.672Z,1633729217.672 [Default:CheckIn](INFO): Running loop #3 2021-10-08T21:40:17.672Z,1633729217.672 [Default:CheckIn] Running Loop=3 2021-10-08T21:40:17.672Z,1633729217.672 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T21:40:17.672Z,1633729217.672 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T21:40:19.665Z,1633729219.665 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214018.00,A,3648.16559,N,12147.28277,W,1.147,211.55,081021,,,A*77 2021-10-08T21:40:19.667Z,1633729219.667 [NAL9602](INFO): GPS fix at 20211008T214018: (36.802760, -121.788046) 2021-10-08T21:40:19.698Z,1633729219.698 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T21:40:19.698Z,1633729219.698 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T21:40:30.465Z,1633729230.465 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0006.lzma 2021-10-08T21:40:31.467Z,1633729231.467 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0006.lzma.bak 2021-10-08T21:40:31.467Z,1633729231.467 [DataOverHttps](INFO): SBD MOMSN=15991620 2021-10-08T21:40:47.626Z,1633729247.626 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20211008T212822/Express0007.lzma 2021-10-08T21:40:48.490Z,1633729248.490 [BPC1](ERROR): BPC1B: No match for serial number FFFF in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-10-08T21:40:48.627Z,1633729248.627 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0007.lzma.bak 2021-10-08T21:40:48.628Z,1633729248.628 [DataOverHttps](INFO): SBD MOMSN=15991623 2021-10-08T21:40:50.511Z,1633729250.511 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T21:40:50.511Z,1633729250.511 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T21:40:50.511Z,1633729250.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T21:40:52.115Z,1633729252.115 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T21:45:51.212Z,1633729551.212 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T21:45:51.212Z,1633729551.212 [Default:CheckIn:C.Wait] Stopped 2021-10-08T21:45:51.212Z,1633729551.212 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T21:45:51.213Z,1633729551.213 [Default:CheckIn:D] Running Loop=1 2021-10-08T21:45:51.627Z,1633729551.627 [Default:CheckIn:D] Stopped 2021-10-08T21:45:51.627Z,1633729551.627 [Default:CheckIn:E] Running Loop=1 2021-10-08T21:45:52.023Z,1633729552.023 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.484363 min 2021-10-08T21:45:52.023Z,1633729552.023 [Default:CheckIn:E] Stopped 2021-10-08T21:45:52.023Z,1633729552.023 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T21:45:52.024Z,1633729552.024 [Default:CheckIn] Stopped 2021-10-08T21:45:52.024Z,1633729552.024 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T21:45:52.024Z,1633729552.024 [Default:CheckIn](INFO): Running loop #4 2021-10-08T21:45:52.024Z,1633729552.024 [Default:CheckIn] Running Loop=4 2021-10-08T21:45:52.024Z,1633729552.024 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T21:45:52.024Z,1633729552.024 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T21:45:54.033Z,1633729554.033 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214553.00,A,3648.16665,N,12147.27755,W,0.058,0.00,081021,,,A*77 2021-10-08T21:45:54.035Z,1633729554.035 [NAL9602](INFO): GPS fix at 20211008T214553: (36.802777, -121.787959) 2021-10-08T21:45:54.047Z,1633729554.047 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T21:45:54.047Z,1633729554.047 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T21:46:01.857Z,1633729561.857 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0009.lzma 2021-10-08T21:46:02.859Z,1633729562.859 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0009.lzma.bak 2021-10-08T21:46:02.859Z,1633729562.859 [DataOverHttps](INFO): SBD MOMSN=15991670 2021-10-08T21:46:19.941Z,1633729579.941 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20211008T212822/Express0010.lzma 2021-10-08T21:46:20.943Z,1633729580.943 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0010.lzma.bak 2021-10-08T21:46:20.944Z,1633729580.944 [DataOverHttps](INFO): SBD MOMSN=15991673 2021-10-08T21:46:22.753Z,1633729582.753 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T21:46:22.774Z,1633729582.774 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T21:46:22.774Z,1633729582.774 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T21:46:24.948Z,1633729584.948 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T21:46:25.030Z,1633729585.030 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T21:47:05.193Z,1633729625.193 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2021-10-08T21:47:05.196Z,1633729625.196 [BPC1](INFO): Received data from all battery sticks. 2021-10-08T21:50:56.279Z,1633729856.279 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T21:51:23.365Z,1633729883.365 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T21:51:23.365Z,1633729883.365 [Default:CheckIn:C.Wait] Stopped 2021-10-08T21:51:23.369Z,1633729883.369 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T21:51:23.370Z,1633729883.370 [Default:CheckIn:D] Running Loop=1 2021-10-08T21:51:23.762Z,1633729883.762 [Default:CheckIn:D] Stopped 2021-10-08T21:51:23.762Z,1633729883.762 [Default:CheckIn:E] Running Loop=1 2021-10-08T21:51:24.163Z,1633729884.163 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.019938 min 2021-10-08T21:51:24.163Z,1633729884.163 [Default:CheckIn:E] Stopped 2021-10-08T21:51:24.163Z,1633729884.163 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T21:51:24.163Z,1633729884.163 [Default:CheckIn] Stopped 2021-10-08T21:51:24.163Z,1633729884.163 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T21:51:24.164Z,1633729884.164 [Default:CheckIn](INFO): Running loop #5 2021-10-08T21:51:24.164Z,1633729884.164 [Default:CheckIn] Running Loop=5 2021-10-08T21:51:24.164Z,1633729884.164 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T21:51:24.164Z,1633729884.164 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T21:51:26.174Z,1633729886.174 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215125.00,A,3648.16893,N,12147.28160,W,0.389,0.00,081021,,,A*74 2021-10-08T21:51:26.177Z,1633729886.177 [NAL9602](INFO): GPS fix at 20211008T215125: (36.802816, -121.788027) 2021-10-08T21:51:26.188Z,1633729886.188 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T21:51:26.188Z,1633729886.188 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T21:51:33.513Z,1633729893.513 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20211008T212822/Courier0012.lzma 2021-10-08T21:51:34.516Z,1633729894.516 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0012.lzma.bak 2021-10-08T21:51:34.516Z,1633729894.516 [DataOverHttps](INFO): SBD MOMSN=15991687 2021-10-08T21:51:50.490Z,1633729910.490 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20211008T212822/Express0013.lzma 2021-10-08T21:51:51.491Z,1633729911.491 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0013.lzma.bak 2021-10-08T21:51:51.492Z,1633729911.492 [DataOverHttps](INFO): SBD MOMSN=15991694 2021-10-08T21:51:52.870Z,1633729912.870 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T21:51:52.870Z,1633729912.870 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T21:51:52.870Z,1633729912.870 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T21:51:58.522Z,1633729918.522 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T21:56:53.470Z,1633730213.470 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T21:56:53.470Z,1633730213.470 [Default:CheckIn:C.Wait] Stopped 2021-10-08T21:56:53.470Z,1633730213.470 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T21:56:53.470Z,1633730213.470 [Default:CheckIn:D] Running Loop=1 2021-10-08T21:56:53.884Z,1633730213.884 [Default:CheckIn:D] Stopped 2021-10-08T21:56:53.884Z,1633730213.884 [Default:CheckIn:E] Running Loop=1 2021-10-08T21:56:54.298Z,1633730214.298 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.521973 min 2021-10-08T21:56:54.298Z,1633730214.298 [Default:CheckIn:E] Stopped 2021-10-08T21:56:54.298Z,1633730214.298 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T21:56:54.298Z,1633730214.298 [Default:CheckIn] Stopped 2021-10-08T21:56:54.298Z,1633730214.298 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T21:56:54.299Z,1633730214.299 [Default:CheckIn](INFO): Running loop #6 2021-10-08T21:56:54.299Z,1633730214.299 [Default:CheckIn] Running Loop=6 2021-10-08T21:56:54.299Z,1633730214.299 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T21:56:54.299Z,1633730214.299 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T21:56:56.289Z,1633730216.289 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215655.00,A,3648.16798,N,12147.28660,W,0.233,0.00,081021,,,A*77 2021-10-08T21:56:56.292Z,1633730216.292 [NAL9602](INFO): GPS fix at 20211008T215655: (36.802800, -121.788110) 2021-10-08T21:56:56.303Z,1633730216.303 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T21:56:56.303Z,1633730216.303 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T21:57:03.461Z,1633730223.461 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0015.lzma 2021-10-08T21:57:04.463Z,1633730224.463 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0015.lzma.bak 2021-10-08T21:57:04.463Z,1633730224.463 [DataOverHttps](INFO): SBD MOMSN=15991735 2021-10-08T21:57:20.469Z,1633730240.469 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20211008T212822/Express0016.lzma 2021-10-08T21:57:21.471Z,1633730241.471 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0016.lzma.bak 2021-10-08T21:57:21.472Z,1633730241.472 [DataOverHttps](INFO): SBD MOMSN=15991738 2021-10-08T21:57:22.955Z,1633730242.955 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T21:57:22.955Z,1633730242.955 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T21:57:22.955Z,1633730242.955 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T21:57:26.987Z,1633730246.987 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T21:57:27.066Z,1633730247.066 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T21:59:12.436Z,1633730352.436 [NAL9602](INFO): SBD MO Status=2, MOMSN=30084, MT Status=2, MTMSN=0 2021-10-08T21:59:12.436Z,1633730352.436 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-10-08T22:01:10.028Z,1633730470.028 [NAL9602](INFO): SBD MO Status=2, MOMSN=30084, MT Status=2, MTMSN=0 2021-10-08T22:01:10.029Z,1633730470.029 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-10-08T22:01:58.515Z,1633730518.515 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T22:02:23.606Z,1633730543.606 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:02:23.606Z,1633730543.606 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:02:23.606Z,1633730543.606 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:02:23.607Z,1633730543.607 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:02:23.978Z,1633730543.978 [Default:CheckIn:D] Stopped 2021-10-08T22:02:23.978Z,1633730543.978 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:02:24.375Z,1633730544.375 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.023535 min 2021-10-08T22:02:24.375Z,1633730544.375 [Default:CheckIn:E] Stopped 2021-10-08T22:02:24.375Z,1633730544.375 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:02:24.376Z,1633730544.376 [Default:CheckIn] Stopped 2021-10-08T22:02:24.376Z,1633730544.376 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:02:24.376Z,1633730544.376 [Default:CheckIn](INFO): Running loop #7 2021-10-08T22:02:24.376Z,1633730544.376 [Default:CheckIn] Running Loop=7 2021-10-08T22:02:24.376Z,1633730544.376 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:02:24.376Z,1633730544.376 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:02:26.385Z,1633730546.385 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220225.00,A,3648.16976,N,12147.27890,W,0.078,0.00,081021,,,A*7F 2021-10-08T22:02:26.387Z,1633730546.387 [NAL9602](INFO): GPS fix at 20211008T220225: (36.802829, -121.787982) 2021-10-08T22:02:26.428Z,1633730546.428 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:02:26.428Z,1633730546.428 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:02:34.553Z,1633730554.553 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0018.lzma 2021-10-08T22:02:35.556Z,1633730555.556 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0018.lzma.bak 2021-10-08T22:02:35.556Z,1633730555.556 [DataOverHttps](INFO): SBD MOMSN=15991757 2021-10-08T22:02:54.385Z,1633730574.385 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20211008T212822/Express0019.lzma 2021-10-08T22:02:55.387Z,1633730575.387 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0019.lzma.bak 2021-10-08T22:02:55.388Z,1633730575.388 [DataOverHttps](INFO): SBD MOMSN=15991802 2021-10-08T22:02:57.175Z,1633730577.175 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:02:57.175Z,1633730577.175 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:02:57.176Z,1633730577.176 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T22:02:58.363Z,1633730578.363 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T22:07:57.748Z,1633730877.748 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:07:57.748Z,1633730877.748 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:07:57.748Z,1633730877.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:07:57.748Z,1633730877.748 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:07:58.169Z,1633730878.169 [Default:CheckIn:D] Stopped 2021-10-08T22:07:58.169Z,1633730878.169 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:07:58.545Z,1633730878.545 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.593392 min 2021-10-08T22:07:58.546Z,1633730878.546 [Default:CheckIn:E] Stopped 2021-10-08T22:07:58.546Z,1633730878.546 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:07:58.546Z,1633730878.546 [Default:CheckIn] Stopped 2021-10-08T22:07:58.546Z,1633730878.546 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:07:58.546Z,1633730878.546 [Default:CheckIn](INFO): Running loop #8 2021-10-08T22:07:58.546Z,1633730878.546 [Default:CheckIn] Running Loop=8 2021-10-08T22:07:58.546Z,1633730878.546 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:07:58.546Z,1633730878.546 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:08:00.558Z,1633730880.558 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220759.00,A,3648.16768,N,12147.28206,W,0.330,0.00,081021,,,A*75 2021-10-08T22:08:00.560Z,1633730880.560 [NAL9602](INFO): GPS fix at 20211008T220759: (36.802795, -121.788034) 2021-10-08T22:08:00.571Z,1633730880.571 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:08:00.571Z,1633730880.571 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:08:08.369Z,1633730888.369 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0021.lzma 2021-10-08T22:08:09.371Z,1633730889.371 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0021.lzma.bak 2021-10-08T22:08:09.371Z,1633730889.371 [DataOverHttps](INFO): SBD MOMSN=15991806 2021-10-08T22:08:28.237Z,1633730908.237 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20211008T212822/Express0022.lzma 2021-10-08T22:08:29.239Z,1633730909.239 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0022.lzma.bak 2021-10-08T22:08:29.240Z,1633730909.240 [DataOverHttps](INFO): SBD MOMSN=15991811 2021-10-08T22:08:30.971Z,1633730910.971 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:08:30.971Z,1633730910.971 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:08:30.971Z,1633730910.971 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T22:08:31.328Z,1633730911.328 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T22:08:31.402Z,1633730911.402 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T22:12:06.663Z,1633731126.663 [NAL9602](INFO): SBD MO Status=2, MOMSN=30084, MT Status=2, MTMSN=0 2021-10-08T22:12:06.663Z,1633731126.663 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-10-08T22:13:02.418Z,1633731182.418 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T22:13:31.509Z,1633731211.509 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:13:31.510Z,1633731211.510 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:13:31.510Z,1633731211.510 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:13:31.512Z,1633731211.512 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:13:31.927Z,1633731211.927 [Default:CheckIn:D] Stopped 2021-10-08T22:13:31.928Z,1633731211.928 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:13:32.339Z,1633731212.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.156034 min 2021-10-08T22:13:32.339Z,1633731212.339 [Default:CheckIn:E] Stopped 2021-10-08T22:13:32.339Z,1633731212.339 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:13:32.339Z,1633731212.339 [Default:CheckIn] Stopped 2021-10-08T22:13:32.340Z,1633731212.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:13:32.340Z,1633731212.340 [Default:CheckIn](INFO): Running loop #9 2021-10-08T22:13:32.340Z,1633731212.340 [Default:CheckIn] Running Loop=9 2021-10-08T22:13:32.341Z,1633731212.341 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:13:32.341Z,1633731212.341 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:13:34.333Z,1633731214.333 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221333.00,A,3648.16810,N,12147.28116,W,0.369,0.00,081021,,,A*72 2021-10-08T22:13:34.336Z,1633731214.336 [NAL9602](INFO): GPS fix at 20211008T221333: (36.802802, -121.788019) 2021-10-08T22:13:34.364Z,1633731214.364 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:13:34.364Z,1633731214.364 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:13:41.893Z,1633731221.893 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20211008T212822/Courier0024.lzma 2021-10-08T22:13:42.895Z,1633731222.895 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0024.lzma.bak 2021-10-08T22:13:42.896Z,1633731222.896 [DataOverHttps](INFO): SBD MOMSN=15991872 2021-10-08T22:13:58.741Z,1633731238.741 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20211008T212822/Express0025.lzma 2021-10-08T22:13:59.743Z,1633731239.743 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0025.lzma.bak 2021-10-08T22:13:59.744Z,1633731239.744 [DataOverHttps](INFO): SBD MOMSN=15991875 2021-10-08T22:14:01.406Z,1633731241.406 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:14:01.406Z,1633731241.406 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:14:01.406Z,1633731241.406 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T22:14:06.647Z,1633731246.647 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T22:19:02.029Z,1633731542.029 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:19:02.030Z,1633731542.030 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:19:02.030Z,1633731542.030 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:19:02.030Z,1633731542.030 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:19:02.441Z,1633731542.441 [Default:CheckIn:D] Stopped 2021-10-08T22:19:02.441Z,1633731542.441 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:19:02.829Z,1633731542.829 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.664596 min 2021-10-08T22:19:02.829Z,1633731542.829 [Default:CheckIn:E] Stopped 2021-10-08T22:19:02.830Z,1633731542.830 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:19:02.830Z,1633731542.830 [Default:CheckIn] Stopped 2021-10-08T22:19:02.830Z,1633731542.830 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:19:02.830Z,1633731542.830 [Default:CheckIn](INFO): Running loop #10 2021-10-08T22:19:02.830Z,1633731542.830 [Default:CheckIn] Running Loop=10 2021-10-08T22:19:02.830Z,1633731542.830 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:19:02.830Z,1633731542.830 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:19:04.843Z,1633731544.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221904.00,A,3648.16761,N,12147.27531,W,0.855,0.00,081021,,,A*7F 2021-10-08T22:19:04.846Z,1633731544.846 [NAL9602](INFO): GPS fix at 20211008T221904: (36.802793, -121.787922) 2021-10-08T22:19:04.857Z,1633731544.857 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:19:04.857Z,1633731544.857 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:19:12.757Z,1633731552.757 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20211008T212822/Courier0027.lzma 2021-10-08T22:19:13.759Z,1633731553.759 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0027.lzma.bak 2021-10-08T22:19:13.760Z,1633731553.760 [DataOverHttps](INFO): SBD MOMSN=15991892 2021-10-08T22:19:29.773Z,1633731569.773 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20211008T212822/Express0028.lzma 2021-10-08T22:19:30.775Z,1633731570.775 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0028.lzma.bak 2021-10-08T22:19:30.776Z,1633731570.776 [DataOverHttps](INFO): SBD MOMSN=15991895 2021-10-08T22:19:32.323Z,1633731572.323 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:19:32.323Z,1633731572.323 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:19:32.323Z,1633731572.323 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T22:19:35.543Z,1633731575.543 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T22:19:35.626Z,1633731575.626 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T22:24:07.435Z,1633731847.435 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T22:24:32.905Z,1633731872.905 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:24:32.905Z,1633731872.905 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:24:32.905Z,1633731872.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:24:32.906Z,1633731872.906 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:24:33.311Z,1633731873.311 [Default:CheckIn:D] Stopped 2021-10-08T22:24:33.311Z,1633731873.311 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:24:33.730Z,1633731873.730 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.179097 min 2021-10-08T22:24:33.730Z,1633731873.730 [Default:CheckIn:E] Stopped 2021-10-08T22:24:33.730Z,1633731873.730 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:24:33.730Z,1633731873.730 [Default:CheckIn] Stopped 2021-10-08T22:24:33.730Z,1633731873.730 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:24:33.731Z,1633731873.731 [Default:CheckIn](INFO): Running loop #11 2021-10-08T22:24:33.731Z,1633731873.731 [Default:CheckIn] Running Loop=11 2021-10-08T22:24:33.731Z,1633731873.731 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:24:33.731Z,1633731873.731 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:24:35.719Z,1633731875.719 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222435.00,A,3648.16626,N,12147.28002,W,0.758,105.92,081021,,,A*76 2021-10-08T22:24:35.722Z,1633731875.722 [NAL9602](INFO): GPS fix at 20211008T222435: (36.802771, -121.788000) 2021-10-08T22:24:35.738Z,1633731875.738 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:24:35.739Z,1633731875.739 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:24:43.422Z,1633731883.422 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0030.lzma 2021-10-08T22:24:44.423Z,1633731884.423 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0030.lzma.bak 2021-10-08T22:24:44.423Z,1633731884.423 [DataOverHttps](INFO): SBD MOMSN=15991936 2021-10-08T22:25:03.262Z,1633731903.262 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20211008T212822/Express0031.lzma 2021-10-08T22:25:04.263Z,1633731904.263 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0031.lzma.bak 2021-10-08T22:25:04.263Z,1633731904.263 [DataOverHttps](INFO): SBD MOMSN=15991939 2021-10-08T22:25:05.665Z,1633731905.665 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:25:05.666Z,1633731905.666 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:25:05.666Z,1633731905.666 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T22:25:08.491Z,1633731908.491 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T22:30:06.271Z,1633732206.271 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:30:06.271Z,1633732206.271 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:30:06.271Z,1633732206.271 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:30:06.272Z,1633732206.272 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:30:06.666Z,1633732206.666 [Default:CheckIn:D] Stopped 2021-10-08T22:30:06.666Z,1633732206.666 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:30:07.073Z,1633732207.073 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.735014 min 2021-10-08T22:30:07.073Z,1633732207.073 [Default:CheckIn:E] Stopped 2021-10-08T22:30:07.074Z,1633732207.074 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:30:07.074Z,1633732207.074 [Default:CheckIn] Stopped 2021-10-08T22:30:07.074Z,1633732207.074 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:30:07.074Z,1633732207.074 [Default:CheckIn](INFO): Running loop #12 2021-10-08T22:30:07.074Z,1633732207.074 [Default:CheckIn] Running Loop=12 2021-10-08T22:30:07.074Z,1633732207.074 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:30:07.074Z,1633732207.074 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:30:09.085Z,1633732209.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223008.00,A,3648.16715,N,12147.27742,W,0.117,105.92,081021,,,A*7D 2021-10-08T22:30:09.087Z,1633732209.087 [NAL9602](INFO): GPS fix at 20211008T223008: (36.802786, -121.787957) 2021-10-08T22:30:09.098Z,1633732209.098 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:30:09.098Z,1633732209.098 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:30:16.305Z,1633732216.305 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20211008T212822/Courier0033.lzma 2021-10-08T22:30:17.308Z,1633732217.308 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0033.lzma.bak 2021-10-08T22:30:17.308Z,1633732217.308 [DataOverHttps](INFO): SBD MOMSN=15991952 2021-10-08T22:30:37.909Z,1633732237.909 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20211008T212822/Express0034.lzma 2021-10-08T22:30:38.912Z,1633732238.912 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0034.lzma.bak 2021-10-08T22:30:38.912Z,1633732238.912 [DataOverHttps](INFO): SBD MOMSN=15991957 2021-10-08T22:30:39.848Z,1633732239.848 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T22:30:39.926Z,1633732239.926 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T22:30:40.679Z,1633732240.679 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:30:40.680Z,1633732240.680 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:30:40.680Z,1633732240.680 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T22:31:00.057Z,1633732260.057 [NAL9602](INFO): SBD MO Status=2, MOMSN=30084, MT Status=2, MTMSN=0 2021-10-08T22:31:00.057Z,1633732260.057 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-10-08T22:31:17.020Z,1633732277.020 [NAL9602](INFO): SBD MO Status=2, MOMSN=30084, MT Status=2, MTMSN=0 2021-10-08T22:31:17.021Z,1633732277.021 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-10-08T22:35:11.499Z,1633732511.499 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T22:35:41.403Z,1633732541.403 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:35:41.403Z,1633732541.403 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:35:41.403Z,1633732541.403 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:35:41.403Z,1633732541.403 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:35:41.813Z,1633732541.813 [Default:CheckIn:D] Stopped 2021-10-08T22:35:41.813Z,1633732541.813 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:35:42.195Z,1633732542.195 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T22:35:42.220Z,1633732542.220 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.320789 min 2021-10-08T22:35:42.220Z,1633732542.220 [Default:CheckIn:E] Stopped 2021-10-08T22:35:42.221Z,1633732542.221 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:35:42.221Z,1633732542.221 [Default:CheckIn] Stopped 2021-10-08T22:35:42.221Z,1633732542.221 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:35:42.221Z,1633732542.221 [Default:CheckIn](INFO): Running loop #13 2021-10-08T22:35:42.221Z,1633732542.221 [Default:CheckIn] Running Loop=13 2021-10-08T22:35:42.221Z,1633732542.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:35:42.226Z,1633732542.226 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:35:44.225Z,1633732544.225 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223543.00,A,3648.16802,N,12147.27926,W,0.058,105.92,081021,,,A*78 2021-10-08T22:35:44.227Z,1633732544.227 [NAL9602](INFO): GPS fix at 20211008T223543: (36.802800, -121.787988) 2021-10-08T22:35:44.255Z,1633732544.255 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:35:44.255Z,1633732544.255 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:35:51.573Z,1633732551.573 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0036.lzma 2021-10-08T22:35:52.575Z,1633732552.575 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0036.lzma.bak 2021-10-08T22:35:52.575Z,1633732552.575 [DataOverHttps](INFO): SBD MOMSN=15992006 2021-10-08T22:36:08.549Z,1633732568.549 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20211008T212822/Express0037.lzma 2021-10-08T22:36:09.552Z,1633732569.552 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0037.lzma.bak 2021-10-08T22:36:09.552Z,1633732569.552 [DataOverHttps](INFO): SBD MOMSN=15992009 2021-10-08T22:36:10.899Z,1633732570.899 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:36:10.899Z,1633732570.899 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:36:10.899Z,1633732570.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T22:36:14.930Z,1633732574.930 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T22:36:15.014Z,1633732575.014 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T22:36:45.229Z,1633732605.229 [NAL9602](INFO): SBD MO Status=2, MOMSN=30084, MT Status=2, MTMSN=0 2021-10-08T22:36:45.229Z,1633732605.229 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-10-08T22:39:40.644Z,1633732780.644 [NAL9602](INFO): SBD MO Status=2, MOMSN=30084, MT Status=2, MTMSN=0 2021-10-08T22:39:40.644Z,1633732780.644 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-10-08T22:40:46.551Z,1633732846.551 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T22:41:11.671Z,1633732871.671 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:41:11.671Z,1633732871.671 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:41:11.672Z,1633732871.672 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:41:11.672Z,1633732871.672 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:41:12.060Z,1633732872.060 [Default:CheckIn:D] Stopped 2021-10-08T22:41:12.061Z,1633732872.061 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:41:12.469Z,1633732872.469 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.824919 min 2021-10-08T22:41:12.469Z,1633732872.469 [Default:CheckIn:E] Stopped 2021-10-08T22:41:12.469Z,1633732872.469 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:41:12.469Z,1633732872.469 [Default:CheckIn] Stopped 2021-10-08T22:41:12.469Z,1633732872.469 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:41:12.470Z,1633732872.470 [Default:CheckIn](INFO): Running loop #14 2021-10-08T22:41:12.470Z,1633732872.470 [Default:CheckIn] Running Loop=14 2021-10-08T22:41:12.470Z,1633732872.470 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:41:12.470Z,1633732872.470 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:41:14.481Z,1633732874.481 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224113.00,A,3648.15485,N,12147.27473,W,0.117,0.00,081021,,,A*76 2021-10-08T22:41:14.484Z,1633732874.484 [NAL9602](INFO): GPS fix at 20211008T224113: (36.802581, -121.787912) 2021-10-08T22:41:14.495Z,1633732874.495 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:41:14.495Z,1633732874.495 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:41:22.565Z,1633732882.565 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0039.lzma 2021-10-08T22:41:23.567Z,1633732883.567 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0039.lzma.bak 2021-10-08T22:41:23.568Z,1633732883.568 [DataOverHttps](INFO): SBD MOMSN=15992022 2021-10-08T22:41:39.557Z,1633732899.557 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20211008T212822/Express0040.lzma 2021-10-08T22:41:40.559Z,1633732900.559 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0040.lzma.bak 2021-10-08T22:41:40.560Z,1633732900.560 [DataOverHttps](INFO): SBD MOMSN=15992025 2021-10-08T22:41:41.975Z,1633732901.975 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:41:41.975Z,1633732901.975 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:41:41.976Z,1633732901.976 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T22:41:46.410Z,1633732906.410 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T22:46:42.702Z,1633733202.702 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:46:42.702Z,1633733202.702 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:46:42.702Z,1633733202.702 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:46:42.702Z,1633733202.702 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:46:43.113Z,1633733203.113 [Default:CheckIn:D] Stopped 2021-10-08T22:46:43.113Z,1633733203.113 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:46:43.522Z,1633733203.522 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.342464 min 2021-10-08T22:46:43.522Z,1633733203.522 [Default:CheckIn:E] Stopped 2021-10-08T22:46:43.523Z,1633733203.523 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:46:43.523Z,1633733203.523 [Default:CheckIn] Stopped 2021-10-08T22:46:43.523Z,1633733203.523 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:46:43.523Z,1633733203.523 [Default:CheckIn](INFO): Running loop #15 2021-10-08T22:46:43.523Z,1633733203.523 [Default:CheckIn] Running Loop=15 2021-10-08T22:46:43.523Z,1633733203.523 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:46:43.523Z,1633733203.523 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:46:45.520Z,1633733205.520 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224644.00,A,3648.17001,N,12147.28056,W,0.136,0.00,081021,,,A*76 2021-10-08T22:46:45.523Z,1633733205.523 [NAL9602](INFO): GPS fix at 20211008T224644: (36.802833, -121.788009) 2021-10-08T22:46:45.534Z,1633733205.534 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:46:45.534Z,1633733205.534 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:46:56.229Z,1633733216.229 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20211008T212822/Courier0042.lzma 2021-10-08T22:46:57.232Z,1633733217.232 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0042.lzma.bak 2021-10-08T22:46:57.232Z,1633733217.232 [DataOverHttps](INFO): SBD MOMSN=15992069 2021-10-08T22:47:13.225Z,1633733233.225 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20211008T212822/Express0043.lzma 2021-10-08T22:47:14.227Z,1633733234.227 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0043.lzma.bak 2021-10-08T22:47:14.228Z,1633733234.228 [DataOverHttps](INFO): SBD MOMSN=15992072 2021-10-08T22:47:15.891Z,1633733235.891 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:47:15.891Z,1633733235.891 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:47:15.891Z,1633733235.891 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T22:47:16.283Z,1633733236.283 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T22:47:16.361Z,1633733236.361 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T22:47:48.208Z,1633733268.208 [NAL9602](INFO): SBD MO Status=2, MOMSN=30084, MT Status=2, MTMSN=0 2021-10-08T22:47:48.208Z,1633733268.208 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-10-08T22:50:12.622Z,1633733412.622 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2021-10-08T22:51:47.487Z,1633733507.487 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T22:52:16.572Z,1633733536.572 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:52:16.572Z,1633733536.572 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:52:16.572Z,1633733536.572 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:52:16.573Z,1633733536.573 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:52:16.971Z,1633733536.971 [Default:CheckIn:D] Stopped 2021-10-08T22:52:16.972Z,1633733536.972 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:52:17.400Z,1633733537.400 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.906771 min 2021-10-08T22:52:17.400Z,1633733537.400 [Default:CheckIn:E] Stopped 2021-10-08T22:52:17.400Z,1633733537.400 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:52:17.400Z,1633733537.400 [Default:CheckIn] Stopped 2021-10-08T22:52:17.400Z,1633733537.400 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:52:17.400Z,1633733537.400 [Default:CheckIn](INFO): Running loop #16 2021-10-08T22:52:17.400Z,1633733537.400 [Default:CheckIn] Running Loop=16 2021-10-08T22:52:17.400Z,1633733537.400 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:52:17.401Z,1633733537.401 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:52:19.385Z,1633733539.385 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225218.00,A,3648.16621,N,12147.27926,W,0.914,0.00,081021,,,A*76 2021-10-08T22:52:19.387Z,1633733539.387 [NAL9602](INFO): GPS fix at 20211008T225218: (36.802770, -121.787988) 2021-10-08T22:52:19.415Z,1633733539.415 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:52:19.415Z,1633733539.415 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:52:30.461Z,1633733550.461 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0045.lzma 2021-10-08T22:52:31.463Z,1633733551.463 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0045.lzma.bak 2021-10-08T22:52:31.464Z,1633733551.464 [DataOverHttps](INFO): SBD MOMSN=15992086 2021-10-08T22:52:47.418Z,1633733567.418 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20211008T212822/Express0046.lzma 2021-10-08T22:52:48.411Z,1633733568.411 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0046.lzma.bak 2021-10-08T22:52:48.412Z,1633733568.412 [DataOverHttps](INFO): SBD MOMSN=15992089 2021-10-08T22:52:50.207Z,1633733570.207 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:52:50.207Z,1633733570.207 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:52:50.207Z,1633733570.207 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T22:52:51.394Z,1633733571.394 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T22:54:32.483Z,1633733672.483 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2021-10-08T22:54:32.487Z,1633733672.487 [BPC1](INFO): Received data from all battery sticks. 2021-10-08T22:57:50.872Z,1633733870.872 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T22:57:50.873Z,1633733870.873 [Default:CheckIn:C.Wait] Stopped 2021-10-08T22:57:50.873Z,1633733870.873 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T22:57:50.873Z,1633733870.873 [Default:CheckIn:D] Running Loop=1 2021-10-08T22:57:51.273Z,1633733871.273 [Default:CheckIn:D] Stopped 2021-10-08T22:57:51.273Z,1633733871.273 [Default:CheckIn:E] Running Loop=1 2021-10-08T22:57:51.686Z,1633733871.686 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.478459 min 2021-10-08T22:57:51.686Z,1633733871.686 [Default:CheckIn:E] Stopped 2021-10-08T22:57:51.686Z,1633733871.686 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T22:57:51.686Z,1633733871.686 [Default:CheckIn] Stopped 2021-10-08T22:57:51.686Z,1633733871.686 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T22:57:51.686Z,1633733871.686 [Default:CheckIn](INFO): Running loop #17 2021-10-08T22:57:51.687Z,1633733871.687 [Default:CheckIn] Running Loop=17 2021-10-08T22:57:51.687Z,1633733871.687 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T22:57:51.687Z,1633733871.687 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T22:57:53.689Z,1633733873.689 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225752.00,A,3648.16697,N,12147.27884,W,0.194,277.88,081021,,,A*7B 2021-10-08T22:57:53.691Z,1633733873.691 [NAL9602](INFO): GPS fix at 20211008T225752: (36.802783, -121.787981) 2021-10-08T22:57:53.731Z,1633733873.731 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T22:57:53.731Z,1633733873.731 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T22:58:01.641Z,1633733881.641 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20211008T212822/Courier0048.lzma 2021-10-08T22:58:02.643Z,1633733882.643 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0048.lzma.bak 2021-10-08T22:58:02.644Z,1633733882.644 [DataOverHttps](INFO): SBD MOMSN=15992135 2021-10-08T22:58:20.285Z,1633733900.285 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20211008T212822/Express0049.lzma 2021-10-08T22:58:21.288Z,1633733901.288 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0049.lzma.bak 2021-10-08T22:58:21.288Z,1633733901.288 [DataOverHttps](INFO): SBD MOMSN=15992138 2021-10-08T22:58:23.218Z,1633733903.218 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T22:58:23.218Z,1633733903.218 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T22:58:23.218Z,1633733903.218 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T23:02:56.332Z,1633734176.332 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T23:03:23.812Z,1633734203.812 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T23:03:23.812Z,1633734203.812 [Default:CheckIn:C.Wait] Stopped 2021-10-08T23:03:23.812Z,1633734203.812 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T23:03:23.812Z,1633734203.812 [Default:CheckIn:D] Running Loop=1 2021-10-08T23:03:24.228Z,1633734204.228 [Default:CheckIn:D] Stopped 2021-10-08T23:03:24.228Z,1633734204.228 [Default:CheckIn:E] Running Loop=1 2021-10-08T23:03:24.623Z,1633734204.623 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.027702 min 2021-10-08T23:03:24.623Z,1633734204.623 [Default:CheckIn:E] Stopped 2021-10-08T23:03:24.623Z,1633734204.623 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T23:03:24.623Z,1633734204.623 [Default:CheckIn] Stopped 2021-10-08T23:03:24.623Z,1633734204.623 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T23:03:24.623Z,1633734204.623 [Default:CheckIn](INFO): Running loop #18 2021-10-08T23:03:24.624Z,1633734204.624 [Default:CheckIn] Running Loop=18 2021-10-08T23:03:24.624Z,1633734204.624 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T23:03:24.624Z,1633734204.624 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T23:03:26.626Z,1633734206.626 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230325.00,A,3648.16451,N,12147.28103,W,0.156,277.88,081021,,,A*74 2021-10-08T23:03:26.629Z,1633734206.629 [NAL9602](INFO): GPS fix at 20211008T230325: (36.802742, -121.788017) 2021-10-08T23:03:26.639Z,1633734206.639 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T23:03:26.639Z,1633734206.639 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T23:03:33.833Z,1633734213.833 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0051.lzma 2021-10-08T23:03:34.835Z,1633734214.835 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0051.lzma.bak 2021-10-08T23:03:34.836Z,1633734214.836 [DataOverHttps](INFO): SBD MOMSN=15992154 2021-10-08T23:03:50.885Z,1633734230.885 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20211008T212822/Express0052.lzma 2021-10-08T23:03:51.887Z,1633734231.887 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0052.lzma.bak 2021-10-08T23:03:51.888Z,1633734231.888 [DataOverHttps](INFO): SBD MOMSN=15992157 2021-10-08T23:03:53.301Z,1633734233.301 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T23:03:53.301Z,1633734233.301 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T23:03:53.301Z,1633734233.301 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T23:03:59.351Z,1633734239.351 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T23:08:53.913Z,1633734533.913 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T23:08:53.914Z,1633734533.914 [Default:CheckIn:C.Wait] Stopped 2021-10-08T23:08:53.914Z,1633734533.914 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T23:08:53.914Z,1633734533.914 [Default:CheckIn:D] Running Loop=1 2021-10-08T23:08:54.289Z,1633734534.289 [Default:CheckIn:D] Stopped 2021-10-08T23:08:54.290Z,1633734534.290 [Default:CheckIn:E] Running Loop=1 2021-10-08T23:08:54.718Z,1633734534.718 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.528735 min 2021-10-08T23:08:54.718Z,1633734534.718 [Default:CheckIn:E] Stopped 2021-10-08T23:08:54.719Z,1633734534.719 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T23:08:54.719Z,1633734534.719 [Default:CheckIn] Stopped 2021-10-08T23:08:54.719Z,1633734534.719 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T23:08:54.719Z,1633734534.719 [Default:CheckIn](INFO): Running loop #19 2021-10-08T23:08:54.719Z,1633734534.719 [Default:CheckIn] Running Loop=19 2021-10-08T23:08:54.719Z,1633734534.719 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T23:08:54.719Z,1633734534.719 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T23:08:56.709Z,1633734536.709 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230856.00,A,3648.16934,N,12147.28308,W,0.019,277.88,081021,,,A*76 2021-10-08T23:08:56.711Z,1633734536.711 [NAL9602](INFO): GPS fix at 20211008T230856: (36.802822, -121.788051) 2021-10-08T23:08:56.745Z,1633734536.745 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T23:08:56.745Z,1633734536.745 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T23:09:04.153Z,1633734544.153 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20211008T212822/Courier0054.lzma 2021-10-08T23:09:05.155Z,1633734545.155 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0054.lzma.bak 2021-10-08T23:09:05.156Z,1633734545.156 [DataOverHttps](INFO): SBD MOMSN=15992205 2021-10-08T23:09:21.065Z,1633734561.065 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20211008T212822/Express0055.lzma 2021-10-08T23:09:22.068Z,1633734562.068 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0055.lzma.bak 2021-10-08T23:09:22.068Z,1633734562.068 [DataOverHttps](INFO): SBD MOMSN=15992208 2021-10-08T23:09:23.388Z,1633734563.388 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T23:09:23.388Z,1633734563.388 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T23:09:23.388Z,1633734563.388 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T23:09:27.407Z,1633734567.407 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T23:09:27.482Z,1633734567.482 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T23:13:59.315Z,1633734839.315 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-10-08T23:14:23.972Z,1633734863.972 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T23:14:23.972Z,1633734863.972 [Default:CheckIn:C.Wait] Stopped 2021-10-08T23:14:23.972Z,1633734863.972 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T23:14:23.973Z,1633734863.973 [Default:CheckIn:D] Running Loop=1 2021-10-08T23:14:24.365Z,1633734864.365 [Default:CheckIn:D] Stopped 2021-10-08T23:14:24.365Z,1633734864.365 [Default:CheckIn:E] Running Loop=1 2021-10-08T23:14:24.773Z,1633734864.773 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.029989 min 2021-10-08T23:14:24.773Z,1633734864.773 [Default:CheckIn:E] Stopped 2021-10-08T23:14:24.773Z,1633734864.773 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T23:14:24.773Z,1633734864.773 [Default:CheckIn] Stopped 2021-10-08T23:14:24.773Z,1633734864.773 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T23:14:24.774Z,1633734864.774 [Default:CheckIn](INFO): Running loop #20 2021-10-08T23:14:24.774Z,1633734864.774 [Default:CheckIn] Running Loop=20 2021-10-08T23:14:24.774Z,1633734864.774 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T23:14:24.774Z,1633734864.774 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T23:14:26.781Z,1633734866.781 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231426.00,A,3648.17290,N,12147.28569,W,0.836,308.54,081021,,,A*74 2021-10-08T23:14:26.784Z,1633734866.784 [NAL9602](INFO): GPS fix at 20211008T231426: (36.802882, -121.788095) 2021-10-08T23:14:26.795Z,1633734866.795 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T23:14:26.795Z,1633734866.795 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-10-08T23:14:34.265Z,1633734874.265 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211008T212822/Courier0057.lzma 2021-10-08T23:14:35.268Z,1633734875.268 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0057.lzma.bak 2021-10-08T23:14:35.268Z,1633734875.268 [DataOverHttps](INFO): SBD MOMSN=15992222 2021-10-08T23:14:53.977Z,1633734893.977 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20211008T212822/Express0058.lzma 2021-10-08T23:14:54.980Z,1633734894.980 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0058.lzma.bak 2021-10-08T23:14:54.980Z,1633734894.980 [DataOverHttps](INFO): SBD MOMSN=15992225 2021-10-08T23:14:56.762Z,1633734896.762 [Default:CheckIn:Read_Iridium] Stopped 2021-10-08T23:14:56.763Z,1633734896.763 [Default:CheckIn:C.Wait] Running Loop=1 2021-10-08T23:14:56.763Z,1633734896.763 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-10-08T23:14:59.551Z,1633734899.551 [NAL9602](INFO): Not Powering down - fast GPS 2021-10-08T23:19:08.422Z,1633735148.422 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2021-10-08T23:19:08.423Z,1633735148.423 [DropWeight] Hardware Fault, FailCount= 1 2021-10-08T23:19:08.423Z,1633735148.423 [DropWeight](ERROR): Hardware Fault 2021-10-08T23:19:08.446Z,1633735148.446 [CommandExec](FAULT): Scheduling is paused 2021-10-08T23:19:08.447Z,1633735148.447 [CBIT](INFO): Critical error at 20211008T231908 2021-10-08T23:19:08.449Z,1633735148.449 [CBIT](ERROR): Hardware Fault in component: DropWeight 2021-10-08T23:19:08.450Z,1633735148.450 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2021-10-08T23:19:08.856Z,1633735148.856 [CBIT](INFO): Critical error at 20211008T231908 2021-10-08T23:19:57.329Z,1633735197.329 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-10-08T23:19:57.329Z,1633735197.329 [Default:CheckIn:C.Wait] Stopped 2021-10-08T23:19:57.329Z,1633735197.329 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-10-08T23:19:57.330Z,1633735197.330 [Default:CheckIn:D] Running Loop=1 2021-10-08T23:19:57.740Z,1633735197.740 [Default:CheckIn:D] Stopped 2021-10-08T23:19:57.740Z,1633735197.740 [Default:CheckIn:E] Running Loop=1 2021-10-08T23:19:58.131Z,1633735198.131 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.586247 min 2021-10-08T23:19:58.131Z,1633735198.131 [Default:CheckIn:E] Stopped 2021-10-08T23:19:58.132Z,1633735198.132 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-10-08T23:19:58.132Z,1633735198.132 [Default:CheckIn] Stopped 2021-10-08T23:19:58.132Z,1633735198.132 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T23:19:58.132Z,1633735198.132 [Default:CheckIn](INFO): Running loop #21 2021-10-08T23:19:58.132Z,1633735198.132 [Default:CheckIn] Running Loop=21 2021-10-08T23:19:58.132Z,1633735198.132 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-10-08T23:19:58.132Z,1633735198.132 [Default:CheckIn:Read_GPS] Running Loop=1 2021-10-08T23:20:30.843Z,1633735230.843 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-10-08T23:20:30.922Z,1633735230.922 [NAL9602](ERROR): received: +CSQ:0 OK084, 2, 0, 0, 0 OK 2021-10-08T23:21:43.086Z,1633735303.086 [CommandExec](IMPORTANT): got command quit 2021-10-08T23:21:44.094Z,1633735304.094 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2021-10-08T23:21:44.094Z,1633735304.094 [CommandExec](INFO): Uninitializing the command executive. 2021-10-08T23:21:44.094Z,1633735304.094 [CommandExec](INFO): Uninitializing the command scheduler. 2021-10-08T23:21:44.094Z,1633735304.094 [CommandExec ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:44.113Z,1633735304.113 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-10-08T23:21:44.114Z,1633735304.114 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-10-08T23:21:44.114Z,1633735304.114 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:44.115Z,1633735304.115 [NavChartDb](INFO): Join timeout helper Thread ID is 3826 2021-10-08T23:21:44.234Z,1633735304.234 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-10-08T23:21:44.234Z,1633735304.234 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:44.246Z,1633735304.246 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-10-08T23:21:44.246Z,1633735304.246 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:44.246Z,1633735304.246 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3827 2021-10-08T23:21:44.382Z,1633735304.382 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-10-08T23:21:44.382Z,1633735304.382 [WetLabsBB2FL](INFO): Powering down 2021-10-08T23:21:44.383Z,1633735304.383 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:44.394Z,1633735304.394 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-10-08T23:21:44.394Z,1633735304.394 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:44.394Z,1633735304.394 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3828 2021-10-08T23:21:45.354Z,1633735305.354 [CTD_Seabird](INFO): Powering down 2021-10-08T23:21:45.366Z,1633735305.366 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-10-08T23:21:45.366Z,1633735305.366 [CTD_Seabird](INFO): Powering down 2021-10-08T23:21:45.382Z,1633735305.382 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:45.402Z,1633735305.402 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-10-08T23:21:45.402Z,1633735305.402 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:45.402Z,1633735305.402 [Radio_Surface](INFO): Join timeout helper Thread ID is 3829 2021-10-08T23:21:45.798Z,1633735305.798 [Radio_Surface](INFO): Powering down 2021-10-08T23:21:45.799Z,1633735305.799 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-10-08T23:21:45.799Z,1633735305.799 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:45.814Z,1633735305.814 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-10-08T23:21:45.814Z,1633735305.814 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:45.814Z,1633735305.814 [Onboard](INFO): Join timeout helper Thread ID is 3830 2021-10-08T23:21:45.902Z,1633735305.902 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2021-10-08T23:21:46.418Z,1633735306.418 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-10-08T23:21:46.418Z,1633735306.418 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:46.426Z,1633735306.426 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-10-08T23:21:46.427Z,1633735306.427 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:46.428Z,1633735306.428 [DataOverHttps](INFO): Join timeout helper Thread ID is 3831 2021-10-08T23:21:46.906Z,1633735306.906 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-10-08T23:21:46.906Z,1633735306.906 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:46.914Z,1633735306.914 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-10-08T23:21:46.915Z,1633735306.915 [logger ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:46.915Z,1633735306.915 [logger](INFO): Join timeout helper Thread ID is 3832 2021-10-08T23:21:46.942Z,1633735306.942 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-10-08T23:21:46.942Z,1633735306.942 [logger ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:46.955Z,1633735306.955 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-10-08T23:21:46.955Z,1633735306.955 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:46.955Z,1633735306.955 [CommandLine](INFO): Join timeout helper Thread ID is 3833 2021-10-08T23:21:47.050Z,1633735307.050 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-10-08T23:21:47.050Z,1633735307.050 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:47.066Z,1633735307.066 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2021-10-08T23:21:47.066Z,1633735307.066 [CommandExec ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:47.067Z,1633735307.067 [CommandExec](INFO): Join timeout helper Thread ID is 3834 2021-10-08T23:21:47.068Z,1633735307.068 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-10-08T23:21:47.068Z,1633735307.068 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:47.068Z,1633735307.068 [controlThread](INFO): Join timeout helper Thread ID is 3835 2021-10-08T23:21:47.182Z,1633735307.182 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-10-08T23:21:47.182Z,1633735307.182 [controlThread](DEBUG): Uninitializing ControlThread 2021-10-08T23:21:47.182Z,1633735307.182 [AHRS_M2](INFO): Powering down 2021-10-08T23:21:47.254Z,1633735307.254 [NAL9602](INFO): Powering down 2021-10-08T23:21:47.257Z,1633735307.257 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-10-08T23:21:47.258Z,1633735307.258 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-10-08T23:21:47.259Z,1633735307.259 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-10-08T23:21:47.259Z,1633735307.259 [MissionManager](INFO): Uninitializing Mission Default 2021-10-08T23:21:47.259Z,1633735307.259 [Default] Stopped 2021-10-08T23:21:47.259Z,1633735307.259 [Default](DEBUG): Aggregate::uninitialize Default 2021-10-08T23:21:47.260Z,1633735307.260 [Default:B.GoToSurface] Stopped 2021-10-08T23:21:47.260Z,1633735307.260 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-10-08T23:21:47.260Z,1633735307.260 [Default:CheckIn] Stopped 2021-10-08T23:21:47.260Z,1633735307.260 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-10-08T23:21:47.260Z,1633735307.260 [Default:CheckIn:Read_GPS] Stopped 2021-10-08T23:21:47.263Z,1633735307.263 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-10-08T23:21:47.263Z,1633735307.263 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-10-08T23:21:47.264Z,1633735307.264 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-10-08T23:21:47.264Z,1633735307.264 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-10-08T23:21:47.264Z,1633735307.264 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-10-08T23:21:47.264Z,1633735307.264 [BuoyancyServo](INFO): Powering down 2021-10-08T23:21:47.278Z,1633735307.278 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-10-08T23:21:47.278Z,1633735307.278 [ElevatorServo](INFO): Powering down 2021-10-08T23:21:47.279Z,1633735307.279 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-10-08T23:21:47.279Z,1633735307.279 [MassServo](INFO): Powering down 2021-10-08T23:21:47.280Z,1633735307.280 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-10-08T23:21:47.280Z,1633735307.280 [RudderServo](INFO): Powering down 2021-10-08T23:21:47.281Z,1633735307.281 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-10-08T23:21:47.281Z,1633735307.281 [ThrusterServo](INFO): Powering down 2021-10-08T23:21:47.282Z,1633735307.282 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-10-08T23:21:47.282Z,1633735307.282 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-10-08T23:21:47.282Z,1633735307.282 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-10-08T23:21:47.283Z,1633735307.283 [CBIT](DEBUG): Powering off loads. 2021-10-08T23:21:47.293Z,1633735307.293 [CBIT](DEBUG): Disabling WDT. 2021-10-08T23:21:47.305Z,1633735307.305 [CBIT](DEBUG): Opening all GF detection circuits. 2021-10-08T23:21:47.306Z,1633735307.306 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:47.362Z,1633735307.362 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:47.365Z,1633735307.365 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:47.372Z,1633735307.372 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:47.441Z,1633735307.441 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:47.445Z,1633735307.445 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:47.489Z,1633735307.489 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-10-08T23:21:47.591Z,1633735307.591 [logger ThreadHandler](INFO): Thread cancelled.