2024-12-18T17:59:15.821Z,1734544755.821 [CommandExec](IMPORTANT): got command restart logs 2024-12-18T17:59:39.816Z,1734544779.816 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-12-18T18:00:10.940Z,1734544810.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:00:42.064Z,1734544842.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:00:56.755Z,1734544856.755 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T18:00:56.755Z,1734544856.755 [Default:CheckIn:C.Wait] Stopped 2024-12-18T18:00:56.755Z,1734544856.755 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T18:00:56.756Z,1734544856.756 [Default:CheckIn:D] Running Loop=1 2024-12-18T18:00:57.163Z,1734544857.163 [Default:CheckIn:D] Stopped 2024-12-18T18:00:57.163Z,1734544857.163 [Default:CheckIn:E] Running Loop=1 2024-12-18T18:00:57.561Z,1734544857.561 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.502645 min 2024-12-18T18:00:57.561Z,1734544857.561 [Default:CheckIn:E] Stopped 2024-12-18T18:00:57.561Z,1734544857.561 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T18:00:57.561Z,1734544857.561 [Default:CheckIn] Stopped 2024-12-18T18:00:57.562Z,1734544857.562 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T18:00:57.562Z,1734544857.562 [Default:CheckIn](INFO): Running loop #3 2024-12-18T18:00:57.562Z,1734544857.562 [Default:CheckIn] Running Loop=3 2024-12-18T18:00:57.562Z,1734544857.562 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T18:00:57.562Z,1734544857.562 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T18:00:59.571Z,1734544859.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180058.00,A,3647.92961,N,12150.79195,W,1.322,321.32,181224,,,D*75 2024-12-18T18:00:59.574Z,1734544859.574 [NAL9602](INFO): GPS fix at 20241218T180058: (36.798827, -121.846532) 2024-12-18T18:00:59.585Z,1734544859.585 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T18:00:59.585Z,1734544859.585 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T18:01:13.188Z,1734544873.188 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:01:27.447Z,1734544887.447 [NAL9602](INFO): SBD MO Status=1, MOMSN=61233, MT Status=0, MTMSN=0 2024-12-18T18:01:27.447Z,1734544887.447 [NAL9602](INFO): No messages in MT queue 2024-12-18T18:01:41.675Z,1734544901.675 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241217T204530/Courier0087.lzma 2024-12-18T18:01:42.677Z,1734544902.677 [DataOverHttps](INFO): Moved sent file to Logs/20241217T204530/Courier0087.lzma.bak 2024-12-18T18:01:42.678Z,1734544902.678 [DataOverHttps](INFO): SBD MOMSN=23822683 2024-12-18T18:01:58.158Z,1734544918.158 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:02:38.432Z,1734544958.432 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20241218T175915/Courier0000.lzma 2024-12-18T18:02:39.433Z,1734544959.433 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0000.lzma.bak 2024-12-18T18:02:39.433Z,1734544959.433 [DataOverHttps](INFO): SBD MOMSN=23822693 2024-12-18T18:02:57.571Z,1734544977.571 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20241217T204530/Express0088.lzma 2024-12-18T18:02:58.574Z,1734544978.574 [DataOverHttps](INFO): Moved sent file to Logs/20241217T204530/Express0088.lzma.bak 2024-12-18T18:02:58.574Z,1734544978.574 [DataOverHttps](INFO): SBD MOMSN=23822702 2024-12-18T18:03:14.476Z,1734544994.476 [DataOverHttps](INFO): Sending 407 bytes from file Logs/20241218T175915/Express0001.lzma 2024-12-18T18:03:15.477Z,1734544995.477 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0001.lzma.bak 2024-12-18T18:03:15.477Z,1734544995.477 [DataOverHttps](INFO): SBD MOMSN=23822707 2024-12-18T18:03:16.600Z,1734544996.600 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T18:03:16.601Z,1734544996.601 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T18:03:16.601Z,1734544996.601 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T18:04:17.176Z,1734545057.176 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:04:48.308Z,1734545088.308 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:05:19.452Z,1734545119.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:05:50.576Z,1734545150.576 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:06:21.704Z,1734545181.704 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:06:24.716Z,1734545184.716 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.763443 2024-12-18T18:07:22.960Z,1734545242.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:07:32.344Z,1734545252.344 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003235 2024-12-18T18:08:17.090Z,1734545297.090 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T18:08:17.090Z,1734545297.090 [Default:CheckIn:C.Wait] Stopped 2024-12-18T18:08:17.090Z,1734545297.090 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T18:08:17.090Z,1734545297.090 [Default:CheckIn:D] Running Loop=1 2024-12-18T18:08:17.496Z,1734545297.496 [Default:CheckIn:D] Stopped 2024-12-18T18:08:17.496Z,1734545297.496 [Default:CheckIn:E] Running Loop=1 2024-12-18T18:08:17.900Z,1734545297.900 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.841541 min 2024-12-18T18:08:17.901Z,1734545297.901 [Default:CheckIn:E] Stopped 2024-12-18T18:08:17.901Z,1734545297.901 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T18:08:17.901Z,1734545297.901 [Default:CheckIn] Stopped 2024-12-18T18:08:17.901Z,1734545297.901 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T18:08:17.901Z,1734545297.901 [Default:CheckIn](INFO): Running loop #4 2024-12-18T18:08:17.901Z,1734545297.901 [Default:CheckIn] Running Loop=4 2024-12-18T18:08:17.901Z,1734545297.901 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T18:08:17.901Z,1734545297.901 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T18:08:19.911Z,1734545299.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180819.00,A,3648.12910,N,12149.47701,W,26.786,80.80,181224,,,D*79 2024-12-18T18:08:19.921Z,1734545299.921 [NAL9602](INFO): GPS fix at 20241218T180819: (36.802152, -121.824617) 2024-12-18T18:08:19.932Z,1734545299.932 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T18:08:19.932Z,1734545299.932 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T18:08:38.546Z,1734545318.546 [NAL9602](INFO): SBD MO Status=2, MOMSN=61234, MT Status=2, MTMSN=0 2024-12-18T18:08:38.546Z,1734545318.546 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-18T18:08:50.666Z,1734545330.666 [NAL9602](INFO): SBD MO Status=1, MOMSN=61234, MT Status=0, MTMSN=0 2024-12-18T18:08:50.666Z,1734545330.666 [NAL9602](INFO): No messages in MT queue 2024-12-18T18:09:21.368Z,1734545361.368 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:09:54.116Z,1734545394.116 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:10:25.248Z,1734545425.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:10:56.376Z,1734545456.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:11:01.396Z,1734545461.396 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.175430 2024-12-18T18:11:12.918Z,1734545472.918 [NAL9602](INFO): SBD MO Status=1, MOMSN=61235, MT Status=0, MTMSN=0 2024-12-18T18:11:12.968Z,1734545472.968 [NAL9602](INFO): Sent 74 bytes from file Logs/20241218T175915/Courier0003.lzma 2024-12-18T18:11:12.968Z,1734545472.968 [NAL9602](INFO): Packets left to send: 0 2024-12-18T18:11:22.297Z,1734545482.297 [NAL9602](INFO): SBD MO Status=1, MOMSN=61236, MT Status=0, MTMSN=0 2024-12-18T18:11:22.348Z,1734545482.348 [NAL9602](INFO): Sent 146 bytes from file Logs/20241218T175915/Express0005.lzma 2024-12-18T18:11:22.348Z,1734545482.348 [NAL9602](INFO): Packets left to send: 0 2024-12-18T18:11:31.162Z,1734545491.162 [NAL9602](INFO): SBD MO Status=0, MOMSN=61237, MT Status=0, MTMSN=0 2024-12-18T18:11:31.233Z,1734545491.233 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T18:11:31.234Z,1734545491.234 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T18:11:31.234Z,1734545491.234 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T18:11:32.532Z,1734545492.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:12:01.857Z,1734545521.857 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:12:03.656Z,1734545523.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:12:34.780Z,1734545554.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-18T18:12:43.176Z,1734545563.176 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003118 2024-12-18T18:16:31.755Z,1734545791.755 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T18:16:31.755Z,1734545791.755 [Default:CheckIn:C.Wait] Stopped 2024-12-18T18:16:31.755Z,1734545791.755 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T18:16:31.756Z,1734545791.756 [Default:CheckIn:D] Running Loop=1 2024-12-18T18:16:32.164Z,1734545792.164 [Default:CheckIn:D] Stopped 2024-12-18T18:16:32.164Z,1734545792.164 [Default:CheckIn:E] Running Loop=1 2024-12-18T18:16:32.555Z,1734545792.555 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.085994 min 2024-12-18T18:16:32.555Z,1734545792.555 [Default:CheckIn:E] Stopped 2024-12-18T18:16:32.555Z,1734545792.555 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T18:16:32.555Z,1734545792.555 [Default:CheckIn] Stopped 2024-12-18T18:16:32.555Z,1734545792.555 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T18:16:32.555Z,1734545792.555 [Default:CheckIn](INFO): Running loop #5 2024-12-18T18:16:32.555Z,1734545792.555 [Default:CheckIn] Running Loop=5 2024-12-18T18:16:32.556Z,1734545792.556 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T18:16:32.556Z,1734545792.556 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T18:16:34.558Z,1734545794.558 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181633.00,A,3648.34831,N,12147.12698,W,3.402,174.26,181224,,,D*79 2024-12-18T18:16:34.561Z,1734545794.561 [NAL9602](INFO): GPS fix at 20241218T181633: (36.805805, -121.785450) 2024-12-18T18:16:34.572Z,1734545794.572 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T18:16:34.572Z,1734545794.572 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T18:16:42.399Z,1734545802.399 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241218T175915/Courier0009.lzma 2024-12-18T18:16:43.402Z,1734545803.402 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0009.lzma.bak 2024-12-18T18:16:43.402Z,1734545803.402 [DataOverHttps](INFO): SBD MOMSN=23822817 2024-12-18T18:16:58.434Z,1734545818.434 [NAL9602](INFO): SBD MO Status=0, MOMSN=61238, MT Status=0, MTMSN=0 2024-12-18T18:16:58.434Z,1734545818.434 [NAL9602](INFO): No messages in MT queue 2024-12-18T18:17:02.531Z,1734545822.531 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20241218T175915/Express0010.lzma 2024-12-18T18:17:03.533Z,1734545823.533 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0010.lzma.bak 2024-12-18T18:17:03.533Z,1734545823.533 [DataOverHttps](INFO): SBD MOMSN=23822838 2024-12-18T18:17:04.922Z,1734545824.922 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T18:17:04.922Z,1734545824.922 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T18:17:04.923Z,1734545824.923 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T18:17:29.135Z,1734545849.135 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:22:05.499Z,1734546125.499 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T18:22:05.499Z,1734546125.499 [Default:CheckIn:C.Wait] Stopped 2024-12-18T18:22:05.499Z,1734546125.499 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T18:22:05.500Z,1734546125.500 [Default:CheckIn:D] Running Loop=1 2024-12-18T18:22:05.906Z,1734546125.906 [Default:CheckIn:D] Stopped 2024-12-18T18:22:05.906Z,1734546125.906 [Default:CheckIn:E] Running Loop=1 2024-12-18T18:22:06.326Z,1734546126.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.648372 min 2024-12-18T18:22:06.326Z,1734546126.326 [Default:CheckIn:E] Stopped 2024-12-18T18:22:06.326Z,1734546126.326 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T18:22:06.327Z,1734546126.327 [Default:CheckIn] Stopped 2024-12-18T18:22:06.327Z,1734546126.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T18:22:06.327Z,1734546126.327 [Default:CheckIn](INFO): Running loop #6 2024-12-18T18:22:06.327Z,1734546126.327 [Default:CheckIn] Running Loop=6 2024-12-18T18:22:06.327Z,1734546126.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T18:22:06.328Z,1734546126.328 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T18:22:08.311Z,1734546128.311 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182207.00,A,3648.14808,N,12147.22251,W,0.019,206.15,181224,,,D*78 2024-12-18T18:22:08.313Z,1734546128.313 [NAL9602](INFO): GPS fix at 20241218T182207: (36.802468, -121.787042) 2024-12-18T18:22:08.324Z,1734546128.324 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T18:22:08.324Z,1734546128.324 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T18:22:15.975Z,1734546135.975 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241218T175915/Courier0012.lzma 2024-12-18T18:22:16.977Z,1734546136.977 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0012.lzma.bak 2024-12-18T18:22:16.977Z,1734546136.977 [DataOverHttps](INFO): SBD MOMSN=23822853 2024-12-18T18:22:35.492Z,1734546155.492 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20241218T175915/Express0013.lzma 2024-12-18T18:22:36.493Z,1734546156.493 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0013.lzma.bak 2024-12-18T18:22:36.493Z,1734546156.493 [DataOverHttps](INFO): SBD MOMSN=23822860 2024-12-18T18:22:37.875Z,1734546157.875 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T18:22:37.875Z,1734546157.875 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T18:22:37.876Z,1734546157.876 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T18:23:17.450Z,1734546197.450 [NAL9602](INFO): SBD MO Status=0, MOMSN=61239, MT Status=0, MTMSN=0 2024-12-18T18:23:17.450Z,1734546197.450 [NAL9602](INFO): No messages in MT queue 2024-12-18T18:23:48.152Z,1734546228.152 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:27:38.450Z,1734546458.450 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T18:27:38.450Z,1734546458.450 [Default:CheckIn:C.Wait] Stopped 2024-12-18T18:27:38.450Z,1734546458.450 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T18:27:38.450Z,1734546458.450 [Default:CheckIn:D] Running Loop=1 2024-12-18T18:27:38.860Z,1734546458.860 [Default:CheckIn:D] Stopped 2024-12-18T18:27:38.860Z,1734546458.860 [Default:CheckIn:E] Running Loop=1 2024-12-18T18:27:39.275Z,1734546459.275 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.197603 min 2024-12-18T18:27:39.275Z,1734546459.275 [Default:CheckIn:E] Stopped 2024-12-18T18:27:39.275Z,1734546459.275 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T18:27:39.275Z,1734546459.275 [Default:CheckIn] Stopped 2024-12-18T18:27:39.275Z,1734546459.275 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T18:27:39.275Z,1734546459.275 [Default:CheckIn](INFO): Running loop #7 2024-12-18T18:27:39.275Z,1734546459.275 [Default:CheckIn] Running Loop=7 2024-12-18T18:27:39.276Z,1734546459.276 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T18:27:39.276Z,1734546459.276 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T18:27:41.274Z,1734546461.274 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182740.00,A,3648.14374,N,12147.22696,W,1.069,202.21,181224,,,D*74 2024-12-18T18:27:41.277Z,1734546461.277 [NAL9602](INFO): GPS fix at 20241218T182740: (36.802396, -121.787116) 2024-12-18T18:27:41.287Z,1734546461.287 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T18:27:41.288Z,1734546461.288 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T18:27:48.871Z,1734546468.871 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241218T175915/Courier0015.lzma 2024-12-18T18:27:49.873Z,1734546469.873 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0015.lzma.bak 2024-12-18T18:27:49.873Z,1734546469.873 [DataOverHttps](INFO): SBD MOMSN=23822882 2024-12-18T18:28:03.490Z,1734546483.490 [NAL9602](INFO): SBD MO Status=2, MOMSN=61240, MT Status=2, MTMSN=0 2024-12-18T18:28:03.490Z,1734546483.490 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-18T18:28:05.603Z,1734546485.603 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20241218T175915/Express0016.lzma 2024-12-18T18:28:06.679Z,1734546486.679 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0016.lzma.bak 2024-12-18T18:28:06.679Z,1734546486.679 [DataOverHttps](INFO): SBD MOMSN=23822886 2024-12-18T18:28:07.968Z,1734546487.968 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T18:28:07.968Z,1734546487.968 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T18:28:07.968Z,1734546487.968 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T18:28:22.074Z,1734546502.074 [NAL9602](INFO): SBD MO Status=0, MOMSN=61240, MT Status=0, MTMSN=0 2024-12-18T18:28:22.074Z,1734546502.074 [NAL9602](INFO): No messages in MT queue 2024-12-18T18:28:52.780Z,1734546532.780 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:33:08.530Z,1734546788.530 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T18:33:08.530Z,1734546788.530 [Default:CheckIn:C.Wait] Stopped 2024-12-18T18:33:08.530Z,1734546788.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T18:33:08.530Z,1734546788.530 [Default:CheckIn:D] Running Loop=1 2024-12-18T18:33:08.938Z,1734546788.938 [Default:CheckIn:D] Stopped 2024-12-18T18:33:08.939Z,1734546788.939 [Default:CheckIn:E] Running Loop=1 2024-12-18T18:33:09.350Z,1734546789.350 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.698910 min 2024-12-18T18:33:09.350Z,1734546789.350 [Default:CheckIn:E] Stopped 2024-12-18T18:33:09.351Z,1734546789.351 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T18:33:09.351Z,1734546789.351 [Default:CheckIn] Stopped 2024-12-18T18:33:09.351Z,1734546789.351 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T18:33:09.351Z,1734546789.351 [Default:CheckIn](INFO): Running loop #8 2024-12-18T18:33:09.351Z,1734546789.351 [Default:CheckIn] Running Loop=8 2024-12-18T18:33:09.351Z,1734546789.351 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T18:33:09.351Z,1734546789.351 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T18:33:11.350Z,1734546791.350 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183310.00,A,3648.17233,N,12147.28103,W,0.039,263.81,181224,,,D*7D 2024-12-18T18:33:11.352Z,1734546791.352 [NAL9602](INFO): GPS fix at 20241218T183310: (36.802872, -121.788017) 2024-12-18T18:33:11.368Z,1734546791.368 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T18:33:11.368Z,1734546791.368 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T18:33:18.487Z,1734546798.487 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241218T175915/Courier0018.lzma 2024-12-18T18:33:19.489Z,1734546799.489 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0018.lzma.bak 2024-12-18T18:33:19.489Z,1734546799.489 [DataOverHttps](INFO): SBD MOMSN=23822907 2024-12-18T18:33:34.788Z,1734546814.788 [NAL9602](INFO): SBD MO Status=2, MOMSN=61241, MT Status=2, MTMSN=0 2024-12-18T18:33:34.788Z,1734546814.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-18T18:33:35.216Z,1734546815.216 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20241218T175915/Express0019.lzma 2024-12-18T18:33:36.217Z,1734546816.217 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0019.lzma.bak 2024-12-18T18:33:36.217Z,1734546816.217 [DataOverHttps](INFO): SBD MOMSN=23822910 2024-12-18T18:33:37.627Z,1734546817.627 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T18:33:37.627Z,1734546817.627 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T18:33:37.627Z,1734546817.627 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T18:33:58.218Z,1734546838.218 [NAL9602](INFO): SBD MO Status=0, MOMSN=61241, MT Status=0, MTMSN=0 2024-12-18T18:33:58.218Z,1734546838.218 [NAL9602](INFO): No messages in MT queue 2024-12-18T18:34:28.925Z,1734546868.925 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:38:38.202Z,1734547118.202 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T18:38:38.202Z,1734547118.202 [Default:CheckIn:C.Wait] Stopped 2024-12-18T18:38:38.202Z,1734547118.202 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T18:38:38.202Z,1734547118.202 [Default:CheckIn:D] Running Loop=1 2024-12-18T18:38:38.612Z,1734547118.612 [Default:CheckIn:D] Stopped 2024-12-18T18:38:38.612Z,1734547118.612 [Default:CheckIn:E] Running Loop=1 2024-12-18T18:38:39.016Z,1734547119.016 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.193473 min 2024-12-18T18:38:39.017Z,1734547119.017 [Default:CheckIn:E] Stopped 2024-12-18T18:38:39.018Z,1734547119.018 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T18:38:39.018Z,1734547119.018 [Default:CheckIn] Stopped 2024-12-18T18:38:39.019Z,1734547119.019 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T18:38:39.020Z,1734547119.020 [Default:CheckIn](INFO): Running loop #9 2024-12-18T18:38:39.020Z,1734547119.020 [Default:CheckIn] Running Loop=9 2024-12-18T18:38:39.020Z,1734547119.020 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T18:38:39.020Z,1734547119.020 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T18:38:41.032Z,1734547121.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183840.00,A,3648.17621,N,12147.27660,W,0.097,263.81,181224,,,D*7D 2024-12-18T18:38:41.034Z,1734547121.034 [NAL9602](INFO): GPS fix at 20241218T183840: (36.802937, -121.787943) 2024-12-18T18:38:41.053Z,1734547121.053 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T18:38:41.053Z,1734547121.053 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T18:38:48.259Z,1734547128.259 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241218T175915/Courier0021.lzma 2024-12-18T18:38:49.261Z,1734547129.261 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0021.lzma.bak 2024-12-18T18:38:49.262Z,1734547129.262 [DataOverHttps](INFO): SBD MOMSN=23822964 2024-12-18T18:39:07.835Z,1734547147.835 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241218T175915/Express0022.lzma 2024-12-18T18:39:08.837Z,1734547148.837 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0022.lzma.bak 2024-12-18T18:39:08.837Z,1734547148.837 [DataOverHttps](INFO): SBD MOMSN=23822967 2024-12-18T18:39:10.170Z,1734547150.170 [NAL9602](INFO): SBD MO Status=0, MOMSN=61242, MT Status=0, MTMSN=0 2024-12-18T18:39:10.170Z,1734547150.170 [NAL9602](INFO): No messages in MT queue 2024-12-18T18:39:10.191Z,1734547150.191 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T18:39:10.191Z,1734547150.191 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T18:39:10.192Z,1734547150.192 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T18:39:40.873Z,1734547180.873 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:44:10.766Z,1734547450.766 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T18:44:10.766Z,1734547450.766 [Default:CheckIn:C.Wait] Stopped 2024-12-18T18:44:10.766Z,1734547450.766 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T18:44:10.767Z,1734547450.767 [Default:CheckIn:D] Running Loop=1 2024-12-18T18:44:11.184Z,1734547451.184 [Default:CheckIn:D] Stopped 2024-12-18T18:44:11.184Z,1734547451.184 [Default:CheckIn:E] Running Loop=1 2024-12-18T18:44:11.575Z,1734547451.575 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.736332 min 2024-12-18T18:44:11.575Z,1734547451.575 [Default:CheckIn:E] Stopped 2024-12-18T18:44:11.575Z,1734547451.575 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T18:44:11.575Z,1734547451.575 [Default:CheckIn] Stopped 2024-12-18T18:44:11.584Z,1734547451.584 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T18:44:11.584Z,1734547451.584 [Default:CheckIn](INFO): Running loop #10 2024-12-18T18:44:11.584Z,1734547451.584 [Default:CheckIn] Running Loop=10 2024-12-18T18:44:11.584Z,1734547451.584 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T18:44:11.584Z,1734547451.584 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T18:44:13.586Z,1734547453.586 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184412.00,A,3648.17677,N,12147.27539,W,0.019,263.81,181224,,,D*7B 2024-12-18T18:44:13.588Z,1734547453.588 [NAL9602](INFO): GPS fix at 20241218T184412: (36.802946, -121.787923) 2024-12-18T18:44:13.599Z,1734547453.599 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T18:44:13.600Z,1734547453.600 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T18:44:22.167Z,1734547462.167 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241218T175915/Courier0024.lzma 2024-12-18T18:44:22.502Z,1734547462.502 [NAL9602](INFO): SBD MO Status=0, MOMSN=61243, MT Status=0, MTMSN=0 2024-12-18T18:44:22.502Z,1734547462.502 [NAL9602](INFO): No messages in MT queue 2024-12-18T18:44:23.169Z,1734547463.169 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0024.lzma.bak 2024-12-18T18:44:23.170Z,1734547463.170 [DataOverHttps](INFO): SBD MOMSN=23822980 2024-12-18T18:44:40.259Z,1734547480.259 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20241218T175915/Express0025.lzma 2024-12-18T18:44:41.262Z,1734547481.262 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0025.lzma.bak 2024-12-18T18:44:41.262Z,1734547481.262 [DataOverHttps](INFO): SBD MOMSN=23822983 2024-12-18T18:44:42.741Z,1734547482.741 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T18:44:42.745Z,1734547482.745 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T18:44:42.756Z,1734547482.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T18:44:45.569Z,1734547485.569 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-18T18:44:51.630Z,1734547491.630 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-18T18:44:53.225Z,1734547493.225 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:47:17.130Z,1734547637.130 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:47:31.674Z,1734547651.674 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:47:45.006Z,1734547665.006 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:47:58.338Z,1734547678.338 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:48:11.670Z,1734547691.670 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:48:25.070Z,1734547705.070 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:48:38.402Z,1734547718.402 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:48:51.352Z,1734547731.352 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:49:04.711Z,1734547744.711 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:49:18.038Z,1734547758.038 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:49:31.370Z,1734547771.370 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:49:43.518Z,1734547783.518 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T18:49:43.518Z,1734547783.518 [Default:CheckIn:C.Wait] Stopped 2024-12-18T18:49:43.518Z,1734547783.518 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T18:49:43.519Z,1734547783.519 [Default:CheckIn:D] Running Loop=1 2024-12-18T18:49:43.961Z,1734547783.961 [Default:CheckIn:D] Stopped 2024-12-18T18:49:43.961Z,1734547783.961 [Default:CheckIn:E] Running Loop=1 2024-12-18T18:49:44.323Z,1734547784.323 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.282617 min 2024-12-18T18:49:44.323Z,1734547784.323 [Default:CheckIn:E] Stopped 2024-12-18T18:49:44.323Z,1734547784.323 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T18:49:44.323Z,1734547784.323 [Default:CheckIn] Stopped 2024-12-18T18:49:44.323Z,1734547784.323 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T18:49:44.323Z,1734547784.323 [Default:CheckIn](INFO): Running loop #11 2024-12-18T18:49:44.323Z,1734547784.323 [Default:CheckIn] Running Loop=11 2024-12-18T18:49:44.324Z,1734547784.324 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T18:49:44.324Z,1734547784.324 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T18:49:46.327Z,1734547786.327 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184945.00,A,3648.17527,N,12147.28023,W,0.058,263.81,181224,,,D*76 2024-12-18T18:49:46.329Z,1734547786.329 [NAL9602](INFO): GPS fix at 20241218T184945: (36.802921, -121.788004) 2024-12-18T18:49:46.341Z,1734547786.341 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T18:49:46.341Z,1734547786.341 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T18:49:53.883Z,1734547793.883 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241218T175915/Courier0027.lzma 2024-12-18T18:49:54.885Z,1734547794.885 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0027.lzma.bak 2024-12-18T18:49:54.885Z,1734547794.885 [DataOverHttps](INFO): SBD MOMSN=23823001 2024-12-18T18:50:05.715Z,1734547805.715 [NAL9602](INFO): SBD MO Status=0, MOMSN=61244, MT Status=0, MTMSN=0 2024-12-18T18:50:05.715Z,1734547805.715 [NAL9602](INFO): No messages in MT queue 2024-12-18T18:50:10.707Z,1734547810.707 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241218T175915/Express0028.lzma 2024-12-18T18:50:11.709Z,1734547811.709 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0028.lzma.bak 2024-12-18T18:50:11.709Z,1734547811.709 [DataOverHttps](INFO): SBD MOMSN=23823004 2024-12-18T18:50:13.000Z,1734547813.000 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T18:50:13.000Z,1734547813.000 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T18:50:13.000Z,1734547813.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T18:50:36.432Z,1734547836.432 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:50:45.315Z,1734547845.315 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-12-18T18:52:53.126Z,1734547973.126 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-12-18T18:55:13.451Z,1734548113.451 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T18:55:13.451Z,1734548113.451 [Default:CheckIn:C.Wait] Stopped 2024-12-18T18:55:13.451Z,1734548113.451 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T18:55:13.452Z,1734548113.452 [Default:CheckIn:D] Running Loop=1 2024-12-18T18:55:13.851Z,1734548113.851 [Default:CheckIn:D] Stopped 2024-12-18T18:55:13.851Z,1734548113.851 [Default:CheckIn:E] Running Loop=1 2024-12-18T18:55:14.251Z,1734548114.251 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.780782 min 2024-12-18T18:55:14.251Z,1734548114.251 [Default:CheckIn:E] Stopped 2024-12-18T18:55:14.251Z,1734548114.251 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T18:55:14.251Z,1734548114.251 [Default:CheckIn] Stopped 2024-12-18T18:55:14.251Z,1734548114.251 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T18:55:14.251Z,1734548114.251 [Default:CheckIn](INFO): Running loop #12 2024-12-18T18:55:14.251Z,1734548114.251 [Default:CheckIn] Running Loop=12 2024-12-18T18:55:14.251Z,1734548114.251 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T18:55:14.251Z,1734548114.251 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T18:55:16.255Z,1734548116.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185515.00,A,3648.17307,N,12147.27989,W,0.058,263.81,181224,,,D*7C 2024-12-18T18:55:16.257Z,1734548116.257 [NAL9602](INFO): GPS fix at 20241218T185515: (36.802884, -121.787998) 2024-12-18T18:55:16.268Z,1734548116.268 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T18:55:16.268Z,1734548116.268 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T18:55:24.387Z,1734548124.387 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241218T175915/Courier0030.lzma 2024-12-18T18:55:25.389Z,1734548125.389 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0030.lzma.bak 2024-12-18T18:55:25.389Z,1734548125.389 [DataOverHttps](INFO): SBD MOMSN=23823016 2024-12-18T18:55:41.263Z,1734548141.263 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241218T175915/Express0031.lzma 2024-12-18T18:55:42.265Z,1734548142.265 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0031.lzma.bak 2024-12-18T18:55:42.265Z,1734548142.265 [DataOverHttps](INFO): SBD MOMSN=23823019 2024-12-18T18:55:43.743Z,1734548143.743 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T18:55:43.743Z,1734548143.743 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T18:55:43.743Z,1734548143.743 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T18:56:41.137Z,1734548201.137 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-12-18T18:56:59.506Z,1734548219.506 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:57:13.242Z,1734548233.242 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:57:27.382Z,1734548247.382 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:57:40.714Z,1734548260.714 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:57:53.646Z,1734548273.646 [NAL9602](INFO): SBD MO Status=2, MOMSN=61245, MT Status=2, MTMSN=0 2024-12-18T18:57:53.646Z,1734548273.646 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-18T18:57:54.450Z,1734548274.450 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:58:00.518Z,1734548280.518 [NAL9602](INFO): SBD MO Status=0, MOMSN=61245, MT Status=0, MTMSN=0 2024-12-18T18:58:00.518Z,1734548280.518 [NAL9602](INFO): No messages in MT queue 2024-12-18T18:58:08.590Z,1734548288.590 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:58:22.378Z,1734548302.378 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:58:31.288Z,1734548311.288 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T18:58:36.530Z,1734548316.530 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:58:49.878Z,1734548329.878 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T18:58:55.675Z,1734548335.675 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-12-18T18:59:03.653Z,1734548343.653 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:00:44.336Z,1734548444.336 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T19:00:44.336Z,1734548444.336 [Default:CheckIn:C.Wait] Stopped 2024-12-18T19:00:44.336Z,1734548444.336 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T19:00:44.337Z,1734548444.337 [Default:CheckIn:D] Running Loop=1 2024-12-18T19:00:44.735Z,1734548444.735 [Default:CheckIn:D] Stopped 2024-12-18T19:00:44.735Z,1734548444.735 [Default:CheckIn:E] Running Loop=1 2024-12-18T19:00:45.130Z,1734548445.130 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.295516 min 2024-12-18T19:00:45.130Z,1734548445.130 [Default:CheckIn:E] Stopped 2024-12-18T19:00:45.130Z,1734548445.130 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T19:00:45.131Z,1734548445.131 [Default:CheckIn] Stopped 2024-12-18T19:00:45.131Z,1734548445.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T19:00:45.131Z,1734548445.131 [Default:CheckIn](INFO): Running loop #13 2024-12-18T19:00:45.131Z,1734548445.131 [Default:CheckIn] Running Loop=13 2024-12-18T19:00:45.131Z,1734548445.131 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T19:00:45.147Z,1734548445.147 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T19:00:47.146Z,1734548447.146 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190046.00,A,3648.17332,N,12147.27900,W,0.058,263.81,181224,,,D*7C 2024-12-18T19:00:47.148Z,1734548447.148 [NAL9602](INFO): GPS fix at 20241218T190046: (36.802889, -121.787983) 2024-12-18T19:00:47.159Z,1734548447.159 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T19:00:47.188Z,1734548447.188 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T19:00:57.916Z,1734548457.916 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20241218T175915/Courier0033.lzma 2024-12-18T19:00:58.917Z,1734548458.917 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0033.lzma.bak 2024-12-18T19:00:58.918Z,1734548458.918 [DataOverHttps](INFO): SBD MOMSN=23823069 2024-12-18T19:01:09.470Z,1734548469.470 [NAL9602](INFO): SBD MO Status=0, MOMSN=61246, MT Status=0, MTMSN=0 2024-12-18T19:01:09.470Z,1734548469.470 [NAL9602](INFO): No messages in MT queue 2024-12-18T19:01:14.839Z,1734548474.839 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20241218T175915/Express0034.lzma 2024-12-18T19:01:15.841Z,1734548475.841 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0034.lzma.bak 2024-12-18T19:01:15.841Z,1734548475.841 [DataOverHttps](INFO): SBD MOMSN=23823074 2024-12-18T19:01:17.168Z,1734548477.168 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T19:01:17.168Z,1734548477.168 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T19:01:17.168Z,1734548477.168 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T19:01:40.181Z,1734548500.181 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T19:06:17.952Z,1734548777.952 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T19:06:17.952Z,1734548777.952 [Default:CheckIn:C.Wait] Stopped 2024-12-18T19:06:17.952Z,1734548777.952 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T19:06:17.953Z,1734548777.953 [Default:CheckIn:D] Running Loop=1 2024-12-18T19:06:18.369Z,1734548778.369 [Default:CheckIn:D] Stopped 2024-12-18T19:06:18.369Z,1734548778.369 [Default:CheckIn:E] Running Loop=1 2024-12-18T19:06:18.749Z,1734548778.749 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.856079 min 2024-12-18T19:06:18.749Z,1734548778.749 [Default:CheckIn:E] Stopped 2024-12-18T19:06:18.749Z,1734548778.749 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T19:06:18.749Z,1734548778.749 [Default:CheckIn] Stopped 2024-12-18T19:06:18.749Z,1734548778.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T19:06:18.749Z,1734548778.749 [Default:CheckIn](INFO): Running loop #14 2024-12-18T19:06:18.750Z,1734548778.750 [Default:CheckIn] Running Loop=14 2024-12-18T19:06:18.750Z,1734548778.750 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T19:06:18.750Z,1734548778.750 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T19:06:20.771Z,1734548780.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190620.00,A,3648.17143,N,12147.28025,W,0.117,321.42,181224,,,D*7D 2024-12-18T19:06:20.773Z,1734548780.773 [NAL9602](INFO): GPS fix at 20241218T190620: (36.802857, -121.788004) 2024-12-18T19:06:20.792Z,1734548780.792 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T19:06:20.792Z,1734548780.792 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T19:06:28.474Z,1734548788.474 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:06:31.043Z,1734548791.043 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241218T175915/Courier0036.lzma 2024-12-18T19:06:32.045Z,1734548792.045 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0036.lzma.bak 2024-12-18T19:06:32.045Z,1734548792.045 [DataOverHttps](INFO): SBD MOMSN=23823114 2024-12-18T19:06:41.798Z,1734548801.798 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:06:47.863Z,1734548807.863 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20241218T175915/Express0037.lzma 2024-12-18T19:06:48.865Z,1734548808.865 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0037.lzma.bak 2024-12-18T19:06:48.866Z,1734548808.866 [DataOverHttps](INFO): SBD MOMSN=23823117 2024-12-18T19:06:50.309Z,1734548810.309 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T19:06:50.309Z,1734548810.309 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T19:06:50.309Z,1734548810.309 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T19:06:55.210Z,1734548815.210 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:06:55.641Z,1734548815.641 [NAL9602](INFO): SBD MO Status=0, MOMSN=61247, MT Status=0, MTMSN=0 2024-12-18T19:06:55.641Z,1734548815.641 [NAL9602](INFO): No messages in MT queue 2024-12-18T19:07:08.570Z,1734548828.570 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:07:21.902Z,1734548841.902 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:07:26.358Z,1734548846.358 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T19:07:35.236Z,1734548855.236 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:07:48.573Z,1734548868.573 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:08:01.899Z,1734548881.899 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:08:16.443Z,1734548896.443 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:08:30.196Z,1734548910.196 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:08:35.851Z,1734548915.851 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-12-18T19:08:35.851Z,1734548915.851 [DropWeight] Hardware Fault, FailCount= 1 2024-12-18T19:08:35.851Z,1734548915.851 [DropWeight](ERROR): Hardware Fault 2024-12-18T19:08:35.869Z,1734548915.869 [CommandExec](FAULT): Scheduling is paused 2024-12-18T19:08:35.870Z,1734548915.870 [CBIT](INFO): Critical error at 20241218T190835 2024-12-18T19:08:35.900Z,1734548915.900 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-12-18T19:08:35.901Z,1734548915.901 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-12-18T19:08:36.279Z,1734548916.279 [CBIT](INFO): Critical error at 20241218T190835 2024-12-18T19:08:36.689Z,1734548916.689 [CBIT](IMPORTANT): Beginning ground fault scan 2024-12-18T19:08:39.517Z,1734548919.517 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2024-12-18T19:08:43.526Z,1734548923.526 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:08:47.641Z,1734548927.641 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002137 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.002006 CHAN A3 (5V): -0.001274 CHAN B0 (3.3V): -0.000271 CHAN B1 (3.15aV): -0.000173 CHAN B2 (3.15bV): -0.000255 CHAN B3 (GND): 0.000156 OPEN: -0.000485 Full Scale: +/- 1 mA 2024-12-18T19:11:51.002Z,1734549111.002 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T19:11:51.002Z,1734549111.002 [Default:CheckIn:C.Wait] Stopped 2024-12-18T19:11:51.002Z,1734549111.002 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T19:11:51.003Z,1734549111.003 [Default:CheckIn:D] Running Loop=1 2024-12-18T19:11:51.411Z,1734549111.411 [Default:CheckIn:D] Stopped 2024-12-18T19:11:51.411Z,1734549111.411 [Default:CheckIn:E] Running Loop=1 2024-12-18T19:11:51.826Z,1734549111.826 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.406787 min 2024-12-18T19:11:51.826Z,1734549111.826 [Default:CheckIn:E] Stopped 2024-12-18T19:11:51.826Z,1734549111.826 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T19:11:51.826Z,1734549111.826 [Default:CheckIn] Stopped 2024-12-18T19:11:51.826Z,1734549111.826 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T19:11:51.826Z,1734549111.826 [Default:CheckIn](INFO): Running loop #15 2024-12-18T19:11:51.827Z,1734549111.827 [Default:CheckIn] Running Loop=15 2024-12-18T19:11:51.827Z,1734549111.827 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T19:11:51.827Z,1734549111.827 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T19:11:53.823Z,1734549113.823 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191153.00,A,3648.17451,N,12147.28056,W,0.019,23.01,181224,,,D*44 2024-12-18T19:11:53.825Z,1734549113.825 [NAL9602](INFO): GPS fix at 20241218T191153: (36.802909, -121.788009) 2024-12-18T19:11:53.836Z,1734549113.836 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T19:11:53.836Z,1734549113.836 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T19:12:01.027Z,1734549121.027 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20241218T175915/Courier0039.lzma 2024-12-18T19:12:02.029Z,1734549122.029 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0039.lzma.bak 2024-12-18T19:12:02.029Z,1734549122.029 [DataOverHttps](INFO): SBD MOMSN=23823130 2024-12-18T19:12:07.966Z,1734549127.966 [NAL9602](INFO): SBD MO Status=0, MOMSN=61248, MT Status=0, MTMSN=0 2024-12-18T19:12:07.966Z,1734549127.966 [NAL9602](INFO): No messages in MT queue 2024-12-18T19:12:17.824Z,1734549137.824 [DataOverHttps](INFO): Sending 362 bytes from file Logs/20241218T175915/Express0040.lzma 2024-12-18T19:12:18.825Z,1734549138.825 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0040.lzma.bak 2024-12-18T19:12:18.826Z,1734549138.826 [DataOverHttps](INFO): SBD MOMSN=23823135 2024-12-18T19:12:20.098Z,1734549140.098 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T19:12:20.099Z,1734549140.099 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T19:12:20.099Z,1734549140.099 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T19:12:38.728Z,1734549158.728 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T19:13:17.964Z,1734549197.964 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2024-12-18T19:13:17.968Z,1734549197.968 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 36, 37, 50, 51, 54, 55. 2024-12-18T19:17:20.798Z,1734549440.798 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T19:17:20.798Z,1734549440.798 [Default:CheckIn:C.Wait] Stopped 2024-12-18T19:17:20.798Z,1734549440.798 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T19:17:20.798Z,1734549440.798 [Default:CheckIn:D] Running Loop=1 2024-12-18T19:17:21.206Z,1734549441.206 [Default:CheckIn:D] Stopped 2024-12-18T19:17:21.206Z,1734549441.206 [Default:CheckIn:E] Running Loop=1 2024-12-18T19:17:21.610Z,1734549441.610 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.903369 min 2024-12-18T19:17:21.610Z,1734549441.610 [Default:CheckIn:E] Stopped 2024-12-18T19:17:21.610Z,1734549441.610 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T19:17:21.610Z,1734549441.610 [Default:CheckIn] Stopped 2024-12-18T19:17:21.610Z,1734549441.610 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T19:17:21.610Z,1734549441.610 [Default:CheckIn](INFO): Running loop #16 2024-12-18T19:17:21.610Z,1734549441.610 [Default:CheckIn] Running Loop=16 2024-12-18T19:17:21.610Z,1734549441.610 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T19:17:21.610Z,1734549441.610 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T19:17:23.614Z,1734549443.614 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191722.00,A,3648.16693,N,12147.28211,W,0.350,185.21,181224,,,D*79 2024-12-18T19:17:23.616Z,1734549443.616 [NAL9602](INFO): GPS fix at 20241218T191722: (36.802782, -121.788035) 2024-12-18T19:17:23.647Z,1734549443.648 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T19:17:23.648Z,1734549443.648 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T19:17:31.387Z,1734549451.387 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20241218T175915/Courier0042.lzma 2024-12-18T19:17:32.389Z,1734549452.389 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0042.lzma.bak 2024-12-18T19:17:32.389Z,1734549452.389 [DataOverHttps](INFO): SBD MOMSN=23823166 2024-12-18T19:17:48.260Z,1734549468.260 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20241218T175915/Express0043.lzma 2024-12-18T19:17:49.262Z,1734549469.262 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0043.lzma.bak 2024-12-18T19:17:49.262Z,1734549469.262 [DataOverHttps](INFO): SBD MOMSN=23823169 2024-12-18T19:17:50.355Z,1734549470.355 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T19:17:50.355Z,1734549470.355 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T19:17:50.355Z,1734549470.355 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T19:19:03.871Z,1734549543.871 [NAL9602](INFO): SBD MO Status=0, MOMSN=61249, MT Status=0, MTMSN=0 2024-12-18T19:19:03.871Z,1734549543.871 [NAL9602](INFO): No messages in MT queue 2024-12-18T19:19:34.587Z,1734549574.587 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T19:22:50.953Z,1734549770.953 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T19:22:50.953Z,1734549770.953 [Default:CheckIn:C.Wait] Stopped 2024-12-18T19:22:50.953Z,1734549770.953 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T19:22:50.954Z,1734549770.954 [Default:CheckIn:D] Running Loop=1 2024-12-18T19:22:51.335Z,1734549771.335 [Default:CheckIn:D] Stopped 2024-12-18T19:22:51.336Z,1734549771.336 [Default:CheckIn:E] Running Loop=1 2024-12-18T19:22:51.731Z,1734549771.731 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.405387 min 2024-12-18T19:22:51.731Z,1734549771.731 [Default:CheckIn:E] Stopped 2024-12-18T19:22:51.732Z,1734549771.732 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T19:22:51.732Z,1734549771.732 [Default:CheckIn] Stopped 2024-12-18T19:22:51.732Z,1734549771.732 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T19:22:51.732Z,1734549771.732 [Default:CheckIn](INFO): Running loop #17 2024-12-18T19:22:51.732Z,1734549771.732 [Default:CheckIn] Running Loop=17 2024-12-18T19:22:51.732Z,1734549771.732 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T19:22:51.732Z,1734549771.732 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T19:22:53.742Z,1734549773.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192253.00,A,3648.17044,N,12147.27959,W,0.758,19.72,181224,,,D*42 2024-12-18T19:22:53.744Z,1734549773.744 [NAL9602](INFO): GPS fix at 20241218T192253: (36.802841, -121.787993) 2024-12-18T19:22:53.764Z,1734549773.764 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T19:22:53.764Z,1734549773.764 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T19:23:01.239Z,1734549781.239 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241218T175915/Courier0045.lzma 2024-12-18T19:23:02.241Z,1734549782.241 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0045.lzma.bak 2024-12-18T19:23:02.241Z,1734549782.241 [DataOverHttps](INFO): SBD MOMSN=23823181 2024-12-18T19:23:18.019Z,1734549798.019 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241218T175915/Express0046.lzma 2024-12-18T19:23:19.021Z,1734549799.021 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0046.lzma.bak 2024-12-18T19:23:19.021Z,1734549799.021 [DataOverHttps](INFO): SBD MOMSN=23823186 2024-12-18T19:23:20.455Z,1734549800.455 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T19:23:20.455Z,1734549800.455 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T19:23:20.455Z,1734549800.455 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T19:23:36.213Z,1734549816.213 [CBIT](INFO): Clearing failed state for component DropWeight 2024-12-18T19:23:36.213Z,1734549816.213 [DropWeight] No Fault, FailCount= 1 2024-12-18T19:25:46.309Z,1734549946.309 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-18T19:27:56.393Z,1734550076.393 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-12-18T19:28:16.996Z,1734550096.996 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:28:21.049Z,1734550101.049 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T19:28:21.049Z,1734550101.049 [Default:CheckIn:C.Wait] Stopped 2024-12-18T19:28:21.049Z,1734550101.049 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T19:28:21.050Z,1734550101.050 [Default:CheckIn:D] Running Loop=1 2024-12-18T19:28:21.463Z,1734550101.463 [Default:CheckIn:D] Stopped 2024-12-18T19:28:21.463Z,1734550101.463 [Default:CheckIn:E] Running Loop=1 2024-12-18T19:28:21.867Z,1734550101.867 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.907650 min 2024-12-18T19:28:21.880Z,1734550101.880 [Default:CheckIn:E] Stopped 2024-12-18T19:28:21.880Z,1734550101.880 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T19:28:21.880Z,1734550101.880 [Default:CheckIn] Stopped 2024-12-18T19:28:21.880Z,1734550101.880 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T19:28:21.880Z,1734550101.880 [Default:CheckIn](INFO): Running loop #18 2024-12-18T19:28:21.880Z,1734550101.880 [Default:CheckIn] Running Loop=18 2024-12-18T19:28:21.880Z,1734550101.880 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T19:28:21.880Z,1734550101.880 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T19:28:23.863Z,1734550103.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192823.00,A,3648.17285,N,12147.28005,W,0.194,304.69,181224,,,A*79 2024-12-18T19:28:23.865Z,1734550103.865 [NAL9602](INFO): GPS fix at 20241218T192823: (36.802881, -121.788001) 2024-12-18T19:28:23.884Z,1734550103.884 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T19:28:23.884Z,1734550103.884 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T19:28:30.346Z,1734550110.346 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:28:31.551Z,1734550111.551 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241218T175915/Courier0048.lzma 2024-12-18T19:28:32.553Z,1734550112.553 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0048.lzma.bak 2024-12-18T19:28:32.553Z,1734550112.553 [DataOverHttps](INFO): SBD MOMSN=23823199 2024-12-18T19:28:44.082Z,1734550124.082 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:28:48.612Z,1734550128.612 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20241218T175915/Express0049.lzma 2024-12-18T19:28:49.614Z,1734550129.614 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0049.lzma.bak 2024-12-18T19:28:49.614Z,1734550129.614 [DataOverHttps](INFO): SBD MOMSN=23823202 2024-12-18T19:28:50.960Z,1734550130.960 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T19:28:50.960Z,1734550130.960 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T19:28:50.960Z,1734550130.960 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T19:28:56.632Z,1734550136.632 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T19:28:58.252Z,1734550138.252 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:29:11.588Z,1734550151.588 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:29:24.897Z,1734550164.897 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:29:38.233Z,1734550178.233 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:29:51.562Z,1734550191.562 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:30:05.298Z,1734550205.298 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:30:18.630Z,1734550218.630 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:30:32.410Z,1734550232.410 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:33:51.611Z,1734550431.611 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T19:33:51.611Z,1734550431.611 [Default:CheckIn:C.Wait] Stopped 2024-12-18T19:33:51.611Z,1734550431.611 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T19:33:51.611Z,1734550431.611 [Default:CheckIn:D] Running Loop=1 2024-12-18T19:33:52.032Z,1734550432.032 [Default:CheckIn:D] Stopped 2024-12-18T19:33:52.032Z,1734550432.032 [Default:CheckIn:E] Running Loop=1 2024-12-18T19:33:52.484Z,1734550432.484 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.417131 min 2024-12-18T19:33:52.484Z,1734550432.484 [Default:CheckIn:E] Stopped 2024-12-18T19:33:52.484Z,1734550432.484 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T19:33:52.485Z,1734550432.485 [Default:CheckIn] Stopped 2024-12-18T19:33:52.485Z,1734550432.485 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T19:33:52.485Z,1734550432.485 [Default:CheckIn](INFO): Running loop #19 2024-12-18T19:33:52.485Z,1734550432.485 [Default:CheckIn] Running Loop=19 2024-12-18T19:33:52.485Z,1734550432.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T19:33:52.485Z,1734550432.485 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T19:33:54.430Z,1734550434.430 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193353.00,A,3648.16421,N,12147.28325,W,0.467,211.51,181224,,,A*7B 2024-12-18T19:33:54.433Z,1734550434.433 [NAL9602](INFO): GPS fix at 20241218T193353: (36.802737, -121.788054) 2024-12-18T19:33:54.444Z,1734550434.444 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T19:33:54.444Z,1734550434.444 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T19:34:02.167Z,1734550442.167 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241218T175915/Courier0051.lzma 2024-12-18T19:34:03.169Z,1734550443.169 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0051.lzma.bak 2024-12-18T19:34:03.169Z,1734550443.169 [DataOverHttps](INFO): SBD MOMSN=23823212 2024-12-18T19:34:20.303Z,1734550460.303 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20241218T175915/Express0052.lzma 2024-12-18T19:34:21.305Z,1734550461.305 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0052.lzma.bak 2024-12-18T19:34:21.306Z,1734550461.306 [DataOverHttps](INFO): SBD MOMSN=23823217 2024-12-18T19:34:22.729Z,1734550462.729 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T19:34:22.729Z,1734550462.729 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T19:34:22.729Z,1734550462.729 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T19:37:58.450Z,1734550678.450 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:38:10.166Z,1734550690.166 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:38:21.977Z,1734550701.977 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:38:33.598Z,1734550713.598 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:38:45.718Z,1734550725.718 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:38:56.225Z,1734550736.225 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-12-18T19:38:57.838Z,1734550737.838 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:39:09.958Z,1734550749.958 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:39:22.484Z,1734550762.484 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:39:23.304Z,1734550763.304 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T19:39:23.304Z,1734550763.304 [Default:CheckIn:C.Wait] Stopped 2024-12-18T19:39:23.304Z,1734550763.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T19:39:23.304Z,1734550763.304 [Default:CheckIn:D] Running Loop=1 2024-12-18T19:39:23.727Z,1734550763.727 [Default:CheckIn:D] Stopped 2024-12-18T19:39:23.727Z,1734550763.727 [Default:CheckIn:E] Running Loop=1 2024-12-18T19:39:24.125Z,1734550764.125 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.945378 min 2024-12-18T19:39:24.125Z,1734550764.125 [Default:CheckIn:E] Stopped 2024-12-18T19:39:24.125Z,1734550764.125 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T19:39:24.125Z,1734550764.125 [Default:CheckIn] Stopped 2024-12-18T19:39:24.125Z,1734550764.125 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T19:39:24.126Z,1734550764.126 [Default:CheckIn](INFO): Running loop #20 2024-12-18T19:39:24.126Z,1734550764.126 [Default:CheckIn] Running Loop=20 2024-12-18T19:39:24.126Z,1734550764.126 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T19:39:24.126Z,1734550764.126 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T19:39:26.126Z,1734550766.126 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193925.00,A,3648.17084,N,12147.27162,W,1.536,161.47,181224,,,A*73 2024-12-18T19:39:26.128Z,1734550766.128 [NAL9602](INFO): GPS fix at 20241218T193925: (36.802847, -121.787860) 2024-12-18T19:39:26.159Z,1734550766.159 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T19:39:26.160Z,1734550766.160 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T19:39:33.787Z,1734550773.787 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241218T175915/Courier0054.lzma 2024-12-18T19:39:34.603Z,1734550774.603 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:39:34.789Z,1734550774.789 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0054.lzma.bak 2024-12-18T19:39:34.789Z,1734550774.789 [DataOverHttps](INFO): SBD MOMSN=23823262 2024-12-18T19:39:46.722Z,1734550786.722 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:39:50.651Z,1734550790.651 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20241218T175915/Express0055.lzma 2024-12-18T19:39:51.653Z,1734550791.653 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Express0055.lzma.bak 2024-12-18T19:39:51.653Z,1734550791.653 [DataOverHttps](INFO): SBD MOMSN=23823265 2024-12-18T19:39:52.807Z,1734550792.807 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T19:39:52.807Z,1734550792.807 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-18T19:39:52.807Z,1734550792.807 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-18T19:39:58.470Z,1734550798.470 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-18T19:39:58.843Z,1734550798.843 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:40:10.558Z,1734550810.558 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-18T19:44:53.409Z,1734551093.409 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-18T19:44:53.409Z,1734551093.409 [Default:CheckIn:C.Wait] Stopped 2024-12-18T19:44:53.410Z,1734551093.410 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-18T19:44:53.410Z,1734551093.410 [Default:CheckIn:D] Running Loop=1 2024-12-18T19:44:53.791Z,1734551093.791 [Default:CheckIn:D] Stopped 2024-12-18T19:44:53.791Z,1734551093.791 [Default:CheckIn:E] Running Loop=1 2024-12-18T19:44:54.311Z,1734551094.311 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.446387 min 2024-12-18T19:44:54.315Z,1734551094.315 [Default:CheckIn:E] Stopped 2024-12-18T19:44:54.315Z,1734551094.315 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-18T19:44:54.316Z,1734551094.316 [Default:CheckIn] Stopped 2024-12-18T19:44:54.316Z,1734551094.316 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T19:44:54.316Z,1734551094.316 [Default:CheckIn](INFO): Running loop #21 2024-12-18T19:44:54.316Z,1734551094.316 [Default:CheckIn] Running Loop=21 2024-12-18T19:44:54.316Z,1734551094.316 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-18T19:44:54.316Z,1734551094.316 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-18T19:44:54.576Z,1734551094.576 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-12-18T19:44:55.098Z,1734551095.098 [CommandExec](IMPORTANT): got command get 2024-12-18T19:44:55.098Z,1734551095.098 [CommandExec](FAULT): Incomplete syntax. Try: help get 2024-12-18T19:44:56.190Z,1734551096.190 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194455.00,A,3648.17898,N,12147.27095,W,1.613,62.71,181224,,,A*41 2024-12-18T19:44:56.193Z,1734551096.193 [NAL9602](INFO): GPS fix at 20241218T194455: (36.802983, -121.787849) 2024-12-18T19:44:56.209Z,1734551096.209 [Default:CheckIn:Read_GPS] Stopped 2024-12-18T19:44:56.209Z,1734551096.209 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-18T19:45:05.927Z,1734551105.927 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241218T175915/Courier0057.lzma 2024-12-18T19:45:06.930Z,1734551106.930 [DataOverHttps](INFO): Moved sent file to Logs/20241218T175915/Courier0057.lzma.bak 2024-12-18T19:45:06.930Z,1734551106.930 [DataOverHttps](INFO): SBD MOMSN=23823292 2024-12-18T19:45:15.786Z,1734551115.786 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2024-12-18T19:45:15.787Z,1734551115.787 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 98.000000 % 2024-12-18T19:45:19.124Z,1734551119.124 [CommandExec](IMPORTANT): got command quit 2024-12-18T19:45:20.127Z,1734551120.127 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-18T19:45:20.128Z,1734551120.128 [CommandExec](INFO): Uninitializing the command executive. 2024-12-18T19:45:20.128Z,1734551120.128 [CommandExec](INFO): Uninitializing the command scheduler. 2024-12-18T19:45:20.128Z,1734551120.128 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:20.187Z,1734551120.187 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-12-18T19:45:20.187Z,1734551120.187 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-12-18T19:45:20.188Z,1734551120.188 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:20.188Z,1734551120.188 [NavChartDb](INFO): Join timeout helper Thread ID is 9645 2024-12-18T19:45:20.240Z,1734551120.240 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-18T19:45:20.240Z,1734551120.240 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:20.251Z,1734551120.251 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-12-18T19:45:20.251Z,1734551120.251 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:20.252Z,1734551120.252 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9646 2024-12-18T19:45:20.620Z,1734551120.620 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-18T19:45:20.620Z,1734551120.620 [WetLabsBB2FL](INFO): Powering down 2024-12-18T19:45:20.621Z,1734551120.621 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:20.627Z,1734551120.627 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-12-18T19:45:20.627Z,1734551120.627 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:20.628Z,1734551120.628 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9647 2024-12-18T19:45:20.692Z,1734551120.692 [CTD_Seabird](INFO): Powering down 2024-12-18T19:45:20.703Z,1734551120.703 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-18T19:45:20.704Z,1734551120.704 [CTD_Seabird](INFO): Powering down 2024-12-18T19:45:20.716Z,1734551120.716 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:20.731Z,1734551120.731 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-12-18T19:45:20.731Z,1734551120.731 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:20.732Z,1734551120.732 [Radio_Surface](INFO): Join timeout helper Thread ID is 9648 2024-12-18T19:45:21.060Z,1734551121.060 [Radio_Surface](INFO): Powering down 2024-12-18T19:45:21.060Z,1734551121.060 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-18T19:45:21.061Z,1734551121.061 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:21.063Z,1734551121.063 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-12-18T19:45:21.063Z,1734551121.063 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:21.064Z,1734551121.064 [Onboard](INFO): Join timeout helper Thread ID is 9649 2024-12-18T19:45:25.864Z,1734551125.864 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-18T19:45:25.864Z,1734551125.864 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:25.891Z,1734551125.891 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-12-18T19:45:25.891Z,1734551125.891 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:25.891Z,1734551125.891 [DataOverHttps](INFO): Join timeout helper Thread ID is 9650 2024-12-18T19:45:26.504Z,1734551126.504 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-18T19:45:26.507Z,1734551126.507 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:26.508Z,1734551126.508 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-12-18T19:45:26.508Z,1734551126.508 [logger ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:26.509Z,1734551126.509 [logger](INFO): Join timeout helper Thread ID is 9651 2024-12-18T19:45:26.511Z,1734551126.511 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-18T19:45:26.512Z,1734551126.512 [logger ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:26.528Z,1734551126.528 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-12-18T19:45:26.528Z,1734551126.528 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:26.528Z,1734551126.528 [CommandLine](INFO): Join timeout helper Thread ID is 9652 2024-12-18T19:45:26.603Z,1734551126.603 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-18T19:45:26.604Z,1734551126.604 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:26.608Z,1734551126.608 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-12-18T19:45:26.608Z,1734551126.608 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:26.609Z,1734551126.609 [CommandExec](INFO): Join timeout helper Thread ID is 9653 2024-12-18T19:45:26.610Z,1734551126.610 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-12-18T19:45:26.610Z,1734551126.610 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:26.610Z,1734551126.610 [controlThread](INFO): Join timeout helper Thread ID is 9654 2024-12-18T19:45:26.875Z,1734551126.875 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-18T19:45:26.876Z,1734551126.876 [controlThread](DEBUG): Uninitializing ControlThread 2024-12-18T19:45:26.876Z,1734551126.876 [AHRS_M2](INFO): Powering down 2024-12-18T19:45:26.948Z,1734551126.948 [NAL9602](INFO): Powering down 2024-12-18T19:45:26.950Z,1734551126.950 [Sonardyne_Nano](INFO): Powering down 2024-12-18T19:45:27.069Z,1734551127.069 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-12-18T19:45:27.070Z,1734551127.070 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-12-18T19:45:27.070Z,1734551127.070 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-12-18T19:45:27.071Z,1734551127.071 [MissionManager](INFO): Uninitializing Mission Default 2024-12-18T19:45:27.071Z,1734551127.071 [Default] Stopped 2024-12-18T19:45:27.071Z,1734551127.071 [Default](DEBUG): Aggregate::uninitialize Default 2024-12-18T19:45:27.071Z,1734551127.071 [Default:B.GoToSurface] Stopped 2024-12-18T19:45:27.071Z,1734551127.071 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-12-18T19:45:27.071Z,1734551127.071 [Default:CheckIn] Stopped 2024-12-18T19:45:27.071Z,1734551127.071 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-18T19:45:27.071Z,1734551127.071 [Default:CheckIn:Read_Iridium] Stopped 2024-12-18T19:45:27.074Z,1734551127.074 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-12-18T19:45:27.074Z,1734551127.074 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-12-18T19:45:27.075Z,1734551127.075 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-12-18T19:45:27.075Z,1734551127.075 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-12-18T19:45:27.076Z,1734551127.076 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-12-18T19:45:27.076Z,1734551127.076 [BuoyancyServo](INFO): Powering down 2024-12-18T19:45:27.087Z,1734551127.087 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-12-18T19:45:27.088Z,1734551127.088 [ElevatorServo](INFO): Powering down 2024-12-18T19:45:27.088Z,1734551127.088 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-12-18T19:45:27.089Z,1734551127.089 [MassServo](INFO): Powering down 2024-12-18T19:45:27.089Z,1734551127.089 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-12-18T19:45:27.089Z,1734551127.089 [RudderServo](INFO): Powering down 2024-12-18T19:45:27.090Z,1734551127.090 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-12-18T19:45:27.090Z,1734551127.090 [ThrusterHE](INFO): Powering down 2024-12-18T19:45:27.091Z,1734551127.091 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-12-18T19:45:27.092Z,1734551127.092 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-12-18T19:45:27.092Z,1734551127.092 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-12-18T19:45:27.092Z,1734551127.092 [CBIT](DEBUG): Powering off loads. 2024-12-18T19:45:27.103Z,1734551127.103 [CBIT](DEBUG): Disabling WDT. 2024-12-18T19:45:27.115Z,1734551127.115 [CBIT](DEBUG): Opening all GF detection circuits. 2024-12-18T19:45:27.116Z,1734551127.116 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:27.173Z,1734551127.173 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:27.176Z,1734551127.176 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:27.183Z,1734551127.183 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:27.256Z,1734551127.256 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:27.261Z,1734551127.261 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:27.304Z,1734551127.304 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-12-18T19:45:27.408Z,1734551127.408 [logger ThreadHandler](INFO): Thread cancelled.