2023-08-02T17:03:10.926Z,1690995790.926 [CommandExec](IMPORTANT): got command restart logs 2023-08-02T17:03:25.392Z,1690995805.392 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T17:03:31.056Z,1690995811.056 [NAL9602](INFO): SBD MO Status=1, MOMSN=44592, MT Status=0, MTMSN=0 2023-08-02T17:03:31.056Z,1690995811.056 [NAL9602](INFO): No messages in MT queue 2023-08-02T17:03:40.744Z,1690995820.744 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T17:03:55.691Z,1690995835.691 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T17:04:01.753Z,1690995841.753 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T17:04:11.044Z,1690995851.044 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T17:04:27.205Z,1690995867.205 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T17:04:42.558Z,1690995882.558 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-08-02T17:04:49.026Z,1690995889.026 [BPC1](INFO): Calculating totals. Valid battery stick count: 38. Valid reserve battery stick count: 5. 2023-08-02T17:04:49.029Z,1690995889.029 [BPC1](INFO): Received data from all battery sticks. 2023-08-02T17:05:06.213Z,1690995906.213 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-08-02T17:05:37.337Z,1690995937.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-08-02T17:06:08.473Z,1690995968.473 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-08-02T17:06:39.597Z,1690995999.597 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-08-02T17:07:10.721Z,1690996030.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-08-02T17:07:12.730Z,1690996032.730 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.119492 2023-08-02T17:08:05.106Z,1690996085.106 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T17:08:05.106Z,1690996085.106 [Default:CheckIn:C.Wait] Stopped 2023-08-02T17:08:05.106Z,1690996085.106 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T17:08:05.106Z,1690996085.106 [Default:CheckIn:D] Running Loop=1 2023-08-02T17:08:05.374Z,1690996085.374 [Default:CheckIn:D] Stopped 2023-08-02T17:08:05.374Z,1690996085.374 [Default:CheckIn:E] Running Loop=1 2023-08-02T17:08:05.780Z,1690996085.780 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.374359 min 2023-08-02T17:08:05.780Z,1690996085.780 [Default:CheckIn:E] Stopped 2023-08-02T17:08:05.780Z,1690996085.780 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T17:08:05.780Z,1690996085.780 [Default:CheckIn] Stopped 2023-08-02T17:08:05.780Z,1690996085.780 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T17:08:05.780Z,1690996085.780 [Default:CheckIn](INFO): Running loop #4 2023-08-02T17:08:05.780Z,1690996085.780 [Default:CheckIn] Running Loop=4 2023-08-02T17:08:05.780Z,1690996085.780 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T17:08:05.780Z,1690996085.780 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T17:08:07.796Z,1690996087.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170808.00,A,3648.02344,N,12149.25675,W,24.104,81.49,020823,,,D*77 2023-08-02T17:08:07.798Z,1690996087.798 [NAL9602](INFO): GPS fix at 20230802T170808: (36.800391, -121.820946) 2023-08-02T17:08:07.809Z,1690996087.809 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T17:08:07.809Z,1690996087.809 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T17:08:11.978Z,1690996091.978 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-08-02T17:08:43.101Z,1690996123.101 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-08-02T17:09:01.286Z,1690996141.286 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003434 2023-08-02T17:09:04.872Z,1690996144.872 [NAL9602](INFO): SBD MO Status=2, MOMSN=44593, MT Status=2, MTMSN=0 2023-08-02T17:09:04.872Z,1690996144.872 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-08-02T17:09:08.600Z,1690996148.600 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230801T202228/Courier0090.lzma 2023-08-02T17:09:09.603Z,1690996149.603 [DataOverHttps](INFO): Moved sent file to Logs/20230801T202228/Courier0090.lzma.bak 2023-08-02T17:09:09.604Z,1690996149.604 [DataOverHttps](INFO): SBD MOMSN=18509703 2023-08-02T17:09:35.568Z,1690996175.568 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T17:09:40.533Z,1690996180.533 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20230802T170310/Courier0000.lzma 2023-08-02T17:09:41.535Z,1690996181.535 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0000.lzma.bak 2023-08-02T17:09:41.535Z,1690996181.535 [DataOverHttps](INFO): SBD MOMSN=18509705 2023-08-02T17:09:59.316Z,1690996199.316 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230802T170310/Courier0003.lzma 2023-08-02T17:10:00.319Z,1690996200.319 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0003.lzma.bak 2023-08-02T17:10:00.319Z,1690996200.319 [DataOverHttps](INFO): SBD MOMSN=18509709 2023-08-02T17:10:18.949Z,1690996218.949 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20230801T202228/Express0091.lzma 2023-08-02T17:10:19.951Z,1690996219.951 [DataOverHttps](INFO): Moved sent file to Logs/20230801T202228/Express0091.lzma.bak 2023-08-02T17:10:19.951Z,1690996219.951 [DataOverHttps](INFO): SBD MOMSN=18509711 2023-08-02T17:10:35.721Z,1690996235.721 [DataOverHttps](INFO): Sending 397 bytes from file Logs/20230802T170310/Express0001.lzma 2023-08-02T17:10:36.723Z,1690996236.723 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0001.lzma.bak 2023-08-02T17:10:36.723Z,1690996236.723 [DataOverHttps](INFO): SBD MOMSN=18509716 2023-08-02T17:10:55.353Z,1690996255.353 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230802T170310/Express0004.lzma 2023-08-02T17:10:56.364Z,1690996256.364 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0004.lzma.bak 2023-08-02T17:10:56.365Z,1690996256.365 [DataOverHttps](INFO): SBD MOMSN=18509720 2023-08-02T17:10:57.658Z,1690996257.658 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T17:10:57.658Z,1690996257.658 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T17:10:57.658Z,1690996257.658 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T17:15:58.214Z,1690996558.214 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T17:15:58.214Z,1690996558.214 [Default:CheckIn:C.Wait] Stopped 2023-08-02T17:15:58.214Z,1690996558.214 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T17:15:58.215Z,1690996558.215 [Default:CheckIn:D] Running Loop=1 2023-08-02T17:15:58.616Z,1690996558.616 [Default:CheckIn:D] Stopped 2023-08-02T17:15:58.616Z,1690996558.616 [Default:CheckIn:E] Running Loop=1 2023-08-02T17:15:59.026Z,1690996559.026 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.261725 min 2023-08-02T17:15:59.026Z,1690996559.026 [Default:CheckIn:E] Stopped 2023-08-02T17:15:59.026Z,1690996559.026 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T17:15:59.026Z,1690996559.026 [Default:CheckIn] Stopped 2023-08-02T17:15:59.026Z,1690996559.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T17:15:59.026Z,1690996559.026 [Default:CheckIn](INFO): Running loop #5 2023-08-02T17:15:59.026Z,1690996559.026 [Default:CheckIn] Running Loop=5 2023-08-02T17:15:59.026Z,1690996559.026 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T17:15:59.027Z,1690996559.027 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T17:16:01.032Z,1690996561.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171601.00,A,3648.40890,N,12147.12720,W,4.296,184.91,020823,,,D*75 2023-08-02T17:16:01.035Z,1690996561.035 [NAL9602](INFO): GPS fix at 20230802T171601: (36.806815, -121.785453) 2023-08-02T17:16:01.045Z,1690996561.045 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T17:16:01.045Z,1690996561.045 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T17:16:08.848Z,1690996568.848 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0006.lzma 2023-08-02T17:16:09.851Z,1690996569.851 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0006.lzma.bak 2023-08-02T17:16:09.851Z,1690996569.851 [DataOverHttps](INFO): SBD MOMSN=18509722 2023-08-02T17:16:25.709Z,1690996585.709 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230802T170310/Express0007.lzma 2023-08-02T17:16:26.084Z,1690996586.084 [NAL9602](INFO): SBD MO Status=1, MOMSN=44593, MT Status=0, MTMSN=0 2023-08-02T17:16:26.084Z,1690996586.084 [NAL9602](INFO): No messages in MT queue 2023-08-02T17:16:26.711Z,1690996586.711 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0007.lzma.bak 2023-08-02T17:16:26.711Z,1690996586.711 [DataOverHttps](INFO): SBD MOMSN=18509725 2023-08-02T17:16:28.117Z,1690996588.117 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T17:16:28.118Z,1690996588.118 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T17:16:28.118Z,1690996588.118 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T17:16:56.781Z,1690996616.781 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T17:21:28.687Z,1690996888.687 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T17:21:28.688Z,1690996888.688 [Default:CheckIn:C.Wait] Stopped 2023-08-02T17:21:28.688Z,1690996888.688 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T17:21:28.688Z,1690996888.688 [Default:CheckIn:D] Running Loop=1 2023-08-02T17:21:29.087Z,1690996889.087 [Default:CheckIn:D] Stopped 2023-08-02T17:21:29.087Z,1690996889.087 [Default:CheckIn:E] Running Loop=1 2023-08-02T17:21:29.493Z,1690996889.493 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.769562 min 2023-08-02T17:21:29.493Z,1690996889.493 [Default:CheckIn:E] Stopped 2023-08-02T17:21:29.493Z,1690996889.493 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T17:21:29.493Z,1690996889.493 [Default:CheckIn] Stopped 2023-08-02T17:21:29.493Z,1690996889.493 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T17:21:29.494Z,1690996889.494 [Default:CheckIn](INFO): Running loop #6 2023-08-02T17:21:29.494Z,1690996889.494 [Default:CheckIn] Running Loop=6 2023-08-02T17:21:29.494Z,1690996889.494 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T17:21:29.494Z,1690996889.494 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T17:21:31.504Z,1690996891.504 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172132.00,A,3648.15849,N,12147.21839,W,0.117,221.05,020823,,,D*7D 2023-08-02T17:21:31.506Z,1690996891.506 [NAL9602](INFO): GPS fix at 20230802T172132: (36.802642, -121.786973) 2023-08-02T17:21:31.531Z,1690996891.531 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T17:21:31.531Z,1690996891.531 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T17:21:39.152Z,1690996899.152 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230802T170310/Courier0009.lzma 2023-08-02T17:21:40.155Z,1690996900.155 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0009.lzma.bak 2023-08-02T17:21:40.155Z,1690996900.155 [DataOverHttps](INFO): SBD MOMSN=18509730 2023-08-02T17:21:46.856Z,1690996906.856 [NAL9602](INFO): SBD MO Status=1, MOMSN=44594, MT Status=0, MTMSN=0 2023-08-02T17:21:46.856Z,1690996906.856 [NAL9602](INFO): No messages in MT queue 2023-08-02T17:21:56.033Z,1690996916.033 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230802T170310/Express0010.lzma 2023-08-02T17:21:57.035Z,1690996917.035 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0010.lzma.bak 2023-08-02T17:21:57.035Z,1690996917.035 [DataOverHttps](INFO): SBD MOMSN=18509733 2023-08-02T17:21:58.181Z,1690996918.181 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T17:21:58.182Z,1690996918.182 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T17:21:58.182Z,1690996918.182 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T17:22:17.556Z,1690996937.556 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T17:26:58.753Z,1690997218.753 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T17:26:58.754Z,1690997218.754 [Default:CheckIn:C.Wait] Stopped 2023-08-02T17:26:58.754Z,1690997218.754 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T17:26:58.754Z,1690997218.754 [Default:CheckIn:D] Running Loop=1 2023-08-02T17:26:59.163Z,1690997219.163 [Default:CheckIn:D] Stopped 2023-08-02T17:26:59.163Z,1690997219.163 [Default:CheckIn:E] Running Loop=1 2023-08-02T17:26:59.566Z,1690997219.566 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.270841 min 2023-08-02T17:26:59.566Z,1690997219.566 [Default:CheckIn:E] Stopped 2023-08-02T17:26:59.566Z,1690997219.566 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T17:26:59.566Z,1690997219.566 [Default:CheckIn] Stopped 2023-08-02T17:26:59.566Z,1690997219.566 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T17:26:59.567Z,1690997219.567 [Default:CheckIn](INFO): Running loop #7 2023-08-02T17:26:59.567Z,1690997219.567 [Default:CheckIn] Running Loop=7 2023-08-02T17:26:59.567Z,1690997219.567 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T17:26:59.567Z,1690997219.567 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T17:27:01.573Z,1690997221.573 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172702.00,A,3648.14735,N,12147.25310,W,2.546,347.26,020823,,,D*7B 2023-08-02T17:27:01.575Z,1690997221.575 [NAL9602](INFO): GPS fix at 20230802T172702: (36.802456, -121.787552) 2023-08-02T17:27:01.586Z,1690997221.586 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T17:27:01.586Z,1690997221.586 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T17:27:09.368Z,1690997229.368 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0012.lzma 2023-08-02T17:27:10.371Z,1690997230.371 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0012.lzma.bak 2023-08-02T17:27:10.371Z,1690997230.371 [DataOverHttps](INFO): SBD MOMSN=18509738 2023-08-02T17:27:15.312Z,1690997235.312 [NAL9602](INFO): SBD MO Status=1, MOMSN=44595, MT Status=0, MTMSN=0 2023-08-02T17:27:15.312Z,1690997235.312 [NAL9602](INFO): No messages in MT queue 2023-08-02T17:27:26.157Z,1690997246.157 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20230802T170310/Express0013.lzma 2023-08-02T17:27:27.159Z,1690997247.159 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0013.lzma.bak 2023-08-02T17:27:27.159Z,1690997247.159 [DataOverHttps](INFO): SBD MOMSN=18509741 2023-08-02T17:27:28.250Z,1690997248.250 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T17:27:28.250Z,1690997248.250 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T17:27:28.250Z,1690997248.250 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T17:27:46.009Z,1690997266.009 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T17:32:28.819Z,1690997548.819 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T17:32:28.819Z,1690997548.819 [Default:CheckIn:C.Wait] Stopped 2023-08-02T17:32:28.819Z,1690997548.819 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T17:32:28.819Z,1690997548.819 [Default:CheckIn:D] Running Loop=1 2023-08-02T17:32:29.236Z,1690997549.236 [Default:CheckIn:D] Stopped 2023-08-02T17:32:29.236Z,1690997549.236 [Default:CheckIn:E] Running Loop=1 2023-08-02T17:32:29.646Z,1690997549.646 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.772058 min 2023-08-02T17:32:29.646Z,1690997549.646 [Default:CheckIn:E] Stopped 2023-08-02T17:32:29.646Z,1690997549.646 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T17:32:29.647Z,1690997549.647 [Default:CheckIn] Stopped 2023-08-02T17:32:29.647Z,1690997549.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T17:32:29.647Z,1690997549.647 [Default:CheckIn](INFO): Running loop #8 2023-08-02T17:32:29.647Z,1690997549.647 [Default:CheckIn] Running Loop=8 2023-08-02T17:32:29.647Z,1690997549.647 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T17:32:29.647Z,1690997549.647 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T17:32:31.645Z,1690997551.645 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173232.00,A,3648.17105,N,12147.28114,W,0.156,241.08,020823,,,D*7D 2023-08-02T17:32:31.647Z,1690997551.647 [NAL9602](INFO): GPS fix at 20230802T173232: (36.802851, -121.788019) 2023-08-02T17:32:31.688Z,1690997551.688 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T17:32:31.688Z,1690997551.688 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T17:32:39.485Z,1690997559.485 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230802T170310/Courier0015.lzma 2023-08-02T17:32:40.487Z,1690997560.487 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0015.lzma.bak 2023-08-02T17:32:40.487Z,1690997560.487 [DataOverHttps](INFO): SBD MOMSN=18509746 2023-08-02T17:32:59.337Z,1690997579.337 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230802T170310/Express0016.lzma 2023-08-02T17:33:00.339Z,1690997580.339 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0016.lzma.bak 2023-08-02T17:33:00.339Z,1690997580.339 [DataOverHttps](INFO): SBD MOMSN=18509749 2023-08-02T17:33:01.606Z,1690997581.606 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T17:33:01.606Z,1690997581.606 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T17:33:01.606Z,1690997581.606 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T17:37:34.322Z,1690997854.322 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-08-02T17:38:02.214Z,1690997882.214 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T17:38:02.214Z,1690997882.214 [Default:CheckIn:C.Wait] Stopped 2023-08-02T17:38:02.214Z,1690997882.214 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T17:38:02.214Z,1690997882.214 [Default:CheckIn:D] Running Loop=1 2023-08-02T17:38:02.612Z,1690997882.612 [Default:CheckIn:D] Stopped 2023-08-02T17:38:02.612Z,1690997882.612 [Default:CheckIn:E] Running Loop=1 2023-08-02T17:38:03.022Z,1690997883.022 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.328324 min 2023-08-02T17:38:03.022Z,1690997883.022 [Default:CheckIn:E] Stopped 2023-08-02T17:38:03.022Z,1690997883.022 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T17:38:03.022Z,1690997883.022 [Default:CheckIn] Stopped 2023-08-02T17:38:03.023Z,1690997883.023 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T17:38:03.023Z,1690997883.023 [Default:CheckIn](INFO): Running loop #9 2023-08-02T17:38:03.023Z,1690997883.023 [Default:CheckIn] Running Loop=9 2023-08-02T17:38:03.023Z,1690997883.023 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T17:38:03.023Z,1690997883.023 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T17:38:05.032Z,1690997885.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173805.00,A,3648.16934,N,12147.28080,W,0.136,308.16,020823,,,A*74 2023-08-02T17:38:05.034Z,1690997885.034 [NAL9602](INFO): GPS fix at 20230802T173805: (36.802822, -121.788013) 2023-08-02T17:38:05.044Z,1690997885.044 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T17:38:05.044Z,1690997885.044 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T17:38:12.860Z,1690997892.860 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0018.lzma 2023-08-02T17:38:13.863Z,1690997893.863 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0018.lzma.bak 2023-08-02T17:38:13.863Z,1690997893.863 [DataOverHttps](INFO): SBD MOMSN=18509752 2023-08-02T17:38:29.613Z,1690997909.613 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230802T170310/Express0019.lzma 2023-08-02T17:38:30.615Z,1690997910.615 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0019.lzma.bak 2023-08-02T17:38:30.615Z,1690997910.615 [DataOverHttps](INFO): SBD MOMSN=18509755 2023-08-02T17:38:31.714Z,1690997911.714 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T17:38:31.714Z,1690997911.714 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T17:38:31.714Z,1690997911.714 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T17:38:37.347Z,1690997917.347 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T17:43:32.294Z,1690998212.294 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T17:43:32.294Z,1690998212.294 [Default:CheckIn:C.Wait] Stopped 2023-08-02T17:43:32.294Z,1690998212.294 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T17:43:32.294Z,1690998212.294 [Default:CheckIn:D] Running Loop=1 2023-08-02T17:43:32.680Z,1690998212.680 [Default:CheckIn:D] Stopped 2023-08-02T17:43:32.680Z,1690998212.680 [Default:CheckIn:E] Running Loop=1 2023-08-02T17:43:33.085Z,1690998213.085 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.829447 min 2023-08-02T17:43:33.085Z,1690998213.085 [Default:CheckIn:E] Stopped 2023-08-02T17:43:33.085Z,1690998213.085 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T17:43:33.085Z,1690998213.085 [Default:CheckIn] Stopped 2023-08-02T17:43:33.085Z,1690998213.085 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T17:43:33.085Z,1690998213.085 [Default:CheckIn](INFO): Running loop #10 2023-08-02T17:43:33.085Z,1690998213.085 [Default:CheckIn] Running Loop=10 2023-08-02T17:43:33.086Z,1690998213.086 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T17:43:33.086Z,1690998213.086 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T17:43:35.100Z,1690998215.100 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174335.00,A,3648.17008,N,12147.28043,W,0.467,297.94,020823,,,A*7F 2023-08-02T17:43:35.102Z,1690998215.102 [NAL9602](INFO): GPS fix at 20230802T174335: (36.802835, -121.788007) 2023-08-02T17:43:35.135Z,1690998215.135 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T17:43:35.135Z,1690998215.135 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T17:43:42.832Z,1690998222.832 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0021.lzma 2023-08-02T17:43:43.835Z,1690998223.835 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0021.lzma.bak 2023-08-02T17:43:43.835Z,1690998223.835 [DataOverHttps](INFO): SBD MOMSN=18509759 2023-08-02T17:43:59.692Z,1690998239.692 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230802T170310/Express0022.lzma 2023-08-02T17:44:00.695Z,1690998240.695 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0022.lzma.bak 2023-08-02T17:44:00.695Z,1690998240.695 [DataOverHttps](INFO): SBD MOMSN=18509762 2023-08-02T17:44:01.787Z,1690998241.787 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T17:44:01.787Z,1690998241.787 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T17:44:01.787Z,1690998241.787 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T17:44:05.802Z,1690998245.802 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-08-02T17:44:05.877Z,1690998245.877 [NAL9602](ERROR): received: +CSQ:0 OK595, 0, 0, 0, 0 OK 2023-08-02T17:48:04.563Z,1690998484.563 [NAL9602](INFO): SBD MO Status=2, MOMSN=44596, MT Status=2, MTMSN=0 2023-08-02T17:48:04.563Z,1690998484.563 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-08-02T17:48:37.290Z,1690998517.290 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-08-02T17:49:02.366Z,1690998542.366 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T17:49:02.367Z,1690998542.367 [Default:CheckIn:C.Wait] Stopped 2023-08-02T17:49:02.367Z,1690998542.367 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T17:49:02.367Z,1690998542.367 [Default:CheckIn:D] Running Loop=1 2023-08-02T17:49:02.752Z,1690998542.752 [Default:CheckIn:D] Stopped 2023-08-02T17:49:02.752Z,1690998542.752 [Default:CheckIn:E] Running Loop=1 2023-08-02T17:49:03.159Z,1690998543.159 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.330652 min 2023-08-02T17:49:03.159Z,1690998543.159 [Default:CheckIn:E] Stopped 2023-08-02T17:49:03.160Z,1690998543.160 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T17:49:03.160Z,1690998543.160 [Default:CheckIn] Stopped 2023-08-02T17:49:03.160Z,1690998543.160 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T17:49:03.160Z,1690998543.160 [Default:CheckIn](INFO): Running loop #11 2023-08-02T17:49:03.161Z,1690998543.161 [Default:CheckIn] Running Loop=11 2023-08-02T17:49:03.161Z,1690998543.161 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T17:49:03.161Z,1690998543.161 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T17:49:05.172Z,1690998545.172 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174905.00,A,3648.16550,N,12147.28177,W,1.011,322.99,020823,,,A*7F 2023-08-02T17:49:05.175Z,1690998545.175 [NAL9602](INFO): GPS fix at 20230802T174905: (36.802758, -121.788029) 2023-08-02T17:49:05.186Z,1690998545.186 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T17:49:05.186Z,1690998545.186 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T17:49:12.433Z,1690998552.433 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0024.lzma 2023-08-02T17:49:13.435Z,1690998553.435 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0024.lzma.bak 2023-08-02T17:49:13.435Z,1690998553.435 [DataOverHttps](INFO): SBD MOMSN=18509765 2023-08-02T17:49:29.273Z,1690998569.273 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230802T170310/Express0025.lzma 2023-08-02T17:49:30.275Z,1690998570.275 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0025.lzma.bak 2023-08-02T17:49:30.275Z,1690998570.275 [DataOverHttps](INFO): SBD MOMSN=18509768 2023-08-02T17:49:31.431Z,1690998571.431 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T17:49:31.432Z,1690998571.432 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T17:49:31.432Z,1690998571.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T17:49:37.078Z,1690998577.078 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T17:54:32.030Z,1690998872.030 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T17:54:32.030Z,1690998872.030 [Default:CheckIn:C.Wait] Stopped 2023-08-02T17:54:32.030Z,1690998872.030 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T17:54:32.031Z,1690998872.031 [Default:CheckIn:D] Running Loop=1 2023-08-02T17:54:32.436Z,1690998872.436 [Default:CheckIn:D] Stopped 2023-08-02T17:54:32.436Z,1690998872.436 [Default:CheckIn:E] Running Loop=1 2023-08-02T17:54:32.852Z,1690998872.852 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.825382 min 2023-08-02T17:54:32.852Z,1690998872.852 [Default:CheckIn:E] Stopped 2023-08-02T17:54:32.853Z,1690998872.853 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T17:54:32.853Z,1690998872.853 [Default:CheckIn] Stopped 2023-08-02T17:54:32.853Z,1690998872.853 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T17:54:32.853Z,1690998872.853 [Default:CheckIn](INFO): Running loop #12 2023-08-02T17:54:32.853Z,1690998872.853 [Default:CheckIn] Running Loop=12 2023-08-02T17:54:32.853Z,1690998872.853 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T17:54:32.853Z,1690998872.853 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T17:54:34.852Z,1690998874.852 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175435.00,A,3648.16917,N,12147.27607,W,0.525,322.99,020823,,,A*73 2023-08-02T17:54:34.855Z,1690998874.855 [NAL9602](INFO): GPS fix at 20230802T175435: (36.802819, -121.787935) 2023-08-02T17:54:34.865Z,1690998874.865 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T17:54:34.866Z,1690998874.866 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T17:54:41.816Z,1690998881.816 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0027.lzma 2023-08-02T17:54:42.819Z,1690998882.819 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0027.lzma.bak 2023-08-02T17:54:42.819Z,1690998882.819 [DataOverHttps](INFO): SBD MOMSN=18509772 2023-08-02T17:55:02.092Z,1690998902.092 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230802T170310/Express0028.lzma 2023-08-02T17:55:03.095Z,1690998903.095 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0028.lzma.bak 2023-08-02T17:55:03.095Z,1690998903.095 [DataOverHttps](INFO): SBD MOMSN=18509775 2023-08-02T17:55:04.432Z,1690998904.432 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T17:55:04.432Z,1690998904.432 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T17:55:04.432Z,1690998904.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T17:55:05.614Z,1690998905.614 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-08-02T17:55:05.689Z,1690998905.689 [NAL9602](ERROR): received: +CSQ:0 OK596, 2, 0, 0, 0 OK 2023-08-02T17:57:24.587Z,1690999044.587 [NAL9602](INFO): SBD MO Status=2, MOMSN=44596, MT Status=2, MTMSN=0 2023-08-02T17:57:24.587Z,1690999044.587 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-08-02T17:59:37.097Z,1690999177.097 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-08-02T18:00:04.990Z,1690999204.990 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:00:04.990Z,1690999204.990 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:00:04.990Z,1690999204.990 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:00:04.990Z,1690999204.990 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:00:05.394Z,1690999205.394 [Default:CheckIn:D] Stopped 2023-08-02T18:00:05.394Z,1690999205.394 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:00:05.792Z,1690999205.792 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.374683 min 2023-08-02T18:00:05.792Z,1690999205.792 [Default:CheckIn:E] Stopped 2023-08-02T18:00:05.792Z,1690999205.792 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:00:05.793Z,1690999205.793 [Default:CheckIn] Stopped 2023-08-02T18:00:05.793Z,1690999205.793 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:00:05.809Z,1690999205.809 [Default:CheckIn](INFO): Running loop #13 2023-08-02T18:00:05.809Z,1690999205.809 [Default:CheckIn] Running Loop=13 2023-08-02T18:00:05.809Z,1690999205.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:00:05.809Z,1690999205.809 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:00:07.803Z,1690999207.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180008.00,A,3648.17083,N,12147.28299,W,0.622,0.00,020823,,,A*7D 2023-08-02T18:00:07.805Z,1690999207.805 [NAL9602](INFO): GPS fix at 20230802T180008: (36.802847, -121.788050) 2023-08-02T18:00:07.815Z,1690999207.815 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:00:07.816Z,1690999207.816 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:00:14.688Z,1690999214.688 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230802T170310/Courier0030.lzma 2023-08-02T18:00:15.691Z,1690999215.691 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0030.lzma.bak 2023-08-02T18:00:15.691Z,1690999215.691 [DataOverHttps](INFO): SBD MOMSN=18509778 2023-08-02T18:00:31.473Z,1690999231.473 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230802T170310/Express0031.lzma 2023-08-02T18:00:32.491Z,1690999232.491 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0031.lzma.bak 2023-08-02T18:00:32.491Z,1690999232.491 [DataOverHttps](INFO): SBD MOMSN=18509781 2023-08-02T18:00:33.668Z,1690999233.668 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:00:33.668Z,1690999233.668 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:00:33.669Z,1690999233.669 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:00:40.130Z,1690999240.130 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T18:05:34.243Z,1690999534.243 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:05:34.243Z,1690999534.243 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:05:34.243Z,1690999534.243 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:05:34.243Z,1690999534.243 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:05:34.654Z,1690999534.654 [Default:CheckIn:D] Stopped 2023-08-02T18:05:34.654Z,1690999534.654 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:05:35.061Z,1690999535.061 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.862345 min 2023-08-02T18:05:35.061Z,1690999535.061 [Default:CheckIn:E] Stopped 2023-08-02T18:05:35.061Z,1690999535.061 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:05:35.061Z,1690999535.061 [Default:CheckIn] Stopped 2023-08-02T18:05:35.061Z,1690999535.061 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:05:35.061Z,1690999535.061 [Default:CheckIn](INFO): Running loop #14 2023-08-02T18:05:35.061Z,1690999535.061 [Default:CheckIn] Running Loop=14 2023-08-02T18:05:35.061Z,1690999535.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:05:35.062Z,1690999535.062 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:05:37.068Z,1690999537.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180537.00,A,3648.17332,N,12147.28219,W,0.447,0.00,020823,,,A*74 2023-08-02T18:05:37.080Z,1690999537.080 [NAL9602](INFO): GPS fix at 20230802T180537: (36.802889, -121.788037) 2023-08-02T18:05:37.090Z,1690999537.090 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:05:37.090Z,1690999537.090 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:05:44.544Z,1690999544.544 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0033.lzma 2023-08-02T18:05:45.547Z,1690999545.547 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0033.lzma.bak 2023-08-02T18:05:45.547Z,1690999545.547 [DataOverHttps](INFO): SBD MOMSN=18509785 2023-08-02T18:06:01.453Z,1690999561.453 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230802T170310/Express0034.lzma 2023-08-02T18:06:02.455Z,1690999562.455 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0034.lzma.bak 2023-08-02T18:06:02.455Z,1690999562.455 [DataOverHttps](INFO): SBD MOMSN=18509788 2023-08-02T18:06:03.742Z,1690999563.742 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:06:03.742Z,1690999563.742 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:06:03.742Z,1690999563.742 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:06:07.767Z,1690999567.767 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-08-02T18:06:07.845Z,1690999567.845 [NAL9602](ERROR): received: +CSQ:0 OK596, 2, 0, 0, 0 OK 2023-08-02T18:07:21.355Z,1690999641.355 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-08-02T18:07:27.352Z,1690999647.352 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:07:41.087Z,1690999661.087 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:07:55.228Z,1690999675.228 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:08:08.964Z,1690999688.964 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:08:22.700Z,1690999702.700 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:08:36.435Z,1690999716.435 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:08:50.172Z,1690999730.172 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:09:03.908Z,1690999743.908 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:09:17.240Z,1690999757.240 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:09:30.976Z,1690999770.976 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:09:45.923Z,1690999785.923 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:10:00.871Z,1690999800.871 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:10:16.223Z,1690999816.223 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:10:31.172Z,1690999831.172 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:10:39.258Z,1690999839.258 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-08-02T18:10:46.524Z,1690999846.524 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:11:02.284Z,1690999862.284 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:11:04.354Z,1690999864.354 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:11:04.354Z,1690999864.354 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:11:04.354Z,1690999864.354 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:11:04.354Z,1690999864.354 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:11:04.726Z,1690999864.726 [Default:CheckIn:D] Stopped 2023-08-02T18:11:04.726Z,1690999864.726 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:11:05.123Z,1690999865.123 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.363558 min 2023-08-02T18:11:05.123Z,1690999865.123 [Default:CheckIn:E] Stopped 2023-08-02T18:11:05.124Z,1690999865.124 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:11:05.124Z,1690999865.124 [Default:CheckIn] Stopped 2023-08-02T18:11:05.124Z,1690999865.124 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:11:05.124Z,1690999865.124 [Default:CheckIn](INFO): Running loop #15 2023-08-02T18:11:05.124Z,1690999865.124 [Default:CheckIn] Running Loop=15 2023-08-02T18:11:05.124Z,1690999865.124 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:11:05.124Z,1690999865.124 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:11:07.140Z,1690999867.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181107.00,A,3648.16951,N,12147.27535,W,0.233,114.20,020823,,,A*79 2023-08-02T18:11:07.143Z,1690999867.143 [NAL9602](INFO): GPS fix at 20230802T181107: (36.802825, -121.787922) 2023-08-02T18:11:07.153Z,1690999867.153 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:11:07.153Z,1690999867.153 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:11:14.829Z,1690999874.829 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0036.lzma 2023-08-02T18:11:15.831Z,1690999875.831 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0036.lzma.bak 2023-08-02T18:11:15.831Z,1690999875.831 [DataOverHttps](INFO): SBD MOMSN=18509791 2023-08-02T18:11:17.639Z,1690999877.639 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:11:31.701Z,1690999891.701 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230802T170310/Express0037.lzma 2023-08-02T18:11:32.703Z,1690999892.703 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0037.lzma.bak 2023-08-02T18:11:32.703Z,1690999892.703 [DataOverHttps](INFO): SBD MOMSN=18509794 2023-08-02T18:11:33.396Z,1690999893.396 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:11:33.824Z,1690999893.824 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:11:33.824Z,1690999893.824 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:11:33.825Z,1690999893.825 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:11:39.054Z,1690999899.054 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T18:11:48.345Z,1690999908.345 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-08-02T18:12:03.292Z,1690999923.292 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-08-02T18:12:09.763Z,1690999929.763 [BPC1](INFO): Calculating totals. Valid battery stick count: 38. Valid reserve battery stick count: 5. 2023-08-02T18:12:09.765Z,1690999929.765 [BPC1](INFO): Received data from all battery sticks. 2023-08-02T18:16:34.406Z,1691000194.406 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:16:34.406Z,1691000194.406 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:16:34.406Z,1691000194.406 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:16:34.406Z,1691000194.406 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:16:34.795Z,1691000194.795 [Default:CheckIn:D] Stopped 2023-08-02T18:16:34.795Z,1691000194.795 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:16:35.204Z,1691000195.204 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.864697 min 2023-08-02T18:16:35.204Z,1691000195.204 [Default:CheckIn:E] Stopped 2023-08-02T18:16:35.204Z,1691000195.204 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:16:35.204Z,1691000195.204 [Default:CheckIn] Stopped 2023-08-02T18:16:35.205Z,1691000195.205 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:16:35.205Z,1691000195.205 [Default:CheckIn](INFO): Running loop #16 2023-08-02T18:16:35.205Z,1691000195.205 [Default:CheckIn] Running Loop=16 2023-08-02T18:16:35.205Z,1691000195.205 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:16:35.205Z,1691000195.205 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:16:37.218Z,1691000197.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181637.00,A,3648.16729,N,12147.27753,W,1.069,147.93,020823,,,A*7C 2023-08-02T18:16:37.220Z,1691000197.220 [NAL9602](INFO): GPS fix at 20230802T181637: (36.802788, -121.787959) 2023-08-02T18:16:37.231Z,1691000197.231 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:16:37.231Z,1691000197.231 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:16:44.976Z,1691000204.976 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230802T170310/Courier0039.lzma 2023-08-02T18:16:45.979Z,1691000205.979 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0039.lzma.bak 2023-08-02T18:16:45.979Z,1691000205.979 [DataOverHttps](INFO): SBD MOMSN=18509799 2023-08-02T18:17:04.365Z,1691000224.365 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230802T170310/Express0040.lzma 2023-08-02T18:17:05.367Z,1691000225.367 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0040.lzma.bak 2023-08-02T18:17:05.367Z,1691000225.367 [DataOverHttps](INFO): SBD MOMSN=18509802 2023-08-02T18:17:06.777Z,1691000226.777 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:17:06.777Z,1691000226.777 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:17:06.777Z,1691000226.777 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:17:07.966Z,1691000227.966 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-08-02T18:17:08.049Z,1691000228.049 [NAL9602](ERROR): received: +CSQ:0 OK596, 2, 0, 0, 0 OK 2023-08-02T18:20:09.364Z,1691000409.364 [NAL9602](INFO): SBD MO Status=2, MOMSN=44596, MT Status=2, MTMSN=0 2023-08-02T18:20:09.364Z,1691000409.364 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-08-02T18:21:02.691Z,1691000462.691 [NAL9602](INFO): SBD MO Status=2, MOMSN=44596, MT Status=2, MTMSN=0 2023-08-02T18:21:02.691Z,1691000462.691 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-08-02T18:21:39.049Z,1691000499.049 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-08-02T18:22:07.352Z,1691000527.352 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:22:07.352Z,1691000527.352 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:22:07.352Z,1691000527.352 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:22:07.353Z,1691000527.353 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:22:07.748Z,1691000527.748 [Default:CheckIn:D] Stopped 2023-08-02T18:22:07.748Z,1691000527.748 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:22:08.147Z,1691000528.147 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.413916 min 2023-08-02T18:22:08.147Z,1691000528.147 [Default:CheckIn:E] Stopped 2023-08-02T18:22:08.147Z,1691000528.147 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:22:08.147Z,1691000528.147 [Default:CheckIn] Stopped 2023-08-02T18:22:08.147Z,1691000528.147 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:22:08.148Z,1691000528.148 [Default:CheckIn](INFO): Running loop #17 2023-08-02T18:22:08.148Z,1691000528.148 [Default:CheckIn] Running Loop=17 2023-08-02T18:22:08.148Z,1691000528.148 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:22:08.148Z,1691000528.148 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:22:10.161Z,1691000530.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182210.00,A,3648.16886,N,12147.27791,W,0.330,147.93,020823,,,A*74 2023-08-02T18:22:10.164Z,1691000530.164 [NAL9602](INFO): GPS fix at 20230802T182210: (36.802814, -121.787965) 2023-08-02T18:22:10.174Z,1691000530.174 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:22:10.174Z,1691000530.174 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:22:17.852Z,1691000537.852 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0042.lzma 2023-08-02T18:22:18.855Z,1691000538.855 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0042.lzma.bak 2023-08-02T18:22:18.855Z,1691000538.855 [DataOverHttps](INFO): SBD MOMSN=18509805 2023-08-02T18:22:36.841Z,1691000556.841 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230802T170310/Express0043.lzma 2023-08-02T18:22:37.843Z,1691000557.843 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0043.lzma.bak 2023-08-02T18:22:37.843Z,1691000557.843 [DataOverHttps](INFO): SBD MOMSN=18509808 2023-08-02T18:22:39.308Z,1691000559.308 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:22:39.308Z,1691000559.308 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:22:39.309Z,1691000559.309 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:22:42.126Z,1691000562.126 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T18:27:39.948Z,1691000859.948 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:27:39.948Z,1691000859.948 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:27:39.948Z,1691000859.948 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:27:39.948Z,1691000859.948 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:27:40.351Z,1691000860.351 [Default:CheckIn:D] Stopped 2023-08-02T18:27:40.351Z,1691000860.351 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:27:40.756Z,1691000860.756 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.957292 min 2023-08-02T18:27:40.756Z,1691000860.756 [Default:CheckIn:E] Stopped 2023-08-02T18:27:40.756Z,1691000860.756 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:27:40.756Z,1691000860.756 [Default:CheckIn] Stopped 2023-08-02T18:27:40.757Z,1691000860.757 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:27:40.757Z,1691000860.757 [Default:CheckIn](INFO): Running loop #18 2023-08-02T18:27:40.757Z,1691000860.757 [Default:CheckIn] Running Loop=18 2023-08-02T18:27:40.757Z,1691000860.757 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:27:40.757Z,1691000860.757 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:27:42.767Z,1691000862.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182743.00,A,3648.16940,N,12147.27927,W,0.564,147.93,020823,,,A*78 2023-08-02T18:27:42.770Z,1691000862.770 [NAL9602](INFO): GPS fix at 20230802T182743: (36.802823, -121.787988) 2023-08-02T18:27:42.800Z,1691000862.800 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:27:42.800Z,1691000862.800 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:27:50.064Z,1691000870.064 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0045.lzma 2023-08-02T18:27:51.067Z,1691000871.067 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0045.lzma.bak 2023-08-02T18:27:51.067Z,1691000871.067 [DataOverHttps](INFO): SBD MOMSN=18509812 2023-08-02T18:28:07.089Z,1691000887.089 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230802T170310/Express0046.lzma 2023-08-02T18:28:08.091Z,1691000888.091 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0046.lzma.bak 2023-08-02T18:28:08.091Z,1691000888.091 [DataOverHttps](INFO): SBD MOMSN=18509815 2023-08-02T18:28:09.446Z,1691000889.446 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:28:09.446Z,1691000889.446 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:28:09.446Z,1691000889.446 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:28:13.478Z,1691000893.478 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-08-02T18:28:13.553Z,1691000893.553 [NAL9602](ERROR): received: +CSQ:0 OK596, 2, 0, 0, 0 OK 2023-08-02T18:29:06.398Z,1691000946.398 [NAL9602](INFO): SBD MO Status=2, MOMSN=44596, MT Status=2, MTMSN=0 2023-08-02T18:29:06.399Z,1691000946.399 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-08-02T18:30:18.724Z,1691001018.724 [NAL9602](INFO): SBD MO Status=2, MOMSN=44596, MT Status=2, MTMSN=0 2023-08-02T18:30:18.724Z,1691001018.724 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-08-02T18:30:45.784Z,1691001045.784 [NAL9602](INFO): SBD MO Status=2, MOMSN=44596, MT Status=2, MTMSN=0 2023-08-02T18:30:45.784Z,1691001045.784 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-08-02T18:32:45.007Z,1691001165.007 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-08-02T18:33:10.139Z,1691001190.139 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:33:10.139Z,1691001190.139 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:33:10.139Z,1691001190.139 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:33:10.139Z,1691001190.139 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:33:10.558Z,1691001190.558 [Default:CheckIn:D] Stopped 2023-08-02T18:33:10.559Z,1691001190.559 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:33:10.938Z,1691001190.938 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.460758 min 2023-08-02T18:33:10.938Z,1691001190.938 [Default:CheckIn:E] Stopped 2023-08-02T18:33:10.938Z,1691001190.938 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:33:10.938Z,1691001190.938 [Default:CheckIn] Stopped 2023-08-02T18:33:10.938Z,1691001190.938 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:33:10.938Z,1691001190.938 [Default:CheckIn](INFO): Running loop #19 2023-08-02T18:33:10.939Z,1691001190.939 [Default:CheckIn] Running Loop=19 2023-08-02T18:33:10.939Z,1691001190.939 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:33:10.939Z,1691001190.939 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:33:12.952Z,1691001192.952 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183313.00,A,3648.16973,N,12147.27960,W,0.233,142.63,020823,,,A*74 2023-08-02T18:33:12.954Z,1691001192.954 [NAL9602](INFO): GPS fix at 20230802T183313: (36.802829, -121.787993) 2023-08-02T18:33:12.965Z,1691001192.965 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:33:12.965Z,1691001192.965 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:33:20.644Z,1691001200.644 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230802T170310/Courier0048.lzma 2023-08-02T18:33:21.647Z,1691001201.647 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0048.lzma.bak 2023-08-02T18:33:21.647Z,1691001201.647 [DataOverHttps](INFO): SBD MOMSN=18509819 2023-08-02T18:33:37.549Z,1691001217.549 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230802T170310/Express0049.lzma 2023-08-02T18:33:38.551Z,1691001218.551 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0049.lzma.bak 2023-08-02T18:33:38.551Z,1691001218.551 [DataOverHttps](INFO): SBD MOMSN=18509822 2023-08-02T18:33:39.635Z,1691001219.635 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:33:39.636Z,1691001219.636 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:33:39.636Z,1691001219.636 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:33:45.267Z,1691001225.267 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T18:37:36.433Z,1691001456.433 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-08-02T18:37:36.433Z,1691001456.433 [DropWeight] Hardware Fault, FailCount= 1 2023-08-02T18:37:36.433Z,1691001456.433 [DropWeight](ERROR): Hardware Fault 2023-08-02T18:37:36.450Z,1691001456.450 [CommandExec](FAULT): Scheduling is paused 2023-08-02T18:37:36.450Z,1691001456.450 [CBIT](INFO): Critical error at 20230802T183736 2023-08-02T18:37:36.453Z,1691001456.453 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-08-02T18:37:36.454Z,1691001456.454 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-08-02T18:37:36.859Z,1691001456.859 [CBIT](INFO): Critical error at 20230802T183736 2023-08-02T18:38:40.336Z,1691001520.336 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:38:40.336Z,1691001520.336 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:38:40.336Z,1691001520.336 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:38:40.336Z,1691001520.336 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:38:40.741Z,1691001520.741 [Default:CheckIn:D] Stopped 2023-08-02T18:38:40.741Z,1691001520.741 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:38:41.131Z,1691001521.131 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.963802 min 2023-08-02T18:38:41.131Z,1691001521.131 [Default:CheckIn:E] Stopped 2023-08-02T18:38:41.131Z,1691001521.131 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:38:41.131Z,1691001521.131 [Default:CheckIn] Stopped 2023-08-02T18:38:41.131Z,1691001521.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:38:41.132Z,1691001521.132 [Default:CheckIn](INFO): Running loop #20 2023-08-02T18:38:41.132Z,1691001521.132 [Default:CheckIn] Running Loop=20 2023-08-02T18:38:41.132Z,1691001521.132 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:38:41.132Z,1691001521.132 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:38:43.144Z,1691001523.144 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183843.00,A,3648.17449,N,12147.27953,W,0.058,359.91,020823,,,A*75 2023-08-02T18:38:43.146Z,1691001523.146 [NAL9602](INFO): GPS fix at 20230802T183843: (36.802908, -121.787992) 2023-08-02T18:38:43.156Z,1691001523.156 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:38:43.156Z,1691001523.156 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:38:53.261Z,1691001533.261 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20230802T170310/Courier0051.lzma 2023-08-02T18:38:54.263Z,1691001534.263 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0051.lzma.bak 2023-08-02T18:38:54.264Z,1691001534.264 [DataOverHttps](INFO): SBD MOMSN=18509828 2023-08-02T18:39:10.545Z,1691001550.545 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20230802T170310/Express0052.lzma 2023-08-02T18:39:11.547Z,1691001551.547 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0052.lzma.bak 2023-08-02T18:39:11.547Z,1691001551.547 [DataOverHttps](INFO): SBD MOMSN=18509833 2023-08-02T18:39:12.695Z,1691001552.695 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:39:12.695Z,1691001552.695 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:39:12.695Z,1691001552.695 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:39:13.887Z,1691001553.887 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-08-02T18:39:13.961Z,1691001553.961 [NAL9602](ERROR): received: +CSQ:0 OK596, 2, 0, 0, 0 OK 2023-08-02T18:39:24.395Z,1691001564.395 [NAL9602](INFO): SBD MO Status=1, MOMSN=44596, MT Status=0, MTMSN=0 2023-08-02T18:39:24.396Z,1691001564.396 [NAL9602](INFO): No messages in MT queue 2023-08-02T18:39:55.105Z,1691001595.105 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T18:44:13.486Z,1691001853.486 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:44:13.486Z,1691001853.486 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:44:13.486Z,1691001853.486 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:44:13.487Z,1691001853.487 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:44:13.883Z,1691001853.883 [Default:CheckIn:D] Stopped 2023-08-02T18:44:13.883Z,1691001853.883 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:44:14.288Z,1691001854.288 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.516170 min 2023-08-02T18:44:14.288Z,1691001854.288 [Default:CheckIn:E] Stopped 2023-08-02T18:44:14.288Z,1691001854.288 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:44:14.288Z,1691001854.288 [Default:CheckIn] Stopped 2023-08-02T18:44:14.288Z,1691001854.288 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:44:14.288Z,1691001854.288 [Default:CheckIn](INFO): Running loop #21 2023-08-02T18:44:14.288Z,1691001854.288 [Default:CheckIn] Running Loop=21 2023-08-02T18:44:14.288Z,1691001854.288 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:44:14.288Z,1691001854.288 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:44:16.296Z,1691001856.296 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184416.00,A,3648.16963,N,12147.27993,W,0.194,58.75,020823,,,A*4F 2023-08-02T18:44:16.298Z,1691001856.298 [NAL9602](INFO): GPS fix at 20230802T184416: (36.802827, -121.787999) 2023-08-02T18:44:16.347Z,1691001856.347 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:44:16.347Z,1691001856.347 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:44:26.617Z,1691001866.617 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230802T170310/Courier0054.lzma 2023-08-02T18:44:27.622Z,1691001867.622 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0054.lzma.bak 2023-08-02T18:44:27.622Z,1691001867.622 [DataOverHttps](INFO): SBD MOMSN=18509840 2023-08-02T18:44:43.421Z,1691001883.421 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230802T170310/Express0055.lzma 2023-08-02T18:44:44.423Z,1691001884.423 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0055.lzma.bak 2023-08-02T18:44:44.423Z,1691001884.423 [DataOverHttps](INFO): SBD MOMSN=18509843 2023-08-02T18:44:45.508Z,1691001885.508 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:44:45.509Z,1691001885.509 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:44:45.509Z,1691001885.509 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:47:40.996Z,1691002060.996 [NAL9602](INFO): SBD MO Status=2, MOMSN=44597, MT Status=2, MTMSN=0 2023-08-02T18:47:40.996Z,1691002060.996 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-08-02T18:48:05.237Z,1691002085.237 [NAL9602](INFO): SBD MO Status=2, MOMSN=44597, MT Status=2, MTMSN=0 2023-08-02T18:48:05.237Z,1691002085.237 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-08-02T18:49:18.044Z,1691002158.044 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-08-02T18:49:45.931Z,1691002185.931 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:49:45.932Z,1691002185.932 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:49:45.932Z,1691002185.932 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:49:45.932Z,1691002185.932 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:49:46.339Z,1691002186.339 [Default:CheckIn:D] Stopped 2023-08-02T18:49:46.339Z,1691002186.339 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:49:46.744Z,1691002186.744 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.057104 min 2023-08-02T18:49:46.744Z,1691002186.744 [Default:CheckIn:E] Stopped 2023-08-02T18:49:46.744Z,1691002186.744 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:49:46.744Z,1691002186.744 [Default:CheckIn] Stopped 2023-08-02T18:49:46.744Z,1691002186.744 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:49:46.744Z,1691002186.744 [Default:CheckIn](INFO): Running loop #22 2023-08-02T18:49:46.744Z,1691002186.744 [Default:CheckIn] Running Loop=22 2023-08-02T18:49:46.744Z,1691002186.744 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:49:46.745Z,1691002186.745 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:49:48.756Z,1691002188.756 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184949.00,A,3648.17028,N,12147.27932,W,0.136,58.75,020823,,,A*4C 2023-08-02T18:49:48.758Z,1691002188.758 [NAL9602](INFO): GPS fix at 20230802T184949: (36.802838, -121.787989) 2023-08-02T18:49:48.768Z,1691002188.768 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:49:48.768Z,1691002188.768 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:49:56.416Z,1691002196.416 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230802T170310/Courier0057.lzma 2023-08-02T18:49:57.419Z,1691002197.419 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0057.lzma.bak 2023-08-02T18:49:57.419Z,1691002197.419 [DataOverHttps](INFO): SBD MOMSN=18509847 2023-08-02T18:50:13.505Z,1691002213.505 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230802T170310/Express0058.lzma 2023-08-02T18:50:14.507Z,1691002214.507 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0058.lzma.bak 2023-08-02T18:50:14.507Z,1691002214.507 [DataOverHttps](INFO): SBD MOMSN=18509851 2023-08-02T18:50:15.835Z,1691002215.835 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:50:15.835Z,1691002215.835 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:50:15.835Z,1691002215.835 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:50:21.107Z,1691002221.107 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-02T18:51:15.941Z,1691002275.941 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-08-02T18:51:47.073Z,1691002307.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-08-02T18:52:36.578Z,1691002356.578 [CBIT](INFO): Clearing failed state for component DropWeight 2023-08-02T18:52:36.578Z,1691002356.578 [DropWeight] No Fault, FailCount= 1 2023-08-02T18:55:16.627Z,1691002516.627 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-08-02T18:55:16.627Z,1691002516.627 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:55:16.627Z,1691002516.627 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:55:16.627Z,1691002516.627 [Default:CheckIn:D] Running Loop=1 2023-08-02T18:55:17.041Z,1691002517.041 [Default:CheckIn:D] Stopped 2023-08-02T18:55:17.041Z,1691002517.041 [Default:CheckIn:E] Running Loop=1 2023-08-02T18:55:17.483Z,1691002517.483 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.568799 min 2023-08-02T18:55:17.483Z,1691002517.483 [Default:CheckIn:E] Stopped 2023-08-02T18:55:17.483Z,1691002517.483 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-08-02T18:55:17.483Z,1691002517.483 [Default:CheckIn] Stopped 2023-08-02T18:55:17.483Z,1691002517.483 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:55:17.484Z,1691002517.484 [Default:CheckIn](INFO): Running loop #23 2023-08-02T18:55:17.484Z,1691002517.484 [Default:CheckIn] Running Loop=23 2023-08-02T18:55:17.484Z,1691002517.484 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-02T18:55:17.484Z,1691002517.484 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-02T18:55:19.443Z,1691002519.443 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185520.00,A,3648.16732,N,12147.29141,W,2.974,260.42,020823,,,A*70 2023-08-02T18:55:19.446Z,1691002519.446 [NAL9602](INFO): GPS fix at 20230802T185520: (36.802789, -121.788190) 2023-08-02T18:55:19.456Z,1691002519.456 [Default:CheckIn:Read_GPS] Stopped 2023-08-02T18:55:19.456Z,1691002519.456 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-02T18:55:26.836Z,1691002526.836 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230802T170310/Courier0060.lzma 2023-08-02T18:55:27.839Z,1691002527.839 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0060.lzma.bak 2023-08-02T18:55:27.839Z,1691002527.839 [DataOverHttps](INFO): SBD MOMSN=18509856 2023-08-02T18:55:43.761Z,1691002543.761 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20230802T170310/Express0061.lzma 2023-08-02T18:55:44.763Z,1691002544.763 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0061.lzma.bak 2023-08-02T18:55:44.763Z,1691002544.763 [DataOverHttps](INFO): SBD MOMSN=18509859 2023-08-02T18:55:46.145Z,1691002546.145 [Default:CheckIn:Read_Iridium] Stopped 2023-08-02T18:55:46.145Z,1691002546.145 [Default:CheckIn:C.Wait] Running Loop=1 2023-08-02T18:55:46.145Z,1691002546.145 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-08-02T18:55:50.162Z,1691002550.162 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-08-02T18:55:50.237Z,1691002550.237 [NAL9602](ERROR): received: +CSQ:0 OK597, 2, 0, 0, 0 OK 2023-08-02T18:57:32.525Z,1691002652.525 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2023-08-02T18:57:32.526Z,1691002652.526 [CommandExec](IMPORTANT): Onboard.Humidity 9.650836 % 2023-08-02T18:57:38.560Z,1691002658.560 [CommandExec](IMPORTANT): got command get Onboard.Pressure 2023-08-02T18:57:38.560Z,1691002658.560 [CommandExec](IMPORTANT): Onboard.Pressure 65473.296875 Pa 2023-08-02T18:57:43.057Z,1691002663.057 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2023-08-02T18:57:43.057Z,1691002663.057 [CommandExec](IMPORTANT): Onboard.Pressure 9.505112 psi 2023-08-02T18:57:51.086Z,1691002671.086 [CommandExec](IMPORTANT): got command quit 2023-08-02T18:57:52.093Z,1691002672.093 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:52.093Z,1691002672.093 [CommandExec](INFO): Uninitializing the command executive. 2023-08-02T18:57:52.093Z,1691002672.093 [CommandExec](INFO): Uninitializing the command scheduler. 2023-08-02T18:57:52.094Z,1691002672.094 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:52.153Z,1691002672.153 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-08-02T18:57:52.153Z,1691002672.153 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-08-02T18:57:52.153Z,1691002672.153 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:52.154Z,1691002672.154 [NavChartDb](INFO): Join timeout helper Thread ID is 3632 2023-08-02T18:57:52.449Z,1691002672.449 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:52.449Z,1691002672.449 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:52.465Z,1691002672.465 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-08-02T18:57:52.465Z,1691002672.465 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:52.465Z,1691002672.465 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3633 2023-08-02T18:57:52.709Z,1691002672.709 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:52.710Z,1691002672.710 [WetLabsBB2FL](INFO): Powering down 2023-08-02T18:57:52.710Z,1691002672.710 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:52.717Z,1691002672.717 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-08-02T18:57:52.717Z,1691002672.717 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:52.718Z,1691002672.718 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3634 2023-08-02T18:57:53.450Z,1691002673.450 [CTD_Seabird](INFO): Powering down 2023-08-02T18:57:53.461Z,1691002673.461 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:53.461Z,1691002673.461 [CTD_Seabird](INFO): Powering down 2023-08-02T18:57:53.473Z,1691002673.473 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:53.473Z,1691002673.473 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-08-02T18:57:53.473Z,1691002673.473 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:53.474Z,1691002673.474 [Radio_Surface](INFO): Join timeout helper Thread ID is 3635 2023-08-02T18:57:53.493Z,1691002673.493 [Radio_Surface](INFO): Powering down 2023-08-02T18:57:53.494Z,1691002673.494 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:53.495Z,1691002673.495 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:53.495Z,1691002673.495 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-08-02T18:57:53.495Z,1691002673.495 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:53.496Z,1691002673.496 [Onboard](INFO): Join timeout helper Thread ID is 3636 2023-08-02T18:57:57.581Z,1691002677.581 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:57.581Z,1691002677.581 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:57.590Z,1691002677.590 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-08-02T18:57:57.590Z,1691002677.590 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:57.590Z,1691002677.590 [DataOverHttps](INFO): Join timeout helper Thread ID is 3637 2023-08-02T18:57:58.333Z,1691002678.333 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:58.337Z,1691002678.337 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.342Z,1691002678.342 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-08-02T18:57:58.342Z,1691002678.342 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.343Z,1691002678.343 [BackseatComponent](INFO): Join timeout helper Thread ID is 3638 2023-08-02T18:57:58.397Z,1691002678.397 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:58.398Z,1691002678.398 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.410Z,1691002678.410 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-08-02T18:57:58.410Z,1691002678.410 [logger ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.410Z,1691002678.410 [logger](INFO): Join timeout helper Thread ID is 3639 2023-08-02T18:57:58.421Z,1691002678.421 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:58.422Z,1691002678.422 [logger ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.442Z,1691002678.442 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-08-02T18:57:58.442Z,1691002678.442 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.442Z,1691002678.442 [CommandLine](INFO): Join timeout helper Thread ID is 3640 2023-08-02T18:57:58.481Z,1691002678.481 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:58.481Z,1691002678.481 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.502Z,1691002678.502 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-08-02T18:57:58.502Z,1691002678.502 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.502Z,1691002678.502 [CommandExec](INFO): Join timeout helper Thread ID is 3641 2023-08-02T18:57:58.503Z,1691002678.503 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-08-02T18:57:58.503Z,1691002678.503 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.504Z,1691002678.504 [controlThread](INFO): Join timeout helper Thread ID is 3642 2023-08-02T18:57:58.625Z,1691002678.625 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T18:57:58.626Z,1691002678.626 [controlThread](DEBUG): Uninitializing ControlThread 2023-08-02T18:57:58.626Z,1691002678.626 [AHRS_M2](INFO): Powering down 2023-08-02T18:57:58.698Z,1691002678.698 [NAL9602](INFO): Powering down 2023-08-02T18:57:58.700Z,1691002678.700 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-08-02T18:57:58.701Z,1691002678.701 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-08-02T18:57:58.702Z,1691002678.702 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-08-02T18:57:58.702Z,1691002678.702 [MissionManager](INFO): Uninitializing Mission Default 2023-08-02T18:57:58.703Z,1691002678.703 [Default] Stopped 2023-08-02T18:57:58.703Z,1691002678.703 [Default](DEBUG): Aggregate::uninitialize Default 2023-08-02T18:57:58.703Z,1691002678.703 [Default:B.GoToSurface] Stopped 2023-08-02T18:57:58.703Z,1691002678.703 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-08-02T18:57:58.703Z,1691002678.703 [Default:CheckIn] Stopped 2023-08-02T18:57:58.703Z,1691002678.703 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-02T18:57:58.703Z,1691002678.703 [Default:CheckIn:C.Wait] Stopped 2023-08-02T18:57:58.703Z,1691002678.703 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-08-02T18:57:58.706Z,1691002678.706 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-08-02T18:57:58.706Z,1691002678.706 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-08-02T18:57:58.707Z,1691002678.707 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-08-02T18:57:58.707Z,1691002678.707 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-08-02T18:57:58.707Z,1691002678.707 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-08-02T18:57:58.707Z,1691002678.707 [BuoyancyServo](INFO): Powering down 2023-08-02T18:57:58.721Z,1691002678.721 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-08-02T18:57:58.721Z,1691002678.721 [ElevatorServo](INFO): Powering down 2023-08-02T18:57:58.722Z,1691002678.722 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-08-02T18:57:58.722Z,1691002678.722 [MassServo](INFO): Powering down 2023-08-02T18:57:58.723Z,1691002678.723 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-08-02T18:57:58.723Z,1691002678.723 [RudderServo](INFO): Powering down 2023-08-02T18:57:58.724Z,1691002678.724 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-08-02T18:57:58.724Z,1691002678.724 [ThrusterHE](INFO): Powering down 2023-08-02T18:57:58.725Z,1691002678.725 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-08-02T18:57:58.725Z,1691002678.725 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-08-02T18:57:58.726Z,1691002678.726 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-08-02T18:57:58.726Z,1691002678.726 [CBIT](DEBUG): Powering off loads. 2023-08-02T18:57:58.737Z,1691002678.737 [CBIT](DEBUG): Disabling WDT. 2023-08-02T18:57:58.749Z,1691002678.749 [CBIT](DEBUG): Opening all GF detection circuits. 2023-08-02T18:57:58.750Z,1691002678.750 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.813Z,1691002678.813 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.816Z,1691002678.816 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.822Z,1691002678.822 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.867Z,1691002678.867 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.902Z,1691002678.902 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.906Z,1691002678.906 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:58.948Z,1691002678.948 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-08-02T18:57:59.052Z,1691002679.052 [logger ThreadHandler](INFO): Thread cancelled.