2019-10-05T17:14:06.832Z,1570295646.832 [DataOverHttps](INFO): Received command:restart logs
2019-10-05T17:14:06.850Z,1570295646.850 [CommandLine](IMPORTANT): got command restart logs
2019-10-05T17:17:27.101Z,1570295847.101 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T17:17:27.101Z,1570295847.101 [Default:CheckIn:C.Wait] Stopped
2019-10-05T17:17:27.101Z,1570295847.101 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T17:17:27.102Z,1570295847.102 [Default:CheckIn:D] Running Loop=1
2019-10-05T17:17:27.510Z,1570295847.510 [Default:CheckIn:D] Stopped
2019-10-05T17:17:27.510Z,1570295847.510 [Default:CheckIn:E] Running Loop=1
2019-10-05T17:17:27.913Z,1570295847.913 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.123362 min 
2019-10-05T17:17:27.913Z,1570295847.913 [Default:CheckIn:E] Stopped
2019-10-05T17:17:27.914Z,1570295847.914 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T17:17:27.914Z,1570295847.914 [Default:CheckIn] Stopped
2019-10-05T17:17:27.914Z,1570295847.914 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T17:17:27.914Z,1570295847.914 [Default:CheckIn](INFO): Running loop #5
2019-10-05T17:17:27.914Z,1570295847.914 [Default:CheckIn] Running Loop=5
2019-10-05T17:17:27.914Z,1570295847.914 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T17:17:27.914Z,1570295847.914 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T17:17:29.509Z,1570295849.509 [NAL9602](DEBUG): Fix Requested
2019-10-05T17:17:29.908Z,1570295849.908 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171721.00,A,3648.31494,N,12147.70275,W,20.216,87.33,051019,,,A*7F
2019-10-05T17:17:29.910Z,1570295849.910 [NAL9602](INFO): GPS fix at 20191005T171721: (36.805249, -121.795046)
2019-10-05T17:17:29.965Z,1570295849.965 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T17:17:29.965Z,1570295849.965 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T17:17:39.340Z,1570295859.340 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191004T183455/Courier0223.lzma
2019-10-05T17:17:41.347Z,1570295861.347 [DataOverHttps](INFO): Moved sent file to Logs/20191004T183455/Courier0223.lzma.bak
2019-10-05T17:17:41.347Z,1570295861.347 [DataOverHttps](INFO): SBD MOMSN=11853169
2019-10-05T17:17:45.255Z,1570295865.255 [NAL9602](INFO): SBD MO Status=0, MOMSN=2637, MT Status=0, MTMSN=0
2019-10-05T17:17:45.255Z,1570295865.255 [NAL9602](INFO): No messages in MT queue
2019-10-05T17:17:59.845Z,1570295879.845 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20191005T171406/Courier0000.lzma
2019-10-05T17:18:01.850Z,1570295881.850 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0000.lzma.bak
2019-10-05T17:18:01.850Z,1570295881.850 [DataOverHttps](INFO): SBD MOMSN=11853171
2019-10-05T17:18:15.958Z,1570295895.958 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T17:18:20.300Z,1570295900.300 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191005T171406/Courier0003.lzma
2019-10-05T17:18:22.306Z,1570295902.306 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0003.lzma.bak
2019-10-05T17:18:22.306Z,1570295902.306 [DataOverHttps](INFO): SBD MOMSN=11853175
2019-10-05T17:18:44.272Z,1570295924.272 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20191004T183455/Express0224.lzma
2019-10-05T17:18:46.279Z,1570295926.279 [DataOverHttps](INFO): Moved sent file to Logs/20191004T183455/Express0224.lzma.bak
2019-10-05T17:18:46.279Z,1570295926.279 [DataOverHttps](INFO): SBD MOMSN=11853177
2019-10-05T17:19:06.105Z,1570295946.105 [DataOverHttps](INFO): Sending 336 bytes from file Logs/20191005T171406/Express0001.lzma
2019-10-05T17:19:08.117Z,1570295948.117 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0001.lzma.bak
2019-10-05T17:19:08.118Z,1570295948.118 [DataOverHttps](INFO): SBD MOMSN=11853180
2019-10-05T17:19:27.812Z,1570295967.812 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191005T171406/Express0004.lzma
2019-10-05T17:19:29.818Z,1570295969.818 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0004.lzma.bak
2019-10-05T17:19:29.819Z,1570295969.819 [DataOverHttps](INFO): SBD MOMSN=11853185
2019-10-05T17:19:34.389Z,1570295974.389 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T17:19:34.390Z,1570295974.390 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T17:19:34.390Z,1570295974.390 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T17:24:34.978Z,1570296274.978 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T17:24:34.978Z,1570296274.978 [Default:CheckIn:C.Wait] Stopped
2019-10-05T17:24:34.978Z,1570296274.978 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T17:24:34.978Z,1570296274.978 [Default:CheckIn:D] Running Loop=1
2019-10-05T17:24:35.408Z,1570296275.408 [Default:CheckIn:D] Stopped
2019-10-05T17:24:35.408Z,1570296275.408 [Default:CheckIn:E] Running Loop=1
2019-10-05T17:24:35.814Z,1570296275.814 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.254993 min 
2019-10-05T17:24:35.814Z,1570296275.814 [Default:CheckIn:E] Stopped
2019-10-05T17:24:35.815Z,1570296275.815 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T17:24:35.815Z,1570296275.815 [Default:CheckIn] Stopped
2019-10-05T17:24:35.815Z,1570296275.815 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T17:24:35.815Z,1570296275.815 [Default:CheckIn](INFO): Running loop #6
2019-10-05T17:24:35.815Z,1570296275.815 [Default:CheckIn] Running Loop=6
2019-10-05T17:24:35.815Z,1570296275.815 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T17:24:35.815Z,1570296275.815 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T17:24:37.361Z,1570296277.361 [NAL9602](DEBUG): Fix Requested
2019-10-05T17:24:37.765Z,1570296277.765 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172429.00,A,3648.19045,N,12147.17842,W,3.771,221.33,051019,,,D*74
2019-10-05T17:24:37.767Z,1570296277.767 [NAL9602](INFO): GPS fix at 20191005T172429: (36.803174, -121.786307)
2019-10-05T17:24:37.844Z,1570296277.844 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T17:24:37.844Z,1570296277.844 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T17:24:41.069Z,1570296281.069 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191005T171406/Courier0006.lzma
2019-10-05T17:24:43.078Z,1570296283.078 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0006.lzma.bak
2019-10-05T17:24:43.078Z,1570296283.078 [DataOverHttps](INFO): SBD MOMSN=11853197
2019-10-05T17:25:04.464Z,1570296304.464 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20191005T171406/Express0007.lzma
2019-10-05T17:25:06.470Z,1570296306.470 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0007.lzma.bak
2019-10-05T17:25:06.470Z,1570296306.470 [DataOverHttps](INFO): SBD MOMSN=11853200
2019-10-05T17:25:10.031Z,1570296310.031 [NAL9602](INFO): SBD MO Status=2, MOMSN=2638, MT Status=2, MTMSN=0
2019-10-05T17:25:10.031Z,1570296310.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-05T17:25:10.497Z,1570296310.497 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T17:25:10.497Z,1570296310.497 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T17:25:10.497Z,1570296310.497 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T17:25:33.462Z,1570296333.462 [NAL9602](INFO): SBD MO Status=2, MOMSN=2638, MT Status=2, MTMSN=0
2019-10-05T17:25:33.462Z,1570296333.462 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-05T17:25:56.491Z,1570296356.491 [NAL9602](INFO): SBD MO Status=2, MOMSN=2638, MT Status=2, MTMSN=0
2019-10-05T17:25:56.491Z,1570296356.491 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-05T17:26:14.669Z,1570296374.669 [NAL9602](INFO): SBD MO Status=2, MOMSN=2638, MT Status=2, MTMSN=0
2019-10-05T17:26:14.669Z,1570296374.669 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-05T17:26:32.043Z,1570296392.043 [NAL9602](INFO): SBD MO Status=2, MOMSN=2638, MT Status=2, MTMSN=0
2019-10-05T17:26:32.044Z,1570296392.044 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-05T17:27:35.083Z,1570296455.083 [NAL9602](INFO): SBD MO Status=0, MOMSN=2638, MT Status=0, MTMSN=0
2019-10-05T17:27:35.083Z,1570296455.083 [NAL9602](INFO): No messages in MT queue
2019-10-05T17:28:05.774Z,1570296485.774 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T17:30:11.053Z,1570296611.053 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T17:30:11.053Z,1570296611.053 [Default:CheckIn:C.Wait] Stopped
2019-10-05T17:30:11.053Z,1570296611.053 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T17:30:11.054Z,1570296611.054 [Default:CheckIn:D] Running Loop=1
2019-10-05T17:30:11.456Z,1570296611.456 [Default:CheckIn:D] Stopped
2019-10-05T17:30:11.456Z,1570296611.456 [Default:CheckIn:E] Running Loop=1
2019-10-05T17:30:11.857Z,1570296611.857 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.855798 min 
2019-10-05T17:30:11.857Z,1570296611.857 [Default:CheckIn:E] Stopped
2019-10-05T17:30:11.858Z,1570296611.858 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T17:30:11.858Z,1570296611.858 [Default:CheckIn] Stopped
2019-10-05T17:30:11.858Z,1570296611.858 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T17:30:11.858Z,1570296611.858 [Default:CheckIn](INFO): Running loop #7
2019-10-05T17:30:11.858Z,1570296611.858 [Default:CheckIn] Running Loop=7
2019-10-05T17:30:11.858Z,1570296611.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T17:30:11.858Z,1570296611.858 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T17:30:13.449Z,1570296613.449 [NAL9602](DEBUG): Fix Requested
2019-10-05T17:30:13.837Z,1570296613.837 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173005.00,A,3648.15059,N,12147.22422,W,0.058,264.95,051019,,,D*70
2019-10-05T17:30:13.839Z,1570296613.839 [NAL9602](INFO): GPS fix at 20191005T173005: (36.802510, -121.787070)
2019-10-05T17:30:13.876Z,1570296613.876 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T17:30:13.877Z,1570296613.877 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T17:30:24.084Z,1570296624.084 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191005T171406/Courier0009.lzma
2019-10-05T17:30:25.967Z,1570296625.967 [NAL9602](INFO): SBD MO Status=0, MOMSN=2639, MT Status=0, MTMSN=0
2019-10-05T17:30:25.967Z,1570296625.967 [NAL9602](INFO): No messages in MT queue
2019-10-05T17:30:26.090Z,1570296626.090 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0009.lzma.bak
2019-10-05T17:30:26.090Z,1570296626.090 [DataOverHttps](INFO): SBD MOMSN=11853204
2019-10-05T17:30:49.781Z,1570296649.781 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191005T171406/Express0010.lzma
2019-10-05T17:30:51.991Z,1570296651.991 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0010.lzma.bak
2019-10-05T17:30:51.991Z,1570296651.991 [DataOverHttps](INFO): SBD MOMSN=11853207
2019-10-05T17:30:56.666Z,1570296656.666 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T17:30:56.754Z,1570296656.754 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T17:30:56.755Z,1570296656.755 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T17:30:56.755Z,1570296656.755 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T17:35:57.288Z,1570296957.288 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T17:35:57.288Z,1570296957.288 [Default:CheckIn:C.Wait] Stopped
2019-10-05T17:35:57.288Z,1570296957.288 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T17:35:57.297Z,1570296957.297 [Default:CheckIn:D] Running Loop=1
2019-10-05T17:35:57.704Z,1570296957.704 [Default:CheckIn:D] Stopped
2019-10-05T17:35:57.704Z,1570296957.704 [Default:CheckIn:E] Running Loop=1
2019-10-05T17:35:58.116Z,1570296958.116 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.626599 min 
2019-10-05T17:35:58.117Z,1570296958.117 [Default:CheckIn:E] Stopped
2019-10-05T17:35:58.118Z,1570296958.118 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T17:35:58.118Z,1570296958.118 [Default:CheckIn] Stopped
2019-10-05T17:35:58.119Z,1570296958.119 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T17:35:58.120Z,1570296958.120 [Default:CheckIn](INFO): Running loop #8
2019-10-05T17:35:58.121Z,1570296958.121 [Default:CheckIn] Running Loop=8
2019-10-05T17:35:58.122Z,1570296958.122 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T17:35:58.122Z,1570296958.122 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T17:35:59.685Z,1570296959.685 [NAL9602](DEBUG): Fix Requested
2019-10-05T17:36:00.089Z,1570296960.089 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173551.00,A,3648.14992,N,12147.22407,W,0.039,264.95,051019,,,D*7B
2019-10-05T17:36:00.091Z,1570296960.091 [NAL9602](INFO): GPS fix at 20191005T173551: (36.802499, -121.787068)
2019-10-05T17:36:00.124Z,1570296960.124 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T17:36:00.124Z,1570296960.124 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T17:36:01.297Z,1570296961.297 [NAL9602](ERROR): parseGSV uart error: serial buffer full
2019-10-05T17:36:09.120Z,1570296969.120 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191005T171406/Courier0012.lzma
2019-10-05T17:36:11.126Z,1570296971.126 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0012.lzma.bak
2019-10-05T17:36:11.126Z,1570296971.126 [DataOverHttps](INFO): SBD MOMSN=11853211
2019-10-05T17:36:21.896Z,1570296981.896 [NAL9602](INFO): SBD MO Status=2, MOMSN=2640, MT Status=2, MTMSN=0
2019-10-05T17:36:21.896Z,1570296981.896 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-05T17:36:32.521Z,1570296992.521 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191005T171406/Express0013.lzma
2019-10-05T17:36:34.526Z,1570296994.526 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0013.lzma.bak
2019-10-05T17:36:34.527Z,1570296994.527 [DataOverHttps](INFO): SBD MOMSN=11853214
2019-10-05T17:36:39.721Z,1570296999.721 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T17:36:39.721Z,1570296999.721 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T17:36:39.721Z,1570296999.721 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T17:37:14.011Z,1570297034.011 [NAL9602](INFO): SBD MO Status=2, MOMSN=2640, MT Status=2, MTMSN=0
2019-10-05T17:37:14.011Z,1570297034.011 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-05T17:37:54.415Z,1570297074.415 [NAL9602](INFO): SBD MO Status=2, MOMSN=2640, MT Status=2, MTMSN=0
2019-10-05T17:37:54.415Z,1570297074.415 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-05T17:38:17.847Z,1570297097.847 [NAL9602](INFO): SBD MO Status=0, MOMSN=2640, MT Status=0, MTMSN=0
2019-10-05T17:38:17.847Z,1570297097.847 [NAL9602](INFO): No messages in MT queue
2019-10-05T17:38:48.551Z,1570297128.551 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T17:41:40.284Z,1570297300.284 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T17:41:40.284Z,1570297300.284 [Default:CheckIn:C.Wait] Stopped
2019-10-05T17:41:40.284Z,1570297300.284 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T17:41:40.289Z,1570297300.289 [Default:CheckIn:D] Running Loop=1
2019-10-05T17:41:40.696Z,1570297300.696 [Default:CheckIn:D] Stopped
2019-10-05T17:41:40.696Z,1570297300.696 [Default:CheckIn:E] Running Loop=1
2019-10-05T17:41:41.092Z,1570297301.092 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.343136 min 
2019-10-05T17:41:41.113Z,1570297301.113 [Default:CheckIn:E] Stopped
2019-10-05T17:41:41.113Z,1570297301.113 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T17:41:41.113Z,1570297301.113 [Default:CheckIn] Stopped
2019-10-05T17:41:41.113Z,1570297301.113 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T17:41:41.113Z,1570297301.113 [Default:CheckIn](INFO): Running loop #9
2019-10-05T17:41:41.113Z,1570297301.113 [Default:CheckIn] Running Loop=9
2019-10-05T17:41:41.113Z,1570297301.113 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T17:41:41.113Z,1570297301.113 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T17:41:42.687Z,1570297302.687 [NAL9602](DEBUG): Fix Requested
2019-10-05T17:41:43.080Z,1570297303.080 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174134.00,A,3648.14579,N,12147.22402,W,0.136,264.95,051019,,,D*79
2019-10-05T17:41:43.082Z,1570297303.082 [NAL9602](INFO): GPS fix at 20191005T174134: (36.802430, -121.787067)
2019-10-05T17:41:43.129Z,1570297303.129 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T17:41:43.129Z,1570297303.129 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T17:41:44.302Z,1570297304.302 [NAL9602](ERROR): parseGSV uart error: serial buffer full
2019-10-05T17:41:53.280Z,1570297313.280 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191005T171406/Courier0015.lzma
2019-10-05T17:41:56.165Z,1570297316.165 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0015.lzma.bak
2019-10-05T17:41:56.165Z,1570297316.165 [DataOverHttps](INFO): SBD MOMSN=11853223
2019-10-05T17:41:59.235Z,1570297319.235 [NAL9602](INFO): SBD MO Status=0, MOMSN=2641, MT Status=0, MTMSN=0
2019-10-05T17:41:59.236Z,1570297319.236 [NAL9602](INFO): No messages in MT queue
2019-10-05T17:42:15.360Z,1570297335.360 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191005T171406/Express0016.lzma
2019-10-05T17:42:17.366Z,1570297337.366 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0016.lzma.bak
2019-10-05T17:42:17.366Z,1570297337.366 [DataOverHttps](INFO): SBD MOMSN=11853226
2019-10-05T17:42:21.496Z,1570297341.496 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T17:42:21.496Z,1570297341.496 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T17:42:21.496Z,1570297341.496 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T17:42:29.942Z,1570297349.942 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T17:47:22.080Z,1570297642.080 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T17:47:22.080Z,1570297642.080 [Default:CheckIn:C.Wait] Stopped
2019-10-05T17:47:22.080Z,1570297642.080 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T17:47:22.080Z,1570297642.080 [Default:CheckIn:D] Running Loop=1
2019-10-05T17:47:22.480Z,1570297642.480 [Default:CheckIn:D] Stopped
2019-10-05T17:47:22.480Z,1570297642.480 [Default:CheckIn:E] Running Loop=1
2019-10-05T17:47:22.873Z,1570297642.873 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.039535 min 
2019-10-05T17:47:22.873Z,1570297642.873 [Default:CheckIn:E] Stopped
2019-10-05T17:47:22.873Z,1570297642.873 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T17:47:22.874Z,1570297642.874 [Default:CheckIn] Stopped
2019-10-05T17:47:22.874Z,1570297642.874 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T17:47:22.874Z,1570297642.874 [Default:CheckIn](INFO): Running loop #10
2019-10-05T17:47:22.874Z,1570297642.874 [Default:CheckIn] Running Loop=10
2019-10-05T17:47:22.874Z,1570297642.874 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T17:47:22.874Z,1570297642.874 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T17:47:24.465Z,1570297644.465 [NAL9602](DEBUG): Fix Requested
2019-10-05T17:47:24.858Z,1570297644.858 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174716.00,A,3648.14861,N,12147.22444,W,0.097,264.95,051019,,,D*73
2019-10-05T17:47:24.860Z,1570297644.860 [NAL9602](INFO): GPS fix at 20191005T174716: (36.802477, -121.787074)
2019-10-05T17:47:24.903Z,1570297644.903 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T17:47:24.903Z,1570297644.903 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T17:47:26.497Z,1570297646.497 [NAL9602](ERROR): parseGSV uart error: serial buffer full
2019-10-05T17:47:33.908Z,1570297653.908 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191005T171406/Courier0018.lzma
2019-10-05T17:47:35.915Z,1570297655.915 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0018.lzma.bak
2019-10-05T17:47:35.915Z,1570297655.915 [DataOverHttps](INFO): SBD MOMSN=11853235
2019-10-05T17:47:56.068Z,1570297676.068 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191005T171406/Express0019.lzma
2019-10-05T17:47:58.074Z,1570297678.074 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0019.lzma.bak
2019-10-05T17:47:58.075Z,1570297678.075 [DataOverHttps](INFO): SBD MOMSN=11853238
2019-10-05T17:48:02.072Z,1570297682.072 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T17:48:02.072Z,1570297682.072 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T17:48:02.072Z,1570297682.072 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T17:48:15.359Z,1570297695.359 [NAL9602](INFO): SBD MO Status=2, MOMSN=2642, MT Status=2, MTMSN=0
2019-10-05T17:48:15.359Z,1570297695.359 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-05T17:49:20.453Z,1570297760.453 [NAL9602](INFO): SBD MO Status=0, MOMSN=2642, MT Status=0, MTMSN=0
2019-10-05T17:49:20.454Z,1570297760.454 [NAL9602](INFO): No messages in MT queue
2019-10-05T17:49:51.115Z,1570297791.115 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T17:53:02.684Z,1570297982.684 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T17:53:02.684Z,1570297982.684 [Default:CheckIn:C.Wait] Stopped
2019-10-05T17:53:02.684Z,1570297982.684 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T17:53:02.689Z,1570297982.689 [Default:CheckIn:D] Running Loop=1
2019-10-05T17:53:03.092Z,1570297983.092 [Default:CheckIn:D] Stopped
2019-10-05T17:53:03.092Z,1570297983.092 [Default:CheckIn:E] Running Loop=1
2019-10-05T17:53:03.488Z,1570297983.488 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.716402 min 
2019-10-05T17:53:03.488Z,1570297983.488 [Default:CheckIn:E] Stopped
2019-10-05T17:53:03.509Z,1570297983.509 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T17:53:03.509Z,1570297983.509 [Default:CheckIn] Stopped
2019-10-05T17:53:03.509Z,1570297983.509 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T17:53:03.509Z,1570297983.509 [Default:CheckIn](INFO): Running loop #11
2019-10-05T17:53:03.509Z,1570297983.509 [Default:CheckIn] Running Loop=11
2019-10-05T17:53:03.509Z,1570297983.509 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T17:53:03.509Z,1570297983.509 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T17:53:05.073Z,1570297985.073 [NAL9602](DEBUG): Fix Requested
2019-10-05T17:53:05.466Z,1570297985.466 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175257.00,A,3648.15032,N,12147.22393,W,0.136,264.95,051019,,,D*7A
2019-10-05T17:53:05.468Z,1570297985.468 [NAL9602](INFO): GPS fix at 20191005T175257: (36.802505, -121.787065)
2019-10-05T17:53:05.508Z,1570297985.508 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T17:53:05.508Z,1570297985.508 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T17:53:07.493Z,1570297987.493 [NAL9602](ERROR): parseGSV uart error: serial buffer full
2019-10-05T17:53:14.796Z,1570297994.796 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191005T171406/Courier0021.lzma
2019-10-05T17:53:16.802Z,1570297996.802 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0021.lzma.bak
2019-10-05T17:53:16.802Z,1570297996.802 [DataOverHttps](INFO): SBD MOMSN=11853242
2019-10-05T17:53:31.324Z,1570298011.324 [NAL9602](INFO): SBD MO Status=0, MOMSN=2643, MT Status=0, MTMSN=0
2019-10-05T17:53:31.324Z,1570298011.324 [NAL9602](INFO): No messages in MT queue
2019-10-05T17:53:36.848Z,1570298016.848 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191005T171406/Express0022.lzma
2019-10-05T17:53:38.854Z,1570298018.854 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0022.lzma.bak
2019-10-05T17:53:38.854Z,1570298018.854 [DataOverHttps](INFO): SBD MOMSN=11853245
2019-10-05T17:53:42.684Z,1570298022.684 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T17:53:42.689Z,1570298022.689 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T17:53:42.689Z,1570298022.689 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T17:54:02.026Z,1570298042.026 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T17:58:43.260Z,1570298323.260 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T17:58:43.260Z,1570298323.260 [Default:CheckIn:C.Wait] Stopped
2019-10-05T17:58:43.260Z,1570298323.260 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T17:58:43.265Z,1570298323.265 [Default:CheckIn:D] Running Loop=1
2019-10-05T17:58:43.652Z,1570298323.652 [Default:CheckIn:D] Stopped
2019-10-05T17:58:43.652Z,1570298323.652 [Default:CheckIn:E] Running Loop=1
2019-10-05T17:58:44.049Z,1570298324.049 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.392391 min 
2019-10-05T17:58:44.049Z,1570298324.049 [Default:CheckIn:E] Stopped
2019-10-05T17:58:44.050Z,1570298324.050 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T17:58:44.050Z,1570298324.050 [Default:CheckIn] Stopped
2019-10-05T17:58:44.050Z,1570298324.050 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T17:58:44.050Z,1570298324.050 [Default:CheckIn](INFO): Running loop #12
2019-10-05T17:58:44.050Z,1570298324.050 [Default:CheckIn] Running Loop=12
2019-10-05T17:58:44.050Z,1570298324.050 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T17:58:44.050Z,1570298324.050 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T17:58:45.653Z,1570298325.653 [NAL9602](DEBUG): Fix Requested
2019-10-05T17:58:46.048Z,1570298326.048 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175837.00,A,3648.15042,N,12147.22336,W,0.097,0.00,051019,,,A*7D
2019-10-05T17:58:46.050Z,1570298326.050 [NAL9602](INFO): GPS fix at 20191005T175837: (36.802507, -121.787056)
2019-10-05T17:58:46.074Z,1570298326.074 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T17:58:46.074Z,1570298326.074 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T17:58:56.136Z,1570298336.136 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191005T171406/Courier0024.lzma
2019-10-05T17:58:58.142Z,1570298338.142 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0024.lzma.bak
2019-10-05T17:58:58.142Z,1570298338.142 [DataOverHttps](INFO): SBD MOMSN=11853249
2019-10-05T17:59:04.459Z,1570298344.459 [NAL9602](INFO): SBD MO Status=0, MOMSN=2644, MT Status=0, MTMSN=0
2019-10-05T17:59:04.460Z,1570298344.460 [NAL9602](INFO): No messages in MT queue
2019-10-05T17:59:18.068Z,1570298358.068 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20191005T171406/Express0025.lzma
2019-10-05T17:59:20.074Z,1570298360.074 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0025.lzma.bak
2019-10-05T17:59:20.074Z,1570298360.074 [DataOverHttps](INFO): SBD MOMSN=11853252
2019-10-05T17:59:23.896Z,1570298363.896 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T17:59:23.921Z,1570298363.921 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T17:59:23.921Z,1570298363.921 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T17:59:35.165Z,1570298375.165 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T18:04:24.504Z,1570298664.504 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T18:04:24.504Z,1570298664.504 [Default:CheckIn:C.Wait] Stopped
2019-10-05T18:04:24.504Z,1570298664.504 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T18:04:24.525Z,1570298664.525 [Default:CheckIn:D] Running Loop=1
2019-10-05T18:04:24.904Z,1570298664.904 [Default:CheckIn:D] Stopped
2019-10-05T18:04:24.904Z,1570298664.904 [Default:CheckIn:E] Running Loop=1
2019-10-05T18:04:25.320Z,1570298665.320 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.079932 min 
2019-10-05T18:04:25.325Z,1570298665.325 [Default:CheckIn:E] Stopped
2019-10-05T18:04:25.326Z,1570298665.326 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T18:04:25.326Z,1570298665.326 [Default:CheckIn] Stopped
2019-10-05T18:04:25.327Z,1570298665.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T18:04:25.328Z,1570298665.328 [Default:CheckIn](INFO): Running loop #13
2019-10-05T18:04:25.328Z,1570298665.328 [Default:CheckIn] Running Loop=13
2019-10-05T18:04:25.333Z,1570298665.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T18:04:25.333Z,1570298665.333 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T18:04:26.901Z,1570298666.901 [NAL9602](DEBUG): Fix Requested
2019-10-05T18:04:27.296Z,1570298667.296 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180418.00,A,3648.16746,N,12147.20122,W,5.773,47.67,051019,,,A*49
2019-10-05T18:04:27.298Z,1570298667.298 [NAL9602](INFO): GPS fix at 20191005T180418: (36.802791, -121.786687)
2019-10-05T18:04:27.346Z,1570298667.346 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T18:04:27.346Z,1570298667.346 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T18:04:40.295Z,1570298680.295 [NAL9602](INFO): SBD MO Status=0, MOMSN=2645, MT Status=0, MTMSN=0
2019-10-05T18:04:40.295Z,1570298680.295 [NAL9602](INFO): No messages in MT queue
2019-10-05T18:04:40.628Z,1570298680.628 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191005T171406/Courier0027.lzma
2019-10-05T18:04:42.634Z,1570298682.634 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0027.lzma.bak
2019-10-05T18:04:42.634Z,1570298682.634 [DataOverHttps](INFO): SBD MOMSN=11853256
2019-10-05T18:05:04.532Z,1570298704.532 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191005T171406/Express0028.lzma
2019-10-05T18:05:06.538Z,1570298706.538 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0028.lzma.bak
2019-10-05T18:05:06.539Z,1570298706.539 [DataOverHttps](INFO): SBD MOMSN=11853259
2019-10-05T18:05:10.580Z,1570298710.580 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T18:05:10.585Z,1570298710.585 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T18:05:10.585Z,1570298710.585 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T18:05:10.938Z,1570298710.938 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T18:10:11.144Z,1570299011.144 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T18:10:11.144Z,1570299011.144 [Default:CheckIn:C.Wait] Stopped
2019-10-05T18:10:11.144Z,1570299011.144 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T18:10:11.149Z,1570299011.149 [Default:CheckIn:D] Running Loop=1
2019-10-05T18:10:11.569Z,1570299011.569 [Default:CheckIn:D] Stopped
2019-10-05T18:10:11.569Z,1570299011.569 [Default:CheckIn:E] Running Loop=1
2019-10-05T18:10:11.945Z,1570299011.945 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.857674 min 
2019-10-05T18:10:11.945Z,1570299011.945 [Default:CheckIn:E] Stopped
2019-10-05T18:10:11.946Z,1570299011.946 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T18:10:11.946Z,1570299011.946 [Default:CheckIn] Stopped
2019-10-05T18:10:11.946Z,1570299011.946 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T18:10:11.946Z,1570299011.946 [Default:CheckIn](INFO): Running loop #14
2019-10-05T18:10:11.946Z,1570299011.946 [Default:CheckIn] Running Loop=14
2019-10-05T18:10:11.946Z,1570299011.946 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T18:10:11.946Z,1570299011.946 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T18:10:13.545Z,1570299013.545 [NAL9602](DEBUG): Fix Requested
2019-10-05T18:10:13.940Z,1570299013.940 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181005.00,A,3648.40675,N,12147.42839,W,8.184,242.20,051019,,,A*72
2019-10-05T18:10:13.950Z,1570299013.950 [NAL9602](INFO): GPS fix at 20191005T181005: (36.806779, -121.790473)
2019-10-05T18:10:13.977Z,1570299013.977 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T18:10:13.977Z,1570299013.977 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T18:10:22.816Z,1570299022.816 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191005T171406/Courier0030.lzma
2019-10-05T18:10:24.822Z,1570299024.822 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0030.lzma.bak
2019-10-05T18:10:24.822Z,1570299024.822 [DataOverHttps](INFO): SBD MOMSN=11853266
2019-10-05T18:10:29.295Z,1570299029.295 [NAL9602](INFO): SBD MO Status=0, MOMSN=2646, MT Status=0, MTMSN=0
2019-10-05T18:10:29.295Z,1570299029.295 [NAL9602](INFO): No messages in MT queue
2019-10-05T18:10:48.628Z,1570299048.628 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191005T171406/Express0031.lzma
2019-10-05T18:10:50.634Z,1570299050.634 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0031.lzma.bak
2019-10-05T18:10:50.635Z,1570299050.635 [DataOverHttps](INFO): SBD MOMSN=11853270
2019-10-05T18:10:55.184Z,1570299055.184 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T18:10:55.184Z,1570299055.184 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T18:10:55.205Z,1570299055.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T18:10:59.994Z,1570299059.994 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T18:15:50.084Z,1570299350.084 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-05T18:15:55.761Z,1570299355.761 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T18:15:55.761Z,1570299355.761 [Default:CheckIn:C.Wait] Stopped
2019-10-05T18:15:55.761Z,1570299355.761 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T18:15:55.761Z,1570299355.761 [Default:CheckIn:D] Running Loop=1
2019-10-05T18:15:56.173Z,1570299356.173 [Default:CheckIn:D] Stopped
2019-10-05T18:15:56.173Z,1570299356.173 [Default:CheckIn:E] Running Loop=1
2019-10-05T18:15:56.583Z,1570299356.583 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.601082 min 
2019-10-05T18:15:56.583Z,1570299356.583 [Default:CheckIn:E] Stopped
2019-10-05T18:15:56.584Z,1570299356.584 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T18:15:56.584Z,1570299356.584 [Default:CheckIn] Stopped
2019-10-05T18:15:56.584Z,1570299356.584 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T18:15:56.584Z,1570299356.584 [Default:CheckIn](INFO): Running loop #15
2019-10-05T18:15:56.584Z,1570299356.584 [Default:CheckIn] Running Loop=15
2019-10-05T18:15:56.584Z,1570299356.584 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T18:15:56.584Z,1570299356.584 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T18:15:56.949Z,1570299356.949 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-05T18:15:58.157Z,1570299358.157 [NAL9602](DEBUG): Fix Requested
2019-10-05T18:15:58.560Z,1570299358.560 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181550.00,A,3647.61401,N,12149.39859,W,27.505,236.09,051019,,,A*46
2019-10-05T18:15:58.562Z,1570299358.562 [NAL9602](INFO): GPS fix at 20191005T181550: (36.793567, -121.823310)
2019-10-05T18:15:58.624Z,1570299358.624 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T18:15:58.624Z,1570299358.624 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T18:16:07.904Z,1570299367.904 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191005T171406/Courier0033.lzma
2019-10-05T18:16:09.910Z,1570299369.910 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0033.lzma.bak
2019-10-05T18:16:09.910Z,1570299369.910 [DataOverHttps](INFO): SBD MOMSN=11853276
2019-10-05T18:16:13.109Z,1570299373.109 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-05T18:16:25.219Z,1570299385.219 [NAL9602](INFO): SBD MO Status=0, MOMSN=2647, MT Status=0, MTMSN=0
2019-10-05T18:16:25.219Z,1570299385.219 [NAL9602](INFO): No messages in MT queue
2019-10-05T18:16:28.068Z,1570299388.068 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-05T18:16:30.157Z,1570299390.157 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20191005T171406/Express0034.lzma
2019-10-05T18:16:32.162Z,1570299392.162 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0034.lzma.bak
2019-10-05T18:16:32.163Z,1570299392.163 [DataOverHttps](INFO): SBD MOMSN=11853279
2019-10-05T18:16:39.005Z,1570299399.005 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T18:16:39.005Z,1570299399.005 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T18:16:39.005Z,1570299399.005 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T18:16:43.415Z,1570299403.415 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-05T18:16:55.918Z,1570299415.918 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T18:16:58.760Z,1570299418.760 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-05T18:17:14.110Z,1570299434.110 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-05T18:17:29.464Z,1570299449.464 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-05T18:17:44.826Z,1570299464.826 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-05T18:18:00.168Z,1570299480.168 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-05T18:18:21.606Z,1570299501.606 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2019-10-05T18:18:21.609Z,1570299501.609 [BPC1](INFO): Received data from all battery sticks.
2019-10-05T18:21:39.572Z,1570299699.572 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T18:21:39.572Z,1570299699.572 [Default:CheckIn:C.Wait] Stopped
2019-10-05T18:21:39.572Z,1570299699.572 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T18:21:39.593Z,1570299699.593 [Default:CheckIn:D] Running Loop=1
2019-10-05T18:21:39.976Z,1570299699.976 [Default:CheckIn:D] Stopped
2019-10-05T18:21:39.976Z,1570299699.976 [Default:CheckIn:E] Running Loop=1
2019-10-05T18:21:40.381Z,1570299700.381 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.331136 min 
2019-10-05T18:21:40.382Z,1570299700.382 [Default:CheckIn:E] Stopped
2019-10-05T18:21:40.382Z,1570299700.382 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T18:21:40.382Z,1570299700.382 [Default:CheckIn] Stopped
2019-10-05T18:21:40.382Z,1570299700.382 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T18:21:40.382Z,1570299700.382 [Default:CheckIn](INFO): Running loop #16
2019-10-05T18:21:40.382Z,1570299700.382 [Default:CheckIn] Running Loop=16
2019-10-05T18:21:40.382Z,1570299700.382 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T18:21:40.382Z,1570299700.382 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T18:21:41.969Z,1570299701.969 [NAL9602](DEBUG): Fix Requested
2019-10-05T18:21:42.360Z,1570299702.360 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182134.00,A,3646.25914,N,12152.15457,W,28.147,236.50,051019,,,A*4C
2019-10-05T18:21:42.362Z,1570299702.362 [NAL9602](INFO): GPS fix at 20191005T182134: (36.770986, -121.869243)
2019-10-05T18:21:42.386Z,1570299702.386 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T18:21:42.386Z,1570299702.386 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T18:21:52.157Z,1570299712.157 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20191005T171406/Courier0036.lzma
2019-10-05T18:21:54.162Z,1570299714.162 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0036.lzma.bak
2019-10-05T18:21:54.163Z,1570299714.163 [DataOverHttps](INFO): SBD MOMSN=11853284
2019-10-05T18:21:56.903Z,1570299716.903 [NAL9602](INFO): SBD MO Status=0, MOMSN=2648, MT Status=0, MTMSN=0
2019-10-05T18:21:56.903Z,1570299716.903 [NAL9602](INFO): No messages in MT queue
2019-10-05T18:22:13.856Z,1570299733.856 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20191005T171406/Express0037.lzma
2019-10-05T18:22:15.862Z,1570299735.862 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0037.lzma.bak
2019-10-05T18:22:15.863Z,1570299735.863 [DataOverHttps](INFO): SBD MOMSN=11853287
2019-10-05T18:22:19.984Z,1570299739.984 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T18:22:19.989Z,1570299739.989 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T18:22:19.989Z,1570299739.989 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T18:22:27.623Z,1570299747.623 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T18:27:20.568Z,1570300040.568 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T18:27:20.568Z,1570300040.568 [Default:CheckIn:C.Wait] Stopped
2019-10-05T18:27:20.568Z,1570300040.568 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T18:27:20.573Z,1570300040.573 [Default:CheckIn:D] Running Loop=1
2019-10-05T18:27:20.964Z,1570300040.964 [Default:CheckIn:D] Stopped
2019-10-05T18:27:20.964Z,1570300040.964 [Default:CheckIn:E] Running Loop=1
2019-10-05T18:27:21.373Z,1570300041.373 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.014266 min 
2019-10-05T18:27:21.373Z,1570300041.373 [Default:CheckIn:E] Stopped
2019-10-05T18:27:21.373Z,1570300041.373 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T18:27:21.373Z,1570300041.373 [Default:CheckIn] Stopped
2019-10-05T18:27:21.374Z,1570300041.374 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T18:27:21.374Z,1570300041.374 [Default:CheckIn](INFO): Running loop #17
2019-10-05T18:27:21.374Z,1570300041.374 [Default:CheckIn] Running Loop=17
2019-10-05T18:27:21.374Z,1570300041.374 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T18:27:21.374Z,1570300041.374 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T18:27:22.965Z,1570300042.965 [NAL9602](DEBUG): Fix Requested
2019-10-05T18:27:23.346Z,1570300043.346 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182715.00,A,3645.81101,N,12153.02988,W,16.678,250.67,051019,,,A*42
2019-10-05T18:27:23.348Z,1570300043.348 [NAL9602](INFO): GPS fix at 20191005T182715: (36.763517, -121.883831)
2019-10-05T18:27:23.393Z,1570300043.393 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T18:27:23.393Z,1570300043.393 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T18:27:32.772Z,1570300052.772 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191005T171406/Courier0039.lzma
2019-10-05T18:27:34.778Z,1570300054.778 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0039.lzma.bak
2019-10-05T18:27:34.778Z,1570300054.778 [DataOverHttps](INFO): SBD MOMSN=11853292
2019-10-05T18:27:39.511Z,1570300059.511 [NAL9602](INFO): SBD MO Status=0, MOMSN=2649, MT Status=0, MTMSN=0
2019-10-05T18:27:39.511Z,1570300059.511 [NAL9602](INFO): No messages in MT queue
2019-10-05T18:27:54.596Z,1570300074.596 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20191005T171406/Express0040.lzma
2019-10-05T18:27:56.602Z,1570300076.602 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0040.lzma.bak
2019-10-05T18:27:56.603Z,1570300076.603 [DataOverHttps](INFO): SBD MOMSN=11853295
2019-10-05T18:28:00.562Z,1570300080.562 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T18:28:00.562Z,1570300080.562 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T18:28:00.563Z,1570300080.563 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T18:28:10.208Z,1570300090.208 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T18:29:21.610Z,1570300161.610 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.240131
2019-10-05T18:29:32.101Z,1570300172.101 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003322
2019-10-05T18:33:01.158Z,1570300381.158 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T18:33:01.158Z,1570300381.158 [Default:CheckIn:C.Wait] Stopped
2019-10-05T18:33:01.159Z,1570300381.159 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T18:33:01.159Z,1570300381.159 [Default:CheckIn:D] Running Loop=1
2019-10-05T18:33:01.549Z,1570300381.549 [Default:CheckIn:D] Stopped
2019-10-05T18:33:01.549Z,1570300381.549 [Default:CheckIn:E] Running Loop=1
2019-10-05T18:33:01.957Z,1570300381.957 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.690682 min 
2019-10-05T18:33:01.957Z,1570300381.957 [Default:CheckIn:E] Stopped
2019-10-05T18:33:01.957Z,1570300381.957 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T18:33:01.957Z,1570300381.957 [Default:CheckIn] Stopped
2019-10-05T18:33:01.957Z,1570300381.957 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T18:33:01.957Z,1570300381.957 [Default:CheckIn](INFO): Running loop #18
2019-10-05T18:33:01.957Z,1570300381.957 [Default:CheckIn] Running Loop=18
2019-10-05T18:33:01.958Z,1570300381.958 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T18:33:01.958Z,1570300381.958 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T18:33:03.539Z,1570300383.539 [NAL9602](DEBUG): Fix Requested
2019-10-05T18:33:03.932Z,1570300383.932 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183255.00,A,3645.06997,N,12155.73185,W,23.346,260.67,051019,,,A*42
2019-10-05T18:33:03.934Z,1570300383.934 [NAL9602](INFO): GPS fix at 20191005T183255: (36.751166, -121.928864)
2019-10-05T18:33:03.975Z,1570300383.975 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T18:33:03.975Z,1570300383.975 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T18:33:07.440Z,1570300387.440 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191005T171406/Courier0042.lzma
2019-10-05T18:33:11.458Z,1570300391.458 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Courier0042.lzma.bak
2019-10-05T18:33:11.458Z,1570300391.458 [DataOverHttps](INFO): SBD MOMSN=11853305
2019-10-05T18:33:32.337Z,1570300412.337 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20191005T171406/Express0043.lzma
2019-10-05T18:33:34.342Z,1570300414.342 [DataOverHttps](INFO): Moved sent file to Logs/20191005T171406/Express0043.lzma.bak
2019-10-05T18:33:34.343Z,1570300414.343 [DataOverHttps](INFO): SBD MOMSN=11853308
2019-10-05T18:33:38.320Z,1570300418.320 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T18:33:38.320Z,1570300418.320 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T18:33:38.320Z,1570300418.320 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T18:33:38.679Z,1570300418.679 [NAL9602](INFO): SBD MO Status=0, MOMSN=2650, MT Status=0, MTMSN=0
2019-10-05T18:33:38.680Z,1570300418.680 [NAL9602](INFO): No messages in MT queue
2019-10-05T18:34:09.384Z,1570300449.384 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T18:36:26.793Z,1570300586.793 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-05T18:36:37.738Z,1570300597.738 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.014155
CHAN A1 (24V): 0.001162
CHAN A2 (12V): -0.004828
CHAN A3 (5V): -0.002440
CHAN B0 (3.3V): -0.001254
CHAN B1 (3.15aV): -0.001350
CHAN B2 (3.15bV): -0.001368
CHAN B3 (GND): -0.000139
OPEN: 0.004932
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-05T18:37:54.406Z,1570300674.406 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:38:01.434Z,1570300681.434 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.254791
2019-10-05T18:38:38.888Z,1570300718.888 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T18:38:38.889Z,1570300718.889 [Default:CheckIn:C.Wait] Stopped
2019-10-05T18:38:38.889Z,1570300718.889 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T18:38:38.889Z,1570300718.889 [Default:CheckIn:D] Running Loop=1
2019-10-05T18:38:39.302Z,1570300719.302 [Default:CheckIn:D] Stopped
2019-10-05T18:38:39.302Z,1570300719.302 [Default:CheckIn:E] Running Loop=1
2019-10-05T18:38:39.707Z,1570300719.707 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.319906 min 
2019-10-05T18:38:39.708Z,1570300719.708 [Default:CheckIn:E] Stopped
2019-10-05T18:38:39.708Z,1570300719.708 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T18:38:39.708Z,1570300719.708 [Default:CheckIn] Stopped
2019-10-05T18:38:39.708Z,1570300719.708 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T18:38:39.708Z,1570300719.708 [Default:CheckIn](INFO): Running loop #19
2019-10-05T18:38:39.708Z,1570300719.708 [Default:CheckIn] Running Loop=19
2019-10-05T18:38:39.708Z,1570300719.708 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T18:38:39.709Z,1570300719.709 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T18:38:41.305Z,1570300721.305 [NAL9602](DEBUG): Fix Requested
2019-10-05T18:38:41.700Z,1570300721.700 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183833.00,A,3644.57661,N,12157.62293,W,1.147,143.99,051019,,,D*78
2019-10-05T18:38:41.702Z,1570300721.702 [NAL9602](INFO): GPS fix at 20191005T183833: (36.742944, -121.960382)
2019-10-05T18:38:41.755Z,1570300721.755 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T18:38:41.755Z,1570300721.755 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T18:38:59.260Z,1570300739.260 [NAL9602](INFO): SBD MO Status=1, MOMSN=2651, MT Status=0, MTMSN=0
2019-10-05T18:38:59.317Z,1570300739.317 [NAL9602](INFO): Sent 62 bytes from file Logs/20191005T171406/Courier0045.lzma
2019-10-05T18:38:59.317Z,1570300739.317 [NAL9602](INFO): Packets left to send: 0
2019-10-05T18:39:04.705Z,1570300744.705 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:39:09.450Z,1570300749.450 [NAL9602](INFO): SBD MO Status=1, MOMSN=2652, MT Status=0, MTMSN=0
2019-10-05T18:39:09.501Z,1570300749.501 [NAL9602](INFO): Sent 332 bytes from file Logs/20191005T171406/Express0046.lzma
2019-10-05T18:39:09.501Z,1570300749.501 [NAL9602](INFO): Packets left to send: 1
2019-10-05T18:39:24.483Z,1570300764.483 [NAL9602](INFO): SBD MO Status=1, MOMSN=2653, MT Status=0, MTMSN=0
2019-10-05T18:39:24.541Z,1570300764.541 [NAL9602](INFO): Sent 14 bytes from file Logs/20191005T171406/Express0046.lzma
2019-10-05T18:39:24.541Z,1570300764.541 [NAL9602](INFO): Packets left to send: 0
2019-10-05T18:39:39.857Z,1570300779.857 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:39:44.139Z,1570300784.139 [NAL9602](INFO): SBD MO Status=0, MOMSN=2654, MT Status=0, MTMSN=0
2019-10-05T18:39:44.237Z,1570300784.237 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T18:39:44.238Z,1570300784.238 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T18:39:44.238Z,1570300784.238 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T18:40:14.844Z,1570300814.844 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T18:40:15.013Z,1570300815.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:40:50.153Z,1570300850.153 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:41:25.301Z,1570300885.301 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:42:00.441Z,1570300920.441 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:42:35.581Z,1570300955.581 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:42:49.837Z,1570300969.837 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003344
2019-10-05T18:43:50.457Z,1570301030.457 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.623363
2019-10-05T18:44:21.581Z,1570301061.581 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:44:44.736Z,1570301084.736 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T18:44:44.737Z,1570301084.737 [Default:CheckIn:C.Wait] Stopped
2019-10-05T18:44:44.737Z,1570301084.737 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T18:44:44.737Z,1570301084.737 [Default:CheckIn:D] Running Loop=1
2019-10-05T18:44:45.150Z,1570301085.150 [Default:CheckIn:D] Stopped
2019-10-05T18:44:45.150Z,1570301085.150 [Default:CheckIn:E] Running Loop=1
2019-10-05T18:44:45.553Z,1570301085.553 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.417367 min 
2019-10-05T18:44:45.553Z,1570301085.553 [Default:CheckIn:E] Stopped
2019-10-05T18:44:45.554Z,1570301085.554 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T18:44:45.554Z,1570301085.554 [Default:CheckIn] Stopped
2019-10-05T18:44:45.554Z,1570301085.554 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T18:44:45.554Z,1570301085.554 [Default:CheckIn](INFO): Running loop #20
2019-10-05T18:44:45.554Z,1570301085.554 [Default:CheckIn] Running Loop=20
2019-10-05T18:44:45.554Z,1570301085.554 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T18:44:45.554Z,1570301085.554 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T18:44:47.149Z,1570301087.149 [NAL9602](DEBUG): Fix Requested
2019-10-05T18:44:47.548Z,1570301087.548 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184439.00,A,3644.18307,N,12159.23184,W,1.089,175.86,051019,,,A*74
2019-10-05T18:44:47.550Z,1570301087.550 [NAL9602](INFO): GPS fix at 20191005T184439: (36.736384, -121.987197)
2019-10-05T18:44:47.600Z,1570301087.600 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T18:44:47.600Z,1570301087.600 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T18:44:49.570Z,1570301089.570 [NAL9602](ERROR): parseGSV uart error: serial buffer full
2019-10-05T18:44:56.721Z,1570301096.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:45:01.455Z,1570301101.455 [NAL9602](INFO): SBD MO Status=1, MOMSN=2655, MT Status=0, MTMSN=0
2019-10-05T18:45:01.517Z,1570301101.517 [NAL9602](INFO): Sent 62 bytes from file Logs/20191005T171406/Courier0048.lzma
2019-10-05T18:45:01.517Z,1570301101.517 [NAL9602](INFO): Packets left to send: 0
2019-10-05T18:45:13.199Z,1570301113.199 [NAL9602](INFO): SBD MO Status=1, MOMSN=2656, MT Status=0, MTMSN=0
2019-10-05T18:45:13.253Z,1570301113.253 [NAL9602](INFO): Sent 137 bytes from file Logs/20191005T171406/Express0049.lzma
2019-10-05T18:45:13.253Z,1570301113.253 [NAL9602](INFO): Packets left to send: 0
2019-10-05T18:45:27.412Z,1570301127.412 [NAL9602](INFO): SBD MO Status=2, MOMSN=2657, MT Status=2, MTMSN=0
2019-10-05T18:45:27.412Z,1570301127.412 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-05T18:45:29.458Z,1570301129.458 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -32.00 mm (2 active estimators).
2019-10-05T18:45:31.861Z,1570301131.861 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:45:34.636Z,1570301134.636 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2019-10-05T18:45:38.747Z,1570301138.747 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2019-10-05T18:45:38.768Z,1570301138.768 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 3.70 mm.
2019-10-05T18:45:43.063Z,1570301143.063 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2019-10-05T18:45:47.178Z,1570301147.178 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2019-10-05T18:46:07.005Z,1570301167.005 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:46:10.262Z,1570301170.262 [NAL9602](INFO): SBD MO Status=0, MOMSN=2657, MT Status=0, MTMSN=0
2019-10-05T18:46:10.371Z,1570301170.371 [Default:CheckIn:Read_Iridium] Stopped
2019-10-05T18:46:10.371Z,1570301170.371 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-05T18:46:10.371Z,1570301170.371 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-05T18:46:40.966Z,1570301200.966 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-05T18:46:42.145Z,1570301202.145 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:47:17.289Z,1570301237.289 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:47:52.429Z,1570301272.429 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:48:27.573Z,1570301307.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:49:02.721Z,1570301342.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:49:37.861Z,1570301377.861 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:50:13.001Z,1570301413.001 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:50:48.141Z,1570301448.141 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:51:10.874Z,1570301470.874 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-05T18:51:10.874Z,1570301470.874 [Default:CheckIn:C.Wait] Stopped
2019-10-05T18:51:10.874Z,1570301470.874 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-05T18:51:10.875Z,1570301470.875 [Default:CheckIn:D] Running Loop=1
2019-10-05T18:51:11.286Z,1570301471.286 [Default:CheckIn:D] Stopped
2019-10-05T18:51:11.287Z,1570301471.287 [Default:CheckIn:E] Running Loop=1
2019-10-05T18:51:11.698Z,1570301471.698 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.852970 min 
2019-10-05T18:51:11.698Z,1570301471.698 [Default:CheckIn:E] Stopped
2019-10-05T18:51:11.698Z,1570301471.698 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-05T18:51:11.698Z,1570301471.698 [Default:CheckIn] Stopped
2019-10-05T18:51:11.698Z,1570301471.698 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-05T18:51:11.698Z,1570301471.698 [Default:CheckIn](INFO): Running loop #21
2019-10-05T18:51:11.698Z,1570301471.698 [Default:CheckIn] Running Loop=21
2019-10-05T18:51:11.698Z,1570301471.698 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-05T18:51:11.699Z,1570301471.699 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-05T18:51:13.289Z,1570301473.289 [NAL9602](DEBUG): Fix Requested
2019-10-05T18:51:13.673Z,1570301473.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185105.00,A,3644.13958,N,12159.19875,W,0.991,177.32,051019,,,A*76
2019-10-05T18:51:13.675Z,1570301473.675 [NAL9602](INFO): GPS fix at 20191005T185105: (36.735660, -121.986646)
2019-10-05T18:51:13.698Z,1570301473.698 [Default:CheckIn:Read_GPS] Stopped
2019-10-05T18:51:13.698Z,1570301473.698 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-05T18:51:15.293Z,1570301475.293 [NAL9602](ERROR): parseGSV uart error: serial buffer full
2019-10-05T18:51:23.281Z,1570301483.281 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-10-05T18:51:32.023Z,1570301492.023 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=2658, MT Status=1, MTMSN=100
2019-10-05T18:51:32.073Z,1570301492.073 [NAL9602](INFO): Sent 62 bytes from file Logs/20191005T171406/Courier0051.lzma
2019-10-05T18:51:32.073Z,1570301492.073 [NAL9602](INFO): Packets left to send: 0
2019-10-05T18:51:32.509Z,1570301492.509 [NAL9602](INFO): Received command:restart logs