Hi @thboop ,
good to know (documentation: specifically the sections on differences and communication led me to believe that self-hosted runners were not yet self-updating).
The Runner log is included below (looks as if the update was nearly done?). I had to delete a few lines to stay in the message length limit.
Thanks,
Claus
[2019-11-13 13:27:28Z INFO Listener] Runner is built for Linux (X64) - linux-x64.
[2019-11-13 13:27:28Z INFO Listener] RuntimeInformation: Linux 3.10.0-957.el7.x86_64 #1 SMP Thu Nov 8 23:39:32 UTC 2018.
[2019-11-13 13:27:28Z INFO Listener] Version: 2.160.0
[2019-11-13 13:27:28Z INFO Listener] Commit: 1413297394f94a1b768bda66254fc658b4a04591
[2019-11-13 13:27:28Z INFO Listener] Culture: en-US
[2019-11-13 13:27:28Z INFO Listener] UI Culture: en-US
[2019-11-13 13:27:28Z INFO Listener] Validating directory permissions for: '/home/---/actions-runner'
[2019-11-13 13:27:28Z INFO CommandLineParser] Parse
[2019-11-13 13:27:28Z INFO CommandLineParser] Parsing 1 args
[2019-11-13 13:27:28Z INFO CommandLineParser] parsing argument
[2019-11-13 13:27:28Z INFO CommandLineParser] HasArgs: False
[2019-11-13 13:27:28Z INFO CommandLineParser] Adding Command: run
[2019-11-13 13:27:28Z INFO Listener] Arguments parsed
[2019-11-13 13:27:28Z INFO HostContext] Well known config file 'Proxy': '/home/---/actions-runner/.proxy'
[2019-11-13 13:27:28Z INFO RunnerWebProxy] No proxy setting found.
[2019-11-13 13:27:28Z INFO HostContext] Well known config file 'Certificates': '/home/---/actions-runner/.certificates'
[2019-11-13 13:27:28Z INFO RunnerCertificateManager] No certificate setting found.
[2019-11-13 13:27:28Z INFO Runner] ExecuteCommand
[2019-11-13 13:27:28Z INFO ConfigurationStore] currentAssemblyLocation: /home/---/actions-runner/bin/Runner.Listener.dll
[2019-11-13 13:27:28Z INFO ConfigurationStore] binPath: /home/---/actions-runner/bin
[2019-11-13 13:27:28Z INFO ConfigurationStore] RootFolder: /home/---/actions-runner
[2019-11-13 13:27:28Z INFO HostContext] Well known config file 'Runner': '/home/---/actions-runner/.runner'
[2019-11-13 13:27:28Z INFO ConfigurationStore] ConfigFilePath: /home/---/actions-runner/.runner
[2019-11-13 13:27:28Z INFO HostContext] Well known config file 'Credentials': '/home/---/actions-runner/.credentials'
[2019-11-13 13:27:28Z INFO ConfigurationStore] CredFilePath: /home/---/actions-runner/.credentials
[2019-11-13 13:27:28Z INFO HostContext] Well known config file 'Service': '/home/---/actions-runner/.service'
[2019-11-13 13:27:28Z INFO ConfigurationStore] ServiceConfigFilePath: /home/---/actions-runner/.service
[2019-11-13 13:27:28Z INFO HostContext] Well known config file 'Options': '/home/---/actions-runner/.options'
[2019-11-13 13:27:28Z INFO ConfigurationStore] RuntimeOptionsFilePath: /home/---/actions-runner/.options
[2019-11-13 13:27:28Z INFO CommandSettings] Flag 'help': 'False'
[2019-11-13 13:27:28Z INFO CommandSettings] Flag 'version': 'False'
[2019-11-13 13:27:28Z INFO CommandSettings] Flag 'commit': 'False'
[2019-11-13 13:27:28Z INFO CommandSettings] Command 'configure': 'False'
[2019-11-13 13:27:28Z INFO CommandSettings] Command 'remove': 'False'
[2019-11-13 13:27:28Z INFO CommandSettings] Command 'warmup': 'False'
[2019-11-13 13:27:28Z INFO ConfigurationManager] LoadSettings
[2019-11-13 13:27:28Z INFO ConfigurationStore] IsConfigured()
[2019-11-13 13:27:28Z INFO ConfigurationStore] IsConfigured: True
[2019-11-13 13:27:28Z INFO ConfigurationManager] Is configured: True
[2019-11-13 13:27:28Z INFO ConfigurationStore] Read setting file: 309 chars
[2019-11-13 13:27:28Z INFO ConfigurationManager] Settings Loaded
[2019-11-13 13:27:28Z INFO ConfigurationStore] IsServiceConfigured()
[2019-11-13 13:27:28Z INFO ConfigurationStore] IsServiceConfigured: False
[2019-11-13 13:27:28Z INFO CommandSettings] Command 'run': 'True'
[2019-11-13 13:27:28Z INFO ConfigurationStore] IsConfigured()
[2019-11-13 13:27:28Z INFO ConfigurationStore] IsConfigured: True
[2019-11-13 13:27:28Z INFO ConfigurationManager] Is configured: True
[2019-11-13 13:27:28Z INFO Runner] Could not parse the argument value '' for StartupType. Defaulting to Manual
[2019-11-13 13:27:28Z INFO Runner] Set runner startup type - Manual
[2019-11-13 13:27:28Z INFO CommandSettings] Flag 'once': 'False'
[2019-11-13 13:27:28Z INFO Runner] RunAsync
[2019-11-13 13:27:28Z INFO ConfigurationManager] LoadSettings
[2019-11-13 13:27:28Z INFO ConfigurationStore] IsConfigured()
[2019-11-13 13:27:28Z INFO ConfigurationStore] IsConfigured: True
[2019-11-13 13:27:28Z INFO ConfigurationManager] Is configured: True
[2019-11-13 13:27:28Z INFO ConfigurationManager] Settings Loaded
[2019-11-13 13:27:29Z INFO MessageListener] {
"AgentId": 21,
"AgentName": "-----------------------",
"PoolId": 1,
"PoolName": "Default",
"ServerUrl": "https://pipelines.actions.githubusercontent.com/--------------------------------------------------/",
"GitHubUrl": "https://github.com/------/--------------------",
"WorkFolder": "_work"
}
[2019-11-13 13:27:29Z INFO MessageListener] Loading Credentials
[2019-11-13 13:27:29Z INFO ConfigurationStore] HasCredentials()
[2019-11-13 13:27:29Z INFO ConfigurationStore] stored True
[2019-11-13 13:27:29Z INFO CredentialManager] GetCredentialProvider
[2019-11-13 13:27:29Z INFO CredentialManager] Creating type OAuth
[2019-11-13 13:27:29Z INFO CredentialManager] Creating credential type: OAuth
[2019-11-13 13:27:29Z INFO HostContext] Well known config file 'RSACredentials': '/home/---/actions-runner/.credentials_rsaparams'
[2019-11-13 13:27:29Z INFO RSAFileKeyManager] Loading RSA key parameters from file /home/---/actions-runner/.credentials_rsaparams
[2019-11-13 13:27:29Z INFO MessageListener] Attempt to create session.
[2019-11-13 13:27:29Z INFO MessageListener] Connecting to the Agent Server...
[2019-11-13 13:27:29Z INFO RunnerServer] Establish connection with 100 seconds timeout.
[2019-11-13 13:27:29Z INFO GitHubActionsRunner] Starting operation Location.GetConnectionData
[2019-11-13 13:27:29Z INFO RunnerServer] Establish connection with 60 seconds timeout.
[2019-11-13 13:27:29Z INFO GitHubActionsRunner] Starting operation Location.GetConnectionData
[2019-11-13 13:27:29Z INFO RunnerServer] Establish connection with 60 seconds timeout.
[2019-11-13 13:27:29Z INFO GitHubActionsRunner] Starting operation Location.GetConnectionData
[2019-11-13 13:27:30Z INFO GitHubActionsRunner] Finished operation Location.GetConnectionData
[2019-11-13 13:27:30Z INFO GitHubActionsRunner] Finished operation Location.GetConnectionData
[2019-11-13 13:27:30Z INFO GitHubActionsRunner] Finished operation Location.GetConnectionData
[2019-11-13 13:27:30Z INFO MessageListener] VssConnection created
[2019-11-13 13:27:30Z INFO Terminal] WRITE LINE:
[2019-11-13 13:27:30Z INFO Terminal] WRITE LINE:
[2019-11-13 13:27:30Z WARN GitHubActionsRunner] Authentication failed with status code 401.
Cache-Control: no-cache^M
Pragma: no-cache^M
P3P: CP="----------------------------------------------------------------------------------------------------------------"^M
WWW-Authenticate: Basic realm="https://pipelines.actions.githubusercontent.com/serviceDeployments/pipelinesghubeus21", Bearer, TFS-Federated^M
X-TFS-ProcessId: ------------------------------------^M
Strict-Transport-Security: max-age=31536000; includeSubDomains^M
ActivityId: ------------------------------------^M
X-TFS-Session: ------------------------------------^M
X-VSS-E2EID: ------------------------------------^M
X-Frame-Options: SAMEORIGIN^M
X-TFS-FedAuthRealm: https://pipelines.actions.githubusercontent.com/serviceDeployments/pipelinesghubeus21^M
X-TFS-FedAuthIssuer: https://www.visualstudio.com/^M
X-VSS-AuthorizationEndpoint: https://vssps.actions.githubusercontent.com/--------------------------------------------------/^M
X-VSS-ResourceTenant: 00000000-0000-0000-0000-000000000000^M
X-VSS-S2STargetService: ------------------------------------/visualstudio.com^M
X-TFS-FedAuthRedirect: https://github.com^M
Request-Context: appId=cid-v1:------------------------------------^M
Access-Control-Expose-Headers: Request-Context^M
X-Content-Type-Options: nosniff^M
X-MSEdge-Ref: Ref A: -------------------------------- Ref B: ----------- Ref C: 2019-11-13T13:27:30Z^M
Date: Wed, 13 Nov 2019 13:27:29 GMT^M
[2019-11-13 13:27:30Z INFO RSAFileKeyManager] Loading RSA key parameters from file /home/---/actions-runner/.credentials_rsaparams
[2019-11-13 13:27:30Z INFO RSAFileKeyManager] Loading RSA key parameters from file /home/---/actions-runner/.credentials_rsaparams
[2019-11-13 13:27:30Z INFO GitHubActionsRunner] AAD Correlation ID for this token request: Unknown
[2019-11-13 13:27:31Z INFO MessageListener] Session created.
[2019-11-13 13:27:31Z INFO Terminal] WRITE LINE: 2019-11-13 13:27:31Z: Listening for Jobs
[2019-11-13 13:27:31Z INFO JobDispatcher] Set runner/worker IPC timeout to 30 seconds.
[2019-11-13 13:57:36Z INFO MessageListener] No message retrieved from session '------------------------------------' within last 30 minutes.
[2019-11-13 14:08:22Z INFO RSAFileKeyManager] Loading RSA key parameters from file /home/---/actions-runner/.credentials_rsaparams
[2019-11-13 14:08:22Z INFO MessageListener] Message '23' received from session '------------------------------------'.
[2019-11-13 14:08:22Z INFO Runner] Refresh message received, kick-off selfupdate background process.
[2019-11-13 14:08:23Z INFO SelfUpdater] Version '2.160.2' of 'agent' package available in server.
[2019-11-13 14:08:23Z INFO SelfUpdater] Current running runner version is 2.160.0
[2019-11-13 14:08:23Z INFO SelfUpdater] An update is available.
[2019-11-13 14:08:23Z INFO Terminal] WRITE LINE: Runner update in progress, do not shutdown runner.
[2019-11-13 14:08:23Z INFO Terminal] WRITE LINE: Downloading 2.160.2 runner
[2019-11-13 14:08:23Z INFO SelfUpdater] Attempt 1: save latest runner into /home/---/actions-runner/_work/_update/runner1.tar.gz.
[2019-11-13 14:08:23Z INFO SelfUpdater] Download runner: begin download
[2019-11-13 14:08:41Z INFO SelfUpdater] Download runner: finished download
[2019-11-13 14:08:41Z INFO SelfUpdater] Which: 'tar'
[2019-11-13 14:08:41Z INFO SelfUpdater] Location: '/usr/bin/tar'
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Starting process:
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] File name: '/usr/bin/tar'
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Arguments: '-xzf "/home/---/actions-runner/_work/_update/runner1.tar.gz"'
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Working directory: '/home/---/actions-runner/_work/_update'
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Require exit code zero: 'False'
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Encoding web name: ; code page: ''
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Force kill process on cancellation: 'False'
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Redirected STDIN: 'False'
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Persist current code page: 'False'
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Keep redirected STDIN open: 'False'
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] High priority process: 'False'
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Updated oom_score_adj to 500 for PID: 10037.
[2019-11-13 14:08:41Z INFO ProcessInvokerWrapper] Process started with process id 10037, waiting for process exit.
[2019-11-13 14:08:44Z INFO ProcessInvokerWrapper] STDOUT/STDERR stream read finished.
[2019-11-13 14:08:44Z INFO ProcessInvokerWrapper] STDOUT/STDERR stream read finished.
[2019-11-13 14:08:44Z INFO ProcessInvokerWrapper] Finished process 10037 with exit code 0, and elapsed time 00:00:02.6709466.
[2019-11-13 14:08:44Z INFO SelfUpdater] Finished getting latest runner package at: /home/---/actions-runner/_work/_update.
[2019-11-13 14:08:44Z INFO SelfUpdater] Copy /home/---/actions-runner/_work/_update/bin to /home/---/actions-runner/bin.2.160.2.
[2019-11-13 14:08:44Z INFO SelfUpdater] Copy /home/---/actions-runner/_work/_update/externals to /home/---/actions-runner/externals.2.160.2.
[2019-11-13 14:08:44Z INFO SelfUpdater] Copy any remaining .sh/.cmd files into runner root.
[2019-11-13 14:08:44Z INFO SelfUpdater] Download latest runner and unzip into runner root.
[2019-11-13 14:08:44Z INFO Terminal] WRITE LINE: Waiting for current job finish running.
[2019-11-13 14:08:44Z INFO SelfUpdater] All running job has exited.
[2019-11-13 14:08:44Z INFO SelfUpdater] Delete old version runner backup.
[2019-11-13 14:08:44Z INFO Terminal] WRITE LINE: Generate and execute update script.
[2019-11-13 14:08:44Z INFO SelfUpdater] Generate update script into: /home/---/actions-runner/_work/_update.sh
[2019-11-13 14:08:44Z INFO SelfUpdater] Which: 'bash'
[2019-11-13 14:08:44Z INFO SelfUpdater] Location: '/usr/bin/bash'
[2019-11-13 14:08:44Z INFO SelfUpdater] Update script start running
[2019-11-13 14:08:44Z INFO Terminal] WRITE LINE: Runner will exit shortly for update, should back online within 10 seconds.
[2019-11-13 14:08:44Z INFO Runner] Auto update task finished at backend, an runner update is ready to apply exit the current runner instance.
[2019-11-13 14:08:44Z INFO Runner] Stop message queue looping.
[2019-11-13 14:08:44Z WARN GitHubActionsRunner] GET request to https://pipelines.actions.githubusercontent.com/--------------------------------------------------/_apis/distributedtask/pools/1/messages?sessionId=------------------------------------&lastMessageId=23 has been cancelled.
[2019-11-13 14:08:44Z INFO MessageListener] Get next message has been cancelled.
[2019-11-13 14:08:44Z INFO Runner] Ignore any exception after cancel message loop. System.Threading.Tasks.TaskCanceledException: The operation was canceled.
---> System.IO.IOException: Unable to read data from the transport connection: Operation canceled.
---> System.Net.Sockets.SocketException (125): Operation canceled
--- End of inner exception stack trace ---
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.GetResult(Int16 token)
at System.Net.Security.SslStream.<FillBufferAsync>g__InternalFillBufferAsync|215_0[TReadAdapter](TReadAdapter adap, ValueTask`1 task, Int32 min, Int32 initial)
at System.Net.Security.SslStream.ReadAsyncInternal[TReadAdapter](TReadAdapter adapter, Memory`1 buffer)
at System.Net.Http.HttpConnection.SendAsyncCore(HttpRequestMessage request, CancellationToken cancellationToken)
--- End of inner exception stack trace ---
at System.Net.Http.HttpConnection.SendAsyncCore(HttpRequestMessage request, CancellationToken cancellationToken)
at System.Net.Http.AuthenticationHelper.SendWithNtAuthAsync(HttpRequestMessage request, Uri authUri, ICredentials credentials, Boolean isProxyAuth, HttpConnection connection, HttpConnectionPool connectionPool, CancellationToken cancellationToken)
at System.Net.Http.HttpConnectionPool.SendWithNtConnectionAuthAsync(HttpConnection connection, HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken)
at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken)
at System.Net.Http.AuthenticationHelper.SendWithAuthAsync(HttpRequestMessage request, Uri authUri, ICredentials credentials, Boolean preAuthenticate, Boolean isProxyAuth, Boolean doRequestAuth, HttpConnectionPool pool, CancellationToken cancellationToken)
at System.Net.Http.DecompressionHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken)
at GitHub.Services.Common.VssHttpMessageHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken)
at GitHub.Services.Common.VssHttpRetryMessageHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken)
at System.Net.Http.HttpClient.FinishSendAsyncBuffered(Task`1 sendTask, HttpRequestMessage request, CancellationTokenSource cts, Boolean disposeCts)
at GitHub.Services.WebApi.VssHttpClientBase.SendAsync(HttpRequestMessage message, HttpCompletionOption completionOption, Object userState, CancellationToken cancellationToken)
at GitHub.Services.WebApi.VssHttpClientBase.SendAsync[T](HttpRequestMessage message, Object userState, CancellationToken cancellationToken)
at GitHub.Services.WebApi.VssHttpClientBase.SendAsync[T](HttpMethod method, IEnumerable`1 additionalHeaders, Guid locationId, Object routeValues, ApiResourceVersion version, HttpContent content, IEnumerable`1 queryParameters, Object userState, CancellationToken cancellationToken)
at GitHub.Runner.Listener.MessageListener.GetNextMessageAsync(CancellationToken token)
at GitHub.Runner.Listener.Runner.RunAsync(RunnerSettings settings, Boolean runOnce)
[2019-11-13 14:08:44Z INFO JobDispatcher] Shutting down JobDispatcher. Make sure all WorkerDispatcher has finished.