ohadschn / letsencrypt-webapp-renewer

[PLEASE USE FREE MS MANAGED CERTS INSTEAD] Simple WebJob-ready console application for renewing Azure Web App SSL certificates
https://www.ohadsoft.com/
Apache License 2.0
282 stars 51 forks source link

Renewal fails during scheduled job run, but works when web job is started manually. #62

Closed braudabaugh closed 5 years ago

braudabaugh commented 6 years ago

When the web job runs we get the error below. However, running it manually works. Any help would be appreciated.

[11/01/2018 00:00:30 > b0c2e2: SYS INFO] Status changed to Initializing
[11/01/2018 00:00:38 > b0c2e2: SYS INFO] Run script 'AzureLetsEncryptRenewer.exe' with script host - 'WindowsScriptHost'
[11/01/2018 00:00:38 > b0c2e2: SYS INFO] Status changed to Running
[11/01/2018 00:00:39 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Web App SSL renewal job (renewer) started
[11/01/2018 00:00:39 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsing Web Apps for SSL renewal from webjob/site configuration...
[11/01/2018 00:00:39 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsed web apps for SSL renewal: spool{beta}; spool; tlstitch
[11/01/2018 00:00:39 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsed shared parameters: ResourceGroup: , SubscriptionId: be89b0c3-5854-4dcb-a456-aaabe7d91a5f, TenantId: ser266cornell.onmicrosoft.com, ClientId: 57325623-4186-45f6-8d38-bac45edb5e51, ClientSecret: <SCRUBBED>, Email: sam@threadlearning.com, ServicePlanResourceGroup: , UseIpBasedSsl: , RsaKeyLength: , AcmeBaseUri: , RenewXNumberOfDaysBeforeExpiration: , AuthenticationUri: , AzureTokenAudience: , AzureManagementEndpoint: , AzureDefaultWebsiteDomainName: 
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsing SSL renewal parameters for web app 'spool{beta}'...
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsing SSL renewal parameters for web app 'spool'...
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsing SSL renewal parameters for web app 'tlstitch'...
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Completed parsing of Web App SSL cert renewal information
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating SSL certificate with parameters: SubscriptionId: be89b0c3-5854-4dcb-a456-aaabe7d91a5f, TenantId: ser266cornell.onmicrosoft.com, ResourceGroup: spool, WebApp: spool, Hosts: System.String[], Email: sam@threadlearning.com, ClientId: 57325623-4186-45f6-8d38-bac45edb5e51, ClientSecret: <SCRUBBED>, ServicePlanResourceGroup: , SiteSlotName: beta, UseIpBasedSsl: False, RsaKeyLength: 2048, AcmeBaseUri: , RenewXNumberOfDaysBeforeExpiration: -1, AuthenticationUri: , AzureTokenAudience: , AzureManagementEndpoint: , AzureDefaultWebsiteDomainName: 
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating secure PFX password for 'spool'...
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Adding SSL cert for 'spool'...
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:40:  - AuthenticationContext: ADAL .NET with assembly version '2.28.3.860', file version '2.28.31117.1411' and informational version '78bd21073cfd91768d97894ace1ba90c5b904eec' is running...
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:40: f22e3486-201a-4b59-b936-219206b34bad - AcquireTokenHandlerBase: === Token Acquisition started:
[11/01/2018 00:00:40 > b0c2e2: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/01/2018 00:00:40 > b0c2e2: INFO]    Resource: https://management.core.windows.net/
[11/01/2018 00:00:40 > b0c2e2: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/01/2018 00:00:40 > b0c2e2: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (0 items)
[11/01/2018 00:00:40 > b0c2e2: INFO]    Authentication Target: Client
[11/01/2018 00:00:40 > b0c2e2: INFO]    
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:40: f22e3486-201a-4b59-b936-219206b34bad - TokenCache: Looking up cache for a token...
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:40: f22e3486-201a-4b59-b936-219206b34bad - TokenCache: No matching token was found in the cache
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:40: f22e3486-201a-4b59-b936-219206b34bad - TokenCache: Storing token in the cache...
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:40: f22e3486-201a-4b59-b936-219206b34bad - TokenCache: An item was stored in the cache
[11/01/2018 00:00:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:40: f22e3486-201a-4b59-b936-219206b34bad - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/01/2018 00:00:40 > b0c2e2: INFO]    Access Token Hash: PHEjzFXAPQZmE0RBrSELqKYsjJd/i7LKtegy8uJOrUY=
[11/01/2018 00:00:40 > b0c2e2: INFO]    Refresh Token Hash: [No Refresh Token]
[11/01/2018 00:00:40 > b0c2e2: INFO]    Expiration Time: 11/01/2018 01:00:39 +00:00
[11/01/2018 00:00:40 > b0c2e2: INFO]    User Hash: null
[11/01/2018 00:00:40 > b0c2e2: INFO]    
[11/01/2018 00:00:42 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Staring add certificate
[11/01/2018 00:00:42 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:42: adc83fa3-92e4-4cfe-a26a-f6d2cb91150d - AcquireTokenHandlerBase: === Token Acquisition started:
[11/01/2018 00:00:42 > b0c2e2: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/01/2018 00:00:42 > b0c2e2: INFO]    Resource: https://management.core.windows.net/
[11/01/2018 00:00:42 > b0c2e2: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/01/2018 00:00:42 > b0c2e2: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/01/2018 00:00:42 > b0c2e2: INFO]    Authentication Target: Client
[11/01/2018 00:00:42 > b0c2e2: INFO]    
[11/01/2018 00:00:42 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:42: adc83fa3-92e4-4cfe-a26a-f6d2cb91150d - TokenCache: Looking up cache for a token...
[11/01/2018 00:00:42 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:42: adc83fa3-92e4-4cfe-a26a-f6d2cb91150d - TokenCache: An item matching the requested resource was found in the cache
[11/01/2018 00:00:42 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:42: adc83fa3-92e4-4cfe-a26a-f6d2cb91150d - TokenCache: 59.957291045 minutes left until token in cache expires
[11/01/2018 00:00:42 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:42: adc83fa3-92e4-4cfe-a26a-f6d2cb91150d - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/01/2018 00:00:42 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:00:42: adc83fa3-92e4-4cfe-a26a-f6d2cb91150d - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/01/2018 00:00:42 > b0c2e2: INFO]    Access Token Hash: PHEjzFXAPQZmE0RBrSELqKYsjJd/i7LKtegy8uJOrUY=
[11/01/2018 00:00:42 > b0c2e2: INFO]    Refresh Token Hash: [No Refresh Token]
[11/01/2018 00:00:42 > b0c2e2: INFO]    Expiration Time: 11/01/2018 01:00:39 +00:00
[11/01/2018 00:00:42 > b0c2e2: INFO]    User Hash: null
[11/01/2018 00:00:42 > b0c2e2: INFO]    
[11/01/2018 00:00:42 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Add certificate for acmeConfig hostname beta.pmtaba.com
[11/01/2018 00:00:42 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : RequestAndInstallInternal
[11/01/2018 00:00:42 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Signer from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Signer
[11/01/2018 00:00:43 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 
[11/01/2018 00:00:43 > b0c2e2: INFO] Getting AcmeServerDirectory
[11/01/2018 00:00:43 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Registration from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Registration
[11/01/2018 00:00:53 > b0c2e2: INFO] 
[11/01/2018 00:00:53 > b0c2e2: INFO] Authorizing Identifier beta.pmtaba.com Using Challenge Type http-01
[11/01/2018 00:00:53 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Authorizing Identifier beta.pmtaba.com Using Challenge Type http-01
[11/01/2018 00:00:54 > b0c2e2: INFO]  Answer should now be browsable at http://beta.pmtaba.com/.well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw
[11/01/2018 00:00:54 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Answer should now be browsable at http://beta.pmtaba.com/.well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw
[11/01/2018 00:00:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Checking status OK
[11/01/2018 00:00:56 > b0c2e2: INFO]  Submitting answer
[11/01/2018 00:00:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Submitting answer
[11/01/2018 00:00:57 > b0c2e2: INFO]  Refreshing authorization attempt 1
[11/01/2018 00:00:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 1
[11/01/2018 00:00:59 > b0c2e2: INFO]  Refreshing authorization attempt 2
[11/01/2018 00:00:59 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 2
[11/01/2018 00:01:03 > b0c2e2: INFO]  Refreshing authorization attempt 3
[11/01/2018 00:01:03 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 3
[11/01/2018 00:01:09 > b0c2e2: INFO]  Refreshing authorization attempt 4
[11/01/2018 00:01:09 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 4
[11/01/2018 00:01:17 > b0c2e2: INFO]  Refreshing authorization attempt 5
[11/01/2018 00:01:17 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 5
[11/01/2018 00:01:28 > b0c2e2: INFO]  Refreshing authorization attempt 6
[11/01/2018 00:01:28 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 6
[11/01/2018 00:01:40 > b0c2e2: INFO]  Authorization Result: pending
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Auth Result pending
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Error: 0 : Authorization Failed pending
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Full Error Details {"IdentifierPart":{"Type":"dns","Value":"beta.pmtaba.com"},"IdentifierType":"dns","Identifier":"beta.pmtaba.com","Uri":"https://acme-v01.api.letsencrypt.org/acme/authz/A2YvQ9NTBxt_tbh9xR7bPg2VJzTYTpZwQL5_9xn03fs","Status":"pending","Expires":"2018-11-08T00:00:53Z","Challenges":[{"ChallengePart":{"Type":"http-01","Uri":"https://acme-v01.api.letsencrypt.org/acme/challenge/A2YvQ9NTBxt_tbh9xR7bPg2VJzTYTpZwQL5_9xn03fs/8827126541","Token":"FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw","Status":"pending","Validated":null,"Error":null},"Challenge":{"Token":"FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw","FileUrl":"http://beta.pmtaba.com/.well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw","FilePath":".well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw","FileContent":"FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw.gqs44cwkyDnSB9ZbQG1Ezn2ITjGqH8Li30KNdJXUO7k","TypeKind":64,"Type":"http-01","Answer":{"KeyAuthorization":"FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw.gqs44cwkyDnSB9ZbQG1Ezn2ITjGqH8Li30KNdJXUO7k"}},"Type":"http-01","Uri":"https://acme-v01.api.letsencrypt.org/acme/challenge/A2YvQ9NTBxt_tbh9xR7bPg2VJzTYTpZwQL5_9xn03fs/8827126541","Token":"FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw","Status":"pending","OldChallengeAnswer":{"Key":null,"Value":null},"ChallengeAnswerMessage":null,"HandlerName":null,"HandlerHandleDate":null,"HandlerCleanUpDate":null,"SubmitDate":"2018-11-01T00:00:57.1800384+00:00","SubmitResponse":{"StatusCode":202,"Headers":["Boulder-Requester","Link","Replay-Nonce","Pragma","Connection","Content-Length","Cache-Control","Content-Type","Date","Expires","Location","Server"],"Links":["<https://acme-v01.api.letsencrypt.org/acme/authz/A2YvQ9NTBxt_tbh9xR7bPg2VJzTYTpZwQL5_9xn03fs>;rel=\"up\""],"RawContent":"ewogICJ0eXBlIjogImh0dHAtMDEiLAogICJzdGF0dXMiOiAicGVuZGluZyIsCiAgInVyaSI6ICJodHRwczovL2FjbWUtdjAxLmFwaS5sZXRzZW5jcnlwdC5vcmcvYWNtZS9jaGFsbGVuZ2UvQTJZdlE5TlRCeHRfdGJoOXhSN2JQZzJWSnpUWVRwWndRTDVfOXhuMDNmcy84ODI3MTI2NTQxIiwKICAidG9rZW4iOiAiRkxQOU56OVd6TWJvbDl5N2NKd3Z2cWIxYzRjMjYxNFBoU3FnejRhc3N0dyIsCiAgImtleUF1dGhvcml6YXRpb24iOiAiRkxQOU56OVd6TWJvbDl5N2NKd3Z2cWIxYzRjMjYxNFBoU3FnejRhc3N0dy5ncXM0NGN3a3lEblNCOVpiUUcxRXpuMklUakdxSDhMaTMwS05kSlhVTzdrIgp9","ContentAsString":"{\n  \"type\": \"http-01\",\n  \"status\": \"pending\",\n  \"uri\": \"https://acme-v01.api.letsencrypt.org/acme/challenge/A2YvQ9NTBxt_tbh9xR7bPg2VJzTYTpZwQL5_9xn03fs/8827126541\",\n  \"token\": \"FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw\",\n  \"keyAuthorization\": \"FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw.gqs44cwkyDnSB9ZbQG1Ezn2ITjGqH8Li30KNdJXUO7k\"\n}","IsError":false,"Error":null,"ProblemDetail":null}}],"Combinations":[[2],[1],[3],[0]]}
[11/01/2018 00:01:40 > b0c2e2: INFO] The ACME server was probably unable to reach http://beta.pmtaba.com/.well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Error: 0 : Unable to reach http://beta.pmtaba.com/.well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw
[11/01/2018 00:01:40 > b0c2e2: INFO] 
[11/01/2018 00:01:40 > b0c2e2: INFO] Check in a browser to see if the answer file is being served correctly.
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Error: 0 : Encountered exception: System.Exception: The Lets Encrypt ACME server was probably unable to reach http://beta.pmtaba.com/.well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw view error report from Lets Encrypt at https://acme-v01.api.letsencrypt.org/acme/authz/A2YvQ9NTBxt_tbh9xR7bPg2VJzTYTpZwQL5_9xn03fs for more information
[11/01/2018 00:01:40 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:01:40 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
[11/01/2018 00:01:40 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:01:40 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:01:40 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.AcmeService.<RequestCertificate>d__5.MoveNext()
[11/01/2018 00:01:40 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:01:40 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestInternalAsync>d__12.MoveNext()
[11/01/2018 00:01:40 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:01:40 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestAndInstallInternalAsync>d__13.MoveNext()
[11/01/2018 00:01:40 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:01:40 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<AddCertificate>d__9.MoveNext()
[11/01/2018 00:01:40 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.Management.RenewalManager.<Renew>d__6.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal\Management\RenewalManager.cs:line 73
[11/01/2018 00:01:40 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:40 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.AppSettings.AppSettingsRenewer.<Renew>d__4.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\AppSettings\AppSettingsRenewer.cs:line 35
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating SSL certificate with parameters: SubscriptionId: be89b0c3-5854-4dcb-a456-aaabe7d91a5f, TenantId: ser266cornell.onmicrosoft.com, ResourceGroup: spool, WebApp: spool, Hosts: System.String[], Email: sam@threadlearning.com, ClientId: 57325623-4186-45f6-8d38-bac45edb5e51, ClientSecret: <SCRUBBED>, ServicePlanResourceGroup: , SiteSlotName: , UseIpBasedSsl: False, RsaKeyLength: 2048, AcmeBaseUri: , RenewXNumberOfDaysBeforeExpiration: -1, AuthenticationUri: , AzureTokenAudience: , AzureManagementEndpoint: , AzureDefaultWebsiteDomainName: 
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating secure PFX password for 'spool'...
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Adding SSL cert for 'spool'...
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:40: 281b0852-3851-4411-b1c8-c4def34c5351 - AcquireTokenHandlerBase: === Token Acquisition started:
[11/01/2018 00:01:40 > b0c2e2: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/01/2018 00:01:40 > b0c2e2: INFO]    Resource: https://management.core.windows.net/
[11/01/2018 00:01:40 > b0c2e2: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/01/2018 00:01:40 > b0c2e2: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/01/2018 00:01:40 > b0c2e2: INFO]    Authentication Target: Client
[11/01/2018 00:01:40 > b0c2e2: INFO]    
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:40: 281b0852-3851-4411-b1c8-c4def34c5351 - TokenCache: Looking up cache for a token...
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:40: 281b0852-3851-4411-b1c8-c4def34c5351 - TokenCache: An item matching the requested resource was found in the cache
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:40: 281b0852-3851-4411-b1c8-c4def34c5351 - TokenCache: 58.9804100066667 minutes left until token in cache expires
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:40: 281b0852-3851-4411-b1c8-c4def34c5351 - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/01/2018 00:01:40 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:40: 281b0852-3851-4411-b1c8-c4def34c5351 - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/01/2018 00:01:40 > b0c2e2: INFO]    Access Token Hash: PHEjzFXAPQZmE0RBrSELqKYsjJd/i7LKtegy8uJOrUY=
[11/01/2018 00:01:40 > b0c2e2: INFO]    Refresh Token Hash: [No Refresh Token]
[11/01/2018 00:01:40 > b0c2e2: INFO]    Expiration Time: 11/01/2018 01:00:39 +00:00
[11/01/2018 00:01:40 > b0c2e2: INFO]    User Hash: null
[11/01/2018 00:01:40 > b0c2e2: INFO]    
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Staring add certificate
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:41: 17527ec9-534d-4604-9f38-93b3919dd4dc - AcquireTokenHandlerBase: === Token Acquisition started:
[11/01/2018 00:01:41 > b0c2e2: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/01/2018 00:01:41 > b0c2e2: INFO]    Resource: https://management.core.windows.net/
[11/01/2018 00:01:41 > b0c2e2: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/01/2018 00:01:41 > b0c2e2: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/01/2018 00:01:41 > b0c2e2: INFO]    Authentication Target: Client
[11/01/2018 00:01:41 > b0c2e2: INFO]    
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:41: 17527ec9-534d-4604-9f38-93b3919dd4dc - TokenCache: Looking up cache for a token...
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:41: 17527ec9-534d-4604-9f38-93b3919dd4dc - TokenCache: An item matching the requested resource was found in the cache
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:41: 17527ec9-534d-4604-9f38-93b3919dd4dc - TokenCache: 58.9707729233333 minutes left until token in cache expires
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:41: 17527ec9-534d-4604-9f38-93b3919dd4dc - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:41: 17527ec9-534d-4604-9f38-93b3919dd4dc - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/01/2018 00:01:41 > b0c2e2: INFO]    Access Token Hash: PHEjzFXAPQZmE0RBrSELqKYsjJd/i7LKtegy8uJOrUY=
[11/01/2018 00:01:41 > b0c2e2: INFO]    Refresh Token Hash: [No Refresh Token]
[11/01/2018 00:01:41 > b0c2e2: INFO]    Expiration Time: 11/01/2018 01:00:39 +00:00
[11/01/2018 00:01:41 > b0c2e2: INFO]    User Hash: null
[11/01/2018 00:01:41 > b0c2e2: INFO]    
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Add certificate for acmeConfig hostname www.pmtaba.com, pmtaba.com
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : RequestAndInstallInternal
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Signer from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Signer
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 
[11/01/2018 00:01:41 > b0c2e2: INFO] Getting AcmeServerDirectory
[11/01/2018 00:01:41 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Registration from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Registration
[11/01/2018 00:01:47 > b0c2e2: INFO] 
[11/01/2018 00:01:47 > b0c2e2: INFO] Authorizing Identifier www.pmtaba.com Using Challenge Type http-01
[11/01/2018 00:01:47 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Authorizing Identifier www.pmtaba.com Using Challenge Type http-01
[11/01/2018 00:01:48 > b0c2e2: INFO]  Answer should now be browsable at http://www.pmtaba.com/.well-known/acme-challenge/CqssVmMpVaybdremcn4W-pY9n_CrkIMvkUbEQyuHJSo
[11/01/2018 00:01:48 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Answer should now be browsable at http://www.pmtaba.com/.well-known/acme-challenge/CqssVmMpVaybdremcn4W-pY9n_CrkIMvkUbEQyuHJSo
[11/01/2018 00:01:49 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Checking status OK
[11/01/2018 00:01:49 > b0c2e2: INFO]  Submitting answer
[11/01/2018 00:01:49 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Submitting answer
[11/01/2018 00:01:50 > b0c2e2: INFO]  Refreshing authorization attempt 1
[11/01/2018 00:01:50 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 1
[11/01/2018 00:01:52 > b0c2e2: INFO]  Authorization Result: valid
[11/01/2018 00:01:52 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Auth Result valid
[11/01/2018 00:01:52 > b0c2e2: INFO]  Deleting answer
[11/01/2018 00:01:52 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Deleting answer
[11/01/2018 00:01:52 > b0c2e2: INFO] 
[11/01/2018 00:01:52 > b0c2e2: INFO] Authorizing Identifier pmtaba.com Using Challenge Type http-01
[11/01/2018 00:01:52 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Authorizing Identifier pmtaba.com Using Challenge Type http-01
[11/01/2018 00:01:52 > b0c2e2: INFO]  Answer should now be browsable at http://pmtaba.com/.well-known/acme-challenge/Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4
[11/01/2018 00:01:52 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Answer should now be browsable at http://pmtaba.com/.well-known/acme-challenge/Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4
[11/01/2018 00:01:53 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Checking status OK
[11/01/2018 00:01:53 > b0c2e2: INFO]  Submitting answer
[11/01/2018 00:01:53 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Submitting answer
[11/01/2018 00:01:54 > b0c2e2: INFO]  Refreshing authorization attempt 1
[11/01/2018 00:01:54 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 1
[11/01/2018 00:01:56 > b0c2e2: INFO]  Authorization Result: invalid
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Auth Result invalid
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Error: 0 : Authorization Failed invalid
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Full Error Details {"IdentifierPart":{"Type":"dns","Value":"pmtaba.com"},"IdentifierType":"dns","Identifier":"pmtaba.com","Uri":"https://acme-v01.api.letsencrypt.org/acme/authz/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys","Status":"invalid","Expires":"2018-11-08T00:01:52Z","Challenges":[{"ChallengePart":{"Type":"tls-sni-01","Uri":"https://acme-v01.api.letsencrypt.org/acme/challenge/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys/8827183060","Token":"faYgaP09qW0R9310fwKMTBhISoS4zFGODN4g0Y0ukQs","Status":"invalid","Validated":null,"Error":null},"Challenge":null,"Type":"tls-sni-01","Uri":"https://acme-v01.api.letsencrypt.org/acme/challenge/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys/8827183060","Token":"faYgaP09qW0R9310fwKMTBhISoS4zFGODN4g0Y0ukQs","Status":"invalid","OldChallengeAnswer":{"Key":null,"Value":null},"ChallengeAnswerMessage":null,"HandlerName":null,"HandlerHandleDate":null,"HandlerCleanUpDate":null,"SubmitDate":null,"SubmitResponse":null},{"ChallengePart":{"Type":"tls-alpn-01","Uri":"https://acme-v01.api.letsencrypt.org/acme/challenge/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys/8827183061","Token":"e7GAWKDCEO-z3G-Cz8z6n61lVI8VKOlpBhUIvqCmTtA","Status":"invalid","Validated":null,"Error":null},"Challenge":null,"Type":"tls-alpn-01","Uri":"https://acme-v01.api.letsencrypt.org/acme/challenge/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys/8827183061","Token":"e7GAWKDCEO-z3G-Cz8z6n61lVI8VKOlpBhUIvqCmTtA","Status":"invalid","OldChallengeAnswer":{"Key":null,"Value":null},"ChallengeAnswerMessage":null,"HandlerName":null,"HandlerHandleDate":null,"HandlerCleanUpDate":null,"SubmitDate":null,"SubmitResponse":null},{"ChallengePart":{"Type":"dns-01","Uri":"https://acme-v01.api.letsencrypt.org/acme/challenge/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys/8827183063","Token":"YRGVl5oObBMz8R9WQhK7QezEXf3OdEHcRRB2v0xQmHU","Status":"invalid","Validated":null,"Error":null},"Challenge":null,"Type":"dns-01","Uri":"https://acme-v01.api.letsencrypt.org/acme/challenge/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys/8827183063","Token":"YRGVl5oObBMz8R9WQhK7QezEXf3OdEHcRRB2v0xQmHU","Status":"invalid","OldChallengeAnswer":{"Key":null,"Value":null},"ChallengeAnswerMessage":null,"HandlerName":null,"HandlerHandleDate":null,"HandlerCleanUpDate":null,"SubmitDate":null,"SubmitResponse":null},{"ChallengePart":{"Type":"http-01","Uri":"https://acme-v01.api.letsencrypt.org/acme/challenge/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys/8827183064","Token":"Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4","Status":"invalid","Validated":null,"Error":{"type":"urn:acme:error:connection","detail":"Fetching https://pmtaba.com/.well-known/acme-challenge/Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4: Error getting validation data","status":"400"},"validationRecord":[{"url":"http://pmtaba.com/.well-known/acme-challenge/Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4","hostname":"pmtaba.com","port":"80","addressesResolved":["13.90.213.204"],"addressUsed":"13.90.213.204"},{"url":"https://pmtaba.com/.well-known/acme-challenge/Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4","hostname":"pmtaba.com","port":"443","addressesResolved":["13.90.213.204"]}]},"Challenge":null,"Type":"http-01","Uri":"https://acme-v01.api.letsencrypt.org/acme/challenge/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys/8827183064","Token":"Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4","Status":"invalid","OldChallengeAnswer":{"Key":null,"Value":null},"ChallengeAnswerMessage":null,"HandlerName":null,"HandlerHandleDate":null,"HandlerCleanUpDate":null,"SubmitDate":null,"SubmitResponse":null}],"Combinations":[[0],[1],[2],[3]]}
[11/01/2018 00:01:56 > b0c2e2: INFO] The ACME server was probably unable to reach http://pmtaba.com/.well-known/acme-challenge/Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Error: 0 : Unable to reach http://pmtaba.com/.well-known/acme-challenge/Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4
[11/01/2018 00:01:56 > b0c2e2: INFO] 
[11/01/2018 00:01:56 > b0c2e2: INFO] Check in a browser to see if the answer file is being served correctly.
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Error: 0 : Encountered exception: System.Exception: The Lets Encrypt ACME server was probably unable to reach http://pmtaba.com/.well-known/acme-challenge/Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4 view error report from Lets Encrypt at https://acme-v01.api.letsencrypt.org/acme/authz/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys for more information
[11/01/2018 00:01:56 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:01:56 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
[11/01/2018 00:01:56 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:01:56 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:01:56 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.AcmeService.<RequestCertificate>d__5.MoveNext()
[11/01/2018 00:01:56 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:01:56 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestInternalAsync>d__12.MoveNext()
[11/01/2018 00:01:56 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:01:56 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestAndInstallInternalAsync>d__13.MoveNext()
[11/01/2018 00:01:56 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:01:56 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<AddCertificate>d__9.MoveNext()
[11/01/2018 00:01:56 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.Management.RenewalManager.<Renew>d__6.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal\Management\RenewalManager.cs:line 73
[11/01/2018 00:01:56 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:01:56 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.AppSettings.AppSettingsRenewer.<Renew>d__4.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\AppSettings\AppSettingsRenewer.cs:line 35
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating SSL certificate with parameters: SubscriptionId: be89b0c3-5854-4dcb-a456-aaabe7d91a5f, TenantId: ser266cornell.onmicrosoft.com, ResourceGroup: tlstitch, WebApp: tlstitch, Hosts: System.String[], Email: sam@threadlearning.com, ClientId: 57325623-4186-45f6-8d38-bac45edb5e51, ClientSecret: <SCRUBBED>, ServicePlanResourceGroup: , SiteSlotName: , UseIpBasedSsl: False, RsaKeyLength: 2048, AcmeBaseUri: , RenewXNumberOfDaysBeforeExpiration: -1, AuthenticationUri: , AzureTokenAudience: , AzureManagementEndpoint: , AzureDefaultWebsiteDomainName: 
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating secure PFX password for 'tlstitch'...
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Adding SSL cert for 'tlstitch'...
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:56: 65520fcc-5921-4d1f-b4ba-5c584251aa05 - AcquireTokenHandlerBase: === Token Acquisition started:
[11/01/2018 00:01:56 > b0c2e2: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/01/2018 00:01:56 > b0c2e2: INFO]    Resource: https://management.core.windows.net/
[11/01/2018 00:01:56 > b0c2e2: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/01/2018 00:01:56 > b0c2e2: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/01/2018 00:01:56 > b0c2e2: INFO]    Authentication Target: Client
[11/01/2018 00:01:56 > b0c2e2: INFO]    
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:56: 65520fcc-5921-4d1f-b4ba-5c584251aa05 - TokenCache: Looking up cache for a token...
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:56: 65520fcc-5921-4d1f-b4ba-5c584251aa05 - TokenCache: An item matching the requested resource was found in the cache
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:56: 65520fcc-5921-4d1f-b4ba-5c584251aa05 - TokenCache: 58.7134728083333 minutes left until token in cache expires
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:56: 65520fcc-5921-4d1f-b4ba-5c584251aa05 - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/01/2018 00:01:56 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:56: 65520fcc-5921-4d1f-b4ba-5c584251aa05 - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/01/2018 00:01:56 > b0c2e2: INFO]    Access Token Hash: PHEjzFXAPQZmE0RBrSELqKYsjJd/i7LKtegy8uJOrUY=
[11/01/2018 00:01:56 > b0c2e2: INFO]    Refresh Token Hash: [No Refresh Token]
[11/01/2018 00:01:56 > b0c2e2: INFO]    Expiration Time: 11/01/2018 01:00:39 +00:00
[11/01/2018 00:01:56 > b0c2e2: INFO]    User Hash: null
[11/01/2018 00:01:56 > b0c2e2: INFO]    
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Staring add certificate
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:57: 2a091985-16e2-4040-94d9-8073a39919e3 - AcquireTokenHandlerBase: === Token Acquisition started:
[11/01/2018 00:01:57 > b0c2e2: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/01/2018 00:01:57 > b0c2e2: INFO]    Resource: https://management.core.windows.net/
[11/01/2018 00:01:57 > b0c2e2: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/01/2018 00:01:57 > b0c2e2: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/01/2018 00:01:57 > b0c2e2: INFO]    Authentication Target: Client
[11/01/2018 00:01:57 > b0c2e2: INFO]    
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:57: 2a091985-16e2-4040-94d9-8073a39919e3 - TokenCache: Looking up cache for a token...
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:57: 2a091985-16e2-4040-94d9-8073a39919e3 - TokenCache: An item matching the requested resource was found in the cache
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:57: 2a091985-16e2-4040-94d9-8073a39919e3 - TokenCache: 58.7022736366667 minutes left until token in cache expires
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:57: 2a091985-16e2-4040-94d9-8073a39919e3 - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:01:57: 2a091985-16e2-4040-94d9-8073a39919e3 - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/01/2018 00:01:57 > b0c2e2: INFO]    Access Token Hash: PHEjzFXAPQZmE0RBrSELqKYsjJd/i7LKtegy8uJOrUY=
[11/01/2018 00:01:57 > b0c2e2: INFO]    Refresh Token Hash: [No Refresh Token]
[11/01/2018 00:01:57 > b0c2e2: INFO]    Expiration Time: 11/01/2018 01:00:39 +00:00
[11/01/2018 00:01:57 > b0c2e2: INFO]    User Hash: null
[11/01/2018 00:01:57 > b0c2e2: INFO]    
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Add certificate for acmeConfig hostname accounts.threadlearning.com
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : RequestAndInstallInternal
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Signer from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Signer
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 
[11/01/2018 00:01:57 > b0c2e2: INFO] Getting AcmeServerDirectory
[11/01/2018 00:01:57 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Registration from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Registration
[11/01/2018 00:02:06 > b0c2e2: INFO] 
[11/01/2018 00:02:06 > b0c2e2: INFO] Authorizing Identifier accounts.threadlearning.com Using Challenge Type http-01
[11/01/2018 00:02:06 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Authorizing Identifier accounts.threadlearning.com Using Challenge Type http-01
[11/01/2018 00:02:06 > b0c2e2: INFO]  Answer should now be browsable at http://accounts.threadlearning.com/.well-known/acme-challenge/3gefRgK57syOobL5JD1BQ3I3TlwnHZlfe3-LcldcXlY
[11/01/2018 00:02:06 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Answer should now be browsable at http://accounts.threadlearning.com/.well-known/acme-challenge/3gefRgK57syOobL5JD1BQ3I3TlwnHZlfe3-LcldcXlY
[11/01/2018 00:02:08 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Checking status OK
[11/01/2018 00:02:08 > b0c2e2: INFO]  Submitting answer
[11/01/2018 00:02:08 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Submitting answer
[11/01/2018 00:02:08 > b0c2e2: INFO]  Refreshing authorization attempt 1
[11/01/2018 00:02:08 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 1
[11/01/2018 00:02:10 > b0c2e2: INFO]  Authorization Result: valid
[11/01/2018 00:02:10 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Auth Result valid
[11/01/2018 00:02:10 > b0c2e2: INFO]  Deleting answer
[11/01/2018 00:02:10 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Deleting answer
[11/01/2018 00:02:10 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : RSAKeyBits: 2048
[11/01/2018 00:02:12 > b0c2e2: INFO] 
[11/01/2018 00:02:12 > b0c2e2: INFO] Requesting Certificate
[11/01/2018 00:02:12 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Requesting Certificate
[11/01/2018 00:02:12 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : certRequ ACMESharp.CertificateRequest
[11/01/2018 00:02:12 > b0c2e2: INFO]  Request Status: Created
[11/01/2018 00:02:12 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Request Status: Created
[11/01/2018 00:02:13 > b0c2e2: INFO]  Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\accounts.threadlearning.com-crt.der
[11/01/2018 00:02:13 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\accounts.threadlearning.com-crt.der
[11/01/2018 00:02:13 > b0c2e2: INFO]  Saving Issuer Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\ca-0A0141420000015385736A0B85ECA708-crt.pem
[11/01/2018 00:02:13 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Issuer Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\ca-0A0141420000015385736A0B85ECA708-crt.pem
[11/01/2018 00:02:13 > b0c2e2: INFO]  Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\accounts.threadlearning.com-all.pfx
[11/01/2018 00:02:13 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\accounts.threadlearning.com-all.pfx
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 98b69e03-f14d-4c9b-a502-8ae0fa508114 - AcquireTokenHandlerBase: === Token Acquisition started:
[11/01/2018 00:02:14 > b0c2e2: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/01/2018 00:02:14 > b0c2e2: INFO]    Resource: https://management.core.windows.net/
[11/01/2018 00:02:14 > b0c2e2: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/01/2018 00:02:14 > b0c2e2: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/01/2018 00:02:14 > b0c2e2: INFO]    Authentication Target: Client
[11/01/2018 00:02:14 > b0c2e2: INFO]    
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 98b69e03-f14d-4c9b-a502-8ae0fa508114 - TokenCache: Looking up cache for a token...
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 98b69e03-f14d-4c9b-a502-8ae0fa508114 - TokenCache: An item matching the requested resource was found in the cache
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 98b69e03-f14d-4c9b-a502-8ae0fa508114 - TokenCache: 58.4202319883333 minutes left until token in cache expires
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 98b69e03-f14d-4c9b-a502-8ae0fa508114 - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 98b69e03-f14d-4c9b-a502-8ae0fa508114 - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/01/2018 00:02:14 > b0c2e2: INFO]    Access Token Hash: PHEjzFXAPQZmE0RBrSELqKYsjJd/i7LKtegy8uJOrUY=
[11/01/2018 00:02:14 > b0c2e2: INFO]    Refresh Token Hash: [No Refresh Token]
[11/01/2018 00:02:14 > b0c2e2: INFO]    Expiration Time: 11/01/2018 01:00:39 +00:00
[11/01/2018 00:02:14 > b0c2e2: INFO]    User Hash: null
[11/01/2018 00:02:14 > b0c2e2: INFO]    
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Installing certificate D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\accounts.threadlearning.com-all.pfx on azure with server farm id /subscriptions/be89b0c3-5854-4dcb-a456-aaabe7d91a5f/resourceGroups/app.threadlearning.com/providers/Microsoft.Web/serverfarms/threadlearning
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 6d9c22cd-3d8b-46e3-9cea-f4dcf9bc7531 - AcquireTokenHandlerBase: === Token Acquisition started:
[11/01/2018 00:02:14 > b0c2e2: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/01/2018 00:02:14 > b0c2e2: INFO]    Resource: https://management.core.windows.net/
[11/01/2018 00:02:14 > b0c2e2: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/01/2018 00:02:14 > b0c2e2: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/01/2018 00:02:14 > b0c2e2: INFO]    Authentication Target: Client
[11/01/2018 00:02:14 > b0c2e2: INFO]    
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 6d9c22cd-3d8b-46e3-9cea-f4dcf9bc7531 - TokenCache: Looking up cache for a token...
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 6d9c22cd-3d8b-46e3-9cea-f4dcf9bc7531 - TokenCache: An item matching the requested resource was found in the cache
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 6d9c22cd-3d8b-46e3-9cea-f4dcf9bc7531 - TokenCache: 58.4111141466667 minutes left until token in cache expires
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 6d9c22cd-3d8b-46e3-9cea-f4dcf9bc7531 - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/01/2018 00:02:14 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/01/2018 00:02:14: 6d9c22cd-3d8b-46e3-9cea-f4dcf9bc7531 - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/01/2018 00:02:14 > b0c2e2: INFO]    Access Token Hash: PHEjzFXAPQZmE0RBrSELqKYsjJd/i7LKtegy8uJOrUY=
[11/01/2018 00:02:14 > b0c2e2: INFO]    Refresh Token Hash: [No Refresh Token]
[11/01/2018 00:02:14 > b0c2e2: INFO]    Expiration Time: 11/01/2018 01:00:39 +00:00
[11/01/2018 00:02:14 > b0c2e2: INFO]    User Hash: null
[11/01/2018 00:02:14 > b0c2e2: INFO]    
[11/01/2018 00:02:19 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : SSL cert added successfully to 'tlstitch'
[11/01/2018 00:02:19 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Sending e-mail notification for tlstitch... 
[11/01/2018 00:02:22 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Finished sending e-mail notification for: tlstitch
[11/01/2018 00:02:22 > b0c2e2: INFO] AzureLetsEncryptRenewer.exe Error: 0 : Unexpected exception: System.AggregateException: Encountered exception(s) during cert renewal (and/or notification) ---> System.Exception: The Lets Encrypt ACME server was probably unable to reach http://beta.pmtaba.com/.well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw view error report from Lets Encrypt at https://acme-v01.api.letsencrypt.org/acme/authz/A2YvQ9NTBxt_tbh9xR7bPg2VJzTYTpZwQL5_9xn03fs for more information
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.AcmeService.<RequestCertificate>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestInternalAsync>d__12.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestAndInstallInternalAsync>d__13.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<AddCertificate>d__9.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.Management.RenewalManager.<Renew>d__6.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal\Management\RenewalManager.cs:line 73
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.AppSettings.AppSettingsRenewer.<Renew>d__4.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\AppSettings\AppSettingsRenewer.cs:line 35
[11/01/2018 00:02:22 > b0c2e2: INFO]    --- End of inner exception stack trace ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.AppSettings.AppSettingsRenewer.<Renew>d__4.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\AppSettings\AppSettingsRenewer.cs:line 47
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.Program.WebJobMain(String webjobName) in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\Program.cs:line 53
[11/01/2018 00:02:22 > b0c2e2: INFO] ---> (Inner Exception #0) System.Exception: The Lets Encrypt ACME server was probably unable to reach http://beta.pmtaba.com/.well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw view error report from Lets Encrypt at https://acme-v01.api.letsencrypt.org/acme/authz/A2YvQ9NTBxt_tbh9xR7bPg2VJzTYTpZwQL5_9xn03fs for more information
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.AcmeService.<RequestCertificate>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestInternalAsync>d__12.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestAndInstallInternalAsync>d__13.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<AddCertificate>d__9.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.Management.RenewalManager.<Renew>d__6.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal\Management\RenewalManager.cs:line 73
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.AppSettings.AppSettingsRenewer.<Renew>d__4.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\AppSettings\AppSettingsRenewer.cs:line 35<---
[11/01/2018 00:02:22 > b0c2e2: INFO] 
[11/01/2018 00:02:22 > b0c2e2: INFO] ---> (Inner Exception #1) System.Exception: The Lets Encrypt ACME server was probably unable to reach http://pmtaba.com/.well-known/acme-challenge/Z3Rdeax7afNBruaCeqSwbJfdnPhFhkPNoJ0HSVPC9M4 view error report from Lets Encrypt at https://acme-v01.api.letsencrypt.org/acme/authz/Clja0OJpg_wuCINArUoM26pA2b3VK5Ryn8jeYzoISys for more information
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.Services.AcmeService.<RequestCertificate>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestInternalAsync>d__12.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestAndInstallInternalAsync>d__13.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: INFO]    at LetsEncrypt.Azure.Core.CertificateManager.<AddCertificate>d__9.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.Management.RenewalManager.<Renew>d__6.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal\Management\RenewalManager.cs:line 73
[11/01/2018 00:02:22 > b0c2e2: INFO] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: INFO]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.AppSettings.AppSettingsRenewer.<Renew>d__4.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\AppSettings\AppSettingsRenewer.cs:line 35<---
[11/01/2018 00:02:22 > b0c2e2: INFO] 
[11/01/2018 00:02:22 > b0c2e2: ERR ] 
[11/01/2018 00:02:22 > b0c2e2: ERR ] Unhandled Exception: System.AggregateException: Encountered exception(s) during cert renewal (and/or notification) ---> System.Exception: The Lets Encrypt ACME server was probably unable to reach http://beta.pmtaba.com/.well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw view error report from Lets Encrypt at https://acme-v01.api.letsencrypt.org/acme/authz/A2YvQ9NTBxt_tbh9xR7bPg2VJzTYTpZwQL5_9xn03fs for more information
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: ERR ] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at LetsEncrypt.Azure.Core.Services.BaseHttpAuthorizationChallengeProvider.<Authorize>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: ERR ] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at LetsEncrypt.Azure.Core.Services.AcmeService.<RequestCertificate>d__5.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: ERR ] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestInternalAsync>d__12.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: ERR ] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at LetsEncrypt.Azure.Core.CertificateManager.<RequestAndInstallInternalAsync>d__13.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: ERR ] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter`1.GetResult()
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at LetsEncrypt.Azure.Core.CertificateManager.<AddCertificate>d__9.MoveNext()
[11/01/2018 00:02:22 > b0c2e2: ERR ] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at OhadSoft.AzureLetsEncrypt.Renewal.Management.RenewalManager.<Renew>d__6.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal\Management\RenewalManager.cs:line 73
[11/01/2018 00:02:22 > b0c2e2: ERR ] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.AppSettings.AppSettingsRenewer.<Renew>d__4.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\AppSettings\AppSettingsRenewer.cs:line 35
[11/01/2018 00:02:22 > b0c2e2: ERR ]    --- End of inner exception stack trace ---
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.AppSettings.AppSettingsRenewer.<Renew>d__4.MoveNext() in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\AppSettings\AppSettingsRenewer.cs:line 47
[11/01/2018 00:02:22 > b0c2e2: ERR ] --- End of stack trace from previous location where exception was thrown ---
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.Program.WebJobMain(String webjobName) in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\Program.cs:line 58
[11/01/2018 00:02:22 > b0c2e2: ERR ]    at OhadSoft.AzureLetsEncrypt.Renewal.WebJob.Program.Main(String[] args) in C:\projects\letsencrypt-webapp-renewer\OhadSoft.AzureLetsEncrypt.Renewal\OhadSoft.AzureLetsEncrypt.Renewal.WebJob\Program.cs:line 33
[11/01/2018 00:02:23 > b0c2e2: SYS INFO] Status changed to Failed
[11/01/2018 00:02:23 > b0c2e2: SYS ERR ] Job failed due to exit code -532462766
ohadschn commented 6 years ago
  1. When you say "manually" do you mean by clicking the "Run" button in the app's WebJob pane in the Azure portal?
  2. Can you share the logs when running manually? There shouldn't be any difference...
  3. Looking at the actual error The Lets Encrypt ACME server was probably unable to reach http://beta.pmtaba.com/.well-known/acme-challenge/FLP9Nz9WzMbol9y7cJwvvqb1c4c2614PhSqgz4asstw it's strange, because I can actually browse that URL just fine. Maybe it was some transient issue with the Web App and simply trying again would work...
braudabaugh commented 6 years ago

Yes, by manual I mean clicking the "run" button. I concur with your point #3, but still no idea how to solve it. Here is the log from the "manual" run that succeeds:

[11/25/2018 01:02:49 > 181c66: SYS INFO] Status changed to Initializing
[11/25/2018 01:02:56 > 181c66: SYS INFO] Run script 'AzureLetsEncryptRenewer.exe' with script host - 'WindowsScriptHost'
[11/25/2018 01:02:56 > 181c66: SYS INFO] Status changed to Running
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Web App SSL renewal job (renewer) started
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsing Web Apps for SSL renewal from webjob/site configuration...
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsed web apps for SSL renewal: spool{beta}; spool; tlstitch
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsed shared parameters: ResourceGroup: , SubscriptionId: be89b0c3-5854-4dcb-a456-aaabe7d91a5f, TenantId: ser266cornell.onmicrosoft.com, ClientId: 57325623-4186-45f6-8d38-bac45edb5e51, ClientSecret: <SCRUBBED>, Email: sam@threadlearning.com, ServicePlanResourceGroup: , UseIpBasedSsl: , RsaKeyLength: , AcmeBaseUri: , RenewXNumberOfDaysBeforeExpiration: , AuthenticationUri: , AzureTokenAudience: , AzureManagementEndpoint: , AzureDefaultWebsiteDomainName: 
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsing SSL renewal parameters for web app 'spool{beta}'...
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsing SSL renewal parameters for web app 'spool'...
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Parsing SSL renewal parameters for web app 'tlstitch'...
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Completed parsing of Web App SSL cert renewal information
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating SSL certificate with parameters: SubscriptionId: be89b0c3-5854-4dcb-a456-aaabe7d91a5f, TenantId: ser266cornell.onmicrosoft.com, ResourceGroup: spool, WebApp: spool, Hosts: System.String[], Email: sam@threadlearning.com, ClientId: 57325623-4186-45f6-8d38-bac45edb5e51, ClientSecret: <SCRUBBED>, ServicePlanResourceGroup: , SiteSlotName: beta, UseIpBasedSsl: False, RsaKeyLength: 2048, AcmeBaseUri: , RenewXNumberOfDaysBeforeExpiration: -1, AuthenticationUri: , AzureTokenAudience: , AzureManagementEndpoint: , AzureDefaultWebsiteDomainName: 
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating secure PFX password for 'spool'...
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Adding SSL cert for 'spool'...
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:02:58:  - AuthenticationContext: ADAL .NET with assembly version '2.28.3.860', file version '2.28.31117.1411' and informational version '78bd21073cfd91768d97894ace1ba90c5b904eec' is running...
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:02:58: 1599fe55-a47e-44a5-9140-3a6a07f61fcc - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:02:58 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:02:58 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:02:58 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:02:58 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (0 items)
[11/25/2018 01:02:58 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:02:58 > 181c66: INFO]    
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:02:58: 1599fe55-a47e-44a5-9140-3a6a07f61fcc - TokenCache: Looking up cache for a token...
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:02:58: 1599fe55-a47e-44a5-9140-3a6a07f61fcc - TokenCache: No matching token was found in the cache
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:02:58: 1599fe55-a47e-44a5-9140-3a6a07f61fcc - TokenCache: Storing token in the cache...
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:02:58: 1599fe55-a47e-44a5-9140-3a6a07f61fcc - TokenCache: An item was stored in the cache
[11/25/2018 01:02:58 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:02:58: 1599fe55-a47e-44a5-9140-3a6a07f61fcc - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:02:58 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:02:58 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:02:58 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:02:58 > 181c66: INFO]    User Hash: null
[11/25/2018 01:02:58 > 181c66: INFO]    
[11/25/2018 01:03:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Staring add certificate
[11/25/2018 01:03:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:00: 506ce66a-9e5c-420d-979b-50dab52c957b - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:03:00 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:03:00 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:03:00 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:03:00 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:03:00 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:03:00 > 181c66: INFO]    
[11/25/2018 01:03:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:00: 506ce66a-9e5c-420d-979b-50dab52c957b - TokenCache: Looking up cache for a token...
[11/25/2018 01:03:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:00: 506ce66a-9e5c-420d-979b-50dab52c957b - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:03:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:00: 506ce66a-9e5c-420d-979b-50dab52c957b - TokenCache: 59.9786757 minutes left until token in cache expires
[11/25/2018 01:03:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:00: 506ce66a-9e5c-420d-979b-50dab52c957b - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:03:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:00: 506ce66a-9e5c-420d-979b-50dab52c957b - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:03:00 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:03:00 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:03:00 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:03:00 > 181c66: INFO]    User Hash: null
[11/25/2018 01:03:00 > 181c66: INFO]    
[11/25/2018 01:03:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Add certificate for acmeConfig hostname beta.pmtaba.com
[11/25/2018 01:03:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : RequestAndInstallInternal
[11/25/2018 01:03:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Signer from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Signer
[11/25/2018 01:03:01 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 
[11/25/2018 01:03:01 > 181c66: INFO] Getting AcmeServerDirectory
[11/25/2018 01:03:01 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Registration from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Registration
[11/25/2018 01:03:15 > 181c66: INFO] 
[11/25/2018 01:03:15 > 181c66: INFO] Authorizing Identifier beta.pmtaba.com Using Challenge Type http-01
[11/25/2018 01:03:15 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Authorizing Identifier beta.pmtaba.com Using Challenge Type http-01
[11/25/2018 01:03:16 > 181c66: INFO]  Answer should now be browsable at http://beta.pmtaba.com/.well-known/acme-challenge/fY-37SYzmZaJp04veoL0JpYcaT2RlLShi1Xe7Bj7neU
[11/25/2018 01:03:16 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Answer should now be browsable at http://beta.pmtaba.com/.well-known/acme-challenge/fY-37SYzmZaJp04veoL0JpYcaT2RlLShi1Xe7Bj7neU
[11/25/2018 01:03:18 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Checking status OK
[11/25/2018 01:03:18 > 181c66: INFO]  Submitting answer
[11/25/2018 01:03:18 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Submitting answer
[11/25/2018 01:03:18 > 181c66: INFO]  Refreshing authorization attempt 1
[11/25/2018 01:03:18 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 1
[11/25/2018 01:03:20 > 181c66: INFO]  Authorization Result: valid
[11/25/2018 01:03:20 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Auth Result valid
[11/25/2018 01:03:20 > 181c66: INFO]  Deleting answer
[11/25/2018 01:03:20 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Deleting answer
[11/25/2018 01:03:20 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : RSAKeyBits: 2048
[11/25/2018 01:03:26 > 181c66: INFO] 
[11/25/2018 01:03:26 > 181c66: INFO] Requesting Certificate
[11/25/2018 01:03:26 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Requesting Certificate
[11/25/2018 01:03:28 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : certRequ ACMESharp.CertificateRequest
[11/25/2018 01:03:28 > 181c66: INFO]  Request Status: Created
[11/25/2018 01:03:28 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Request Status: Created
[11/25/2018 01:03:28 > 181c66: INFO]  Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\beta.pmtaba.com-crt.der
[11/25/2018 01:03:28 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\beta.pmtaba.com-crt.der
[11/25/2018 01:03:28 > 181c66: INFO]  Saving Issuer Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\ca-0A0141420000015385736A0B85ECA708-crt.pem
[11/25/2018 01:03:28 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Issuer Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\ca-0A0141420000015385736A0B85ECA708-crt.pem
[11/25/2018 01:03:28 > 181c66: INFO]  Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\beta.pmtaba.com-all.pfx
[11/25/2018 01:03:28 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\beta.pmtaba.com-all.pfx
[11/25/2018 01:03:29 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:29: ae1f919d-4d30-481d-b460-afca4df679c0 - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:03:29 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:03:29 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:03:29 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:03:29 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:03:29 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:03:29 > 181c66: INFO]    
[11/25/2018 01:03:29 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:29: ae1f919d-4d30-481d-b460-afca4df679c0 - TokenCache: Looking up cache for a token...
[11/25/2018 01:03:29 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:29: ae1f919d-4d30-481d-b460-afca4df679c0 - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:03:29 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:29: ae1f919d-4d30-481d-b460-afca4df679c0 - TokenCache: 59.4884401833333 minutes left until token in cache expires
[11/25/2018 01:03:29 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:29: ae1f919d-4d30-481d-b460-afca4df679c0 - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:03:29 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:29: ae1f919d-4d30-481d-b460-afca4df679c0 - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:03:29 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:03:29 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:03:29 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:03:29 > 181c66: INFO]    User Hash: null
[11/25/2018 01:03:29 > 181c66: INFO]    
[11/25/2018 01:03:30 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Installing certificate D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\beta.pmtaba.com-all.pfx on azure with server farm id /subscriptions/be89b0c3-5854-4dcb-a456-aaabe7d91a5f/resourceGroups/app.threadlearning.com/providers/Microsoft.Web/serverfarms/threadlearning
[11/25/2018 01:03:30 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:30: dd42ec8f-461f-43b9-8ec5-c18c0dce6e0c - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:03:30 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:03:30 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:03:30 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:03:30 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:03:30 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:03:30 > 181c66: INFO]    
[11/25/2018 01:03:30 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:30: dd42ec8f-461f-43b9-8ec5-c18c0dce6e0c - TokenCache: Looking up cache for a token...
[11/25/2018 01:03:30 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:30: dd42ec8f-461f-43b9-8ec5-c18c0dce6e0c - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:03:30 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:30: dd42ec8f-461f-43b9-8ec5-c18c0dce6e0c - TokenCache: 59.4775968783333 minutes left until token in cache expires
[11/25/2018 01:03:30 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:30: dd42ec8f-461f-43b9-8ec5-c18c0dce6e0c - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:03:30 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:30: dd42ec8f-461f-43b9-8ec5-c18c0dce6e0c - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:03:30 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:03:30 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:03:30 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:03:30 > 181c66: INFO]    User Hash: null
[11/25/2018 01:03:30 > 181c66: INFO]    
[11/25/2018 01:03:35 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : SSL cert added successfully to 'spool'
[11/25/2018 01:03:35 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Sending e-mail notification for spool... 
[11/25/2018 01:03:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Finished sending e-mail notification for: spool
[11/25/2018 01:03:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating SSL certificate with parameters: SubscriptionId: be89b0c3-5854-4dcb-a456-aaabe7d91a5f, TenantId: ser266cornell.onmicrosoft.com, ResourceGroup: spool, WebApp: spool, Hosts: System.String[], Email: sam@threadlearning.com, ClientId: 57325623-4186-45f6-8d38-bac45edb5e51, ClientSecret: <SCRUBBED>, ServicePlanResourceGroup: , SiteSlotName: , UseIpBasedSsl: False, RsaKeyLength: 2048, AcmeBaseUri: , RenewXNumberOfDaysBeforeExpiration: -1, AuthenticationUri: , AzureTokenAudience: , AzureManagementEndpoint: , AzureDefaultWebsiteDomainName: 
[11/25/2018 01:03:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating secure PFX password for 'spool'...
[11/25/2018 01:03:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Adding SSL cert for 'spool'...
[11/25/2018 01:03:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:37: 8be049e3-e796-4dba-b629-dd19efc723bc - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:03:37 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:03:37 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:03:37 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:03:37 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:03:37 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:03:37 > 181c66: INFO]    
[11/25/2018 01:03:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:37: 8be049e3-e796-4dba-b629-dd19efc723bc - TokenCache: Looking up cache for a token...
[11/25/2018 01:03:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:37: 8be049e3-e796-4dba-b629-dd19efc723bc - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:03:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:37: 8be049e3-e796-4dba-b629-dd19efc723bc - TokenCache: 59.3523324716667 minutes left until token in cache expires
[11/25/2018 01:03:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:37: 8be049e3-e796-4dba-b629-dd19efc723bc - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:03:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:37: 8be049e3-e796-4dba-b629-dd19efc723bc - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:03:37 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:03:37 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:03:37 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:03:37 > 181c66: INFO]    User Hash: null
[11/25/2018 01:03:37 > 181c66: INFO]    
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Staring add certificate
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:38: d6cd28ae-3bd3-4f83-a1b5-f700f60efa4e - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:03:38 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:03:38 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:03:38 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:03:38 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:03:38 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:03:38 > 181c66: INFO]    
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:38: d6cd28ae-3bd3-4f83-a1b5-f700f60efa4e - TokenCache: Looking up cache for a token...
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:38: d6cd28ae-3bd3-4f83-a1b5-f700f60efa4e - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:38: d6cd28ae-3bd3-4f83-a1b5-f700f60efa4e - TokenCache: 59.3364469216667 minutes left until token in cache expires
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:38: d6cd28ae-3bd3-4f83-a1b5-f700f60efa4e - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:03:38: d6cd28ae-3bd3-4f83-a1b5-f700f60efa4e - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:03:38 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:03:38 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:03:38 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:03:38 > 181c66: INFO]    User Hash: null
[11/25/2018 01:03:38 > 181c66: INFO]    
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Add certificate for acmeConfig hostname www.pmtaba.com, pmtaba.com
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : RequestAndInstallInternal
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Signer from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Signer
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 
[11/25/2018 01:03:38 > 181c66: INFO] Getting AcmeServerDirectory
[11/25/2018 01:03:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Registration from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Registration
[11/25/2018 01:03:45 > 181c66: INFO] 
[11/25/2018 01:03:45 > 181c66: INFO] Authorizing Identifier www.pmtaba.com Using Challenge Type http-01
[11/25/2018 01:03:45 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Authorizing Identifier www.pmtaba.com Using Challenge Type http-01
[11/25/2018 01:03:45 > 181c66: INFO]  Answer should now be browsable at http://www.pmtaba.com/.well-known/acme-challenge/CqssVmMpVaybdremcn4W-pY9n_CrkIMvkUbEQyuHJSo
[11/25/2018 01:03:45 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Answer should now be browsable at http://www.pmtaba.com/.well-known/acme-challenge/CqssVmMpVaybdremcn4W-pY9n_CrkIMvkUbEQyuHJSo
[11/25/2018 01:03:52 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Checking status OK
[11/25/2018 01:03:52 > 181c66: INFO]  Submitting answer
[11/25/2018 01:03:52 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Submitting answer
[11/25/2018 01:03:52 > 181c66: INFO]  Authorization Result: valid
[11/25/2018 01:03:52 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Auth Result valid
[11/25/2018 01:03:52 > 181c66: INFO]  Deleting answer
[11/25/2018 01:03:52 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Deleting answer
[11/25/2018 01:03:52 > 181c66: INFO] 
[11/25/2018 01:03:52 > 181c66: INFO] Authorizing Identifier pmtaba.com Using Challenge Type http-01
[11/25/2018 01:03:52 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Authorizing Identifier pmtaba.com Using Challenge Type http-01
[11/25/2018 01:04:00 > 181c66: INFO]  Answer should now be browsable at http://pmtaba.com/.well-known/acme-challenge/CXG_lqT6Ct_Chbxj7ME21OfgdA_dmGi1-RUMpE9OFEg
[11/25/2018 01:04:00 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Answer should now be browsable at http://pmtaba.com/.well-known/acme-challenge/CXG_lqT6Ct_Chbxj7ME21OfgdA_dmGi1-RUMpE9OFEg
[11/25/2018 01:04:01 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Checking status OK
[11/25/2018 01:04:01 > 181c66: INFO]  Submitting answer
[11/25/2018 01:04:01 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Submitting answer
[11/25/2018 01:04:01 > 181c66: INFO]  Refreshing authorization attempt 1
[11/25/2018 01:04:01 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Refreshing authorization attempt 1
[11/25/2018 01:04:03 > 181c66: INFO]  Authorization Result: valid
[11/25/2018 01:04:03 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Auth Result valid
[11/25/2018 01:04:03 > 181c66: INFO]  Deleting answer
[11/25/2018 01:04:03 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Deleting answer
[11/25/2018 01:04:03 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : RSAKeyBits: 2048
[11/25/2018 01:04:08 > 181c66: INFO] 
[11/25/2018 01:04:08 > 181c66: INFO] Requesting Certificate
[11/25/2018 01:04:08 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Requesting Certificate
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : certRequ ACMESharp.CertificateRequest
[11/25/2018 01:04:10 > 181c66: INFO]  Request Status: Created
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Request Status: Created
[11/25/2018 01:04:10 > 181c66: INFO]  Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\www.pmtaba.com-crt.der
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\www.pmtaba.com-crt.der
[11/25/2018 01:04:10 > 181c66: INFO]  Saving Issuer Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\ca-0A0141420000015385736A0B85ECA708-crt.pem
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Issuer Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\ca-0A0141420000015385736A0B85ECA708-crt.pem
[11/25/2018 01:04:10 > 181c66: INFO]  Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\www.pmtaba.com-all.pfx
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\www.pmtaba.com-all.pfx
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:10: 40595a67-10e1-4727-a188-ef32067e010b - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:04:10 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:04:10 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:04:10 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:04:10 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:04:10 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:04:10 > 181c66: INFO]    
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:10: 40595a67-10e1-4727-a188-ef32067e010b - TokenCache: Looking up cache for a token...
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:10: 40595a67-10e1-4727-a188-ef32067e010b - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:10: 40595a67-10e1-4727-a188-ef32067e010b - TokenCache: 58.8017938833333 minutes left until token in cache expires
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:10: 40595a67-10e1-4727-a188-ef32067e010b - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:04:10 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:10: 40595a67-10e1-4727-a188-ef32067e010b - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:04:10 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:04:10 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:04:10 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:04:10 > 181c66: INFO]    User Hash: null
[11/25/2018 01:04:10 > 181c66: INFO]    
[11/25/2018 01:04:12 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Installing certificate D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\www.pmtaba.com-all.pfx on azure with server farm id /subscriptions/be89b0c3-5854-4dcb-a456-aaabe7d91a5f/resourceGroups/app.threadlearning.com/providers/Microsoft.Web/serverfarms/threadlearning
[11/25/2018 01:04:12 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:12: df7f1b72-1cca-4eee-b5cc-279506d39fe6 - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:04:12 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:04:12 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:04:12 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:04:12 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:04:12 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:04:12 > 181c66: INFO]    
[11/25/2018 01:04:12 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:12: df7f1b72-1cca-4eee-b5cc-279506d39fe6 - TokenCache: Looking up cache for a token...
[11/25/2018 01:04:12 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:12: df7f1b72-1cca-4eee-b5cc-279506d39fe6 - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:04:12 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:12: df7f1b72-1cca-4eee-b5cc-279506d39fe6 - TokenCache: 58.77286606 minutes left until token in cache expires
[11/25/2018 01:04:12 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:12: df7f1b72-1cca-4eee-b5cc-279506d39fe6 - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:04:12 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:12: df7f1b72-1cca-4eee-b5cc-279506d39fe6 - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:04:12 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:04:12 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:04:12 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:04:12 > 181c66: INFO]    User Hash: null
[11/25/2018 01:04:12 > 181c66: INFO]    
[11/25/2018 01:04:16 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : SSL cert added successfully to 'spool'
[11/25/2018 01:04:16 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Sending e-mail notification for spool... 
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Finished sending e-mail notification for: spool
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating SSL certificate with parameters: SubscriptionId: be89b0c3-5854-4dcb-a456-aaabe7d91a5f, TenantId: ser266cornell.onmicrosoft.com, ResourceGroup: tlstitch, WebApp: tlstitch, Hosts: System.String[], Email: sam@threadlearning.com, ClientId: 57325623-4186-45f6-8d38-bac45edb5e51, ClientSecret: <SCRUBBED>, ServicePlanResourceGroup: , SiteSlotName: , UseIpBasedSsl: False, RsaKeyLength: 2048, AcmeBaseUri: , RenewXNumberOfDaysBeforeExpiration: -1, AuthenticationUri: , AzureTokenAudience: , AzureManagementEndpoint: , AzureDefaultWebsiteDomainName: 
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Generating secure PFX password for 'tlstitch'...
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Adding SSL cert for 'tlstitch'...
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: ed619c27-6d6f-4bfd-a470-7837eb74882c - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:04:17 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:04:17 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:04:17 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:04:17 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:04:17 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:04:17 > 181c66: INFO]    
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: ed619c27-6d6f-4bfd-a470-7837eb74882c - TokenCache: Looking up cache for a token...
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: ed619c27-6d6f-4bfd-a470-7837eb74882c - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: ed619c27-6d6f-4bfd-a470-7837eb74882c - TokenCache: 58.692915935 minutes left until token in cache expires
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: ed619c27-6d6f-4bfd-a470-7837eb74882c - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: ed619c27-6d6f-4bfd-a470-7837eb74882c - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:04:17 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:04:17 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:04:17 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:04:17 > 181c66: INFO]    User Hash: null
[11/25/2018 01:04:17 > 181c66: INFO]    
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Staring add certificate
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: fbfd083d-92ae-4fb6-bc35-40efbf2de103 - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:04:17 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:04:17 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:04:17 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:04:17 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:04:17 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:04:17 > 181c66: INFO]    
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: fbfd083d-92ae-4fb6-bc35-40efbf2de103 - TokenCache: Looking up cache for a token...
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: fbfd083d-92ae-4fb6-bc35-40efbf2de103 - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: fbfd083d-92ae-4fb6-bc35-40efbf2de103 - TokenCache: 58.6871859166667 minutes left until token in cache expires
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: fbfd083d-92ae-4fb6-bc35-40efbf2de103 - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:17: fbfd083d-92ae-4fb6-bc35-40efbf2de103 - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:04:17 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:04:17 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:04:17 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:04:17 > 181c66: INFO]    User Hash: null
[11/25/2018 01:04:17 > 181c66: INFO]    
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Add certificate for acmeConfig hostname accounts.threadlearning.com
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : RequestAndInstallInternal
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Signer from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Signer
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 
[11/25/2018 01:04:17 > 181c66: INFO] Getting AcmeServerDirectory
[11/25/2018 01:04:17 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Loading Registration from D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\Registration
[11/25/2018 01:04:26 > 181c66: INFO] 
[11/25/2018 01:04:26 > 181c66: INFO] Authorizing Identifier accounts.threadlearning.com Using Challenge Type http-01
[11/25/2018 01:04:26 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Authorizing Identifier accounts.threadlearning.com Using Challenge Type http-01
[11/25/2018 01:04:27 > 181c66: INFO]  Answer should now be browsable at http://accounts.threadlearning.com/.well-known/acme-challenge/3gefRgK57syOobL5JD1BQ3I3TlwnHZlfe3-LcldcXlY
[11/25/2018 01:04:27 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Answer should now be browsable at http://accounts.threadlearning.com/.well-known/acme-challenge/3gefRgK57syOobL5JD1BQ3I3TlwnHZlfe3-LcldcXlY
[11/25/2018 01:04:28 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Checking status OK
[11/25/2018 01:04:28 > 181c66: INFO]  Submitting answer
[11/25/2018 01:04:28 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Submitting answer
[11/25/2018 01:04:28 > 181c66: INFO]  Authorization Result: valid
[11/25/2018 01:04:28 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Auth Result valid
[11/25/2018 01:04:28 > 181c66: INFO]  Deleting answer
[11/25/2018 01:04:28 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Deleting answer
[11/25/2018 01:04:28 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : RSAKeyBits: 2048
[11/25/2018 01:04:31 > 181c66: INFO] 
[11/25/2018 01:04:31 > 181c66: INFO] Requesting Certificate
[11/25/2018 01:04:31 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Requesting Certificate
[11/25/2018 01:04:33 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : certRequ ACMESharp.CertificateRequest
[11/25/2018 01:04:33 > 181c66: INFO]  Request Status: Created
[11/25/2018 01:04:33 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Request Status: Created
[11/25/2018 01:04:33 > 181c66: INFO]  Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\accounts.threadlearning.com-crt.der
[11/25/2018 01:04:33 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\accounts.threadlearning.com-crt.der
[11/25/2018 01:04:34 > 181c66: INFO]  Saving Issuer Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\ca-0A0141420000015385736A0B85ECA708-crt.pem
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Issuer Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\ca-0A0141420000015385736A0B85ECA708-crt.pem
[11/25/2018 01:04:34 > 181c66: INFO]  Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\accounts.threadlearning.com-all.pfx
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Saving Certificate to D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\accounts.threadlearning.com-all.pfx
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 28e83dd7-79f2-4e9b-956e-035d62c6d123 - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:04:34 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:04:34 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:04:34 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:04:34 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:04:34 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:04:34 > 181c66: INFO]    
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 28e83dd7-79f2-4e9b-956e-035d62c6d123 - TokenCache: Looking up cache for a token...
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 28e83dd7-79f2-4e9b-956e-035d62c6d123 - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 28e83dd7-79f2-4e9b-956e-035d62c6d123 - TokenCache: 58.4142045416667 minutes left until token in cache expires
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 28e83dd7-79f2-4e9b-956e-035d62c6d123 - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 28e83dd7-79f2-4e9b-956e-035d62c6d123 - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:04:34 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:04:34 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:04:34 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:04:34 > 181c66: INFO]    User Hash: null
[11/25/2018 01:04:34 > 181c66: INFO]    
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Installing certificate D:\home\siteextensions\letsencrypt\config\httpsacme-v01.api.letsencrypt.org\accounts.threadlearning.com-all.pfx on azure with server farm id /subscriptions/be89b0c3-5854-4dcb-a456-aaabe7d91a5f/resourceGroups/app.threadlearning.com/providers/Microsoft.Web/serverfarms/threadlearning
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 9929f2a7-4292-43f9-82b0-42bce6431f98 - AcquireTokenHandlerBase: === Token Acquisition started:
[11/25/2018 01:04:34 > 181c66: INFO]    Authority: https://login.windows.net/ser266cornell.onmicrosoft.com/
[11/25/2018 01:04:34 > 181c66: INFO]    Resource: https://management.core.windows.net/
[11/25/2018 01:04:34 > 181c66: INFO]    ClientId: 57325623-4186-45f6-8d38-bac45edb5e51
[11/25/2018 01:04:34 > 181c66: INFO]    CacheType: Microsoft.IdentityModel.Clients.ActiveDirectory.TokenCache (1 items)
[11/25/2018 01:04:34 > 181c66: INFO]    Authentication Target: Client
[11/25/2018 01:04:34 > 181c66: INFO]    
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 9929f2a7-4292-43f9-82b0-42bce6431f98 - TokenCache: Looking up cache for a token...
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 9929f2a7-4292-43f9-82b0-42bce6431f98 - TokenCache: An item matching the requested resource was found in the cache
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 9929f2a7-4292-43f9-82b0-42bce6431f98 - TokenCache: 58.40749021 minutes left until token in cache expires
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 9929f2a7-4292-43f9-82b0-42bce6431f98 - TokenCache: A matching item (access token or refresh token or both) was found in the cache
[11/25/2018 01:04:34 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : 11/25/2018 01:04:34: 9929f2a7-4292-43f9-82b0-42bce6431f98 - AcquireTokenHandlerBase: === Token Acquisition finished successfully. An access token was retuned:
[11/25/2018 01:04:34 > 181c66: INFO]    Access Token Hash: ve/63lCUBSJ/7rHMrsbFZHWjmPRkfN6p5/J1qhf5tsw=
[11/25/2018 01:04:34 > 181c66: INFO]    Refresh Token Hash: [No Refresh Token]
[11/25/2018 01:04:34 > 181c66: INFO]    Expiration Time: 11/25/2018 02:02:58 +00:00
[11/25/2018 01:04:34 > 181c66: INFO]    User Hash: null
[11/25/2018 01:04:34 > 181c66: INFO]    
[11/25/2018 01:04:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : SSL cert added successfully to 'tlstitch'
[11/25/2018 01:04:37 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Sending e-mail notification for tlstitch... 
[11/25/2018 01:04:38 > 181c66: INFO] AzureLetsEncryptRenewer.exe Information: 0 : Finished sending e-mail notification for: tlstitch
[11/25/2018 01:04:38 > 181c66: SYS INFO] Status changed to Success
ohadschn commented 5 years ago

Apologies for the late reply.

I diffed the logs and couldn't find any difference other than the authorization failing for some reason. Can you verify that this is reproducible? That is, manually running it multiple times works, and multiple schedules fail? I would still like to rule out transient errors.

If that's the case, please post a recent log so we can take a look at the Let's Encrypt report and try and figure our the root cause. Usually one can find the reason in the original repo's issue section or possibly in the Let's Encrypt forums...

ohadschn commented 5 years ago

@braudabaugh was this ever resolved?

braudabaugh commented 5 years ago

Our last renewal worked, so finger crossed that it is resolved. @raudabaugh, is taking over this issue on our end.

ohadschn commented 5 years ago

Thanks for the update, will close for now - feel free to re-open should it arise again.