2020-09-08T23:09:02.796Z,1599606542.796 [CommandLine](IMPORTANT): got command restart logs 2020-09-08T23:09:04.059Z,1599606544.059 [DataOverHttps](INFO): Moved sent file to Logs/20200908T191429/Express0065.lzma.bak 2020-09-08T23:09:04.059Z,1599606544.059 [DataOverHttps](INFO): SBD MOMSN=12647303 2020-09-08T23:09:22.916Z,1599606562.916 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200908T230813/Courier0000.lzma 2020-09-08T23:09:23.917Z,1599606563.917 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230813/Courier0000.lzma.bak 2020-09-08T23:09:23.918Z,1599606563.918 [DataOverHttps](INFO): SBD MOMSN=12647307 2020-09-08T23:09:39.568Z,1599606579.568 [DataOverHttps](INFO): Sending 363 bytes from file Logs/20200908T230813/Express0001.lzma 2020-09-08T23:09:40.570Z,1599606580.570 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230813/Express0001.lzma.bak 2020-09-08T23:09:40.570Z,1599606580.570 [DataOverHttps](INFO): SBD MOMSN=12647310 2020-09-08T23:09:42.311Z,1599606582.311 [Default:CheckIn:Read_Iridium] Stopped 2020-09-08T23:09:42.311Z,1599606582.311 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-08T23:09:42.311Z,1599606582.311 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-08T23:14:42.975Z,1599606882.975 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-08T23:14:42.975Z,1599606882.975 [Default:CheckIn:C.Wait] Stopped 2020-09-08T23:14:42.975Z,1599606882.975 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-08T23:14:42.976Z,1599606882.976 [Default:CheckIn:D] Running Loop=1 2020-09-08T23:14:43.326Z,1599606883.326 [Default:CheckIn:D] Stopped 2020-09-08T23:14:43.326Z,1599606883.326 [Default:CheckIn:E] Running Loop=1 2020-09-08T23:14:43.711Z,1599606883.711 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.381970 min 2020-09-08T23:14:43.711Z,1599606883.711 [Default:CheckIn:E] Stopped 2020-09-08T23:14:43.711Z,1599606883.711 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-08T23:14:43.711Z,1599606883.711 [Default:CheckIn] Stopped 2020-09-08T23:14:43.711Z,1599606883.711 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-08T23:14:43.713Z,1599606883.713 [Default:CheckIn](INFO): Running loop #9 2020-09-08T23:14:43.714Z,1599606883.714 [Default:CheckIn] Running Loop=9 2020-09-08T23:14:43.714Z,1599606883.714 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-08T23:14:43.714Z,1599606883.714 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-08T23:14:45.723Z,1599606885.723 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231440.00,A,3648.38029,N,12147.11928,W,2.896,187.86,080920,,,D*74 2020-09-08T23:14:45.725Z,1599606885.725 [NAL9602](INFO): GPS fix at 20200908T231440: (36.806338, -121.785321) 2020-09-08T23:14:45.736Z,1599606885.736 [Default:CheckIn:Read_GPS] Stopped 2020-09-08T23:14:45.736Z,1599606885.736 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-08T23:14:52.924Z,1599606892.924 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20200908T230902/Courier0003.lzma 2020-09-08T23:14:53.925Z,1599606893.925 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0003.lzma.bak 2020-09-08T23:14:53.926Z,1599606893.926 [DataOverHttps](INFO): SBD MOMSN=12647327 2020-09-08T23:15:08.757Z,1599606908.757 [NAL9602](INFO): SBD MO Status=0, MOMSN=9587, MT Status=0, MTMSN=0 2020-09-08T23:15:08.757Z,1599606908.757 [NAL9602](INFO): No messages in MT queue 2020-09-08T23:15:09.560Z,1599606909.560 [DataOverHttps](INFO): Sending 394 bytes from file Logs/20200908T230902/Express0004.lzma 2020-09-08T23:15:10.562Z,1599606910.562 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0004.lzma.bak 2020-09-08T23:15:10.562Z,1599606910.562 [DataOverHttps](INFO): SBD MOMSN=12647331 2020-09-08T23:15:12.402Z,1599606912.402 [Default:CheckIn:Read_Iridium] Stopped 2020-09-08T23:15:12.402Z,1599606912.402 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-08T23:15:12.402Z,1599606912.402 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-08T23:15:39.447Z,1599606939.447 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-08T23:20:12.974Z,1599607212.974 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-08T23:20:12.974Z,1599607212.974 [Default:CheckIn:C.Wait] Stopped 2020-09-08T23:20:12.974Z,1599607212.974 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-08T23:20:12.975Z,1599607212.975 [Default:CheckIn:D] Running Loop=1 2020-09-08T23:20:13.374Z,1599607213.374 [Default:CheckIn:D] Stopped 2020-09-08T23:20:13.374Z,1599607213.374 [Default:CheckIn:E] Running Loop=1 2020-09-08T23:20:13.786Z,1599607213.786 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.882780 min 2020-09-08T23:20:13.786Z,1599607213.786 [Default:CheckIn:E] Stopped 2020-09-08T23:20:13.786Z,1599607213.786 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-08T23:20:13.786Z,1599607213.786 [Default:CheckIn] Stopped 2020-09-08T23:20:13.786Z,1599607213.786 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-08T23:20:13.787Z,1599607213.787 [Default:CheckIn](INFO): Running loop #10 2020-09-08T23:20:13.787Z,1599607213.787 [Default:CheckIn] Running Loop=10 2020-09-08T23:20:13.787Z,1599607213.787 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-08T23:20:13.787Z,1599607213.787 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-08T23:20:15.791Z,1599607215.791 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232010.00,A,3648.30912,N,12147.14576,W,0.039,346.62,080920,,,D*77 2020-09-08T23:20:15.793Z,1599607215.793 [NAL9602](INFO): GPS fix at 20200908T232010: (36.805152, -121.785763) 2020-09-08T23:20:15.803Z,1599607215.803 [Default:CheckIn:Read_GPS] Stopped 2020-09-08T23:20:15.803Z,1599607215.803 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-08T23:20:22.943Z,1599607222.943 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T230902/Courier0006.lzma 2020-09-08T23:20:23.945Z,1599607223.945 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0006.lzma.bak 2020-09-08T23:20:23.946Z,1599607223.946 [DataOverHttps](INFO): SBD MOMSN=12647343 2020-09-08T23:20:41.639Z,1599607241.639 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20200908T230902/Express0007.lzma 2020-09-08T23:20:42.641Z,1599607242.641 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0007.lzma.bak 2020-09-08T23:20:42.642Z,1599607242.642 [DataOverHttps](INFO): SBD MOMSN=12647346 2020-09-08T23:20:44.502Z,1599607244.502 [Default:CheckIn:Read_Iridium] Stopped 2020-09-08T23:20:44.502Z,1599607244.502 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-08T23:20:44.502Z,1599607244.502 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-08T23:21:26.106Z,1599607286.106 [NAL9602](INFO): SBD MO Status=0, MOMSN=9588, MT Status=0, MTMSN=0 2020-09-08T23:21:26.106Z,1599607286.106 [NAL9602](INFO): No messages in MT queue 2020-09-08T23:21:56.809Z,1599607316.809 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-08T23:25:45.091Z,1599607545.091 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-08T23:25:45.091Z,1599607545.091 [Default:CheckIn:C.Wait] Stopped 2020-09-08T23:25:45.091Z,1599607545.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-08T23:25:45.091Z,1599607545.091 [Default:CheckIn:D] Running Loop=1 2020-09-08T23:25:45.477Z,1599607545.477 [Default:CheckIn:D] Stopped 2020-09-08T23:25:45.477Z,1599607545.477 [Default:CheckIn:E] Running Loop=1 2020-09-08T23:25:45.883Z,1599607545.883 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.417826 min 2020-09-08T23:25:45.883Z,1599607545.883 [Default:CheckIn:E] Stopped 2020-09-08T23:25:45.883Z,1599607545.883 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-08T23:25:45.883Z,1599607545.883 [Default:CheckIn] Stopped 2020-09-08T23:25:45.883Z,1599607545.883 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-08T23:25:45.883Z,1599607545.883 [Default:CheckIn](INFO): Running loop #11 2020-09-08T23:25:45.883Z,1599607545.883 [Default:CheckIn] Running Loop=11 2020-09-08T23:25:45.884Z,1599607545.884 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-08T23:25:45.884Z,1599607545.884 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-08T23:25:47.901Z,1599607547.901 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232542.00,A,3648.30805,N,12147.14566,W,0.019,346.62,080920,,,D*71 2020-09-08T23:25:47.903Z,1599607547.903 [NAL9602](INFO): GPS fix at 20200908T232542: (36.805134, -121.785761) 2020-09-08T23:25:47.913Z,1599607547.913 [Default:CheckIn:Read_GPS] Stopped 2020-09-08T23:25:47.913Z,1599607547.913 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-08T23:25:55.079Z,1599607555.079 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T230902/Courier0009.lzma 2020-09-08T23:25:56.081Z,1599607556.081 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0009.lzma.bak 2020-09-08T23:25:56.082Z,1599607556.082 [DataOverHttps](INFO): SBD MOMSN=12647357 2020-09-08T23:26:03.650Z,1599607563.650 [NAL9602](INFO): SBD MO Status=0, MOMSN=9589, MT Status=0, MTMSN=0 2020-09-08T23:26:03.650Z,1599607563.650 [NAL9602](INFO): No messages in MT queue 2020-09-08T23:26:11.763Z,1599607571.763 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20200908T230902/Express0010.lzma 2020-09-08T23:26:12.766Z,1599607572.766 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0010.lzma.bak 2020-09-08T23:26:12.766Z,1599607572.766 [DataOverHttps](INFO): SBD MOMSN=12647360 2020-09-08T23:26:14.566Z,1599607574.566 [Default:CheckIn:Read_Iridium] Stopped 2020-09-08T23:26:14.567Z,1599607574.567 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-08T23:26:14.567Z,1599607574.567 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-08T23:26:34.353Z,1599607594.353 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-08T23:28:43.629Z,1599607723.629 [DataOverHttps](IMPORTANT): SBD MTMSN=20200908T232838 2020-09-08T23:28:51.123Z,1599607731.123 [DataOverHttps](INFO): Received command:Strobe off 2020-09-08T23:28:51.208Z,1599607731.208 [CommandLine](IMPORTANT): got command strobe off 2020-09-08T23:28:51.209Z,1599607731.209 [CommandLine](IMPORTANT): Deactivating strobe 2020-09-08T23:31:15.153Z,1599607875.153 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-08T23:31:15.153Z,1599607875.153 [Default:CheckIn:C.Wait] Stopped 2020-09-08T23:31:15.153Z,1599607875.153 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-08T23:31:15.153Z,1599607875.153 [Default:CheckIn:D] Running Loop=1 2020-09-08T23:31:15.559Z,1599607875.559 [Default:CheckIn:D] Stopped 2020-09-08T23:31:15.560Z,1599607875.560 [Default:CheckIn:E] Running Loop=1 2020-09-08T23:31:15.976Z,1599607875.976 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.919198 min 2020-09-08T23:31:15.976Z,1599607875.976 [Default:CheckIn:E] Stopped 2020-09-08T23:31:15.976Z,1599607875.976 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-08T23:31:15.976Z,1599607875.976 [Default:CheckIn] Stopped 2020-09-08T23:31:15.976Z,1599607875.976 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-08T23:31:15.977Z,1599607875.977 [Default:CheckIn](INFO): Running loop #12 2020-09-08T23:31:15.977Z,1599607875.977 [Default:CheckIn] Running Loop=12 2020-09-08T23:31:15.977Z,1599607875.977 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-08T23:31:15.977Z,1599607875.977 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-08T23:31:17.979Z,1599607877.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233112.00,A,3648.11805,N,12147.21278,W,2.566,261.66,080920,,,D*73 2020-09-08T23:31:17.981Z,1599607877.981 [NAL9602](INFO): GPS fix at 20200908T233112: (36.801968, -121.786880) 2020-09-08T23:31:17.991Z,1599607877.991 [Default:CheckIn:Read_GPS] Stopped 2020-09-08T23:31:17.991Z,1599607877.991 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-08T23:31:25.707Z,1599607885.707 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200908T230902/Courier0012.lzma 2020-09-08T23:31:26.710Z,1599607886.710 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0012.lzma.bak 2020-09-08T23:31:26.710Z,1599607886.710 [DataOverHttps](INFO): SBD MOMSN=12647374 2020-09-08T23:31:33.753Z,1599607893.753 [NAL9602](INFO): SBD MO Status=2, MOMSN=9590, MT Status=2, MTMSN=0 2020-09-08T23:31:33.753Z,1599607893.753 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-09-08T23:31:42.219Z,1599607902.219 [NAL9602](INFO): SBD MO Status=0, MOMSN=9590, MT Status=0, MTMSN=0 2020-09-08T23:31:42.219Z,1599607902.219 [NAL9602](INFO): No messages in MT queue 2020-09-08T23:31:43.152Z,1599607903.152 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200908T230902/Courier0015.lzma 2020-09-08T23:31:44.154Z,1599607904.154 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0015.lzma.bak 2020-09-08T23:31:44.154Z,1599607904.154 [DataOverHttps](INFO): SBD MOMSN=12647377 2020-09-08T23:31:59.901Z,1599607919.901 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20200908T230902/Express0013.lzma 2020-09-08T23:32:00.901Z,1599607920.901 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0013.lzma.bak 2020-09-08T23:32:00.902Z,1599607920.902 [DataOverHttps](INFO): SBD MOMSN=12647379 2020-09-08T23:32:12.917Z,1599607932.917 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-08T23:32:16.559Z,1599607936.559 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200908T230902/Express0016.lzma 2020-09-08T23:32:17.561Z,1599607937.561 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0016.lzma.bak 2020-09-08T23:32:17.562Z,1599607937.562 [DataOverHttps](INFO): SBD MOMSN=12647385 2020-09-08T23:32:19.403Z,1599607939.403 [Default:CheckIn:Read_Iridium] Stopped 2020-09-08T23:32:19.403Z,1599607939.403 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-08T23:32:19.403Z,1599607939.403 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-08T23:37:20.012Z,1599608240.012 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-08T23:37:20.013Z,1599608240.013 [Default:CheckIn:C.Wait] Stopped 2020-09-08T23:37:20.013Z,1599608240.013 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-08T23:37:20.013Z,1599608240.013 [Default:CheckIn:D] Running Loop=1 2020-09-08T23:37:20.423Z,1599608240.423 [Default:CheckIn:D] Stopped 2020-09-08T23:37:20.423Z,1599608240.423 [Default:CheckIn:E] Running Loop=1 2020-09-08T23:37:20.826Z,1599608240.826 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.000260 min 2020-09-08T23:37:20.826Z,1599608240.826 [Default:CheckIn:E] Stopped 2020-09-08T23:37:20.826Z,1599608240.826 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-08T23:37:20.826Z,1599608240.826 [Default:CheckIn] Stopped 2020-09-08T23:37:20.826Z,1599608240.826 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-08T23:37:20.826Z,1599608240.826 [Default:CheckIn](INFO): Running loop #13 2020-09-08T23:37:20.826Z,1599608240.826 [Default:CheckIn] Running Loop=13 2020-09-08T23:37:20.826Z,1599608240.826 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-08T23:37:20.827Z,1599608240.827 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-08T23:37:22.839Z,1599608242.839 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233717.00,A,3648.12248,N,12147.22929,W,0.019,284.11,080920,,,D*78 2020-09-08T23:37:22.841Z,1599608242.841 [NAL9602](INFO): GPS fix at 20200908T233717: (36.802041, -121.787155) 2020-09-08T23:37:22.851Z,1599608242.851 [Default:CheckIn:Read_GPS] Stopped 2020-09-08T23:37:22.851Z,1599608242.851 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-08T23:37:30.471Z,1599608250.471 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T230902/Courier0018.lzma 2020-09-08T23:37:31.474Z,1599608251.474 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0018.lzma.bak 2020-09-08T23:37:31.474Z,1599608251.474 [DataOverHttps](INFO): SBD MOMSN=12647395 2020-09-08T23:37:47.147Z,1599608267.147 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20200908T230902/Express0019.lzma 2020-09-08T23:37:48.150Z,1599608268.150 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0019.lzma.bak 2020-09-08T23:37:48.150Z,1599608268.150 [DataOverHttps](INFO): SBD MOMSN=12647398 2020-09-08T23:37:49.935Z,1599608269.935 [Default:CheckIn:Read_Iridium] Stopped 2020-09-08T23:37:49.935Z,1599608269.935 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-08T23:37:49.935Z,1599608269.935 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-08T23:38:47.270Z,1599608327.270 [NAL9602](INFO): SBD MO Status=2, MOMSN=9591, MT Status=2, MTMSN=0 2020-09-08T23:38:47.270Z,1599608327.270 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-09-08T23:39:17.978Z,1599608357.978 [NAL9602](INFO): SBD MO Status=0, MOMSN=9591, MT Status=0, MTMSN=0 2020-09-08T23:39:17.978Z,1599608357.978 [NAL9602](INFO): No messages in MT queue 2020-09-08T23:39:48.681Z,1599608388.681 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-08T23:42:50.503Z,1599608570.503 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-08T23:42:50.503Z,1599608570.503 [Default:CheckIn:C.Wait] Stopped 2020-09-08T23:42:50.503Z,1599608570.503 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-08T23:42:50.525Z,1599608570.525 [Default:CheckIn:D] Running Loop=1 2020-09-08T23:42:50.906Z,1599608570.906 [Default:CheckIn:D] Stopped 2020-09-08T23:42:50.906Z,1599608570.906 [Default:CheckIn:E] Running Loop=1 2020-09-08T23:42:51.322Z,1599608571.322 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.508317 min 2020-09-08T23:42:51.322Z,1599608571.322 [Default:CheckIn:E] Stopped 2020-09-08T23:42:51.322Z,1599608571.322 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-08T23:42:51.322Z,1599608571.322 [Default:CheckIn] Stopped 2020-09-08T23:42:51.323Z,1599608571.323 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-08T23:42:51.323Z,1599608571.323 [Default:CheckIn](INFO): Running loop #14 2020-09-08T23:42:51.323Z,1599608571.323 [Default:CheckIn] Running Loop=14 2020-09-08T23:42:51.323Z,1599608571.323 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-08T23:42:51.323Z,1599608571.323 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-08T23:42:53.328Z,1599608573.328 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234248.00,A,3648.12153,N,12147.23063,W,0.778,262.16,080920,,,D*70 2020-09-08T23:42:53.330Z,1599608573.330 [NAL9602](INFO): GPS fix at 20200908T234248: (36.802025, -121.787177) 2020-09-08T23:42:53.340Z,1599608573.340 [Default:CheckIn:Read_GPS] Stopped 2020-09-08T23:42:53.340Z,1599608573.340 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-08T23:43:00.583Z,1599608580.583 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200908T230902/Courier0021.lzma 2020-09-08T23:43:01.585Z,1599608581.585 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0021.lzma.bak 2020-09-08T23:43:01.586Z,1599608581.586 [DataOverHttps](INFO): SBD MOMSN=12647409 2020-09-08T23:43:05.846Z,1599608585.846 [NAL9602](INFO): SBD MO Status=0, MOMSN=9592, MT Status=0, MTMSN=0 2020-09-08T23:43:05.846Z,1599608585.846 [NAL9602](INFO): No messages in MT queue 2020-09-08T23:43:17.180Z,1599608597.180 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20200908T230902/Express0022.lzma 2020-09-08T23:43:18.182Z,1599608598.182 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0022.lzma.bak 2020-09-08T23:43:18.182Z,1599608598.182 [DataOverHttps](INFO): SBD MOMSN=12647412 2020-09-08T23:43:19.994Z,1599608599.994 [Default:CheckIn:Read_Iridium] Stopped 2020-09-08T23:43:19.994Z,1599608599.994 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-08T23:43:19.994Z,1599608599.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-08T23:43:36.553Z,1599608616.553 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-08T23:48:20.591Z,1599608900.591 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-08T23:48:20.591Z,1599608900.591 [Default:CheckIn:C.Wait] Stopped 2020-09-08T23:48:20.591Z,1599608900.591 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-08T23:48:20.591Z,1599608900.591 [Default:CheckIn:D] Running Loop=1 2020-09-08T23:48:21.000Z,1599608901.000 [Default:CheckIn:D] Stopped 2020-09-08T23:48:21.000Z,1599608901.000 [Default:CheckIn:E] Running Loop=1 2020-09-08T23:48:21.411Z,1599608901.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.009880 min 2020-09-08T23:48:21.411Z,1599608901.411 [Default:CheckIn:E] Stopped 2020-09-08T23:48:21.411Z,1599608901.411 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-08T23:48:21.411Z,1599608901.411 [Default:CheckIn] Stopped 2020-09-08T23:48:21.411Z,1599608901.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-08T23:48:21.411Z,1599608901.411 [Default:CheckIn](INFO): Running loop #15 2020-09-08T23:48:21.411Z,1599608901.411 [Default:CheckIn] Running Loop=15 2020-09-08T23:48:21.411Z,1599608901.411 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-08T23:48:21.412Z,1599608901.412 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-08T23:48:23.411Z,1599608903.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234818.00,A,3648.12148,N,12147.23424,W,0.000,295.96,080920,,,D*7A 2020-09-08T23:48:23.413Z,1599608903.413 [NAL9602](INFO): GPS fix at 20200908T234818: (36.802025, -121.787237) 2020-09-08T23:48:23.423Z,1599608903.423 [Default:CheckIn:Read_GPS] Stopped 2020-09-08T23:48:23.423Z,1599608903.423 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-08T23:48:30.339Z,1599608910.339 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T230902/Courier0024.lzma 2020-09-08T23:48:31.342Z,1599608911.342 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0024.lzma.bak 2020-09-08T23:48:31.342Z,1599608911.342 [DataOverHttps](INFO): SBD MOMSN=12647424 2020-09-08T23:48:40.786Z,1599608920.786 [NAL9602](INFO): SBD MO Status=0, MOMSN=9593, MT Status=0, MTMSN=0 2020-09-08T23:48:40.786Z,1599608920.786 [NAL9602](INFO): No messages in MT queue 2020-09-08T23:48:48.159Z,1599608928.159 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20200908T230902/Express0025.lzma 2020-09-08T23:48:49.162Z,1599608929.162 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0025.lzma.bak 2020-09-08T23:48:49.162Z,1599608929.162 [DataOverHttps](INFO): SBD MOMSN=12647427 2020-09-08T23:48:50.927Z,1599608930.927 [Default:CheckIn:Read_Iridium] Stopped 2020-09-08T23:48:50.927Z,1599608930.927 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-08T23:48:50.927Z,1599608930.927 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-08T23:49:11.485Z,1599608951.485 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-08T23:53:51.488Z,1599609231.488 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-08T23:53:51.488Z,1599609231.488 [Default:CheckIn:C.Wait] Stopped 2020-09-08T23:53:51.489Z,1599609231.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-08T23:53:51.489Z,1599609231.489 [Default:CheckIn:D] Running Loop=1 2020-09-08T23:53:51.879Z,1599609231.879 [Default:CheckIn:D] Stopped 2020-09-08T23:53:51.879Z,1599609231.879 [Default:CheckIn:E] Running Loop=1 2020-09-08T23:53:52.282Z,1599609232.282 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.524528 min 2020-09-08T23:53:52.282Z,1599609232.282 [Default:CheckIn:E] Stopped 2020-09-08T23:53:52.282Z,1599609232.282 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-08T23:53:52.282Z,1599609232.282 [Default:CheckIn] Stopped 2020-09-08T23:53:52.282Z,1599609232.282 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-08T23:53:52.283Z,1599609232.283 [Default:CheckIn](INFO): Running loop #16 2020-09-08T23:53:52.283Z,1599609232.283 [Default:CheckIn] Running Loop=16 2020-09-08T23:53:52.283Z,1599609232.283 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-08T23:53:52.283Z,1599609232.283 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-08T23:53:54.291Z,1599609234.291 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235349.00,A,3648.12002,N,12147.23567,W,0.019,295.96,080920,,,D*75 2020-09-08T23:53:54.293Z,1599609234.293 [NAL9602](INFO): GPS fix at 20200908T235349: (36.802000, -121.787261) 2020-09-08T23:53:54.303Z,1599609234.303 [Default:CheckIn:Read_GPS] Stopped 2020-09-08T23:53:54.304Z,1599609234.304 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-08T23:54:01.167Z,1599609241.167 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T230902/Courier0027.lzma 2020-09-08T23:54:02.170Z,1599609242.170 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0027.lzma.bak 2020-09-08T23:54:02.170Z,1599609242.170 [DataOverHttps](INFO): SBD MOMSN=12647438 2020-09-08T23:54:12.926Z,1599609252.926 [NAL9602](INFO): SBD MO Status=0, MOMSN=9594, MT Status=0, MTMSN=0 2020-09-08T23:54:12.926Z,1599609252.926 [NAL9602](INFO): No messages in MT queue 2020-09-08T23:54:17.979Z,1599609257.979 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20200908T230902/Express0028.lzma 2020-09-08T23:54:18.982Z,1599609258.982 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0028.lzma.bak 2020-09-08T23:54:18.982Z,1599609258.982 [DataOverHttps](INFO): SBD MOMSN=12647441 2020-09-08T23:54:20.622Z,1599609260.622 [Default:CheckIn:Read_Iridium] Stopped 2020-09-08T23:54:20.623Z,1599609260.623 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-08T23:54:20.623Z,1599609260.623 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-08T23:54:43.635Z,1599609283.635 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-08T23:59:21.206Z,1599609561.206 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-08T23:59:21.206Z,1599609561.206 [Default:CheckIn:C.Wait] Stopped 2020-09-08T23:59:21.207Z,1599609561.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-08T23:59:21.207Z,1599609561.207 [Default:CheckIn:D] Running Loop=1 2020-09-08T23:59:21.618Z,1599609561.618 [Default:CheckIn:D] Stopped 2020-09-08T23:59:21.618Z,1599609561.618 [Default:CheckIn:E] Running Loop=1 2020-09-08T23:59:22.014Z,1599609562.014 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.020174 min 2020-09-08T23:59:22.014Z,1599609562.014 [Default:CheckIn:E] Stopped 2020-09-08T23:59:22.014Z,1599609562.014 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-08T23:59:22.014Z,1599609562.014 [Default:CheckIn] Stopped 2020-09-08T23:59:22.014Z,1599609562.014 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-08T23:59:22.015Z,1599609562.015 [Default:CheckIn](INFO): Running loop #17 2020-09-08T23:59:22.015Z,1599609562.015 [Default:CheckIn] Running Loop=17 2020-09-08T23:59:22.015Z,1599609562.015 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-08T23:59:22.015Z,1599609562.015 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-08T23:59:24.027Z,1599609564.027 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235919.00,A,3648.12093,N,12147.23864,W,1.594,289.13,080920,,,D*7D 2020-09-08T23:59:24.029Z,1599609564.029 [NAL9602](INFO): GPS fix at 20200908T235919: (36.802016, -121.787311) 2020-09-08T23:59:24.076Z,1599609564.076 [Default:CheckIn:Read_GPS] Stopped 2020-09-08T23:59:24.076Z,1599609564.076 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-08T23:59:31.063Z,1599609571.063 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200908T230902/Courier0030.lzma 2020-09-08T23:59:32.065Z,1599609572.065 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0030.lzma.bak 2020-09-08T23:59:32.066Z,1599609572.066 [DataOverHttps](INFO): SBD MOMSN=12647452 2020-09-08T23:59:38.162Z,1599609578.162 [NAL9602](INFO): SBD MO Status=0, MOMSN=9595, MT Status=0, MTMSN=0 2020-09-08T23:59:38.162Z,1599609578.162 [NAL9602](INFO): No messages in MT queue 2020-09-08T23:59:47.912Z,1599609587.912 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20200908T230902/Express0031.lzma 2020-09-08T23:59:48.913Z,1599609588.913 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0031.lzma.bak 2020-09-08T23:59:48.914Z,1599609588.914 [DataOverHttps](INFO): SBD MOMSN=12647455 2020-09-08T23:59:50.703Z,1599609590.703 [Default:CheckIn:Read_Iridium] Stopped 2020-09-08T23:59:50.703Z,1599609590.703 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-08T23:59:50.703Z,1599609590.703 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-09T00:00:08.863Z,1599609608.863 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-09T00:04:51.299Z,1599609891.299 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-09T00:04:51.299Z,1599609891.299 [Default:CheckIn:C.Wait] Stopped 2020-09-09T00:04:51.299Z,1599609891.299 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-09T00:04:51.299Z,1599609891.299 [Default:CheckIn:D] Running Loop=1 2020-09-09T00:04:51.704Z,1599609891.704 [Default:CheckIn:D] Stopped 2020-09-09T00:04:51.704Z,1599609891.704 [Default:CheckIn:E] Running Loop=1 2020-09-09T00:04:52.101Z,1599609892.101 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.521606 min 2020-09-09T00:04:52.101Z,1599609892.101 [Default:CheckIn:E] Stopped 2020-09-09T00:04:52.101Z,1599609892.101 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-09T00:04:52.101Z,1599609892.101 [Default:CheckIn] Stopped 2020-09-09T00:04:52.101Z,1599609892.101 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-09T00:04:52.101Z,1599609892.101 [Default:CheckIn](INFO): Running loop #18 2020-09-09T00:04:52.102Z,1599609892.102 [Default:CheckIn] Running Loop=18 2020-09-09T00:04:52.102Z,1599609892.102 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-09T00:04:52.102Z,1599609892.102 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-09T00:04:54.103Z,1599609894.103 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000449.00,A,3648.17091,N,12147.27788,W,0.039,210.45,090920,,,D*7E 2020-09-09T00:04:54.105Z,1599609894.105 [NAL9602](INFO): GPS fix at 20200909T000449: (36.802849, -121.787965) 2020-09-09T00:04:54.114Z,1599609894.114 [Default:CheckIn:Read_GPS] Stopped 2020-09-09T00:04:54.114Z,1599609894.114 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-09T00:05:01.923Z,1599609901.923 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T230902/Courier0033.lzma 2020-09-09T00:05:02.926Z,1599609902.926 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0033.lzma.bak 2020-09-09T00:05:02.926Z,1599609902.926 [DataOverHttps](INFO): SBD MOMSN=12647466 2020-09-09T00:05:18.687Z,1599609918.687 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20200908T230902/Express0034.lzma 2020-09-09T00:05:19.690Z,1599609919.690 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0034.lzma.bak 2020-09-09T00:05:19.690Z,1599609919.690 [DataOverHttps](INFO): SBD MOMSN=12647469 2020-09-09T00:05:21.193Z,1599609921.193 [Default:CheckIn:Read_Iridium] Stopped 2020-09-09T00:05:21.193Z,1599609921.193 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-09T00:05:21.193Z,1599609921.193 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-09T00:05:28.038Z,1599609928.038 [NAL9602](INFO): SBD MO Status=2, MOMSN=9596, MT Status=2, MTMSN=0 2020-09-09T00:05:28.038Z,1599609928.038 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-09-09T00:09:56.697Z,1599610196.697 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-09-09T00:10:21.759Z,1599610221.759 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-09T00:10:21.759Z,1599610221.759 [Default:CheckIn:C.Wait] Stopped 2020-09-09T00:10:21.759Z,1599610221.759 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-09T00:10:21.759Z,1599610221.759 [Default:CheckIn:D] Running Loop=1 2020-09-09T00:10:22.159Z,1599610222.159 [Default:CheckIn:D] Stopped 2020-09-09T00:10:22.159Z,1599610222.159 [Default:CheckIn:E] Running Loop=1 2020-09-09T00:10:22.562Z,1599610222.562 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.029191 min 2020-09-09T00:10:22.562Z,1599610222.562 [Default:CheckIn:E] Stopped 2020-09-09T00:10:22.562Z,1599610222.562 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-09T00:10:22.562Z,1599610222.562 [Default:CheckIn] Stopped 2020-09-09T00:10:22.562Z,1599610222.562 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-09T00:10:22.563Z,1599610222.563 [Default:CheckIn](INFO): Running loop #19 2020-09-09T00:10:22.563Z,1599610222.563 [Default:CheckIn] Running Loop=19 2020-09-09T00:10:22.563Z,1599610222.563 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-09T00:10:22.563Z,1599610222.563 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-09T00:10:24.571Z,1599610224.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001019.00,A,3648.16582,N,12147.28065,W,0.058,210.45,090920,,,D*74 2020-09-09T00:10:24.573Z,1599610224.573 [NAL9602](INFO): GPS fix at 20200909T001019: (36.802764, -121.788011) 2020-09-09T00:10:24.583Z,1599610224.583 [Default:CheckIn:Read_GPS] Stopped 2020-09-09T00:10:24.583Z,1599610224.583 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-09T00:10:31.547Z,1599610231.547 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T230902/Courier0036.lzma 2020-09-09T00:10:32.550Z,1599610232.550 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0036.lzma.bak 2020-09-09T00:10:32.550Z,1599610232.550 [DataOverHttps](INFO): SBD MOMSN=12647479 2020-09-09T00:10:48.436Z,1599610248.436 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20200908T230902/Express0037.lzma 2020-09-09T00:10:49.438Z,1599610249.438 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0037.lzma.bak 2020-09-09T00:10:49.438Z,1599610249.438 [DataOverHttps](INFO): SBD MOMSN=12647482 2020-09-09T00:10:51.250Z,1599610251.250 [Default:CheckIn:Read_Iridium] Stopped 2020-09-09T00:10:51.250Z,1599610251.250 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-09T00:10:51.250Z,1599610251.250 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-09T00:10:56.888Z,1599610256.888 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-09T00:15:51.851Z,1599610551.851 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-09T00:15:51.851Z,1599610551.851 [Default:CheckIn:C.Wait] Stopped 2020-09-09T00:15:51.851Z,1599610551.851 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-09T00:15:51.851Z,1599610551.851 [Default:CheckIn:D] Running Loop=1 2020-09-09T00:15:52.258Z,1599610552.258 [Default:CheckIn:D] Stopped 2020-09-09T00:15:52.258Z,1599610552.258 [Default:CheckIn:E] Running Loop=1 2020-09-09T00:15:52.658Z,1599610552.658 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.530843 min 2020-09-09T00:15:52.658Z,1599610552.658 [Default:CheckIn:E] Stopped 2020-09-09T00:15:52.658Z,1599610552.658 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-09T00:15:52.658Z,1599610552.658 [Default:CheckIn] Stopped 2020-09-09T00:15:52.658Z,1599610552.658 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-09T00:15:52.658Z,1599610552.658 [Default:CheckIn](INFO): Running loop #20 2020-09-09T00:15:52.658Z,1599610552.658 [Default:CheckIn] Running Loop=20 2020-09-09T00:15:52.658Z,1599610552.658 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-09T00:15:52.659Z,1599610552.659 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-09T00:15:54.671Z,1599610554.671 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001549.00,A,3648.16598,N,12147.28166,W,0.214,210.45,090920,,,A*72 2020-09-09T00:15:54.674Z,1599610554.674 [NAL9602](INFO): GPS fix at 20200909T001549: (36.802766, -121.788028) 2020-09-09T00:15:54.700Z,1599610554.700 [Default:CheckIn:Read_GPS] Stopped 2020-09-09T00:15:54.700Z,1599610554.700 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-09T00:16:02.267Z,1599610562.267 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T230902/Courier0039.lzma 2020-09-09T00:16:03.270Z,1599610563.270 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0039.lzma.bak 2020-09-09T00:16:03.270Z,1599610563.270 [DataOverHttps](INFO): SBD MOMSN=12647493 2020-09-09T00:16:19.079Z,1599610579.079 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20200908T230902/Express0040.lzma 2020-09-09T00:16:20.082Z,1599610580.082 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0040.lzma.bak 2020-09-09T00:16:20.082Z,1599610580.082 [DataOverHttps](INFO): SBD MOMSN=12647496 2020-09-09T00:16:21.753Z,1599610581.753 [Default:CheckIn:Read_Iridium] Stopped 2020-09-09T00:16:21.753Z,1599610581.753 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-09T00:16:21.753Z,1599610581.753 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-09T00:16:25.374Z,1599610585.374 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-09-09T00:16:25.452Z,1599610585.452 [NAL9602](FAULT): received: +CSQ:0 OK96, 2, 0, 0, 0 OK 2020-09-09T00:16:25.452Z,1599610585.452 [NAL9602] Data Fault, FailCount= 1 2020-09-09T00:16:25.452Z,1599610585.452 [NAL9602](ERROR): Data Fault 2020-09-09T00:16:25.512Z,1599610585.512 [CBIT](ERROR): Data Fault in component: NAL9602 2020-09-09T00:16:25.777Z,1599610585.777 [NAL9602](INFO): Powering down 2020-09-09T00:16:26.604Z,1599610586.604 [CBIT](INFO): Clearing failed state for component NAL9602 2020-09-09T00:16:26.604Z,1599610586.604 [NAL9602] No Fault, FailCount= 1 2020-09-09T00:16:55.835Z,1599610615.835 [NAL9602](INFO): Powering up NAL9602 2020-09-09T00:17:06.745Z,1599610626.745 [NAL9602](INFO): NAL9602 initialized 2020-09-09T00:17:37.853Z,1599610657.853 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-09T00:18:43.733Z,1599610723.733 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2020-09-09T00:18:43.735Z,1599610723.735 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2020-09-09T00:21:22.502Z,1599610882.502 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-09T00:21:22.502Z,1599610882.502 [Default:CheckIn:C.Wait] Stopped 2020-09-09T00:21:22.502Z,1599610882.502 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-09T00:21:22.503Z,1599610882.503 [Default:CheckIn:D] Running Loop=1 2020-09-09T00:21:22.919Z,1599610882.919 [Default:CheckIn:D] Stopped 2020-09-09T00:21:22.919Z,1599610882.919 [Default:CheckIn:E] Running Loop=1 2020-09-09T00:21:23.314Z,1599610883.314 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.041854 min 2020-09-09T00:21:23.314Z,1599610883.314 [Default:CheckIn:E] Stopped 2020-09-09T00:21:23.314Z,1599610883.314 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-09T00:21:23.314Z,1599610883.314 [Default:CheckIn] Stopped 2020-09-09T00:21:23.314Z,1599610883.314 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-09T00:21:23.314Z,1599610883.314 [Default:CheckIn](INFO): Running loop #21 2020-09-09T00:21:23.314Z,1599610883.314 [Default:CheckIn] Running Loop=21 2020-09-09T00:21:23.314Z,1599610883.314 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-09T00:21:23.314Z,1599610883.314 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-09T00:21:25.321Z,1599610885.321 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002119.00,A,3648.16612,N,12147.27871,W,0.039,181.58,090920,,,A*7B 2020-09-09T00:21:25.324Z,1599610885.324 [NAL9602](INFO): GPS fix at 20200909T002119: (36.802769, -121.787978) 2020-09-09T00:21:25.333Z,1599610885.333 [Default:CheckIn:Read_GPS] Stopped 2020-09-09T00:21:25.333Z,1599610885.333 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-09T00:21:27.339Z,1599610887.339 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-09-09T00:21:33.072Z,1599610893.072 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20200908T230902/Courier0042.lzma 2020-09-09T00:21:34.074Z,1599610894.074 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Courier0042.lzma.bak 2020-09-09T00:21:34.074Z,1599610894.074 [DataOverHttps](INFO): SBD MOMSN=12647506 2020-09-09T00:21:49.684Z,1599610909.684 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20200908T230902/Express0043.lzma 2020-09-09T00:21:50.699Z,1599610910.699 [DataOverHttps](INFO): Moved sent file to Logs/20200908T230902/Express0043.lzma.bak 2020-09-09T00:21:50.699Z,1599610910.699 [DataOverHttps](INFO): SBD MOMSN=12647509 2020-09-09T00:21:52.406Z,1599610912.406 [Default:CheckIn:Read_Iridium]