2024-07-24T16:38:37.977Z,1721839117.977 [CommandExec](IMPORTANT): got command restart logs 2024-07-24T16:40:18.787Z,1721839218.787 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T16:40:18.787Z,1721839218.787 [Default:CheckIn:C.Wait] Stopped 2024-07-24T16:40:18.787Z,1721839218.787 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T16:40:18.787Z,1721839218.787 [Default:CheckIn:D] Running Loop=1 2024-07-24T16:40:19.207Z,1721839219.207 [Default:CheckIn:D] Stopped 2024-07-24T16:40:19.207Z,1721839219.207 [Default:CheckIn:E] Running Loop=1 2024-07-24T16:40:19.595Z,1721839219.595 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.490769 min 2024-07-24T16:40:19.595Z,1721839219.595 [Default:CheckIn:E] Stopped 2024-07-24T16:40:19.595Z,1721839219.595 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T16:40:19.596Z,1721839219.596 [Default:CheckIn] Stopped 2024-07-24T16:40:19.596Z,1721839219.596 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T16:40:19.596Z,1721839219.596 [Default:CheckIn](INFO): Running loop #4 2024-07-24T16:40:19.596Z,1721839219.596 [Default:CheckIn] Running Loop=4 2024-07-24T16:40:19.596Z,1721839219.596 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T16:40:19.596Z,1721839219.596 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T16:40:21.608Z,1721839221.608 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164020.00,A,3647.92831,N,12150.32624,W,10.011,65.13,240724,,,D*7E 2024-07-24T16:40:21.610Z,1721839221.610 [NAL9602](INFO): GPS fix at 20240724T164020: (36.798805, -121.838771) 2024-07-24T16:40:21.653Z,1721839221.653 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T16:40:21.653Z,1721839221.653 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T16:40:30.724Z,1721839230.724 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240723T201124/Courier0120.lzma 2024-07-24T16:40:31.726Z,1721839231.726 [DataOverHttps](INFO): Moved sent file to Logs/20240723T201124/Courier0120.lzma.bak 2024-07-24T16:40:31.727Z,1721839231.727 [DataOverHttps](INFO): SBD MOMSN=21384208 2024-07-24T16:40:33.751Z,1721839233.751 [NAL9602](INFO): SBD MO Status=0, MOMSN=53417, MT Status=0, MTMSN=0 2024-07-24T16:40:33.751Z,1721839233.751 [NAL9602](INFO): No messages in MT queue 2024-07-24T16:40:52.497Z,1721839252.497 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240724T163837/Courier0000.lzma 2024-07-24T16:40:53.498Z,1721839253.498 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0000.lzma.bak 2024-07-24T16:40:53.498Z,1721839253.498 [DataOverHttps](INFO): SBD MOMSN=21384211 2024-07-24T16:41:04.482Z,1721839264.482 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T16:41:31.748Z,1721839291.748 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20240723T201124/Express0121.lzma 2024-07-24T16:41:32.751Z,1721839292.751 [DataOverHttps](INFO): Moved sent file to Logs/20240723T201124/Express0121.lzma.bak 2024-07-24T16:41:32.751Z,1721839292.751 [DataOverHttps](INFO): SBD MOMSN=21384216 2024-07-24T16:41:57.381Z,1721839317.381 [DataOverHttps](INFO): Sending 397 bytes from file Logs/20240724T163837/Express0001.lzma 2024-07-24T16:41:58.382Z,1721839318.382 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0001.lzma.bak 2024-07-24T16:41:58.382Z,1721839318.382 [DataOverHttps](INFO): SBD MOMSN=21384219 2024-07-24T16:42:30.509Z,1721839350.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:43:46.373Z,1721839426.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:43:54.716Z,1721839434.716 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20240724T163837/Express0004.lzma 2024-07-24T16:43:55.718Z,1721839435.718 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0004.lzma.bak 2024-07-24T16:43:55.718Z,1721839435.718 [DataOverHttps](INFO): SBD MOMSN=21384223 2024-07-24T16:43:57.063Z,1721839437.063 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T16:43:57.063Z,1721839437.063 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T16:43:57.063Z,1721839437.063 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T16:46:29.941Z,1721839589.941 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:47:01.064Z,1721839621.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:47:32.189Z,1721839652.189 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:48:03.313Z,1721839683.313 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:48:16.365Z,1721839696.365 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.263321 2024-07-24T16:48:57.639Z,1721839737.639 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T16:48:57.639Z,1721839737.639 [Default:CheckIn:C.Wait] Stopped 2024-07-24T16:48:57.639Z,1721839737.639 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T16:48:57.639Z,1721839737.639 [Default:CheckIn:D] Running Loop=1 2024-07-24T16:48:58.039Z,1721839738.039 [Default:CheckIn:D] Stopped 2024-07-24T16:48:58.039Z,1721839738.039 [Default:CheckIn:E] Running Loop=1 2024-07-24T16:48:58.451Z,1721839738.451 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.137974 min 2024-07-24T16:48:58.451Z,1721839738.451 [Default:CheckIn:E] Stopped 2024-07-24T16:48:58.451Z,1721839738.451 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T16:48:58.452Z,1721839738.452 [Default:CheckIn] Stopped 2024-07-24T16:48:58.452Z,1721839738.452 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T16:48:58.452Z,1721839738.452 [Default:CheckIn](INFO): Running loop #5 2024-07-24T16:48:58.452Z,1721839738.452 [Default:CheckIn] Running Loop=5 2024-07-24T16:48:58.452Z,1721839738.452 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T16:48:58.452Z,1721839738.452 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T16:49:00.459Z,1721839740.459 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164859.00,A,3648.10057,N,12148.68613,W,0.991,28.10,240724,,,D*4D 2024-07-24T16:49:00.461Z,1721839740.461 [NAL9602](INFO): GPS fix at 20240724T164859: (36.801676, -121.811436) 2024-07-24T16:49:00.490Z,1721839740.490 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T16:49:00.490Z,1721839740.490 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T16:49:04.556Z,1721839744.556 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:49:25.967Z,1721839765.967 [NAL9602](INFO): SBD MO Status=1, MOMSN=53418, MT Status=0, MTMSN=0 2024-07-24T16:49:26.019Z,1721839766.019 [NAL9602](INFO): Sent 73 bytes from file Logs/20240724T163837/Courier0006.lzma 2024-07-24T16:49:26.019Z,1721839766.019 [NAL9602](INFO): Packets left to send: 0 2024-07-24T16:49:34.887Z,1721839774.887 [NAL9602](INFO): SBD MO Status=1, MOMSN=53419, MT Status=0, MTMSN=0 2024-07-24T16:49:34.936Z,1721839774.936 [NAL9602](INFO): Sent 145 bytes from file Logs/20240724T163837/Express0007.lzma 2024-07-24T16:49:34.937Z,1721839774.937 [NAL9602](INFO): Packets left to send: 0 2024-07-24T16:49:35.681Z,1721839775.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:49:42.563Z,1721839782.563 [NAL9602](INFO): SBD MO Status=0, MOMSN=53420, MT Status=0, MTMSN=0 2024-07-24T16:49:42.652Z,1721839782.652 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T16:49:42.652Z,1721839782.652 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T16:49:42.652Z,1721839782.652 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T16:50:06.824Z,1721839806.824 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:50:13.260Z,1721839813.260 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T16:50:37.949Z,1721839837.949 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:51:09.073Z,1721839869.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:51:40.205Z,1721839900.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:52:11.341Z,1721839931.341 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:52:37.765Z,1721839957.765 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003204 2024-07-24T16:53:43.801Z,1721840023.801 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:54:14.925Z,1721840054.925 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:54:43.159Z,1721840083.159 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T16:54:43.159Z,1721840083.159 [Default:CheckIn:C.Wait] Stopped 2024-07-24T16:54:43.159Z,1721840083.159 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T16:54:43.159Z,1721840083.159 [Default:CheckIn:D] Running Loop=1 2024-07-24T16:54:43.570Z,1721840083.570 [Default:CheckIn:D] Stopped 2024-07-24T16:54:43.570Z,1721840083.570 [Default:CheckIn:E] Running Loop=1 2024-07-24T16:54:43.967Z,1721840083.967 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.896824 min 2024-07-24T16:54:43.968Z,1721840083.968 [Default:CheckIn:E] Stopped 2024-07-24T16:54:43.968Z,1721840083.968 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T16:54:43.968Z,1721840083.968 [Default:CheckIn] Stopped 2024-07-24T16:54:43.968Z,1721840083.968 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T16:54:43.968Z,1721840083.968 [Default:CheckIn](INFO): Running loop #6 2024-07-24T16:54:43.968Z,1721840083.968 [Default:CheckIn] Running Loop=6 2024-07-24T16:54:43.968Z,1721840083.968 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T16:54:43.969Z,1721840083.969 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T16:54:45.975Z,1721840085.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165445.00,A,3648.07492,N,12148.42907,W,4.704,96.01,240724,,,D*47 2024-07-24T16:54:45.978Z,1721840085.978 [NAL9602](INFO): GPS fix at 20240724T165445: (36.801249, -121.807151) 2024-07-24T16:54:45.993Z,1721840085.993 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T16:54:45.993Z,1721840085.993 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T16:54:46.049Z,1721840086.049 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:55:02.951Z,1721840102.951 [NAL9602](INFO): SBD MO Status=0, MOMSN=53421, MT Status=0, MTMSN=0 2024-07-24T16:55:02.951Z,1721840102.951 [NAL9602](INFO): No messages in MT queue 2024-07-24T16:55:17.173Z,1721840117.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:55:33.649Z,1721840133.649 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T16:55:48.297Z,1721840148.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T16:55:54.854Z,1721840154.854 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=183.272568 2024-07-24T16:56:20.297Z,1721840180.297 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003235 2024-07-24T16:56:24.619Z,1721840184.619 [NAL9602](INFO): SBD MO Status=2, MOMSN=53422, MT Status=2, MTMSN=0 2024-07-24T16:56:24.619Z,1721840184.619 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T16:56:30.992Z,1721840190.992 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0009.lzma 2024-07-24T16:56:31.994Z,1721840191.994 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0009.lzma.bak 2024-07-24T16:56:31.994Z,1721840191.994 [DataOverHttps](INFO): SBD MOMSN=21384246 2024-07-24T16:56:48.221Z,1721840208.221 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240724T163837/Express0010.lzma 2024-07-24T16:56:49.222Z,1721840209.222 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0010.lzma.bak 2024-07-24T16:56:49.222Z,1721840209.222 [DataOverHttps](INFO): SBD MOMSN=21384249 2024-07-24T16:56:50.515Z,1721840210.515 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T16:56:50.533Z,1721840210.533 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T16:56:50.534Z,1721840210.534 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T16:56:55.394Z,1721840215.394 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:01:51.154Z,1721840511.154 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:01:51.154Z,1721840511.154 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:01:51.155Z,1721840511.155 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:01:51.155Z,1721840511.155 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:01:51.550Z,1721840511.550 [Default:CheckIn:D] Stopped 2024-07-24T17:01:51.550Z,1721840511.550 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:01:51.954Z,1721840511.954 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.029814 min 2024-07-24T17:01:51.955Z,1721840511.955 [Default:CheckIn:E] Stopped 2024-07-24T17:01:51.955Z,1721840511.955 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:01:51.955Z,1721840511.955 [Default:CheckIn] Stopped 2024-07-24T17:01:51.955Z,1721840511.955 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:01:51.955Z,1721840511.955 [Default:CheckIn](INFO): Running loop #7 2024-07-24T17:01:51.955Z,1721840511.955 [Default:CheckIn] Running Loop=7 2024-07-24T17:01:51.955Z,1721840511.955 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:01:51.955Z,1721840511.955 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:01:53.967Z,1721840513.967 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170153.00,A,3648.13567,N,12147.77072,W,4.879,58.27,240724,,,D*4E 2024-07-24T17:01:53.970Z,1721840513.970 [NAL9602](INFO): GPS fix at 20240724T170153: (36.802261, -121.796179) 2024-07-24T17:01:53.980Z,1721840513.980 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:01:53.980Z,1721840513.980 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:02:01.544Z,1721840521.544 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0012.lzma 2024-07-24T17:02:02.546Z,1721840522.546 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0012.lzma.bak 2024-07-24T17:02:02.546Z,1721840522.546 [DataOverHttps](INFO): SBD MOMSN=21384252 2024-07-24T17:02:18.825Z,1721840538.825 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240724T163837/Express0013.lzma 2024-07-24T17:02:19.826Z,1721840539.826 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0013.lzma.bak 2024-07-24T17:02:19.826Z,1721840539.826 [DataOverHttps](INFO): SBD MOMSN=21384255 2024-07-24T17:02:21.058Z,1721840541.058 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:02:21.058Z,1721840541.058 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:02:21.058Z,1721840541.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:02:31.175Z,1721840551.175 [NAL9602](INFO): SBD MO Status=2, MOMSN=53422, MT Status=2, MTMSN=0 2024-07-24T17:02:31.175Z,1721840551.175 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T17:03:15.227Z,1721840595.227 [NAL9602](INFO): SBD MO Status=1, MOMSN=53422, MT Status=0, MTMSN=0 2024-07-24T17:03:15.227Z,1721840595.227 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:03:45.929Z,1721840625.929 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:07:21.675Z,1721840841.675 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:07:21.675Z,1721840841.675 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:07:21.675Z,1721840841.675 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:07:21.675Z,1721840841.675 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:07:22.075Z,1721840842.075 [Default:CheckIn:D] Stopped 2024-07-24T17:07:22.076Z,1721840842.076 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:07:22.492Z,1721840842.492 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.538574 min 2024-07-24T17:07:22.492Z,1721840842.492 [Default:CheckIn:E] Stopped 2024-07-24T17:07:22.492Z,1721840842.492 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:07:22.492Z,1721840842.492 [Default:CheckIn] Stopped 2024-07-24T17:07:22.493Z,1721840842.493 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:07:22.493Z,1721840842.493 [Default:CheckIn](INFO): Running loop #8 2024-07-24T17:07:22.493Z,1721840842.493 [Default:CheckIn] Running Loop=8 2024-07-24T17:07:22.493Z,1721840842.493 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:07:22.493Z,1721840842.493 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:07:24.495Z,1721840844.495 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170723.00,A,3648.39779,N,12147.37344,W,4.840,52.06,240724,,,D*4B 2024-07-24T17:07:24.497Z,1721840844.497 [NAL9602](INFO): GPS fix at 20240724T170723: (36.806630, -121.789557) 2024-07-24T17:07:24.529Z,1721840844.529 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:07:24.529Z,1721840844.529 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:07:31.700Z,1721840851.700 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0015.lzma 2024-07-24T17:07:32.702Z,1721840852.702 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0015.lzma.bak 2024-07-24T17:07:32.702Z,1721840852.702 [DataOverHttps](INFO): SBD MOMSN=21384260 2024-07-24T17:07:41.867Z,1721840861.867 [NAL9602](INFO): SBD MO Status=2, MOMSN=53423, MT Status=2, MTMSN=0 2024-07-24T17:07:41.867Z,1721840861.867 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T17:07:48.660Z,1721840868.660 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240724T163837/Express0016.lzma 2024-07-24T17:07:49.662Z,1721840869.662 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0016.lzma.bak 2024-07-24T17:07:49.662Z,1721840869.662 [DataOverHttps](INFO): SBD MOMSN=21384263 2024-07-24T17:07:50.767Z,1721840870.767 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:07:50.767Z,1721840870.767 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:07:50.767Z,1721840870.767 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:08:00.051Z,1721840880.051 [NAL9602](INFO): SBD MO Status=2, MOMSN=53423, MT Status=2, MTMSN=0 2024-07-24T17:08:00.051Z,1721840880.051 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T17:08:25.111Z,1721840905.111 [NAL9602](INFO): SBD MO Status=1, MOMSN=53423, MT Status=0, MTMSN=0 2024-07-24T17:08:25.111Z,1721840905.111 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:08:55.825Z,1721840935.825 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:12:51.363Z,1721841171.363 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:12:51.363Z,1721841171.363 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:12:51.364Z,1721841171.364 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:12:51.364Z,1721841171.364 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:12:51.763Z,1721841171.763 [Default:CheckIn:D] Stopped 2024-07-24T17:12:51.763Z,1721841171.763 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:12:52.205Z,1721841172.205 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.033366 min 2024-07-24T17:12:52.205Z,1721841172.205 [Default:CheckIn:E] Stopped 2024-07-24T17:12:52.205Z,1721841172.205 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:12:52.205Z,1721841172.205 [Default:CheckIn] Stopped 2024-07-24T17:12:52.205Z,1721841172.205 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:12:52.205Z,1721841172.205 [Default:CheckIn](INFO): Running loop #9 2024-07-24T17:12:52.205Z,1721841172.205 [Default:CheckIn] Running Loop=9 2024-07-24T17:12:52.205Z,1721841172.205 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:12:52.205Z,1721841172.205 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:12:54.180Z,1721841174.180 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171253.00,A,3648.50054,N,12147.12043,W,2.605,154.40,240724,,,D*70 2024-07-24T17:12:54.182Z,1721841174.182 [NAL9602](INFO): GPS fix at 20240724T171253: (36.808342, -121.785341) 2024-07-24T17:12:54.201Z,1721841174.201 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:12:54.201Z,1721841174.201 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:13:01.432Z,1721841181.432 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0018.lzma 2024-07-24T17:13:02.434Z,1721841182.434 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0018.lzma.bak 2024-07-24T17:13:02.434Z,1721841182.434 [DataOverHttps](INFO): SBD MOMSN=21384277 2024-07-24T17:13:12.008Z,1721841192.008 [NAL9602](INFO): SBD MO Status=1, MOMSN=53424, MT Status=0, MTMSN=0 2024-07-24T17:13:12.008Z,1721841192.008 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:13:18.420Z,1721841198.420 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240724T163837/Express0019.lzma 2024-07-24T17:13:19.422Z,1721841199.422 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0019.lzma.bak 2024-07-24T17:13:19.422Z,1721841199.422 [DataOverHttps](INFO): SBD MOMSN=21384280 2024-07-24T17:13:20.898Z,1721841200.898 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:13:20.898Z,1721841200.898 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:13:20.898Z,1721841200.898 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:13:42.704Z,1721841222.704 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:18:13.016Z,1721841493.016 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-24T17:18:16.248Z,1721841496.248 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:18:21.559Z,1721841501.559 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:18:21.559Z,1721841501.559 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:18:21.559Z,1721841501.559 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:18:21.559Z,1721841501.559 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:18:21.927Z,1721841501.927 [Default:CheckIn:D] Stopped 2024-07-24T17:18:21.927Z,1721841501.927 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:18:22.332Z,1721841502.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.536100 min 2024-07-24T17:18:22.332Z,1721841502.332 [Default:CheckIn:E] Stopped 2024-07-24T17:18:22.332Z,1721841502.332 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:18:22.332Z,1721841502.332 [Default:CheckIn] Stopped 2024-07-24T17:18:22.332Z,1721841502.332 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:18:22.333Z,1721841502.333 [Default:CheckIn](INFO): Running loop #10 2024-07-24T17:18:22.333Z,1721841502.333 [Default:CheckIn] Running Loop=10 2024-07-24T17:18:22.333Z,1721841502.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:18:22.333Z,1721841502.333 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:18:24.331Z,1721841504.331 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171823.00,A,3648.26960,N,12147.14099,W,2.430,189.59,240724,,,D*7F 2024-07-24T17:18:24.333Z,1721841504.333 [NAL9602](INFO): GPS fix at 20240724T171823: (36.804493, -121.785683) 2024-07-24T17:18:24.382Z,1721841504.382 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:18:24.382Z,1721841504.382 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:18:28.063Z,1721841508.063 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-24T17:18:28.481Z,1721841508.481 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:18:31.711Z,1721841511.711 [NAL9602](INFO): SBD MO Status=1, MOMSN=53425, MT Status=0, MTMSN=0 2024-07-24T17:18:31.711Z,1721841511.711 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:18:32.260Z,1721841512.260 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0021.lzma 2024-07-24T17:18:33.262Z,1721841513.262 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0021.lzma.bak 2024-07-24T17:18:33.262Z,1721841513.262 [DataOverHttps](INFO): SBD MOMSN=21384444 2024-07-24T17:18:40.183Z,1721841520.183 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:18:43.010Z,1721841523.010 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-24T17:18:49.869Z,1721841529.869 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240724T163837/Express0022.lzma 2024-07-24T17:18:50.870Z,1721841530.870 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0022.lzma.bak 2024-07-24T17:18:50.870Z,1721841530.870 [DataOverHttps](INFO): SBD MOMSN=21384447 2024-07-24T17:18:51.937Z,1721841531.937 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:18:52.336Z,1721841532.336 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:18:52.337Z,1721841532.337 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:18:52.337Z,1721841532.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:18:57.998Z,1721841537.998 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-24T17:19:02.450Z,1721841542.450 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:19:04.059Z,1721841544.059 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:19:13.350Z,1721841553.350 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-24T17:19:15.371Z,1721841555.371 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:19:27.086Z,1721841567.086 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:19:28.300Z,1721841568.300 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-24T17:19:38.802Z,1721841578.802 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:19:43.654Z,1721841583.654 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-24T17:19:50.519Z,1721841590.519 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:19:58.598Z,1721841598.598 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-24T17:20:02.643Z,1721841602.643 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:20:13.951Z,1721841613.951 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-24T17:20:14.773Z,1721841614.773 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:20:26.879Z,1721841626.879 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-07-24T17:20:28.899Z,1721841628.899 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-24T17:20:40.622Z,1721841640.622 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 4. 2024-07-24T17:20:40.640Z,1721841640.640 [BPC1](INFO): Received data from all battery sticks. 2024-07-24T17:23:52.986Z,1721841832.986 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:23:52.986Z,1721841832.986 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:23:52.986Z,1721841832.986 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:23:52.987Z,1721841832.987 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:23:53.383Z,1721841833.383 [Default:CheckIn:D] Stopped 2024-07-24T17:23:53.383Z,1721841833.383 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:23:53.782Z,1721841833.782 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.060364 min 2024-07-24T17:23:53.782Z,1721841833.782 [Default:CheckIn:E] Stopped 2024-07-24T17:23:53.782Z,1721841833.782 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:23:53.782Z,1721841833.782 [Default:CheckIn] Stopped 2024-07-24T17:23:53.782Z,1721841833.782 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:23:53.783Z,1721841833.783 [Default:CheckIn](INFO): Running loop #11 2024-07-24T17:23:53.783Z,1721841833.783 [Default:CheckIn] Running Loop=11 2024-07-24T17:23:53.783Z,1721841833.783 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:23:53.783Z,1721841833.783 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:23:55.795Z,1721841835.795 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172355.00,A,3648.13229,N,12147.21080,W,0.039,272.91,240724,,,D*74 2024-07-24T17:23:55.798Z,1721841835.798 [NAL9602](INFO): GPS fix at 20240724T172355: (36.802205, -121.786847) 2024-07-24T17:23:55.808Z,1721841835.808 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:23:55.808Z,1721841835.808 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:24:03.133Z,1721841843.133 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240724T163837/Courier0024.lzma 2024-07-24T17:24:04.134Z,1721841844.134 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0024.lzma.bak 2024-07-24T17:24:04.134Z,1721841844.134 [DataOverHttps](INFO): SBD MOMSN=21384452 2024-07-24T17:24:21.284Z,1721841861.284 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240724T163837/Express0025.lzma 2024-07-24T17:24:22.286Z,1721841862.286 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0025.lzma.bak 2024-07-24T17:24:22.286Z,1721841862.286 [DataOverHttps](INFO): SBD MOMSN=21384455 2024-07-24T17:24:23.742Z,1721841863.742 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:24:23.742Z,1721841863.742 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:24:23.742Z,1721841863.742 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:24:25.295Z,1721841865.295 [NAL9602](INFO): SBD MO Status=1, MOMSN=53426, MT Status=0, MTMSN=0 2024-07-24T17:24:25.295Z,1721841865.295 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:24:56.089Z,1721841896.089 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:29:24.415Z,1721842164.415 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:29:24.415Z,1721842164.415 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:29:24.415Z,1721842164.415 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:29:24.415Z,1721842164.415 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:29:24.807Z,1721842164.807 [Default:CheckIn:D] Stopped 2024-07-24T17:29:24.807Z,1721842164.807 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:29:25.206Z,1721842165.206 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.584102 min 2024-07-24T17:29:25.207Z,1721842165.207 [Default:CheckIn:E] Stopped 2024-07-24T17:29:25.207Z,1721842165.207 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:29:25.207Z,1721842165.207 [Default:CheckIn] Stopped 2024-07-24T17:29:25.207Z,1721842165.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:29:25.207Z,1721842165.207 [Default:CheckIn](INFO): Running loop #12 2024-07-24T17:29:25.207Z,1721842165.207 [Default:CheckIn] Running Loop=12 2024-07-24T17:29:25.207Z,1721842165.207 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:29:25.207Z,1721842165.207 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:29:27.227Z,1721842167.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172926.00,A,3648.13336,N,12147.20938,W,0.019,272.91,240724,,,D*7C 2024-07-24T17:29:27.229Z,1721842167.229 [NAL9602](INFO): GPS fix at 20240724T172926: (36.802223, -121.786823) 2024-07-24T17:29:27.239Z,1721842167.239 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:29:27.239Z,1721842167.239 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:29:34.468Z,1721842174.468 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0027.lzma 2024-07-24T17:29:35.470Z,1721842175.470 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0027.lzma.bak 2024-07-24T17:29:35.470Z,1721842175.470 [DataOverHttps](INFO): SBD MOMSN=21384501 2024-07-24T17:29:51.564Z,1721842191.564 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240724T163837/Express0028.lzma 2024-07-24T17:29:52.566Z,1721842192.566 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0028.lzma.bak 2024-07-24T17:29:52.566Z,1721842192.566 [DataOverHttps](INFO): SBD MOMSN=21384504 2024-07-24T17:29:53.925Z,1721842193.925 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:29:53.925Z,1721842193.925 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:29:53.925Z,1721842193.925 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:30:12.466Z,1721842212.466 [NAL9602](INFO): SBD MO Status=2, MOMSN=53427, MT Status=2, MTMSN=0 2024-07-24T17:30:12.466Z,1721842212.466 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T17:30:53.270Z,1721842253.270 [NAL9602](INFO): SBD MO Status=2, MOMSN=53427, MT Status=2, MTMSN=0 2024-07-24T17:30:53.271Z,1721842253.271 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T17:31:19.126Z,1721842279.126 [NAL9602](INFO): SBD MO Status=2, MOMSN=53427, MT Status=2, MTMSN=0 2024-07-24T17:31:19.126Z,1721842279.126 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T17:31:38.523Z,1721842298.523 [NAL9602](INFO): SBD MO Status=1, MOMSN=53427, MT Status=0, MTMSN=0 2024-07-24T17:31:38.523Z,1721842298.523 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:32:09.226Z,1721842329.226 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:34:54.497Z,1721842494.497 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:34:54.498Z,1721842494.498 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:34:54.498Z,1721842494.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:34:54.498Z,1721842494.498 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:34:54.887Z,1721842494.887 [Default:CheckIn:D] Stopped 2024-07-24T17:34:54.887Z,1721842494.887 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:34:55.302Z,1721842495.302 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.085425 min 2024-07-24T17:34:55.303Z,1721842495.303 [Default:CheckIn:E] Stopped 2024-07-24T17:34:55.303Z,1721842495.303 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:34:55.303Z,1721842495.303 [Default:CheckIn] Stopped 2024-07-24T17:34:55.303Z,1721842495.303 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:34:55.303Z,1721842495.303 [Default:CheckIn](INFO): Running loop #13 2024-07-24T17:34:55.303Z,1721842495.303 [Default:CheckIn] Running Loop=13 2024-07-24T17:34:55.303Z,1721842495.303 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:34:55.303Z,1721842495.303 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:34:57.299Z,1721842497.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173456.00,A,3648.14990,N,12147.22149,W,0.408,226.87,240724,,,D*78 2024-07-24T17:34:57.302Z,1721842497.302 [NAL9602](INFO): GPS fix at 20240724T173456: (36.802498, -121.787025) 2024-07-24T17:34:57.312Z,1721842497.312 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:34:57.312Z,1721842497.312 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:35:04.928Z,1721842504.928 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240724T163837/Courier0030.lzma 2024-07-24T17:35:05.930Z,1721842505.930 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0030.lzma.bak 2024-07-24T17:35:05.931Z,1721842505.931 [DataOverHttps](INFO): SBD MOMSN=21384509 2024-07-24T17:35:21.844Z,1721842521.844 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240724T163837/Express0031.lzma 2024-07-24T17:35:22.846Z,1721842522.846 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0031.lzma.bak 2024-07-24T17:35:22.846Z,1721842522.846 [DataOverHttps](INFO): SBD MOMSN=21384512 2024-07-24T17:35:23.973Z,1721842523.973 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:35:23.973Z,1721842523.973 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:35:23.973Z,1721842523.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:38:04.759Z,1721842684.759 [NAL9602](INFO): SBD MO Status=2, MOMSN=53428, MT Status=2, MTMSN=0 2024-07-24T17:38:04.759Z,1721842684.759 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T17:38:39.903Z,1721842719.903 [NAL9602](INFO): SBD MO Status=2, MOMSN=53428, MT Status=2, MTMSN=0 2024-07-24T17:38:39.903Z,1721842719.903 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T17:39:15.866Z,1721842755.866 [NAL9602](INFO): SBD MO Status=1, MOMSN=53428, MT Status=0, MTMSN=0 2024-07-24T17:39:15.866Z,1721842755.866 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:39:46.574Z,1721842786.574 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:40:24.564Z,1721842824.564 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:40:24.564Z,1721842824.564 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:40:24.564Z,1721842824.564 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:40:24.565Z,1721842824.565 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:40:24.978Z,1721842824.978 [Default:CheckIn:D] Stopped 2024-07-24T17:40:24.978Z,1721842824.978 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:40:25.368Z,1721842825.368 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.586955 min 2024-07-24T17:40:25.368Z,1721842825.368 [Default:CheckIn:E] Stopped 2024-07-24T17:40:25.368Z,1721842825.368 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:40:25.368Z,1721842825.368 [Default:CheckIn] Stopped 2024-07-24T17:40:25.368Z,1721842825.368 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:40:25.369Z,1721842825.369 [Default:CheckIn](INFO): Running loop #14 2024-07-24T17:40:25.369Z,1721842825.369 [Default:CheckIn] Running Loop=14 2024-07-24T17:40:25.369Z,1721842825.369 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:40:25.369Z,1721842825.369 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:40:27.375Z,1721842827.375 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174026.00,A,3648.15034,N,12147.22332,W,0.019,226.87,240724,,,D*70 2024-07-24T17:40:27.377Z,1721842827.377 [NAL9602](INFO): GPS fix at 20240724T174026: (36.802506, -121.787055) 2024-07-24T17:40:27.388Z,1721842827.388 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:40:27.388Z,1721842827.388 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:40:35.268Z,1721842835.268 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0033.lzma 2024-07-24T17:40:36.270Z,1721842836.270 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0033.lzma.bak 2024-07-24T17:40:36.270Z,1721842836.270 [DataOverHttps](INFO): SBD MOMSN=21384522 2024-07-24T17:40:53.291Z,1721842853.291 [NAL9602](INFO): SBD MO Status=1, MOMSN=53429, MT Status=0, MTMSN=0 2024-07-24T17:40:53.291Z,1721842853.291 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:40:54.860Z,1721842854.860 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240724T163837/Express0034.lzma 2024-07-24T17:40:55.862Z,1721842855.862 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0034.lzma.bak 2024-07-24T17:40:55.862Z,1721842855.862 [DataOverHttps](INFO): SBD MOMSN=21384525 2024-07-24T17:40:57.331Z,1721842857.331 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:40:57.332Z,1721842857.332 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:40:57.332Z,1721842857.332 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:41:23.978Z,1721842883.978 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:45:57.911Z,1721843157.911 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:45:57.911Z,1721843157.911 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:45:57.911Z,1721843157.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:45:57.911Z,1721843157.911 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:45:58.327Z,1721843158.327 [Default:CheckIn:D] Stopped 2024-07-24T17:45:58.327Z,1721843158.327 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:45:58.719Z,1721843158.719 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.142765 min 2024-07-24T17:45:58.719Z,1721843158.719 [Default:CheckIn:E] Stopped 2024-07-24T17:45:58.719Z,1721843158.719 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:45:58.720Z,1721843158.720 [Default:CheckIn] Stopped 2024-07-24T17:45:58.720Z,1721843158.720 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:45:58.720Z,1721843158.720 [Default:CheckIn](INFO): Running loop #15 2024-07-24T17:45:58.720Z,1721843158.720 [Default:CheckIn] Running Loop=15 2024-07-24T17:45:58.720Z,1721843158.720 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:45:58.720Z,1721843158.720 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:46:00.726Z,1721843160.726 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174600.00,A,3648.14225,N,12147.22921,W,0.019,213.06,240724,,,D*76 2024-07-24T17:46:00.728Z,1721843160.728 [NAL9602](INFO): GPS fix at 20240724T174600: (36.802371, -121.787154) 2024-07-24T17:46:00.739Z,1721843160.739 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:46:00.739Z,1721843160.739 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:46:08.288Z,1721843168.288 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240724T163837/Courier0036.lzma 2024-07-24T17:46:09.290Z,1721843169.290 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0036.lzma.bak 2024-07-24T17:46:09.290Z,1721843169.290 [DataOverHttps](INFO): SBD MOMSN=21384534 2024-07-24T17:46:17.295Z,1721843177.295 [NAL9602](INFO): SBD MO Status=1, MOMSN=53430, MT Status=0, MTMSN=0 2024-07-24T17:46:17.295Z,1721843177.295 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:46:27.809Z,1721843187.809 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240724T163837/Express0037.lzma 2024-07-24T17:46:28.810Z,1721843188.810 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0037.lzma.bak 2024-07-24T17:46:28.810Z,1721843188.810 [DataOverHttps](INFO): SBD MOMSN=21384537 2024-07-24T17:46:30.272Z,1721843190.272 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:46:30.272Z,1721843190.272 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:46:30.272Z,1721843190.272 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:46:48.037Z,1721843208.037 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:51:30.855Z,1721843490.855 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:51:30.855Z,1721843490.855 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:51:30.855Z,1721843490.855 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:51:30.855Z,1721843490.855 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:51:31.265Z,1721843491.265 [Default:CheckIn:D] Stopped 2024-07-24T17:51:31.266Z,1721843491.266 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:51:31.659Z,1721843491.659 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.691740 min 2024-07-24T17:51:31.659Z,1721843491.659 [Default:CheckIn:E] Stopped 2024-07-24T17:51:31.659Z,1721843491.659 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:51:31.659Z,1721843491.659 [Default:CheckIn] Stopped 2024-07-24T17:51:31.659Z,1721843491.659 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:51:31.659Z,1721843491.659 [Default:CheckIn](INFO): Running loop #16 2024-07-24T17:51:31.659Z,1721843491.659 [Default:CheckIn] Running Loop=16 2024-07-24T17:51:31.659Z,1721843491.659 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:51:31.659Z,1721843491.659 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:51:33.671Z,1721843493.671 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175132.00,A,3648.17479,N,12147.27998,W,0.253,233.52,240724,,,D*75 2024-07-24T17:51:33.673Z,1721843493.673 [NAL9602](INFO): GPS fix at 20240724T175132: (36.802913, -121.788000) 2024-07-24T17:51:33.683Z,1721843493.683 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:51:33.684Z,1721843493.684 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:51:41.600Z,1721843501.600 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240724T163837/Courier0039.lzma 2024-07-24T17:51:42.602Z,1721843502.602 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0039.lzma.bak 2024-07-24T17:51:42.602Z,1721843502.602 [DataOverHttps](INFO): SBD MOMSN=21384585 2024-07-24T17:51:58.400Z,1721843518.400 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240724T163837/Express0040.lzma 2024-07-24T17:51:59.402Z,1721843519.402 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0040.lzma.bak 2024-07-24T17:51:59.402Z,1721843519.402 [DataOverHttps](INFO): SBD MOMSN=21384588 2024-07-24T17:52:00.753Z,1721843520.753 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:52:00.753Z,1721843520.753 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:52:00.753Z,1721843520.753 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:56:04.351Z,1721843764.351 [NAL9602](INFO): SBD MO Status=2, MOMSN=53431, MT Status=2, MTMSN=0 2024-07-24T17:56:04.352Z,1721843764.352 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T17:56:20.511Z,1721843780.511 [NAL9602](INFO): SBD MO Status=1, MOMSN=53431, MT Status=0, MTMSN=0 2024-07-24T17:56:20.511Z,1721843780.511 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:56:51.218Z,1721843811.218 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T17:57:01.327Z,1721843821.327 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T17:57:01.327Z,1721843821.327 [Default:CheckIn:C.Wait] Stopped 2024-07-24T17:57:01.327Z,1721843821.327 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T17:57:01.328Z,1721843821.328 [Default:CheckIn:D] Running Loop=1 2024-07-24T17:57:01.726Z,1721843821.726 [Default:CheckIn:D] Stopped 2024-07-24T17:57:01.726Z,1721843821.726 [Default:CheckIn:E] Running Loop=1 2024-07-24T17:57:02.135Z,1721843822.135 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.199422 min 2024-07-24T17:57:02.135Z,1721843822.135 [Default:CheckIn:E] Stopped 2024-07-24T17:57:02.135Z,1721843822.135 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T17:57:02.136Z,1721843822.136 [Default:CheckIn] Stopped 2024-07-24T17:57:02.136Z,1721843822.136 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T17:57:02.136Z,1721843822.136 [Default:CheckIn](INFO): Running loop #17 2024-07-24T17:57:02.136Z,1721843822.136 [Default:CheckIn] Running Loop=17 2024-07-24T17:57:02.136Z,1721843822.136 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T17:57:02.136Z,1721843822.136 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T17:57:04.151Z,1721843824.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175703.00,A,3648.17266,N,12147.28025,W,0.194,233.52,240724,,,A*74 2024-07-24T17:57:04.154Z,1721843824.154 [NAL9602](INFO): GPS fix at 20240724T175703: (36.802878, -121.788004) 2024-07-24T17:57:04.165Z,1721843824.165 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T17:57:04.165Z,1721843824.165 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T17:57:11.988Z,1721843831.988 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0042.lzma 2024-07-24T17:57:12.990Z,1721843832.990 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0042.lzma.bak 2024-07-24T17:57:12.990Z,1721843832.990 [DataOverHttps](INFO): SBD MOMSN=21384593 2024-07-24T17:57:23.937Z,1721843843.937 [NAL9602](INFO): SBD MO Status=1, MOMSN=53432, MT Status=0, MTMSN=0 2024-07-24T17:57:23.938Z,1721843843.938 [NAL9602](INFO): No messages in MT queue 2024-07-24T17:57:28.804Z,1721843848.804 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240724T163837/Express0043.lzma 2024-07-24T17:57:29.806Z,1721843849.806 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0043.lzma.bak 2024-07-24T17:57:29.806Z,1721843849.806 [DataOverHttps](INFO): SBD MOMSN=21384596 2024-07-24T17:57:31.223Z,1721843851.223 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T17:57:31.223Z,1721843851.223 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T17:57:31.223Z,1721843851.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T17:57:54.646Z,1721843874.646 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T18:02:31.803Z,1721844151.803 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T18:02:31.803Z,1721844151.803 [Default:CheckIn:C.Wait] Stopped 2024-07-24T18:02:31.803Z,1721844151.803 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T18:02:31.804Z,1721844151.804 [Default:CheckIn:D] Running Loop=1 2024-07-24T18:02:32.203Z,1721844152.203 [Default:CheckIn:D] Stopped 2024-07-24T18:02:32.203Z,1721844152.203 [Default:CheckIn:E] Running Loop=1 2024-07-24T18:02:32.616Z,1721844152.616 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.707373 min 2024-07-24T18:02:32.616Z,1721844152.616 [Default:CheckIn:E] Stopped 2024-07-24T18:02:32.616Z,1721844152.616 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T18:02:32.616Z,1721844152.616 [Default:CheckIn] Stopped 2024-07-24T18:02:32.616Z,1721844152.616 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T18:02:32.616Z,1721844152.616 [Default:CheckIn](INFO): Running loop #18 2024-07-24T18:02:32.616Z,1721844152.616 [Default:CheckIn] Running Loop=18 2024-07-24T18:02:32.617Z,1721844152.617 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T18:02:32.617Z,1721844152.617 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T18:02:34.623Z,1721844154.623 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180233.00,A,3648.17349,N,12147.27642,W,0.408,233.52,240724,,,A*7C 2024-07-24T18:02:34.626Z,1721844154.626 [NAL9602](INFO): GPS fix at 20240724T180233: (36.802892, -121.787940) 2024-07-24T18:02:34.657Z,1721844154.657 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T18:02:34.657Z,1721844154.657 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T18:02:42.260Z,1721844162.260 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0045.lzma 2024-07-24T18:02:43.262Z,1721844163.262 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0045.lzma.bak 2024-07-24T18:02:43.262Z,1721844163.262 [DataOverHttps](INFO): SBD MOMSN=21384624 2024-07-24T18:03:02.528Z,1721844182.528 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240724T163837/Express0046.lzma 2024-07-24T18:03:03.531Z,1721844183.531 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0046.lzma.bak 2024-07-24T18:03:03.531Z,1721844183.531 [DataOverHttps](INFO): SBD MOMSN=21384627 2024-07-24T18:03:05.008Z,1721844185.008 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T18:03:05.009Z,1721844185.009 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T18:03:05.009Z,1721844185.009 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T18:03:40.119Z,1721844220.119 [NAL9602](INFO): SBD MO Status=2, MOMSN=53433, MT Status=2, MTMSN=0 2024-07-24T18:03:40.119Z,1721844220.119 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T18:04:35.062Z,1721844275.062 [NAL9602](INFO): SBD MO Status=1, MOMSN=53433, MT Status=0, MTMSN=0 2024-07-24T18:04:35.062Z,1721844275.062 [NAL9602](INFO): No messages in MT queue 2024-07-24T18:05:05.769Z,1721844305.769 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T18:08:05.554Z,1721844485.554 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T18:08:05.554Z,1721844485.554 [Default:CheckIn:C.Wait] Stopped 2024-07-24T18:08:05.554Z,1721844485.554 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T18:08:05.554Z,1721844485.554 [Default:CheckIn:D] Running Loop=1 2024-07-24T18:08:05.963Z,1721844485.963 [Default:CheckIn:D] Stopped 2024-07-24T18:08:05.963Z,1721844485.963 [Default:CheckIn:E] Running Loop=1 2024-07-24T18:08:06.371Z,1721844486.371 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.270036 min 2024-07-24T18:08:06.371Z,1721844486.371 [Default:CheckIn:E] Stopped 2024-07-24T18:08:06.371Z,1721844486.371 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T18:08:06.371Z,1721844486.371 [Default:CheckIn] Stopped 2024-07-24T18:08:06.372Z,1721844486.372 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T18:08:06.372Z,1721844486.372 [Default:CheckIn](INFO): Running loop #19 2024-07-24T18:08:06.372Z,1721844486.372 [Default:CheckIn] Running Loop=19 2024-07-24T18:08:06.372Z,1721844486.372 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T18:08:06.372Z,1721844486.372 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T18:08:08.375Z,1721844488.375 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180807.00,A,3648.17569,N,12147.27695,W,0.194,305.69,240724,,,A*73 2024-07-24T18:08:08.377Z,1721844488.377 [NAL9602](INFO): GPS fix at 20240724T180807: (36.802928, -121.787949) 2024-07-24T18:08:08.387Z,1721844488.387 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T18:08:08.388Z,1721844488.388 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T18:08:15.552Z,1721844495.552 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0048.lzma 2024-07-24T18:08:16.554Z,1721844496.554 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0048.lzma.bak 2024-07-24T18:08:16.554Z,1721844496.554 [DataOverHttps](INFO): SBD MOMSN=21384635 2024-07-24T18:08:35.248Z,1721844515.248 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240724T163837/Express0049.lzma 2024-07-24T18:08:35.915Z,1721844515.915 [NAL9602](INFO): SBD MO Status=2, MOMSN=53434, MT Status=2, MTMSN=0 2024-07-24T18:08:35.915Z,1721844515.915 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T18:08:36.146Z,1721844516.146 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0049.lzma.bak 2024-07-24T18:08:36.146Z,1721844516.146 [DataOverHttps](INFO): SBD MOMSN=21384638 2024-07-24T18:08:37.554Z,1721844517.554 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T18:08:37.554Z,1721844517.554 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T18:08:37.555Z,1721844517.555 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T18:09:19.143Z,1721844559.143 [NAL9602](INFO): SBD MO Status=2, MOMSN=53434, MT Status=2, MTMSN=0 2024-07-24T18:09:19.143Z,1721844559.143 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-24T18:12:40.905Z,1721844760.905 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T18:13:10.233Z,1721844790.233 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-07-24T18:13:12.029Z,1721844792.029 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T18:13:38.126Z,1721844818.126 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-24T18:13:38.126Z,1721844818.126 [Default:CheckIn:C.Wait] Stopped 2024-07-24T18:13:38.126Z,1721844818.126 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T18:13:38.126Z,1721844818.126 [Default:CheckIn:D] Running Loop=1 2024-07-24T18:13:38.523Z,1721844818.523 [Default:CheckIn:D] Stopped 2024-07-24T18:13:38.523Z,1721844818.523 [Default:CheckIn:E] Running Loop=1 2024-07-24T18:13:38.927Z,1721844818.927 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.812703 min 2024-07-24T18:13:38.927Z,1721844818.927 [Default:CheckIn:E] Stopped 2024-07-24T18:13:38.927Z,1721844818.927 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-24T18:13:38.928Z,1721844818.928 [Default:CheckIn] Stopped 2024-07-24T18:13:38.928Z,1721844818.928 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T18:13:38.928Z,1721844818.928 [Default:CheckIn](INFO): Running loop #20 2024-07-24T18:13:38.928Z,1721844818.928 [Default:CheckIn] Running Loop=20 2024-07-24T18:13:38.928Z,1721844818.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-24T18:13:38.928Z,1721844818.928 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-24T18:13:46.995Z,1721844826.995 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181346.00,A,3648.17642,N,12147.27148,W,5.695,185.71,240724,,,A*71 2024-07-24T18:13:46.998Z,1721844826.998 [NAL9602](INFO): GPS fix at 20240724T181346: (36.802940, -121.787858) 2024-07-24T18:13:47.024Z,1721844827.024 [Default:CheckIn:Read_GPS] Stopped 2024-07-24T18:13:47.052Z,1721844827.052 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-24T18:13:50.236Z,1721844830.236 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240724T163837/Courier0051.lzma 2024-07-24T18:13:51.238Z,1721844831.238 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Courier0051.lzma.bak 2024-07-24T18:13:51.238Z,1721844831.238 [DataOverHttps](INFO): SBD MOMSN=21384661 2024-07-24T18:14:07.153Z,1721844847.153 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20240724T163837/Express0052.lzma 2024-07-24T18:14:08.154Z,1721844848.154 [DataOverHttps](INFO): Moved sent file to Logs/20240724T163837/Express0052.lzma.bak 2024-07-24T18:14:08.154Z,1721844848.154 [DataOverHttps](INFO): SBD MOMSN=21384664 2024-07-24T18:14:09.308Z,1721844849.308 [Default:CheckIn:Read_Iridium] Stopped 2024-07-24T18:14:09.308Z,1721844849.308 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-24T18:14:09.308Z,1721844849.308 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-24T18:14:19.393Z,1721844859.393 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-24T18:15:09.657Z,1721844909.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T18:15:40.781Z,1721844940.781 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T18:16:11.904Z,1721844971.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T18:16:43.033Z,1721845003.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T18:17:14.157Z,1721845034.157 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-07-24T18:17:17.169Z,1721845037.169 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735016 2024-07-24T18:17:56.681Z,1721845076.681 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003203 2024-07-24T18:18:19.253Z,1721845099.253 [CommandExec](IMPORTANT): got command quit 2024-07-24T18:18:20.256Z,1721845100.256 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:20.256Z,1721845100.256 [CommandExec](INFO): Uninitializing the command executive. 2024-07-24T18:18:20.257Z,1721845100.257 [CommandExec](INFO): Uninitializing the command scheduler. 2024-07-24T18:18:20.257Z,1721845100.257 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:20.392Z,1721845100.392 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-07-24T18:18:20.392Z,1721845100.392 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-07-24T18:18:20.393Z,1721845100.393 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:20.393Z,1721845100.393 [NavChartDb](INFO): Join timeout helper Thread ID is 8286 2024-07-24T18:18:20.680Z,1721845100.680 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:20.681Z,1721845100.681 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:20.688Z,1721845100.688 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-07-24T18:18:20.688Z,1721845100.688 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:20.689Z,1721845100.689 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8287 2024-07-24T18:18:21.001Z,1721845101.001 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:21.001Z,1721845101.001 [WetLabsBB2FL](INFO): Powering down 2024-07-24T18:18:21.001Z,1721845101.001 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:21.004Z,1721845101.004 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-07-24T18:18:21.004Z,1721845101.004 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:21.005Z,1721845101.005 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8288 2024-07-24T18:18:21.456Z,1721845101.456 [CTD_Seabird](INFO): Powering down 2024-07-24T18:18:21.468Z,1721845101.468 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:21.469Z,1721845101.469 [CTD_Seabird](INFO): Powering down 2024-07-24T18:18:21.480Z,1721845101.480 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:21.488Z,1721845101.488 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-07-24T18:18:21.488Z,1721845101.488 [DAT ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:21.489Z,1721845101.489 [DAT](INFO): Join timeout helper Thread ID is 8289 2024-07-24T18:18:21.556Z,1721845101.556 [DAT](INFO): Powering down 2024-07-24T18:18:21.625Z,1721845101.625 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:21.625Z,1721845101.625 [DAT](INFO): Powering down 2024-07-24T18:18:21.626Z,1721845101.626 [DAT ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:21.644Z,1721845101.644 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-07-24T18:18:21.644Z,1721845101.644 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:21.645Z,1721845101.645 [Radio_Surface](INFO): Join timeout helper Thread ID is 8290 2024-07-24T18:18:21.976Z,1721845101.976 [Radio_Surface](INFO): Powering down 2024-07-24T18:18:21.977Z,1721845101.977 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:21.978Z,1721845101.978 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:21.997Z,1721845101.997 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-07-24T18:18:21.997Z,1721845101.997 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:21.998Z,1721845101.998 [Onboard](INFO): Join timeout helper Thread ID is 8291 2024-07-24T18:18:26.348Z,1721845106.348 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:26.349Z,1721845106.349 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:26.365Z,1721845106.365 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-07-24T18:18:26.365Z,1721845106.365 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:26.365Z,1721845106.365 [DataOverHttps](INFO): Join timeout helper Thread ID is 8292 2024-07-24T18:18:27.244Z,1721845107.244 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:27.248Z,1721845107.248 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.261Z,1721845107.261 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-07-24T18:18:27.261Z,1721845107.261 [logger ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.261Z,1721845107.261 [logger](INFO): Join timeout helper Thread ID is 8293 2024-07-24T18:18:27.272Z,1721845107.272 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:27.273Z,1721845107.273 [logger ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.281Z,1721845107.281 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-07-24T18:18:27.281Z,1721845107.281 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.281Z,1721845107.281 [CommandLine](INFO): Join timeout helper Thread ID is 8294 2024-07-24T18:18:27.356Z,1721845107.356 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:27.357Z,1721845107.357 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.361Z,1721845107.361 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-07-24T18:18:27.361Z,1721845107.361 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.361Z,1721845107.361 [CommandExec](INFO): Join timeout helper Thread ID is 8295 2024-07-24T18:18:27.362Z,1721845107.362 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-07-24T18:18:27.363Z,1721845107.363 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.363Z,1721845107.363 [controlThread](INFO): Join timeout helper Thread ID is 8296 2024-07-24T18:18:27.432Z,1721845107.432 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-24T18:18:27.433Z,1721845107.433 [controlThread](DEBUG): Uninitializing ControlThread 2024-07-24T18:18:27.433Z,1721845107.433 [AHRS_M2](INFO): Powering down 2024-07-24T18:18:27.505Z,1721845107.505 [NAL9602](INFO): Powering down 2024-07-24T18:18:27.508Z,1721845107.508 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-07-24T18:18:27.508Z,1721845107.508 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-07-24T18:18:27.509Z,1721845107.509 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-07-24T18:18:27.509Z,1721845107.509 [MissionManager](INFO): Uninitializing Mission Default 2024-07-24T18:18:27.510Z,1721845107.510 [Default] Stopped 2024-07-24T18:18:27.510Z,1721845107.510 [Default](DEBUG): Aggregate::uninitialize Default 2024-07-24T18:18:27.510Z,1721845107.510 [Default:B.GoToSurface] Stopped 2024-07-24T18:18:27.510Z,1721845107.510 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-07-24T18:18:27.510Z,1721845107.510 [Default:CheckIn] Stopped 2024-07-24T18:18:27.510Z,1721845107.510 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-24T18:18:27.510Z,1721845107.510 [Default:CheckIn:C.Wait] Stopped 2024-07-24T18:18:27.510Z,1721845107.510 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-24T18:18:27.513Z,1721845107.513 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-07-24T18:18:27.513Z,1721845107.513 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-07-24T18:18:27.513Z,1721845107.513 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-07-24T18:18:27.514Z,1721845107.514 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-07-24T18:18:27.514Z,1721845107.514 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-07-24T18:18:27.514Z,1721845107.514 [BuoyancyServo](INFO): Powering down 2024-07-24T18:18:27.528Z,1721845107.528 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-07-24T18:18:27.528Z,1721845107.528 [ElevatorServo](INFO): Powering down 2024-07-24T18:18:27.529Z,1721845107.529 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-07-24T18:18:27.529Z,1721845107.529 [MassServo](INFO): Powering down 2024-07-24T18:18:27.530Z,1721845107.530 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-07-24T18:18:27.530Z,1721845107.530 [RudderServo](INFO): Powering down 2024-07-24T18:18:27.531Z,1721845107.531 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-07-24T18:18:27.531Z,1721845107.531 [ThrusterHE](INFO): Powering down 2024-07-24T18:18:27.532Z,1721845107.532 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-07-24T18:18:27.532Z,1721845107.532 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-07-24T18:18:27.533Z,1721845107.533 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-07-24T18:18:27.533Z,1721845107.533 [CBIT](DEBUG): Powering off loads. 2024-07-24T18:18:27.544Z,1721845107.544 [CBIT](DEBUG): Disabling WDT. 2024-07-24T18:18:27.556Z,1721845107.556 [CBIT](DEBUG): Opening all GF detection circuits. 2024-07-24T18:18:27.557Z,1721845107.557 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.605Z,1721845107.605 [DAT ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.610Z,1721845107.610 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.612Z,1721845107.612 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.618Z,1721845107.618 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.690Z,1721845107.690 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.694Z,1721845107.694 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.735Z,1721845107.735 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-07-24T18:18:27.843Z,1721845107.843 [logger ThreadHandler](INFO): Thread cancelled.