2021-04-26T16:35:09.666Z,1619454909.666 [CommandLine](IMPORTANT): got command restart logs 2021-04-26T16:37:35.339Z,1619455055.339 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T16:37:35.339Z,1619455055.339 [Default:CheckIn:C.Wait] Stopped 2021-04-26T16:37:35.339Z,1619455055.339 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T16:37:35.339Z,1619455055.339 [Default:CheckIn:D] Running Loop=1 2021-04-26T16:37:35.758Z,1619455055.758 [Default:CheckIn:D] Stopped 2021-04-26T16:37:35.758Z,1619455055.758 [Default:CheckIn:E] Running Loop=1 2021-04-26T16:37:36.174Z,1619455056.174 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.098553 min 2021-04-26T16:37:36.174Z,1619455056.174 [Default:CheckIn:E] Stopped 2021-04-26T16:37:36.174Z,1619455056.174 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T16:37:36.174Z,1619455056.174 [Default:CheckIn] Stopped 2021-04-26T16:37:36.174Z,1619455056.174 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T16:37:36.174Z,1619455056.174 [Default:CheckIn](INFO): Running loop #2 2021-04-26T16:37:36.174Z,1619455056.174 [Default:CheckIn] Running Loop=2 2021-04-26T16:37:36.174Z,1619455056.174 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T16:37:36.174Z,1619455056.174 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T16:37:38.163Z,1619455058.163 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163736.00,A,3647.85578,N,12150.95039,W,1.302,291.21,260421,,,D*75 2021-04-26T16:37:38.165Z,1619455058.165 [NAL9602](INFO): GPS fix at 20210426T163736: (36.797596, -121.849173) 2021-04-26T16:37:38.203Z,1619455058.203 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T16:37:38.203Z,1619455058.203 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T16:37:42.433Z,1619455062.433 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210426T002032/Courier0063.lzma 2021-04-26T16:37:43.435Z,1619455063.435 [DataOverHttps](INFO): Moved sent file to Logs/20210426T002032/Courier0063.lzma.bak 2021-04-26T16:37:43.435Z,1619455063.435 [DataOverHttps](INFO): SBD MOMSN=15604700 2021-04-26T16:37:57.241Z,1619455077.241 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=41864, MT Status=1, MTMSN=3002 2021-04-26T16:37:57.242Z,1619455077.242 [NAL9602](INFO): Data available in MT queue 2021-04-26T16:37:57.738Z,1619455077.738 [NAL9602](INFO): Received command:stop 2021-04-26T16:37:57.826Z,1619455077.826 [CommandLine](IMPORTANT): got command stop 2021-04-26T16:38:01.161Z,1619455081.161 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20210426T163509/Courier0000.lzma 2021-04-26T16:38:02.163Z,1619455082.163 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0000.lzma.bak 2021-04-26T16:38:02.163Z,1619455082.163 [DataOverHttps](INFO): SBD MOMSN=15604702 2021-04-26T16:38:17.849Z,1619455097.849 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20210426T002032/Express0064.lzma 2021-04-26T16:38:18.851Z,1619455098.851 [DataOverHttps](INFO): Moved sent file to Logs/20210426T002032/Express0064.lzma.bak 2021-04-26T16:38:18.851Z,1619455098.851 [DataOverHttps](INFO): SBD MOMSN=15604710 2021-04-26T16:38:28.373Z,1619455108.373 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T16:38:41.450Z,1619455121.450 [DataOverHttps](INFO): Sending 394 bytes from file Logs/20210426T163509/Express0001.lzma 2021-04-26T16:38:42.451Z,1619455122.451 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0001.lzma.bak 2021-04-26T16:38:42.451Z,1619455122.451 [DataOverHttps](INFO): SBD MOMSN=15604715 2021-04-26T16:39:03.257Z,1619455143.257 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210426T163509/Express0004.lzma 2021-04-26T16:39:04.259Z,1619455144.259 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0004.lzma.bak 2021-04-26T16:39:04.259Z,1619455144.259 [DataOverHttps](INFO): SBD MOMSN=15604719 2021-04-26T16:39:05.636Z,1619455145.636 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T16:39:05.636Z,1619455145.636 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T16:39:05.636Z,1619455145.636 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T16:44:06.331Z,1619455446.331 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T16:44:06.333Z,1619455446.333 [Default:CheckIn:C.Wait] Stopped 2021-04-26T16:44:06.333Z,1619455446.333 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T16:44:06.334Z,1619455446.334 [Default:CheckIn:D] Running Loop=1 2021-04-26T16:44:06.743Z,1619455446.743 [Default:CheckIn:D] Stopped 2021-04-26T16:44:06.743Z,1619455446.743 [Default:CheckIn:E] Running Loop=1 2021-04-26T16:44:07.136Z,1619455447.136 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.614963 min 2021-04-26T16:44:07.136Z,1619455447.136 [Default:CheckIn:E] Stopped 2021-04-26T16:44:07.136Z,1619455447.136 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T16:44:07.136Z,1619455447.136 [Default:CheckIn] Stopped 2021-04-26T16:44:07.136Z,1619455447.136 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T16:44:07.136Z,1619455447.136 [Default:CheckIn](INFO): Running loop #3 2021-04-26T16:44:07.136Z,1619455447.136 [Default:CheckIn] Running Loop=3 2021-04-26T16:44:07.137Z,1619455447.137 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T16:44:07.137Z,1619455447.137 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T16:44:09.151Z,1619455449.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164407.00,A,3647.87621,N,12150.73637,W,4.646,84.75,260421,,,D*49 2021-04-26T16:44:09.154Z,1619455449.154 [NAL9602](INFO): GPS fix at 20210426T164407: (36.797937, -121.845606) 2021-04-26T16:44:09.163Z,1619455449.163 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T16:44:09.163Z,1619455449.163 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T16:44:15.950Z,1619455455.950 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163509/Courier0006.lzma 2021-04-26T16:44:16.951Z,1619455456.951 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0006.lzma.bak 2021-04-26T16:44:16.951Z,1619455456.951 [DataOverHttps](INFO): SBD MOMSN=15604730 2021-04-26T16:44:32.629Z,1619455472.629 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20210426T163509/Express0007.lzma 2021-04-26T16:44:35.639Z,1619455475.639 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0007.lzma.bak 2021-04-26T16:44:35.639Z,1619455475.639 [DataOverHttps](INFO): SBD MOMSN=15604733 2021-04-26T16:44:37.432Z,1619455477.432 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T16:44:37.432Z,1619455477.432 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T16:44:37.432Z,1619455477.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T16:44:49.586Z,1619455489.586 [NAL9602](INFO): SBD MO Status=2, MOMSN=41865, MT Status=2, MTMSN=0 2021-04-26T16:44:49.587Z,1619455489.587 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T16:45:06.960Z,1619455506.960 [NAL9602](INFO): SBD MO Status=2, MOMSN=41865, MT Status=2, MTMSN=0 2021-04-26T16:45:06.960Z,1619455506.960 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T16:45:37.862Z,1619455537.862 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-26T16:46:02.718Z,1619455562.718 [NAL9602](INFO): SBD MO Status=1, MOMSN=41865, MT Status=0, MTMSN=0 2021-04-26T16:46:02.719Z,1619455562.719 [NAL9602](INFO): No messages in MT queue 2021-04-26T16:46:33.417Z,1619455593.417 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T16:49:38.144Z,1619455778.144 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T16:49:38.144Z,1619455778.144 [Default:CheckIn:C.Wait] Stopped 2021-04-26T16:49:38.144Z,1619455778.144 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T16:49:38.145Z,1619455778.145 [Default:CheckIn:D] Running Loop=1 2021-04-26T16:49:38.552Z,1619455778.552 [Default:CheckIn:D] Stopped 2021-04-26T16:49:38.553Z,1619455778.553 [Default:CheckIn:E] Running Loop=1 2021-04-26T16:49:38.963Z,1619455778.963 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.145125 min 2021-04-26T16:49:38.964Z,1619455778.964 [Default:CheckIn:E] Stopped 2021-04-26T16:49:38.964Z,1619455778.964 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T16:49:38.964Z,1619455778.964 [Default:CheckIn] Stopped 2021-04-26T16:49:38.964Z,1619455778.964 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T16:49:38.964Z,1619455778.964 [Default:CheckIn](INFO): Running loop #4 2021-04-26T16:49:38.964Z,1619455778.964 [Default:CheckIn] Running Loop=4 2021-04-26T16:49:38.964Z,1619455778.964 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T16:49:38.964Z,1619455778.964 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T16:49:40.951Z,1619455780.951 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164939.00,A,3648.18166,N,12148.34412,W,27.680,65.92,260421,,,D*76 2021-04-26T16:49:40.953Z,1619455780.953 [NAL9602](INFO): GPS fix at 20210426T164939: (36.803028, -121.805735) 2021-04-26T16:49:40.963Z,1619455780.963 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T16:49:40.963Z,1619455780.963 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T16:49:49.737Z,1619455789.737 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163509/Courier0009.lzma 2021-04-26T16:49:50.739Z,1619455790.739 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0009.lzma.bak 2021-04-26T16:49:50.739Z,1619455790.739 [DataOverHttps](INFO): SBD MOMSN=15604747 2021-04-26T16:49:57.933Z,1619455797.933 [NAL9602](INFO): SBD MO Status=1, MOMSN=41866, MT Status=0, MTMSN=0 2021-04-26T16:49:57.933Z,1619455797.933 [NAL9602](INFO): No messages in MT queue 2021-04-26T16:50:07.337Z,1619455807.337 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20210426T163509/Express0010.lzma 2021-04-26T16:50:08.339Z,1619455808.339 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0010.lzma.bak 2021-04-26T16:50:08.339Z,1619455808.339 [DataOverHttps](INFO): SBD MOMSN=15604754 2021-04-26T16:50:09.643Z,1619455809.643 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T16:50:09.643Z,1619455809.643 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T16:50:09.643Z,1619455809.643 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T16:50:28.624Z,1619455828.624 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T16:51:09.806Z,1619455869.806 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-26T16:51:40.930Z,1619455900.930 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-26T16:55:10.277Z,1619456110.277 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T16:55:10.277Z,1619456110.277 [Default:CheckIn:C.Wait] Stopped 2021-04-26T16:55:10.277Z,1619456110.277 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T16:55:10.277Z,1619456110.277 [Default:CheckIn:D] Running Loop=1 2021-04-26T16:55:10.682Z,1619456110.682 [Default:CheckIn:D] Stopped 2021-04-26T16:55:10.682Z,1619456110.682 [Default:CheckIn:E] Running Loop=1 2021-04-26T16:55:11.107Z,1619456111.107 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.680617 min 2021-04-26T16:55:11.107Z,1619456111.107 [Default:CheckIn:E] Stopped 2021-04-26T16:55:11.107Z,1619456111.107 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T16:55:11.107Z,1619456111.107 [Default:CheckIn] Stopped 2021-04-26T16:55:11.107Z,1619456111.107 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T16:55:11.107Z,1619456111.107 [Default:CheckIn](INFO): Running loop #5 2021-04-26T16:55:11.108Z,1619456111.108 [Default:CheckIn] Running Loop=5 2021-04-26T16:55:11.108Z,1619456111.108 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T16:55:11.108Z,1619456111.108 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T16:55:13.099Z,1619456113.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165511.00,A,3648.21135,N,12147.15795,W,6.084,193.76,260421,,,D*7E 2021-04-26T16:55:13.101Z,1619456113.101 [NAL9602](INFO): GPS fix at 20210426T165511: (36.803522, -121.785966) 2021-04-26T16:55:13.111Z,1619456113.111 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T16:55:13.111Z,1619456113.111 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T16:55:20.497Z,1619456120.497 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163509/Courier0012.lzma 2021-04-26T16:55:21.499Z,1619456121.499 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0012.lzma.bak 2021-04-26T16:55:21.499Z,1619456121.499 [DataOverHttps](INFO): SBD MOMSN=15604769 2021-04-26T16:55:27.242Z,1619456127.242 [NAL9602](INFO): SBD MO Status=1, MOMSN=41867, MT Status=0, MTMSN=0 2021-04-26T16:55:27.242Z,1619456127.242 [NAL9602](INFO): No messages in MT queue 2021-04-26T16:55:37.185Z,1619456137.185 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210426T163509/Express0013.lzma 2021-04-26T16:55:38.187Z,1619456138.187 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0013.lzma.bak 2021-04-26T16:55:38.187Z,1619456138.187 [DataOverHttps](INFO): SBD MOMSN=15604772 2021-04-26T16:55:39.383Z,1619456139.383 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T16:55:39.383Z,1619456139.383 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T16:55:39.383Z,1619456139.383 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T16:55:57.938Z,1619456157.938 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T17:00:40.064Z,1619456440.064 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T17:00:40.065Z,1619456440.065 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:00:40.065Z,1619456440.065 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:00:40.065Z,1619456440.065 [Default:CheckIn:D] Running Loop=1 2021-04-26T17:00:40.480Z,1619456440.480 [Default:CheckIn:D] Stopped 2021-04-26T17:00:40.480Z,1619456440.480 [Default:CheckIn:E] Running Loop=1 2021-04-26T17:00:40.873Z,1619456440.873 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.177246 min 2021-04-26T17:00:40.873Z,1619456440.873 [Default:CheckIn:E] Stopped 2021-04-26T17:00:40.873Z,1619456440.873 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T17:00:40.873Z,1619456440.873 [Default:CheckIn] Stopped 2021-04-26T17:00:40.877Z,1619456440.877 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:00:40.878Z,1619456440.878 [Default:CheckIn](INFO): Running loop #6 2021-04-26T17:00:40.878Z,1619456440.878 [Default:CheckIn] Running Loop=6 2021-04-26T17:00:40.878Z,1619456440.878 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T17:00:40.878Z,1619456440.878 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T17:00:42.879Z,1619456442.879 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170041.00,A,3648.13740,N,12147.20638,W,0.136,264.55,260421,,,D*7B 2021-04-26T17:00:42.881Z,1619456442.881 [NAL9602](INFO): GPS fix at 20210426T170041: (36.802290, -121.786773) 2021-04-26T17:00:42.916Z,1619456442.916 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T17:00:42.916Z,1619456442.916 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T17:00:53.493Z,1619456453.493 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163509/Courier0015.lzma 2021-04-26T17:00:54.495Z,1619456454.495 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0015.lzma.bak 2021-04-26T17:00:54.495Z,1619456454.495 [DataOverHttps](INFO): SBD MOMSN=15604786 2021-04-26T17:01:10.237Z,1619456470.237 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20210426T163509/Express0016.lzma 2021-04-26T17:01:11.239Z,1619456471.239 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0016.lzma.bak 2021-04-26T17:01:11.239Z,1619456471.239 [DataOverHttps](INFO): SBD MOMSN=15604790 2021-04-26T17:01:12.825Z,1619456472.825 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T17:01:12.826Z,1619456472.826 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T17:01:12.826Z,1619456472.826 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T17:02:32.804Z,1619456552.804 [NAL9602](INFO): SBD MO Status=1, MOMSN=41868, MT Status=0, MTMSN=0 2021-04-26T17:02:32.804Z,1619456552.804 [NAL9602](INFO): No messages in MT queue 2021-04-26T17:03:03.551Z,1619456583.551 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T17:06:13.445Z,1619456773.445 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T17:06:13.445Z,1619456773.445 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:06:13.446Z,1619456773.446 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:06:13.446Z,1619456773.446 [Default:CheckIn:D] Running Loop=1 2021-04-26T17:06:13.855Z,1619456773.855 [Default:CheckIn:D] Stopped 2021-04-26T17:06:13.855Z,1619456773.855 [Default:CheckIn:E] Running Loop=1 2021-04-26T17:06:14.255Z,1619456774.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.733496 min 2021-04-26T17:06:14.256Z,1619456774.256 [Default:CheckIn:E] Stopped 2021-04-26T17:06:14.256Z,1619456774.256 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T17:06:14.256Z,1619456774.256 [Default:CheckIn] Stopped 2021-04-26T17:06:14.256Z,1619456774.256 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:06:14.256Z,1619456774.256 [Default:CheckIn](INFO): Running loop #7 2021-04-26T17:06:14.256Z,1619456774.256 [Default:CheckIn] Running Loop=7 2021-04-26T17:06:14.256Z,1619456774.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T17:06:14.256Z,1619456774.256 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T17:06:16.268Z,1619456776.268 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170614.00,A,3648.13715,N,12147.21617,W,0.039,232.32,260421,,,D*7D 2021-04-26T17:06:16.271Z,1619456776.271 [NAL9602](INFO): GPS fix at 20210426T170614: (36.802286, -121.786936) 2021-04-26T17:06:16.280Z,1619456776.280 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T17:06:16.280Z,1619456776.280 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T17:06:23.189Z,1619456783.189 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163509/Courier0018.lzma 2021-04-26T17:06:24.191Z,1619456784.191 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0018.lzma.bak 2021-04-26T17:06:24.191Z,1619456784.191 [DataOverHttps](INFO): SBD MOMSN=15604802 2021-04-26T17:06:43.046Z,1619456803.046 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210426T163509/Express0019.lzma 2021-04-26T17:06:44.047Z,1619456804.047 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0019.lzma.bak 2021-04-26T17:06:44.047Z,1619456804.047 [DataOverHttps](INFO): SBD MOMSN=15604809 2021-04-26T17:06:44.610Z,1619456804.610 [NAL9602](INFO): SBD MO Status=2, MOMSN=41869, MT Status=2, MTMSN=0 2021-04-26T17:06:44.611Z,1619456804.611 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:06:45.432Z,1619456805.432 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T17:06:45.432Z,1619456805.432 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T17:06:45.432Z,1619456805.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T17:07:12.547Z,1619456832.547 [NAL9602](INFO): SBD MO Status=1, MOMSN=41869, MT Status=0, MTMSN=0 2021-04-26T17:07:12.547Z,1619456832.547 [NAL9602](INFO): No messages in MT queue 2021-04-26T17:07:43.237Z,1619456863.237 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T17:11:46.062Z,1619457106.062 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T17:11:46.062Z,1619457106.062 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:11:46.062Z,1619457106.062 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:11:46.062Z,1619457106.062 [Default:CheckIn:D] Running Loop=1 2021-04-26T17:11:46.471Z,1619457106.471 [Default:CheckIn:D] Stopped 2021-04-26T17:11:46.471Z,1619457106.471 [Default:CheckIn:E] Running Loop=1 2021-04-26T17:11:46.881Z,1619457106.881 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.277104 min 2021-04-26T17:11:46.881Z,1619457106.881 [Default:CheckIn:E] Stopped 2021-04-26T17:11:46.882Z,1619457106.882 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T17:11:46.882Z,1619457106.882 [Default:CheckIn] Stopped 2021-04-26T17:11:46.882Z,1619457106.882 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:11:46.882Z,1619457106.882 [Default:CheckIn](INFO): Running loop #8 2021-04-26T17:11:46.882Z,1619457106.882 [Default:CheckIn] Running Loop=8 2021-04-26T17:11:46.882Z,1619457106.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T17:11:46.882Z,1619457106.882 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T17:11:48.888Z,1619457108.888 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171147.00,A,3648.13802,N,12147.21639,W,0.019,232.32,260421,,,A*7F 2021-04-26T17:11:48.891Z,1619457108.891 [NAL9602](INFO): GPS fix at 20210426T171147: (36.802300, -121.786940) 2021-04-26T17:11:48.916Z,1619457108.916 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T17:11:48.916Z,1619457108.916 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T17:11:55.821Z,1619457115.821 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163509/Courier0021.lzma 2021-04-26T17:11:56.823Z,1619457116.823 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0021.lzma.bak 2021-04-26T17:11:56.823Z,1619457116.823 [DataOverHttps](INFO): SBD MOMSN=15604819 2021-04-26T17:12:03.028Z,1619457123.028 [NAL9602](INFO): SBD MO Status=1, MOMSN=41870, MT Status=0, MTMSN=0 2021-04-26T17:12:03.028Z,1619457123.028 [NAL9602](INFO): No messages in MT queue 2021-04-26T17:12:12.781Z,1619457132.781 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20210426T163509/Express0022.lzma 2021-04-26T17:12:13.783Z,1619457133.783 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0022.lzma.bak 2021-04-26T17:12:13.783Z,1619457133.783 [DataOverHttps](INFO): SBD MOMSN=15604825 2021-04-26T17:12:15.150Z,1619457135.150 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T17:12:15.150Z,1619457135.150 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T17:12:15.150Z,1619457135.150 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T17:12:33.725Z,1619457153.725 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T17:17:15.747Z,1619457435.747 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T17:17:15.747Z,1619457435.747 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:17:15.748Z,1619457435.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:17:15.748Z,1619457435.748 [Default:CheckIn:D] Running Loop=1 2021-04-26T17:17:16.157Z,1619457436.157 [Default:CheckIn:D] Stopped 2021-04-26T17:17:16.157Z,1619457436.157 [Default:CheckIn:E] Running Loop=1 2021-04-26T17:17:16.550Z,1619457436.550 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.771863 min 2021-04-26T17:17:16.550Z,1619457436.550 [Default:CheckIn:E] Stopped 2021-04-26T17:17:16.550Z,1619457436.550 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T17:17:16.551Z,1619457436.551 [Default:CheckIn] Stopped 2021-04-26T17:17:16.551Z,1619457436.551 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:17:16.551Z,1619457436.551 [Default:CheckIn](INFO): Running loop #9 2021-04-26T17:17:16.551Z,1619457436.551 [Default:CheckIn] Running Loop=9 2021-04-26T17:17:16.551Z,1619457436.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T17:17:16.551Z,1619457436.551 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T17:17:18.563Z,1619457438.563 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171717.00,A,3648.17192,N,12147.27232,W,2.760,293.71,260421,,,A*76 2021-04-26T17:17:18.565Z,1619457438.565 [NAL9602](INFO): GPS fix at 20210426T171717: (36.802865, -121.787872) 2021-04-26T17:17:18.575Z,1619457438.575 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T17:17:18.575Z,1619457438.575 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T17:17:24.737Z,1619457444.737 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163509/Courier0024.lzma 2021-04-26T17:17:25.739Z,1619457445.739 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0024.lzma.bak 2021-04-26T17:17:25.740Z,1619457445.740 [DataOverHttps](INFO): SBD MOMSN=15604833 2021-04-26T17:17:41.527Z,1619457461.527 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210426T163509/Express0025.lzma 2021-04-26T17:17:42.287Z,1619457462.287 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0025.lzma.bak 2021-04-26T17:17:42.287Z,1619457462.287 [DataOverHttps](INFO): SBD MOMSN=15604836 2021-04-26T17:17:43.628Z,1619457463.628 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T17:17:43.629Z,1619457463.629 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T17:17:43.629Z,1619457463.629 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T17:20:09.895Z,1619457609.895 [NAL9602](INFO): SBD MO Status=2, MOMSN=41871, MT Status=2, MTMSN=0 2021-04-26T17:20:09.895Z,1619457609.895 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:20:48.692Z,1619457648.692 [NAL9602](INFO): SBD MO Status=2, MOMSN=41871, MT Status=2, MTMSN=0 2021-04-26T17:20:48.692Z,1619457648.692 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:21:13.727Z,1619457673.727 [NAL9602](INFO): SBD MO Status=1, MOMSN=41871, MT Status=0, MTMSN=0 2021-04-26T17:21:13.727Z,1619457673.727 [NAL9602](INFO): No messages in MT queue 2021-04-26T17:21:44.432Z,1619457704.432 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T17:22:44.251Z,1619457764.251 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T17:22:44.251Z,1619457764.251 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:22:44.251Z,1619457764.251 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:22:44.251Z,1619457764.251 [Default:CheckIn:D] Running Loop=1 2021-04-26T17:22:44.638Z,1619457764.638 [Default:CheckIn:D] Stopped 2021-04-26T17:22:44.638Z,1619457764.638 [Default:CheckIn:E] Running Loop=1 2021-04-26T17:22:45.044Z,1619457765.044 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.246558 min 2021-04-26T17:22:45.044Z,1619457765.044 [Default:CheckIn:E] Stopped 2021-04-26T17:22:45.044Z,1619457765.044 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T17:22:45.044Z,1619457765.044 [Default:CheckIn] Stopped 2021-04-26T17:22:45.045Z,1619457765.045 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:22:45.045Z,1619457765.045 [Default:CheckIn](INFO): Running loop #10 2021-04-26T17:22:45.045Z,1619457765.045 [Default:CheckIn] Running Loop=10 2021-04-26T17:22:45.045Z,1619457765.045 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T17:22:45.045Z,1619457765.045 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T17:22:47.079Z,1619457767.079 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172245.00,A,3648.16772,N,12147.28299,W,0.680,312.95,260421,,,A*7F 2021-04-26T17:22:47.090Z,1619457767.090 [NAL9602](INFO): GPS fix at 20210426T172245: (36.802795, -121.788050) 2021-04-26T17:22:47.132Z,1619457767.132 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T17:22:47.132Z,1619457767.132 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T17:22:54.455Z,1619457774.455 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163509/Courier0027.lzma 2021-04-26T17:22:55.455Z,1619457775.455 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0027.lzma.bak 2021-04-26T17:22:55.455Z,1619457775.455 [DataOverHttps](INFO): SBD MOMSN=15604846 2021-04-26T17:22:58.884Z,1619457778.884 [NAL9602](INFO): SBD MO Status=1, MOMSN=41872, MT Status=0, MTMSN=0 2021-04-26T17:22:58.884Z,1619457778.884 [NAL9602](INFO): No messages in MT queue 2021-04-26T17:23:10.997Z,1619457790.997 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20210426T163509/Express0028.lzma 2021-04-26T17:23:11.999Z,1619457791.999 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0028.lzma.bak 2021-04-26T17:23:11.000Z,1619457792.000 [DataOverHttps](INFO): SBD MOMSN=15604849 2021-04-26T17:23:13.472Z,1619457793.472 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T17:23:13.472Z,1619457793.472 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T17:23:13.473Z,1619457793.473 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T17:23:29.617Z,1619457809.617 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T17:28:14.084Z,1619458094.084 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T17:28:14.084Z,1619458094.084 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:28:14.084Z,1619458094.084 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:28:14.085Z,1619458094.085 [Default:CheckIn:D] Running Loop=1 2021-04-26T17:28:14.484Z,1619458094.484 [Default:CheckIn:D] Stopped 2021-04-26T17:28:14.484Z,1619458094.484 [Default:CheckIn:E] Running Loop=1 2021-04-26T17:28:14.881Z,1619458094.881 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.743978 min 2021-04-26T17:28:14.882Z,1619458094.882 [Default:CheckIn:E] Stopped 2021-04-26T17:28:14.882Z,1619458094.882 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T17:28:14.882Z,1619458094.882 [Default:CheckIn] Stopped 2021-04-26T17:28:14.882Z,1619458094.882 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:28:14.882Z,1619458094.882 [Default:CheckIn](INFO): Running loop #11 2021-04-26T17:28:14.882Z,1619458094.882 [Default:CheckIn] Running Loop=11 2021-04-26T17:28:14.882Z,1619458094.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T17:28:14.882Z,1619458094.882 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T17:28:16.895Z,1619458096.895 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172815.00,A,3648.16932,N,12147.28167,W,0.408,312.95,260421,,,A*7A 2021-04-26T17:28:16.897Z,1619458096.897 [NAL9602](INFO): GPS fix at 20210426T172815: (36.802822, -121.788028) 2021-04-26T17:28:16.907Z,1619458096.907 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T17:28:16.907Z,1619458096.907 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T17:28:23.589Z,1619458103.589 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163509/Courier0030.lzma 2021-04-26T17:28:24.591Z,1619458104.591 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0030.lzma.bak 2021-04-26T17:28:24.591Z,1619458104.591 [DataOverHttps](INFO): SBD MOMSN=15604863 2021-04-26T17:28:43.589Z,1619458123.589 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210426T163509/Express0031.lzma 2021-04-26T17:28:44.591Z,1619458124.591 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0031.lzma.bak 2021-04-26T17:28:44.592Z,1619458124.592 [DataOverHttps](INFO): SBD MOMSN=15604866 2021-04-26T17:28:46.072Z,1619458126.072 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T17:28:46.072Z,1619458126.072 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T17:28:46.072Z,1619458126.072 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T17:28:50.982Z,1619458130.982 [NAL9602](INFO): SBD MO Status=2, MOMSN=41873, MT Status=2, MTMSN=0 2021-04-26T17:28:50.982Z,1619458130.982 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:29:19.639Z,1619458159.639 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-26T17:29:25.326Z,1619458165.326 [NAL9602](INFO): SBD MO Status=1, MOMSN=41873, MT Status=0, MTMSN=0 2021-04-26T17:29:25.326Z,1619458165.326 [NAL9602](INFO): No messages in MT queue 2021-04-26T17:29:30.952Z,1619458170.952 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-26T17:29:42.264Z,1619458182.264 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-26T17:29:53.979Z,1619458193.979 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-26T17:29:56.003Z,1619458196.003 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T17:30:05.692Z,1619458205.692 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-26T17:30:17.414Z,1619458217.414 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-26T17:30:28.727Z,1619458228.727 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-26T17:30:40.454Z,1619458240.454 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-26T17:33:46.761Z,1619458426.761 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T17:33:46.762Z,1619458426.762 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:33:46.762Z,1619458426.762 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:33:46.762Z,1619458426.762 [Default:CheckIn:D] Running Loop=1 2021-04-26T17:33:47.156Z,1619458427.156 [Default:CheckIn:D] Stopped 2021-04-26T17:33:47.156Z,1619458427.156 [Default:CheckIn:E] Running Loop=1 2021-04-26T17:33:47.616Z,1619458427.616 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.288517 min 2021-04-26T17:33:47.616Z,1619458427.616 [Default:CheckIn:E] Stopped 2021-04-26T17:33:47.616Z,1619458427.616 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T17:33:47.617Z,1619458427.617 [Default:CheckIn] Stopped 2021-04-26T17:33:47.617Z,1619458427.617 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:33:47.617Z,1619458427.617 [Default:CheckIn](INFO): Running loop #12 2021-04-26T17:33:47.617Z,1619458427.617 [Default:CheckIn] Running Loop=12 2021-04-26T17:33:47.617Z,1619458427.617 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T17:33:47.617Z,1619458427.617 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T17:33:49.556Z,1619458429.556 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173348.00,A,3648.16556,N,12147.28325,W,0.311,0.00,260421,,,A*71 2021-04-26T17:33:49.558Z,1619458429.558 [NAL9602](INFO): GPS fix at 20210426T173348: (36.802759, -121.788054) 2021-04-26T17:33:49.567Z,1619458429.567 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T17:33:49.567Z,1619458429.567 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T17:33:57.172Z,1619458437.172 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210426T163509/Courier0033.lzma 2021-04-26T17:33:58.171Z,1619458438.171 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0033.lzma.bak 2021-04-26T17:33:58.171Z,1619458438.171 [DataOverHttps](INFO): SBD MOMSN=15604878 2021-04-26T17:34:13.989Z,1619458453.989 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210426T163509/Express0034.lzma 2021-04-26T17:34:14.991Z,1619458454.991 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0034.lzma.bak 2021-04-26T17:34:14.991Z,1619458454.991 [DataOverHttps](INFO): SBD MOMSN=15604881 2021-04-26T17:34:16.216Z,1619458456.216 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T17:34:16.216Z,1619458456.216 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T17:34:16.216Z,1619458456.216 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T17:35:54.001Z,1619458554.001 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2021-04-26T17:35:54.002Z,1619458554.002 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2021-04-26T17:35:54.004Z,1619458554.004 [BPC1](INFO): Received data from all battery sticks. 2021-04-26T17:36:18.215Z,1619458578.215 [NAL9602](INFO): SBD MO Status=2, MOMSN=41874, MT Status=2, MTMSN=0 2021-04-26T17:36:18.215Z,1619458578.215 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:37:08.728Z,1619458628.728 [NAL9602](INFO): SBD MO Status=2, MOMSN=41874, MT Status=2, MTMSN=0 2021-04-26T17:37:08.728Z,1619458628.728 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:37:33.770Z,1619458653.770 [NAL9602](INFO): SBD MO Status=2, MOMSN=41874, MT Status=2, MTMSN=0 2021-04-26T17:37:33.771Z,1619458653.771 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:38:12.954Z,1619458692.954 [NAL9602](INFO): SBD MO Status=2, MOMSN=41874, MT Status=2, MTMSN=0 2021-04-26T17:38:12.955Z,1619458692.955 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:38:31.146Z,1619458711.146 [NAL9602](INFO): SBD MO Status=2, MOMSN=41874, MT Status=2, MTMSN=0 2021-04-26T17:38:31.146Z,1619458711.146 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:38:53.380Z,1619458733.380 [NAL9602](INFO): SBD MO Status=1, MOMSN=41874, MT Status=0, MTMSN=0 2021-04-26T17:38:53.380Z,1619458733.380 [NAL9602](INFO): No messages in MT queue 2021-04-26T17:39:16.816Z,1619458756.816 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T17:39:16.816Z,1619458756.816 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:39:16.816Z,1619458756.816 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:39:16.817Z,1619458756.817 [Default:CheckIn:D] Running Loop=1 2021-04-26T17:39:17.220Z,1619458757.220 [Default:CheckIn:D] Stopped 2021-04-26T17:39:17.220Z,1619458757.220 [Default:CheckIn:E] Running Loop=1 2021-04-26T17:39:17.625Z,1619458757.625 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.789583 min 2021-04-26T17:39:17.625Z,1619458757.625 [Default:CheckIn:E] Stopped 2021-04-26T17:39:17.625Z,1619458757.625 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T17:39:17.625Z,1619458757.625 [Default:CheckIn] Stopped 2021-04-26T17:39:17.626Z,1619458757.626 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:39:17.626Z,1619458757.626 [Default:CheckIn](INFO): Running loop #13 2021-04-26T17:39:17.626Z,1619458757.626 [Default:CheckIn] Running Loop=13 2021-04-26T17:39:17.626Z,1619458757.626 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T17:39:17.626Z,1619458757.626 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T17:39:19.627Z,1619458759.627 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173918.00,A,3648.16757,N,12147.28202,W,0.078,117.67,260421,,,A*73 2021-04-26T17:39:19.629Z,1619458759.629 [NAL9602](INFO): GPS fix at 20210426T173918: (36.802793, -121.788034) 2021-04-26T17:39:19.639Z,1619458759.639 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T17:39:19.639Z,1619458759.639 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T17:39:27.301Z,1619458767.301 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20210426T163509/Courier0036.lzma 2021-04-26T17:39:28.316Z,1619458768.316 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0036.lzma.bak 2021-04-26T17:39:28.317Z,1619458768.317 [DataOverHttps](INFO): SBD MOMSN=15604891 2021-04-26T17:39:44.281Z,1619458784.281 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210426T163509/Express0037.lzma 2021-04-26T17:39:45.283Z,1619458785.283 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0037.lzma.bak 2021-04-26T17:39:45.283Z,1619458785.283 [DataOverHttps](INFO): SBD MOMSN=15604894 2021-04-26T17:39:46.707Z,1619458786.707 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T17:39:46.707Z,1619458786.707 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T17:39:46.707Z,1619458786.707 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T17:39:52.354Z,1619458792.354 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T17:44:47.412Z,1619459087.412 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T17:44:47.413Z,1619459087.413 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:44:47.413Z,1619459087.413 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:44:47.413Z,1619459087.413 [Default:CheckIn:D] Running Loop=1 2021-04-26T17:44:47.760Z,1619459087.760 [Default:CheckIn:D] Stopped 2021-04-26T17:44:47.760Z,1619459087.760 [Default:CheckIn:E] Running Loop=1 2021-04-26T17:44:48.164Z,1619459088.164 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.298584 min 2021-04-26T17:44:48.164Z,1619459088.164 [Default:CheckIn:E] Stopped 2021-04-26T17:44:48.164Z,1619459088.164 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T17:44:48.164Z,1619459088.164 [Default:CheckIn] Stopped 2021-04-26T17:44:48.164Z,1619459088.164 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:44:48.164Z,1619459088.164 [Default:CheckIn](INFO): Running loop #14 2021-04-26T17:44:48.164Z,1619459088.164 [Default:CheckIn] Running Loop=14 2021-04-26T17:44:48.165Z,1619459088.165 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T17:44:48.165Z,1619459088.165 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T17:44:50.185Z,1619459090.185 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174448.00,A,3648.16699,N,12147.28217,W,0.156,117.67,260421,,,A*76 2021-04-26T17:44:50.187Z,1619459090.187 [NAL9602](INFO): GPS fix at 20210426T174448: (36.802783, -121.788036) 2021-04-26T17:44:50.196Z,1619459090.196 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T17:44:50.197Z,1619459090.197 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T17:44:57.173Z,1619459097.173 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210426T163509/Courier0039.lzma 2021-04-26T17:44:58.175Z,1619459098.175 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0039.lzma.bak 2021-04-26T17:44:58.175Z,1619459098.175 [DataOverHttps](INFO): SBD MOMSN=15604907 2021-04-26T17:45:13.701Z,1619459113.701 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20210426T163509/Express0040.lzma 2021-04-26T17:45:14.703Z,1619459114.703 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0040.lzma.bak 2021-04-26T17:45:14.703Z,1619459114.703 [DataOverHttps](INFO): SBD MOMSN=15604910 2021-04-26T17:45:16.050Z,1619459116.050 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T17:45:16.050Z,1619459116.050 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T17:45:16.050Z,1619459116.050 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T17:46:35.240Z,1619459195.240 [NAL9602](INFO): SBD MO Status=2, MOMSN=41875, MT Status=2, MTMSN=0 2021-04-26T17:46:35.240Z,1619459195.240 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:49:53.615Z,1619459393.615 [NAL9602](INFO): SBD MO Status=2, MOMSN=41875, MT Status=2, MTMSN=0 2021-04-26T17:49:53.615Z,1619459393.615 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-26T17:49:54.023Z,1619459394.023 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-04-26T17:50:16.646Z,1619459416.646 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-26T17:50:16.646Z,1619459416.646 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:50:16.646Z,1619459416.646 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:50:16.648Z,1619459416.648 [Default:CheckIn:D] Running Loop=1 2021-04-26T17:50:17.055Z,1619459417.055 [Default:CheckIn:D] Stopped 2021-04-26T17:50:17.055Z,1619459417.055 [Default:CheckIn:E] Running Loop=1 2021-04-26T17:50:17.459Z,1619459417.459 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.786833 min 2021-04-26T17:50:17.459Z,1619459417.459 [Default:CheckIn:E] Stopped 2021-04-26T17:50:17.460Z,1619459417.460 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-26T17:50:17.460Z,1619459417.460 [Default:CheckIn] Stopped 2021-04-26T17:50:17.460Z,1619459417.460 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:50:17.460Z,1619459417.460 [Default:CheckIn](INFO): Running loop #15 2021-04-26T17:50:17.460Z,1619459417.460 [Default:CheckIn] Running Loop=15 2021-04-26T17:50:17.460Z,1619459417.460 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-26T17:50:17.460Z,1619459417.460 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-26T17:50:19.478Z,1619459419.478 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175018.00,A,3648.16537,N,12147.27897,W,0.350,21.91,260421,,,A*45 2021-04-26T17:50:19.480Z,1619459419.480 [NAL9602](INFO): GPS fix at 20210426T175018: (36.802756, -121.787983) 2021-04-26T17:50:19.489Z,1619459419.489 [Default:CheckIn:Read_GPS] Stopped 2021-04-26T17:50:19.489Z,1619459419.489 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-26T17:50:27.045Z,1619459427.045 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210426T163509/Courier0042.lzma 2021-04-26T17:50:28.047Z,1619459428.047 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Courier0042.lzma.bak 2021-04-26T17:50:28.047Z,1619459428.047 [DataOverHttps](INFO): SBD MOMSN=15604920 2021-04-26T17:50:48.369Z,1619459448.369 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20210426T163509/Express0043.lzma 2021-04-26T17:50:49.371Z,1619459449.371 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163509/Express0043.lzma.bak 2021-04-26T17:50:49.371Z,1619459449.371 [DataOverHttps](INFO): SBD MOMSN=15604923 2021-04-26T17:50:50.665Z,1619459450.665 [Default:CheckIn:Read_Iridium] Stopped 2021-04-26T17:50:50.665Z,1619459450.665 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-26T17:50:50.666Z,1619459450.666 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-26T17:50:52.262Z,1619459452.262 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-26T17:52:21.155Z,1619459541.155 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2021-04-26T17:52:21.155Z,1619459541.155 [DropWeight] Hardware Fault, FailCount= 1 2021-04-26T17:52:21.155Z,1619459541.155 [DropWeight](ERROR): Hardware Fault 2021-04-26T17:52:21.176Z,1619459541.176 [CommandLine](FAULT): Scheduling is paused 2021-04-26T17:52:21.176Z,1619459541.176 [CBIT](INFO): Critical error at 20210426T175221 2021-04-26T17:52:21.179Z,1619459541.179 [CBIT](ERROR): Hardware Fault in component: DropWeight 2021-04-26T17:52:21.179Z,1619459541.179 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2021-04-26T17:52:21.589Z,1619459541.589 [CBIT](INFO): Critical error at 20210426T175221 2021-04-26T17:53:39.858Z,1619459619.858 [CommandLine](IMPORTANT): got command quit 2021-04-26T17:53:40.869Z,1619459620.869 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-26T17:53:40.870Z,1619459620.870 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:40.949Z,1619459620.949 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-04-26T17:53:40.949Z,1619459620.949 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:40.950Z,1619459620.950 [CommandLine](INFO): Join timeout helper Thread ID is 7284 2021-04-26T17:53:40.951Z,1619459620.951 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-04-26T17:53:40.951Z,1619459620.951 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:40.951Z,1619459620.951 [NavChartDb](INFO): Join timeout helper Thread ID is 7285 2021-04-26T17:53:41.293Z,1619459621.293 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-26T17:53:41.294Z,1619459621.294 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:41.301Z,1619459621.301 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-04-26T17:53:41.301Z,1619459621.301 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:41.302Z,1619459621.302 [Radio_Surface](INFO): Join timeout helper Thread ID is 7286 2021-04-26T17:53:41.337Z,1619459621.337 [Radio_Surface](INFO): Powering down 2021-04-26T17:53:41.338Z,1619459621.338 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-26T17:53:41.339Z,1619459621.339 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:41.341Z,1619459621.341 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-04-26T17:53:41.341Z,1619459621.341 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:41.342Z,1619459621.342 [Onboard](INFO): Join timeout helper Thread ID is 7287 2021-04-26T17:53:41.542Z,1619459621.542 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2021-04-26T17:53:44.054Z,1619459624.054 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-26T17:53:44.054Z,1619459624.054 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:44.065Z,1619459624.065 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-04-26T17:53:44.065Z,1619459624.065 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:44.066Z,1619459624.066 [DataOverHttps](INFO): Join timeout helper Thread ID is 7288 2021-04-26T17:53:44.553Z,1619459624.553 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-26T17:53:44.554Z,1619459624.554 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:44.562Z,1619459624.562 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-04-26T17:53:44.562Z,1619459624.562 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:44.563Z,1619459624.563 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7289 2021-04-26T17:53:44.601Z,1619459624.601 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-26T17:53:44.602Z,1619459624.602 [WetLabsBB2FL](INFO): Powering down 2021-04-26T17:53:44.603Z,1619459624.603 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:44.622Z,1619459624.622 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-04-26T17:53:44.622Z,1619459624.622 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:44.622Z,1619459624.622 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7290 2021-04-26T17:53:45.245Z,1619459625.245 [CTD_Seabird](INFO): Powering down 2021-04-26T17:53:45.257Z,1619459625.257 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-26T17:53:45.258Z,1619459625.258 [CTD_Seabird](INFO): Powering down 2021-04-26T17:53:45.269Z,1619459625.269 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:45.282Z,1619459625.282 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-04-26T17:53:45.282Z,1619459625.282 [logger ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:45.283Z,1619459625.283 [logger](INFO): Join timeout helper Thread ID is 7291 2021-04-26T17:53:45.305Z,1619459625.305 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-26T17:53:45.306Z,1619459625.306 [logger ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:45.322Z,1619459625.322 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-04-26T17:53:45.322Z,1619459625.322 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:45.322Z,1619459625.322 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-04-26T17:53:45.322Z,1619459625.322 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:45.323Z,1619459625.323 [controlThread](INFO): Join timeout helper Thread ID is 7292 2021-04-26T17:53:45.569Z,1619459625.569 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-26T17:53:45.570Z,1619459625.570 [controlThread](DEBUG): Uninitializing ControlThread 2021-04-26T17:53:45.572Z,1619459625.572 [AHRS_M2](INFO): Powering down 2021-04-26T17:53:45.642Z,1619459625.642 [NAL9602](INFO): Powering down 2021-04-26T17:53:45.643Z,1619459625.643 [DAT](INFO): Powering down 2021-04-26T17:53:45.762Z,1619459625.762 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-04-26T17:53:45.763Z,1619459625.763 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-04-26T17:53:45.763Z,1619459625.763 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-04-26T17:53:45.764Z,1619459625.764 [MissionManager](INFO): Uninitializing Mission Default 2021-04-26T17:53:45.764Z,1619459625.764 [Default] Stopped 2021-04-26T17:53:45.764Z,1619459625.764 [Default](DEBUG): Aggregate::uninitialize Default 2021-04-26T17:53:45.764Z,1619459625.764 [Default:B.GoToSurface] Stopped 2021-04-26T17:53:45.764Z,1619459625.764 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-04-26T17:53:45.764Z,1619459625.764 [Default:CheckIn] Stopped 2021-04-26T17:53:45.764Z,1619459625.764 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-26T17:53:45.765Z,1619459625.765 [Default:CheckIn:C.Wait] Stopped 2021-04-26T17:53:45.765Z,1619459625.765 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-26T17:53:45.767Z,1619459625.767 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-04-26T17:53:45.768Z,1619459625.768 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-04-26T17:53:45.768Z,1619459625.768 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-04-26T17:53:45.768Z,1619459625.768 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-04-26T17:53:45.769Z,1619459625.769 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-04-26T17:53:45.769Z,1619459625.769 [BuoyancyServo](INFO): Powering down 2021-04-26T17:53:45.781Z,1619459625.781 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-04-26T17:53:45.782Z,1619459625.782 [ElevatorServo](INFO): Powering down 2021-04-26T17:53:45.782Z,1619459625.782 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-04-26T17:53:45.782Z,1619459625.782 [MassServo](INFO): Powering down 2021-04-26T17:53:45.783Z,1619459625.783 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-04-26T17:53:45.783Z,1619459625.783 [RudderServo](INFO): Powering down 2021-04-26T17:53:45.784Z,1619459625.784 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-04-26T17:53:45.784Z,1619459625.784 [ThrusterServo](INFO): Powering down 2021-04-26T17:53:45.785Z,1619459625.785 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-04-26T17:53:45.785Z,1619459625.785 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-04-26T17:53:45.786Z,1619459625.786 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-04-26T17:53:45.786Z,1619459625.786 [CBIT](DEBUG): Powering off loads. 2021-04-26T17:53:45.797Z,1619459625.797 [CBIT](DEBUG): Disabling WDT. 2021-04-26T17:53:45.809Z,1619459625.809 [CBIT](DEBUG): Opening all GF detection circuits. 2021-04-26T17:53:45.810Z,1619459625.810 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:45.850Z,1619459625.850 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:45.935Z,1619459625.935 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:45.971Z,1619459625.971 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:45.973Z,1619459625.973 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:45.979Z,1619459625.979 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:46.064Z,1619459626.064 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-26T17:53:46.145Z,1619459626.145 [logger ThreadHandler](INFO): Thread cancelled.