2021-08-04T22:20:02.223Z,1628115602.223 [DataOverHttps](INFO): Received command:restart logs 2021-08-04T22:20:02.233Z,1628115602.233 [CommandLine](IMPORTANT): got command restart logs 2021-08-04T22:21:00.848Z,1628115660.848 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T22:21:00.848Z,1628115660.848 [Default:CheckIn:C.Wait] Stopped 2021-08-04T22:21:00.848Z,1628115660.848 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T22:21:00.848Z,1628115660.848 [Default:CheckIn:D] Running Loop=1 2021-08-04T22:21:01.222Z,1628115661.222 [Default:CheckIn:D] Stopped 2021-08-04T22:21:01.222Z,1628115661.222 [Default:CheckIn:E] Running Loop=1 2021-08-04T22:21:01.686Z,1628115661.686 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.488866 min 2021-08-04T22:21:01.686Z,1628115661.686 [Default:CheckIn:E] Stopped 2021-08-04T22:21:01.686Z,1628115661.686 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T22:21:01.686Z,1628115661.686 [Default:CheckIn] Stopped 2021-08-04T22:21:01.686Z,1628115661.686 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T22:21:01.687Z,1628115661.687 [Default:CheckIn](INFO): Running loop #2 2021-08-04T22:21:01.687Z,1628115661.687 [Default:CheckIn] Running Loop=2 2021-08-04T22:21:01.687Z,1628115661.687 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T22:21:01.687Z,1628115661.687 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T22:21:03.639Z,1628115663.639 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222104.00,A,4149.69770,N,08324.70207,W,0.311,14.65,040821,,,A*49 2021-08-04T22:21:03.656Z,1628115663.656 [NAL9602](INFO): GPS fix at 20210804T222104: (41.828295, -83.411701) 2021-08-04T22:21:03.671Z,1628115663.671 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T22:21:03.671Z,1628115663.671 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T22:21:11.776Z,1628115671.776 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20210804T214554/Courier0009.lzma 2021-08-04T22:21:12.778Z,1628115672.778 [DataOverHttps](INFO): Moved sent file to Logs/20210804T214554/Courier0009.lzma.bak 2021-08-04T22:21:12.778Z,1628115672.778 [DataOverHttps](INFO): SBD MOMSN=15826267 2021-08-04T22:21:30.004Z,1628115690.004 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20210804T222002/Courier0000.lzma 2021-08-04T22:21:31.006Z,1628115691.006 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0000.lzma.bak 2021-08-04T22:21:31.006Z,1628115691.006 [DataOverHttps](INFO): SBD MOMSN=15826272 2021-08-04T22:21:41.906Z,1628115701.906 [NAL9602](INFO): SBD MO Status=0, MOMSN=41964, MT Status=0, MTMSN=0 2021-08-04T22:21:41.906Z,1628115701.906 [NAL9602](INFO): No messages in MT queue 2021-08-04T22:21:52.744Z,1628115712.744 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210804T222002/Courier0003.lzma 2021-08-04T22:21:53.626Z,1628115713.626 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0003.lzma.bak 2021-08-04T22:21:53.626Z,1628115713.626 [DataOverHttps](INFO): SBD MOMSN=15826285 2021-08-04T22:22:10.864Z,1628115730.864 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20210804T214554/Express0010.lzma 2021-08-04T22:22:11.866Z,1628115731.866 [DataOverHttps](INFO): Moved sent file to Logs/20210804T214554/Express0010.lzma.bak 2021-08-04T22:22:11.866Z,1628115731.866 [DataOverHttps](INFO): SBD MOMSN=15826287 2021-08-04T22:22:12.576Z,1628115732.576 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T22:22:17.035Z,1628115737.035 [DVL_micro](ERROR): only read 3 of 4 data items 2021-08-04T22:22:17.036Z,1628115737.036 [DVL_micro](ERROR): Failed to parse: :BI,-0005,+00128,+00000,A 2021-08-04T22:22:28.164Z,1628115748.164 [DataOverHttps](INFO): Sending 429 bytes from file Logs/20210804T222002/Express0001.lzma 2021-08-04T22:22:29.166Z,1628115749.166 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0001.lzma.bak 2021-08-04T22:22:29.166Z,1628115749.166 [DataOverHttps](INFO): SBD MOMSN=15826290 2021-08-04T22:22:29.957Z,1628115749.957 [DVL_micro](ERROR): only read 3 of 4 data items 2021-08-04T22:22:29.957Z,1628115749.957 [DVL_micro](ERROR): Failed to parse: :RD,+0000.34,+00000.38,+9999.99 2021-08-04T22:22:51.564Z,1628115771.564 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210804T222002/Express0004.lzma 2021-08-04T22:22:52.566Z,1628115772.566 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0004.lzma.bak 2021-08-04T22:22:52.566Z,1628115772.566 [DataOverHttps](INFO): SBD MOMSN=15826294 2021-08-04T22:22:54.267Z,1628115774.267 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T22:22:54.268Z,1628115774.268 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T22:22:54.268Z,1628115774.268 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T22:23:01.745Z,1628115781.745 [DataOverHttps](IMPORTANT): SBD MTMSN=20210804T222302 2021-08-04T22:23:10.091Z,1628115790.091 [DataOverHttps](INFO): Received command:ibit 2021-08-04T22:23:10.120Z,1628115790.120 [CommandLine](IMPORTANT): got command ibit 2021-08-04T22:23:10.455Z,1628115790.455 [IBIT](IMPORTANT): Beginning Initiated BIT 2021-08-04T22:23:10.455Z,1628115790.455 [IBIT](IMPORTANT): Beginning control surface checks. 2021-08-04T22:23:10.458Z,1628115790.458 [CBIT](IMPORTANT): Beginning ground fault scan 2021-08-04T22:23:12.056Z,1628115792.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222312.00,A,4149.69792,N,08324.70326,W,0.058,259.96,040821,,,A*7B 2021-08-04T22:23:12.058Z,1628115792.058 [NAL9602](INFO): GPS fix at 20210804T222312: (41.828299, -83.411721) 2021-08-04T22:23:21.376Z,1628115801.376 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.009762 CHAN A1 (24V): 0.001080 CHAN A2 (12V): -0.006190 CHAN A3 (5V): -0.003387 CHAN B0 (3.3V): -0.002703 CHAN B1 (3.15aV): -0.001723 CHAN B2 (3.15bV): -0.001205 CHAN B3 (GND): -0.005471 OPEN: 0.011821 Full Scale Calc: 4.765 mA, -1.589 mA 2021-08-04T22:23:43.306Z,1628115823.306 [NAL9602](INFO): SBD MO Status=0, MOMSN=41965, MT Status=0, MTMSN=0 2021-08-04T22:23:43.306Z,1628115823.306 [NAL9602](INFO): No messages in MT queue 2021-08-04T22:23:44.455Z,1628115824.455 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222345.00,A,4149.69798,N,08324.70387,W,0.097,259.96,040821,,,A*7B 2021-08-04T22:23:44.458Z,1628115824.458 [NAL9602](INFO): GPS fix at 20210804T222345: (41.828300, -83.411731) 2021-08-04T22:23:47.678Z,1628115827.678 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222348.00,A,4149.69800,N,08324.70370,W,0.253,259.96,040821,,,A*7A 2021-08-04T22:23:47.681Z,1628115827.681 [NAL9602](INFO): GPS fix at 20210804T222348: (41.828300, -83.411728) 2021-08-04T22:23:50.506Z,1628115830.506 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222351.00,A,4149.69805,N,08324.70370,W,0.097,259.96,040821,,,A*7D 2021-08-04T22:23:50.520Z,1628115830.520 [NAL9602](INFO): GPS fix at 20210804T222351: (41.828301, -83.411728) 2021-08-04T22:23:53.738Z,1628115833.738 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222354.00,A,4149.69813,N,08324.70336,W,0.175,259.96,040821,,,A*70 2021-08-04T22:23:53.752Z,1628115833.752 [NAL9602](INFO): GPS fix at 20210804T222354: (41.828302, -83.411723) 2021-08-04T22:23:56.573Z,1628115836.573 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222357.00,A,4149.69828,N,08324.70348,W,0.117,259.96,040821,,,A*76 2021-08-04T22:23:56.576Z,1628115836.576 [NAL9602](INFO): GPS fix at 20210804T222357: (41.828305, -83.411725) 2021-08-04T22:23:56.593Z,1628115836.593 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 4 Latitude: 41.828304 Longitude: -83.411728 2021-08-04T22:23:57.011Z,1628115837.011 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.611000 2021-08-04T22:23:57.011Z,1628115837.011 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2021-08-04T22:23:57.011Z,1628115837.011 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2021-08-04T22:23:57.388Z,1628115837.388 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2021-08-04T22:23:57.388Z,1628115837.388 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2021-08-04T22:23:57.389Z,1628115837.389 [IBIT](IMPORTANT): Pressure:8.000588 PSI 2021-08-04T22:23:57.389Z,1628115837.389 [IBIT](IMPORTANT): Humidity:17.677938 % 2021-08-04T22:23:57.856Z,1628115837.856 [IBIT](IMPORTANT): Vehicle Pitch:-11.493846 degrees 2021-08-04T22:23:57.856Z,1628115837.856 [IBIT](IMPORTANT): Vehicle Roll:8.650454 degrees 2021-08-04T22:23:57.857Z,1628115837.857 [IBIT](IMPORTANT): Vehicle Heading:76.668488 degrees 2021-08-04T22:23:58.241Z,1628115838.241 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2021-08-04T22:23:58.242Z,1628115838.242 [IBIT](IMPORTANT): buoyancyNeutral: 130.000000 cc 2021-08-04T22:23:58.242Z,1628115838.242 [IBIT](IMPORTANT): massDefault: 0.894689 cm 2021-08-04T22:23:58.242Z,1628115838.242 [IBIT](IMPORTANT): stopDepth: 10.000000 m 2021-08-04T22:23:58.243Z,1628115838.243 [IBIT](IMPORTANT): abortDepth: 15.000000 m 2021-08-04T22:23:58.243Z,1628115838.243 [IBIT](IMPORTANT): IBIT PASSED 2021-08-04T22:24:29.289Z,1628115869.289 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T22:25:46.239Z,1628115946.239 [DVL_micro](ERROR): Failed to parse: :SA,-11.81,+08.27,088.0 2021-08-04T22:27:19.973Z,1628116039.973 [DVL_micro](ERROR): Failed to parse: :BI,-00038,+00500,+00090,+00000,A 2021-08-04T22:27:25.241Z,1628116045.241 [DVL_micro](ERROR): only read 3 of 4 data items 2021-08-04T22:27:25.242Z,1628116045.242 [DVL_micro](ERROR): Failed to parse: :BI,+0003,+00086,+00000,A 2021-08-04T22:27:54.819Z,1628116074.819 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T22:27:54.819Z,1628116074.819 [Default:CheckIn:C.Wait] Stopped 2021-08-04T22:27:54.846Z,1628116074.846 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T22:27:54.846Z,1628116074.846 [Default:CheckIn:D] Running Loop=1 2021-08-04T22:27:55.160Z,1628116075.160 [Default:CheckIn:D] Stopped 2021-08-04T22:27:55.160Z,1628116075.160 [Default:CheckIn:E] Running Loop=1 2021-08-04T22:27:55.584Z,1628116075.584 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.387847 min 2021-08-04T22:27:55.584Z,1628116075.584 [Default:CheckIn:E] Stopped 2021-08-04T22:27:55.584Z,1628116075.584 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T22:27:55.584Z,1628116075.584 [Default:CheckIn] Stopped 2021-08-04T22:27:55.584Z,1628116075.584 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T22:27:55.585Z,1628116075.585 [Default:CheckIn](INFO): Running loop #3 2021-08-04T22:27:55.585Z,1628116075.585 [Default:CheckIn] Running Loop=3 2021-08-04T22:27:55.585Z,1628116075.585 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T22:27:55.585Z,1628116075.585 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T22:27:57.592Z,1628116077.592 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222758.00,A,4149.70051,N,08324.70367,W,0.039,259.96,040821,,,A*73 2021-08-04T22:27:57.594Z,1628116077.594 [NAL9602](INFO): GPS fix at 20210804T222758: (41.828342, -83.411728) 2021-08-04T22:27:57.645Z,1628116077.645 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T22:27:57.645Z,1628116077.645 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T22:28:05.363Z,1628116085.363 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210804T222002/Courier0006.lzma 2021-08-04T22:28:06.374Z,1628116086.374 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0006.lzma.bak 2021-08-04T22:28:06.374Z,1628116086.374 [DataOverHttps](INFO): SBD MOMSN=15826330 2021-08-04T22:28:23.574Z,1628116103.574 [DataOverHttps](INFO): Sending 900 bytes from file Logs/20210804T222002/Express0007.lzma 2021-08-04T22:28:24.574Z,1628116104.574 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0007.lzma.bak 2021-08-04T22:28:24.574Z,1628116104.574 [DataOverHttps](INFO): SBD MOMSN=15826373 2021-08-04T22:28:26.124Z,1628116106.124 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T22:28:26.124Z,1628116106.124 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T22:28:26.125Z,1628116106.125 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T22:28:45.143Z,1628116125.143 [NAL9602](INFO): SBD MO Status=2, MOMSN=41966, MT Status=2, MTMSN=0 2021-08-04T22:28:45.143Z,1628116125.143 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-04T22:29:12.579Z,1628116152.579 [NAL9602](INFO): SBD MO Status=2, MOMSN=41966, MT Status=2, MTMSN=0 2021-08-04T22:29:12.579Z,1628116152.579 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-04T22:30:40.363Z,1628116240.363 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-08-04T22:30:40.376Z,1628116240.376 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+25.8,0000.0,1489:WI,+00000,+00000,+00000,+00000,I 2021-08-04T22:30:41.156Z,1628116241.156 [NAL9602](INFO): SBD MO Status=0, MOMSN=41966, MT Status=0, MTMSN=0 2021-08-04T22:30:41.156Z,1628116241.156 [NAL9602](INFO): No messages in MT queue 2021-08-04T22:31:11.898Z,1628116271.898 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T22:32:26.255Z,1628116346.255 [DVL_micro](ERROR): Failed to parse: :W,+00000,+00000,+00000,I 2021-08-04T22:33:26.876Z,1628116406.876 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T22:33:26.876Z,1628116406.876 [Default:CheckIn:C.Wait] Stopped 2021-08-04T22:33:26.876Z,1628116406.876 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T22:33:26.877Z,1628116406.877 [Default:CheckIn:D] Running Loop=1 2021-08-04T22:33:27.261Z,1628116407.261 [Default:CheckIn:D] Stopped 2021-08-04T22:33:27.262Z,1628116407.262 [Default:CheckIn:E] Running Loop=1 2021-08-04T22:33:27.675Z,1628116407.675 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.922864 min 2021-08-04T22:33:27.675Z,1628116407.675 [Default:CheckIn:E] Stopped 2021-08-04T22:33:27.675Z,1628116407.675 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T22:33:27.675Z,1628116407.675 [Default:CheckIn] Stopped 2021-08-04T22:33:27.676Z,1628116407.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T22:33:27.676Z,1628116407.676 [Default:CheckIn](INFO): Running loop #4 2021-08-04T22:33:27.676Z,1628116407.676 [Default:CheckIn] Running Loop=4 2021-08-04T22:33:27.676Z,1628116407.676 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T22:33:27.676Z,1628116407.676 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T22:33:29.691Z,1628116409.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223330.00,A,4149.69567,N,08324.70590,W,0.253,197.99,040821,,,A*7E 2021-08-04T22:33:29.694Z,1628116409.694 [NAL9602](INFO): GPS fix at 20210804T223330: (41.828261, -83.411765) 2021-08-04T22:33:29.703Z,1628116409.703 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T22:33:29.703Z,1628116409.703 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T22:33:38.692Z,1628116418.692 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00 2021-08-04T22:33:40.156Z,1628116420.156 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210804T222002/Courier0011.lzma 2021-08-04T22:33:41.158Z,1628116421.158 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0011.lzma.bak 2021-08-04T22:33:41.158Z,1628116421.158 [DataOverHttps](INFO): SBD MOMSN=15826467 2021-08-04T22:33:42.777Z,1628116422.777 [NAL9602](INFO): SBD MO Status=0, MOMSN=41967, MT Status=0, MTMSN=0 2021-08-04T22:33:42.777Z,1628116422.777 [NAL9602](INFO): No messages in MT queue 2021-08-04T22:34:00.600Z,1628116440.600 [DataOverHttps](INFO): Sending 309 bytes from file Logs/20210804T222002/Express0010.lzma 2021-08-04T22:34:01.713Z,1628116441.713 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0010.lzma.bak 2021-08-04T22:34:01.713Z,1628116441.713 [DataOverHttps](INFO): SBD MOMSN=15826470 2021-08-04T22:34:03.309Z,1628116443.309 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T22:34:03.310Z,1628116443.310 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T22:34:03.310Z,1628116443.310 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T22:34:05.311Z,1628116445.311 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-08-04T22:34:05.311Z,1628116445.311 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+25.9,0000.0,1489.0I,+00000,+00000,+00000,+00000,I 2021-08-04T22:34:13.404Z,1628116453.404 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T22:39:04.117Z,1628116744.117 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T22:39:04.117Z,1628116744.117 [Default:CheckIn:C.Wait] Stopped 2021-08-04T22:39:04.117Z,1628116744.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T22:39:04.118Z,1628116744.118 [Default:CheckIn:D] Running Loop=1 2021-08-04T22:39:04.509Z,1628116744.509 [Default:CheckIn:D] Stopped 2021-08-04T22:39:04.510Z,1628116744.510 [Default:CheckIn:E] Running Loop=1 2021-08-04T22:39:04.914Z,1628116744.914 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.543665 min 2021-08-04T22:39:04.914Z,1628116744.914 [Default:CheckIn:E] Stopped 2021-08-04T22:39:04.914Z,1628116744.914 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T22:39:04.914Z,1628116744.914 [Default:CheckIn] Stopped 2021-08-04T22:39:04.914Z,1628116744.914 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T22:39:04.915Z,1628116744.915 [Default:CheckIn](INFO): Running loop #5 2021-08-04T22:39:04.915Z,1628116744.915 [Default:CheckIn] Running Loop=5 2021-08-04T22:39:04.915Z,1628116744.915 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T22:39:04.915Z,1628116744.915 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T22:39:06.934Z,1628116746.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223907.00,A,4149.69812,N,08324.70759,W,0.078,87.79,040821,,,A*4D 2021-08-04T22:39:06.936Z,1628116746.936 [NAL9602](INFO): GPS fix at 20210804T223907: (41.828302, -83.411793) 2021-08-04T22:39:06.946Z,1628116746.946 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T22:39:06.946Z,1628116746.946 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T22:39:13.425Z,1628116753.425 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210804T222002/Courier0015.lzma 2021-08-04T22:39:14.422Z,1628116754.422 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0015.lzma.bak 2021-08-04T22:39:14.422Z,1628116754.422 [DataOverHttps](INFO): SBD MOMSN=15826507 2021-08-04T22:39:17.036Z,1628116757.036 [NAL9602](INFO): SBD MO Status=0, MOMSN=41968, MT Status=0, MTMSN=0 2021-08-04T22:39:17.036Z,1628116757.036 [NAL9602](INFO): No messages in MT queue 2021-08-04T22:39:31.328Z,1628116771.328 [DataOverHttps](INFO): Sending 324 bytes from file Logs/20210804T222002/Express0016.lzma 2021-08-04T22:39:32.330Z,1628116772.330 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0016.lzma.bak 2021-08-04T22:39:32.330Z,1628116772.330 [DataOverHttps](INFO): SBD MOMSN=15826510 2021-08-04T22:39:34.494Z,1628116774.494 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T22:39:34.494Z,1628116774.494 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T22:39:34.494Z,1628116774.494 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T22:39:47.824Z,1628116787.824 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T22:41:51.130Z,1628116911.130 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2021-08-04T22:44:06.098Z,1628117046.098 [DVL_micro](ERROR): only read 3 of 4 data items 2021-08-04T22:44:06.098Z,1628117046.098 [DVL_micro](ERROR): Failed to parse: :BI,-0334,+00027,+00000,I 2021-08-04T22:44:10.927Z,1628117050.927 [DVL_micro](ERROR): Failed to parse: :000000000,35.0,+26.3,0000.0,1489.0,000 2021-08-04T22:44:16.180Z,1628117056.180 [DVL_micro](ERROR): only read 3 of 4 data items 2021-08-04T22:44:16.180Z,1628117056.180 [DVL_micro](ERROR): Failed to parse: :BI,-00132,+01198,+00010, 2021-08-04T22:44:35.179Z,1628117075.179 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T22:44:35.179Z,1628117075.179 [Default:CheckIn:C.Wait] Stopped 2021-08-04T22:44:35.179Z,1628117075.179 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T22:44:35.179Z,1628117075.179 [Default:CheckIn:D] Running Loop=1 2021-08-04T22:44:35.583Z,1628117075.583 [Default:CheckIn:D] Stopped 2021-08-04T22:44:35.583Z,1628117075.583 [Default:CheckIn:E] Running Loop=1 2021-08-04T22:44:35.000Z,1628117076.000 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.061558 min 2021-08-04T22:44:35.000Z,1628117076.000 [Default:CheckIn:E] Stopped 2021-08-04T22:44:36.000Z,1628117076.000 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T22:44:36.000Z,1628117076.000 [Default:CheckIn] Stopped 2021-08-04T22:44:36.000Z,1628117076.000 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T22:44:36.001Z,1628117076.001 [Default:CheckIn](INFO): Running loop #6 2021-08-04T22:44:36.001Z,1628117076.001 [Default:CheckIn] Running Loop=6 2021-08-04T22:44:36.001Z,1628117076.001 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T22:44:36.001Z,1628117076.001 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T22:44:38.007Z,1628117078.007 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224438.00,A,4149.69796,N,08324.70700,W,0.019,87.79,040821,,,A*43 2021-08-04T22:44:38.010Z,1628117078.010 [NAL9602](INFO): GPS fix at 20210804T224438: (41.828299, -83.411783) 2021-08-04T22:44:38.072Z,1628117078.072 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T22:44:38.072Z,1628117078.072 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T22:44:45.379Z,1628117085.379 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210804T222002/Courier0018.lzma 2021-08-04T22:44:46.382Z,1628117086.382 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0018.lzma.bak 2021-08-04T22:44:46.382Z,1628117086.382 [DataOverHttps](INFO): SBD MOMSN=15826586 2021-08-04T22:45:02.944Z,1628117102.944 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20210804T222002/Express0019.lzma 2021-08-04T22:45:03.946Z,1628117103.946 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0019.lzma.bak 2021-08-04T22:45:03.946Z,1628117103.946 [DataOverHttps](INFO): SBD MOMSN=15826589 2021-08-04T22:45:05.483Z,1628117105.483 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T22:45:05.483Z,1628117105.483 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T22:45:05.483Z,1628117105.483 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T22:45:21.630Z,1628117121.630 [NAL9602](INFO): SBD MO Status=2, MOMSN=41969, MT Status=2, MTMSN=0 2021-08-04T22:45:21.630Z,1628117121.630 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-04T22:45:32.942Z,1628117132.942 [NAL9602](INFO): SBD MO Status=0, MOMSN=41969, MT Status=0, MTMSN=0 2021-08-04T22:45:32.942Z,1628117132.942 [NAL9602](INFO): No messages in MT queue 2021-08-04T22:46:03.706Z,1628117163.706 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T22:50:06.166Z,1628117406.166 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T22:50:06.166Z,1628117406.166 [Default:CheckIn:C.Wait] Stopped 2021-08-04T22:50:06.166Z,1628117406.166 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T22:50:06.166Z,1628117406.166 [Default:CheckIn:D] Running Loop=1 2021-08-04T22:50:06.567Z,1628117406.567 [Default:CheckIn:D] Stopped 2021-08-04T22:50:06.567Z,1628117406.567 [Default:CheckIn:E] Running Loop=1 2021-08-04T22:50:06.973Z,1628117406.973 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.577950 min 2021-08-04T22:50:06.973Z,1628117406.973 [Default:CheckIn:E] Stopped 2021-08-04T22:50:06.973Z,1628117406.973 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T22:50:06.973Z,1628117406.973 [Default:CheckIn] Stopped 2021-08-04T22:50:06.974Z,1628117406.974 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T22:50:06.974Z,1628117406.974 [Default:CheckIn](INFO): Running loop #7 2021-08-04T22:50:06.974Z,1628117406.974 [Default:CheckIn] Running Loop=7 2021-08-04T22:50:06.974Z,1628117406.974 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T22:50:06.974Z,1628117406.974 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T22:50:08.982Z,1628117408.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225009.00,A,4149.69778,N,08324.70589,W,0.039,87.79,040821,,,D*40 2021-08-04T22:50:08.985Z,1628117408.985 [NAL9602](INFO): GPS fix at 20210804T225009: (41.828296, -83.411765) 2021-08-04T22:50:08.994Z,1628117408.994 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T22:50:08.994Z,1628117408.994 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T22:50:17.311Z,1628117417.311 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210804T222002/Courier0021.lzma 2021-08-04T22:50:18.314Z,1628117418.314 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0021.lzma.bak 2021-08-04T22:50:18.314Z,1628117418.314 [DataOverHttps](INFO): SBD MOMSN=15826629 2021-08-04T22:50:21.913Z,1628117421.913 [NAL9602](INFO): SBD MO Status=0, MOMSN=41970, MT Status=0, MTMSN=0 2021-08-04T22:50:21.913Z,1628117421.913 [NAL9602](INFO): No messages in MT queue 2021-08-04T22:50:35.792Z,1628117435.792 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210804T222002/Express0022.lzma 2021-08-04T22:50:36.794Z,1628117436.794 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0022.lzma.bak 2021-08-04T22:50:36.794Z,1628117436.794 [DataOverHttps](INFO): SBD MOMSN=15826632 2021-08-04T22:50:38.493Z,1628117438.493 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T22:50:38.493Z,1628117438.493 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T22:50:38.494Z,1628117438.494 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T22:50:52.613Z,1628117452.613 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T22:55:39.153Z,1628117739.153 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T22:55:39.153Z,1628117739.153 [Default:CheckIn:C.Wait] Stopped 2021-08-04T22:55:39.153Z,1628117739.153 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T22:55:39.154Z,1628117739.154 [Default:CheckIn:D] Running Loop=1 2021-08-04T22:55:39.582Z,1628117739.582 [Default:CheckIn:D] Stopped 2021-08-04T22:55:39.582Z,1628117739.582 [Default:CheckIn:E] Running Loop=1 2021-08-04T22:55:39.961Z,1628117739.961 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.128198 min 2021-08-04T22:55:39.962Z,1628117739.962 [Default:CheckIn:E] Stopped 2021-08-04T22:55:39.962Z,1628117739.962 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T22:55:39.962Z,1628117739.962 [Default:CheckIn] Stopped 2021-08-04T22:55:39.962Z,1628117739.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T22:55:39.962Z,1628117739.962 [Default:CheckIn](INFO): Running loop #8 2021-08-04T22:55:39.962Z,1628117739.962 [Default:CheckIn] Running Loop=8 2021-08-04T22:55:39.962Z,1628117739.962 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T22:55:39.962Z,1628117739.962 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T22:55:41.971Z,1628117741.971 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225542.00,A,4149.69741,N,08324.70551,W,0.019,87.79,040821,,,D*47 2021-08-04T22:55:41.974Z,1628117741.974 [NAL9602](INFO): GPS fix at 20210804T225542: (41.828290, -83.411759) 2021-08-04T22:55:41.984Z,1628117741.984 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T22:55:41.984Z,1628117741.984 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T22:55:49.239Z,1628117749.239 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210804T222002/Courier0024.lzma 2021-08-04T22:55:50.242Z,1628117750.242 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0024.lzma.bak 2021-08-04T22:55:50.242Z,1628117750.242 [DataOverHttps](INFO): SBD MOMSN=15826699 2021-08-04T22:55:56.923Z,1628117756.923 [NAL9602](INFO): SBD MO Status=2, MOMSN=41971, MT Status=2, MTMSN=0 2021-08-04T22:55:56.923Z,1628117756.923 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-04T22:56:07.434Z,1628117767.434 [NAL9602](INFO): SBD MO Status=0, MOMSN=41971, MT Status=0, MTMSN=0 2021-08-04T22:56:07.435Z,1628117767.435 [NAL9602](INFO): No messages in MT queue 2021-08-04T22:56:07.535Z,1628117767.535 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210804T222002/Express0025.lzma 2021-08-04T22:56:08.538Z,1628117768.538 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0025.lzma.bak 2021-08-04T22:56:08.538Z,1628117768.538 [DataOverHttps](INFO): SBD MOMSN=15826702 2021-08-04T22:56:10.261Z,1628117770.261 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T22:56:10.261Z,1628117770.261 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T22:56:10.261Z,1628117770.261 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T22:56:38.121Z,1628117798.121 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T23:01:02.027Z,1628118062.027 [DVL_micro](ERROR): Failed to parse: :T00000000,35.0,+27.7,0000.0,1489.0,000 2021-08-04T23:01:10.927Z,1628118070.927 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T23:01:10.927Z,1628118070.927 [Default:CheckIn:C.Wait] Stopped 2021-08-04T23:01:10.927Z,1628118070.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T23:01:10.927Z,1628118070.927 [Default:CheckIn:D] Running Loop=1 2021-08-04T23:01:11.323Z,1628118071.323 [Default:CheckIn:D] Stopped 2021-08-04T23:01:11.323Z,1628118071.323 [Default:CheckIn:E] Running Loop=1 2021-08-04T23:01:11.747Z,1628118071.747 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.657227 min 2021-08-04T23:01:11.747Z,1628118071.747 [Default:CheckIn:E] Stopped 2021-08-04T23:01:11.747Z,1628118071.747 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T23:01:11.747Z,1628118071.747 [Default:CheckIn] Stopped 2021-08-04T23:01:11.747Z,1628118071.747 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T23:01:11.747Z,1628118071.747 [Default:CheckIn](INFO): Running loop #9 2021-08-04T23:01:11.748Z,1628118071.748 [Default:CheckIn] Running Loop=9 2021-08-04T23:01:11.748Z,1628118071.748 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T23:01:11.748Z,1628118071.748 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T23:01:12.128Z,1628118072.128 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-08-04T23:01:12.128Z,1628118072.128 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+27.7,0000.0,10 2021-08-04T23:01:13.741Z,1628118073.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230114.00,A,4149.64502,N,08324.50101,W,7.562,143.46,040821,,,D*74 2021-08-04T23:01:13.743Z,1628118073.743 [NAL9602](INFO): GPS fix at 20210804T230114: (41.827417, -83.408350) 2021-08-04T23:01:13.753Z,1628118073.753 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T23:01:13.753Z,1628118073.753 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T23:01:19.783Z,1628118079.783 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210804T222002/Courier0027.lzma 2021-08-04T23:01:20.786Z,1628118080.786 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0027.lzma.bak 2021-08-04T23:01:20.786Z,1628118080.786 [DataOverHttps](INFO): SBD MOMSN=15826743 2021-08-04T23:01:30.710Z,1628118090.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=41972, MT Status=0, MTMSN=0 2021-08-04T23:01:30.710Z,1628118090.710 [NAL9602](INFO): No messages in MT queue 2021-08-04T23:01:41.924Z,1628118101.924 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20210804T222002/Express0028.lzma 2021-08-04T23:01:42.988Z,1628118102.988 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0028.lzma.bak 2021-08-04T23:01:42.989Z,1628118102.989 [DataOverHttps](INFO): SBD MOMSN=15826753 2021-08-04T23:01:45.349Z,1628118105.349 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T23:01:45.350Z,1628118105.350 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T23:01:45.350Z,1628118105.350 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T23:02:01.501Z,1628118121.501 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T23:04:58.095Z,1628118298.095 [DVL_micro](ERROR): Failed to parse: 9.99,+9999.99,+9999.99,+9999.99 2021-08-04T23:06:45.999Z,1628118405.999 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T23:06:45.999Z,1628118405.999 [Default:CheckIn:C.Wait] Stopped 2021-08-04T23:06:45.999Z,1628118405.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T23:06:45.999Z,1628118405.999 [Default:CheckIn:D] Running Loop=1 2021-08-04T23:06:46.411Z,1628118406.411 [Default:CheckIn:D] Stopped 2021-08-04T23:06:46.411Z,1628118406.411 [Default:CheckIn:E] Running Loop=1 2021-08-04T23:06:46.840Z,1628118406.840 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.242029 min 2021-08-04T23:06:46.840Z,1628118406.840 [Default:CheckIn:E] Stopped 2021-08-04T23:06:46.840Z,1628118406.840 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T23:06:46.841Z,1628118406.841 [Default:CheckIn] Stopped 2021-08-04T23:06:46.841Z,1628118406.841 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T23:06:46.841Z,1628118406.841 [Default:CheckIn](INFO): Running loop #10 2021-08-04T23:06:46.841Z,1628118406.841 [Default:CheckIn] Running Loop=10 2021-08-04T23:06:46.841Z,1628118406.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T23:06:46.841Z,1628118406.841 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T23:06:48.814Z,1628118408.814 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230649.00,A,4149.01472,N,08323.98637,W,9.603,133.44,040821,,,D*70 2021-08-04T23:06:48.831Z,1628118408.831 [NAL9602](INFO): GPS fix at 20210804T230649: (41.816912, -83.399773) 2021-08-04T23:06:48.841Z,1628118408.841 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T23:06:48.841Z,1628118408.841 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T23:06:56.595Z,1628118416.595 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210804T222002/Courier0030.lzma 2021-08-04T23:06:57.598Z,1628118417.598 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0030.lzma.bak 2021-08-04T23:06:57.598Z,1628118417.598 [DataOverHttps](INFO): SBD MOMSN=15826823 2021-08-04T23:07:14.356Z,1628118434.356 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20210804T222002/Express0031.lzma 2021-08-04T23:07:15.358Z,1628118435.358 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0031.lzma.bak 2021-08-04T23:07:15.358Z,1628118435.358 [DataOverHttps](INFO): SBD MOMSN=15826826 2021-08-04T23:07:17.083Z,1628118437.083 [NAL9602](INFO): SBD MO Status=0, MOMSN=41973, MT Status=0, MTMSN=0 2021-08-04T23:07:17.084Z,1628118437.084 [NAL9602](INFO): No messages in MT queue 2021-08-04T23:07:17.141Z,1628118437.141 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T23:07:17.141Z,1628118437.141 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T23:07:17.141Z,1628118437.141 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T23:07:47.808Z,1628118467.808 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T23:12:12.491Z,1628118732.491 [DVL_micro](ERROR): Failed to parse:00000000000,35.0,+28.1,489.0,000 2021-08-04T23:12:17.768Z,1628118737.768 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T23:12:17.768Z,1628118737.768 [Default:CheckIn:C.Wait] Stopped 2021-08-04T23:12:17.768Z,1628118737.768 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T23:12:17.769Z,1628118737.769 [Default:CheckIn:D] Running Loop=1 2021-08-04T23:12:18.157Z,1628118738.157 [Default:CheckIn:D] Stopped 2021-08-04T23:12:18.158Z,1628118738.158 [Default:CheckIn:E] Running Loop=1 2021-08-04T23:12:18.561Z,1628118738.561 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.771130 min 2021-08-04T23:12:18.561Z,1628118738.561 [Default:CheckIn:E] Stopped 2021-08-04T23:12:18.562Z,1628118738.562 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T23:12:18.562Z,1628118738.562 [Default:CheckIn] Stopped 2021-08-04T23:12:18.562Z,1628118738.562 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T23:12:18.562Z,1628118738.562 [Default:CheckIn](INFO): Running loop #11 2021-08-04T23:12:18.562Z,1628118738.562 [Default:CheckIn] Running Loop=11 2021-08-04T23:12:18.562Z,1628118738.562 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T23:12:18.562Z,1628118738.562 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T23:12:20.583Z,1628118740.583 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231221.00,A,4147.90491,N,08322.52150,W,17.359,135.45,040821,,,D*43 2021-08-04T23:12:20.586Z,1628118740.586 [NAL9602](INFO): GPS fix at 20210804T231221: (41.798415, -83.375358) 2021-08-04T23:12:20.596Z,1628118740.596 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T23:12:20.596Z,1628118740.596 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T23:12:22.640Z,1628118742.640 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-08-04T23:12:22.640Z,1628118742.640 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+28.1,0000.0,14 2021-08-04T23:12:27.312Z,1628118747.312 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210804T222002/Courier0033.lzma 2021-08-04T23:12:28.314Z,1628118748.314 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0033.lzma.bak 2021-08-04T23:12:28.314Z,1628118748.314 [DataOverHttps](INFO): SBD MOMSN=15826902 2021-08-04T23:12:32.516Z,1628118752.516 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2021-08-04T23:12:38.538Z,1628118758.538 [NAL9602](INFO): SBD MO Status=0, MOMSN=41974, MT Status=0, MTMSN=0 2021-08-04T23:12:38.538Z,1628118758.538 [NAL9602](INFO): No messages in MT queue 2021-08-04T23:12:50.648Z,1628118770.648 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20210804T222002/Express0034.lzma 2021-08-04T23:12:51.634Z,1628118771.634 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0034.lzma.bak 2021-08-04T23:12:51.634Z,1628118771.634 [DataOverHttps](INFO): SBD MOMSN=15826905 2021-08-04T23:12:53.440Z,1628118773.440 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T23:12:53.440Z,1628118773.440 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T23:12:53.440Z,1628118773.440 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T23:13:09.184Z,1628118789.184 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T23:14:18.330Z,1628118858.330 [DVL_micro](ERROR): only read 3 of 4 data items 2021-08-04T23:14:18.330Z,1628118858.330 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+99999.99,+9999.99 2021-08-04T23:17:54.195Z,1628119074.195 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T23:17:54.195Z,1628119074.195 [Default:CheckIn:C.Wait] Stopped 2021-08-04T23:17:54.195Z,1628119074.195 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T23:17:54.195Z,1628119074.195 [Default:CheckIn:D] Running Loop=1 2021-08-04T23:17:54.623Z,1628119074.623 [Default:CheckIn:D] Stopped 2021-08-04T23:17:54.624Z,1628119074.624 [Default:CheckIn:E] Running Loop=1 2021-08-04T23:17:55.003Z,1628119075.003 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.378898 min 2021-08-04T23:17:55.003Z,1628119075.003 [Default:CheckIn:E] Stopped 2021-08-04T23:17:55.003Z,1628119075.003 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T23:17:55.003Z,1628119075.003 [Default:CheckIn] Stopped 2021-08-04T23:17:55.003Z,1628119075.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T23:17:55.004Z,1628119075.004 [Default:CheckIn](INFO): Running loop #12 2021-08-04T23:17:55.004Z,1628119075.004 [Default:CheckIn] Running Loop=12 2021-08-04T23:17:55.004Z,1628119075.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T23:17:55.004Z,1628119075.004 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T23:17:57.025Z,1628119077.025 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231757.00,A,4146.77206,N,08320.97812,W,17.300,126.85,040821,,,D*41 2021-08-04T23:17:57.028Z,1628119077.028 [NAL9602](INFO): GPS fix at 20210804T231757: (41.779534, -83.349635) 2021-08-04T23:17:57.037Z,1628119077.037 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T23:17:57.037Z,1628119077.037 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T23:18:04.136Z,1628119084.136 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210804T222002/Courier0036.lzma 2021-08-04T23:18:05.138Z,1628119085.138 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Courier0036.lzma.bak 2021-08-04T23:18:05.138Z,1628119085.138 [DataOverHttps](INFO): SBD MOMSN=15826946 2021-08-04T23:18:15.853Z,1628119095.853 [NAL9602](INFO): SBD MO Status=2, MOMSN=41975, MT Status=2, MTMSN=0 2021-08-04T23:18:15.853Z,1628119095.853 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-04T23:18:21.448Z,1628119101.448 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20210804T222002/Express0037.lzma 2021-08-04T23:18:22.450Z,1628119102.450 [DataOverHttps](INFO): Moved sent file to Logs/20210804T222002/Express0037.lzma.bak 2021-08-04T23:18:22.450Z,1628119102.450 [DataOverHttps](INFO): SBD MOMSN=15826949 2021-08-04T23:18:23.955Z,1628119103.955 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T23:18:23.956Z,1628119103.956 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T23:18:23.957Z,1628119103.957 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T23:18:27.195Z,1628119107.195 [NAL9602](INFO): SBD MO Status=0, MOMSN=41975, MT Status=0, MTMSN=0 2021-08-04T23:18:27.195Z,1628119107.195 [NAL9602](INFO): No messages in MT queue 2021-08-04T23:18:31.603Z,1628119111.603 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-08-04T23:18:43.331Z,1628119123.331 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-08-04T23:18:55.047Z,1628119135.047 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-08-04T23:18:57.878Z,1628119137.878 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T23:19:06.358Z,1628119146.358 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-08-04T23:19:18.073Z,1628119158.073 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-08-04T23:19:29.406Z,1628119169.406 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-08-04T23:19:41.157Z,1628119181.157 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-08-04T23:19:52.837Z,1628119192.837 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-08-04T23:20:46.623Z,1628119246.623 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-08-04T23:20:53.040Z,1628119253.040 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: -12 2021-08-04T23:20:53.042Z,1628119253.042 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2021-08-04T23:22:40.169Z,1628119360.169 [DataOverHttps](IMPORTANT): SBD MTMSN=20210804T232240 2021-08-04T23:22:49.071Z,1628119369.071 [DataOverHttps](INFO): Received command:strobe off 2021-08-04T23:22:49.086Z,1628119369.086 [CommandLine](IMPORTANT): got command strobe off 2021-08-04T23:22:49.086Z,1628119369.086 [CommandLine](IMPORTANT): Deactivating strobe 2021-08-04T23:23:24.698Z,1628119404.698 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T23:23:24.699Z,1628119404.699 [Default:CheckIn:C.Wait] Stopped 2021-08-04T23:23:24.699Z,1628119404.699 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T23:23:24.699Z,1628119404.699 [Default:CheckIn:D] Running Loop=1 2021-08-04T23:23:25.110Z,1628119405.110 [Default:CheckIn:D] Stopped 2021-08-04T23:23:25.110Z,1628119405.110 [Default:CheckIn:E] Running Loop=1 2021-08-04T23:23:25.521Z,1628119405.521 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.887012 min 2021-08-04T23:23:25.521Z,1628119405.521 [Default:CheckIn:E] Stopped 2021-08-04T23:23:25.521Z,1628119405.521 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T23:23:25.521Z,1628119405.521 [Default:CheckIn] Stopped 2021-08-04T23:23:25.521Z,1628119405.521 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T23:23:25.521Z,1628119405.521 [Default:CheckIn](INFO): Running loop #13 2021-08-04T23:23:25.522Z,1628119405.522 [Default:CheckIn] Running Loop=13 2021-08-04T23:23:25.522Z,1628119405.522 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T23:23:25.522Z,1628119405.522 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T23:23:27.547Z,1628119407.547 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232328.00,A,4145.75272,N,08319.40505,W,17.048,95.90,040821,,,D*75 2021-08-04T23:23:27.550Z,1628119407.550 [NAL9602](INFO): GPS fix at 20210804T232328: (41.762545, -83.323418) 2021-08-04T23:23:27.559Z,1628119407.559 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T23:23:27.559Z,1628119407.559 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T23:23:40.455Z,1628119420.455 [NAL9602](INFO): SBD MO Status=0, MOMSN=41976, MT Status=0, MTMSN=0 2021-08-04T23:23:40.455Z,1628119420.455 [NAL9602](INFO): No messages in MT queue 2021-08-04T23:23:41.336Z,1628119421.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:24:11.162Z,1628119451.162 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T23:24:12.460Z,1628119452.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:24:43.584Z,1628119483.584 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:25:03.280Z,1628119503.280 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2021-08-04T23:25:03.281Z,1628119503.281 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2021-08-04T23:25:03.283Z,1628119503.283 [BPC1](INFO): Received data from all battery sticks. 2021-08-04T23:25:14.732Z,1628119514.732 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:25:45.856Z,1628119545.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:25:50.876Z,1628119550.876 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.803482 2021-08-04T23:26:06.368Z,1628119566.368 [NAL9602](INFO): SBD MO Status=1, MOMSN=41977, MT Status=0, MTMSN=0 2021-08-04T23:26:06.420Z,1628119566.420 [NAL9602](INFO): Sent 71 bytes from file Logs/20210804T222002/Courier0039.lzma 2021-08-04T23:26:06.420Z,1628119566.420 [NAL9602](INFO): Packets left to send: 0 2021-08-04T23:26:19.088Z,1628119579.088 [NAL9602](INFO): SBD MO Status=1, MOMSN=41978, MT Status=0, MTMSN=0 2021-08-04T23:26:19.144Z,1628119579.144 [NAL9602](INFO): Sent 218 bytes from file Logs/20210804T222002/Express0040.lzma 2021-08-04T23:26:19.144Z,1628119579.144 [NAL9602](INFO): Packets left to send: 0 2021-08-04T23:26:22.000Z,1628119582.000 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:26:22.750Z,1628119582.750 [NAL9602](INFO): SBD MO Status=0, MOMSN=41979, MT Status=0, MTMSN=0 2021-08-04T23:26:22.828Z,1628119582.828 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T23:26:22.828Z,1628119582.828 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T23:26:22.828Z,1628119582.828 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T23:26:53.148Z,1628119613.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:26:53.458Z,1628119613.458 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T23:27:24.272Z,1628119644.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:27:55.396Z,1628119675.396 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:28:00.922Z,1628119680.922 [DVL_micro](ERROR): Failed to parse: :R99,+99999.99,+9999.99 2021-08-04T23:28:26.520Z,1628119706.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:28:57.644Z,1628119737.644 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:29:28.768Z,1628119768.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:29:59.892Z,1628119799.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:30:17.060Z,1628119817.060 [DVL_micro](ERROR): only read 3 of 4 data items 2021-08-04T23:30:17.060Z,1628119817.060 [DVL_micro](ERROR): Failed to parse: :BI,-00144,+00098,+0000,I 2021-08-04T23:30:31.036Z,1628119831.036 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:31:02.164Z,1628119862.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:31:23.341Z,1628119883.341 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T23:31:23.341Z,1628119883.341 [Default:CheckIn:C.Wait] Stopped 2021-08-04T23:31:23.341Z,1628119883.341 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T23:31:23.342Z,1628119883.342 [Default:CheckIn:D] Running Loop=1 2021-08-04T23:31:23.739Z,1628119883.739 [Default:CheckIn:D] Stopped 2021-08-04T23:31:23.740Z,1628119883.740 [Default:CheckIn:E] Running Loop=1 2021-08-04T23:31:24.129Z,1628119884.129 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.864168 min 2021-08-04T23:31:24.129Z,1628119884.129 [Default:CheckIn:E] Stopped 2021-08-04T23:31:24.129Z,1628119884.129 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T23:31:24.129Z,1628119884.129 [Default:CheckIn] Stopped 2021-08-04T23:31:24.129Z,1628119884.129 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T23:31:24.129Z,1628119884.129 [Default:CheckIn](INFO): Running loop #14 2021-08-04T23:31:24.129Z,1628119884.129 [Default:CheckIn] Running Loop=14 2021-08-04T23:31:24.129Z,1628119884.129 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T23:31:24.130Z,1628119884.130 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T23:31:26.142Z,1628119886.142 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233126.00,A,4145.30766,N,08318.21704,W,18.544,66.71,040821,,,D*79 2021-08-04T23:31:26.144Z,1628119886.144 [NAL9602](INFO): GPS fix at 20210804T233126: (41.755128, -83.303617) 2021-08-04T23:31:26.154Z,1628119886.154 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T23:31:26.154Z,1628119886.154 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T23:31:33.296Z,1628119893.296 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:31:46.018Z,1628119906.018 [NAL9602](INFO): SBD MO Status=1, MOMSN=41980, MT Status=0, MTMSN=0 2021-08-04T23:31:46.073Z,1628119906.073 [NAL9602](INFO): Sent 201 bytes from file Logs/20210804T222002/Courier0045.lzma 2021-08-04T23:31:46.073Z,1628119906.073 [NAL9602](INFO): Packets left to send: 0 2021-08-04T23:31:57.555Z,1628119917.555 [NAL9602](INFO): SBD MO Status=1, MOMSN=41981, MT Status=0, MTMSN=0 2021-08-04T23:31:57.608Z,1628119917.608 [NAL9602](INFO): Sent 144 bytes from file Logs/20210804T222002/Express0046.lzma 2021-08-04T23:31:57.608Z,1628119917.608 [NAL9602](INFO): Packets left to send: 0 2021-08-04T23:32:04.420Z,1628119924.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:32:07.780Z,1628119927.780 [NAL9602](INFO): SBD MO Status=0, MOMSN=41982, MT Status=0, MTMSN=0 2021-08-04T23:32:07.868Z,1628119927.868 [Default:CheckIn:Read_Iridium] Stopped 2021-08-04T23:32:07.869Z,1628119927.869 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-04T23:32:07.869Z,1628119927.869 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-04T23:32:35.544Z,1628119955.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:32:38.473Z,1628119958.473 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-04T23:33:06.696Z,1628119986.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:33:37.820Z,1628120017.820 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:34:08.960Z,1628120048.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:34:40.084Z,1628120080.084 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:35:11.208Z,1628120111.208 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:35:42.340Z,1628120142.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:36:13.464Z,1628120173.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:36:44.588Z,1628120204.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:37:08.350Z,1628120228.350 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-04T23:37:08.351Z,1628120228.351 [Default:CheckIn:C.Wait] Stopped 2021-08-04T23:37:08.351Z,1628120228.351 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-04T23:37:08.351Z,1628120228.351 [Default:CheckIn:D] Running Loop=1 2021-08-04T23:37:08.758Z,1628120228.758 [Default:CheckIn:D] Stopped 2021-08-04T23:37:08.758Z,1628120228.758 [Default:CheckIn:E] Running Loop=1 2021-08-04T23:37:09.165Z,1628120229.165 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.614478 min 2021-08-04T23:37:09.165Z,1628120229.165 [Default:CheckIn:E] Stopped 2021-08-04T23:37:09.165Z,1628120229.165 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-04T23:37:09.165Z,1628120229.165 [Default:CheckIn] Stopped 2021-08-04T23:37:09.165Z,1628120229.165 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-04T23:37:09.165Z,1628120229.165 [Default:CheckIn](INFO): Running loop #15 2021-08-04T23:37:09.165Z,1628120229.165 [Default:CheckIn] Running Loop=15 2021-08-04T23:37:09.166Z,1628120229.166 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-04T23:37:09.166Z,1628120229.166 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-04T23:37:11.187Z,1628120231.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233711.00,A,4145.48147,N,08317.72489,W,0.564,347.71,040821,,,D*75 2021-08-04T23:37:11.200Z,1628120231.200 [NAL9602](INFO): GPS fix at 20210804T233711: (41.758024, -83.295415) 2021-08-04T23:37:11.210Z,1628120231.210 [Default:CheckIn:Read_GPS] Stopped 2021-08-04T23:37:11.210Z,1628120231.210 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-04T23:37:15.720Z,1628120235.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-04T23:37:24.249Z,1628120244.249 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=41983, MT Status=1, MTMSN=3005 2021-08-04T23:37:24.296Z,1628120244.296 [NAL9602](INFO): Sent 72 bytes from file Logs/20210804T222002/Courier0048.lzma 2021-08-04T23:37:24.296Z,1628120244.296 [NAL9602](INFO): Packets left to send: 0