2022-04-12T20:27:56.668Z,1649795276.668 [CommandExec](IMPORTANT): got command restart logs
2022-04-12T20:27:58.005Z,1649795278.005 [DataOverHttps](INFO): Moved sent file to Logs/20220411T100546/Courier0327.lzma.bak
2022-04-12T20:27:58.005Z,1649795278.005 [DataOverHttps](INFO): SBD MOMSN=16643209
2022-04-12T20:28:21.834Z,1649795301.834 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220411T100546/Courier0330.lzma
2022-04-12T20:28:22.838Z,1649795302.838 [DataOverHttps](INFO): Moved sent file to Logs/20220411T100546/Courier0330.lzma.bak
2022-04-12T20:28:22.838Z,1649795302.838 [DataOverHttps](INFO): SBD MOMSN=16643213
2022-04-12T20:28:24.987Z,1649795304.987 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T20:28:41.307Z,1649795321.307 [DataOverHttps](INFO): Sending 302 bytes from file Logs/20220411T100546/Express0328.lzma
2022-04-12T20:28:42.309Z,1649795322.309 [DataOverHttps](INFO): Moved sent file to Logs/20220411T100546/Express0328.lzma.bak
2022-04-12T20:28:42.309Z,1649795322.309 [DataOverHttps](INFO): SBD MOMSN=16643215
2022-04-12T20:29:00.630Z,1649795340.630 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20220411T100546/Express0331.lzma
2022-04-12T20:29:01.633Z,1649795341.633 [DataOverHttps](INFO): Moved sent file to Logs/20220411T100546/Express0331.lzma.bak
2022-04-12T20:29:01.633Z,1649795341.633 [DataOverHttps](INFO): SBD MOMSN=16643220
2022-04-12T20:29:05.039Z,1649795345.039 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T20:29:05.039Z,1649795345.039 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T20:29:05.039Z,1649795345.039 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T20:34:05.585Z,1649795645.585 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T20:34:05.585Z,1649795645.585 [Default:CheckIn:C.Wait] Stopped
2022-04-12T20:34:05.585Z,1649795645.585 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T20:34:05.586Z,1649795645.586 [Default:CheckIn:D] Running Loop=1
2022-04-12T20:34:06.032Z,1649795646.032 [Default:CheckIn:D] Stopped
2022-04-12T20:34:06.032Z,1649795646.032 [Default:CheckIn:E] Running Loop=1
2022-04-12T20:34:06.396Z,1649795646.396 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.738515 min 
2022-04-12T20:34:06.396Z,1649795646.396 [Default:CheckIn:E] Stopped
2022-04-12T20:34:06.397Z,1649795646.397 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T20:34:06.397Z,1649795646.397 [Default:CheckIn] Stopped
2022-04-12T20:34:06.397Z,1649795646.397 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T20:34:06.397Z,1649795646.397 [Default:CheckIn](INFO): Running loop #6
2022-04-12T20:34:06.397Z,1649795646.397 [Default:CheckIn] Running Loop=6
2022-04-12T20:34:06.397Z,1649795646.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T20:34:06.397Z,1649795646.397 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T20:34:08.388Z,1649795648.388 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203406.00,A,3648.17929,N,12147.18026,W,3.032,210.29,120422,,,D*76
2022-04-12T20:34:08.390Z,1649795648.390 [NAL9602](INFO): GPS fix at 20220412T203406: (36.802988, -121.786338)
2022-04-12T20:34:08.402Z,1649795648.402 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T20:34:08.402Z,1649795648.402 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T20:34:17.023Z,1649795657.023 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20220412T202756/Courier0003.lzma
2022-04-12T20:34:18.024Z,1649795658.024 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0003.lzma.bak
2022-04-12T20:34:18.025Z,1649795658.025 [DataOverHttps](INFO): SBD MOMSN=16643232
2022-04-12T20:34:21.765Z,1649795661.765 [NAL9602](INFO): SBD MO Status=1, MOMSN=6709, MT Status=0, MTMSN=0
2022-04-12T20:34:21.765Z,1649795661.765 [NAL9602](INFO): No messages in MT queue
2022-04-12T20:34:36.511Z,1649795676.511 [DataOverHttps](INFO): Sending 379 bytes from file Logs/20220412T202756/Express0004.lzma
2022-04-12T20:34:37.512Z,1649795677.512 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0004.lzma.bak
2022-04-12T20:34:37.513Z,1649795677.513 [DataOverHttps](INFO): SBD MOMSN=16643236
2022-04-12T20:34:41.184Z,1649795681.184 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T20:34:41.185Z,1649795681.185 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T20:34:41.185Z,1649795681.185 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T20:34:52.466Z,1649795692.466 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T20:39:41.745Z,1649795981.745 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T20:39:41.745Z,1649795981.745 [Default:CheckIn:C.Wait] Stopped
2022-04-12T20:39:41.745Z,1649795981.745 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T20:39:41.746Z,1649795981.746 [Default:CheckIn:D] Running Loop=1
2022-04-12T20:39:42.152Z,1649795982.152 [Default:CheckIn:D] Stopped
2022-04-12T20:39:42.152Z,1649795982.152 [Default:CheckIn:E] Running Loop=1
2022-04-12T20:39:42.547Z,1649795982.547 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.340515 min 
2022-04-12T20:39:42.547Z,1649795982.547 [Default:CheckIn:E] Stopped
2022-04-12T20:39:42.547Z,1649795982.547 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T20:39:42.547Z,1649795982.547 [Default:CheckIn] Stopped
2022-04-12T20:39:42.547Z,1649795982.547 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T20:39:42.547Z,1649795982.547 [Default:CheckIn](INFO): Running loop #7
2022-04-12T20:39:42.547Z,1649795982.547 [Default:CheckIn] Running Loop=7
2022-04-12T20:39:42.547Z,1649795982.547 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T20:39:42.547Z,1649795982.547 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T20:39:44.574Z,1649795984.574 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203943.00,A,3648.16546,N,12147.21681,W,0.039,327.38,120422,,,D*72
2022-04-12T20:39:44.577Z,1649795984.577 [NAL9602](INFO): GPS fix at 20220412T203943: (36.802758, -121.786947)
2022-04-12T20:39:44.598Z,1649795984.598 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T20:39:44.598Z,1649795984.598 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T20:39:58.810Z,1649795998.810 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0006.lzma
2022-04-12T20:39:59.813Z,1649795999.813 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0006.lzma.bak
2022-04-12T20:39:59.813Z,1649795999.813 [DataOverHttps](INFO): SBD MOMSN=16643242
2022-04-12T20:40:05.681Z,1649796005.681 [NAL9602](INFO): SBD MO Status=1, MOMSN=6710, MT Status=0, MTMSN=0
2022-04-12T20:40:05.681Z,1649796005.681 [NAL9602](INFO): No messages in MT queue
2022-04-12T20:40:18.287Z,1649796018.287 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220412T202756/Express0007.lzma
2022-04-12T20:40:19.288Z,1649796019.288 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0007.lzma.bak
2022-04-12T20:40:19.288Z,1649796019.288 [DataOverHttps](INFO): SBD MOMSN=16643246
2022-04-12T20:40:22.691Z,1649796022.691 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T20:40:22.692Z,1649796022.692 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T20:40:22.692Z,1649796022.692 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T20:40:36.407Z,1649796036.407 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T20:45:23.323Z,1649796323.323 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T20:45:23.323Z,1649796323.323 [Default:CheckIn:C.Wait] Stopped
2022-04-12T20:45:23.323Z,1649796323.323 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T20:45:23.323Z,1649796323.323 [Default:CheckIn:D] Running Loop=1
2022-04-12T20:45:23.740Z,1649796323.740 [Default:CheckIn:D] Stopped
2022-04-12T20:45:23.740Z,1649796323.740 [Default:CheckIn:E] Running Loop=1
2022-04-12T20:45:24.133Z,1649796324.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.033643 min 
2022-04-12T20:45:24.133Z,1649796324.133 [Default:CheckIn:E] Stopped
2022-04-12T20:45:24.133Z,1649796324.133 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T20:45:24.133Z,1649796324.133 [Default:CheckIn] Stopped
2022-04-12T20:45:24.133Z,1649796324.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T20:45:24.133Z,1649796324.133 [Default:CheckIn](INFO): Running loop #8
2022-04-12T20:45:24.134Z,1649796324.134 [Default:CheckIn] Running Loop=8
2022-04-12T20:45:24.134Z,1649796324.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T20:45:24.134Z,1649796324.134 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T20:45:26.144Z,1649796326.144 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204524.00,A,3648.15760,N,12147.21874,W,0.078,215.78,120422,,,D*78
2022-04-12T20:45:26.146Z,1649796326.146 [NAL9602](INFO): GPS fix at 20220412T204524: (36.802627, -121.786979)
2022-04-12T20:45:26.157Z,1649796326.157 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T20:45:26.157Z,1649796326.157 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T20:45:35.190Z,1649796335.190 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220412T202756/Courier0009.lzma
2022-04-12T20:45:36.192Z,1649796336.192 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0009.lzma.bak
2022-04-12T20:45:36.192Z,1649796336.192 [DataOverHttps](INFO): SBD MOMSN=16643277
2022-04-12T20:45:43.972Z,1649796343.972 [NAL9602](INFO): SBD MO Status=1, MOMSN=6711, MT Status=0, MTMSN=0
2022-04-12T20:45:43.972Z,1649796343.972 [NAL9602](INFO): No messages in MT queue
2022-04-12T20:46:01.066Z,1649796361.066 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220412T202756/Express0010.lzma
2022-04-12T20:46:02.072Z,1649796362.072 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0010.lzma.bak
2022-04-12T20:46:02.072Z,1649796362.072 [DataOverHttps](INFO): SBD MOMSN=16643282
2022-04-12T20:46:05.449Z,1649796365.449 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T20:46:05.449Z,1649796365.449 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T20:46:05.449Z,1649796365.449 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T20:46:14.692Z,1649796374.692 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T20:51:05.988Z,1649796665.988 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T20:51:05.988Z,1649796665.988 [Default:CheckIn:C.Wait] Stopped
2022-04-12T20:51:05.988Z,1649796665.988 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T20:51:05.988Z,1649796665.988 [Default:CheckIn:D] Running Loop=1
2022-04-12T20:51:06.394Z,1649796666.394 [Default:CheckIn:D] Stopped
2022-04-12T20:51:06.394Z,1649796666.394 [Default:CheckIn:E] Running Loop=1
2022-04-12T20:51:06.801Z,1649796666.801 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.744543 min 
2022-04-12T20:51:06.801Z,1649796666.801 [Default:CheckIn:E] Stopped
2022-04-12T20:51:06.801Z,1649796666.801 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T20:51:06.801Z,1649796666.801 [Default:CheckIn] Stopped
2022-04-12T20:51:06.801Z,1649796666.801 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T20:51:06.801Z,1649796666.801 [Default:CheckIn](INFO): Running loop #9
2022-04-12T20:51:06.801Z,1649796666.801 [Default:CheckIn] Running Loop=9
2022-04-12T20:51:06.801Z,1649796666.801 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T20:51:06.801Z,1649796666.801 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T20:51:08.804Z,1649796668.804 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205107.00,A,3648.15727,N,12147.21788,W,0.253,120.79,120422,,,D*7C
2022-04-12T20:51:08.806Z,1649796668.806 [NAL9602](INFO): GPS fix at 20220412T205107: (36.802621, -121.786965)
2022-04-12T20:51:08.859Z,1649796668.859 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T20:51:08.859Z,1649796668.859 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T20:51:17.754Z,1649796677.754 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0012.lzma
2022-04-12T20:51:18.757Z,1649796678.757 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0012.lzma.bak
2022-04-12T20:51:18.757Z,1649796678.757 [DataOverHttps](INFO): SBD MOMSN=16643291
2022-04-12T20:51:37.726Z,1649796697.726 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220412T202756/Express0013.lzma
2022-04-12T20:51:38.728Z,1649796698.728 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0013.lzma.bak
2022-04-12T20:51:38.729Z,1649796698.729 [DataOverHttps](INFO): SBD MOMSN=16643294
2022-04-12T20:51:42.121Z,1649796702.121 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T20:51:42.121Z,1649796702.121 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T20:51:42.121Z,1649796702.121 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T20:51:46.109Z,1649796706.109 [NAL9602](INFO): SBD MO Status=2, MOMSN=6712, MT Status=2, MTMSN=0
2022-04-12T20:51:46.109Z,1649796706.109 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-12T20:52:12.376Z,1649796732.376 [NAL9602](INFO): SBD MO Status=1, MOMSN=6712, MT Status=0, MTMSN=0
2022-04-12T20:52:12.376Z,1649796732.376 [NAL9602](INFO): No messages in MT queue
2022-04-12T20:52:43.080Z,1649796763.080 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T20:56:42.665Z,1649797002.665 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T20:56:42.665Z,1649797002.665 [Default:CheckIn:C.Wait] Stopped
2022-04-12T20:56:42.665Z,1649797002.665 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T20:56:42.665Z,1649797002.665 [Default:CheckIn:D] Running Loop=1
2022-04-12T20:56:43.065Z,1649797003.065 [Default:CheckIn:D] Stopped
2022-04-12T20:56:43.065Z,1649797003.065 [Default:CheckIn:E] Running Loop=1
2022-04-12T20:56:43.469Z,1649797003.469 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.355729 min 
2022-04-12T20:56:43.469Z,1649797003.469 [Default:CheckIn:E] Stopped
2022-04-12T20:56:43.469Z,1649797003.469 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T20:56:43.469Z,1649797003.469 [Default:CheckIn] Stopped
2022-04-12T20:56:43.469Z,1649797003.469 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T20:56:43.469Z,1649797003.469 [Default:CheckIn](INFO): Running loop #10
2022-04-12T20:56:43.469Z,1649797003.469 [Default:CheckIn] Running Loop=10
2022-04-12T20:56:43.469Z,1649797003.469 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T20:56:43.469Z,1649797003.469 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T20:56:45.477Z,1649797005.477 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205643.00,A,3648.14318,N,12147.23873,W,0.544,261.40,120422,,,D*76
2022-04-12T20:56:45.480Z,1649797005.480 [NAL9602](INFO): GPS fix at 20220412T205643: (36.802386, -121.787312)
2022-04-12T20:56:45.512Z,1649797005.512 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T20:56:45.512Z,1649797005.512 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T20:56:54.686Z,1649797014.686 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0015.lzma
2022-04-12T20:56:55.688Z,1649797015.688 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0015.lzma.bak
2022-04-12T20:56:55.689Z,1649797015.689 [DataOverHttps](INFO): SBD MOMSN=16643299
2022-04-12T20:57:14.178Z,1649797034.178 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220412T202756/Express0016.lzma
2022-04-12T20:57:15.180Z,1649797035.180 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0016.lzma.bak
2022-04-12T20:57:15.181Z,1649797035.181 [DataOverHttps](INFO): SBD MOMSN=16643302
2022-04-12T20:57:18.622Z,1649797038.622 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T20:57:18.622Z,1649797038.622 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T20:57:18.622Z,1649797038.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T20:57:35.973Z,1649797055.973 [NAL9602](INFO): SBD MO Status=1, MOMSN=6713, MT Status=0, MTMSN=0
2022-04-12T20:57:35.973Z,1649797055.973 [NAL9602](INFO): No messages in MT queue
2022-04-12T20:58:06.679Z,1649797086.679 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:02:19.186Z,1649797339.186 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:02:19.186Z,1649797339.186 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:02:19.186Z,1649797339.186 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:02:19.188Z,1649797339.188 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:02:19.602Z,1649797339.602 [Default:CheckIn:D] Stopped
2022-04-12T21:02:19.602Z,1649797339.602 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:02:20.004Z,1649797340.004 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.964681 min 
2022-04-12T21:02:20.004Z,1649797340.004 [Default:CheckIn:E] Stopped
2022-04-12T21:02:20.004Z,1649797340.004 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:02:20.004Z,1649797340.004 [Default:CheckIn] Stopped
2022-04-12T21:02:20.005Z,1649797340.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:02:20.005Z,1649797340.005 [Default:CheckIn](INFO): Running loop #11
2022-04-12T21:02:20.005Z,1649797340.005 [Default:CheckIn] Running Loop=11
2022-04-12T21:02:20.005Z,1649797340.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:02:20.005Z,1649797340.005 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:02:22.004Z,1649797342.004 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210220.00,A,3648.16801,N,12147.27428,W,0.330,181.01,120422,,,D*79
2022-04-12T21:02:22.006Z,1649797342.006 [NAL9602](INFO): GPS fix at 20220412T210220: (36.802800, -121.787905)
2022-04-12T21:02:22.034Z,1649797342.034 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:02:22.034Z,1649797342.034 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:02:30.606Z,1649797350.606 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0018.lzma
2022-04-12T21:02:31.609Z,1649797351.609 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0018.lzma.bak
2022-04-12T21:02:31.609Z,1649797351.609 [DataOverHttps](INFO): SBD MOMSN=16643311
2022-04-12T21:02:49.986Z,1649797369.986 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220412T202756/Express0019.lzma
2022-04-12T21:02:50.988Z,1649797370.988 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0019.lzma.bak
2022-04-12T21:02:50.989Z,1649797370.989 [DataOverHttps](INFO): SBD MOMSN=16643314
2022-04-12T21:02:54.364Z,1649797374.364 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:02:54.365Z,1649797374.365 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:02:54.365Z,1649797374.365 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T21:07:24.202Z,1649797644.202 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T21:07:54.903Z,1649797674.903 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:07:54.941Z,1649797674.941 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:07:54.941Z,1649797674.941 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:07:54.941Z,1649797674.941 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:07:54.941Z,1649797674.941 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:07:55.329Z,1649797675.329 [Default:CheckIn:D] Stopped
2022-04-12T21:07:55.329Z,1649797675.329 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:07:55.724Z,1649797675.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.560128 min 
2022-04-12T21:07:55.724Z,1649797675.724 [Default:CheckIn:E] Stopped
2022-04-12T21:07:55.724Z,1649797675.724 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:07:55.724Z,1649797675.724 [Default:CheckIn] Stopped
2022-04-12T21:07:55.724Z,1649797675.724 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:07:55.724Z,1649797675.724 [Default:CheckIn](INFO): Running loop #12
2022-04-12T21:07:55.724Z,1649797675.724 [Default:CheckIn] Running Loop=12
2022-04-12T21:07:55.724Z,1649797675.724 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:07:55.725Z,1649797675.725 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:07:57.732Z,1649797677.732 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210756.00,A,3648.17246,N,12147.27525,W,0.019,120.98,120422,,,A*7F
2022-04-12T21:07:57.734Z,1649797677.734 [NAL9602](INFO): GPS fix at 20220412T210756: (36.802874, -121.787921)
2022-04-12T21:07:57.762Z,1649797677.762 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:07:57.762Z,1649797677.762 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:08:06.390Z,1649797686.390 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220412T202756/Courier0021.lzma
2022-04-12T21:08:07.392Z,1649797687.392 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0021.lzma.bak
2022-04-12T21:08:07.393Z,1649797687.393 [DataOverHttps](INFO): SBD MOMSN=16643322
2022-04-12T21:08:25.963Z,1649797705.963 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20220412T202756/Express0022.lzma
2022-04-12T21:08:26.964Z,1649797706.964 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0022.lzma.bak
2022-04-12T21:08:26.965Z,1649797706.965 [DataOverHttps](INFO): SBD MOMSN=16643325
2022-04-12T21:08:28.497Z,1649797708.497 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-04-12T21:08:28.579Z,1649797708.579 [NAL9602](ERROR): received: 
+CSQ:0

OK13, 0, 0, 0, 0

OK
2022-04-12T21:08:30.541Z,1649797710.541 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:08:30.541Z,1649797710.541 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:08:30.541Z,1649797710.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T21:11:01.199Z,1649797861.199 [NAL9602](INFO): SBD MO Status=2, MOMSN=6714, MT Status=2, MTMSN=0
2022-04-12T21:11:01.199Z,1649797861.199 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-12T21:12:59.966Z,1649797979.966 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T21:13:30.682Z,1649798010.682 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:13:31.116Z,1649798011.116 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:13:31.117Z,1649798011.117 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:13:31.117Z,1649798011.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:13:31.117Z,1649798011.117 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:13:31.508Z,1649798011.508 [Default:CheckIn:D] Stopped
2022-04-12T21:13:31.508Z,1649798011.508 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:13:31.893Z,1649798011.893 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.163110 min 
2022-04-12T21:13:31.893Z,1649798011.893 [Default:CheckIn:E] Stopped
2022-04-12T21:13:31.893Z,1649798011.893 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:13:31.893Z,1649798011.893 [Default:CheckIn] Stopped
2022-04-12T21:13:31.893Z,1649798011.893 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:13:31.893Z,1649798011.893 [Default:CheckIn](INFO): Running loop #13
2022-04-12T21:13:31.894Z,1649798011.894 [Default:CheckIn] Running Loop=13
2022-04-12T21:13:31.894Z,1649798011.894 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:13:31.894Z,1649798011.894 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:13:33.904Z,1649798013.904 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211332.00,A,3648.17273,N,12147.28523,W,1.769,321.39,120422,,,A*7E
2022-04-12T21:13:33.906Z,1649798013.906 [NAL9602](INFO): GPS fix at 20220412T211332: (36.802879, -121.788087)
2022-04-12T21:13:33.918Z,1649798013.918 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:13:33.918Z,1649798013.918 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:13:42.670Z,1649798022.670 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220412T202756/Courier0024.lzma
2022-04-12T21:13:43.672Z,1649798023.672 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0024.lzma.bak
2022-04-12T21:13:43.673Z,1649798023.673 [DataOverHttps](INFO): SBD MOMSN=16643329
2022-04-12T21:14:02.990Z,1649798042.990 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20220412T202756/Express0025.lzma
2022-04-12T21:14:03.992Z,1649798043.992 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0025.lzma.bak
2022-04-12T21:14:03.993Z,1649798043.993 [DataOverHttps](INFO): SBD MOMSN=16643332
2022-04-12T21:14:04.636Z,1649798044.636 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-04-12T21:14:04.719Z,1649798044.719 [NAL9602](ERROR): received: 
+CSQ:0

OK14, 2, 0, 0, 0

OK
2022-04-12T21:14:07.483Z,1649798047.483 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:14:07.484Z,1649798047.484 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:14:07.484Z,1649798047.484 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T21:18:36.120Z,1649798316.120 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T21:19:06.823Z,1649798346.823 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:19:08.069Z,1649798348.069 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:19:08.069Z,1649798348.069 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:19:08.069Z,1649798348.069 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:19:08.069Z,1649798348.069 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:19:08.453Z,1649798348.453 [Default:CheckIn:D] Stopped
2022-04-12T21:19:08.453Z,1649798348.453 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:19:08.859Z,1649798348.859 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.778857 min 
2022-04-12T21:19:08.859Z,1649798348.859 [Default:CheckIn:E] Stopped
2022-04-12T21:19:08.859Z,1649798348.859 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:19:08.859Z,1649798348.859 [Default:CheckIn] Stopped
2022-04-12T21:19:08.859Z,1649798348.859 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:19:08.860Z,1649798348.860 [Default:CheckIn](INFO): Running loop #14
2022-04-12T21:19:08.860Z,1649798348.860 [Default:CheckIn] Running Loop=14
2022-04-12T21:19:08.860Z,1649798348.860 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:19:08.860Z,1649798348.860 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:19:10.860Z,1649798350.860 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211909.00,A,3648.16161,N,12147.27446,W,0.156,95.04,120422,,,A*49
2022-04-12T21:19:10.862Z,1649798350.862 [NAL9602](INFO): GPS fix at 20220412T211909: (36.802693, -121.787908)
2022-04-12T21:19:10.889Z,1649798350.889 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:19:10.889Z,1649798350.889 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:19:19.426Z,1649798359.426 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0027.lzma
2022-04-12T21:19:20.428Z,1649798360.428 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0027.lzma.bak
2022-04-12T21:19:20.429Z,1649798360.429 [DataOverHttps](INFO): SBD MOMSN=16643336
2022-04-12T21:19:39.538Z,1649798379.538 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20220412T202756/Express0028.lzma
2022-04-12T21:19:40.540Z,1649798380.540 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0028.lzma.bak
2022-04-12T21:19:40.541Z,1649798380.541 [DataOverHttps](INFO): SBD MOMSN=16643339
2022-04-12T21:19:41.580Z,1649798381.580 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-04-12T21:19:41.663Z,1649798381.663 [NAL9602](ERROR): received: 
+CSQ:0

OK14, 2, 0, 0, 0

OK
2022-04-12T21:19:44.018Z,1649798384.018 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:19:44.018Z,1649798384.018 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:19:44.018Z,1649798384.018 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T21:20:00.558Z,1649798400.558 [NAL9602](INFO): SBD MO Status=1, MOMSN=6714, MT Status=0, MTMSN=0
2022-04-12T21:20:00.558Z,1649798400.558 [NAL9602](INFO): No messages in MT queue
2022-04-12T21:20:31.258Z,1649798431.258 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:24:44.594Z,1649798684.594 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:24:44.594Z,1649798684.594 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:24:44.594Z,1649798684.594 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:24:44.594Z,1649798684.594 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:24:45.001Z,1649798685.001 [Default:CheckIn:D] Stopped
2022-04-12T21:24:45.001Z,1649798685.001 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:24:45.389Z,1649798685.389 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.387988 min 
2022-04-12T21:24:45.389Z,1649798685.389 [Default:CheckIn:E] Stopped
2022-04-12T21:24:45.389Z,1649798685.389 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:24:45.389Z,1649798685.389 [Default:CheckIn] Stopped
2022-04-12T21:24:45.389Z,1649798685.389 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:24:45.389Z,1649798685.389 [Default:CheckIn](INFO): Running loop #15
2022-04-12T21:24:45.389Z,1649798685.389 [Default:CheckIn] Running Loop=15
2022-04-12T21:24:45.390Z,1649798685.390 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:24:45.390Z,1649798685.390 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:24:47.401Z,1649798687.401 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212445.00,A,3648.17545,N,12147.27705,W,0.311,355.83,120422,,,A*79
2022-04-12T21:24:47.404Z,1649798687.404 [NAL9602](INFO): GPS fix at 20220412T212445: (36.802924, -121.787951)
2022-04-12T21:24:47.415Z,1649798687.415 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:24:47.415Z,1649798687.415 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:24:56.442Z,1649798696.442 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0030.lzma
2022-04-12T21:24:57.444Z,1649798697.444 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0030.lzma.bak
2022-04-12T21:24:57.445Z,1649798697.445 [DataOverHttps](INFO): SBD MOMSN=16643345
2022-04-12T21:25:15.882Z,1649798715.882 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220412T202756/Express0031.lzma
2022-04-12T21:25:17.261Z,1649798717.261 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0031.lzma.bak
2022-04-12T21:25:17.261Z,1649798717.261 [DataOverHttps](INFO): SBD MOMSN=16643348
2022-04-12T21:25:20.187Z,1649798720.187 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:25:20.187Z,1649798720.187 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:25:20.187Z,1649798720.187 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T21:29:01.517Z,1649798941.517 [NAL9602](INFO): SBD MO Status=2, MOMSN=6715, MT Status=2, MTMSN=0
2022-04-12T21:29:01.517Z,1649798941.517 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-12T21:29:30.646Z,1649798970.646 [NAL9602](INFO): SBD MO Status=1, MOMSN=6715, MT Status=0, MTMSN=0
2022-04-12T21:29:30.646Z,1649798970.646 [NAL9602](INFO): No messages in MT queue
2022-04-12T21:30:01.336Z,1649799001.336 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:30:20.764Z,1649799020.764 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:30:20.764Z,1649799020.764 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:30:20.764Z,1649799020.764 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:30:20.765Z,1649799020.765 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:30:21.176Z,1649799021.176 [Default:CheckIn:D] Stopped
2022-04-12T21:30:21.176Z,1649799021.176 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:30:21.566Z,1649799021.566 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.990918 min 
2022-04-12T21:30:21.566Z,1649799021.566 [Default:CheckIn:E] Stopped
2022-04-12T21:30:21.566Z,1649799021.566 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:30:21.566Z,1649799021.566 [Default:CheckIn] Stopped
2022-04-12T21:30:21.566Z,1649799021.566 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:30:21.591Z,1649799021.591 [Default:CheckIn](INFO): Running loop #16
2022-04-12T21:30:21.591Z,1649799021.591 [Default:CheckIn] Running Loop=16
2022-04-12T21:30:21.591Z,1649799021.591 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:30:21.591Z,1649799021.591 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:30:23.555Z,1649799023.555 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213022.00,A,3648.18133,N,12147.29114,W,2.041,316.52,120422,,,A*70
2022-04-12T21:30:23.558Z,1649799023.558 [NAL9602](INFO): GPS fix at 20220412T213022: (36.803022, -121.788186)
2022-04-12T21:30:23.569Z,1649799023.569 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:30:23.569Z,1649799023.569 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:30:32.828Z,1649799032.828 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0033.lzma
2022-04-12T21:30:33.824Z,1649799033.824 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0033.lzma.bak
2022-04-12T21:30:33.825Z,1649799033.825 [DataOverHttps](INFO): SBD MOMSN=16643351
2022-04-12T21:30:39.742Z,1649799039.742 [NAL9602](INFO): SBD MO Status=1, MOMSN=6716, MT Status=0, MTMSN=0
2022-04-12T21:30:39.742Z,1649799039.742 [NAL9602](INFO): No messages in MT queue
2022-04-12T21:30:52.450Z,1649799052.450 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220412T202756/Express0034.lzma
2022-04-12T21:30:53.452Z,1649799053.452 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0034.lzma.bak
2022-04-12T21:30:53.453Z,1649799053.453 [DataOverHttps](INFO): SBD MOMSN=16643354
2022-04-12T21:30:57.108Z,1649799057.108 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:30:57.108Z,1649799057.108 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:30:57.109Z,1649799057.109 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T21:31:10.447Z,1649799070.447 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:34:31.213Z,1649799271.213 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-04-12T21:34:31.216Z,1649799271.216 [BPC1](INFO): Received data from all battery sticks.
2022-04-12T21:35:57.724Z,1649799357.724 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:35:57.724Z,1649799357.724 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:35:57.724Z,1649799357.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:35:57.724Z,1649799357.724 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:35:58.102Z,1649799358.102 [Default:CheckIn:D] Stopped
2022-04-12T21:35:58.102Z,1649799358.102 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:35:58.510Z,1649799358.510 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.606348 min 
2022-04-12T21:35:58.511Z,1649799358.511 [Default:CheckIn:E] Stopped
2022-04-12T21:35:58.511Z,1649799358.511 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:35:58.511Z,1649799358.511 [Default:CheckIn] Stopped
2022-04-12T21:35:58.511Z,1649799358.511 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:35:58.511Z,1649799358.511 [Default:CheckIn](INFO): Running loop #17
2022-04-12T21:35:58.511Z,1649799358.511 [Default:CheckIn] Running Loop=17
2022-04-12T21:35:58.511Z,1649799358.511 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:35:58.511Z,1649799358.511 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:36:00.492Z,1649799360.492 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213558.00,A,3648.19046,N,12147.30626,W,1.225,313.61,120422,,,A*72
2022-04-12T21:36:00.495Z,1649799360.495 [NAL9602](INFO): GPS fix at 20220412T213558: (36.803174, -121.788438)
2022-04-12T21:36:00.506Z,1649799360.506 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:36:00.506Z,1649799360.506 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:36:09.526Z,1649799369.526 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20220412T202756/Courier0036.lzma
2022-04-12T21:36:10.528Z,1649799370.528 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0036.lzma.bak
2022-04-12T21:36:10.529Z,1649799370.529 [DataOverHttps](INFO): SBD MOMSN=16643376
2022-04-12T21:36:29.031Z,1649799389.031 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220412T202756/Express0037.lzma
2022-04-12T21:36:30.033Z,1649799390.033 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0037.lzma.bak
2022-04-12T21:36:30.033Z,1649799390.033 [DataOverHttps](INFO): SBD MOMSN=16643379
2022-04-12T21:36:33.645Z,1649799393.645 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:36:33.645Z,1649799393.645 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:36:33.645Z,1649799393.645 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T21:36:49.382Z,1649799409.382 [NAL9602](INFO): SBD MO Status=1, MOMSN=6717, MT Status=0, MTMSN=0
2022-04-12T21:36:49.382Z,1649799409.382 [NAL9602](INFO): No messages in MT queue
2022-04-12T21:37:20.086Z,1649799440.086 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:41:34.212Z,1649799694.212 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:41:34.212Z,1649799694.212 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:41:34.212Z,1649799694.212 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:41:34.212Z,1649799694.212 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:41:34.605Z,1649799694.605 [Default:CheckIn:D] Stopped
2022-04-12T21:41:34.605Z,1649799694.605 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:41:35.008Z,1649799695.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.214722 min 
2022-04-12T21:41:35.008Z,1649799695.008 [Default:CheckIn:E] Stopped
2022-04-12T21:41:35.008Z,1649799695.008 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:41:35.008Z,1649799695.008 [Default:CheckIn] Stopped
2022-04-12T21:41:35.008Z,1649799695.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:41:35.008Z,1649799695.008 [Default:CheckIn](INFO): Running loop #18
2022-04-12T21:41:35.009Z,1649799695.009 [Default:CheckIn] Running Loop=18
2022-04-12T21:41:35.009Z,1649799695.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:41:35.009Z,1649799695.009 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:41:37.025Z,1649799697.025 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214135.00,A,3648.16941,N,12147.29016,W,0.389,326.26,120422,,,A*75
2022-04-12T21:41:37.028Z,1649799697.028 [NAL9602](INFO): GPS fix at 20220412T214135: (36.802824, -121.788169)
2022-04-12T21:41:37.039Z,1649799697.039 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:41:37.039Z,1649799697.039 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:41:45.771Z,1649799705.771 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0039.lzma
2022-04-12T21:41:46.773Z,1649799706.773 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0039.lzma.bak
2022-04-12T21:41:46.773Z,1649799706.773 [DataOverHttps](INFO): SBD MOMSN=16643392
2022-04-12T21:42:05.046Z,1649799725.046 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220412T202756/Express0040.lzma
2022-04-12T21:42:06.049Z,1649799726.049 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0040.lzma.bak
2022-04-12T21:42:06.049Z,1649799726.049 [DataOverHttps](INFO): SBD MOMSN=16643400
2022-04-12T21:42:09.813Z,1649799729.813 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:42:09.813Z,1649799729.813 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:42:09.813Z,1649799729.813 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T21:46:39.238Z,1649799999.238 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T21:47:09.949Z,1649800029.949 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:47:10.380Z,1649800030.380 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:47:10.380Z,1649800030.380 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:47:10.380Z,1649800030.380 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:47:10.380Z,1649800030.380 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:47:10.762Z,1649800030.762 [Default:CheckIn:D] Stopped
2022-04-12T21:47:10.762Z,1649800030.762 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:47:11.174Z,1649800031.174 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.817342 min 
2022-04-12T21:47:11.174Z,1649800031.174 [Default:CheckIn:E] Stopped
2022-04-12T21:47:11.175Z,1649800031.175 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:47:11.175Z,1649800031.175 [Default:CheckIn] Stopped
2022-04-12T21:47:11.175Z,1649800031.175 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:47:11.175Z,1649800031.175 [Default:CheckIn](INFO): Running loop #19
2022-04-12T21:47:11.175Z,1649800031.175 [Default:CheckIn] Running Loop=19
2022-04-12T21:47:11.175Z,1649800031.175 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:47:11.175Z,1649800031.175 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:47:13.184Z,1649800033.184 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214711.00,A,3648.17702,N,12147.29663,W,0.972,134.22,120422,,,A*72
2022-04-12T21:47:13.186Z,1649800033.186 [NAL9602](INFO): GPS fix at 20220412T214711: (36.802950, -121.788277)
2022-04-12T21:47:13.197Z,1649800033.197 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:47:13.198Z,1649800033.198 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:47:21.782Z,1649800041.782 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0042.lzma
2022-04-12T21:47:22.784Z,1649800042.784 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0042.lzma.bak
2022-04-12T21:47:22.785Z,1649800042.785 [DataOverHttps](INFO): SBD MOMSN=16643477
2022-04-12T21:47:42.003Z,1649800062.003 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20220412T202756/Express0043.lzma
2022-04-12T21:47:43.005Z,1649800063.005 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0043.lzma.bak
2022-04-12T21:47:43.005Z,1649800063.005 [DataOverHttps](INFO): SBD MOMSN=16643483
2022-04-12T21:47:43.910Z,1649800063.910 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-04-12T21:47:43.991Z,1649800063.991 [NAL9602](ERROR): received: 
+CSQ:0

OK17, 0, 0, 0, 0

OK
2022-04-12T21:47:46.356Z,1649800066.356 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:47:46.357Z,1649800066.357 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:47:46.357Z,1649800066.357 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T21:52:14.994Z,1649800334.994 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T21:52:45.695Z,1649800365.695 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:52:46.933Z,1649800366.933 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:52:46.933Z,1649800366.933 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:52:46.933Z,1649800366.933 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:52:46.933Z,1649800366.933 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:52:47.333Z,1649800367.333 [Default:CheckIn:D] Stopped
2022-04-12T21:52:47.333Z,1649800367.333 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:52:47.736Z,1649800367.736 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.426855 min 
2022-04-12T21:52:47.736Z,1649800367.736 [Default:CheckIn:E] Stopped
2022-04-12T21:52:47.736Z,1649800367.736 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:52:47.736Z,1649800367.736 [Default:CheckIn] Stopped
2022-04-12T21:52:47.736Z,1649800367.736 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:52:47.736Z,1649800367.736 [Default:CheckIn](INFO): Running loop #20
2022-04-12T21:52:47.736Z,1649800367.736 [Default:CheckIn] Running Loop=20
2022-04-12T21:52:47.736Z,1649800367.736 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:52:47.737Z,1649800367.737 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:52:49.744Z,1649800369.744 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215248.00,A,3648.16499,N,12147.28055,W,0.292,136.96,120422,,,A*70
2022-04-12T21:52:49.746Z,1649800369.746 [NAL9602](INFO): GPS fix at 20220412T215248: (36.802750, -121.788009)
2022-04-12T21:52:49.774Z,1649800369.774 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:52:49.774Z,1649800369.774 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:52:58.459Z,1649800378.459 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220412T202756/Courier0045.lzma
2022-04-12T21:52:59.460Z,1649800379.460 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0045.lzma.bak
2022-04-12T21:52:59.461Z,1649800379.461 [DataOverHttps](INFO): SBD MOMSN=16643501
2022-04-12T21:53:18.250Z,1649800398.250 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20220412T202756/Express0046.lzma
2022-04-12T21:53:19.253Z,1649800399.253 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0046.lzma.bak
2022-04-12T21:53:19.253Z,1649800399.253 [DataOverHttps](INFO): SBD MOMSN=16643507
2022-04-12T21:53:20.508Z,1649800400.508 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-04-12T21:53:20.587Z,1649800400.587 [NAL9602](ERROR): received: 
+CSQ:0

OK17, 0, 0, 0, 0

OK
2022-04-12T21:53:22.961Z,1649800402.961 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:53:22.961Z,1649800402.961 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:53:22.962Z,1649800402.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T21:57:51.995Z,1649800671.995 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T21:58:22.700Z,1649800702.700 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T21:58:23.537Z,1649800703.537 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T21:58:23.537Z,1649800703.537 [Default:CheckIn:C.Wait] Stopped
2022-04-12T21:58:23.537Z,1649800703.537 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T21:58:23.538Z,1649800703.538 [Default:CheckIn:D] Running Loop=1
2022-04-12T21:58:23.917Z,1649800703.917 [Default:CheckIn:D] Stopped
2022-04-12T21:58:23.917Z,1649800703.917 [Default:CheckIn:E] Running Loop=1
2022-04-12T21:58:24.326Z,1649800704.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.036589 min 
2022-04-12T21:58:24.326Z,1649800704.326 [Default:CheckIn:E] Stopped
2022-04-12T21:58:24.326Z,1649800704.326 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T21:58:24.326Z,1649800704.326 [Default:CheckIn] Stopped
2022-04-12T21:58:24.326Z,1649800704.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T21:58:24.326Z,1649800704.326 [Default:CheckIn](INFO): Running loop #21
2022-04-12T21:58:24.327Z,1649800704.327 [Default:CheckIn] Running Loop=21
2022-04-12T21:58:24.327Z,1649800704.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T21:58:24.327Z,1649800704.327 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T21:58:26.335Z,1649800706.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215824.00,A,3648.17012,N,12147.28286,W,0.272,136.96,120422,,,A*74
2022-04-12T21:58:26.337Z,1649800706.337 [NAL9602](INFO): GPS fix at 20220412T215824: (36.802835, -121.788048)
2022-04-12T21:58:26.349Z,1649800706.349 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T21:58:26.349Z,1649800706.349 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T21:58:35.338Z,1649800715.338 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220412T202756/Courier0048.lzma
2022-04-12T21:58:36.341Z,1649800716.341 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0048.lzma.bak
2022-04-12T21:58:36.341Z,1649800716.341 [DataOverHttps](INFO): SBD MOMSN=16643550
2022-04-12T21:58:54.735Z,1649800734.735 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220412T202756/Express0049.lzma
2022-04-12T21:58:55.741Z,1649800735.741 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0049.lzma.bak
2022-04-12T21:58:55.742Z,1649800735.742 [DataOverHttps](INFO): SBD MOMSN=16643553
2022-04-12T21:58:57.036Z,1649800737.036 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-04-12T21:58:57.119Z,1649800737.119 [NAL9602](ERROR): received: 
+CSQ:0

OK17, 0, 0, 0, 0

OK
2022-04-12T21:58:59.481Z,1649800739.481 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T21:58:59.481Z,1649800739.481 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T21:58:59.481Z,1649800739.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T22:03:28.118Z,1649801008.118 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T22:03:58.828Z,1649801038.828 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T22:04:00.073Z,1649801040.073 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T22:04:00.073Z,1649801040.073 [Default:CheckIn:C.Wait] Stopped
2022-04-12T22:04:00.073Z,1649801040.073 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T22:04:00.073Z,1649801040.073 [Default:CheckIn:D] Running Loop=1
2022-04-12T22:04:00.449Z,1649801040.449 [Default:CheckIn:D] Stopped
2022-04-12T22:04:00.449Z,1649801040.449 [Default:CheckIn:E] Running Loop=1
2022-04-12T22:04:00.856Z,1649801040.856 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.645451 min 
2022-04-12T22:04:00.856Z,1649801040.856 [Default:CheckIn:E] Stopped
2022-04-12T22:04:00.857Z,1649801040.857 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T22:04:00.857Z,1649801040.857 [Default:CheckIn] Stopped
2022-04-12T22:04:00.857Z,1649801040.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T22:04:00.857Z,1649801040.857 [Default:CheckIn](INFO): Running loop #22
2022-04-12T22:04:00.857Z,1649801040.857 [Default:CheckIn] Running Loop=22
2022-04-12T22:04:00.857Z,1649801040.857 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T22:04:00.857Z,1649801040.857 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T22:04:02.865Z,1649801042.865 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220401.00,A,3648.16548,N,12147.28289,W,0.486,136.96,120422,,,A*70
2022-04-12T22:04:02.879Z,1649801042.879 [NAL9602](INFO): GPS fix at 20220412T220401: (36.802758, -121.788048)
2022-04-12T22:04:02.891Z,1649801042.891 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T22:04:02.891Z,1649801042.891 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T22:04:11.906Z,1649801051.906 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0051.lzma
2022-04-12T22:04:12.908Z,1649801052.908 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0051.lzma.bak
2022-04-12T22:04:12.909Z,1649801052.909 [DataOverHttps](INFO): SBD MOMSN=16643569
2022-04-12T22:04:31.219Z,1649801071.219 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220412T202756/Express0052.lzma
2022-04-12T22:04:32.221Z,1649801072.221 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0052.lzma.bak
2022-04-12T22:04:32.221Z,1649801072.221 [DataOverHttps](INFO): SBD MOMSN=16643572
2022-04-12T22:04:33.566Z,1649801073.566 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-04-12T22:04:33.639Z,1649801073.639 [NAL9602](ERROR): received: 
+CSQ:0

OK17, 0, 0, 0, 0

OK
2022-04-12T22:04:35.629Z,1649801075.629 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T22:04:35.629Z,1649801075.629 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T22:04:35.629Z,1649801075.629 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T22:08:28.297Z,1649801308.297 [NAL9602](INFO): SBD MO Status=2, MOMSN=6718, MT Status=2, MTMSN=0
2022-04-12T22:08:28.297Z,1649801308.297 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-12T22:08:43.649Z,1649801323.649 [NAL9602](INFO): SBD MO Status=2, MOMSN=6718, MT Status=2, MTMSN=0
2022-04-12T22:08:43.649Z,1649801323.649 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-12T22:09:05.054Z,1649801345.054 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T22:09:35.770Z,1649801375.770 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T22:09:36.194Z,1649801376.194 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T22:09:36.199Z,1649801376.199 [Default:CheckIn:C.Wait] Stopped
2022-04-12T22:09:36.199Z,1649801376.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T22:09:36.199Z,1649801376.199 [Default:CheckIn:D] Running Loop=1
2022-04-12T22:09:36.608Z,1649801376.608 [Default:CheckIn:D] Stopped
2022-04-12T22:09:36.608Z,1649801376.608 [Default:CheckIn:E] Running Loop=1
2022-04-12T22:09:36.989Z,1649801376.989 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.248112 min 
2022-04-12T22:09:36.989Z,1649801376.989 [Default:CheckIn:E] Stopped
2022-04-12T22:09:36.989Z,1649801376.989 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T22:09:36.989Z,1649801376.989 [Default:CheckIn] Stopped
2022-04-12T22:09:36.990Z,1649801376.990 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T22:09:36.990Z,1649801376.990 [Default:CheckIn](INFO): Running loop #23
2022-04-12T22:09:36.990Z,1649801376.990 [Default:CheckIn] Running Loop=23
2022-04-12T22:09:36.990Z,1649801376.990 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T22:09:36.990Z,1649801376.990 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T22:09:38.993Z,1649801378.993 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220937.00,A,3648.16942,N,12147.28310,W,0.214,0.00,120422,,,A*79
2022-04-12T22:09:38.995Z,1649801378.995 [NAL9602](INFO): GPS fix at 20220412T220937: (36.802824, -121.788052)
2022-04-12T22:09:39.007Z,1649801379.007 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T22:09:39.007Z,1649801379.007 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T22:09:47.842Z,1649801387.842 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220412T202756/Courier0054.lzma
2022-04-12T22:09:48.845Z,1649801388.845 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0054.lzma.bak
2022-04-12T22:09:48.845Z,1649801388.845 [DataOverHttps](INFO): SBD MOMSN=16643590
2022-04-12T22:10:07.319Z,1649801407.319 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20220412T202756/Express0055.lzma
2022-04-12T22:10:08.321Z,1649801408.321 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0055.lzma.bak
2022-04-12T22:10:08.321Z,1649801408.321 [DataOverHttps](INFO): SBD MOMSN=16643593
2022-04-12T22:10:09.696Z,1649801409.696 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-04-12T22:10:09.771Z,1649801409.771 [NAL9602](ERROR): received: 
+CSQ:0

OK18, 2, 0, 0, 0

OK
2022-04-12T22:10:11.747Z,1649801411.747 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T22:10:11.747Z,1649801411.747 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T22:10:11.747Z,1649801411.747 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T22:14:41.182Z,1649801681.182 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T22:15:11.888Z,1649801711.888 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T22:15:12.324Z,1649801712.324 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T22:15:12.324Z,1649801712.324 [Default:CheckIn:C.Wait] Stopped
2022-04-12T22:15:12.324Z,1649801712.324 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T22:15:12.324Z,1649801712.324 [Default:CheckIn:D] Running Loop=1
2022-04-12T22:15:12.733Z,1649801712.733 [Default:CheckIn:D] Stopped
2022-04-12T22:15:12.733Z,1649801712.733 [Default:CheckIn:E] Running Loop=1
2022-04-12T22:15:13.118Z,1649801713.118 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.850187 min 
2022-04-12T22:15:13.119Z,1649801713.119 [Default:CheckIn:E] Stopped
2022-04-12T22:15:13.119Z,1649801713.119 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T22:15:13.119Z,1649801713.119 [Default:CheckIn] Stopped
2022-04-12T22:15:13.119Z,1649801713.119 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T22:15:13.119Z,1649801713.119 [Default:CheckIn](INFO): Running loop #24
2022-04-12T22:15:13.119Z,1649801713.119 [Default:CheckIn] Running Loop=24
2022-04-12T22:15:13.119Z,1649801713.119 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T22:15:13.120Z,1649801713.120 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T22:15:15.122Z,1649801715.122 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221513.00,A,3648.16654,N,12147.28043,W,0.739,291.98,120422,,,A*7E
2022-04-12T22:15:15.134Z,1649801715.134 [NAL9602](INFO): GPS fix at 20220412T221513: (36.802776, -121.788007)
2022-04-12T22:15:15.145Z,1649801715.145 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T22:15:15.145Z,1649801715.145 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T22:15:24.050Z,1649801724.050 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220412T202756/Courier0057.lzma
2022-04-12T22:15:25.053Z,1649801725.053 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0057.lzma.bak
2022-04-12T22:15:25.053Z,1649801725.053 [DataOverHttps](INFO): SBD MOMSN=16643597
2022-04-12T22:15:43.563Z,1649801743.563 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20220412T202756/Express0058.lzma
2022-04-12T22:15:44.565Z,1649801744.565 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0058.lzma.bak
2022-04-12T22:15:44.565Z,1649801744.565 [DataOverHttps](INFO): SBD MOMSN=16643600
2022-04-12T22:15:45.824Z,1649801745.824 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-04-12T22:15:45.907Z,1649801745.907 [NAL9602](ERROR): received: 
+CSQ:0

OK18, 2, 0, 0, 0

OK
2022-04-12T22:15:48.297Z,1649801748.297 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T22:15:48.297Z,1649801748.297 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T22:15:48.297Z,1649801748.297 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T22:20:17.321Z,1649802017.321 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T22:20:48.021Z,1649802048.021 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T22:20:48.847Z,1649802048.847 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-12T22:20:48.848Z,1649802048.848 [Default:CheckIn:C.Wait] Stopped
2022-04-12T22:20:48.848Z,1649802048.848 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T22:20:48.848Z,1649802048.848 [Default:CheckIn:D] Running Loop=1
2022-04-12T22:20:49.241Z,1649802049.241 [Default:CheckIn:D] Stopped
2022-04-12T22:20:49.241Z,1649802049.241 [Default:CheckIn:E] Running Loop=1
2022-04-12T22:20:49.648Z,1649802049.648 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.458659 min 
2022-04-12T22:20:49.648Z,1649802049.648 [Default:CheckIn:E] Stopped
2022-04-12T22:20:49.648Z,1649802049.648 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-12T22:20:49.648Z,1649802049.648 [Default:CheckIn] Stopped
2022-04-12T22:20:49.648Z,1649802049.648 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T22:20:49.649Z,1649802049.649 [Default:CheckIn](INFO): Running loop #25
2022-04-12T22:20:49.649Z,1649802049.649 [Default:CheckIn] Running Loop=25
2022-04-12T22:20:49.649Z,1649802049.649 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-12T22:20:49.649Z,1649802049.649 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-12T22:21:22.361Z,1649802082.361 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-04-12T22:21:22.439Z,1649802082.439 [NAL9602](ERROR): received: 
+CSQ:0

OK18, 2, 0, 0, 0

OK
2022-04-12T22:23:03.047Z,1649802183.047 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.955318
2022-04-12T22:23:13.628Z,1649802193.628 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004440
2022-04-12T22:25:49.820Z,1649802349.820 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-12T22:20:49.6Z
2022-04-12T22:25:49.820Z,1649802349.820 [Default:CheckIn:Read_GPS] Stopped
2022-04-12T22:25:49.821Z,1649802349.821 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-12T22:25:54.250Z,1649802354.250 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-12T22:25:58.826Z,1649802358.826 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220412T202756/Courier0060.lzma
2022-04-12T22:25:59.829Z,1649802359.829 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Courier0060.lzma.bak
2022-04-12T22:25:59.829Z,1649802359.829 [DataOverHttps](INFO): SBD MOMSN=16643610
2022-04-12T22:26:18.067Z,1649802378.067 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220412T202756/Express0061.lzma
2022-04-12T22:26:19.065Z,1649802379.065 [DataOverHttps](INFO): Moved sent file to Logs/20220412T202756/Express0061.lzma.bak
2022-04-12T22:26:19.065Z,1649802379.065 [DataOverHttps](INFO): SBD MOMSN=16643618
2022-04-12T22:26:22.992Z,1649802382.992 [Default:CheckIn:Read_Iridium] Stopped
2022-04-12T22:26:22.992Z,1649802382.992 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-12T22:26:22.992Z,1649802382.992 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-12T22:26:24.950Z,1649802384.950 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-12T22:27:31.027Z,1649802451.027 [CommandExec](IMPORTANT): got command quit
2022-04-12T22:27:32.039Z,1649802452.039 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:32.039Z,1649802452.039 [CommandExec](INFO): Uninitializing the command executive.
2022-04-12T22:27:32.039Z,1649802452.039 [CommandExec](INFO): Uninitializing the command scheduler.
2022-04-12T22:27:32.039Z,1649802452.039 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:32.103Z,1649802452.103 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-04-12T22:27:32.103Z,1649802452.103 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-04-12T22:27:32.103Z,1649802452.103 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:32.103Z,1649802452.103 [NavChartDb](INFO): Join timeout helper Thread ID is 5566
2022-04-12T22:27:32.180Z,1649802452.180 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:32.180Z,1649802452.180 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:32.183Z,1649802452.183 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-04-12T22:27:32.183Z,1649802452.183 [DAT ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:32.183Z,1649802452.183 [DAT](INFO): Join timeout helper Thread ID is 5567
2022-04-12T22:27:32.419Z,1649802452.419 [DAT](INFO): Powering down
2022-04-12T22:27:32.487Z,1649802452.487 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:32.487Z,1649802452.487 [DAT](INFO): Powering down
2022-04-12T22:27:32.488Z,1649802452.488 [DAT ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:32.496Z,1649802452.496 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-04-12T22:27:32.497Z,1649802452.497 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:32.497Z,1649802452.497 [Radio_Surface](INFO): Join timeout helper Thread ID is 5568
2022-04-12T22:27:32.547Z,1649802452.547 [Radio_Surface](INFO): Powering down
2022-04-12T22:27:32.548Z,1649802452.548 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:32.548Z,1649802452.548 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:32.559Z,1649802452.559 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-04-12T22:27:32.559Z,1649802452.559 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:32.559Z,1649802452.559 [Onboard](INFO): Join timeout helper Thread ID is 5569
2022-04-12T22:27:32.735Z,1649802452.735 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-04-12T22:27:33.191Z,1649802453.191 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:33.191Z,1649802453.191 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:33.203Z,1649802453.203 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-04-12T22:27:33.203Z,1649802453.203 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:33.203Z,1649802453.203 [DataOverHttps](INFO): Join timeout helper Thread ID is 5570
2022-04-12T22:27:33.740Z,1649802453.740 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:33.740Z,1649802453.740 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:33.757Z,1649802453.757 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-04-12T22:27:33.757Z,1649802453.757 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:33.757Z,1649802453.757 [BackseatComponent](INFO): Join timeout helper Thread ID is 5571
2022-04-12T22:27:33.763Z,1649802453.763 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:33.763Z,1649802453.763 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:33.779Z,1649802453.779 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-04-12T22:27:33.780Z,1649802453.780 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:33.780Z,1649802453.780 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5572
2022-04-12T22:27:34.203Z,1649802454.203 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:34.203Z,1649802454.203 [WetLabsBB2FL](INFO): Powering down
2022-04-12T22:27:34.205Z,1649802454.205 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:34.215Z,1649802454.215 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-04-12T22:27:34.215Z,1649802454.215 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:34.216Z,1649802454.216 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5573
2022-04-12T22:27:34.919Z,1649802454.919 [CTD_Seabird](INFO): Powering down
2022-04-12T22:27:34.931Z,1649802454.931 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:34.931Z,1649802454.931 [CTD_Seabird](INFO): Powering down
2022-04-12T22:27:34.943Z,1649802454.943 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:34.959Z,1649802454.959 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-04-12T22:27:34.959Z,1649802454.959 [logger ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:34.960Z,1649802454.960 [logger](INFO): Join timeout helper Thread ID is 5574
2022-04-12T22:27:34.967Z,1649802454.967 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:34.967Z,1649802454.967 [logger ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:34.979Z,1649802454.979 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-04-12T22:27:34.979Z,1649802454.979 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:34.980Z,1649802454.980 [CommandLine](INFO): Join timeout helper Thread ID is 5575
2022-04-12T22:27:35.039Z,1649802455.039 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:35.039Z,1649802455.039 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.059Z,1649802455.059 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-04-12T22:27:35.059Z,1649802455.059 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.060Z,1649802455.060 [CommandExec](INFO): Join timeout helper Thread ID is 5576
2022-04-12T22:27:35.061Z,1649802455.061 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-04-12T22:27:35.061Z,1649802455.061 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.061Z,1649802455.061 [controlThread](INFO): Join timeout helper Thread ID is 5577
2022-04-12T22:27:35.227Z,1649802455.227 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-12T22:27:35.227Z,1649802455.227 [controlThread](DEBUG): Uninitializing ControlThread
2022-04-12T22:27:35.228Z,1649802455.228 [AHRS_M2](INFO): Powering down
2022-04-12T22:27:35.299Z,1649802455.299 [NAL9602](INFO): Powering down
2022-04-12T22:27:35.301Z,1649802455.301 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-04-12T22:27:35.302Z,1649802455.302 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-04-12T22:27:35.302Z,1649802455.302 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-04-12T22:27:35.303Z,1649802455.303 [MissionManager](INFO): Uninitializing Mission Default
2022-04-12T22:27:35.303Z,1649802455.303 [Default] Stopped
2022-04-12T22:27:35.303Z,1649802455.303 [Default](DEBUG): Aggregate::uninitialize Default
2022-04-12T22:27:35.303Z,1649802455.303 [Default:B.GoToSurface] Stopped
2022-04-12T22:27:35.303Z,1649802455.303 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-12T22:27:35.303Z,1649802455.303 [Default:CheckIn] Stopped
2022-04-12T22:27:35.304Z,1649802455.304 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-12T22:27:35.304Z,1649802455.304 [Default:CheckIn:C.Wait] Stopped
2022-04-12T22:27:35.304Z,1649802455.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-12T22:27:35.306Z,1649802455.306 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-04-12T22:27:35.307Z,1649802455.307 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-04-12T22:27:35.307Z,1649802455.307 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-04-12T22:27:35.307Z,1649802455.307 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-04-12T22:27:35.308Z,1649802455.308 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-04-12T22:27:35.308Z,1649802455.308 [BuoyancyServo](INFO): Powering down
2022-04-12T22:27:35.323Z,1649802455.323 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-04-12T22:27:35.323Z,1649802455.323 [ElevatorServo](INFO): Powering down
2022-04-12T22:27:35.324Z,1649802455.324 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-04-12T22:27:35.324Z,1649802455.324 [MassServo](INFO): Powering down
2022-04-12T22:27:35.325Z,1649802455.325 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-04-12T22:27:35.325Z,1649802455.325 [RudderServo](INFO): Powering down
2022-04-12T22:27:35.325Z,1649802455.325 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-04-12T22:27:35.326Z,1649802455.326 [ThrusterHE](INFO): Powering down
2022-04-12T22:27:35.327Z,1649802455.327 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-04-12T22:27:35.327Z,1649802455.327 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-04-12T22:27:35.327Z,1649802455.327 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-04-12T22:27:35.327Z,1649802455.327 [CBIT](DEBUG): Powering off loads.
2022-04-12T22:27:35.338Z,1649802455.338 [CBIT](DEBUG): Disabling WDT.
2022-04-12T22:27:35.350Z,1649802455.350 [CBIT](DEBUG): Opening all GF detection circuits.
2022-04-12T22:27:35.351Z,1649802455.351 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.397Z,1649802455.397 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.401Z,1649802455.401 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.445Z,1649802455.445 [DAT ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.449Z,1649802455.449 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.451Z,1649802455.451 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.457Z,1649802455.457 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.501Z,1649802455.501 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.541Z,1649802455.541 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-12T22:27:35.641Z,1649802455.641 [logger ThreadHandler](INFO): Thread cancelled.