2019-05-23T15:44:23.387Z,1558626263.387 [CommandLine](IMPORTANT): got command restart logs 2019-05-23T15:44:31.822Z,1558626271.822 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T15:44:39.009Z,1558626279.009 [DataOverHttps](INFO): Sending 608 bytes from file Logs/20190522T180617/Express0695.lzma 2019-05-23T15:44:39.815Z,1558626279.815 [DataOverHttps](INFO): Moved sent file to Logs/20190522T180617/Express0695.lzma.bak 2019-05-23T15:44:39.815Z,1558626279.815 [DataOverHttps](INFO): SBD MOMSN=11159617 2019-05-23T15:45:35.000Z,1558626335.000 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190522T180617/Courier0697.lzma 2019-05-23T15:45:35.811Z,1558626335.811 [DataOverHttps](INFO): Moved sent file to Logs/20190522T180617/Courier0697.lzma.bak 2019-05-23T15:45:35.811Z,1558626335.811 [DataOverHttps](INFO): SBD MOMSN=11159619 2019-05-23T15:46:30.824Z,1558626390.824 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190523T154423/Courier0000.lzma 2019-05-23T15:46:31.630Z,1558626391.630 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0000.lzma.bak 2019-05-23T15:46:31.630Z,1558626391.630 [DataOverHttps](INFO): SBD MOMSN=11159625 2019-05-23T15:46:43.232Z,1558626403.232 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20190522T180617/Express0698.lzma 2019-05-23T15:46:44.039Z,1558626404.039 [DataOverHttps](INFO): Moved sent file to Logs/20190522T180617/Express0698.lzma.bak 2019-05-23T15:46:44.039Z,1558626404.039 [DataOverHttps](INFO): SBD MOMSN=11159629 2019-05-23T15:47:39.372Z,1558626459.372 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190523T154423/Express0001.lzma 2019-05-23T15:47:40.178Z,1558626460.178 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Express0001.lzma.bak 2019-05-23T15:47:40.178Z,1558626460.178 [DataOverHttps](INFO): SBD MOMSN=11159637 2019-05-23T15:47:40.932Z,1558626460.932 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T15:47:40.932Z,1558626460.932 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T15:47:40.932Z,1558626460.932 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T15:52:41.565Z,1558626761.565 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-23T15:52:41.565Z,1558626761.565 [Default:CheckIn:C.Wait] Stopped 2019-05-23T15:52:41.566Z,1558626761.566 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-23T15:52:41.566Z,1558626761.566 [Default:CheckIn:D] Running Loop=1 2019-05-23T15:52:41.904Z,1558626761.904 [Default:CheckIn:D] Stopped 2019-05-23T15:52:41.904Z,1558626761.904 [Default:CheckIn:E] Running Loop=1 2019-05-23T15:52:42.306Z,1558626762.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1302.947656 min 2019-05-23T15:52:42.306Z,1558626762.306 [Default:CheckIn:E] Stopped 2019-05-23T15:52:42.306Z,1558626762.306 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-23T15:52:42.306Z,1558626762.306 [Default:CheckIn] Stopped 2019-05-23T15:52:42.306Z,1558626762.306 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-23T15:52:42.307Z,1558626762.307 [Default:CheckIn](INFO): Running loop #231 2019-05-23T15:52:42.307Z,1558626762.307 [Default:CheckIn] Running Loop=231 2019-05-23T15:52:42.307Z,1558626762.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-23T15:52:42.307Z,1558626762.307 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-23T15:52:43.913Z,1558626763.913 [NAL9602](DEBUG): Fix Requested 2019-05-23T15:52:44.304Z,1558626764.304 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155244.00,A,3648.16396,N,12147.21473,W,0.019,0.00,230519,,,A*7B 2019-05-23T15:52:44.307Z,1558626764.307 [NAL9602](INFO): GPS fix at 20190523T155244: (36.802733, -121.786912) 2019-05-23T15:52:44.330Z,1558626764.330 [Default:CheckIn:Read_GPS] Stopped 2019-05-23T15:52:44.330Z,1558626764.330 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-23T15:52:45.931Z,1558626765.931 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-05-23T15:52:50.305Z,1558626770.305 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190523T154423/Courier0003.lzma 2019-05-23T15:52:51.110Z,1558626771.110 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0003.lzma.bak 2019-05-23T15:52:51.110Z,1558626771.110 [DataOverHttps](INFO): SBD MOMSN=11159664 2019-05-23T15:53:02.602Z,1558626782.602 [DataOverHttps](INFO): Sending 1056 bytes from file Logs/20190523T154423/Express0004.lzma 2019-05-23T15:53:03.422Z,1558626783.422 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Express0004.lzma.bak 2019-05-23T15:53:03.422Z,1558626783.422 [DataOverHttps](INFO): SBD MOMSN=11159667 2019-05-23T15:53:04.124Z,1558626784.124 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T15:53:04.124Z,1558626784.124 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T15:53:04.124Z,1558626784.124 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T15:53:08.136Z,1558626788.136 [NAL9602](INFO): SBD MO Status=0, MOMSN=5057, MT Status=0, MTMSN=0 2019-05-23T15:53:08.136Z,1558626788.136 [NAL9602](INFO): No messages in MT queue 2019-05-23T15:53:38.843Z,1558626818.843 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T15:53:39.679Z,1558626819.679 [RDI_Pathfinder](ERROR): Failed to parse: +0.00, +0.00, +0.00, 0.00,24404.63 2019-05-23T15:54:39.490Z,1558626879.490 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-05-23T15:54:39.490Z,1558626879.490 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-3276,V 2019-05-23T15:58:04.703Z,1558627084.703 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-23T15:58:04.704Z,1558627084.704 [Default:CheckIn:C.Wait] Stopped 2019-05-23T15:58:04.704Z,1558627084.704 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-23T15:58:04.704Z,1558627084.704 [Default:CheckIn:D] Running Loop=1 2019-05-23T15:58:05.104Z,1558627085.104 [Default:CheckIn:D] Stopped 2019-05-23T15:58:05.104Z,1558627085.104 [Default:CheckIn:E] Running Loop=1 2019-05-23T15:58:05.502Z,1558627085.502 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1308.334245 min 2019-05-23T15:58:05.503Z,1558627085.503 [Default:CheckIn:E] Stopped 2019-05-23T15:58:05.503Z,1558627085.503 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-23T15:58:05.503Z,1558627085.503 [Default:CheckIn] Stopped 2019-05-23T15:58:05.503Z,1558627085.503 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-23T15:58:05.503Z,1558627085.503 [Default:CheckIn](INFO): Running loop #232 2019-05-23T15:58:05.503Z,1558627085.503 [Default:CheckIn] Running Loop=232 2019-05-23T15:58:05.503Z,1558627085.503 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-23T15:58:05.503Z,1558627085.503 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-23T15:58:07.109Z,1558627087.109 [NAL9602](DEBUG): Fix Requested 2019-05-23T15:58:07.507Z,1558627087.507 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155807.00,A,3648.16360,N,12147.21422,W,0.019,0.00,230519,,,A*7B 2019-05-23T15:58:07.509Z,1558627087.509 [NAL9602](INFO): GPS fix at 20190523T155807: (36.802727, -121.786904) 2019-05-23T15:58:07.575Z,1558627087.575 [Default:CheckIn:Read_GPS] Stopped 2019-05-23T15:58:07.575Z,1558627087.575 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-23T15:58:09.126Z,1558627089.126 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-05-23T15:58:13.228Z,1558627093.228 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190523T154423/Courier0006.lzma 2019-05-23T15:58:14.034Z,1558627094.034 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0006.lzma.bak 2019-05-23T15:58:14.035Z,1558627094.035 [DataOverHttps](INFO): SBD MOMSN=11159686 2019-05-23T15:58:25.558Z,1558627105.558 [DataOverHttps](INFO): Sending 553 bytes from file Logs/20190523T154423/Express0007.lzma 2019-05-23T15:58:26.362Z,1558627106.362 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Express0007.lzma.bak 2019-05-23T15:58:26.362Z,1558627106.362 [DataOverHttps](INFO): SBD MOMSN=11159689 2019-05-23T15:58:27.330Z,1558627107.330 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T15:58:27.330Z,1558627107.330 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T15:58:27.330Z,1558627107.330 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T15:58:29.316Z,1558627109.316 [NAL9602](INFO): SBD MO Status=0, MOMSN=5058, MT Status=0, MTMSN=0 2019-05-23T15:58:29.316Z,1558627109.316 [NAL9602](INFO): No messages in MT queue 2019-05-23T15:59:00.019Z,1558627140.019 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T16:00:30.558Z,1558627230.558 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-05-23T16:03:27.903Z,1558627407.903 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-23T16:03:27.904Z,1558627407.904 [Default:CheckIn:C.Wait] Stopped 2019-05-23T16:03:27.904Z,1558627407.904 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-23T16:03:27.904Z,1558627407.904 [Default:CheckIn:D] Running Loop=1 2019-05-23T16:03:28.308Z,1558627408.308 [Default:CheckIn:D] Stopped 2019-05-23T16:03:28.308Z,1558627408.308 [Default:CheckIn:E] Running Loop=1 2019-05-23T16:03:28.712Z,1558627408.712 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1313.721094 min 2019-05-23T16:03:28.712Z,1558627408.712 [Default:CheckIn:E] Stopped 2019-05-23T16:03:28.712Z,1558627408.712 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-23T16:03:28.712Z,1558627408.712 [Default:CheckIn] Stopped 2019-05-23T16:03:28.713Z,1558627408.713 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-23T16:03:28.713Z,1558627408.713 [Default:CheckIn](INFO): Running loop #233 2019-05-23T16:03:28.713Z,1558627408.713 [Default:CheckIn] Running Loop=233 2019-05-23T16:03:28.713Z,1558627408.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-23T16:03:28.713Z,1558627408.713 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-23T16:03:30.313Z,1558627410.313 [NAL9602](DEBUG): Fix Requested 2019-05-23T16:03:30.701Z,1558627410.701 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160330.00,A,3648.16457,N,12147.21174,W,0.058,0.00,230519,,,A*72 2019-05-23T16:03:30.703Z,1558627410.703 [NAL9602](INFO): GPS fix at 20190523T160330: (36.802743, -121.786862) 2019-05-23T16:03:30.761Z,1558627410.761 [Default:CheckIn:Read_GPS] Stopped 2019-05-23T16:03:30.761Z,1558627410.761 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-23T16:03:31.918Z,1558627411.918 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-05-23T16:03:36.704Z,1558627416.704 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190523T154423/Courier0009.lzma 2019-05-23T16:03:37.510Z,1558627417.510 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0009.lzma.bak 2019-05-23T16:03:37.511Z,1558627417.511 [DataOverHttps](INFO): SBD MOMSN=11159721 2019-05-23T16:03:49.345Z,1558627429.345 [DataOverHttps](INFO): Sending 557 bytes from file Logs/20190523T154423/Express0010.lzma 2019-05-23T16:03:50.150Z,1558627430.150 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Express0010.lzma.bak 2019-05-23T16:03:50.151Z,1558627430.151 [DataOverHttps](INFO): SBD MOMSN=11159725 2019-05-23T16:03:50.924Z,1558627430.924 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T16:03:50.924Z,1558627430.924 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T16:03:50.924Z,1558627430.924 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T16:03:54.128Z,1558627434.128 [NAL9602](INFO): SBD MO Status=0, MOMSN=5059, MT Status=0, MTMSN=0 2019-05-23T16:03:54.128Z,1558627434.128 [NAL9602](INFO): No messages in MT queue 2019-05-23T16:04:24.828Z,1558627464.828 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T16:05:56.984Z,1558627556.984 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-05-23T16:05:56.987Z,1558627556.987 [BPC1](INFO): Received data from all battery sticks. 2019-05-23T16:08:14.744Z,1558627694.744 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2019-05-23T16:08:51.516Z,1558627731.516 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-23T16:08:51.516Z,1558627731.516 [Default:CheckIn:C.Wait] Stopped 2019-05-23T16:08:51.516Z,1558627731.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-23T16:08:51.516Z,1558627731.516 [Default:CheckIn:D] Running Loop=1 2019-05-23T16:08:51.916Z,1558627731.916 [Default:CheckIn:D] Stopped 2019-05-23T16:08:51.916Z,1558627731.916 [Default:CheckIn:E] Running Loop=1 2019-05-23T16:08:52.317Z,1558627732.317 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1319.114453 min 2019-05-23T16:08:52.317Z,1558627732.317 [Default:CheckIn:E] Stopped 2019-05-23T16:08:52.318Z,1558627732.318 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-23T16:08:52.318Z,1558627732.318 [Default:CheckIn] Stopped 2019-05-23T16:08:52.318Z,1558627732.318 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-23T16:08:52.318Z,1558627732.318 [Default:CheckIn](INFO): Running loop #234 2019-05-23T16:08:52.318Z,1558627732.318 [Default:CheckIn] Running Loop=234 2019-05-23T16:08:52.318Z,1558627732.318 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-23T16:08:52.318Z,1558627732.318 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-23T16:08:53.917Z,1558627733.917 [NAL9602](DEBUG): Fix Requested 2019-05-23T16:08:54.305Z,1558627734.305 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160854.00,A,3648.16762,N,12147.21365,W,0.272,0.00,230519,,,A*76 2019-05-23T16:08:54.307Z,1558627734.307 [NAL9602](INFO): GPS fix at 20190523T160854: (36.802794, -121.786894) 2019-05-23T16:08:54.332Z,1558627734.332 [Default:CheckIn:Read_GPS] Stopped 2019-05-23T16:08:54.332Z,1558627734.332 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-23T16:08:55.946Z,1558627735.946 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-05-23T16:09:00.005Z,1558627740.005 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20190523T154423/Courier0012.lzma 2019-05-23T16:09:00.810Z,1558627740.810 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0012.lzma.bak 2019-05-23T16:09:00.811Z,1558627740.811 [DataOverHttps](INFO): SBD MOMSN=11159756 2019-05-23T16:09:12.281Z,1558627752.281 [DataOverHttps](INFO): Sending 556 bytes from file Logs/20190523T154423/Express0013.lzma 2019-05-23T16:09:13.086Z,1558627753.086 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Express0013.lzma.bak 2019-05-23T16:09:13.086Z,1558627753.086 [DataOverHttps](INFO): SBD MOMSN=11159759 2019-05-23T16:09:13.732Z,1558627753.732 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T16:09:13.732Z,1558627753.732 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T16:09:13.732Z,1558627753.732 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T16:09:14.505Z,1558627754.505 [NAL9602](INFO): SBD MO Status=0, MOMSN=5060, MT Status=0, MTMSN=0 2019-05-23T16:09:14.505Z,1558627754.505 [NAL9602](INFO): No messages in MT queue 2019-05-23T16:09:45.207Z,1558627785.207 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T16:10:53.902Z,1558627853.902 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-05-23T16:10:53.902Z,1558627853.902 [RDI_Pathfinder](ERROR): Failed to parse: :TS,190723095.0, -0.1, 0.0,1448.9, 0 2019-05-23T16:14:14.304Z,1558628054.304 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-23T16:14:14.304Z,1558628054.304 [Default:CheckIn:C.Wait] Stopped 2019-05-23T16:14:14.304Z,1558628054.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-23T16:14:14.304Z,1558628054.304 [Default:CheckIn:D] Running Loop=1 2019-05-23T16:14:14.714Z,1558628054.714 [Default:CheckIn:D] Stopped 2019-05-23T16:14:14.714Z,1558628054.714 [Default:CheckIn:E] Running Loop=1 2019-05-23T16:14:15.120Z,1558628055.120 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1324.494531 min 2019-05-23T16:14:15.120Z,1558628055.120 [Default:CheckIn:E] Stopped 2019-05-23T16:14:15.120Z,1558628055.120 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-23T16:14:15.121Z,1558628055.121 [Default:CheckIn] Stopped 2019-05-23T16:14:15.121Z,1558628055.121 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-23T16:14:15.121Z,1558628055.121 [Default:CheckIn](INFO): Running loop #235 2019-05-23T16:14:15.121Z,1558628055.121 [Default:CheckIn] Running Loop=235 2019-05-23T16:14:15.121Z,1558628055.121 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-23T16:14:15.121Z,1558628055.121 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-23T16:14:16.713Z,1558628056.713 [NAL9602](DEBUG): Fix Requested 2019-05-23T16:14:17.102Z,1558628057.102 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161417.00,A,3648.16550,N,12147.21047,W,0.097,0.00,230519,,,A*75 2019-05-23T16:14:17.104Z,1558628057.104 [NAL9602](INFO): GPS fix at 20190523T161417: (36.802758, -121.786841) 2019-05-23T16:14:17.137Z,1558628057.137 [Default:CheckIn:Read_GPS] Stopped 2019-05-23T16:14:17.137Z,1558628057.137 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-23T16:14:22.708Z,1558628062.708 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190523T154423/Courier0015.lzma 2019-05-23T16:14:23.517Z,1558628063.517 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0015.lzma.bak 2019-05-23T16:14:23.518Z,1558628063.518 [DataOverHttps](INFO): SBD MOMSN=11159784 2019-05-23T16:14:30.845Z,1558628070.845 [NAL9602](INFO): SBD MO Status=0, MOMSN=5061, MT Status=0, MTMSN=0 2019-05-23T16:14:30.845Z,1558628070.845 [NAL9602](INFO): No messages in MT queue 2019-05-23T16:14:35.123Z,1558628075.123 [DataOverHttps](INFO): Sending 558 bytes from file Logs/20190523T154423/Express0016.lzma 2019-05-23T16:14:35.926Z,1558628075.926 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Express0016.lzma.bak 2019-05-23T16:14:35.926Z,1558628075.926 [DataOverHttps](INFO): SBD MOMSN=11159788 2019-05-23T16:14:36.520Z,1558628076.520 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T16:14:36.520Z,1558628076.520 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T16:14:36.520Z,1558628076.520 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T16:15:01.542Z,1558628101.542 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T16:17:53.660Z,1558628273.660 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-05-23T16:17:53.661Z,1558628273.661 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19052309271679,35.0, -0.1, 0.0,1448.5.79 2019-05-23T16:19:37.120Z,1558628377.120 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-23T16:19:37.120Z,1558628377.120 [Default:CheckIn:C.Wait] Stopped 2019-05-23T16:19:37.120Z,1558628377.120 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-23T16:19:37.120Z,1558628377.120 [Default:CheckIn:D] Running Loop=1 2019-05-23T16:19:37.535Z,1558628377.535 [Default:CheckIn:D] Stopped 2019-05-23T16:19:37.535Z,1558628377.535 [Default:CheckIn:E] Running Loop=1 2019-05-23T16:19:37.904Z,1558628377.904 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1329.874870 min 2019-05-23T16:19:37.904Z,1558628377.904 [Default:CheckIn:E] Stopped 2019-05-23T16:19:37.905Z,1558628377.905 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-23T16:19:37.905Z,1558628377.905 [Default:CheckIn] Stopped 2019-05-23T16:19:37.905Z,1558628377.905 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-23T16:19:37.906Z,1558628377.906 [Default:CheckIn](INFO): Running loop #236 2019-05-23T16:19:37.906Z,1558628377.906 [Default:CheckIn] Running Loop=236 2019-05-23T16:19:37.906Z,1558628377.906 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-23T16:19:37.906Z,1558628377.906 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-23T16:19:39.509Z,1558628379.509 [NAL9602](DEBUG): Fix Requested 2019-05-23T16:19:39.911Z,1558628379.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161939.00,A,3648.16690,N,12147.21281,W,0.078,0.00,230519,,,A*72 2019-05-23T16:19:39.913Z,1558628379.913 [NAL9602](INFO): GPS fix at 20190523T161939: (36.802782, -121.786880) 2019-05-23T16:19:39.937Z,1558628379.937 [Default:CheckIn:Read_GPS] Stopped 2019-05-23T16:19:39.937Z,1558628379.937 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-23T16:19:45.508Z,1558628385.508 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190523T154423/Courier0018.lzma 2019-05-23T16:19:46.314Z,1558628386.314 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0018.lzma.bak 2019-05-23T16:19:46.314Z,1558628386.314 [DataOverHttps](INFO): SBD MOMSN=11159817 2019-05-23T16:19:51.211Z,1558628391.211 [NAL9602](INFO): SBD MO Status=0, MOMSN=5062, MT Status=0, MTMSN=0 2019-05-23T16:19:51.212Z,1558628391.212 [NAL9602](INFO): No messages in MT queue 2019-05-23T16:19:58.385Z,1558628398.385 [DataOverHttps](INFO): Sending 561 bytes from file Logs/20190523T154423/Express0019.lzma 2019-05-23T16:19:59.191Z,1558628399.191 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Express0019.lzma.bak 2019-05-23T16:19:59.191Z,1558628399.191 [DataOverHttps](INFO): SBD MOMSN=11159820 2019-05-23T16:20:00.128Z,1558628400.128 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T16:20:00.128Z,1558628400.128 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T16:20:00.128Z,1558628400.128 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T16:20:21.914Z,1558628421.914 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T16:24:17.038Z,1558628657.038 [Micromodem](ERROR): Response from modem failed NMEA checksum: 7 2019-05-23T16:25:00.708Z,1558628700.708 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-23T16:25:00.708Z,1558628700.708 [Default:CheckIn:C.Wait] Stopped 2019-05-23T16:25:00.708Z,1558628700.708 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-23T16:25:00.708Z,1558628700.708 [Default:CheckIn:D] Running Loop=1 2019-05-23T16:25:01.104Z,1558628701.104 [Default:CheckIn:D] Stopped 2019-05-23T16:25:01.104Z,1558628701.104 [Default:CheckIn:E] Running Loop=1 2019-05-23T16:25:01.512Z,1558628701.512 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1335.267578 min 2019-05-23T16:25:01.512Z,1558628701.512 [Default:CheckIn:E] Stopped 2019-05-23T16:25:01.512Z,1558628701.512 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-23T16:25:01.512Z,1558628701.512 [Default:CheckIn] Stopped 2019-05-23T16:25:01.512Z,1558628701.512 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-23T16:25:01.513Z,1558628701.513 [Default:CheckIn](INFO): Running loop #237 2019-05-23T16:25:01.513Z,1558628701.513 [Default:CheckIn] Running Loop=237 2019-05-23T16:25:01.513Z,1558628701.513 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-23T16:25:01.513Z,1558628701.513 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-23T16:25:03.113Z,1558628703.113 [NAL9602](DEBUG): Fix Requested 2019-05-23T16:25:03.519Z,1558628703.519 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162503.00,A,3648.52101,N,12147.15492,W,6.298,293.83,230519,,,A*71 2019-05-23T16:25:03.526Z,1558628703.526 [NAL9602](INFO): GPS fix at 20190523T162503: (36.808684, -121.785915) 2019-05-23T16:25:03.595Z,1558628703.595 [Default:CheckIn:Read_GPS] Stopped 2019-05-23T16:25:03.595Z,1558628703.595 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-23T16:25:05.131Z,1558628705.131 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-05-23T16:25:09.392Z,1558628709.392 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190523T154423/Courier0021.lzma 2019-05-23T16:25:10.198Z,1558628710.198 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0021.lzma.bak 2019-05-23T16:25:10.198Z,1558628710.198 [DataOverHttps](INFO): SBD MOMSN=11159856 2019-05-23T16:25:21.781Z,1558628721.781 [DataOverHttps](INFO): Sending 573 bytes from file Logs/20190523T154423/Express0022.lzma 2019-05-23T16:25:22.586Z,1558628722.586 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Express0022.lzma.bak 2019-05-23T16:25:22.587Z,1558628722.587 [DataOverHttps](INFO): SBD MOMSN=11159860 2019-05-23T16:25:22.892Z,1558628722.892 [NAL9602](INFO): SBD MO Status=2, MOMSN=5063, MT Status=2, MTMSN=0 2019-05-23T16:25:22.893Z,1558628722.893 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-23T16:25:23.344Z,1558628723.344 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T16:25:23.344Z,1558628723.344 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T16:25:23.345Z,1558628723.345 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T16:26:10.160Z,1558628770.160 [NAL9602](INFO): SBD MO Status=2, MOMSN=5063, MT Status=2, MTMSN=0 2019-05-23T16:26:10.160Z,1558628770.160 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-23T16:26:36.424Z,1558628796.424 [NAL9602](INFO): SBD MO Status=2, MOMSN=5063, MT Status=2, MTMSN=0 2019-05-23T16:26:36.424Z,1558628796.424 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-23T16:27:16.416Z,1558628836.416 [NAL9602](INFO): SBD MO Status=0, MOMSN=5063, MT Status=0, MTMSN=0 2019-05-23T16:27:16.416Z,1558628836.416 [NAL9602](INFO): No messages in MT queue 2019-05-23T16:27:47.120Z,1558628867.120 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T16:30:23.908Z,1558629023.908 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-23T16:30:23.908Z,1558629023.908 [Default:CheckIn:C.Wait] Stopped 2019-05-23T16:30:23.908Z,1558629023.908 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-23T16:30:23.908Z,1558629023.908 [Default:CheckIn:D] Running Loop=1 2019-05-23T16:30:24.312Z,1558629024.312 [Default:CheckIn:D] Stopped 2019-05-23T16:30:24.312Z,1558629024.312 [Default:CheckIn:E] Running Loop=1 2019-05-23T16:30:24.724Z,1558629024.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1340.654427 min 2019-05-23T16:30:24.724Z,1558629024.724 [Default:CheckIn:E] Stopped 2019-05-23T16:30:24.724Z,1558629024.724 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-23T16:30:24.724Z,1558629024.724 [Default:CheckIn] Stopped 2019-05-23T16:30:24.724Z,1558629024.724 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-23T16:30:24.724Z,1558629024.724 [Default:CheckIn](INFO): Running loop #238 2019-05-23T16:30:24.725Z,1558629024.725 [Default:CheckIn] Running Loop=238 2019-05-23T16:30:24.725Z,1558629024.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-23T16:30:24.725Z,1558629024.725 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-23T16:30:26.313Z,1558629026.313 [NAL9602](DEBUG): Fix Requested 2019-05-23T16:30:26.705Z,1558629026.705 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163026.00,A,3648.34086,N,12147.98678,W,14.579,287.14,230519,,,A*4F 2019-05-23T16:30:26.707Z,1558629026.707 [NAL9602](INFO): GPS fix at 20190523T163026: (36.805681, -121.799780) 2019-05-23T16:30:26.730Z,1558629026.730 [Default:CheckIn:Read_GPS] Stopped 2019-05-23T16:30:26.730Z,1558629026.730 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-23T16:30:27.950Z,1558629027.950 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-05-23T16:30:32.356Z,1558629032.356 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190523T154423/Courier0024.lzma 2019-05-23T16:30:33.162Z,1558629033.162 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0024.lzma.bak 2019-05-23T16:30:33.162Z,1558629033.162 [DataOverHttps](INFO): SBD MOMSN=11159885 2019-05-23T16:30:42.884Z,1558629042.884 [NAL9602](INFO): SBD MO Status=0, MOMSN=5064, MT Status=0, MTMSN=0 2019-05-23T16:30:42.884Z,1558629042.884 [NAL9602](INFO): No messages in MT queue 2019-05-23T16:30:44.725Z,1558629044.725 [DataOverHttps](INFO): Sending 589 bytes from file Logs/20190523T154423/Express0025.lzma 2019-05-23T16:30:45.530Z,1558629045.530 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Express0025.lzma.bak 2019-05-23T16:30:45.530Z,1558629045.530 [DataOverHttps](INFO): SBD MOMSN=11159888 2019-05-23T16:30:46.147Z,1558629046.147 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T16:30:46.147Z,1558629046.147 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T16:30:46.147Z,1558629046.147 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T16:30:55.875Z,1558629055.875 [RDI_Pathfinder](ERROR): Failed to parse: +0.00, +0.00, 0.00,26636.26 2019-05-23T16:31:13.590Z,1558629073.590 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T16:35:35.832Z,1558629335.832 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-05-23T16:35:35.833Z,1558629335.833 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.0 +0.00, 0.00,26916.71 2019-05-23T16:35:46.717Z,1558629346.717 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-23T16:35:46.717Z,1558629346.717 [Default:CheckIn:C.Wait] Stopped 2019-05-23T16:35:46.717Z,1558629346.717 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-23T16:35:46.717Z,1558629346.717 [Default:CheckIn:D] Running Loop=1 2019-05-23T16:35:47.153Z,1558629347.153 [Default:CheckIn:D] Stopped 2019-05-23T16:35:47.153Z,1558629347.153 [Default:CheckIn:E] Running Loop=1 2019-05-23T16:35:47.536Z,1558629347.536 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1346.034896 min 2019-05-23T16:35:47.537Z,1558629347.537 [Default:CheckIn:E] Stopped 2019-05-23T16:35:47.537Z,1558629347.537 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-23T16:35:47.537Z,1558629347.537 [Default:CheckIn] Stopped 2019-05-23T16:35:47.537Z,1558629347.537 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-23T16:35:47.537Z,1558629347.537 [Default:CheckIn](INFO): Running loop #239 2019-05-23T16:35:47.537Z,1558629347.537 [Default:CheckIn] Running Loop=239 2019-05-23T16:35:47.538Z,1558629347.538 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-23T16:35:47.538Z,1558629347.538 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-23T16:35:49.133Z,1558629349.133 [NAL9602](DEBUG): Fix Requested 2019-05-23T16:35:49.524Z,1558629349.524 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163549.00,A,3648.64385,N,12149.55717,W,10.536,293.37,230519,,,D*4F 2019-05-23T16:35:49.527Z,1558629349.527 [NAL9602](INFO): GPS fix at 20190523T163549: (36.810731, -121.825953) 2019-05-23T16:35:49.597Z,1558629349.597 [Default:CheckIn:Read_GPS] Stopped 2019-05-23T16:35:49.597Z,1558629349.597 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-23T16:35:51.142Z,1558629351.142 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-05-23T16:35:55.284Z,1558629355.284 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190523T154423/Courier0027.lzma 2019-05-23T16:35:56.090Z,1558629356.090 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0027.lzma.bak 2019-05-23T16:35:56.090Z,1558629356.090 [DataOverHttps](INFO): SBD MOMSN=11159936 2019-05-23T16:36:07.669Z,1558629367.669 [DataOverHttps](INFO): Sending 574 bytes from file Logs/20190523T154423/Express0028.lzma 2019-05-23T16:36:08.474Z,1558629368.474 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Express0028.lzma.bak 2019-05-23T16:36:08.475Z,1558629368.475 [DataOverHttps](INFO): SBD MOMSN=11159939 2019-05-23T16:36:09.365Z,1558629369.365 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T16:36:09.365Z,1558629369.365 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T16:36:09.365Z,1558629369.365 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T16:36:15.380Z,1558629375.380 [NAL9602](INFO): SBD MO Status=2, MOMSN=5065, MT Status=2, MTMSN=0 2019-05-23T16:36:15.380Z,1558629375.380 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-23T16:36:42.044Z,1558629402.044 [NAL9602](INFO): SBD MO Status=0, MOMSN=5065, MT Status=0, MTMSN=0 2019-05-23T16:36:42.044Z,1558629402.044 [NAL9602](INFO): No messages in MT queue 2019-05-23T16:37:12.743Z,1558629432.743 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T16:39:20.835Z,1558629560.835 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-05-23T16:39:35.819Z,1558629575.819 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-05-23T16:39:35.819Z,1558629575.819 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 2019-05-23T16:40:00.838Z,1558629600.838 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-05-23T16:40:00.838Z,1558629600.838 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00 2019-05-23T16:40:02.841Z,1558629602.841 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-05-23T16:40:02.842Z,1558629602.842 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19052309492679,35.0, -0.1, 0.0 0 2019-05-23T16:41:09.913Z,1558629669.913 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-23T16:41:09.913Z,1558629669.913 [Default:CheckIn:C.Wait] Stopped 2019-05-23T16:41:09.913Z,1558629669.913 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-23T16:41:09.913Z,1558629669.913 [Default:CheckIn:D] Running Loop=1 2019-05-23T16:41:10.322Z,1558629670.322 [Default:CheckIn:D] Stopped 2019-05-23T16:41:10.322Z,1558629670.322 [Default:CheckIn:E] Running Loop=1 2019-05-23T16:41:10.741Z,1558629670.741 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1351.421224 min 2019-05-23T16:41:10.741Z,1558629670.741 [Default:CheckIn:E] Stopped 2019-05-23T16:41:10.742Z,1558629670.742 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-23T16:41:10.742Z,1558629670.742 [Default:CheckIn] Stopped 2019-05-23T16:41:10.742Z,1558629670.742 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-23T16:41:10.742Z,1558629670.742 [Default:CheckIn](INFO): Running loop #240 2019-05-23T16:41:10.742Z,1558629670.742 [Default:CheckIn] Running Loop=240 2019-05-23T16:41:10.742Z,1558629670.742 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-23T16:41:10.742Z,1558629670.742 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-23T16:41:12.325Z,1558629672.325 [NAL9602](DEBUG): Fix Requested 2019-05-23T16:41:12.724Z,1558629672.724 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164112.00,A,3648.90623,N,12149.43985,W,0.564,79.14,230519,,,D*43 2019-05-23T16:41:12.727Z,1558629672.727 [NAL9602](INFO): GPS fix at 20190523T164112: (36.815104, -121.823998) 2019-05-23T16:41:12.760Z,1558629672.760 [Default:CheckIn:Read_GPS] Stopped 2019-05-23T16:41:12.760Z,1558629672.760 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-23T16:41:13.938Z,1558629673.938 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-05-23T16:41:18.340Z,1558629678.340 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190523T154423/Courier0030.lzma 2019-05-23T16:41:19.146Z,1558629679.146 [DataOverHttps](INFO): Moved sent file to Logs/20190523T154423/Courier0030.lzma.bak 2019-05-23T16:41:19.146Z,1558629679.146 [DataOverHttps](INFO): SBD MOMSN=11159976 2019-05-23T16:41:34.169Z,1558629694.169 [DataOverHttps](INFO): Sending 552 bytes from file Logs/20190523T154423/Express0031.lzma 2019-05-23T16:41:35.344Z,1558629695.344 [NAL9602](INFO): SBD MO Status=2, MOMSN=5066, MT Status=2, MTMSN=0 2019-05-23T16:41:35.344Z,1558629695.344 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-23T16:41:56.401Z,1558629716.401 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2019-05-23T16:42:25.910Z,1558629745.910 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.126129 2019-05-23T16:42:32.751Z,1558629752.751 [NAL9602](INFO): SBD MO Status=0, MOMSN=5066, MT Status=0, MTMSN=0 2019-05-23T16:42:32.751Z,1558629752.751 [NAL9602](INFO): No messages in MT queue 2019-05-23T16:42:41.113Z,1558629761.113 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002890 2019-05-23T16:43:00.318Z,1558629780.318 [NAL9602](INFO): SBD MO Status=1, MOMSN=5067, MT Status=0, MTMSN=0 2019-05-23T16:43:00.365Z,1558629780.365 [NAL9602](INFO): Sent 332 bytes from file Logs/20190523T154423/Express0031.lzma 2019-05-23T16:43:00.365Z,1558629780.365 [NAL9602](INFO): Packets left to send: 1 2019-05-23T16:43:31.022Z,1558629811.022 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T16:43:41.333Z,1558629821.333 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.223732 2019-05-23T16:44:07.492Z,1558629847.492 [NAL9602](INFO): SBD MO Status=1, MOMSN=5068, MT Status=0, MTMSN=0 2019-05-23T16:44:07.541Z,1558629847.541 [NAL9602](INFO): Sent 220 bytes from file Logs/20190523T154423/Express0031.lzma 2019-05-23T16:44:07.541Z,1558629847.541 [NAL9602](INFO): Packets left to send: 0 2019-05-23T16:44:12.037Z,1558629852.037 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-05-23T16:44:28.902Z,1558629868.902 [NAL9602](INFO): SBD MO Status=0, MOMSN=5069, MT Status=0, MTMSN=0 2019-05-23T16:44:29.027Z,1558629869.027 [Default:CheckIn:Read_Iridium] Stopped 2019-05-23T16:44:29.027Z,1558629869.027 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-23T16:44:29.027Z,1558629869.027 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-23T16:44:47.205Z,1558629887.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-05-23T16:44:51.953Z,1558629891.953 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-05-23T16:44:51.953Z,1558629891.953 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19052309541579,35.0, -0.1, 0.0,1448.RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-05-23T16:44:59.607Z,1558629899.607 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-23T16:45:22.365Z,1558629922.365 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-05-23T16:45:54.090Z,1558629954.090 [DataOverHttps](IMPORTANT): SBD MTMSN=20190523T164554 2019-05-23T16:45:54.094Z,1558629954.094 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003310 2019-05-23T16:45:59.600Z,1558629959.600 [DataOverHttps](INFO): Received command:restart logs