Self-Hosted runner -automatic updates not working #4071
Description
Describe the bug
The runner stopped updating, and we are already quite behind the latest releases. Since the GitHub action will be moving to new Node.js version we should update all actions, which is not possible with the current 2.319.1 .
For example, in order to use actions/checkout@v5 , we need to have at least v2.327.1 of the runner.
- Automatic updates are not disabled
- Runners have required connectivity to perform the update
- No error in
Runner_log files orSelfUpdatelog files - There were 2 successful updates in the past
# ls -l | grep Self
-rw-r--r-- 1 gitrunner gitrunner 1394 Jul 30 2024 SelfUpdate-20240730-141102.log.succeed
-rw-r--r-- 1 gitrunner gitrunner 1083 Feb 16 2025 SelfUpdate-20250216-155650.log.succeed
-rw-r--r-- 1 gitrunner gitrunner 1083 Jun 1 17:56 SelfUpdate-20250601-155619.log.succeed
To Reproduce
Steps to reproduce the behavior:
- Go to the runner folder
- Check the runner version - 2.319,1
- Latest runner version is 2.238.0
Expected behavior
Runner should receive automatic updates. By default, self-hosted runners should automatically perform a software update whenever a new version of the runner software is available
Runner Version and Platform
2.319
OS of the machine running the runner? OSX/Windows/Linux/...
RHEL 9.6
What's not working?
Automatic updates
Job Log Output
Last successful automatic update SelfUpdate-20250601-155619.log.succeed
[2025-06-01 17:56:19-5123] --------whoami--------
gitrunner
[2025-06-01 17:56:19-5238] --------whoami--------
[2025-06-01 17:56:19-5251] Waiting for Runner.Listener (1511754) to complete
[2025-06-01 17:56:19-5265] Process 1511754 still running
[2025-06-01 17:56:21-0019] Process 1511754 finished running
[2025-06-01 17:56:21-0034] Sleep 1 more second to make sure process exited
[2025-06-01 17:56:22-0018] Delete existing junction bin folder
[2025-06-01 17:56:22-0063] Delete existing junction externals folder
[2025-06-01 17:56:22-0092] Create junction bin folder
[2025-06-01 17:56:22-0119] Create junction externals folder
[2025-06-01 17:56:22-0185] Update succeed
[2025-06-01 17:56:22-0210] update.finished file creation succeed
[2025-06-01 17:56:22-0223] Rename /data/trackandtrace/runner-tt-01/_diag/SelfUpdate-20250601-155619.log to be /data/trackandtrace/runner-tt-01/_diag/SelfUpdate-20250601-155619.log.succeed
renamed '/data/trackandtrace/runner-tt-01/_diag/SelfUpdate-20250601-155619.log' -> '/data/trackandtrace/runner-tt-01/_diag/SelfUpdate-20250601-155619.log.succeed'
Runner and Worker's Diagnostic Logs
[2025-10-07 08:07:31Z INFO HostContext] Configuring anonymous proxy http://<PROXY-REDACTED>:8080 for all HTTP requests.
[2025-10-07 08:07:31Z INFO HostContext] Configuring anonymous proxy http://<PROXY-REDACTED>:8080 for all HTTPS requests.
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Root': '/data/<REDACTED>/runner-tt-01'
[2025-10-07 08:07:31Z INFO HostContext] Well known config file 'Credentials': '/data/<REDACTED>/runner-tt-01/.credentials'
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Root': '/data/<REDACTED>/runner-tt-01'
[2025-10-07 08:07:31Z INFO HostContext] Well known config file 'Runner': '/data/<REDACTED>/runner-tt-01/.runner'
[2025-10-07 08:07:31Z INFO Listener] Runner is built for Linux (X64) - linux-x64.
[2025-10-07 08:07:31Z INFO Listener] RuntimeInformation: Linux 5.14.0-570.42.2.el9_6.x86_64 #1 SMP PREEMPT_DYNAMIC Mon Sep 8 12:15:13 EDT 2025.
[2025-10-07 08:07:31Z INFO Listener] Version: 2.319.1
[2025-10-07 08:07:31Z INFO Listener] Commit: bc6614c04d3ddc20e5ca5d5a87ae2b58dda322ec
[2025-10-07 08:07:31Z INFO Listener] Culture:
[2025-10-07 08:07:31Z INFO Listener] UI Culture:
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Root': '/data/<REDACTED>/runner-tt-01'
[2025-10-07 08:07:31Z INFO Listener] Validating directory permissions for: '/data/<REDACTED>/runner-tt-01'
[2025-10-07 08:07:31Z INFO CommandLineParser] Parse
[2025-10-07 08:07:31Z INFO CommandLineParser] Parsing 3 args
[2025-10-07 08:07:31Z INFO CommandLineParser] parsing argument
[2025-10-07 08:07:31Z INFO CommandLineParser] HasArgs: False
[2025-10-07 08:07:31Z INFO CommandLineParser] Adding Command: run
[2025-10-07 08:07:31Z INFO CommandLineParser] parsing argument
[2025-10-07 08:07:31Z INFO CommandLineParser] HasArgs: True
[2025-10-07 08:07:31Z INFO CommandLineParser] arg: startuptype
[2025-10-07 08:07:31Z INFO CommandLineParser] parsing argument
[2025-10-07 08:07:31Z INFO CommandLineParser] HasArgs: True
[2025-10-07 08:07:31Z INFO CommandLineParser] Adding option 'startuptype': 'service'
[2025-10-07 08:07:31Z INFO Listener] Arguments parsed
[2025-10-07 08:07:31Z INFO Runner] ExecuteCommand
[2025-10-07 08:07:31Z INFO ConfigurationStore] currentAssemblyLocation: /data/<REDACTED>/runner-tt-01/bin.2.319.1/Runner.Listener.dll
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:07:31Z INFO ConfigurationStore] binPath: /data/<REDACTED>/runner-tt-01/bin.2.319.1
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Root': '/data/<REDACTED>/runner-tt-01'
[2025-10-07 08:07:31Z INFO ConfigurationStore] RootFolder: /data/<REDACTED>/runner-tt-01
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Root': '/data/<REDACTED>/runner-tt-01'
[2025-10-07 08:07:31Z INFO HostContext] Well known config file 'Runner': '/data/<REDACTED>/runner-tt-01/.runner'
[2025-10-07 08:07:31Z INFO ConfigurationStore] ConfigFilePath: /data/<REDACTED>/runner-tt-01/.runner
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Root': '/data/<REDACTED>/runner-tt-01'
[2025-10-07 08:07:31Z INFO HostContext] Well known config file 'Credentials': '/data/<REDACTED>/runner-tt-01/.credentials'
[2025-10-07 08:07:31Z INFO ConfigurationStore] CredFilePath: /data/<REDACTED>/runner-tt-01/.credentials
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Root': '/data/<REDACTED>/runner-tt-01'
[2025-10-07 08:07:31Z INFO HostContext] Well known config file 'MigratedCredentials': '/data/<REDACTED>/runner-tt-01/.credentials_migrated'
[2025-10-07 08:07:31Z INFO ConfigurationStore] MigratedCredFilePath: /data/<REDACTED>/runner-tt-01/.credentials_migrated
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Root': '/data/<REDACTED>/runner-tt-01'
[2025-10-07 08:07:31Z INFO HostContext] Well known config file 'Service': '/data/<REDACTED>/runner-tt-01/.service'
[2025-10-07 08:07:31Z INFO ConfigurationStore] ServiceConfigFilePath: /data/<REDACTED>/runner-tt-01/.service
[2025-10-07 08:07:31Z INFO CommandSettings] Flag 'help': 'False'
[2025-10-07 08:07:31Z INFO CommandSettings] Flag 'version': 'False'
[2025-10-07 08:07:31Z INFO CommandSettings] Flag 'commit': 'False'
[2025-10-07 08:07:31Z INFO CommandSettings] Flag 'check': 'False'
[2025-10-07 08:07:31Z INFO CommandSettings] Command 'configure': 'False'
[2025-10-07 08:07:31Z INFO CommandSettings] Command 'remove': 'False'
[2025-10-07 08:07:31Z INFO CommandSettings] Command 'warmup': 'False'
[2025-10-07 08:07:31Z INFO ConfigurationManager] LoadSettings
[2025-10-07 08:07:31Z INFO ConfigurationStore] IsConfigured()
[2025-10-07 08:07:31Z INFO ConfigurationStore] IsConfigured: True
[2025-10-07 08:07:31Z INFO ConfigurationManager] Is configured: True
[2025-10-07 08:07:31Z INFO ConfigurationStore] Read setting file: 316 chars
[2025-10-07 08:07:31Z INFO ConfigurationManager] Settings Loaded
[2025-10-07 08:07:31Z INFO ConfigurationStore] IsServiceConfigured()
[2025-10-07 08:07:31Z INFO ConfigurationStore] IsServiceConfigured: True
[2025-10-07 08:07:31Z INFO CommandSettings] Command 'run': 'True'
[2025-10-07 08:07:31Z INFO ConfigurationStore] IsConfigured()
[2025-10-07 08:07:31Z INFO ConfigurationStore] IsConfigured: True
[2025-10-07 08:07:31Z INFO ConfigurationManager] Is configured: True
[2025-10-07 08:07:31Z INFO Runner] Set runner startup type - Service
[2025-10-07 08:07:31Z INFO CommandSettings] Flag 'once': 'False'
[2025-10-07 08:07:31Z INFO CommandSettings] Flag 'once': 'False'
[2025-10-07 08:07:31Z INFO Runner] RunAsync
[2025-10-07 08:07:31Z INFO ConfigurationManager] LoadSettings
[2025-10-07 08:07:31Z INFO ConfigurationStore] IsConfigured()
[2025-10-07 08:07:31Z INFO ConfigurationStore] IsConfigured: True
[2025-10-07 08:07:31Z INFO ConfigurationManager] Is configured: True
[2025-10-07 08:07:31Z INFO ConfigurationManager] Settings Loaded
[2025-10-07 08:07:31Z INFO MessageListener] {
"IsHostedServer": false,
"AgentId": 3,
"AgentName": "xa032ls11000085-tt-01",
"PoolId": 1,
"PoolName": "Default",
"ServerUrl": "https://<REDACTED>/_services/pipelines/<REDACTED>",
"GitHubUrl": "https://<REDACTED>/<REDACTED>",
"WorkFolder": "_work"
}
[2025-10-07 08:07:31Z INFO MessageListener] Loading Credentials
[2025-10-07 08:07:31Z INFO ConfigurationStore] HasCredentials()
[2025-10-07 08:07:31Z INFO ConfigurationStore] stored True
[2025-10-07 08:07:31Z INFO CredentialManager] GetCredentialProvider
[2025-10-07 08:07:31Z INFO CredentialManager] Creating type OAuth
[2025-10-07 08:07:31Z INFO CredentialManager] Creating credential type: OAuth
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:07:31Z INFO HostContext] Well known directory 'Root': '/data/<REDACTED>/runner-tt-01'
[2025-10-07 08:07:31Z INFO HostContext] Well known config file 'RSACredentials': '/data/<REDACTED>/runner-tt-01/.credentials_rsaparams'
[2025-10-07 08:07:31Z INFO RSAFileKeyManager] Loading RSA key parameters from file /data/<REDACTED>/runner-tt-01/.credentials_rsaparams
[2025-10-07 08:07:32Z INFO MessageListener] Attempt to create session.
[2025-10-07 08:07:32Z INFO MessageListener] Connecting to the Runner Server...
[2025-10-07 08:07:32Z INFO RunnerServer] EstablishVssConnection
[2025-10-07 08:07:32Z INFO RunnerServer] Establish connection with 100 seconds timeout.
[2025-10-07 08:07:32Z INFO GitHubActionsService] Starting operation Location.GetConnectionData
[2025-10-07 08:07:32Z INFO RunnerServer] EstablishVssConnection
[2025-10-07 08:07:32Z INFO RunnerServer] Establish connection with 60 seconds timeout.
[2025-10-07 08:07:32Z INFO GitHubActionsService] Starting operation Location.GetConnectionData
[2025-10-07 08:07:32Z INFO RunnerServer] EstablishVssConnection
[2025-10-07 08:07:32Z INFO RunnerServer] Establish connection with 60 seconds timeout.
[2025-10-07 08:07:32Z INFO GitHubActionsService] Starting operation Location.GetConnectionData
[2025-10-07 08:07:32Z INFO GitHubActionsService] Finished operation Location.GetConnectionData
[2025-10-07 08:07:32Z INFO GitHubActionsService] Finished operation Location.GetConnectionData
[2025-10-07 08:07:33Z INFO GitHubActionsService] Finished operation Location.GetConnectionData
[2025-10-07 08:07:33Z INFO MessageListener] VssConnection created
[2025-10-07 08:07:33Z INFO Terminal] WRITE LINE:
[2025-10-07 08:07:33Z INFO Terminal] WRITE LINE:
[2025-10-07 08:07:33Z INFO RSAFileKeyManager] Loading RSA key parameters from file /data/<REDACTED>/runner-tt-01/.credentials_rsaparams
[2025-10-07 08:07:33Z INFO RSAFileKeyManager] Loading RSA key parameters from file /data/<REDACTED>/runner-tt-01/.credentials_rsaparams
[2025-10-07 08:07:33Z INFO GitHubActionsService] AAD Correlation ID for this token request: Unknown
[2025-10-07 08:07:33Z INFO MessageListener] Session created.
[2025-10-07 08:07:33Z INFO Terminal] WRITE LINE: Current runner version: '2.319.1'
[2025-10-07 08:07:33Z INFO Terminal] WRITE LINE: 2025-10-07 08:07:33Z: Listening for Jobs
[2025-10-07 08:07:33Z INFO JobDispatcher] Set runner/worker IPC timeout to 30 seconds.
[2025-10-07 08:15:17Z INFO RSAFileKeyManager] Loading RSA key parameters from file /data/<REDACTED>/runner-tt-01/.credentials_rsaparams
[2025-10-07 08:15:18Z INFO MessageListener] Message '571' received from session '<REDACTED>'.
[2025-10-07 08:15:18Z INFO Runner] Received job message of length 27159 from service, with hash '376a638aead2468188af8100235532d963e92d3f9fad150e2be940a2c02bd059'
[2025-10-07 08:15:18Z INFO JobDispatcher] Job request 16316 for plan 917a09c3-ec86-42a5-b115-a5c49d665881 job e4166175-5992-52fe-847f-c5e9f513e046 received.
[2025-10-07 08:15:18Z INFO JobDispatcher] Pull OrchestrationId 917a09c3-ec86-42a5-b115-a5c49d665881.preparation.__default from JWT claims
[2025-10-07 08:15:18Z INFO MessageListener] Received job status event. JobState: Busy
[2025-10-07 08:15:18Z INFO MessageListener] _getMessagesTokenSource is already disposed.
[2025-10-07 08:15:18Z INFO Terminal] WRITE LINE: 2025-10-07 08:15:18Z: Running job: Env Preparation
[2025-10-07 08:15:18Z INFO JobDispatcher] Start renew job request 16316 for job e4166175-5992-52fe-847f-c5e9f513e046.
[2025-10-07 08:15:18Z INFO JobDispatcher] Successfully renew job request 16316, job is valid till 10/07/2025 08:25:18
[2025-10-07 08:15:18Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] Starting process:
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] File name: '/data/<REDACTED>/runner-tt-01/bin.2.319.1/Runner.Worker'
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] Arguments: 'spawnclient 106 109'
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] Working directory: '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] Require exit code zero: 'False'
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] Encoding web name: ; code page: ''
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] Force kill process on cancellation: 'True'
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] Redirected STDIN: 'False'
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] Persist current code page: 'False'
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] Keep redirected STDIN open: 'False'
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] High priority process: 'True'
[2025-10-07 08:15:19Z INFO ProcessInvokerWrapper] Process started with process id 1031498, waiting for process exit.
[2025-10-07 08:15:19Z INFO JobDispatcher] Send job request message to worker for job e4166175-5992-52fe-847f-c5e9f513e046.
[2025-10-07 08:15:19Z INFO ProcessChannel] Sending message of length 27159, with hash '376a638aead2468188af8100235532d963e92d3f9fad150e2be940a2c02bd059'
[2025-10-07 08:15:19Z INFO JobNotification] Entering JobStarted Notification
[2025-10-07 08:15:19Z INFO JobNotification] Entering StartMonitor
[2025-10-07 08:15:23Z INFO ProcessInvokerWrapper] STDOUT/STDERR stream read finished.
[2025-10-07 08:15:23Z INFO ProcessInvokerWrapper] STDOUT/STDERR stream read finished.
[2025-10-07 08:15:23Z INFO ProcessInvokerWrapper] Finished process 1031498 with exit code 102, and elapsed time 00:00:04.1469761.
[2025-10-07 08:15:23Z INFO JobDispatcher] Worker finished for job e4166175-5992-52fe-847f-c5e9f513e046. Code: 102
[2025-10-07 08:15:23Z INFO JobDispatcher] finish job request for job e4166175-5992-52fe-847f-c5e9f513e046 with result: Failed
[2025-10-07 08:15:23Z INFO Terminal] WRITE LINE: 2025-10-07 08:15:23Z: Job Env Preparation completed with result: Failed
[2025-10-07 08:15:23Z INFO JobDispatcher] Stop renew job request for job e4166175-5992-52fe-847f-c5e9f513e046.
[2025-10-07 08:15:23Z INFO JobDispatcher] job renew has been cancelled, stop renew job request 16316.
[2025-10-07 08:15:23Z INFO JobNotification] Entering JobCompleted Notification
[2025-10-07 08:15:23Z INFO JobNotification] Entering EndMonitor
[2025-10-07 08:15:23Z INFO MessageListener] Received job status event. JobState: Online
[2025-10-07 08:15:23Z WARN GitHubActionsService] GET request to https://<REDACTED>/_services/pipelines/WIuCxcvVObe2Gi8YN7pHipgAYWBjHFXXUQdoJhXv7wj0ZGuVun/_apis/distributedtask/pools/1/messages?sessionId=a7615cc8-428e-4be0-a488-36432ccd4bb2&lastMessageId=571&status=Busy&runnerVersion=2.319.1&os=Linux&architecture=X64&disableUpdate=false has been cancelled.
[2025-10-07 08:15:23Z INFO MessageListener] Get messages has been cancelled using local token source. Continue to get messages with new status.
[2025-10-07 08:15:24Z INFO MessageListener] Sleeping for 25.893 seconds before retrying.
[2025-10-07 08:15:50Z INFO MessageListener] Sleeping for 28.273 seconds before retrying.
[2025-10-07 08:17:08Z INFO MessageListener] Sleeping for 18.857 seconds before retrying.
[2025-10-07 08:18:17Z INFO MessageListener] Sleeping for 16.505 seconds before retrying.
[2025-10-07 08:19:04Z INFO RSAFileKeyManager] Loading RSA key parameters from file /data/<REDACTED>/runner-tt-01/.credentials_rsaparams
[2025-10-07 08:19:05Z INFO MessageListener] Message '572' received from session '<REDACTED>'.
[2025-10-07 08:19:05Z INFO Runner] Received job message of length 25287 from service, with hash 'c3a3a80ce898da02bd8247ad3e86a8d7201ba77dad62c6356751a6a0a984b9d4'
[2025-10-07 08:19:05Z INFO JobDispatcher] Job request 16318 for plan 307fc98e-791c-432d-9c50-81347864d428 job e4166175-5992-52fe-847f-c5e9f513e046 received.
[2025-10-07 08:19:05Z INFO JobDispatcher] Pull OrchestrationId 307fc98e-791c-432d-9c50-81347864d428.preparation.__default from JWT claims
[2025-10-07 08:19:05Z INFO MessageListener] Received job status event. JobState: Busy
[2025-10-07 08:19:05Z INFO MessageListener] _getMessagesTokenSource is already disposed.
[2025-10-07 08:19:05Z INFO JobDispatcher] Job request e4166175-5992-52fe-847f-c5e9f513e046 processed succeed.
[2025-10-07 08:19:05Z INFO Terminal] WRITE LINE: 2025-10-07 08:19:05Z: Running job: Env Preparation
[2025-10-07 08:19:05Z INFO JobDispatcher] Start renew job request 16318 for job e4166175-5992-52fe-847f-c5e9f513e046.
[2025-10-07 08:19:05Z INFO JobDispatcher] Successfully renew job request 16318, job is valid till 10/07/2025 08:29:05
[2025-10-07 08:19:05Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] Starting process:
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] File name: '/data/<REDACTED>/runner-tt-01/bin.2.319.1/Runner.Worker'
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] Arguments: 'spawnclient 106 109'
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] Working directory: '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] Require exit code zero: 'False'
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] Encoding web name: ; code page: ''
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] Force kill process on cancellation: 'True'
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] Redirected STDIN: 'False'
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] Persist current code page: 'False'
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] Keep redirected STDIN open: 'False'
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] High priority process: 'True'
[2025-10-07 08:19:05Z INFO ProcessInvokerWrapper] Process started with process id 1032007, waiting for process exit.
[2025-10-07 08:19:05Z INFO JobDispatcher] Send job request message to worker for job e4166175-5992-52fe-847f-c5e9f513e046.
[2025-10-07 08:19:05Z INFO ProcessChannel] Sending message of length 25287, with hash 'c3a3a80ce898da02bd8247ad3e86a8d7201ba77dad62c6356751a6a0a984b9d4'
[2025-10-07 08:19:05Z INFO JobNotification] Entering JobStarted Notification
[2025-10-07 08:19:05Z INFO JobNotification] Entering StartMonitor
[2025-10-07 08:19:08Z INFO ProcessInvokerWrapper] STDOUT/STDERR stream read finished.
[2025-10-07 08:19:08Z INFO ProcessInvokerWrapper] STDOUT/STDERR stream read finished.
[2025-10-07 08:19:08Z INFO ProcessInvokerWrapper] Finished process 1032007 with exit code 102, and elapsed time 00:00:02.8674228.
[2025-10-07 08:19:08Z INFO JobDispatcher] Worker finished for job e4166175-5992-52fe-847f-c5e9f513e046. Code: 102
[2025-10-07 08:19:08Z INFO JobDispatcher] finish job request for job e4166175-5992-52fe-847f-c5e9f513e046 with result: Failed
[2025-10-07 08:19:08Z INFO Terminal] WRITE LINE: 2025-10-07 08:19:08Z: Job Env Preparation completed with result: Failed
[2025-10-07 08:19:08Z INFO JobDispatcher] Stop renew job request for job e4166175-5992-52fe-847f-c5e9f513e046.
[2025-10-07 08:19:08Z INFO JobDispatcher] job renew has been cancelled, stop renew job request 16318.
[2025-10-07 08:19:08Z INFO JobNotification] Entering JobCompleted Notification
[2025-10-07 08:19:08Z INFO JobNotification] Entering EndMonitor
[2025-10-07 08:19:08Z INFO MessageListener] Received job status event. JobState: Online
[2025-10-07 08:19:08Z WARN GitHubActionsService] GET request to https://<REDACTED>/_services/pipelines/WIuCxcvVObe2Gi8YN7pHipgAYWBjHFXXUQdoJhXv7wj0ZGuVun/_apis/distributedtask/pools/1/messages?sessionId=a7615cc8-428e-4be0-a488-36432ccd4bb2&lastMessageId=572&status=Busy&runnerVersion=2.319.1&os=Linux&architecture=X64&disableUpdate=false has been cancelled.
[2025-10-07 08:19:08Z INFO MessageListener] Get messages has been cancelled using local token source. Continue to get messages with new status.
[2025-10-07 08:19:09Z INFO MessageListener] Sleeping for 20.34 seconds before retrying.
[2025-10-07 08:19:29Z INFO MessageListener] Sleeping for 27.857 seconds before retrying.
[2025-10-07 08:20:47Z INFO MessageListener] Sleeping for 28.923 seconds before retrying.
[2025-10-07 08:21:17Z INFO RSAFileKeyManager] Loading RSA key parameters from file /data/<REDACTED>/runner-tt-01/.credentials_rsaparams
[2025-10-07 08:21:17Z INFO MessageListener] Message '573' received from session '<REDACTED>'.
[2025-10-07 08:21:17Z INFO Runner] Received job message of length 25304 from service, with hash 'd46aaedf491d4f05a5a724df355fcbf600f73d9d98ff2975ce832ee28adcc6b7'
[2025-10-07 08:21:17Z INFO JobDispatcher] Job request 16319 for plan 7d174a0c-a0f9-4381-8f28-a059a06b728d job e4166175-5992-52fe-847f-c5e9f513e046 received.
[2025-10-07 08:21:17Z INFO JobDispatcher] Pull OrchestrationId 7d174a0c-a0f9-4381-8f28-a059a06b728d.preparation.__default from JWT claims
[2025-10-07 08:21:17Z INFO MessageListener] Received job status event. JobState: Busy
[2025-10-07 08:21:17Z INFO MessageListener] _getMessagesTokenSource is already disposed.
[2025-10-07 08:21:17Z INFO JobDispatcher] Job request e4166175-5992-52fe-847f-c5e9f513e046 processed succeed.
[2025-10-07 08:21:17Z INFO Terminal] WRITE LINE: 2025-10-07 08:21:17Z: Running job: Env Preparation
[2025-10-07 08:21:17Z INFO JobDispatcher] Start renew job request 16319 for job e4166175-5992-52fe-847f-c5e9f513e046.
[2025-10-07 08:21:18Z INFO JobDispatcher] Successfully renew job request 16319, job is valid till 10/07/2025 08:31:18
[2025-10-07 08:21:18Z INFO HostContext] Well known directory 'Bin': '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] Starting process:
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] File name: '/data/<REDACTED>/runner-tt-01/bin.2.319.1/Runner.Worker'
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] Arguments: 'spawnclient 106 109'
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] Working directory: '/data/<REDACTED>/runner-tt-01/bin.2.319.1'
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] Require exit code zero: 'False'
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] Encoding web name: ; code page: ''
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] Force kill process on cancellation: 'True'
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] Redirected STDIN: 'False'
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] Persist current code page: 'False'
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] Keep redirected STDIN open: 'False'
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] High priority process: 'True'
[2025-10-07 08:21:18Z INFO ProcessInvokerWrapper] Process started with process id 1033385, waiting for process exit.
[2025-10-07 08:21:18Z INFO JobDispatcher] Send job request message to worker for job e4166175-5992-52fe-847f-c5e9f513e046.
[2025-10-07 08:21:18Z INFO ProcessChannel] Sending message of length 25304, with hash 'd46aaedf491d4f05a5a724df355fcbf600f73d9d98ff2975ce832ee28adcc6b7'
[2025-10-07 08:21:18Z INFO JobNotification] Entering JobStarted Notification
[2025-10-07 08:21:18Z INFO JobNotification] Entering StartMonitor
[2025-10-07 08:21:20Z INFO ProcessInvokerWrapper] STDOUT/STDERR stream read finished.
[2025-10-07 08:21:20Z INFO ProcessInvokerWrapper] STDOUT/STDERR stream read finished.
[2025-10-07 08:21:20Z INFO ProcessInvokerWrapper] Finished process 1033385 with exit code 102, and elapsed time 00:00:02.4891897.
[2025-10-07 08:21:20Z INFO JobDispatcher] Worker finished for job e4166175-5992-52fe-847f-c5e9f513e046. Code: 102
[2025-10-07 08:21:20Z INFO JobDispatcher] finish job request for job e4166175-5992-52fe-847f-c5e9f513e046 with result: Failed
[2025-10-07 08:21:20Z INFO Terminal] WRITE LINE: 2025-10-07 08:21:20Z: Job Env Preparation completed with result: Failed
[2025-10-07 08:21:20Z INFO JobDispatcher] Stop renew job request for job e4166175-5992-52fe-847f-c5e9f513e046.
[2025-10-07 08:21:20Z INFO JobDispatcher] job renew has been cancelled, stop renew job request 16319.
[2025-10-07 08:21:20Z INFO JobNotification] Entering JobCompleted Notification
[2025-10-07 08:21:20Z INFO JobNotification] Entering EndMonitor
[2025-10-07 08:21:20Z INFO MessageListener] Received job status event. JobState: Online
[2025-10-07 08:21:20Z WARN GitHubActionsService] GET request to https://<REDACTED>/_services/pipelines/WIuCxcvVObe2Gi8YN7pHipgAYWBjHFXXUQdoJhXv7wj0ZGuVun/_apis/distributedtask/pools/1/messages?sessionId=a7615cc8-428e-4be0-a488-36432ccd4bb2&lastMessageId=573&status=Busy&runnerVersion=2.319.1&os=Linux&architecture=X64&disableUpdate=false has been cancelled.
[2025-10-07 08:21:20Z INFO MessageListener] Get messages has been cancelled using local token source. Continue to get messages with new status.
[2025-10-07 08:21:21Z INFO MessageListener] Sleeping for 27.504 seconds before retrying.
[2025-10-07 08:21:49Z INFO MessageListener] Sleeping for 22.681 seconds before retrying.
[2025-10-07 08:23:02Z INFO MessageListener] Sleeping for 27.821 seconds before retrying.