2026-06-13 00:21:42,092 DEBUG zuul.Scheduler: Configured logging: 14.1.1.dev1 2026-06-13 00:21:42,092 DEBUG zuul.TimerDriver: Starting apscheduler 2026-06-13 00:21:42,093 INFO apscheduler.scheduler: Scheduler started 2026-06-13 00:21:42,093 DEBUG apscheduler.scheduler: Looking for jobs to run 2026-06-13 00:21:42,094 DEBUG apscheduler.scheduler: No jobs; waiting until a job is added 2026-06-13 00:21:42,094 DEBUG zuul.BubblewrapDriver: Initializing bubblewrap with oom_score_adj starting: 995, final: 1000 2026-06-13 00:21:42,096 INFO zuul.SQLConnection: Initializing SQL connection database (prefix: ) 2026-06-13 00:21:42,392 DEBUG zuul.zk.base.ZooKeeperClient: ZooKeeper connection (session: 0x100000f9064000f): CONNECTED 2026-06-13 00:21:42,463 INFO zuul.Component: Registering component in ZooKeeper /zuul/components/scheduler/zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local 2026-06-13 00:21:42,470 INFO zuul.ComponentRegistry: Noticed new scheduler component zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003 2026-06-13 00:21:42,472 DEBUG zuul.ComponentRegistry: Registry got event None for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003 2026-06-13 00:21:42,472 INFO zuul.ComponentRegistry: Component scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003 updated: {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'kind': 'scheduler', 'model_api': 37, 'state': 'stopped', 'version': '14.1.1.dev1 90eea2ed8'} 2026-06-13 00:21:42,474 INFO zuul.ComponentRegistry: Noticed new executor component zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000003 2026-06-13 00:21:42,475 DEBUG zuul.ComponentRegistry: Registry got event None for executor zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000003 2026-06-13 00:21:42,475 INFO zuul.ComponentRegistry: Component executor zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000003 updated: {'accepting_work': True, 'allow_unzoned': True, 'hostname': 'zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local', 'kind': 'executor', 'model_api': 37, 'process_merge_jobs': True, 'state': 'running', 'version': '14.1.1.dev1 90eea2ed8', 'zone': None} 2026-06-13 00:21:42,477 INFO zuul.ComponentRegistry: Noticed new merger component zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000003 2026-06-13 00:21:42,479 DEBUG zuul.ComponentRegistry: Registry got event None for merger zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000003 2026-06-13 00:21:42,479 INFO zuul.ComponentRegistry: Component merger zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000003 updated: {'hostname': 'zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local', 'kind': 'merger', 'model_api': 37, 'state': 'running', 'version': '14.1.1.dev1 90eea2ed8'} 2026-06-13 00:21:42,483 INFO zuul.ComponentRegistry: Noticed new web component zuul-web-7f8547d9bb-n7hqc0000000005 2026-06-13 00:21:42,484 DEBUG zuul.ComponentRegistry: Registry got event None for web zuul-web-7f8547d9bb-n7hqc0000000005 2026-06-13 00:21:42,484 INFO zuul.ComponentRegistry: Component web zuul-web-7f8547d9bb-n7hqc0000000005 updated: {'hostname': 'zuul-web-7f8547d9bb-n7hqc', 'kind': 'web', 'model_api': 37, 'state': 'running', 'version': '14.1.1.dev1 90eea2ed8'} 2026-06-13 00:21:42,486 INFO zuul.ComponentRegistry: System minimum data model version 37; this component 37 2026-06-13 00:21:42,663 DEBUG zuul.zk.ZooKeeper: Initialize cache at /keystorage-oidc 2026-06-13 00:21:42,666 DEBUG zuul.zk.ZooKeeper: Cache at /keystorage-oidc is ready 2026-06-13 00:21:42,672 DEBUG zuul.ExecutorQueue: Creating executor queue at root /zuul/executor/unzoned 2026-06-13 00:21:42,679 DEBUG zuul.zk.ZooKeeper: Initialize cache at /zuul/executor/unzoned 2026-06-13 00:21:42,693 DEBUG zuul.zk.ZooKeeper: Cache at /zuul/executor/unzoned is ready 2026-06-13 00:21:42,699 DEBUG zuul.zk.ZooKeeper: Initialize cache at /zuul/merger 2026-06-13 00:21:42,711 DEBUG zuul.zk.ZooKeeper: Cache at /zuul/merger is ready 2026-06-13 00:21:42,717 DEBUG zuul.nodepool: Running nodepool watcher election 2026-06-13 00:21:42,718 DEBUG zuul.TimerDriver: Starting timer election loop 2026-06-13 00:21:42,725 INFO zuul.TimerDriver: Running timer election 2026-06-13 00:21:42,726 INFO zuul.nodepool: Watching nodepool requests 2026-06-13 00:21:42,730 INFO zuul.TimerDriver: Won timer election 2026-06-13 00:21:42,774 DEBUG zuul.SQLConnection: Current migration revision: 21d30f0bffef 2026-06-13 00:21:42,800 DEBUG zuul.GerritConnection: Starting Gerrit Connection/Watchers 2026-06-13 00:21:42,801 DEBUG zuul.GerritConnection: Creating Zookeeper branch cache 2026-06-13 00:21:42,811 INFO zuul.GerritConnection: Creating Zookeeper event queue 2026-06-13 00:21:42,814 DEBUG zuul.GerritConnection: Creating Zookeeper change cache 2026-06-13 00:21:42,817 INFO zuul.GerritConnection: Starting SSH event stream client 2026-06-13 00:21:42,818 INFO zuul.GerritConnection: gerrit: Gerrit Poller is disabled because no HTTP authentication is defined 2026-06-13 00:21:42,819 DEBUG zuul.connection.git: Creating Zookeeper change cache 2026-06-13 00:21:42,825 DEBUG zuul.connection.git: Starting Git Watcher 2026-06-13 00:21:42,825 DEBUG zuul.connection.git: Creating Zookeeper change cache 2026-06-13 00:21:42,861 DEBUG paramiko.transport: starting thread (client mode): 0xe04ad4d0 2026-06-13 00:21:42,862 DEBUG paramiko.transport: Local version/idstring: SSH-2.0-paramiko_4.0.0 2026-06-13 00:21:42,863 DEBUG paramiko.transport: Remote version/idstring: SSH-2.0-GerritCodeReview_3.6.4 (APACHE-SSHD-2.9.2) 2026-06-13 00:21:42,864 INFO paramiko.transport: Connected (version 2.0, client GerritCodeReview_3.6.4) 2026-06-13 00:21:42,865 DEBUG paramiko.transport: === Key exchange possibilities === 2026-06-13 00:21:42,865 DEBUG paramiko.transport: kex algos: curve25519-sha256, curve25519-sha256@libssh.org, curve448-sha512, ecdh-sha2-nistp521, ecdh-sha2-nistp384, ecdh-sha2-nistp256, diffie-hellman-group-exchange-sha256, diffie-hellman-group18-sha512, diffie-hellman-group17-sha512, diffie-hellman-group16-sha512, diffie-hellman-group15-sha512, diffie-hellman-group14-sha256, ext-info-s 2026-06-13 00:21:42,865 DEBUG paramiko.transport: server key: ecdsa-sha2-nistp256, ecdsa-sha2-nistp384, ecdsa-sha2-nistp521, ssh-ed25519, rsa-sha2-512, rsa-sha2-256, ssh-rsa 2026-06-13 00:21:42,865 DEBUG paramiko.transport: client encrypt: chacha20-poly1305@openssh.com, aes128-ctr, aes192-ctr, aes256-ctr, aes128-gcm@openssh.com, aes256-gcm@openssh.com, aes128-cbc, aes192-cbc, aes256-cbc 2026-06-13 00:21:42,865 DEBUG paramiko.transport: server encrypt: chacha20-poly1305@openssh.com, aes128-ctr, aes192-ctr, aes256-ctr, aes128-gcm@openssh.com, aes256-gcm@openssh.com, aes128-cbc, aes192-cbc, aes256-cbc 2026-06-13 00:21:42,866 DEBUG paramiko.transport: client mac: hmac-sha2-256-etm@openssh.com, hmac-sha2-512-etm@openssh.com, hmac-sha1-etm@openssh.com, hmac-sha2-256, hmac-sha2-512, hmac-sha1 2026-06-13 00:21:42,866 DEBUG paramiko.transport: server mac: hmac-sha2-256-etm@openssh.com, hmac-sha2-512-etm@openssh.com, hmac-sha1-etm@openssh.com, hmac-sha2-256, hmac-sha2-512, hmac-sha1 2026-06-13 00:21:42,866 DEBUG paramiko.transport: client compress: none 2026-06-13 00:21:42,866 DEBUG paramiko.transport: server compress: none 2026-06-13 00:21:42,866 DEBUG paramiko.transport: client lang: 2026-06-13 00:21:42,866 DEBUG paramiko.transport: server lang: 2026-06-13 00:21:42,866 DEBUG paramiko.transport: kex follows: False 2026-06-13 00:21:42,866 DEBUG paramiko.transport: === Key exchange agreements === 2026-06-13 00:21:42,867 DEBUG paramiko.transport: Kex: curve25519-sha256@libssh.org 2026-06-13 00:21:42,867 DEBUG paramiko.transport: HostKey: ssh-ed25519 2026-06-13 00:21:42,867 DEBUG paramiko.transport: Cipher: aes128-ctr 2026-06-13 00:21:42,867 DEBUG paramiko.transport: MAC: hmac-sha2-256 2026-06-13 00:21:42,867 DEBUG paramiko.transport: Compression: none 2026-06-13 00:21:42,867 DEBUG paramiko.transport: === End of kex handshake === 2026-06-13 00:21:42,870 DEBUG zuul.connection.git.watcher: Walk through projects refs for connection: git-server 2026-06-13 00:21:42,871 DEBUG zuul.connection.git: Starting Git Watcher 2026-06-13 00:21:42,871 INFO zuul.Scheduler: Starting scheduler 2026-06-13 00:21:42,872 DEBUG zuul.Scheduler: Statsd enabled 2026-06-13 00:21:42,872 DEBUG zuul.Scheduler: Run handler sleeping 2026-06-13 00:21:42,872 DEBUG zuul.Scheduler: Run handler awake 2026-06-13 00:21:42,873 DEBUG zuul.Scheduler: Starting command processor 2026-06-13 00:21:42,875 DEBUG zuul.Scheduler: Running stats election 2026-06-13 00:21:42,875 INFO apscheduler.scheduler: Scheduler started 2026-06-13 00:21:42,876 DEBUG apscheduler.scheduler: Looking for jobs to run 2026-06-13 00:21:42,876 DEBUG apscheduler.scheduler: No jobs; waiting until a job is added 2026-06-13 00:21:42,887 INFO zuul.Scheduler: Priming scheduler config 2026-06-13 00:21:42,889 DEBUG zuul.connection.git.watcher: Walk through projects refs for connection: opendev.org 2026-06-13 00:21:42,889 DEBUG zuul.ComponentRegistry: Registry got event CHANGED for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003 2026-06-13 00:21:42,889 INFO zuul.ComponentRegistry: Component scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003 updated: {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'kind': 'scheduler', 'model_api': 37, 'state': 'initializing', 'version': '14.1.1.dev1 90eea2ed8'} 2026-06-13 00:21:42,890 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is None 2026-06-13 00:21:42,891 DEBUG zuul.Scheduler: Won stats election 2026-06-13 00:21:42,897 INFO zuul.Scheduler: Using system config from Zookeeper 2026-06-13 00:21:42,902 DEBUG zuul.Scheduler: Updating system config 2026-06-13 00:21:42,911 DEBUG paramiko.transport: kex engine KexCurve25519 specified hash_algo 2026-06-13 00:21:42,912 DEBUG paramiko.transport: Switch to new keys ... /usr/local/lib/python3.11/site-packages/paramiko/client.py:885: UserWarning: Unknown ssh-ed25519 host key for [gerrit-sshd.sf]:29418: b'7fd57c74cd69c935a188649df6910004' warnings.warn( 2026-06-13 00:21:43,800 DEBUG paramiko.transport: Got EXT_INFO: {'server-sig-algs': b'ecdsa-sha2-nistp256-cert-v01@openssh.com,ecdsa-sha2-nistp384-cert-v01@openssh.com,ecdsa-sha2-nistp521-cert-v01@openssh.com,ssh-ed25519-cert-v01@openssh.com,rsa-sha2-512-cert-v01@openssh.com,rsa-sha2-256-cert-v01@openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-ed25519,sk-ecdsa-sha2-nistp256@openssh.com,sk-ssh-ed25519@openssh.com,rsa-sha2-512,rsa-sha2-256,ssh-rsa'} 2026-06-13 00:21:43,800 DEBUG paramiko.transport: Trying discovered key b'7902e13afc9bc10eeff7226055acfcfc' in /var/lib/zuul-ssh/..data/priv 2026-06-13 00:21:43,802 DEBUG paramiko.transport: userauth is OK 2026-06-13 00:21:43,804 DEBUG paramiko.transport: Finalizing pubkey algorithm for key of type 'ssh-rsa' 2026-06-13 00:21:43,804 DEBUG paramiko.transport: Our pubkey algorithm list: ['rsa-sha2-512', 'rsa-sha2-256', 'ssh-rsa'] 2026-06-13 00:21:43,804 DEBUG paramiko.transport: Server-side algorithm list: ['ecdsa-sha2-nistp256-cert-v01@openssh.com', 'ecdsa-sha2-nistp384-cert-v01@openssh.com', 'ecdsa-sha2-nistp521-cert-v01@openssh.com', 'ssh-ed25519-cert-v01@openssh.com', 'rsa-sha2-512-cert-v01@openssh.com', 'rsa-sha2-256-cert-v01@openssh.com', 'ecdsa-sha2-nistp256', 'ecdsa-sha2-nistp384', 'ecdsa-sha2-nistp521', 'ssh-ed25519', 'sk-ecdsa-sha2-nistp256@openssh.com', 'sk-ssh-ed25519@openssh.com', 'rsa-sha2-512', 'rsa-sha2-256', 'ssh-rsa'] 2026-06-13 00:21:43,805 DEBUG paramiko.transport: Agreed upon 'rsa-sha2-512' pubkey algorithm 2026-06-13 00:21:43,875 INFO paramiko.transport: Authentication (publickey) successful! 2026-06-13 00:21:43,876 DEBUG paramiko.transport: [chan 0] Max packet in: 32768 bytes 2026-06-13 00:21:44,059 DEBUG paramiko.transport: [chan 0] Max packet out: 32768 bytes 2026-06-13 00:21:44,060 DEBUG paramiko.transport: Secsh channel 0 opened. 2026-06-13 00:21:44,202 DEBUG paramiko.transport: [chan 0] Sesch channel 0 request ok 2026-06-13 00:21:44,763 DEBUG zuul.Scheduler: Run handler sleeping 2026-06-13 00:21:44,773 DEBUG zuul.Scheduler: Wait for internal read tenant lock 2026-06-13 00:21:44,778 DEBUG zuul.Scheduler: Aquired internal read tenant lock 2026-06-13 00:21:45,077 INFO zuul.BranchCache.gerrit: Waiting for branch cache upgrade lock 2026-06-13 00:21:45,376 DEBUG zuul.zk.ZooKeeper: Initialize cache at /zuul/cache/connection/gerrit/project 2026-06-13 00:21:45,394 DEBUG zuul.zk.ZooKeeper: Cache at /zuul/cache/connection/gerrit/project is ready 2026-06-13 00:21:45,395 INFO zuul.BranchCache.gerrit: Cache already upgraded 2026-06-13 00:21:45,869 DEBUG zuul.TenantParser: Using files from cache for project gerrit-sshd.sf/config @master: [] 2026-06-13 00:21:45,870 DEBUG zuul.TenantParser: Using files from cache for project git-server/system-config @master: ['zuul.d/config-project.yaml', 'zuul.d/jobs-base.yaml', 'zuul.d/k8s-secret.yaml', 'zuul.d/sf-logserver-secret.yaml'] 2026-06-13 00:21:45,871 DEBUG zuul.TenantParser: Using files from cache for project opendev.org/zuul/zuul-jobs @master: ['zuul.d/container-jobs.yaml', 'zuul.d/dib-jobs.yaml', 'zuul.d/docker-jobs.yaml', 'zuul.d/general-jobs.yaml', 'zuul.d/go-jobs.yaml', 'zuul.d/haskell-jobs.yaml', 'zuul.d/helm-jobs.yaml', 'zuul.d/js-jobs.yaml', 'zuul.d/nim-jobs.yaml', 'zuul.d/packer-jobs.yaml', 'zuul.d/python-jobs.yaml', 'zuul.d/system-jobs.yaml', 'zuul.d/terraform-jobs.yaml'] 2026-06-13 00:21:45,891 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/config-project.yaml@master 2026-06-13 00:21:45,900 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/container-jobs.yaml@master 2026-06-13 00:21:45,907 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/jobs-base.yaml@master 2026-06-13 00:21:45,914 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/k8s-secret.yaml@master 2026-06-13 00:21:45,916 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/dib-jobs.yaml@master 2026-06-13 00:21:45,921 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/sf-logserver-secret.yaml@master 2026-06-13 00:21:45,960 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/docker-jobs.yaml@master 2026-06-13 00:21:45,969 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/general-jobs.yaml@master 2026-06-13 00:21:45,976 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/go-jobs.yaml@master 2026-06-13 00:21:45,981 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/haskell-jobs.yaml@master 2026-06-13 00:21:45,986 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/helm-jobs.yaml@master 2026-06-13 00:21:45,990 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/js-jobs.yaml@master 2026-06-13 00:21:45,999 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/nim-jobs.yaml@master 2026-06-13 00:21:46,003 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/packer-jobs.yaml@master 2026-06-13 00:21:46,007 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/python-jobs.yaml@master 2026-06-13 00:21:46,068 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/system-jobs.yaml@master 2026-06-13 00:21:46,073 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/terraform-jobs.yaml@master 2026-06-13 00:21:46,081 DEBUG zuul.TenantParser: Created layout id 74666e64c43c4d1196f0710d83ae11f3 2026-06-13 00:21:46,114 DEBUG zuul.Pipeline.internal.check: Building relative_priority queues 2026-06-13 00:21:46,167 DEBUG zuul.Pipeline.internal.gate: Building shared change queues 2026-06-13 00:21:46,167 DEBUG zuul.Pipeline.internal.gate: Created queue: 2026-06-13 00:21:46,167 DEBUG zuul.Pipeline.internal.gate: Added project config to default queue manager: 2026-06-13 00:21:46,175 DEBUG zuul.Pipeline.internal.post: Building relative_priority queues 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: 2 errors detected during internal tenant configuration loading 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: Zuul encountered a syntax error while parsing its 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: configuration in the repo system-config on branch master. The 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: problem was: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: Decryption failed 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: The problem appears in the "base" job stanza: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: job: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: name: base 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: description: The base job. 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: parent: null 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: post-run: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: - playbooks/base/post.yaml 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: pre-run: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: - playbooks/base/pre.yaml 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: roles: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: ... 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: in "system-config/zuul.d/jobs-base.yaml@master", line 5 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: Zuul encountered a syntax error while parsing its 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: configuration in the repo system-config on branch master. The 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: problem was: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: Decryption failed 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: The problem appears in the "config-update" job stanza: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: job: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: name: config-update 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: description: Deploy config repo update. 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: parent: base 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: secrets: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: - k8s_config 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: final: true 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: run: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: - playbooks/config/update.yaml 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: ... 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: 2026-06-13 00:21:46,182 WARNING zuul.ConfigLoader: in "system-config/zuul.d/jobs-base.yaml@master", line 26 2026-06-13 00:21:46,183 DEBUG zuul.GerritConnection: Setting watched checkers to [] 2026-06-13 00:21:46,183 DEBUG zuul.GerritConnection: Setting watched event filters for internal to [, , , , , ] 2026-06-13 00:21:46,183 DEBUG zuul.GerritConnection: Setting watched event filters to {, , , , , } 2026-06-13 00:21:46,183 DEBUG zuul.TimerDriver: No jobs to remove for , 0 remain 2026-06-13 00:21:46,183 DEBUG zuul.Scheduler: Released internal read tenant lock 2026-06-13 00:21:46,186 DEBUG zuul.Scheduler: Wait for demo-tenant read tenant lock 2026-06-13 00:21:46,191 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock 2026-06-13 00:21:46,952 DEBUG zuul.TenantParser: Using files from cache for project gerrit-sshd.sf/demo-tenant-config @master: ['zuul.d/gerrit-base-jobs.yaml', 'zuul.d/gerrit-pipeline.yaml', 'zuul.d/gerrit-project-pipeline.yaml'] 2026-06-13 00:21:46,954 DEBUG zuul.TenantParser: Using files from cache for project gerrit-sshd.sf/demo-project @master: ['.zuul.d/jobs.yaml', '.zuul.d/project.yaml'] 2026-06-13 00:21:46,963 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-base-jobs.yaml@master 2026-06-13 00:21:46,968 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-pipeline.yaml@master 2026-06-13 00:21:46,973 INFO zuul.TenantParser: Loading configuration from demo-project/.zuul.d/jobs.yaml@master 2026-06-13 00:21:46,980 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-project-pipeline.yaml@master 2026-06-13 00:21:46,981 INFO zuul.TenantParser: Loading configuration from demo-project/.zuul.d/project.yaml@master 2026-06-13 00:21:46,989 DEBUG zuul.TenantParser: Created layout id 561faa9288c54ce5b806426a1dcff8ae 2026-06-13 00:21:46,995 DEBUG zuul.Pipeline.demo-tenant.check: Building relative_priority queues 2026-06-13 00:21:47,002 DEBUG zuul.Pipeline.demo-tenant.gate: Building shared change queues 2026-06-13 00:21:47,002 DEBUG zuul.Pipeline.demo-tenant.gate: Created queue: 2026-06-13 00:21:47,002 DEBUG zuul.Pipeline.demo-tenant.gate: Added project demo-tenant-config to default queue manager: 2026-06-13 00:21:47,003 DEBUG zuul.Pipeline.demo-tenant.gate: Created queue: 2026-06-13 00:21:47,003 DEBUG zuul.Pipeline.demo-tenant.gate: Added project demo-project to default queue manager: 2026-06-13 00:21:47,010 DEBUG zuul.Pipeline.demo-tenant.post: Building relative_priority queues 2026-06-13 00:21:47,018 DEBUG zuul.GerritConnection: Setting watched checkers to [] 2026-06-13 00:21:47,018 DEBUG zuul.GerritConnection: Setting watched event filters for demo-tenant to [, , , , , ] 2026-06-13 00:21:47,018 DEBUG zuul.GerritConnection: Setting watched event filters to {, , , , , } 2026-06-13 00:21:47,018 DEBUG zuul.TimerDriver: No jobs to remove for , 0 remain 2026-06-13 00:21:47,018 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock 2026-06-13 00:21:47,021 INFO zuul.Scheduler: Config priming complete (duration: 4.134 seconds) 2026-06-13 00:21:47,022 DEBUG zuul.Scheduler: Run handler awake 2026-06-13 00:21:47,026 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is None 2026-06-13 00:21:47,026 DEBUG zuul.RendezvousElection: Did not win election for /zuul/events/connection/gerrit/election 2026-06-13 00:21:47,027 DEBUG zuul.RendezvousElection: Did not win election for /zuul/events/connection/gerrit/election 2026-06-13 00:21:47,059 DEBUG zuul.ComponentRegistry: Registry got event CHANGED for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003 2026-06-13 00:21:47,059 INFO zuul.ComponentRegistry: Component scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003 updated: {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'kind': 'scheduler', 'model_api': 37, 'state': 'running', 'version': '14.1.1.dev1 90eea2ed8'} 2026-06-13 00:21:47,059 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is 2026-06-13 00:21:47,060 DEBUG zuul.RendezvousElection: Acquiring lock for /zuul/events/connection/gerrit/election 2026-06-13 00:21:47,067 INFO zuul.RendezvousElection: Won election for /zuul/events/connection/gerrit/election 2026-06-13 00:21:47,078 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:21:47,078 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant 2026-06-13 00:21:47,080 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:21:47,095 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock 2026-06-13 00:21:47,097 DEBUG zuul.Scheduler.LayoutUpdate: Aquired demo-tenant read tenant lock 2026-06-13 00:21:47,099 DEBUG zuul.Scheduler.LayoutUpdate: Released demo-tenant read tenant lock 2026-06-13 00:21:47,107 DEBUG zuul.Scheduler: Starting semaphore cleanup 2026-06-13 00:21:47,109 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant 2026-06-13 00:21:47,171 DEBUG zuul.Scheduler: Starting build request cleanup 2026-06-13 00:21:47,172 DEBUG zuul.Scheduler: Finished build request cleanup 2026-06-13 00:21:47,180 DEBUG zuul.Scheduler: Starting node request cleanup 2026-06-13 00:21:47,196 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant 2026-06-13 00:21:47,283 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant 2026-06-13 00:21:47,298 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant 2026-06-13 00:21:47,375 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant 2026-06-13 00:21:47,387 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant 2026-06-13 00:21:47,472 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant 2026-06-13 00:21:47,475 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock 2026-06-13 00:21:47,485 DEBUG zuul.Scheduler: Processing tenant management events in internal 2026-06-13 00:21:47,491 INFO apscheduler.scheduler: Added job "Scheduler._runSemaphoreCleanup" to job store "default" 2026-06-13 00:21:47,491 INFO apscheduler.scheduler: Added job "Scheduler._runBuildRequestCleanup" to job store "default" 2026-06-13 00:21:47,491 INFO apscheduler.scheduler: Added job "Scheduler._runMergeRequestCleanup" to job store "default" 2026-06-13 00:21:47,491 INFO apscheduler.scheduler: Added job "Scheduler._runConnectionCleanup" to job store "default" 2026-06-13 00:21:47,492 INFO apscheduler.scheduler: Added job "Scheduler._runGeneralCleanup" to job store "default" 2026-06-13 00:21:47,492 DEBUG apscheduler.scheduler: Looking for jobs to run 2026-06-13 00:21:47,493 DEBUG apscheduler.scheduler: Next wakeup is due at 2026-06-13 00:22:43.297983+00:00 (in 55.805000 seconds) 2026-06-13 00:21:47,560 DEBUG zuul.Scheduler: Aquired internal read tenant lock 2026-06-13 00:21:47,568 DEBUG zuul.Scheduler: Processing tenant trigger events in internal 2026-06-13 00:21:47,590 DEBUG zuul.Scheduler: Processing pipeline check in tenant internal 2026-06-13 00:21:47,599 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant internal 2026-06-13 00:21:47,668 DEBUG zuul.Scheduler: Processing pipeline gate in tenant internal 2026-06-13 00:21:47,678 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant internal 2026-06-13 00:21:47,685 DEBUG zuul.Scheduler: Processing pipeline post in tenant internal 2026-06-13 00:21:47,694 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant internal 2026-06-13 00:21:47,696 DEBUG zuul.Scheduler: Released internal read tenant lock 2026-06-13 00:21:47,699 DEBUG zuul.Scheduler: Run handler sleeping 2026-06-13 00:22:32,861 DEBUG zuul.CommandSocket: Accepted socket connection 2026-06-13 00:22:32,861 DEBUG zuul.CommandSocket: Received b'full-reconfigure' from socket 2026-06-13 00:22:32,862 DEBUG zuul.Scheduler: Reconfiguration triggered 2026-06-13 00:22:32,886 DEBUG zuul.Scheduler: Configured logging: 14.1.1.dev1 2026-06-13 00:22:32,886 DEBUG zuul.Scheduler: [e: f42a996431d64b53aa2f15a6030ed79a] Submitting reconfiguration event 2026-06-13 00:22:32,959 DEBUG zuul.Scheduler: [e: f42a996431d64b53aa2f15a6030ed79a] Waiting for reconfiguration 2026-06-13 00:22:32,959 DEBUG zuul.Scheduler: Run handler awake 2026-06-13 00:22:32,960 DEBUG zuul.Scheduler: Fetching reconfiguration event 2026-06-13 00:22:32,960 INFO zuul.Scheduler: Reconfiguration beginning (smart=False, tenants=None) 2026-06-13 00:22:32,962 INFO zuul.ConfigLoader: Loading configuration from /var/lib/zuul/main.yaml 2026-06-13 00:22:33,181 DEBUG zuul.Scheduler: Wait for internal write tenant lock (id: RECONFIG) 2026-06-13 00:22:33,186 DEBUG zuul.Scheduler: Aquired internal write tenant lock (id: RECONFIG) /usr/local/lib/python3.11/site-packages/paramiko/client.py:885: UserWarning: Unknown ssh-ed25519 host key for [gerrit-sshd.sf]:29418: b'7fd57c74cd69c935a188649df6910004' warnings.warn( 2026-06-13 00:22:36,000 DEBUG zuul.ComponentRegistry: Registry got event CHANGED for web zuul-web-7f8547d9bb-n7hqc0000000005 2026-06-13 00:22:36,000 INFO zuul.ComponentRegistry: Component web zuul-web-7f8547d9bb-n7hqc0000000005 updated: {'hostname': 'zuul-web-7f8547d9bb-n7hqc', 'kind': 'web', 'model_api': 37, 'state': 'stopped', 'version': '14.1.1.dev1 90eea2ed8'} 2026-06-13 00:22:36,001 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is 2026-06-13 00:22:36,004 DEBUG zuul.GerritConnection: SSH command: 2026-06-13 00:22:36,004 DEBUG zuul.GerritConnection: git-upload-pack config 2026-06-13 00:22:36,062 DEBUG zuul.GerritConnection.io: SSH received stdout: 2026-06-13 00:22:36,062 DEBUG zuul.GerritConnection.io: 00e96c8b9a361dae6306a6b100331103eebf8c600568 HEAD include-tag multi_ack_detailed multi_ack ofs-delta side-band side-band-64k thin-pack no-progress shallow agent=JGit/v6.4.0.202211300538-r-53-ga1901305b symref=HEAD:refs/heads/master 2026-06-13 00:22:36,062 DEBUG zuul.GerritConnection.io: 003f6c8b9a361dae6306a6b100331103eebf8c600568 refs/heads/master 2026-06-13 00:22:36,062 DEBUG zuul.GerritConnection.io: 003e911319da6cf677735b4b999a666e2a722107f881 refs/meta/config 2026-06-13 00:22:36,062 DEBUG zuul.GerritConnection.io: 0000 2026-06-13 00:22:36,063 DEBUG zuul.GerritConnection: SSH exit status: 0 2026-06-13 00:22:36,063 DEBUG zuul.GerritConnection: Fetched 1 branches for config 2026-06-13 00:22:36,063 INFO zuul.GerritConnection: Got branches for config 2026-06-13 00:22:36,064 DEBUG zuul.ComponentRegistry: Registry got event DELETED for web zuul-web-7f8547d9bb-n7hqc0000000005 2026-06-13 00:22:36,064 INFO zuul.ComponentRegistry: Noticed web component zuul-web-7f8547d9bb-n7hqc0000000005 disappeared 2026-06-13 00:22:36,064 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is 2026-06-13 00:22:36,076 ERROR zuul.BranchCacheNew.gerrit: Exception loading ZKObject at /zuul/cache/connection/gerrit/project/data/config 2026-06-13 00:22:36,083 INFO zuul.GerritConnection: Got default branch for config: master 2026-06-13 00:22:36,481 DEBUG zuul.MergeClient: Submitting job ad0fb1d1b8204000bc7d259d308d7813 with data {'connection': 'git-server', 'project': 'system-config', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']} 2026-06-13 00:22:36,482 DEBUG zuul.MergeClient: Submitting job 6d0b9044ff7442578f08d91236c7db68 with data {'connection': 'gerrit', 'project': 'config', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']} 2026-06-13 00:22:36,483 DEBUG zuul.MergeClient: Submitting job c24ab95b2893474c8e6473dd34031def with data {'connection': 'opendev.org', 'project': 'zuul/zuul-jobs', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']} 2026-06-13 00:22:36,489 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper 2026-06-13 00:22:36,491 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper 2026-06-13 00:22:36,492 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper 2026-06-13 00:22:36,511 DEBUG zuul.TenantParser: Submitting cat job for git-server system-config master 2026-06-13 00:22:36,513 DEBUG zuul.TenantParser: Submitting cat job for gerrit config master 2026-06-13 00:22:36,515 DEBUG zuul.TenantParser: Submitting cat job for opendev.org zuul/zuul-jobs master 2026-06-13 00:22:36,515 DEBUG zuul.TenantParser: Waiting for cat job 2026-06-13 00:22:36,810 DEBUG zuul.TenantParser: Cat job got files dict_keys(['.zuul.yaml', 'zuul.d/config-project.yaml', 'zuul.d/jobs-base.yaml', 'zuul.d/k8s-secret.yaml', 'zuul.d/sf-logserver-secret.yaml', 'zuul.yaml']) 2026-06-13 00:22:36,811 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/config-project.yaml@master 2026-06-13 00:22:36,818 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/jobs-base.yaml@master 2026-06-13 00:22:36,820 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/k8s-secret.yaml@master 2026-06-13 00:22:36,822 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/sf-logserver-secret.yaml@master 2026-06-13 00:22:37,061 DEBUG zuul.TenantParser: Waiting for cat job 2026-06-13 00:22:38,063 DEBUG zuul.TenantParser: Cat job got files dict_keys(['.zuul.yaml', 'zuul.yaml']) 2026-06-13 00:22:38,079 DEBUG zuul.TenantParser: Waiting for cat job 2026-06-13 00:22:38,096 DEBUG zuul.TenantParser: Cat job got files dict_keys(['.zuul.yaml', 'zuul.d/container-jobs.yaml', 'zuul.d/dib-jobs.yaml', 'zuul.d/docker-jobs.yaml', 'zuul.d/general-jobs.yaml', 'zuul.d/go-jobs.yaml', 'zuul.d/haskell-jobs.yaml', 'zuul.d/helm-jobs.yaml', 'zuul.d/js-jobs.yaml', 'zuul.d/nim-jobs.yaml', 'zuul.d/packer-jobs.yaml', 'zuul.d/python-jobs.yaml', 'zuul.d/system-jobs.yaml', 'zuul.d/terraform-jobs.yaml', 'zuul.yaml']) 2026-06-13 00:22:38,096 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/container-jobs.yaml@master 2026-06-13 00:22:38,098 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/dib-jobs.yaml@master 2026-06-13 00:22:38,099 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/docker-jobs.yaml@master 2026-06-13 00:22:38,100 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/general-jobs.yaml@master 2026-06-13 00:22:38,105 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/go-jobs.yaml@master 2026-06-13 00:22:38,107 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/haskell-jobs.yaml@master 2026-06-13 00:22:38,108 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/helm-jobs.yaml@master 2026-06-13 00:22:38,110 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/js-jobs.yaml@master 2026-06-13 00:22:38,113 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/nim-jobs.yaml@master 2026-06-13 00:22:38,113 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/packer-jobs.yaml@master 2026-06-13 00:22:38,114 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/python-jobs.yaml@master 2026-06-13 00:22:38,125 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/system-jobs.yaml@master 2026-06-13 00:22:38,127 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/terraform-jobs.yaml@master 2026-06-13 00:22:38,598 DEBUG zuul.TenantParser: Created layout id 5dc6aeeb5fbf4084a3aa9a69d979c1e8 2026-06-13 00:22:38,807 DEBUG zuul.Pipeline.internal.check: Building relative_priority queues 2026-06-13 00:22:38,866 DEBUG zuul.Pipeline.internal.gate: Building shared change queues 2026-06-13 00:22:38,866 DEBUG zuul.Pipeline.internal.gate: Created queue: 2026-06-13 00:22:38,866 DEBUG zuul.Pipeline.internal.gate: Added project config to default queue manager: 2026-06-13 00:22:38,873 DEBUG zuul.Pipeline.internal.post: Building relative_priority queues 2026-06-13 00:22:38,883 DEBUG zuul.GerritConnection: Setting watched checkers to [] 2026-06-13 00:22:38,883 DEBUG zuul.GerritConnection: Setting watched event filters for internal to [, , , , , ] 2026-06-13 00:22:38,883 DEBUG zuul.GerritConnection: Setting watched event filters to {, , , , , } 2026-06-13 00:22:38,883 DEBUG zuul.TimerDriver: No jobs to remove for , 0 remain 2026-06-13 00:22:38,911 DEBUG zuul.Scheduler: Setting layout state last reconfigure ltime to 3796 2026-06-13 00:22:38,929 DEBUG zuul.Scheduler: Released internal write tenant lock (id: RECONFIG) 2026-06-13 00:22:38,993 DEBUG zuul.Scheduler: Wait for demo-tenant write tenant lock (id: RECONFIG) 2026-06-13 00:22:38,996 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:38,999 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:39,000 DEBUG zuul.Scheduler: Aquired demo-tenant write tenant lock (id: RECONFIG) 2026-06-13 00:22:39,064 DEBUG zuul.GerritConnection: SSH command: 2026-06-13 00:22:39,064 DEBUG zuul.GerritConnection: git-upload-pack demo-tenant-config 2026-06-13 00:22:39,065 DEBUG zuul.GerritConnection: SSH command: 2026-06-13 00:22:39,065 DEBUG zuul.GerritConnection: git-upload-pack demo-project 2026-06-13 00:22:39,308 DEBUG zuul.GerritConnection.io: SSH received stdout: 2026-06-13 00:22:39,308 DEBUG zuul.GerritConnection.io: 00e9cfcc64c5e5dbb263546e0fa5f50e393434e0d784 HEAD include-tag multi_ack_detailed multi_ack ofs-delta side-band side-band-64k thin-pack no-progress shallow agent=JGit/v6.4.0.202211300538-r-53-ga1901305b symref=HEAD:refs/heads/master 2026-06-13 00:22:39,308 DEBUG zuul.GerritConnection.io: 003fcfcc64c5e5dbb263546e0fa5f50e393434e0d784 refs/heads/master 2026-06-13 00:22:39,308 DEBUG zuul.GerritConnection.io: 003e4b49d999c82b01806f6713a1b6770508f314f947 refs/meta/config 2026-06-13 00:22:39,308 DEBUG zuul.GerritConnection.io: 0000 2026-06-13 00:22:39,320 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:39,349 DEBUG zuul.GerritConnection.io: SSH received stdout: 2026-06-13 00:22:39,349 DEBUG zuul.GerritConnection.io: 00e9237fb96640f9cae951dc8a29f97cfe910080dbc3 HEAD include-tag multi_ack_detailed multi_ack ofs-delta side-band side-band-64k thin-pack no-progress shallow agent=JGit/v6.4.0.202211300538-r-53-ga1901305b symref=HEAD:refs/heads/master 2026-06-13 00:22:39,349 DEBUG zuul.GerritConnection.io: 003f237fb96640f9cae951dc8a29f97cfe910080dbc3 refs/heads/master 2026-06-13 00:22:39,349 DEBUG zuul.GerritConnection.io: 003e73f607c088d8b0f79937cc03ff8082e968b61ca4 refs/meta/config 2026-06-13 00:22:39,349 DEBUG zuul.GerritConnection.io: 0000 2026-06-13 00:22:39,349 DEBUG zuul.GerritConnection: SSH exit status: 0 2026-06-13 00:22:39,350 DEBUG zuul.GerritConnection: Fetched 1 branches for demo-project 2026-06-13 00:22:39,350 INFO zuul.GerritConnection: Got branches for demo-project 2026-06-13 00:22:39,350 DEBUG zuul.GerritConnection: SSH exit status: 0 2026-06-13 00:22:39,360 DEBUG zuul.GerritConnection: Fetched 1 branches for demo-tenant-config 2026-06-13 00:22:39,360 INFO zuul.GerritConnection: Got branches for demo-tenant-config 2026-06-13 00:22:39,371 ERROR zuul.BranchCacheNew.gerrit: Exception loading ZKObject at /zuul/cache/connection/gerrit/project/data/demo-project 2026-06-13 00:22:39,372 ERROR zuul.BranchCacheNew.gerrit: Exception loading ZKObject at /zuul/cache/connection/gerrit/project/data/demo-tenant-config 2026-06-13 00:22:39,381 INFO zuul.GerritConnection: Got default branch for demo-project: master 2026-06-13 00:22:39,383 INFO zuul.GerritConnection: Got default branch for demo-tenant-config: master 2026-06-13 00:22:39,464 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:39,466 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:39,482 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:39,593 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:39,594 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:39,609 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:39,722 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:39,723 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:39,738 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:39,800 DEBUG zuul.MergeClient: Submitting job ef8999826587476a91b8c424391d252f with data {'connection': 'gerrit', 'project': 'demo-tenant-config', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']} 2026-06-13 00:22:39,803 DEBUG zuul.MergeClient: Submitting job 7e59b69b5ba244a9913dcab9fb1f9153 with data {'connection': 'gerrit', 'project': 'demo-project', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']} 2026-06-13 00:22:39,808 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper 2026-06-13 00:22:39,809 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper 2026-06-13 00:22:39,823 DEBUG zuul.TenantParser: Submitting cat job for gerrit demo-tenant-config master 2026-06-13 00:22:39,825 DEBUG zuul.TenantParser: Submitting cat job for gerrit demo-project master 2026-06-13 00:22:39,825 DEBUG zuul.TenantParser: Waiting for cat job 2026-06-13 00:22:39,854 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:39,855 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:39,875 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:39,992 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:39,993 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:40,013 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:40,123 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:40,123 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:40,134 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:40,247 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:40,248 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:40,268 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:40,397 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:40,399 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:40,415 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:40,530 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:40,531 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:40,545 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:40,655 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:40,655 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:40,673 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:40,786 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:40,787 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:40,822 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:40,973 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:40,978 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:40,997 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:41,075 DEBUG zuul.TenantParser: Cat job got files dict_keys(['.zuul.yaml', 'zuul.d/gerrit-base-jobs.yaml', 'zuul.d/gerrit-pipeline.yaml', 'zuul.d/gerrit-project-pipeline.yaml', 'zuul.yaml']) 2026-06-13 00:22:41,075 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-base-jobs.yaml@master 2026-06-13 00:22:41,076 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-pipeline.yaml@master 2026-06-13 00:22:41,079 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-project-pipeline.yaml@master 2026-06-13 00:22:41,169 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:41,171 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:41,188 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:41,570 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:41,571 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:41,576 DEBUG zuul.TenantParser: Waiting for cat job 2026-06-13 00:22:41,588 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:41,595 DEBUG zuul.TenantParser: Cat job got files dict_keys(['.zuul.d/jobs.yaml', '.zuul.d/project.yaml', '.zuul.yaml', 'zuul.yaml']) 2026-06-13 00:22:41,596 INFO zuul.TenantParser: Loading configuration from demo-project/.zuul.d/jobs.yaml@master 2026-06-13 00:22:41,597 INFO zuul.TenantParser: Loading configuration from demo-project/.zuul.d/project.yaml@master 2026-06-13 00:22:41,701 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:41,703 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:41,708 DEBUG zuul.TenantParser: Created layout id 5338bc9a98f14f4ab2bd67c72004e39c 2026-06-13 00:22:41,714 DEBUG zuul.Pipeline.demo-tenant.check: Building relative_priority queues 2026-06-13 00:22:41,764 DEBUG zuul.Pipeline.demo-tenant.gate: Building shared change queues 2026-06-13 00:22:41,764 DEBUG zuul.Pipeline.demo-tenant.gate: Created queue: 2026-06-13 00:22:41,764 DEBUG zuul.Pipeline.demo-tenant.gate: Added project demo-tenant-config to default queue manager: 2026-06-13 00:22:41,764 DEBUG zuul.Pipeline.demo-tenant.gate: Created queue: 2026-06-13 00:22:41,764 DEBUG zuul.Pipeline.demo-tenant.gate: Added project demo-project to default queue manager: 2026-06-13 00:22:41,767 DEBUG zuul.Scheduler.LayoutUpdate: Skipping layout update of locked tenant demo-tenant 2026-06-13 00:22:41,769 DEBUG zuul.Pipeline.demo-tenant.post: Building relative_priority queues 2026-06-13 00:22:41,776 DEBUG zuul.GerritConnection: Setting watched checkers to [] 2026-06-13 00:22:41,777 DEBUG zuul.GerritConnection: Setting watched event filters for demo-tenant to [, , , , , ] 2026-06-13 00:22:41,777 DEBUG zuul.GerritConnection: Setting watched event filters to {, , , , , } 2026-06-13 00:22:41,777 DEBUG zuul.TimerDriver: No jobs to remove for , 0 remain 2026-06-13 00:22:41,795 DEBUG zuul.Scheduler: Setting layout state last reconfigure ltime to 3796 2026-06-13 00:22:41,873 DEBUG zuul.Scheduler: Released demo-tenant write tenant lock (id: RECONFIG) 2026-06-13 00:22:41,886 INFO zuul.Scheduler: Reconfiguration complete (smart: False, tenants: None, duration: 8.926 seconds) 2026-06-13 00:22:41,887 DEBUG zuul.Scheduler: [e: f42a996431d64b53aa2f15a6030ed79a] Reconfiguration complete 2026-06-13 00:22:41,889 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:41,890 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:41,898 DEBUG zuul.Scheduler: Processing tenant management events in internal 2026-06-13 00:22:41,901 DEBUG zuul.Scheduler.LayoutUpdate: Aquired demo-tenant read tenant lock 2026-06-13 00:22:41,902 DEBUG zuul.Scheduler.LayoutUpdate: Released demo-tenant read tenant lock 2026-06-13 00:22:41,905 DEBUG zuul.Scheduler: Aquired internal read tenant lock 2026-06-13 00:22:41,910 DEBUG zuul.Scheduler: Processing tenant trigger events in internal 2026-06-13 00:22:41,922 DEBUG zuul.Scheduler: Processing pipeline check in tenant internal 2026-06-13 00:22:41,962 DEBUG zuul.Scheduler: Processing management event 2026-06-13 00:22:41,971 DEBUG zuul.Pipeline.internal.check: Starting queue processor: check 2026-06-13 00:22:41,971 DEBUG zuul.Pipeline.internal.check: Finished queue processor: check (changed: False) 2026-06-13 00:22:41,987 DEBUG zuul.Scheduler: Processing pipeline gate in tenant internal 2026-06-13 00:22:42,007 DEBUG zuul.Scheduler: Processing management event 2026-06-13 00:22:42,018 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock 2026-06-13 00:22:42,019 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock 2026-06-13 00:22:42,019 DEBUG zuul.Pipeline.internal.gate: Starting queue processor: gate 2026-06-13 00:22:42,020 DEBUG zuul.Pipeline.internal.gate: Finished queue processor: gate (changed: False) 2026-06-13 00:22:42,037 DEBUG zuul.Scheduler: Processing pipeline post in tenant internal 2026-06-13 00:22:42,038 DEBUG zuul.Scheduler.LayoutUpdate: Aquired demo-tenant read tenant lock 2026-06-13 00:22:42,039 DEBUG zuul.Scheduler.LayoutUpdate: Released demo-tenant read tenant lock 2026-06-13 00:22:42,071 DEBUG zuul.Scheduler: Processing management event 2026-06-13 00:22:42,078 DEBUG zuul.Pipeline.internal.post: Starting queue processor: post 2026-06-13 00:22:42,079 DEBUG zuul.Pipeline.internal.post: Finished queue processor: post (changed: False) 2026-06-13 00:22:42,090 DEBUG zuul.Scheduler: Released internal read tenant lock 2026-06-13 00:22:42,097 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant 2026-06-13 00:22:42,107 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock 2026-06-13 00:22:42,113 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant 2026-06-13 00:22:42,131 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant 2026-06-13 00:22:42,161 DEBUG zuul.Scheduler: Processing management event 2026-06-13 00:22:42,169 DEBUG zuul.Pipeline.demo-tenant.check: Starting queue processor: check 2026-06-13 00:22:42,169 DEBUG zuul.Pipeline.demo-tenant.check: Finished queue processor: check (changed: False) 2026-06-13 00:22:42,180 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant 2026-06-13 00:22:42,193 DEBUG zuul.Scheduler: Processing management event 2026-06-13 00:22:42,261 DEBUG zuul.Pipeline.demo-tenant.gate: Starting queue processor: gate 2026-06-13 00:22:42,261 DEBUG zuul.Pipeline.demo-tenant.gate: Finished queue processor: gate (changed: False) 2026-06-13 00:22:42,273 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant 2026-06-13 00:22:42,290 DEBUG zuul.Scheduler: Processing management event 2026-06-13 00:22:42,296 DEBUG zuul.Pipeline.demo-tenant.post: Starting queue processor: post 2026-06-13 00:22:42,296 DEBUG zuul.Pipeline.demo-tenant.post: Finished queue processor: post (changed: False) 2026-06-13 00:22:42,304 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock 2026-06-13 00:22:42,307 DEBUG zuul.Scheduler: Run handler sleeping 2026-06-13 00:22:42,307 DEBUG zuul.Scheduler: Run handler awake 2026-06-13 00:22:42,318 DEBUG zuul.Scheduler: Processing tenant management events in internal 2026-06-13 00:22:42,324 DEBUG zuul.Scheduler: Aquired internal read tenant lock 2026-06-13 00:22:42,360 DEBUG zuul.Scheduler: Processing tenant trigger events in internal 2026-06-13 00:22:42,379 DEBUG zuul.Scheduler: Processing pipeline check in tenant internal 2026-06-13 00:22:42,383 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant internal 2026-06-13 00:22:42,390 DEBUG zuul.Scheduler: Processing pipeline gate in tenant internal 2026-06-13 00:22:42,393 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant internal 2026-06-13 00:22:42,398 DEBUG zuul.Scheduler: Processing pipeline post in tenant internal 2026-06-13 00:22:42,402 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant internal 2026-06-13 00:22:42,404 DEBUG zuul.Scheduler: Released internal read tenant lock 2026-06-13 00:22:42,412 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant 2026-06-13 00:22:42,421 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock 2026-06-13 00:22:42,428 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant 2026-06-13 00:22:42,447 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant 2026-06-13 00:22:42,450 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant 2026-06-13 00:22:42,458 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant 2026-06-13 00:22:42,464 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant 2026-06-13 00:22:42,471 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant 2026-06-13 00:22:42,475 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant 2026-06-13 00:22:42,478 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock 2026-06-13 00:22:42,481 DEBUG zuul.Scheduler: Run handler sleeping 2026-06-13 00:22:43,303 DEBUG zuul.Scheduler: Starting build request cleanup 2026-06-13 00:22:43,304 DEBUG zuul.Scheduler: Finished build request cleanup 2026-06-13 00:22:45,896 DEBUG zuul.Scheduler: Starting merge request cleanup 2026-06-13 00:22:45,896 DEBUG zuul.Scheduler: Finished merge request cleanup 2026-06-13 00:22:48,609 INFO zuul.ComponentRegistry: Noticed new web component zuul-web-7f8547d9bb-dpwh20000000006 2026-06-13 00:22:48,612 DEBUG zuul.ComponentRegistry: Registry got event None for web zuul-web-7f8547d9bb-dpwh20000000006 2026-06-13 00:22:48,612 INFO zuul.ComponentRegistry: Component web zuul-web-7f8547d9bb-dpwh20000000006 updated: {'hostname': 'zuul-web-7f8547d9bb-dpwh2', 'kind': 'web', 'model_api': 37, 'state': 'stopped', 'version': '14.1.1.dev1 90eea2ed8'} 2026-06-13 00:22:48,612 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is 2026-06-13 00:22:48,881 DEBUG zuul.ComponentRegistry: Registry got event CHANGED for web zuul-web-7f8547d9bb-dpwh20000000006 2026-06-13 00:22:48,882 INFO zuul.ComponentRegistry: Component web zuul-web-7f8547d9bb-dpwh20000000006 updated: {'hostname': 'zuul-web-7f8547d9bb-dpwh2', 'kind': 'web', 'model_api': 37, 'state': 'initializing', 'version': '14.1.1.dev1 90eea2ed8'} 2026-06-13 00:22:48,882 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is 2026-06-13 00:22:52,618 DEBUG zuul.ComponentRegistry: Registry got event CHANGED for web zuul-web-7f8547d9bb-dpwh20000000006 2026-06-13 00:22:52,619 INFO zuul.ComponentRegistry: Component web zuul-web-7f8547d9bb-dpwh20000000006 updated: {'hostname': 'zuul-web-7f8547d9bb-dpwh2', 'kind': 'web', 'model_api': 37, 'state': 'running', 'version': '14.1.1.dev1 90eea2ed8'} 2026-06-13 00:22:52,619 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is 2026-06-13 00:23:45,357 DEBUG zuul.Scheduler: Starting build request cleanup 2026-06-13 00:23:45,358 DEBUG zuul.Scheduler: Finished build request cleanup 2026-06-13 00:23:46,961 DEBUG zuul.Scheduler: Starting merge request cleanup 2026-06-13 00:23:46,961 DEBUG zuul.Scheduler: Finished merge request cleanup