2023-04-25T22:43:44.019Z,1682462624.019 [CommandExec](IMPORTANT): got command restart logs
2023-04-25T22:45:19.488Z,1682462719.488 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-25T22:45:19.488Z,1682462719.488 [Default:CheckIn:C.Wait] Stopped
2023-04-25T22:45:19.489Z,1682462719.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-25T22:45:19.489Z,1682462719.489 [Default:CheckIn:D] Running Loop=1
2023-04-25T22:45:19.900Z,1682462719.901 [Default:CheckIn:D] Stopped
2023-04-25T22:45:19.901Z,1682462719.901 [Default:CheckIn:E] Running Loop=1
2023-04-25T22:45:20.338Z,1682462720.338 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.734731 min 
2023-04-25T22:45:20.338Z,1682462720.338 [Default:CheckIn:E] Stopped
2023-04-25T22:45:20.338Z,1682462720.338 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-25T22:45:20.338Z,1682462720.338 [Default:CheckIn] Stopped
2023-04-25T22:45:20.339Z,1682462720.339 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-25T22:45:20.339Z,1682462720.339 [Default:CheckIn](INFO): Running loop #4
2023-04-25T22:45:20.339Z,1682462720.339 [Default:CheckIn] Running Loop=4
2023-04-25T22:45:20.339Z,1682462720.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-25T22:45:20.339Z,1682462720.339 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-25T22:45:22.293Z,1682462722.293 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224532.00,A,3647.85378,N,12149.55320,W,28.672,79.33,250423,,,D*75
2023-04-25T22:45:22.295Z,1682462722.295 [NAL9602](INFO): GPS fix at 20230425T224532: (36.797563, -121.825887)
2023-04-25T22:45:22.326Z,1682462722.326 [Default:CheckIn:Read_GPS] Stopped
2023-04-25T22:45:22.326Z,1682462722.326 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-25T22:45:23.188Z,1682462723.188 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230425T213717/Courier0024.lzma
2023-04-25T22:45:23.791Z,1682462723.791 [DataOverHttps](INFO): Moved sent file to Logs/20230425T213717/Courier0024.lzma.bak
2023-04-25T22:45:23.792Z,1682462723.792 [DataOverHttps](INFO): SBD MOMSN=18267332
2023-04-25T22:45:43.994Z,1682462743.994 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20230425T224344/Courier0000.lzma
2023-04-25T22:45:44.996Z,1682462744.996 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Courier0000.lzma.bak
2023-04-25T22:45:44.996Z,1682462744.996 [DataOverHttps](INFO): SBD MOMSN=18267334
2023-04-25T22:45:57.435Z,1682462757.435 [NAL9602](INFO): SBD MO Status=2, MOMSN=25041, MT Status=2, MTMSN=0
2023-04-25T22:45:57.436Z,1682462757.436 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-25T22:46:04.108Z,1682462764.108 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20230425T213717/Express0025.lzma
2023-04-25T22:46:05.103Z,1682462765.103 [DataOverHttps](INFO): Moved sent file to Logs/20230425T213717/Express0025.lzma.bak
2023-04-25T22:46:05.104Z,1682462765.104 [DataOverHttps](INFO): SBD MOMSN=18267338
2023-04-25T22:46:13.640Z,1682462773.640 [NAL9602](INFO): SBD MO Status=0, MOMSN=25041, MT Status=0, MTMSN=0
2023-04-25T22:46:13.641Z,1682462773.641 [NAL9602](INFO): No messages in MT queue
2023-04-25T22:46:44.351Z,1682462804.351 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-25T22:47:23.163Z,1682462843.163 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.819611
2023-04-25T22:47:48.316Z,1682462868.316 [NAL9602](INFO): SBD MO Status=1, MOMSN=25042, MT Status=0, MTMSN=0
2023-04-25T22:47:48.370Z,1682462868.370 [NAL9602](INFO): Sent 332 bytes from file Logs/20230425T224344/Express0001.lzma
2023-04-25T22:47:48.370Z,1682462868.370 [NAL9602](INFO): Packets left to send: 1
2023-04-25T22:47:54.294Z,1682462874.294 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-25T22:47:58.900Z,1682462878.900 [NAL9602](INFO): SBD MO Status=1, MOMSN=25043, MT Status=0, MTMSN=0
2023-04-25T22:47:58.958Z,1682462878.958 [NAL9602](INFO): Sent 64 bytes from file Logs/20230425T224344/Express0001.lzma
2023-04-25T22:47:58.958Z,1682462878.958 [NAL9602](INFO): Packets left to send: 0
2023-04-25T22:48:08.995Z,1682462888.995 [NAL9602](INFO): SBD MO Status=0, MOMSN=25044, MT Status=0, MTMSN=0
2023-04-25T22:48:09.087Z,1682462889.087 [Default:CheckIn:Read_Iridium] Stopped
2023-04-25T22:48:09.087Z,1682462889.087 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-25T22:48:09.087Z,1682462889.087 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-25T22:48:29.434Z,1682462909.434 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-25T22:48:39.703Z,1682462919.703 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-25T22:48:54.053Z,1682462934.053 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005349
2023-04-25T22:52:18.871Z,1682463138.871 [DataOverHttps](IMPORTANT): SBD MTMSN=20230425T225228
2023-04-25T22:52:26.429Z,1682463146.429 [DataOverHttps](INFO): Received command: strobe off
2023-04-25T22:52:26.459Z,1682463146.459 [CommandExec](IMPORTANT): got command strobe off
2023-04-25T22:52:26.459Z,1682463146.459 [CommandExec](IMPORTANT): Deactivating strobe
2023-04-25T22:53:09.663Z,1682463189.663 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-25T22:53:09.663Z,1682463189.663 [Default:CheckIn:C.Wait] Stopped
2023-04-25T22:53:09.663Z,1682463189.663 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-25T22:53:09.663Z,1682463189.663 [Default:CheckIn:D] Running Loop=1
2023-04-25T22:53:10.111Z,1682463190.111 [Default:CheckIn:D] Stopped
2023-04-25T22:53:10.111Z,1682463190.111 [Default:CheckIn:E] Running Loop=1
2023-04-25T22:53:10.508Z,1682463190.508 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.571560 min 
2023-04-25T22:53:10.508Z,1682463190.508 [Default:CheckIn:E] Stopped
2023-04-25T22:53:10.508Z,1682463190.508 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-25T22:53:10.508Z,1682463190.508 [Default:CheckIn] Stopped
2023-04-25T22:53:10.508Z,1682463190.508 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-25T22:53:10.508Z,1682463190.508 [Default:CheckIn](INFO): Running loop #5
2023-04-25T22:53:10.508Z,1682463190.508 [Default:CheckIn] Running Loop=5
2023-04-25T22:53:10.509Z,1682463190.509 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-25T22:53:10.509Z,1682463190.509 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-25T22:53:12.481Z,1682463192.481 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225322.00,A,3648.40912,N,12147.11433,W,5.015,183.63,250423,,,D*71
2023-04-25T22:53:12.483Z,1682463192.483 [NAL9602](INFO): GPS fix at 20230425T225322: (36.806819, -121.785239)
2023-04-25T22:53:12.500Z,1682463192.500 [Default:CheckIn:Read_GPS] Stopped
2023-04-25T22:53:12.500Z,1682463192.500 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-25T22:53:19.969Z,1682463199.969 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230425T224344/Courier0003.lzma
2023-04-25T22:53:20.972Z,1682463200.972 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Courier0003.lzma.bak
2023-04-25T22:53:20.972Z,1682463200.972 [DataOverHttps](INFO): SBD MOMSN=18267351
2023-04-25T22:53:25.408Z,1682463205.408 [NAL9602](INFO): SBD MO Status=0, MOMSN=25045, MT Status=0, MTMSN=0
2023-04-25T22:53:25.408Z,1682463205.408 [NAL9602](INFO): No messages in MT queue
2023-04-25T22:53:37.622Z,1682463217.622 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20230425T224344/Express0005.lzma
2023-04-25T22:53:38.623Z,1682463218.623 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Express0005.lzma.bak
2023-04-25T22:53:38.624Z,1682463218.624 [DataOverHttps](INFO): SBD MOMSN=18267354
2023-04-25T22:53:54.765Z,1682463234.765 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230425T224344/Express0010.lzma
2023-04-25T22:53:55.767Z,1682463235.767 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Express0010.lzma.bak
2023-04-25T22:53:55.768Z,1682463235.768 [DataOverHttps](INFO): SBD MOMSN=18267360
2023-04-25T22:53:56.121Z,1682463236.121 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-25T22:53:57.352Z,1682463237.352 [Default:CheckIn:Read_Iridium] Stopped
2023-04-25T22:53:57.353Z,1682463237.353 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-25T22:53:57.353Z,1682463237.353 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-25T22:58:57.920Z,1682463537.920 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-25T22:58:57.920Z,1682463537.920 [Default:CheckIn:C.Wait] Stopped
2023-04-25T22:58:57.920Z,1682463537.920 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-25T22:58:57.920Z,1682463537.920 [Default:CheckIn:D] Running Loop=1
2023-04-25T22:58:58.342Z,1682463538.342 [Default:CheckIn:D] Stopped
2023-04-25T22:58:58.342Z,1682463538.342 [Default:CheckIn:E] Running Loop=1
2023-04-25T22:58:58.722Z,1682463538.722 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.375427 min 
2023-04-25T22:58:58.723Z,1682463538.723 [Default:CheckIn:E] Stopped
2023-04-25T22:58:58.723Z,1682463538.723 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-25T22:58:58.723Z,1682463538.723 [Default:CheckIn] Stopped
2023-04-25T22:58:58.723Z,1682463538.723 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-25T22:58:58.723Z,1682463538.723 [Default:CheckIn](INFO): Running loop #6
2023-04-25T22:58:58.723Z,1682463538.723 [Default:CheckIn] Running Loop=6
2023-04-25T22:58:58.723Z,1682463538.723 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-25T22:58:58.723Z,1682463538.723 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-25T22:59:00.733Z,1682463540.733 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225910.00,A,3648.14882,N,12147.22029,W,0.039,210.61,250423,,,D*7C
2023-04-25T22:59:00.735Z,1682463540.735 [NAL9602](INFO): GPS fix at 20230425T225910: (36.802480, -121.787005)
2023-04-25T22:59:00.747Z,1682463540.747 [Default:CheckIn:Read_GPS] Stopped
2023-04-25T22:59:00.747Z,1682463540.747 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-25T22:59:08.753Z,1682463548.753 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230425T224344/Courier0012.lzma
2023-04-25T22:59:09.755Z,1682463549.755 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Courier0012.lzma.bak
2023-04-25T22:59:09.756Z,1682463549.756 [DataOverHttps](INFO): SBD MOMSN=18267366
2023-04-25T22:59:17.696Z,1682463557.696 [NAL9602](INFO): SBD MO Status=0, MOMSN=25046, MT Status=0, MTMSN=0
2023-04-25T22:59:17.696Z,1682463557.696 [NAL9602](INFO): No messages in MT queue
2023-04-25T22:59:26.013Z,1682463566.013 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230425T224344/Express0013.lzma
2023-04-25T22:59:27.016Z,1682463567.016 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Express0013.lzma.bak
2023-04-25T22:59:27.016Z,1682463567.016 [DataOverHttps](INFO): SBD MOMSN=18267369
2023-04-25T22:59:28.619Z,1682463568.619 [Default:CheckIn:Read_Iridium] Stopped
2023-04-25T22:59:28.619Z,1682463568.619 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-25T22:59:28.619Z,1682463568.619 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-25T22:59:48.403Z,1682463588.403 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-25T23:04:29.255Z,1682463869.255 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-25T23:04:29.255Z,1682463869.255 [Default:CheckIn:C.Wait] Stopped
2023-04-25T23:04:29.255Z,1682463869.255 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-25T23:04:29.256Z,1682463869.256 [Default:CheckIn:D] Running Loop=1
2023-04-25T23:04:29.667Z,1682463869.667 [Default:CheckIn:D] Stopped
2023-04-25T23:04:29.667Z,1682463869.667 [Default:CheckIn:E] Running Loop=1
2023-04-25T23:04:30.113Z,1682463870.113 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.897502 min 
2023-04-25T23:04:30.113Z,1682463870.113 [Default:CheckIn:E] Stopped
2023-04-25T23:04:30.113Z,1682463870.113 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-25T23:04:30.113Z,1682463870.113 [Default:CheckIn] Stopped
2023-04-25T23:04:30.113Z,1682463870.113 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-25T23:04:30.113Z,1682463870.113 [Default:CheckIn](INFO): Running loop #7
2023-04-25T23:04:30.113Z,1682463870.113 [Default:CheckIn] Running Loop=7
2023-04-25T23:04:30.113Z,1682463870.113 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-25T23:04:30.114Z,1682463870.114 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-25T23:04:32.100Z,1682463872.100 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230441.00,A,3648.14875,N,12147.22252,W,0.000,160.30,250423,,,D*7D
2023-04-25T23:04:32.116Z,1682463872.116 [NAL9602](INFO): GPS fix at 20230425T230441: (36.802479, -121.787042)
2023-04-25T23:04:32.147Z,1682463872.147 [Default:CheckIn:Read_GPS] Stopped
2023-04-25T23:04:32.147Z,1682463872.147 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-25T23:04:39.409Z,1682463879.409 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230425T224344/Courier0015.lzma
2023-04-25T23:04:40.411Z,1682463880.411 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Courier0015.lzma.bak
2023-04-25T23:04:40.412Z,1682463880.412 [DataOverHttps](INFO): SBD MOMSN=18267377
2023-04-25T23:04:45.808Z,1682463885.808 [NAL9602](INFO): SBD MO Status=0, MOMSN=25047, MT Status=0, MTMSN=0
2023-04-25T23:04:45.808Z,1682463885.808 [NAL9602](INFO): No messages in MT queue
2023-04-25T23:04:56.638Z,1682463896.638 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230425T224344/Express0016.lzma
2023-04-25T23:04:57.639Z,1682463897.639 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Express0016.lzma.bak
2023-04-25T23:04:57.640Z,1682463897.640 [DataOverHttps](INFO): SBD MOMSN=18267380
2023-04-25T23:04:59.176Z,1682463899.176 [Default:CheckIn:Read_Iridium] Stopped
2023-04-25T23:04:59.176Z,1682463899.176 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-25T23:04:59.176Z,1682463899.176 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-25T23:05:16.519Z,1682463916.519 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-25T23:09:59.742Z,1682464199.742 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-25T23:09:59.742Z,1682464199.742 [Default:CheckIn:C.Wait] Stopped
2023-04-25T23:09:59.742Z,1682464199.742 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-25T23:09:59.742Z,1682464199.742 [Default:CheckIn:D] Running Loop=1
2023-04-25T23:10:00.171Z,1682464200.171 [Default:CheckIn:D] Stopped
2023-04-25T23:10:00.171Z,1682464200.171 [Default:CheckIn:E] Running Loop=1
2023-04-25T23:10:00.545Z,1682464200.545 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.405912 min 
2023-04-25T23:10:00.545Z,1682464200.545 [Default:CheckIn:E] Stopped
2023-04-25T23:10:00.545Z,1682464200.545 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-25T23:10:00.545Z,1682464200.545 [Default:CheckIn] Stopped
2023-04-25T23:10:00.546Z,1682464200.546 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-25T23:10:00.546Z,1682464200.546 [Default:CheckIn](INFO): Running loop #8
2023-04-25T23:10:00.546Z,1682464200.546 [Default:CheckIn] Running Loop=8
2023-04-25T23:10:00.546Z,1682464200.546 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-25T23:10:00.546Z,1682464200.546 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-25T23:10:02.557Z,1682464202.557 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231012.00,A,3648.17121,N,12147.28337,W,0.816,273.06,250423,,,A*73
2023-04-25T23:10:02.559Z,1682464202.559 [NAL9602](INFO): GPS fix at 20230425T231012: (36.802853, -121.788056)
2023-04-25T23:10:02.575Z,1682464202.575 [Default:CheckIn:Read_GPS] Stopped
2023-04-25T23:10:02.575Z,1682464202.575 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-25T23:10:10.052Z,1682464210.052 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230425T224344/Courier0018.lzma
2023-04-25T23:10:11.055Z,1682464211.055 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Courier0018.lzma.bak
2023-04-25T23:10:11.056Z,1682464211.056 [DataOverHttps](INFO): SBD MOMSN=18267384
2023-04-25T23:10:30.617Z,1682464230.617 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230425T224344/Express0019.lzma
2023-04-25T23:10:31.620Z,1682464231.620 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Express0019.lzma.bak
2023-04-25T23:10:31.620Z,1682464231.620 [DataOverHttps](INFO): SBD MOMSN=18267388
2023-04-25T23:10:32.933Z,1682464232.933 [Default:CheckIn:Read_Iridium] Stopped
2023-04-25T23:10:32.933Z,1682464232.933 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-25T23:10:32.933Z,1682464232.933 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-25T23:12:13.123Z,1682464333.123 [NAL9602](INFO): SBD MO Status=2, MOMSN=25048, MT Status=2, MTMSN=0
2023-04-25T23:12:13.123Z,1682464333.123 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-25T23:15:05.279Z,1682464505.279 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-25T23:15:33.567Z,1682464533.567 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-25T23:15:33.567Z,1682464533.567 [Default:CheckIn:C.Wait] Stopped
2023-04-25T23:15:33.567Z,1682464533.567 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-25T23:15:33.567Z,1682464533.567 [Default:CheckIn:D] Running Loop=1
2023-04-25T23:15:33.978Z,1682464533.978 [Default:CheckIn:D] Stopped
2023-04-25T23:15:33.978Z,1682464533.978 [Default:CheckIn:E] Running Loop=1
2023-04-25T23:15:34.403Z,1682464534.403 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.969360 min 
2023-04-25T23:15:34.403Z,1682464534.403 [Default:CheckIn:E] Stopped
2023-04-25T23:15:34.403Z,1682464534.403 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-25T23:15:34.403Z,1682464534.403 [Default:CheckIn] Stopped
2023-04-25T23:15:34.404Z,1682464534.404 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-25T23:15:34.404Z,1682464534.404 [Default:CheckIn](INFO): Running loop #9
2023-04-25T23:15:34.404Z,1682464534.404 [Default:CheckIn] Running Loop=9
2023-04-25T23:15:34.404Z,1682464534.404 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-25T23:15:34.404Z,1682464534.404 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-25T23:15:36.385Z,1682464536.385 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231546.00,A,3648.16595,N,12147.28304,W,0.603,316.56,250423,,,A*70
2023-04-25T23:15:36.395Z,1682464536.395 [NAL9602](INFO): GPS fix at 20230425T231546: (36.802766, -121.788051)
2023-04-25T23:15:36.445Z,1682464536.445 [Default:CheckIn:Read_GPS] Stopped
2023-04-25T23:15:36.445Z,1682464536.445 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-25T23:15:44.397Z,1682464544.397 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230425T224344/Courier0021.lzma
2023-04-25T23:15:45.400Z,1682464545.399 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Courier0021.lzma.bak
2023-04-25T23:15:45.400Z,1682464545.400 [DataOverHttps](INFO): SBD MOMSN=18267391
2023-04-25T23:16:01.610Z,1682464561.610 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230425T224344/Express0022.lzma
2023-04-25T23:16:02.612Z,1682464562.612 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Express0022.lzma.bak
2023-04-25T23:16:02.612Z,1682464562.612 [DataOverHttps](INFO): SBD MOMSN=18267394
2023-04-25T23:16:04.311Z,1682464564.311 [Default:CheckIn:Read_Iridium] Stopped
2023-04-25T23:16:04.311Z,1682464564.311 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-25T23:16:04.311Z,1682464564.311 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-25T23:16:09.107Z,1682464569.107 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-25T23:18:19.234Z,1682464699.234 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-04-25T23:18:19.235Z,1682464699.235 [DropWeight] Hardware Fault, FailCount= 1
2023-04-25T23:18:19.235Z,1682464699.235 [DropWeight](ERROR): Hardware Fault
2023-04-25T23:18:19.258Z,1682464699.258 [CommandExec](FAULT): Scheduling is paused
2023-04-25T23:18:19.258Z,1682464699.258 [CBIT](INFO): Critical error at 20230425T231819
2023-04-25T23:18:19.258Z,1682464699.258 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-04-25T23:18:19.261Z,1682464699.261 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-04-25T23:18:19.261Z,1682464699.261 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-04-25T23:18:19.665Z,1682464699.665 [CBIT](INFO): Critical error at 20230425T231819
2023-04-25T23:21:04.969Z,1682464864.969 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-25T23:21:04.969Z,1682464864.969 [Default:CheckIn:C.Wait] Stopped
2023-04-25T23:21:04.969Z,1682464864.969 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-25T23:21:04.969Z,1682464864.969 [Default:CheckIn:D] Running Loop=1
2023-04-25T23:21:05.338Z,1682464865.338 [Default:CheckIn:D] Stopped
2023-04-25T23:21:05.338Z,1682464865.338 [Default:CheckIn:E] Running Loop=1
2023-04-25T23:21:05.748Z,1682464865.748 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.492013 min 
2023-04-25T23:21:05.748Z,1682464865.748 [Default:CheckIn:E] Stopped
2023-04-25T23:21:05.748Z,1682464865.748 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-25T23:21:05.748Z,1682464865.748 [Default:CheckIn] Stopped
2023-04-25T23:21:05.749Z,1682464865.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-25T23:21:05.749Z,1682464865.749 [Default:CheckIn](INFO): Running loop #10
2023-04-25T23:21:05.749Z,1682464865.749 [Default:CheckIn] Running Loop=10
2023-04-25T23:21:05.749Z,1682464865.749 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-25T23:21:05.749Z,1682464865.749 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-25T23:21:07.749Z,1682464867.749 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232117.00,A,3648.16908,N,12147.28035,W,0.603,75.08,250423,,,A*47
2023-04-25T23:21:07.751Z,1682464867.751 [NAL9602](INFO): GPS fix at 20230425T232117: (36.802818, -121.788006)
2023-04-25T23:21:07.783Z,1682464867.783 [Default:CheckIn:Read_GPS] Stopped
2023-04-25T23:21:07.783Z,1682464867.783 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-25T23:21:15.826Z,1682464875.826 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20230425T224344/Courier0024.lzma
2023-04-25T23:21:16.827Z,1682464876.827 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Courier0024.lzma.bak
2023-04-25T23:21:16.828Z,1682464876.828 [DataOverHttps](INFO): SBD MOMSN=18267398
2023-04-25T23:21:33.173Z,1682464893.173 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20230425T224344/Express0025.lzma
2023-04-25T23:21:34.176Z,1682464894.176 [DataOverHttps](INFO): Moved sent file to Logs/20230425T224344/Express0025.lzma.bak
2023-04-25T23:21:34.176Z,1682464894.176 [DataOverHttps](INFO): SBD MOMSN=18267403
2023-04-25T23:21:35.647Z,1682464895.647 [Default:CheckIn:Read_Iridium] Stopped
2023-04-25T23:21:35.647Z,1682464895.647 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-25T23:21:35.647Z,1682464895.647 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-25T23:21:38.556Z,1682464898.556 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-25T23:21:38.634Z,1682464898.634 [NAL9602](ERROR): received: 
+CSQ:0

OK048, 2, 0, 0, 0

OK
2023-04-25T23:25:08.190Z,1682465108.190 [CommandExec](IMPORTANT): got command quit
2023-04-25T23:25:09.194Z,1682465109.194 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:09.194Z,1682465109.194 [CommandExec](INFO): Uninitializing the command executive.
2023-04-25T23:25:09.194Z,1682465109.194 [CommandExec](INFO): Uninitializing the command scheduler.
2023-04-25T23:25:09.194Z,1682465109.194 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:09.282Z,1682465109.282 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-04-25T23:25:09.282Z,1682465109.282 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-04-25T23:25:09.282Z,1682465109.282 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:09.283Z,1682465109.283 [NavChartDb](INFO): Join timeout helper Thread ID is 4817
2023-04-25T23:25:09.538Z,1682465109.538 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:09.538Z,1682465109.538 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:09.542Z,1682465109.542 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-04-25T23:25:09.542Z,1682465109.542 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:09.542Z,1682465109.542 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4818
2023-04-25T23:25:09.982Z,1682465109.982 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:09.982Z,1682465109.982 [WetLabsBB2FL](INFO): Powering down
2023-04-25T23:25:09.983Z,1682465109.983 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:09.986Z,1682465109.986 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-04-25T23:25:09.986Z,1682465109.986 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:09.986Z,1682465109.986 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4819
2023-04-25T23:25:10.258Z,1682465110.258 [CTD_Seabird](INFO): Powering down
2023-04-25T23:25:10.270Z,1682465110.270 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:10.270Z,1682465110.270 [CTD_Seabird](INFO): Powering down
2023-04-25T23:25:10.283Z,1682465110.283 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:10.290Z,1682465110.290 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-04-25T23:25:10.290Z,1682465110.290 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:10.290Z,1682465110.290 [DAT](INFO): Join timeout helper Thread ID is 4820
2023-04-25T23:25:10.338Z,1682465110.338 [DAT](INFO): Powering down
2023-04-25T23:25:10.411Z,1682465110.411 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:10.411Z,1682465110.411 [DAT](INFO): Powering down
2023-04-25T23:25:10.412Z,1682465110.412 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:10.430Z,1682465110.430 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-04-25T23:25:10.430Z,1682465110.430 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:10.430Z,1682465110.430 [Radio_Surface](INFO): Join timeout helper Thread ID is 4821
2023-04-25T23:25:10.686Z,1682465110.686 [Radio_Surface](INFO): Powering down
2023-04-25T23:25:10.687Z,1682465110.687 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:10.687Z,1682465110.687 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:10.699Z,1682465110.699 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-04-25T23:25:10.699Z,1682465110.699 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:10.699Z,1682465110.699 [Onboard](INFO): Join timeout helper Thread ID is 4822
2023-04-25T23:25:15.126Z,1682465115.126 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:15.126Z,1682465115.126 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.146Z,1682465115.146 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-04-25T23:25:15.147Z,1682465115.147 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.147Z,1682465115.147 [DataOverHttps](INFO): Join timeout helper Thread ID is 4823
2023-04-25T23:25:15.294Z,1682465115.294 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:15.297Z,1682465115.297 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.298Z,1682465115.298 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-04-25T23:25:15.299Z,1682465115.299 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.299Z,1682465115.299 [BackseatComponent](INFO): Join timeout helper Thread ID is 4824
2023-04-25T23:25:15.458Z,1682465115.458 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:15.458Z,1682465115.458 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.466Z,1682465115.466 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-04-25T23:25:15.467Z,1682465115.467 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.467Z,1682465115.467 [logger](INFO): Join timeout helper Thread ID is 4825
2023-04-25T23:25:15.499Z,1682465115.499 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:15.499Z,1682465115.499 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.503Z,1682465115.503 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-04-25T23:25:15.503Z,1682465115.503 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.503Z,1682465115.503 [CommandLine](INFO): Join timeout helper Thread ID is 4826
2023-04-25T23:25:15.510Z,1682465115.510 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:15.510Z,1682465115.510 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.512Z,1682465115.512 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-04-25T23:25:15.512Z,1682465115.512 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.512Z,1682465115.512 [CommandExec](INFO): Join timeout helper Thread ID is 4827
2023-04-25T23:25:15.515Z,1682465115.515 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-04-25T23:25:15.515Z,1682465115.515 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:15.515Z,1682465115.515 [controlThread](INFO): Join timeout helper Thread ID is 4828
2023-04-25T23:25:15.786Z,1682465115.786 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-25T23:25:15.786Z,1682465115.786 [controlThread](DEBUG): Uninitializing ControlThread
2023-04-25T23:25:15.787Z,1682465115.787 [AHRS_M2](INFO): Powering down
2023-04-25T23:25:15.859Z,1682465115.859 [NAL9602](INFO): Powering down
2023-04-25T23:25:15.930Z,1682465115.930 [RDI_Pathfinder](INFO): Powering down
2023-04-25T23:25:15.931Z,1682465115.931 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-04-25T23:25:15.932Z,1682465115.932 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-04-25T23:25:15.933Z,1682465115.933 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-04-25T23:25:15.934Z,1682465115.934 [MissionManager](INFO): Uninitializing Mission Default
2023-04-25T23:25:15.934Z,1682465115.934 [Default] Stopped
2023-04-25T23:25:15.934Z,1682465115.934 [Default](DEBUG): Aggregate::uninitialize Default
2023-04-25T23:25:15.934Z,1682465115.934 [Default:B.GoToSurface] Stopped
2023-04-25T23:25:15.934Z,1682465115.934 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-25T23:25:15.934Z,1682465115.934 [Default:CheckIn] Stopped
2023-04-25T23:25:15.934Z,1682465115.934 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-25T23:25:15.934Z,1682465115.934 [Default:CheckIn:C.Wait] Stopped
2023-04-25T23:25:15.934Z,1682465115.934 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-25T23:25:15.937Z,1682465115.937 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-04-25T23:25:15.937Z,1682465115.937 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-04-25T23:25:15.938Z,1682465115.938 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-04-25T23:25:15.938Z,1682465115.938 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-04-25T23:25:15.938Z,1682465115.938 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-04-25T23:25:15.939Z,1682465115.939 [BuoyancyServo](INFO): Powering down
2023-04-25T23:25:15.950Z,1682465115.950 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-04-25T23:25:15.950Z,1682465115.950 [ElevatorServo](INFO): Powering down
2023-04-25T23:25:15.951Z,1682465115.951 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-04-25T23:25:15.951Z,1682465115.951 [MassServo](INFO): Powering down
2023-04-25T23:25:15.952Z,1682465115.952 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-25T23:25:15.952Z,1682465115.952 [RudderServo](INFO): Powering down
2023-04-25T23:25:15.953Z,1682465115.953 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-04-25T23:25:15.953Z,1682465115.953 [ThrusterServo](INFO): Powering down
2023-04-25T23:25:15.954Z,1682465115.954 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-04-25T23:25:15.954Z,1682465115.954 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-04-25T23:25:15.955Z,1682465115.955 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-04-25T23:25:15.955Z,1682465115.955 [CBIT](DEBUG): Powering off loads.
2023-04-25T23:25:15.966Z,1682465115.966 [CBIT](DEBUG): Disabling WDT.
2023-04-25T23:25:15.978Z,1682465115.978 [CBIT](DEBUG): Opening all GF detection circuits.
2023-04-25T23:25:15.979Z,1682465115.979 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:16.042Z,1682465116.042 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:16.049Z,1682465116.049 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:16.051Z,1682465116.051 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:16.057Z,1682465116.057 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:16.099Z,1682465116.099 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:16.157Z,1682465116.157 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:16.162Z,1682465116.162 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:16.170Z,1682465116.170 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-25T23:25:16.248Z,1682465116.248 [logger ThreadHandler](INFO): Thread cancelled.