2022-06-15T18:25:41.333Z,1655317541.333 [CommandExec](IMPORTANT): got command restart logs
2022-06-15T18:28:05.290Z,1655317685.290 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T18:28:05.290Z,1655317685.290 [Default:CheckIn:C.Wait] Stopped
2022-06-15T18:28:05.290Z,1655317685.290 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T18:28:05.291Z,1655317685.291 [Default:CheckIn:D] Running Loop=1
2022-06-15T18:28:05.710Z,1655317685.710 [Default:CheckIn:D] Stopped
2022-06-15T18:28:05.710Z,1655317685.710 [Default:CheckIn:E] Running Loop=1
2022-06-15T18:28:06.099Z,1655317686.099 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.022811 min 
2022-06-15T18:28:06.116Z,1655317686.116 [Default:CheckIn:E] Stopped
2022-06-15T18:28:06.116Z,1655317686.116 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T18:28:06.116Z,1655317686.116 [Default:CheckIn] Stopped
2022-06-15T18:28:06.116Z,1655317686.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T18:28:06.116Z,1655317686.116 [Default:CheckIn](INFO): Running loop #4
2022-06-15T18:28:06.116Z,1655317686.116 [Default:CheckIn] Running Loop=4
2022-06-15T18:28:06.116Z,1655317686.116 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T18:28:06.116Z,1655317686.116 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T18:28:08.113Z,1655317688.113 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182806.00,A,3648.37249,N,12149.29899,W,1.341,114.98,150622,,,A*77
2022-06-15T18:28:08.115Z,1655317688.115 [NAL9602](INFO): GPS fix at 20220615T182806: (36.806208, -121.821650)
2022-06-15T18:28:08.127Z,1655317688.127 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T18:28:08.127Z,1655317688.127 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T18:28:15.679Z,1655317695.679 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220614T185253/Courier0216.lzma
2022-06-15T18:28:16.682Z,1655317696.682 [DataOverHttps](INFO): Moved sent file to Logs/20220614T185253/Courier0216.lzma.bak
2022-06-15T18:28:16.682Z,1655317696.682 [DataOverHttps](INFO): SBD MOMSN=16891177
2022-06-15T18:28:30.738Z,1655317710.738 [NAL9602](INFO): SBD MO Status=0, MOMSN=9032, MT Status=0, MTMSN=0
2022-06-15T18:28:30.738Z,1655317710.738 [NAL9602](INFO): No messages in MT queue
2022-06-15T18:28:43.737Z,1655317723.737 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20220615T182541/Courier0000.lzma
2022-06-15T18:28:54.789Z,1655317734.789 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0000.lzma.bak
2022-06-15T18:28:54.790Z,1655317734.790 [DataOverHttps](INFO): SBD MOMSN=16891179
2022-06-15T18:29:01.435Z,1655317741.435 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T18:29:15.571Z,1655317755.571 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220615T182541/Courier0003.lzma
2022-06-15T18:29:16.573Z,1655317756.573 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0003.lzma.bak
2022-06-15T18:29:16.574Z,1655317756.574 [DataOverHttps](INFO): SBD MOMSN=16891183
2022-06-15T18:30:41.445Z,1655317841.445 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239784
2022-06-15T18:31:04.969Z,1655317864.969 [NAL9602](INFO): SBD MO Status=2, MOMSN=9033, MT Status=2, MTMSN=0
2022-06-15T18:31:04.969Z,1655317864.969 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-15T18:31:12.568Z,1655317872.568 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-15T18:31:34.403Z,1655317894.403 [NAL9602](INFO): SBD MO Status=1, MOMSN=9033, MT Status=0, MTMSN=0
2022-06-15T18:31:34.457Z,1655317894.457 [NAL9602](INFO): Sent 106 bytes from file Logs/20220614T185253/Express0217.lzma
2022-06-15T18:31:34.457Z,1655317894.457 [NAL9602](INFO): Packets left to send: 0
2022-06-15T18:31:47.726Z,1655317907.726 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-15T18:31:49.359Z,1655317909.359 [NAL9602](INFO): SBD MO Status=1, MOMSN=9034, MT Status=0, MTMSN=0
2022-06-15T18:31:49.416Z,1655317909.416 [NAL9602](INFO): Sent 332 bytes from file Logs/20220615T182541/Express0001.lzma
2022-06-15T18:31:49.416Z,1655317909.416 [NAL9602](INFO): Packets left to send: 1
2022-06-15T18:31:58.540Z,1655317918.540 [NAL9602](INFO): SBD MO Status=1, MOMSN=9035, MT Status=0, MTMSN=0
2022-06-15T18:31:58.588Z,1655317918.588 [NAL9602](INFO): Sent 43 bytes from file Logs/20220615T182541/Express0001.lzma
2022-06-15T18:31:58.588Z,1655317918.588 [NAL9602](INFO): Packets left to send: 0
2022-06-15T18:32:00.400Z,1655317920.400 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003425
2022-06-15T18:32:07.815Z,1655317927.815 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20220615T182541/Express0004.lzma
2022-06-15T18:32:08.818Z,1655317928.818 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0004.lzma.bak
2022-06-15T18:32:08.818Z,1655317928.818 [DataOverHttps](INFO): SBD MOMSN=16891186
2022-06-15T18:32:09.366Z,1655317929.366 [NAL9602](INFO): SBD MO Status=1, MOMSN=9036, MT Status=0, MTMSN=0
2022-06-15T18:32:09.420Z,1655317929.420 [NAL9602](INFO): Sent 61 bytes from file Logs/20220615T182541/Express0004.lzma
2022-06-15T18:32:09.420Z,1655317929.420 [NAL9602](INFO): Packets left to send: 0
2022-06-15T18:32:10.601Z,1655317930.601 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T18:32:10.601Z,1655317930.601 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T18:32:10.601Z,1655317930.601 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T18:32:40.071Z,1655317960.071 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T18:37:11.214Z,1655318231.214 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T18:37:11.214Z,1655318231.214 [Default:CheckIn:C.Wait] Stopped
2022-06-15T18:37:11.214Z,1655318231.214 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T18:37:11.214Z,1655318231.214 [Default:CheckIn:D] Running Loop=1
2022-06-15T18:37:11.620Z,1655318231.620 [Default:CheckIn:D] Stopped
2022-06-15T18:37:11.620Z,1655318231.620 [Default:CheckIn:E] Running Loop=1
2022-06-15T18:37:12.024Z,1655318232.024 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.121305 min 
2022-06-15T18:37:12.024Z,1655318232.024 [Default:CheckIn:E] Stopped
2022-06-15T18:37:12.024Z,1655318232.024 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T18:37:12.024Z,1655318232.024 [Default:CheckIn] Stopped
2022-06-15T18:37:12.024Z,1655318232.024 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T18:37:12.024Z,1655318232.024 [Default:CheckIn](INFO): Running loop #5
2022-06-15T18:37:12.025Z,1655318232.025 [Default:CheckIn] Running Loop=5
2022-06-15T18:37:12.025Z,1655318232.025 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T18:37:12.025Z,1655318232.025 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T18:37:14.042Z,1655318234.042 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183712.00,A,3648.29812,N,12147.13605,W,3.654,192.11,150622,,,D*72
2022-06-15T18:37:14.045Z,1655318234.045 [NAL9602](INFO): GPS fix at 20220615T183712: (36.804969, -121.785601)
2022-06-15T18:37:14.057Z,1655318234.057 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T18:37:14.057Z,1655318234.057 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T18:37:22.099Z,1655318242.099 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220615T182541/Courier0006.lzma
2022-06-15T18:37:23.102Z,1655318243.102 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0006.lzma.bak
2022-06-15T18:37:23.102Z,1655318243.102 [DataOverHttps](INFO): SBD MOMSN=16891235
2022-06-15T18:37:39.206Z,1655318259.206 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220615T182541/Express0007.lzma
2022-06-15T18:37:40.205Z,1655318260.205 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0007.lzma.bak
2022-06-15T18:37:40.206Z,1655318260.206 [DataOverHttps](INFO): SBD MOMSN=16891239
2022-06-15T18:37:41.962Z,1655318261.962 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T18:37:41.962Z,1655318261.962 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T18:37:41.962Z,1655318261.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T18:37:59.800Z,1655318279.800 [NAL9602](INFO): SBD MO Status=0, MOMSN=9037, MT Status=0, MTMSN=0
2022-06-15T18:37:59.800Z,1655318279.800 [NAL9602](INFO): No messages in MT queue
2022-06-15T18:38:30.492Z,1655318310.492 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T18:42:42.615Z,1655318562.615 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T18:42:42.615Z,1655318562.615 [Default:CheckIn:C.Wait] Stopped
2022-06-15T18:42:42.615Z,1655318562.615 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T18:42:42.615Z,1655318562.615 [Default:CheckIn:D] Running Loop=1
2022-06-15T18:42:43.010Z,1655318563.010 [Default:CheckIn:D] Stopped
2022-06-15T18:42:43.011Z,1655318563.011 [Default:CheckIn:E] Running Loop=1
2022-06-15T18:42:43.411Z,1655318563.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.644482 min 
2022-06-15T18:42:43.411Z,1655318563.411 [Default:CheckIn:E] Stopped
2022-06-15T18:42:43.411Z,1655318563.411 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T18:42:43.411Z,1655318563.411 [Default:CheckIn] Stopped
2022-06-15T18:42:43.411Z,1655318563.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T18:42:43.412Z,1655318563.412 [Default:CheckIn](INFO): Running loop #6
2022-06-15T18:42:43.412Z,1655318563.412 [Default:CheckIn] Running Loop=6
2022-06-15T18:42:43.412Z,1655318563.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T18:42:43.412Z,1655318563.412 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T18:42:45.419Z,1655318565.419 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184243.00,A,3648.13846,N,12147.20443,W,0.039,246.43,150622,,,D*7F
2022-06-15T18:42:45.421Z,1655318565.421 [NAL9602](INFO): GPS fix at 20220615T184243: (36.802308, -121.786740)
2022-06-15T18:42:45.438Z,1655318565.438 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T18:42:45.438Z,1655318565.438 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T18:42:52.699Z,1655318572.699 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220615T182541/Courier0009.lzma
2022-06-15T18:42:53.701Z,1655318573.701 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0009.lzma.bak
2022-06-15T18:42:53.702Z,1655318573.702 [DataOverHttps](INFO): SBD MOMSN=16891243
2022-06-15T18:43:09.968Z,1655318589.968 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220615T182541/Express0010.lzma
2022-06-15T18:43:10.970Z,1655318590.970 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0010.lzma.bak
2022-06-15T18:43:10.971Z,1655318590.971 [DataOverHttps](INFO): SBD MOMSN=16891246
2022-06-15T18:43:12.906Z,1655318592.906 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T18:43:12.906Z,1655318592.906 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T18:43:12.906Z,1655318592.906 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T18:45:04.005Z,1655318704.005 [NAL9602](INFO): SBD MO Status=0, MOMSN=9038, MT Status=0, MTMSN=0
2022-06-15T18:45:04.005Z,1655318704.005 [NAL9602](INFO): No messages in MT queue
2022-06-15T18:45:34.691Z,1655318734.691 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T18:48:13.478Z,1655318893.478 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T18:48:13.478Z,1655318893.478 [Default:CheckIn:C.Wait] Stopped
2022-06-15T18:48:13.478Z,1655318893.478 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T18:48:13.478Z,1655318893.478 [Default:CheckIn:D] Running Loop=1
2022-06-15T18:48:13.891Z,1655318893.891 [Default:CheckIn:D] Stopped
2022-06-15T18:48:13.891Z,1655318893.891 [Default:CheckIn:E] Running Loop=1
2022-06-15T18:48:14.309Z,1655318894.309 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.159159 min 
2022-06-15T18:48:14.309Z,1655318894.309 [Default:CheckIn:E] Stopped
2022-06-15T18:48:14.309Z,1655318894.309 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T18:48:14.309Z,1655318894.309 [Default:CheckIn] Stopped
2022-06-15T18:48:14.310Z,1655318894.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T18:48:14.310Z,1655318894.310 [Default:CheckIn](INFO): Running loop #7
2022-06-15T18:48:14.310Z,1655318894.310 [Default:CheckIn] Running Loop=7
2022-06-15T18:48:14.310Z,1655318894.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T18:48:14.310Z,1655318894.310 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T18:48:16.303Z,1655318896.303 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184814.00,A,3648.13895,N,12147.20575,W,0.019,246.43,150622,,,D*7F
2022-06-15T18:48:16.306Z,1655318896.306 [NAL9602](INFO): GPS fix at 20220615T184814: (36.802316, -121.786762)
2022-06-15T18:48:16.333Z,1655318896.333 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T18:48:16.333Z,1655318896.333 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T18:48:25.140Z,1655318905.140 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220615T182541/Courier0012.lzma
2022-06-15T18:48:26.141Z,1655318906.141 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0012.lzma.bak
2022-06-15T18:48:26.142Z,1655318906.142 [DataOverHttps](INFO): SBD MOMSN=16891254
2022-06-15T18:48:30.051Z,1655318910.051 [NAL9602](INFO): SBD MO Status=0, MOMSN=9039, MT Status=0, MTMSN=0
2022-06-15T18:48:30.051Z,1655318910.051 [NAL9602](INFO): No messages in MT queue
2022-06-15T18:48:42.248Z,1655318922.248 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220615T182541/Express0013.lzma
2022-06-15T18:48:43.249Z,1655318923.249 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0013.lzma.bak
2022-06-15T18:48:43.250Z,1655318923.250 [DataOverHttps](INFO): SBD MOMSN=16891257
2022-06-15T18:48:45.014Z,1655318925.014 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T18:48:45.014Z,1655318925.014 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T18:48:45.014Z,1655318925.014 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T18:49:00.739Z,1655318940.739 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T18:53:45.621Z,1655319225.621 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T18:53:45.621Z,1655319225.621 [Default:CheckIn:C.Wait] Stopped
2022-06-15T18:53:45.621Z,1655319225.621 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T18:53:45.621Z,1655319225.621 [Default:CheckIn:D] Running Loop=1
2022-06-15T18:53:46.027Z,1655319226.027 [Default:CheckIn:D] Stopped
2022-06-15T18:53:46.027Z,1655319226.027 [Default:CheckIn:E] Running Loop=1
2022-06-15T18:53:46.444Z,1655319226.444 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.694759 min 
2022-06-15T18:53:46.445Z,1655319226.445 [Default:CheckIn:E] Stopped
2022-06-15T18:53:46.445Z,1655319226.445 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T18:53:46.445Z,1655319226.445 [Default:CheckIn] Stopped
2022-06-15T18:53:46.445Z,1655319226.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T18:53:46.445Z,1655319226.445 [Default:CheckIn](INFO): Running loop #8
2022-06-15T18:53:46.445Z,1655319226.445 [Default:CheckIn] Running Loop=8
2022-06-15T18:53:46.445Z,1655319226.445 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T18:53:46.445Z,1655319226.445 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T18:53:48.441Z,1655319228.441 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185346.00,A,3648.14036,N,12147.20759,W,0.194,339.31,150622,,,D*70
2022-06-15T18:53:48.443Z,1655319228.443 [NAL9602](INFO): GPS fix at 20220615T185346: (36.802339, -121.786793)
2022-06-15T18:53:48.455Z,1655319228.455 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T18:53:48.455Z,1655319228.455 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T18:53:57.883Z,1655319237.883 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220615T182541/Courier0015.lzma
2022-06-15T18:53:58.885Z,1655319238.885 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0015.lzma.bak
2022-06-15T18:53:58.886Z,1655319238.886 [DataOverHttps](INFO): SBD MOMSN=16891261
2022-06-15T18:54:14.836Z,1655319254.836 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220615T182541/Express0016.lzma
2022-06-15T18:54:15.838Z,1655319255.838 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0016.lzma.bak
2022-06-15T18:54:15.838Z,1655319255.838 [DataOverHttps](INFO): SBD MOMSN=16891264
2022-06-15T18:54:17.580Z,1655319257.580 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T18:54:17.581Z,1655319257.581 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T18:54:17.581Z,1655319257.581 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T18:55:14.505Z,1655319314.505 [NAL9602](INFO): SBD MO Status=2, MOMSN=9040, MT Status=2, MTMSN=0
2022-06-15T18:55:14.505Z,1655319314.505 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-15T18:55:25.008Z,1655319325.008 [NAL9602](INFO): SBD MO Status=0, MOMSN=9040, MT Status=0, MTMSN=0
2022-06-15T18:55:25.008Z,1655319325.008 [NAL9602](INFO): No messages in MT queue
2022-06-15T18:55:55.708Z,1655319355.708 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T18:59:18.164Z,1655319558.164 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T18:59:18.164Z,1655319558.164 [Default:CheckIn:C.Wait] Stopped
2022-06-15T18:59:18.164Z,1655319558.164 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T18:59:18.164Z,1655319558.164 [Default:CheckIn:D] Running Loop=1
2022-06-15T18:59:18.570Z,1655319558.570 [Default:CheckIn:D] Stopped
2022-06-15T18:59:18.570Z,1655319558.570 [Default:CheckIn:E] Running Loop=1
2022-06-15T18:59:18.983Z,1655319558.983 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.237146 min 
2022-06-15T18:59:18.983Z,1655319558.983 [Default:CheckIn:E] Stopped
2022-06-15T18:59:18.983Z,1655319558.983 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T18:59:18.988Z,1655319558.988 [Default:CheckIn] Stopped
2022-06-15T18:59:18.988Z,1655319558.988 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T18:59:18.988Z,1655319558.988 [Default:CheckIn](INFO): Running loop #9
2022-06-15T18:59:18.988Z,1655319558.988 [Default:CheckIn] Running Loop=9
2022-06-15T18:59:18.988Z,1655319558.988 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T18:59:18.988Z,1655319558.988 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T18:59:21.105Z,1655319561.105 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185919.00,A,3648.17387,N,12147.27913,W,0.292,290.45,150622,,,D*79
2022-06-15T18:59:21.107Z,1655319561.107 [NAL9602](INFO): GPS fix at 20220615T185919: (36.802898, -121.787986)
2022-06-15T18:59:21.135Z,1655319561.135 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T18:59:21.135Z,1655319561.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T18:59:29.067Z,1655319569.067 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220615T182541/Courier0018.lzma
2022-06-15T18:59:30.070Z,1655319570.070 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0018.lzma.bak
2022-06-15T18:59:30.070Z,1655319570.070 [DataOverHttps](INFO): SBD MOMSN=16891270
2022-06-15T18:59:50.484Z,1655319590.484 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220615T182541/Express0019.lzma
2022-06-15T18:59:51.486Z,1655319591.486 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0019.lzma.bak
2022-06-15T18:59:51.486Z,1655319591.486 [DataOverHttps](INFO): SBD MOMSN=16891273
2022-06-15T18:59:54.289Z,1655319594.289 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T18:59:54.290Z,1655319594.290 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T18:59:54.290Z,1655319594.290 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:02:55.633Z,1655319775.633 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-06-15T19:02:55.636Z,1655319775.636 [BPC1](INFO): Received data from all battery sticks.
2022-06-15T19:04:23.308Z,1655319863.308 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-15T19:04:54.013Z,1655319894.013 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T19:04:54.844Z,1655319894.844 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T19:04:54.844Z,1655319894.844 [Default:CheckIn:C.Wait] Stopped
2022-06-15T19:04:54.844Z,1655319894.844 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T19:04:54.845Z,1655319894.845 [Default:CheckIn:D] Running Loop=1
2022-06-15T19:04:55.261Z,1655319895.261 [Default:CheckIn:D] Stopped
2022-06-15T19:04:55.261Z,1655319895.261 [Default:CheckIn:E] Running Loop=1
2022-06-15T19:04:55.643Z,1655319895.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.848661 min 
2022-06-15T19:04:55.644Z,1655319895.644 [Default:CheckIn:E] Stopped
2022-06-15T19:04:55.644Z,1655319895.644 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T19:04:55.644Z,1655319895.644 [Default:CheckIn] Stopped
2022-06-15T19:04:55.644Z,1655319895.644 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T19:04:55.644Z,1655319895.644 [Default:CheckIn](INFO): Running loop #10
2022-06-15T19:04:55.645Z,1655319895.645 [Default:CheckIn] Running Loop=10
2022-06-15T19:04:55.645Z,1655319895.645 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T19:04:55.645Z,1655319895.645 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T19:04:57.652Z,1655319897.652 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190456.00,A,3648.17566,N,12147.26379,W,0.078,92.81,150622,,,A*4E
2022-06-15T19:04:57.654Z,1655319897.654 [NAL9602](INFO): GPS fix at 20220615T190456: (36.802928, -121.787730)
2022-06-15T19:04:57.666Z,1655319897.666 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T19:04:57.666Z,1655319897.666 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T19:05:05.164Z,1655319905.164 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20220615T182541/Courier0021.lzma
2022-06-15T19:05:06.165Z,1655319906.165 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0021.lzma.bak
2022-06-15T19:05:06.166Z,1655319906.166 [DataOverHttps](INFO): SBD MOMSN=16891276
2022-06-15T19:05:22.360Z,1655319922.360 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20220615T182541/Express0022.lzma
2022-06-15T19:05:23.362Z,1655319923.362 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0022.lzma.bak
2022-06-15T19:05:23.362Z,1655319923.362 [DataOverHttps](INFO): SBD MOMSN=16891279
2022-06-15T19:05:25.140Z,1655319925.140 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T19:05:25.140Z,1655319925.140 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T19:05:25.140Z,1655319925.140 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:05:28.358Z,1655319928.358 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-06-15T19:05:28.436Z,1655319928.436 [NAL9602](ERROR): received: 
+CSQ:0

OK40, 0, 0, 0, 0

OK
2022-06-15T19:05:55.023Z,1655319955.023 [NAL9602](INFO): SBD MO Status=2, MOMSN=9041, MT Status=2, MTMSN=0
2022-06-15T19:05:55.023Z,1655319955.023 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-15T19:08:05.130Z,1655320085.130 [NAL9602](INFO): SBD MO Status=2, MOMSN=9041, MT Status=2, MTMSN=0
2022-06-15T19:08:05.130Z,1655320085.130 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-15T19:10:00.263Z,1655320200.263 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-15T19:10:25.731Z,1655320225.731 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T19:10:25.731Z,1655320225.731 [Default:CheckIn:C.Wait] Stopped
2022-06-15T19:10:25.731Z,1655320225.731 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T19:10:25.731Z,1655320225.731 [Default:CheckIn:D] Running Loop=1
2022-06-15T19:10:26.139Z,1655320226.139 [Default:CheckIn:D] Stopped
2022-06-15T19:10:26.139Z,1655320226.139 [Default:CheckIn:E] Running Loop=1
2022-06-15T19:10:26.549Z,1655320226.549 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.363293 min 
2022-06-15T19:10:26.549Z,1655320226.549 [Default:CheckIn:E] Stopped
2022-06-15T19:10:26.549Z,1655320226.549 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T19:10:26.549Z,1655320226.549 [Default:CheckIn] Stopped
2022-06-15T19:10:26.549Z,1655320226.549 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T19:10:26.549Z,1655320226.549 [Default:CheckIn](INFO): Running loop #11
2022-06-15T19:10:26.549Z,1655320226.549 [Default:CheckIn] Running Loop=11
2022-06-15T19:10:26.549Z,1655320226.549 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T19:10:26.550Z,1655320226.550 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T19:10:28.554Z,1655320228.554 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191027.00,A,3648.17485,N,12147.27335,W,0.078,92.81,150622,,,A*48
2022-06-15T19:10:28.556Z,1655320228.556 [NAL9602](INFO): GPS fix at 20220615T191027: (36.802914, -121.787889)
2022-06-15T19:10:28.586Z,1655320228.586 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T19:10:28.586Z,1655320228.586 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T19:10:35.807Z,1655320235.807 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220615T182541/Courier0024.lzma
2022-06-15T19:10:36.809Z,1655320236.809 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0024.lzma.bak
2022-06-15T19:10:36.810Z,1655320236.810 [DataOverHttps](INFO): SBD MOMSN=16891283
2022-06-15T19:10:52.924Z,1655320252.924 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220615T182541/Express0025.lzma
2022-06-15T19:10:53.925Z,1655320253.925 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0025.lzma.bak
2022-06-15T19:10:53.926Z,1655320253.926 [DataOverHttps](INFO): SBD MOMSN=16891286
2022-06-15T19:10:55.626Z,1655320255.626 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T19:10:55.626Z,1655320255.626 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T19:10:55.626Z,1655320255.626 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:11:01.269Z,1655320261.269 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T19:15:56.219Z,1655320556.219 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T19:15:56.219Z,1655320556.219 [Default:CheckIn:C.Wait] Stopped
2022-06-15T19:15:56.219Z,1655320556.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T19:15:56.228Z,1655320556.228 [Default:CheckIn:D] Running Loop=1
2022-06-15T19:15:56.631Z,1655320556.631 [Default:CheckIn:D] Stopped
2022-06-15T19:15:56.631Z,1655320556.631 [Default:CheckIn:E] Running Loop=1
2022-06-15T19:15:57.016Z,1655320557.016 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.871488 min 
2022-06-15T19:15:57.016Z,1655320557.016 [Default:CheckIn:E] Stopped
2022-06-15T19:15:57.016Z,1655320557.016 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T19:15:57.016Z,1655320557.016 [Default:CheckIn] Stopped
2022-06-15T19:15:57.016Z,1655320557.016 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T19:15:57.016Z,1655320557.016 [Default:CheckIn](INFO): Running loop #12
2022-06-15T19:15:57.017Z,1655320557.017 [Default:CheckIn] Running Loop=12
2022-06-15T19:15:57.017Z,1655320557.017 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T19:15:57.017Z,1655320557.017 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T19:15:59.021Z,1655320559.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191557.00,A,3648.17536,N,12147.27818,W,0.078,92.81,150622,,,A*47
2022-06-15T19:15:59.023Z,1655320559.023 [NAL9602](INFO): GPS fix at 20220615T191557: (36.802923, -121.787970)
2022-06-15T19:15:59.035Z,1655320559.035 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T19:15:59.035Z,1655320559.035 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T19:16:06.831Z,1655320566.831 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220615T182541/Courier0027.lzma
2022-06-15T19:16:07.833Z,1655320567.833 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0027.lzma.bak
2022-06-15T19:16:07.834Z,1655320567.834 [DataOverHttps](INFO): SBD MOMSN=16891290
2022-06-15T19:16:24.391Z,1655320584.391 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220615T182541/Express0028.lzma
2022-06-15T19:16:25.394Z,1655320585.394 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0028.lzma.bak
2022-06-15T19:16:25.394Z,1655320585.394 [DataOverHttps](INFO): SBD MOMSN=16891293
2022-06-15T19:16:27.311Z,1655320587.311 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T19:16:27.311Z,1655320587.311 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T19:16:27.311Z,1655320587.311 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:16:29.729Z,1655320589.729 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-06-15T19:16:29.804Z,1655320589.804 [NAL9602](ERROR): received: 
+CSQ:0

OK41, 2, 0, 0, 0

OK
2022-06-15T19:21:01.227Z,1655320861.227 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-15T19:21:27.899Z,1655320887.899 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T19:21:27.899Z,1655320887.899 [Default:CheckIn:C.Wait] Stopped
2022-06-15T19:21:27.899Z,1655320887.899 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T19:21:27.900Z,1655320887.900 [Default:CheckIn:D] Running Loop=1
2022-06-15T19:21:28.303Z,1655320888.303 [Default:CheckIn:D] Stopped
2022-06-15T19:21:28.303Z,1655320888.303 [Default:CheckIn:E] Running Loop=1
2022-06-15T19:21:28.714Z,1655320888.714 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.399349 min 
2022-06-15T19:21:28.714Z,1655320888.714 [Default:CheckIn:E] Stopped
2022-06-15T19:21:28.715Z,1655320888.715 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T19:21:28.715Z,1655320888.715 [Default:CheckIn] Stopped
2022-06-15T19:21:28.715Z,1655320888.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T19:21:28.715Z,1655320888.715 [Default:CheckIn](INFO): Running loop #13
2022-06-15T19:21:28.715Z,1655320888.715 [Default:CheckIn] Running Loop=13
2022-06-15T19:21:28.715Z,1655320888.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T19:21:28.715Z,1655320888.715 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T19:21:30.715Z,1655320890.715 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192129.00,A,3648.17608,N,12147.27641,W,0.039,92.81,150622,,,A*40
2022-06-15T19:21:30.718Z,1655320890.718 [NAL9602](INFO): GPS fix at 20220615T192129: (36.802935, -121.787940)
2022-06-15T19:21:30.757Z,1655320890.757 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T19:21:30.757Z,1655320890.757 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T19:21:37.811Z,1655320897.811 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220615T182541/Courier0030.lzma
2022-06-15T19:21:38.814Z,1655320898.814 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0030.lzma.bak
2022-06-15T19:21:38.814Z,1655320898.814 [DataOverHttps](INFO): SBD MOMSN=16891296
2022-06-15T19:21:58.380Z,1655320918.380 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20220615T182541/Express0031.lzma
2022-06-15T19:21:59.381Z,1655320919.381 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0031.lzma.bak
2022-06-15T19:21:59.382Z,1655320919.382 [DataOverHttps](INFO): SBD MOMSN=16891299
2022-06-15T19:22:01.122Z,1655320921.122 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T19:22:01.122Z,1655320921.122 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T19:22:01.122Z,1655320921.122 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:22:03.525Z,1655320923.525 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T19:27:01.722Z,1655321221.722 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T19:27:01.722Z,1655321221.722 [Default:CheckIn:C.Wait] Stopped
2022-06-15T19:27:01.722Z,1655321221.722 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T19:27:01.722Z,1655321221.722 [Default:CheckIn:D] Running Loop=1
2022-06-15T19:27:02.122Z,1655321222.122 [Default:CheckIn:D] Stopped
2022-06-15T19:27:02.122Z,1655321222.122 [Default:CheckIn:E] Running Loop=1
2022-06-15T19:27:02.505Z,1655321222.505 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.963005 min 
2022-06-15T19:27:02.505Z,1655321222.505 [Default:CheckIn:E] Stopped
2022-06-15T19:27:02.505Z,1655321222.505 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T19:27:02.505Z,1655321222.505 [Default:CheckIn] Stopped
2022-06-15T19:27:02.505Z,1655321222.505 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T19:27:02.505Z,1655321222.505 [Default:CheckIn](INFO): Running loop #14
2022-06-15T19:27:02.505Z,1655321222.505 [Default:CheckIn] Running Loop=14
2022-06-15T19:27:02.505Z,1655321222.505 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T19:27:02.506Z,1655321222.506 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T19:27:04.509Z,1655321224.509 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192702.00,A,3648.17541,N,12147.27664,W,0.175,0.00,150622,,,A*7D
2022-06-15T19:27:04.511Z,1655321224.511 [NAL9602](INFO): GPS fix at 20220615T192702: (36.802923, -121.787944)
2022-06-15T19:27:04.523Z,1655321224.523 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T19:27:04.523Z,1655321224.523 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T19:27:12.871Z,1655321232.871 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220615T182541/Courier0033.lzma
2022-06-15T19:27:13.873Z,1655321233.873 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0033.lzma.bak
2022-06-15T19:27:13.874Z,1655321233.874 [DataOverHttps](INFO): SBD MOMSN=16891303
2022-06-15T19:27:30.351Z,1655321250.351 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220615T182541/Express0034.lzma
2022-06-15T19:27:31.354Z,1655321251.354 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0034.lzma.bak
2022-06-15T19:27:31.354Z,1655321251.354 [DataOverHttps](INFO): SBD MOMSN=16891306
2022-06-15T19:27:33.213Z,1655321253.213 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T19:27:33.213Z,1655321253.213 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T19:27:33.213Z,1655321253.213 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:27:35.214Z,1655321255.214 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-06-15T19:27:35.288Z,1655321255.288 [NAL9602](ERROR): received: 
+CSQ:0

OK41, 2, 0, 0, 0

OK
2022-06-15T19:29:47.734Z,1655321387.734 [NAL9602](INFO): SBD MO Status=0, MOMSN=9041, MT Status=0, MTMSN=0
2022-06-15T19:29:47.734Z,1655321387.734 [NAL9602](INFO): No messages in MT queue
2022-06-15T19:29:57.932Z,1655321397.932 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-15T19:30:08.766Z,1655321408.766 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005727
CHAN A1 (24V): 0.001086
CHAN A2 (12V): -0.005385
CHAN A3 (5V): -0.002664
CHAN B0 (3.3V): -0.001610
CHAN B1 (3.15aV): -0.001196
CHAN B2 (3.15bV): -0.000969
CHAN B3 (GND): -0.000316
OPEN: 0.004640
Full Scale: +/- 1 mA
2022-06-15T19:30:18.435Z,1655321418.435 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T19:32:33.846Z,1655321553.846 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T19:32:33.846Z,1655321553.846 [Default:CheckIn:C.Wait] Stopped
2022-06-15T19:32:33.846Z,1655321553.846 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T19:32:33.846Z,1655321553.846 [Default:CheckIn:D] Running Loop=1
2022-06-15T19:32:34.253Z,1655321554.253 [Default:CheckIn:D] Stopped
2022-06-15T19:32:34.253Z,1655321554.253 [Default:CheckIn:E] Running Loop=1
2022-06-15T19:32:34.667Z,1655321554.667 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.498527 min 
2022-06-15T19:32:34.667Z,1655321554.667 [Default:CheckIn:E] Stopped
2022-06-15T19:32:34.667Z,1655321554.667 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T19:32:34.667Z,1655321554.667 [Default:CheckIn] Stopped
2022-06-15T19:32:34.667Z,1655321554.667 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T19:32:34.672Z,1655321554.672 [Default:CheckIn](INFO): Running loop #15
2022-06-15T19:32:34.672Z,1655321554.672 [Default:CheckIn] Running Loop=15
2022-06-15T19:32:34.672Z,1655321554.672 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T19:32:34.672Z,1655321554.672 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T19:32:36.673Z,1655321556.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193235.00,A,3648.17606,N,12147.27537,W,0.019,0.00,150622,,,A*73
2022-06-15T19:32:36.676Z,1655321556.676 [NAL9602](INFO): GPS fix at 20220615T193235: (36.802934, -121.787923)
2022-06-15T19:32:36.687Z,1655321556.687 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T19:32:36.687Z,1655321556.687 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T19:32:44.459Z,1655321564.459 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220615T182541/Courier0036.lzma
2022-06-15T19:32:45.461Z,1655321565.461 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0036.lzma.bak
2022-06-15T19:32:45.462Z,1655321565.462 [DataOverHttps](INFO): SBD MOMSN=16891310
2022-06-15T19:32:51.615Z,1655321571.615 [NAL9602](INFO): SBD MO Status=0, MOMSN=9042, MT Status=0, MTMSN=0
2022-06-15T19:32:51.615Z,1655321571.615 [NAL9602](INFO): No messages in MT queue
2022-06-15T19:33:01.448Z,1655321581.448 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20220615T182541/Express0037.lzma
2022-06-15T19:33:02.450Z,1655321582.450 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0037.lzma.bak
2022-06-15T19:33:02.450Z,1655321582.450 [DataOverHttps](INFO): SBD MOMSN=16891313
2022-06-15T19:33:04.149Z,1655321584.149 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T19:33:04.149Z,1655321584.149 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T19:33:04.149Z,1655321584.149 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:33:22.321Z,1655321602.321 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T19:34:00.784Z,1655321640.784 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-06-15T19:34:00.784Z,1655321640.784 [DropWeight] Hardware Fault, FailCount= 1
2022-06-15T19:34:00.784Z,1655321640.784 [DropWeight](ERROR): Hardware Fault
2022-06-15T19:34:00.834Z,1655321640.834 [CommandExec](FAULT): Scheduling is paused
2022-06-15T19:34:00.834Z,1655321640.834 [CBIT](INFO): Critical error at 20220615T193400
2022-06-15T19:34:00.841Z,1655321640.841 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-06-15T19:34:00.841Z,1655321640.841 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-06-15T19:34:01.190Z,1655321641.190 [CBIT](INFO): Critical error at 20220615T193400
2022-06-15T19:38:04.858Z,1655321884.858 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T19:38:04.858Z,1655321884.858 [Default:CheckIn:C.Wait] Stopped
2022-06-15T19:38:04.858Z,1655321884.858 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T19:38:04.859Z,1655321884.859 [Default:CheckIn:D] Running Loop=1
2022-06-15T19:38:05.258Z,1655321885.258 [Default:CheckIn:D] Stopped
2022-06-15T19:38:05.258Z,1655321885.258 [Default:CheckIn:E] Running Loop=1
2022-06-15T19:38:05.714Z,1655321885.714 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.015275 min 
2022-06-15T19:38:05.714Z,1655321885.714 [Default:CheckIn:E] Stopped
2022-06-15T19:38:05.715Z,1655321885.715 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T19:38:05.715Z,1655321885.715 [Default:CheckIn] Stopped
2022-06-15T19:38:05.715Z,1655321885.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T19:38:05.715Z,1655321885.715 [Default:CheckIn](INFO): Running loop #16
2022-06-15T19:38:05.715Z,1655321885.715 [Default:CheckIn] Running Loop=16
2022-06-15T19:38:05.715Z,1655321885.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T19:38:05.715Z,1655321885.715 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T19:38:07.737Z,1655321887.737 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193806.00,A,3648.17479,N,12147.27867,W,0.408,0.00,150622,,,A*7F
2022-06-15T19:38:07.739Z,1655321887.739 [NAL9602](INFO): GPS fix at 20220615T193806: (36.802913, -121.787978)
2022-06-15T19:38:07.834Z,1655321887.834 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T19:38:07.834Z,1655321887.834 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T19:38:15.800Z,1655321895.800 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20220615T182541/Courier0039.lzma
2022-06-15T19:38:16.801Z,1655321896.801 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0039.lzma.bak
2022-06-15T19:38:16.802Z,1655321896.802 [DataOverHttps](INFO): SBD MOMSN=16891319
2022-06-15T19:38:29.895Z,1655321909.895 [NAL9602](INFO): SBD MO Status=0, MOMSN=9043, MT Status=0, MTMSN=0
2022-06-15T19:38:29.895Z,1655321909.895 [NAL9602](INFO): No messages in MT queue
2022-06-15T19:38:32.984Z,1655321912.984 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20220615T182541/Express0040.lzma
2022-06-15T19:38:33.986Z,1655321913.986 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0040.lzma.bak
2022-06-15T19:38:33.986Z,1655321913.986 [DataOverHttps](INFO): SBD MOMSN=16891324
2022-06-15T19:38:35.566Z,1655321915.566 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T19:38:35.566Z,1655321915.566 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T19:38:35.566Z,1655321915.566 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:39:00.595Z,1655321940.595 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T19:43:36.158Z,1655322216.158 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T19:43:36.158Z,1655322216.158 [Default:CheckIn:C.Wait] Stopped
2022-06-15T19:43:36.158Z,1655322216.158 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T19:43:36.158Z,1655322216.158 [Default:CheckIn:D] Running Loop=1
2022-06-15T19:43:36.548Z,1655322216.548 [Default:CheckIn:D] Stopped
2022-06-15T19:43:36.548Z,1655322216.548 [Default:CheckIn:E] Running Loop=1
2022-06-15T19:43:36.950Z,1655322216.950 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.536768 min 
2022-06-15T19:43:36.950Z,1655322216.950 [Default:CheckIn:E] Stopped
2022-06-15T19:43:36.950Z,1655322216.950 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T19:43:36.950Z,1655322216.950 [Default:CheckIn] Stopped
2022-06-15T19:43:36.950Z,1655322216.950 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T19:43:36.951Z,1655322216.951 [Default:CheckIn](INFO): Running loop #17
2022-06-15T19:43:36.951Z,1655322216.951 [Default:CheckIn] Running Loop=17
2022-06-15T19:43:36.951Z,1655322216.951 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T19:43:36.951Z,1655322216.951 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T19:43:38.960Z,1655322218.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194337.00,A,3648.17302,N,12147.27723,W,0.058,0.00,150622,,,A*74
2022-06-15T19:43:38.962Z,1655322218.962 [NAL9602](INFO): GPS fix at 20220615T194337: (36.802884, -121.787954)
2022-06-15T19:43:38.974Z,1655322218.974 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T19:43:38.974Z,1655322218.974 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T19:43:50.215Z,1655322230.215 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220615T182541/Courier0042.lzma
2022-06-15T19:43:51.226Z,1655322231.226 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0042.lzma.bak
2022-06-15T19:43:51.226Z,1655322231.226 [DataOverHttps](INFO): SBD MOMSN=16891330
2022-06-15T19:44:07.722Z,1655322247.722 [NAL9602](INFO): SBD MO Status=2, MOMSN=9044, MT Status=2, MTMSN=0
2022-06-15T19:44:07.722Z,1655322247.722 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-15T19:44:09.435Z,1655322249.435 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220615T182541/Express0043.lzma
2022-06-15T19:44:10.438Z,1655322250.438 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0043.lzma.bak
2022-06-15T19:44:10.438Z,1655322250.438 [DataOverHttps](INFO): SBD MOMSN=16891333
2022-06-15T19:44:12.189Z,1655322252.189 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T19:44:12.190Z,1655322252.190 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T19:44:12.190Z,1655322252.190 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:44:28.725Z,1655322268.725 [NAL9602](INFO): SBD MO Status=2, MOMSN=9044, MT Status=2, MTMSN=0
2022-06-15T19:44:28.725Z,1655322268.725 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-15T19:44:48.517Z,1655322288.517 [NAL9602](INFO): SBD MO Status=0, MOMSN=9044, MT Status=0, MTMSN=0
2022-06-15T19:44:48.517Z,1655322288.517 [NAL9602](INFO): No messages in MT queue
2022-06-15T19:45:19.235Z,1655322319.235 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T19:49:01.115Z,1655322541.115 [CBIT](INFO): Clearing failed state for component DropWeight
2022-06-15T19:49:01.115Z,1655322541.115 [DropWeight] No Fault, FailCount= 1
2022-06-15T19:49:12.809Z,1655322552.809 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T19:49:12.809Z,1655322552.809 [Default:CheckIn:C.Wait] Stopped
2022-06-15T19:49:12.809Z,1655322552.809 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T19:49:12.809Z,1655322552.809 [Default:CheckIn:D] Running Loop=1
2022-06-15T19:49:13.199Z,1655322553.199 [Default:CheckIn:D] Stopped
2022-06-15T19:49:13.199Z,1655322553.199 [Default:CheckIn:E] Running Loop=1
2022-06-15T19:49:13.603Z,1655322553.603 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.147616 min 
2022-06-15T19:49:13.603Z,1655322553.603 [Default:CheckIn:E] Stopped
2022-06-15T19:49:13.603Z,1655322553.603 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T19:49:13.603Z,1655322553.603 [Default:CheckIn] Stopped
2022-06-15T19:49:13.603Z,1655322553.603 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T19:49:13.604Z,1655322553.604 [Default:CheckIn](INFO): Running loop #18
2022-06-15T19:49:13.604Z,1655322553.604 [Default:CheckIn] Running Loop=18
2022-06-15T19:49:13.604Z,1655322553.604 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T19:49:13.604Z,1655322553.604 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T19:49:15.615Z,1655322555.615 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194914.00,A,3648.16954,N,12147.27734,W,0.097,190.48,150622,,,A*76
2022-06-15T19:49:15.617Z,1655322555.617 [NAL9602](INFO): GPS fix at 20220615T194914: (36.802826, -121.787956)
2022-06-15T19:49:15.629Z,1655322555.629 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T19:49:15.629Z,1655322555.629 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T19:49:23.463Z,1655322563.463 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220615T182541/Courier0045.lzma
2022-06-15T19:49:24.465Z,1655322564.465 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0045.lzma.bak
2022-06-15T19:49:24.466Z,1655322564.466 [DataOverHttps](INFO): SBD MOMSN=16891368
2022-06-15T19:49:40.563Z,1655322580.563 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220615T182541/Express0046.lzma
2022-06-15T19:49:41.566Z,1655322581.566 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0046.lzma.bak
2022-06-15T19:49:41.566Z,1655322581.566 [DataOverHttps](INFO): SBD MOMSN=16891372
2022-06-15T19:49:43.970Z,1655322583.970 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T19:49:43.970Z,1655322583.970 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T19:49:43.971Z,1655322583.971 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:54:18.380Z,1655322858.380 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-15T19:54:44.685Z,1655322884.685 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T19:54:44.685Z,1655322884.685 [Default:CheckIn:C.Wait] Stopped
2022-06-15T19:54:44.685Z,1655322884.685 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T19:54:44.686Z,1655322884.686 [Default:CheckIn:D] Running Loop=1
2022-06-15T19:54:45.112Z,1655322885.112 [Default:CheckIn:D] Stopped
2022-06-15T19:54:45.112Z,1655322885.112 [Default:CheckIn:E] Running Loop=1
2022-06-15T19:54:45.484Z,1655322885.484 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.679517 min 
2022-06-15T19:54:45.484Z,1655322885.484 [Default:CheckIn:E] Stopped
2022-06-15T19:54:45.484Z,1655322885.484 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T19:54:45.485Z,1655322885.485 [Default:CheckIn] Stopped
2022-06-15T19:54:45.485Z,1655322885.485 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T19:54:45.485Z,1655322885.485 [Default:CheckIn](INFO): Running loop #19
2022-06-15T19:54:45.485Z,1655322885.485 [Default:CheckIn] Running Loop=19
2022-06-15T19:54:45.485Z,1655322885.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T19:54:45.485Z,1655322885.485 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T19:54:47.475Z,1655322887.475 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195445.00,A,3648.15542,N,12147.27620,W,1.536,207.22,150622,,,A*7C
2022-06-15T19:54:47.478Z,1655322887.478 [NAL9602](INFO): GPS fix at 20220615T195445: (36.802590, -121.787937)
2022-06-15T19:54:47.489Z,1655322887.489 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T19:54:47.489Z,1655322887.489 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T19:54:48.598Z,1655322888.598 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220615T182541/Courier0048.lzma
2022-06-15T19:54:48.993Z,1655322888.993 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0048.lzma.bak
2022-06-15T19:54:48.994Z,1655322888.994 [DataOverHttps](INFO): SBD MOMSN=16891404
2022-06-15T19:55:05.556Z,1655322905.556 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20220615T182541/Express0049.lzma
2022-06-15T19:55:06.558Z,1655322906.558 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0049.lzma.bak
2022-06-15T19:55:06.558Z,1655322906.558 [DataOverHttps](INFO): SBD MOMSN=16891407
2022-06-15T19:55:08.670Z,1655322908.670 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T19:55:08.670Z,1655322908.670 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T19:55:08.670Z,1655322908.670 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T19:55:20.369Z,1655322920.369 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-15T19:59:20.928Z,1655323160.928 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239265
2022-06-15T19:59:31.489Z,1655323171.489 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003781
2022-06-15T20:00:09.226Z,1655323209.226 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-15T20:00:09.226Z,1655323209.226 [Default:CheckIn:C.Wait] Stopped
2022-06-15T20:00:09.227Z,1655323209.227 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T20:00:09.227Z,1655323209.227 [Default:CheckIn:D] Running Loop=1
2022-06-15T20:00:09.630Z,1655323209.630 [Default:CheckIn:D] Stopped
2022-06-15T20:00:09.631Z,1655323209.631 [Default:CheckIn:E] Running Loop=1
2022-06-15T20:00:10.041Z,1655323210.041 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.088151 min 
2022-06-15T20:00:10.041Z,1655323210.041 [Default:CheckIn:E] Stopped
2022-06-15T20:00:10.041Z,1655323210.041 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-15T20:00:10.041Z,1655323210.041 [Default:CheckIn] Stopped
2022-06-15T20:00:10.041Z,1655323210.041 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T20:00:10.041Z,1655323210.041 [Default:CheckIn](INFO): Running loop #20
2022-06-15T20:00:10.042Z,1655323210.042 [Default:CheckIn] Running Loop=20
2022-06-15T20:00:10.042Z,1655323210.042 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-15T20:00:10.042Z,1655323210.042 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-15T20:00:12.059Z,1655323212.059 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200010.00,A,3648.16835,N,12147.27429,W,3.110,303.90,150622,,,A*7C
2022-06-15T20:00:12.061Z,1655323212.061 [NAL9602](INFO): GPS fix at 20220615T200010: (36.802806, -121.787905)
2022-06-15T20:00:12.073Z,1655323212.073 [Default:CheckIn:Read_GPS] Stopped
2022-06-15T20:00:12.073Z,1655323212.073 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-15T20:00:19.807Z,1655323219.807 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220615T182541/Courier0051.lzma
2022-06-15T20:00:20.809Z,1655323220.809 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Courier0051.lzma.bak
2022-06-15T20:00:20.810Z,1655323220.810 [DataOverHttps](INFO): SBD MOMSN=16891413
2022-06-15T20:00:40.463Z,1655323240.463 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220615T182541/Express0052.lzma
2022-06-15T20:00:41.466Z,1655323241.466 [DataOverHttps](INFO): Moved sent file to Logs/20220615T182541/Express0052.lzma.bak
2022-06-15T20:00:41.466Z,1655323241.466 [DataOverHttps](INFO): SBD MOMSN=16891416
2022-06-15T20:00:43.721Z,1655323243.721 [Default:CheckIn:Read_Iridium] Stopped
2022-06-15T20:00:43.721Z,1655323243.721 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-15T20:00:43.721Z,1655323243.721 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-15T20:02:22.644Z,1655323342.644 [CommandExec](IMPORTANT): got command quit
2022-06-15T20:02:23.664Z,1655323343.664 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:23.665Z,1655323343.665 [CommandExec](INFO): Uninitializing the command executive.
2022-06-15T20:02:23.665Z,1655323343.665 [CommandExec](INFO): Uninitializing the command scheduler.
2022-06-15T20:02:23.665Z,1655323343.665 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:23.860Z,1655323343.860 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-06-15T20:02:23.860Z,1655323343.860 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-06-15T20:02:23.860Z,1655323343.860 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:23.860Z,1655323343.860 [NavChartDb](INFO): Join timeout helper Thread ID is 304
2022-06-15T20:02:24.044Z,1655323344.044 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:24.044Z,1655323344.044 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:24.062Z,1655323344.062 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-06-15T20:02:24.062Z,1655323344.062 [DAT ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:24.063Z,1655323344.063 [DAT](INFO): Join timeout helper Thread ID is 305
2022-06-15T20:02:24.216Z,1655323344.216 [DAT](INFO): Powering down
2022-06-15T20:02:24.288Z,1655323344.288 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:24.288Z,1655323344.288 [DAT](INFO): Powering down
2022-06-15T20:02:24.289Z,1655323344.289 [DAT ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:24.292Z,1655323344.292 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-06-15T20:02:24.292Z,1655323344.292 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:24.292Z,1655323344.292 [Radio_Surface](INFO): Join timeout helper Thread ID is 306
2022-06-15T20:02:24.372Z,1655323344.372 [Radio_Surface](INFO): Powering down
2022-06-15T20:02:24.373Z,1655323344.373 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:24.373Z,1655323344.373 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:24.376Z,1655323344.376 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-06-15T20:02:24.376Z,1655323344.376 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:24.376Z,1655323344.376 [Onboard](INFO): Join timeout helper Thread ID is 307
2022-06-15T20:02:24.756Z,1655323344.756 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-06-15T20:02:25.060Z,1655323345.060 [Power24vConverter](INFO): Powering down.
2022-06-15T20:02:28.536Z,1655323348.536 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:28.536Z,1655323348.536 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:28.545Z,1655323348.545 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-06-15T20:02:28.545Z,1655323348.545 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:28.546Z,1655323348.546 [DataOverHttps](INFO): Join timeout helper Thread ID is 308
2022-06-15T20:02:28.772Z,1655323348.772 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:28.772Z,1655323348.772 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:28.785Z,1655323348.785 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-06-15T20:02:28.785Z,1655323348.785 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:28.785Z,1655323348.785 [BackseatComponent](INFO): Join timeout helper Thread ID is 309
2022-06-15T20:02:28.812Z,1655323348.812 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:28.812Z,1655323348.812 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:28.828Z,1655323348.828 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-06-15T20:02:28.829Z,1655323348.829 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:28.829Z,1655323348.829 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 310
2022-06-15T20:02:29.292Z,1655323349.292 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:29.292Z,1655323349.292 [WetLabsBB2FL](INFO): Powering down
2022-06-15T20:02:29.293Z,1655323349.293 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:29.304Z,1655323349.304 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-06-15T20:02:29.304Z,1655323349.304 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:29.305Z,1655323349.305 [CTD_Seabird](INFO): Join timeout helper Thread ID is 311
2022-06-15T20:02:30.224Z,1655323350.224 [CTD_Seabird](INFO): Powering down
2022-06-15T20:02:30.236Z,1655323350.236 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:30.236Z,1655323350.236 [CTD_Seabird](INFO): Powering down
2022-06-15T20:02:30.248Z,1655323350.248 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.249Z,1655323350.249 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-06-15T20:02:30.249Z,1655323350.249 [logger ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.249Z,1655323350.249 [logger](INFO): Join timeout helper Thread ID is 312
2022-06-15T20:02:30.264Z,1655323350.264 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:30.264Z,1655323350.264 [logger ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.272Z,1655323350.272 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-06-15T20:02:30.273Z,1655323350.273 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.273Z,1655323350.273 [CommandLine](INFO): Join timeout helper Thread ID is 313
2022-06-15T20:02:30.276Z,1655323350.276 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:30.276Z,1655323350.276 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.285Z,1655323350.285 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-06-15T20:02:30.285Z,1655323350.285 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.286Z,1655323350.286 [CommandExec](INFO): Join timeout helper Thread ID is 314
2022-06-15T20:02:30.288Z,1655323350.288 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-06-15T20:02:30.289Z,1655323350.289 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.289Z,1655323350.289 [controlThread](INFO): Join timeout helper Thread ID is 315
2022-06-15T20:02:30.324Z,1655323350.324 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-15T20:02:30.324Z,1655323350.324 [controlThread](DEBUG): Uninitializing ControlThread
2022-06-15T20:02:30.325Z,1655323350.325 [AHRS_M2](INFO): Powering down
2022-06-15T20:02:30.396Z,1655323350.396 [NAL9602](INFO): Powering down
2022-06-15T20:02:30.398Z,1655323350.398 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-06-15T20:02:30.399Z,1655323350.399 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-06-15T20:02:30.400Z,1655323350.400 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-06-15T20:02:30.400Z,1655323350.400 [MissionManager](INFO): Uninitializing Mission Default
2022-06-15T20:02:30.400Z,1655323350.400 [Default] Stopped
2022-06-15T20:02:30.401Z,1655323350.401 [Default](DEBUG): Aggregate::uninitialize Default
2022-06-15T20:02:30.401Z,1655323350.401 [Default:B.GoToSurface] Stopped
2022-06-15T20:02:30.401Z,1655323350.401 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-06-15T20:02:30.401Z,1655323350.401 [Default:CheckIn] Stopped
2022-06-15T20:02:30.401Z,1655323350.401 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-15T20:02:30.401Z,1655323350.401 [Default:CheckIn:C.Wait] Stopped
2022-06-15T20:02:30.401Z,1655323350.401 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-15T20:02:30.404Z,1655323350.404 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-06-15T20:02:30.404Z,1655323350.404 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-06-15T20:02:30.404Z,1655323350.404 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-06-15T20:02:30.405Z,1655323350.405 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-06-15T20:02:30.405Z,1655323350.405 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-06-15T20:02:30.405Z,1655323350.405 [BuoyancyServo](INFO): Powering down
2022-06-15T20:02:30.420Z,1655323350.420 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-06-15T20:02:30.420Z,1655323350.420 [ElevatorServo](INFO): Powering down
2022-06-15T20:02:30.421Z,1655323350.421 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-06-15T20:02:30.421Z,1655323350.421 [MassServo](INFO): Powering down
2022-06-15T20:02:30.422Z,1655323350.422 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-06-15T20:02:30.422Z,1655323350.422 [RudderServo](INFO): Powering down
2022-06-15T20:02:30.422Z,1655323350.422 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-06-15T20:02:30.423Z,1655323350.423 [ThrusterHE](INFO): Powering down
2022-06-15T20:02:30.424Z,1655323350.424 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-06-15T20:02:30.424Z,1655323350.424 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-06-15T20:02:30.424Z,1655323350.424 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-06-15T20:02:30.424Z,1655323350.424 [CBIT](DEBUG): Powering off loads.
2022-06-15T20:02:30.435Z,1655323350.435 [CBIT](DEBUG): Disabling WDT.
2022-06-15T20:02:30.447Z,1655323350.447 [CBIT](DEBUG): Opening all GF detection circuits.
2022-06-15T20:02:30.449Z,1655323350.449 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.505Z,1655323350.505 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.510Z,1655323350.510 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.555Z,1655323350.555 [DAT ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.559Z,1655323350.559 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.562Z,1655323350.562 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.568Z,1655323350.568 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.609Z,1655323350.609 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.651Z,1655323350.651 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-15T20:02:30.761Z,1655323350.761 [logger ThreadHandler](INFO): Thread cancelled.