2026-06-10 10:07:23,428 DEBUG zuul.Scheduler: Configured logging: 14.1.1.dev1
2026-06-10 10:07:23,428 DEBUG zuul.TimerDriver: Starting apscheduler
2026-06-10 10:07:23,428 INFO apscheduler.scheduler: Scheduler started
2026-06-10 10:07:23,429 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:07:23,429 DEBUG apscheduler.scheduler: No jobs; waiting until a job is added
2026-06-10 10:07:23,429 DEBUG zuul.BubblewrapDriver: Initializing bubblewrap with oom_score_adj starting: 995, final: 1000
2026-06-10 10:07:23,431 INFO zuul.SQLConnection: Initializing SQL connection database (prefix: )
2026-06-10 10:07:23,649 DEBUG zuul.zk.base.ZooKeeperClient: ZooKeeper connection (session: 0x100001140de0004): CONNECTED
2026-06-10 10:07:23,726 INFO zuul.Component: Registering component in ZooKeeper /zuul/components/scheduler/zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local
2026-06-10 10:07:23,733 INFO zuul.ComponentRegistry: Noticed new scheduler component zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003
2026-06-10 10:07:23,736 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event None for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003
2026-06-10 10:07:23,736 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-10 10:07:23,736 INFO zuul.ComponentRegistry: Noticed new scheduler component zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000002
2026-06-10 10:07:23,737 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event None for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000002
2026-06-10 10:07:23,737 INFO zuul.ComponentRegistry: Component scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000002 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-10 10:07:23,739 INFO zuul.ComponentRegistry: Noticed new executor component zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000003
2026-06-10 10:07:23,740 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event None for executor zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000003
2026-06-10 10:07:23,741 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-10 10:07:23,743 INFO zuul.ComponentRegistry: Noticed new merger component zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000003
2026-06-10 10:07:23,744 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event None for merger zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000003
2026-06-10 10:07:23,744 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-10 10:07:23,744 INFO zuul.ComponentRegistry: Noticed new merger component zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000002
2026-06-10 10:07:23,745 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event None for merger zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000002
2026-06-10 10:07:23,745 INFO zuul.ComponentRegistry: Component merger zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000002 updated: {'hostname': 'zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local', 'kind': 'merger', 'model_api': 37, 'state': 'stopped', 'version': '14.1.1.dev1 90eea2ed8'}
2026-06-10 10:07:23,826 INFO zuul.ComponentRegistry: Noticed new web component zuul-web-7444b8b5dd-6nlj60000000005
2026-06-10 10:07:23,829 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event None for web zuul-web-7444b8b5dd-6nlj60000000005
2026-06-10 10:07:23,829 INFO zuul.ComponentRegistry: Component web zuul-web-7444b8b5dd-6nlj60000000005 updated: {'hostname': 'zuul-web-7444b8b5dd-6nlj6', 'kind': 'web', 'model_api': 37, 'state': 'running', 'version': '14.1.1.dev1 90eea2ed8'}
2026-06-10 10:07:23,830 INFO zuul.ComponentRegistry: Noticed new web component zuul-web-5557f5598d-mtdxx0000000004
2026-06-10 10:07:23,831 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event None for web zuul-web-5557f5598d-mtdxx0000000004
2026-06-10 10:07:23,831 INFO zuul.ComponentRegistry: Component web zuul-web-5557f5598d-mtdxx0000000004 updated: {'hostname': 'zuul-web-5557f5598d-mtdxx', 'kind': 'web', 'model_api': 37, 'state': 'stopped', 'version': '14.1.1.dev1 90eea2ed8'}
2026-06-10 10:07:23,833 INFO zuul.ComponentRegistry: System minimum data model version 37; this component 37
2026-06-10 10:07:23,945 DEBUG zuul.zk.ZooKeeper: Initialize cache at /keystorage-oidc
2026-06-10 10:07:23,947 DEBUG zuul.zk.ZooKeeper: Cache at /keystorage-oidc is ready
2026-06-10 10:07:23,953 DEBUG zuul.ExecutorQueue: Creating executor queue at root /zuul/executor/unzoned
2026-06-10 10:07:23,959 DEBUG zuul.zk.ZooKeeper: Initialize cache at /zuul/executor/unzoned
2026-06-10 10:07:23,971 DEBUG zuul.zk.ZooKeeper: Cache at /zuul/executor/unzoned is ready
2026-06-10 10:07:23,978 DEBUG zuul.zk.ZooKeeper: Initialize cache at /zuul/merger
2026-06-10 10:07:23,991 DEBUG zuul.zk.ZooKeeper: Cache at /zuul/merger is ready
2026-06-10 10:07:23,996 DEBUG zuul.nodepool: Running nodepool watcher election
2026-06-10 10:07:23,997 DEBUG zuul.TimerDriver: Starting timer election loop
2026-06-10 10:07:24,029 INFO zuul.nodepool: Watching nodepool requests
2026-06-10 10:07:24,030 DEBUG zuul.TimerDriver: Waiting for scheduler to be primed
2026-06-10 10:07:24,050 DEBUG zuul.SQLConnection: Current migration revision: 21d30f0bffef
2026-06-10 10:07:24,082 DEBUG zuul.GerritConnection: Starting Gerrit Connection/Watchers
2026-06-10 10:07:24,083 DEBUG zuul.GerritConnection: Creating Zookeeper branch cache
2026-06-10 10:07:24,094 INFO zuul.GerritConnection: Creating Zookeeper event queue
2026-06-10 10:07:24,097 DEBUG zuul.GerritConnection: Creating Zookeeper change cache
2026-06-10 10:07:24,099 INFO zuul.GerritConnection: Starting SSH event stream client
2026-06-10 10:07:24,100 INFO zuul.GerritConnection: gerrit: Gerrit Poller is disabled because no HTTP authentication is defined
2026-06-10 10:07:24,100 DEBUG zuul.connection.git: Creating Zookeeper change cache
2026-06-10 10:07:24,122 DEBUG zuul.connection.git: Starting Git Watcher
2026-06-10 10:07:24,124 DEBUG zuul.connection.git: Creating Zookeeper change cache
2026-06-10 10:07:24,129 DEBUG zuul.connection.git: Starting Git Watcher
2026-06-10 10:07:24,130 INFO zuul.Scheduler: Starting scheduler
2026-06-10 10:07:24,130 DEBUG zuul.Scheduler: Statsd enabled
2026-06-10 10:07:24,130 DEBUG zuul.Scheduler: Starting command processor
2026-06-10 10:07:24,130 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:07:24,131 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:07:24,132 DEBUG zuul.Scheduler: Running stats election
2026-06-10 10:07:24,133 INFO apscheduler.scheduler: Scheduler started
2026-06-10 10:07:24,133 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:07:24,133 DEBUG apscheduler.scheduler: No jobs; waiting until a job is added
2026-06-10 10:07:24,141 INFO zuul.Scheduler: Priming scheduler config
2026-06-10 10:07:24,142 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event CHANGED for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003
2026-06-10 10:07:24,142 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-10 10:07:24,143 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is None
2026-06-10 10:07:24,143 DEBUG zuul.Scheduler: Won stats election
2026-06-10 10:07:24,153 INFO zuul.Scheduler: Using system config from Zookeeper
2026-06-10 10:07:24,157 DEBUG zuul.Scheduler: Updating system config
2026-06-10 10:07:24,951 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:07:24,964 DEBUG zuul.Scheduler: Wait for demo-tenant read tenant lock
2026-06-10 10:07:24,968 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:07:25,235 INFO zuul.BranchCache.gerrit: Waiting for branch cache upgrade lock
2026-06-10 10:07:25,374 DEBUG zuul.zk.ZooKeeper: Initialize cache at /zuul/cache/connection/gerrit/project
2026-06-10 10:07:25,429 DEBUG zuul.zk.ZooKeeper: Cache at /zuul/cache/connection/gerrit/project is ready
2026-06-10 10:07:25,429 INFO zuul.BranchCache.gerrit: Cache already upgraded
2026-06-10 10:07:26,010 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-10 10:07:26,011 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-10 10:07:26,015 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-10 10:07:26,024 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-base-jobs.yaml@master
2026-06-10 10:07:26,030 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/container-jobs.yaml@master
2026-06-10 10:07:26,034 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-pipeline.yaml@master
2026-06-10 10:07:26,038 INFO zuul.TenantParser: Loading configuration from demo-project/.zuul.d/jobs.yaml@master
2026-06-10 10:07:26,042 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/dib-jobs.yaml@master
2026-06-10 10:07:26,044 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-project-pipeline.yaml@master
2026-06-10 10:07:26,045 INFO zuul.TenantParser: Loading configuration from demo-project/.zuul.d/project.yaml@master
2026-06-10 10:07:26,050 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/docker-jobs.yaml@master
2026-06-10 10:07:26,058 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/general-jobs.yaml@master
2026-06-10 10:07:26,065 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/go-jobs.yaml@master
2026-06-10 10:07:26,070 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/haskell-jobs.yaml@master
2026-06-10 10:07:26,074 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/helm-jobs.yaml@master
2026-06-10 10:07:26,123 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/js-jobs.yaml@master
2026-06-10 10:07:26,129 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/nim-jobs.yaml@master
2026-06-10 10:07:26,135 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/packer-jobs.yaml@master
2026-06-10 10:07:26,139 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/python-jobs.yaml@master
2026-06-10 10:07:26,155 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/system-jobs.yaml@master
2026-06-10 10:07:26,161 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/terraform-jobs.yaml@master
2026-06-10 10:07:26,170 DEBUG zuul.TenantParser: Created layout id b093e7c722e64ebfbc517c3c31c60807
2026-06-10 10:07:26,176 DEBUG zuul.Pipeline.demo-tenant.check: Building relative_priority queues
2026-06-10 10:07:26,183 DEBUG zuul.Pipeline.demo-tenant.gate: Building shared change queues
2026-06-10 10:07:26,183 DEBUG zuul.Pipeline.demo-tenant.gate: Created queue: <ChangeQueueManager for None type: all-branches>
2026-06-10 10:07:26,184 DEBUG zuul.Pipeline.demo-tenant.gate: Added project demo-tenant-config to default queue manager: <ChangeQueueManager for None type: all-branches>
2026-06-10 10:07:26,184 DEBUG zuul.Pipeline.demo-tenant.gate: Created queue: <ChangeQueueManager for None type: all-branches>
2026-06-10 10:07:26,184 DEBUG zuul.Pipeline.demo-tenant.gate: Added project demo-project to default queue manager: <ChangeQueueManager for None type: all-branches>
2026-06-10 10:07:26,192 DEBUG zuul.Pipeline.demo-tenant.post: Building relative_priority queues
2026-06-10 10:07:26,199 DEBUG zuul.GerritConnection: Setting watched checkers to []
2026-06-10 10:07:26,199 DEBUG zuul.GerritConnection: Setting watched event filters for demo-tenant to [<GerritEventFilter connection: gerrit types: patchset-created ignore_deletes: True>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True comments: (?i)^(Patch Set [0-9]+:)?( [\w\+-]*)*(\n\n)?\s*(recheck|reverify)>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Workflow:1>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ ignore_deletes: True>]
2026-06-10 10:07:26,199 DEBUG zuul.GerritConnection: Setting watched event filters to {<GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Workflow:1>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True comments: (?i)^(Patch Set [0-9]+:)?( [\w\+-]*)*(\n\n)?\s*(recheck|reverify)>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: patchset-created ignore_deletes: True>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ ignore_deletes: True>}
2026-06-10 10:07:26,199 DEBUG zuul.TimerDriver: No jobs to remove for <Tenant demo-tenant>, 0 remain
2026-06-10 10:07:26,199 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:07:26,226 DEBUG zuul.Scheduler: Wait for internal read tenant lock
2026-06-10 10:07:26,232 DEBUG zuul.Scheduler: Aquired internal read tenant lock
2026-06-10 10:07:27,126 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-10 10:07:27,126 DEBUG zuul.TenantParser: Using files from cache for project gerrit-sshd.sf/config @master: []
2026-06-10 10:07:27,135 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/config-project.yaml@master
2026-06-10 10:07:27,142 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/jobs-base.yaml@master
2026-06-10 10:07:27,146 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/k8s-secret.yaml@master
2026-06-10 10:07:27,150 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/sf-logserver-secret.yaml@master
2026-06-10 10:07:27,156 DEBUG zuul.TenantParser: Created layout id 58b8ea3056594b8f82db269dc16f6058
2026-06-10 10:07:27,269 DEBUG zuul.Pipeline.internal.check: Building relative_priority queues
2026-06-10 10:07:27,323 DEBUG zuul.Pipeline.internal.gate: Building shared change queues
2026-06-10 10:07:27,323 DEBUG zuul.Pipeline.internal.gate: Created queue: <ChangeQueueManager for None type: all-branches>
2026-06-10 10:07:27,323 DEBUG zuul.Pipeline.internal.gate: Added project config to default queue manager: <ChangeQueueManager for None type: all-branches>
2026-06-10 10:07:27,330 DEBUG zuul.Pipeline.internal.post: Building relative_priority queues
2026-06-10 10:07:27,337 DEBUG zuul.GerritConnection: Setting watched checkers to []
2026-06-10 10:07:27,337 DEBUG zuul.GerritConnection: Setting watched event filters for internal to [<GerritEventFilter connection: gerrit types: patchset-created ignore_deletes: True>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True comments: (?i)^(Patch Set [0-9]+:)?( [\w\+-]*)*(\n\n)?\s*(recheck|reverify)>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Workflow:1>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ ignore_deletes: True>]
2026-06-10 10:07:27,337 DEBUG zuul.GerritConnection: Setting watched event filters to {<GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Workflow:1>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True comments: (?i)^(Patch Set [0-9]+:)?( [\w\+-]*)*(\n\n)?\s*(recheck|reverify)>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: patchset-created ignore_deletes: True>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ ignore_deletes: True>}
2026-06-10 10:07:27,337 DEBUG zuul.TimerDriver: No jobs to remove for <Tenant internal>, 0 remain
2026-06-10 10:07:27,337 DEBUG zuul.Scheduler: Released internal read tenant lock
2026-06-10 10:07:27,339 INFO zuul.Scheduler: Config priming complete (duration: 3.198 seconds)
2026-06-10 10:07:27,340 INFO zuul.TimerDriver: Running timer election
2026-06-10 10:07:27,340 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:07:27,345 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is None
2026-06-10 10:07:27,345 DEBUG zuul.RendezvousElection: Did not win election for /zuul/events/connection/gerrit/election
2026-06-10 10:07:27,345 DEBUG zuul.RendezvousElection: Did not win election for /zuul/events/connection/gerrit/election
2026-06-10 10:07:27,346 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event CHANGED for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000003
2026-06-10 10:07:27,346 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-10 10:07:27,346 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is <SchedulerComponent {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'state': 'running', 'kind': 'scheduler', 'version': '14.1.1.dev1 90eea2ed8', 'model_api': 37}>
2026-06-10 10:07:27,346 DEBUG zuul.RendezvousElection: Acquiring lock for /zuul/events/connection/gerrit/election
2026-06-10 10:07:27,355 DEBUG zuul.Scheduler: Starting semaphore cleanup
2026-06-10 10:07:27,361 DEBUG zuul.Scheduler.LayoutUpdate: Aquired demo-tenant read tenant lock
2026-06-10 10:07:27,361 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant
2026-06-10 10:07:27,362 DEBUG zuul.Scheduler.LayoutUpdate: Released demo-tenant read tenant lock
2026-06-10 10:07:27,370 DEBUG zuul.Scheduler: Starting build request cleanup
2026-06-10 10:07:27,370 DEBUG zuul.Scheduler: Finished build request cleanup
2026-06-10 10:07:27,376 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:07:27,376 DEBUG zuul.Scheduler: Starting node request cleanup
2026-06-10 10:07:27,423 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock
2026-06-10 10:07:27,425 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock
2026-06-10 10:07:27,430 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant
2026-06-10 10:07:27,451 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant
2026-06-10 10:07:27,458 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant
2026-06-10 10:07:27,525 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant
2026-06-10 10:07:27,536 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant
2026-06-10 10:07:27,542 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant
2026-06-10 10:07:27,547 INFO apscheduler.scheduler: Added job "Scheduler._runSemaphoreCleanup" to job store "default"
2026-06-10 10:07:27,547 INFO apscheduler.scheduler: Added job "Scheduler._runBuildRequestCleanup" to job store "default"
2026-06-10 10:07:27,547 INFO apscheduler.scheduler: Added job "Scheduler._runMergeRequestCleanup" to job store "default"
2026-06-10 10:07:27,547 INFO apscheduler.scheduler: Added job "Scheduler._runConnectionCleanup" to job store "default"
2026-06-10 10:07:27,547 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:07:27,547 INFO apscheduler.scheduler: Added job "Scheduler._runGeneralCleanup" to job store "default"
2026-06-10 10:07:27,548 DEBUG apscheduler.scheduler: Next wakeup is due at 2026-06-10 10:08:23.695460+00:00 (in 56.146806 seconds)
2026-06-10 10:07:27,548 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:07:27,548 DEBUG apscheduler.scheduler: Next wakeup is due at 2026-06-10 10:08:23.695460+00:00 (in 56.146575 seconds)
2026-06-10 10:07:27,551 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant
2026-06-10 10:07:27,553 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:07:27,560 DEBUG zuul.Scheduler: Processing tenant management events in internal
2026-06-10 10:07:27,566 DEBUG zuul.Scheduler: Aquired internal read tenant lock
2026-06-10 10:07:27,576 DEBUG zuul.Scheduler: Processing tenant trigger events in internal
2026-06-10 10:07:27,592 DEBUG zuul.Scheduler: Processing pipeline check in tenant internal
2026-06-10 10:07:27,599 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant internal
2026-06-10 10:07:27,604 DEBUG zuul.Scheduler: Processing pipeline gate in tenant internal
2026-06-10 10:07:27,611 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant internal
2026-06-10 10:07:27,617 DEBUG zuul.Scheduler: Processing pipeline post in tenant internal
2026-06-10 10:07:27,629 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant internal
2026-06-10 10:07:27,632 DEBUG zuul.Scheduler: Released internal read tenant lock
2026-06-10 10:07:27,634 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:07:28,331 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event DELETED for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000002
2026-06-10 10:07:28,331 INFO zuul.ComponentRegistry: Noticed scheduler component zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000002 disappeared
2026-06-10 10:07:28,331 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is <SchedulerComponent {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'state': 'running', 'kind': 'scheduler', 'version': '14.1.1.dev1 90eea2ed8', 'model_api': 37}>
2026-06-10 10:07:28,336 DEBUG zuul.connection.git.watcher: Walk through projects refs for connection: git-server
2026-06-10 10:07:28,337 INFO zuul.RendezvousElection: Won election for /zuul/events/connection/gerrit/election
2026-06-10 10:07:28,339 DEBUG zuul.connection.git.watcher: Walk through projects refs for connection: opendev.org
2026-06-10 10:07:28,341 INFO zuul.TimerDriver: Won timer election
2026-06-10 10:07:28,342 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event DELETED for merger zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000002
2026-06-10 10:07:28,342 INFO zuul.ComponentRegistry: Noticed merger component zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000002 disappeared
2026-06-10 10:07:28,342 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is <SchedulerComponent {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'state': 'running', 'kind': 'scheduler', 'version': '14.1.1.dev1 90eea2ed8', 'model_api': 37}>
2026-06-10 10:07:28,344 DEBUG paramiko.transport: starting thread (client mode): 0x4ae94c90
2026-06-10 10:07:28,345 DEBUG paramiko.transport: Local version/idstring: SSH-2.0-paramiko_4.0.0
2026-06-10 10:07:28,345 DEBUG paramiko.transport: Remote version/idstring: SSH-2.0-GerritCodeReview_3.6.4 (APACHE-SSHD-2.9.2)
2026-06-10 10:07:28,345 INFO paramiko.transport: Connected (version 2.0, client GerritCodeReview_3.6.4)
2026-06-10 10:07:28,346 DEBUG paramiko.transport: === Key exchange possibilities ===
2026-06-10 10:07:28,347 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-10 10:07:28,347 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-10 10:07:28,347 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-10 10:07:28,347 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-10 10:07:28,347 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-10 10:07:28,347 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-10 10:07:28,347 DEBUG paramiko.transport: client compress: none
2026-06-10 10:07:28,347 DEBUG paramiko.transport: server compress: none
2026-06-10 10:07:28,347 DEBUG paramiko.transport: client lang: <none>
2026-06-10 10:07:28,348 DEBUG paramiko.transport: server lang: <none>
2026-06-10 10:07:28,348 DEBUG paramiko.transport: kex follows: False
2026-06-10 10:07:28,348 DEBUG paramiko.transport: === Key exchange agreements ===
2026-06-10 10:07:28,348 DEBUG paramiko.transport: Kex: curve25519-sha256@libssh.org
2026-06-10 10:07:28,348 DEBUG paramiko.transport: HostKey: ssh-ed25519
2026-06-10 10:07:28,348 DEBUG paramiko.transport: Cipher: aes128-ctr
2026-06-10 10:07:28,348 DEBUG paramiko.transport: MAC: hmac-sha2-256
2026-06-10 10:07:28,348 DEBUG paramiko.transport: Compression: none
2026-06-10 10:07:28,348 DEBUG paramiko.transport: === End of kex handshake ===
2026-06-10 10:07:28,353 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event DELETED for web zuul-web-5557f5598d-mtdxx0000000004
2026-06-10 10:07:28,353 INFO zuul.ComponentRegistry: Noticed web component zuul-web-5557f5598d-mtdxx0000000004 disappeared
2026-06-10 10:07:28,353 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is <SchedulerComponent {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'state': 'running', 'kind': 'scheduler', 'version': '14.1.1.dev1 90eea2ed8', 'model_api': 37}>
2026-06-10 10:07:28,390 DEBUG paramiko.transport: kex engine KexCurve25519 specified hash_algo <built-in function openssl_sha256>
2026-06-10 10:07:28,390 DEBUG paramiko.transport: Switch to new keys ...
2026-06-10 10:07:28,391 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'}
/usr/local/lib/python3.11/site-packages/paramiko/client.py:885: UserWarning: Unknown ssh-ed25519 host key for [gerrit-sshd.sf]:29418: b'a16e20d8e89b403bbff3c840695da0f2'
  warnings.warn(
2026-06-10 10:07:28,392 DEBUG zuul.connection.git.watcher: Read 1 refs for project system-config
2026-06-10 10:07:29,370 DEBUG paramiko.transport: Trying discovered key b'd2d32139d8d810dedf64dfb19f687689' in /var/lib/zuul-ssh/..data/priv
2026-06-10 10:07:29,370 DEBUG zuul.connection.git.watcher: Read 1 refs for project zuul/zuul-jobs
2026-06-10 10:07:29,371 DEBUG paramiko.transport: userauth is OK
2026-06-10 10:07:29,371 DEBUG paramiko.transport: Finalizing pubkey algorithm for key of type 'ssh-rsa'
2026-06-10 10:07:29,371 DEBUG paramiko.transport: Our pubkey algorithm list: ['rsa-sha2-512', 'rsa-sha2-256', 'ssh-rsa']
2026-06-10 10:07:29,371 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-10 10:07:29,372 DEBUG paramiko.transport: Agreed upon 'rsa-sha2-512' pubkey algorithm
2026-06-10 10:07:29,437 INFO paramiko.transport: Authentication (publickey) successful!
2026-06-10 10:07:29,438 DEBUG paramiko.transport: [chan 0] Max packet in: 32768 bytes
2026-06-10 10:07:29,439 DEBUG paramiko.transport: [chan 0] Max packet out: 32768 bytes
2026-06-10 10:07:29,439 DEBUG paramiko.transport: Secsh channel 0 opened.
2026-06-10 10:07:29,440 DEBUG paramiko.transport: [chan 0] Sesch channel 0 request ok
2026-06-10 10:08:20,227 DEBUG zuul.CommandSocket: Accepted socket connection <socket.socket fd=7, family=1, type=1, proto=0, laddr=/var/lib/zuul/scheduler.socket>
2026-06-10 10:08:20,227 DEBUG zuul.CommandSocket: Received b'tenant-reconfigure ["internal"]' from socket
2026-06-10 10:08:20,228 DEBUG zuul.Scheduler: Tenant reconfiguration triggered
2026-06-10 10:08:20,238 DEBUG zuul.Scheduler: Configured logging: 14.1.1.dev1
2026-06-10 10:08:20,238 DEBUG zuul.Scheduler: [e: 993857fabaf14f3f9d91382cef82db6c] Submitting reconfiguration event
2026-06-10 10:08:20,241 DEBUG zuul.Scheduler: [e: 993857fabaf14f3f9d91382cef82db6c] Waiting for reconfiguration
2026-06-10 10:08:20,241 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:08:20,241 DEBUG zuul.Scheduler: Fetching reconfiguration event
2026-06-10 10:08:20,241 INFO zuul.Scheduler: Reconfiguration beginning (smart=False, tenants=['internal'])
2026-06-10 10:08:20,242 INFO zuul.ConfigLoader: Loading configuration from /var/lib/zuul/main.yaml
2026-06-10 10:08:20,342 DEBUG zuul.Scheduler: Wait for internal write tenant lock (id: RECONFIG)
2026-06-10 10:08:20,348 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'a16e20d8e89b403bbff3c840695da0f2'
  warnings.warn(
2026-06-10 10:08:22,443 DEBUG zuul.GerritConnection: SSH command:
2026-06-10 10:08:22,443 DEBUG zuul.GerritConnection:   git-upload-pack config
2026-06-10 10:08:22,523 DEBUG zuul.GerritConnection.io: SSH received stdout:
2026-06-10 10:08:22,523 DEBUG zuul.GerritConnection.io:   00e9909c2f3ef6cea26f89a20f87cadf37f01fd7a1ac 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-10 10:08:22,523 DEBUG zuul.GerritConnection.io:   003f909c2f3ef6cea26f89a20f87cadf37f01fd7a1ac refs/heads/master
2026-06-10 10:08:22,523 DEBUG zuul.GerritConnection.io:   003e9ea3fa1da9adfc617abcdbc274941debe4a82822 refs/meta/config
2026-06-10 10:08:22,523 DEBUG zuul.GerritConnection.io:   0000
2026-06-10 10:08:22,523 DEBUG zuul.GerritConnection: SSH exit status: 0
2026-06-10 10:08:22,523 DEBUG zuul.GerritConnection: Fetched 1 branches for config
2026-06-10 10:08:22,523 INFO zuul.GerritConnection: Got branches for config
2026-06-10 10:08:22,538 ERROR zuul.BranchCacheNew.gerrit: Exception loading ZKObject at /zuul/cache/connection/gerrit/project/data/config
2026-06-10 10:08:22,547 INFO zuul.GerritConnection: Got default branch for config: master
2026-06-10 10:08:22,697 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event CHANGED for web zuul-web-7444b8b5dd-6nlj60000000005
2026-06-10 10:08:22,697 INFO zuul.ComponentRegistry: Component web zuul-web-7444b8b5dd-6nlj60000000005 updated: {'hostname': 'zuul-web-7444b8b5dd-6nlj6', 'kind': 'web', 'model_api': 37, 'state': 'stopped', 'version': '14.1.1.dev1 90eea2ed8'}
2026-06-10 10:08:22,697 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is <SchedulerComponent {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'state': 'running', 'kind': 'scheduler', 'version': '14.1.1.dev1 90eea2ed8', 'model_api': 37}>
2026-06-10 10:08:22,801 DEBUG zuul.MergeClient: Submitting job 851032ba6938425da5256b06f28cdf08 with data {'connection': 'git-server', 'project': 'system-config', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']}
2026-06-10 10:08:22,802 DEBUG zuul.MergeClient: Submitting job 90827ff1c5a1461e8cb3421122297f22 with data {'connection': 'gerrit', 'project': 'config', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']}
2026-06-10 10:08:22,803 DEBUG zuul.MergeClient: Submitting job 7fc4f13d0a4c4563b4c8454786319002 with data {'connection': 'opendev.org', 'project': 'zuul/zuul-jobs', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']}
2026-06-10 10:08:22,812 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest 851032ba6938425da5256b06f28cdf08, job_type=cat, state=requested, path=/zuul/merger/requests/851032ba6938425da5256b06f28cdf08>
2026-06-10 10:08:22,814 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest 90827ff1c5a1461e8cb3421122297f22, job_type=cat, state=requested, path=/zuul/merger/requests/90827ff1c5a1461e8cb3421122297f22>
2026-06-10 10:08:22,814 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest 7fc4f13d0a4c4563b4c8454786319002, job_type=cat, state=requested, path=/zuul/merger/requests/7fc4f13d0a4c4563b4c8454786319002>
2026-06-10 10:08:22,837 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/851032ba6938425da5256b06f28cdf08> for git-server system-config master
2026-06-10 10:08:22,838 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/90827ff1c5a1461e8cb3421122297f22> for gerrit config master
2026-06-10 10:08:22,839 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/7fc4f13d0a4c4563b4c8454786319002> for opendev.org zuul/zuul-jobs master
2026-06-10 10:08:22,840 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/851032ba6938425da5256b06f28cdf08>
2026-06-10 10:08:23,237 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/851032ba6938425da5256b06f28cdf08> 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-10 10:08:23,237 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/config-project.yaml@master
2026-06-10 10:08:23,242 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/jobs-base.yaml@master
2026-06-10 10:08:23,243 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/k8s-secret.yaml@master
2026-06-10 10:08:23,244 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/sf-logserver-secret.yaml@master
2026-06-10 10:08:23,548 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/90827ff1c5a1461e8cb3421122297f22>
2026-06-10 10:08:23,663 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event DELETED for web zuul-web-7444b8b5dd-6nlj60000000005
2026-06-10 10:08:23,663 INFO zuul.ComponentRegistry: Noticed web component zuul-web-7444b8b5dd-6nlj60000000005 disappeared
2026-06-10 10:08:23,664 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is <SchedulerComponent {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'state': 'running', 'kind': 'scheduler', 'version': '14.1.1.dev1 90eea2ed8', 'model_api': 37}>
2026-06-10 10:08:23,704 DEBUG zuul.Scheduler: Starting build request cleanup
2026-06-10 10:08:23,704 DEBUG zuul.Scheduler: Finished build request cleanup
2026-06-10 10:08:24,048 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/90827ff1c5a1461e8cb3421122297f22> got files dict_keys(['.zuul.yaml', 'zuul.yaml'])
2026-06-10 10:08:24,063 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/7fc4f13d0a4c4563b4c8454786319002>
2026-06-10 10:08:24,081 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/7fc4f13d0a4c4563b4c8454786319002> 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-10 10:08:24,081 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/container-jobs.yaml@master
2026-06-10 10:08:24,082 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/dib-jobs.yaml@master
2026-06-10 10:08:24,082 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/docker-jobs.yaml@master
2026-06-10 10:08:24,083 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/general-jobs.yaml@master
2026-06-10 10:08:24,085 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/go-jobs.yaml@master
2026-06-10 10:08:24,086 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/haskell-jobs.yaml@master
2026-06-10 10:08:24,087 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/helm-jobs.yaml@master
2026-06-10 10:08:24,087 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/js-jobs.yaml@master
2026-06-10 10:08:24,089 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/nim-jobs.yaml@master
2026-06-10 10:08:24,089 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/packer-jobs.yaml@master
2026-06-10 10:08:24,089 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/python-jobs.yaml@master
2026-06-10 10:08:24,097 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/system-jobs.yaml@master
2026-06-10 10:08:24,097 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/terraform-jobs.yaml@master
2026-06-10 10:08:24,830 DEBUG zuul.TenantParser: Created layout id 6c2c83361dfc4f1890d840f50737b5f7
2026-06-10 10:08:25,025 DEBUG zuul.Pipeline.internal.check: Building relative_priority queues
2026-06-10 10:08:25,033 DEBUG zuul.Pipeline.internal.gate: Building shared change queues
2026-06-10 10:08:25,033 DEBUG zuul.Pipeline.internal.gate: Created queue: <ChangeQueueManager for None type: all-branches>
2026-06-10 10:08:25,033 DEBUG zuul.Pipeline.internal.gate: Added project config to default queue manager: <ChangeQueueManager for None type: all-branches>
2026-06-10 10:08:25,038 DEBUG zuul.Pipeline.internal.post: Building relative_priority queues
2026-06-10 10:08:25,047 DEBUG zuul.GerritConnection: Setting watched checkers to []
2026-06-10 10:08:25,047 DEBUG zuul.GerritConnection: Setting watched event filters for internal to [<GerritEventFilter connection: gerrit types: patchset-created ignore_deletes: True>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True comments: (?i)^(Patch Set [0-9]+:)?( [\w\+-]*)*(\n\n)?\s*(recheck|reverify)>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Workflow:1>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ ignore_deletes: True>]
2026-06-10 10:08:25,047 DEBUG zuul.GerritConnection: Setting watched event filters to {<GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Workflow:1>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True comments: (?i)^(Patch Set [0-9]+:)?( [\w\+-]*)*(\n\n)?\s*(recheck|reverify)>, <GerritEventFilter connection: gerrit types: comment-added ignore_deletes: True event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: patchset-created ignore_deletes: True>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ ignore_deletes: True>}
2026-06-10 10:08:25,047 DEBUG zuul.TimerDriver: No jobs to remove for <Tenant internal>, 0 remain
2026-06-10 10:08:25,077 DEBUG zuul.Scheduler: Setting layout state last reconfigure ltime to 5267
2026-06-10 10:08:25,131 DEBUG zuul.Scheduler: Released internal write tenant lock (id: RECONFIG)
2026-06-10 10:08:25,153 INFO zuul.Scheduler: Reconfiguration complete (smart: False, tenants: ['internal'], duration: 4.912 seconds)
2026-06-10 10:08:25,154 DEBUG zuul.Scheduler: [e: 993857fabaf14f3f9d91382cef82db6c] Reconfiguration complete
2026-06-10 10:08:25,159 DEBUG zuul.Scheduler.LayoutUpdate: Aquired demo-tenant read tenant lock
2026-06-10 10:08:25,160 DEBUG zuul.Scheduler.LayoutUpdate: Released demo-tenant read tenant lock
2026-06-10 10:08:25,226 DEBUG zuul.Scheduler: Processing tenant management events in internal
2026-06-10 10:08:25,233 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock
2026-06-10 10:08:25,234 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock
2026-06-10 10:08:25,235 DEBUG zuul.Scheduler: Aquired internal read tenant lock
2026-06-10 10:08:25,244 DEBUG zuul.Scheduler: Processing tenant trigger events in internal
2026-06-10 10:08:25,260 DEBUG zuul.Scheduler: Processing pipeline check in tenant internal
2026-06-10 10:08:25,323 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f59483ea410>
2026-06-10 10:08:25,333 DEBUG zuul.Pipeline.internal.check: Starting queue processor: check
2026-06-10 10:08:25,333 DEBUG zuul.Pipeline.internal.check: Finished queue processor: check (changed: False)
2026-06-10 10:08:25,345 DEBUG zuul.Scheduler: Processing pipeline gate in tenant internal
2026-06-10 10:08:25,424 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f594816c150>
2026-06-10 10:08:25,437 DEBUG zuul.Pipeline.internal.gate: Starting queue processor: gate
2026-06-10 10:08:25,437 DEBUG zuul.Pipeline.internal.gate: Finished queue processor: gate (changed: False)
2026-06-10 10:08:25,460 DEBUG zuul.Scheduler: Processing pipeline post in tenant internal
2026-06-10 10:08:25,541 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f594816ca90>
2026-06-10 10:08:25,551 DEBUG zuul.Pipeline.internal.post: Starting queue processor: post
2026-06-10 10:08:25,552 DEBUG zuul.Pipeline.internal.post: Finished queue processor: post (changed: False)
2026-06-10 10:08:25,623 DEBUG zuul.Scheduler: Released internal read tenant lock
2026-06-10 10:08:25,631 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant
2026-06-10 10:08:25,640 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:08:25,648 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant
2026-06-10 10:08:25,737 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant
2026-06-10 10:08:25,739 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant
2026-06-10 10:08:25,744 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant
2026-06-10 10:08:25,746 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant
2026-06-10 10:08:25,752 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant
2026-06-10 10:08:25,754 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant
2026-06-10 10:08:25,756 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:08:25,758 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:08:25,758 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:08:25,832 DEBUG zuul.Scheduler: Processing tenant management events in internal
2026-06-10 10:08:25,840 DEBUG zuul.Scheduler: Aquired internal read tenant lock
2026-06-10 10:08:25,847 DEBUG zuul.Scheduler: Processing tenant trigger events in internal
2026-06-10 10:08:25,861 DEBUG zuul.Scheduler: Processing pipeline check in tenant internal
2026-06-10 10:08:25,924 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant internal
2026-06-10 10:08:25,928 DEBUG zuul.Scheduler: Processing pipeline gate in tenant internal
2026-06-10 10:08:25,930 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant internal
2026-06-10 10:08:25,933 DEBUG zuul.Scheduler: Processing pipeline post in tenant internal
2026-06-10 10:08:25,935 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant internal
2026-06-10 10:08:25,937 DEBUG zuul.Scheduler: Released internal read tenant lock
2026-06-10 10:08:25,942 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant
2026-06-10 10:08:25,948 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:08:25,953 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant
2026-06-10 10:08:26,033 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant
2026-06-10 10:08:26,037 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant
2026-06-10 10:08:26,042 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant
2026-06-10 10:08:26,045 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant
2026-06-10 10:08:26,050 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant
2026-06-10 10:08:26,053 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant
2026-06-10 10:08:26,127 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:08:26,130 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:08:26,672 DEBUG zuul.Scheduler: Starting merge request cleanup
2026-06-10 10:08:26,672 DEBUG zuul.Scheduler: Finished merge request cleanup
2026-06-10 10:08:35,023 INFO zuul.ComponentRegistry: Noticed new web component zuul-web-7444b8b5dd-m47fz0000000006
2026-06-10 10:08:35,025 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event None for web zuul-web-7444b8b5dd-m47fz0000000006
2026-06-10 10:08:35,025 INFO zuul.ComponentRegistry: Component web zuul-web-7444b8b5dd-m47fz0000000006 updated: {'hostname': 'zuul-web-7444b8b5dd-m47fz', 'kind': 'web', 'model_api': 37, 'state': 'stopped', 'version': '14.1.1.dev1 90eea2ed8'}
2026-06-10 10:08:35,025 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is <SchedulerComponent {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'state': 'running', 'kind': 'scheduler', 'version': '14.1.1.dev1 90eea2ed8', 'model_api': 37}>
2026-06-10 10:08:35,258 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event CHANGED for web zuul-web-7444b8b5dd-m47fz0000000006
2026-06-10 10:08:35,259 INFO zuul.ComponentRegistry: Component web zuul-web-7444b8b5dd-m47fz0000000006 updated: {'hostname': 'zuul-web-7444b8b5dd-m47fz', 'kind': 'web', 'model_api': 37, 'state': 'initializing', 'version': '14.1.1.dev1 90eea2ed8'}
2026-06-10 10:08:35,259 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is <SchedulerComponent {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'state': 'running', 'kind': 'scheduler', 'version': '14.1.1.dev1 90eea2ed8', 'model_api': 37}>
2026-06-10 10:08:38,462 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f594ae83690> got event CHANGED for web zuul-web-7444b8b5dd-m47fz0000000006
2026-06-10 10:08:38,462 INFO zuul.ComponentRegistry: Component web zuul-web-7444b8b5dd-m47fz0000000006 updated: {'hostname': 'zuul-web-7444b8b5dd-m47fz', 'kind': 'web', 'model_api': 37, 'state': 'running', 'version': '14.1.1.dev1 90eea2ed8'}
2026-06-10 10:08:38,462 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is <SchedulerComponent {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'state': 'running', 'kind': 'scheduler', 'version': '14.1.1.dev1 90eea2ed8', 'model_api': 37}>
2026-06-10 10:09:28,265 DEBUG zuul.Scheduler: Starting build request cleanup
2026-06-10 10:09:28,266 DEBUG zuul.Scheduler: Finished build request cleanup
2026-06-10 10:09:31,140 DEBUG zuul.Scheduler: Starting merge request cleanup
2026-06-10 10:09:31,140 DEBUG zuul.Scheduler: Finished merge request cleanup