2026-06-10 10:58:35,301 DEBUG zuul.Scheduler: Configured logging: 13.1.0
2026-06-10 10:58:35,301 DEBUG zuul.TimerDriver: Starting apscheduler
2026-06-10 10:58:35,302 INFO apscheduler.scheduler: Scheduler started
2026-06-10 10:58:35,302 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:58:35,302 DEBUG apscheduler.scheduler: No jobs; waiting until a job is added
2026-06-10 10:58:35,303 DEBUG zuul.BubblewrapDriver: Initializing bubblewrap with oom_score_adj starting: 995, final: 1000
2026-06-10 10:58:35,305 INFO zuul.SQLConnection: Initializing SQL connection database (prefix: )
2026-06-10 10:58:35,471 DEBUG zuul.zk.base.ZooKeeperClient: ZooKeeper connection (session: 0x10000067ca80003): CONNECTED
2026-06-10 10:58:35,480 INFO zuul.Component: Registering component in ZooKeeper /zuul/components/scheduler/zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local
2026-06-10 10:58:35,484 INFO zuul.ComponentRegistry: Noticed new scheduler component zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000000
2026-06-10 10:58:35,485 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f4cb0fc19d0> got event None for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000000
2026-06-10 10:58:35,485 INFO zuul.ComponentRegistry: Component scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000000 updated: {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'kind': 'scheduler', 'model_api': 36, 'state': 'stopped', 'version': '13.1.0'}
2026-06-10 10:58:35,502 INFO zuul.ComponentRegistry: Noticed new web component zuul-web-5d44896644-542dr0000000000
2026-06-10 10:58:35,568 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f4cb0fc19d0> got event None for web zuul-web-5d44896644-542dr0000000000
2026-06-10 10:58:35,568 INFO zuul.ComponentRegistry: Component web zuul-web-5d44896644-542dr0000000000 updated: {'hostname': 'zuul-web-5d44896644-542dr', 'kind': 'web', 'model_api': 36, 'state': 'initializing', 'version': '13.1.0'}
2026-06-10 10:58:35,572 INFO zuul.ComponentRegistry: System minimum data model version 36; this component 36
2026-06-10 10:58:35,577 DEBUG zuul.zk.ZooKeeper: Initialize cache at /keystorage-oidc
2026-06-10 10:58:35,585 DEBUG zuul.zk.ZooKeeper: Cache at /keystorage-oidc is ready
2026-06-10 10:58:35,590 DEBUG zuul.ExecutorQueue: Creating executor queue at root /zuul/executor/unzoned
2026-06-10 10:58:35,668 DEBUG zuul.zk.ZooKeeper: Initialize cache at /zuul/executor/unzoned
2026-06-10 10:58:35,676 DEBUG zuul.zk.ZooKeeper: Cache at /zuul/executor/unzoned is ready
2026-06-10 10:58:35,703 DEBUG zuul.zk.ZooKeeper: Initialize cache at /zuul/merger
2026-06-10 10:58:35,780 DEBUG zuul.zk.ZooKeeper: Cache at /zuul/merger is ready
2026-06-10 10:58:35,789 DEBUG zuul.nodepool: Running nodepool watcher election
2026-06-10 10:58:35,790 DEBUG zuul.TimerDriver: Starting timer election loop
2026-06-10 10:58:35,790 INFO zuul.TimerDriver: Running timer election
2026-06-10 10:58:35,806 INFO zuul.nodepool: Watching nodepool requests
2026-06-10 10:58:35,807 INFO zuul.TimerDriver: Won timer election
2026-06-10 10:58:35,819 DEBUG zuul.SQLConnection: Current migration revision: ce1459953a12
2026-06-10 10:58:35,869 DEBUG zuul.GerritConnection: Starting Gerrit Connection/Watchers
2026-06-10 10:58:35,869 DEBUG zuul.GerritConnection: Creating Zookeeper branch cache
2026-06-10 10:58:35,877 INFO zuul.GerritConnection: Creating Zookeeper event queue
2026-06-10 10:58:35,881 DEBUG zuul.GerritConnection: Creating Zookeeper change cache
2026-06-10 10:58:35,889 INFO zuul.GerritConnection: Starting SSH event stream client
2026-06-10 10:58:35,890 INFO zuul.GerritConnection: gerrit: Gerrit Poller is disabled because no HTTP authentication is defined
2026-06-10 10:58:35,890 DEBUG zuul.connection.git: Creating Zookeeper change cache
2026-06-10 10:58:35,901 DEBUG zuul.connection.git: Starting Git Watcher
2026-06-10 10:58:35,902 DEBUG zuul.connection.git: Creating Zookeeper change cache
2026-06-10 10:58:35,904 DEBUG paramiko.transport: starting thread (client mode): 0xb0638f90
2026-06-10 10:58:35,904 DEBUG paramiko.transport: Local version/idstring: SSH-2.0-paramiko_4.0.0
2026-06-10 10:58:35,904 DEBUG paramiko.transport: Remote version/idstring: SSH-2.0-GerritCodeReview_3.6.4 (APACHE-SSHD-2.9.2)
2026-06-10 10:58:35,904 INFO paramiko.transport: Connected (version 2.0, client GerritCodeReview_3.6.4)
2026-06-10 10:58:35,905 DEBUG paramiko.transport: === Key exchange possibilities ===
2026-06-10 10:58:35,905 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:58:35,906 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:58:35,906 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:58:35,906 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:58:35,906 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:58:35,906 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:58:35,906 DEBUG paramiko.transport: client compress: none
2026-06-10 10:58:35,906 DEBUG paramiko.transport: server compress: none
2026-06-10 10:58:35,906 DEBUG paramiko.transport: client lang: <none>
2026-06-10 10:58:35,906 DEBUG paramiko.transport: server lang: <none>
2026-06-10 10:58:35,906 DEBUG paramiko.transport: kex follows: False
2026-06-10 10:58:35,906 DEBUG paramiko.transport: === Key exchange agreements ===
2026-06-10 10:58:35,906 DEBUG paramiko.transport: Kex: curve25519-sha256@libssh.org
2026-06-10 10:58:35,906 DEBUG paramiko.transport: HostKey: ssh-ed25519
2026-06-10 10:58:35,906 DEBUG paramiko.transport: Cipher: aes128-ctr
2026-06-10 10:58:35,906 DEBUG paramiko.transport: MAC: hmac-sha2-256
2026-06-10 10:58:35,906 DEBUG paramiko.transport: Compression: none
2026-06-10 10:58:35,906 DEBUG paramiko.transport: === End of kex handshake ===
2026-06-10 10:58:35,909 DEBUG zuul.connection.git: Starting Git Watcher
2026-06-10 10:58:35,912 INFO zuul.Scheduler: Starting scheduler
2026-06-10 10:58:35,912 DEBUG zuul.Scheduler: Statsd enabled
2026-06-10 10:58:35,913 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:35,913 DEBUG zuul.Scheduler: Starting command processor
2026-06-10 10:58:35,916 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:35,918 DEBUG zuul.Scheduler: Running stats election
2026-06-10 10:58:35,918 INFO apscheduler.scheduler: Scheduler started
2026-06-10 10:58:35,919 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:58:35,919 DEBUG apscheduler.scheduler: No jobs; waiting until a job is added
2026-06-10 10:58:35,948 DEBUG paramiko.transport: kex engine KexCurve25519 specified hash_algo <built-in function openssl_sha256>
2026-06-10 10:58:35,950 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'851dae8f2c4ddc8884833ced28098418'
  warnings.warn(
2026-06-10 10:58:36,493 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-10 10:58:36,495 INFO zuul.Scheduler: Priming scheduler config
2026-06-10 10:58:36,496 DEBUG paramiko.transport: Trying discovered key b'2ae14fcb8341ce7dbdbebe54394806b5' in /var/lib/zuul-ssh/..data/priv
2026-06-10 10:58:36,498 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f4cb0fc19d0> got event CHANGED for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000000
2026-06-10 10:58:36,498 INFO zuul.ComponentRegistry: Component scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000000 updated: {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'kind': 'scheduler', 'model_api': 36, 'state': 'initializing', 'version': '13.1.0'}
2026-06-10 10:58:36,498 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is None
2026-06-10 10:58:36,499 DEBUG paramiko.transport: userauth is OK
2026-06-10 10:58:36,499 DEBUG paramiko.transport: Finalizing pubkey algorithm for key of type 'ssh-rsa'
2026-06-10 10:58:36,499 DEBUG paramiko.transport: Our pubkey algorithm list: ['rsa-sha2-512', 'rsa-sha2-256', 'ssh-rsa']
2026-06-10 10:58:36,499 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:58:36,499 DEBUG paramiko.transport: Agreed upon 'rsa-sha2-512' pubkey algorithm
2026-06-10 10:58:36,508 DEBUG zuul.connection.git.watcher: Walk through projects refs for connection: git-server
2026-06-10 10:58:36,518 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:36,520 INFO paramiko.transport: Authentication (publickey) successful!
2026-06-10 10:58:36,520 DEBUG paramiko.transport: [chan 0] Max packet in: 32768 bytes
2026-06-10 10:58:36,522 INFO zuul.Scheduler: Creating initial system config
2026-06-10 10:58:36,523 DEBUG zuul.Scheduler: Won stats election
2026-06-10 10:58:36,523 INFO zuul.ConfigLoader: Loading configuration from /var/lib/zuul/main.yaml
2026-06-10 10:58:36,568 DEBUG paramiko.transport: [chan 0] Max packet out: 32768 bytes
2026-06-10 10:58:36,568 DEBUG paramiko.transport: Secsh channel 0 opened.
2026-06-10 10:58:36,571 DEBUG paramiko.transport: [chan 0] Sesch channel 0 request ok
2026-06-10 10:58:36,573 DEBUG zuul.connection.git.watcher: Walk through projects refs for connection: opendev.org
2026-06-10 10:58:36,603 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:36,611 INFO zuul.KeyStorage: Generating a new secrets key for git-server/system-config
2026-06-10 10:58:37,120 INFO zuul.KeyStorage: Generating a new secrets key for gerrit/config
2026-06-10 10:58:38,791 INFO zuul.KeyStorage: Generating a new secrets key for opendev.org/zuul/zuul-jobs
2026-06-10 10:58:40,445 INFO zuul.ComponentRegistry: Noticed new executor component zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000000
2026-06-10 10:58:40,448 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f4cb0fc19d0> got event None for executor zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000000
2026-06-10 10:58:40,448 INFO zuul.ComponentRegistry: Component executor zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000000 updated: {'accepting_work': True, 'allow_unzoned': True, 'hostname': 'zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local', 'kind': 'executor', 'model_api': 36, 'process_merge_jobs': False, 'state': 'stopped', 'version': '13.1.0', 'zone': None}
2026-06-10 10:58:40,448 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is None
2026-06-10 10:58:40,459 INFO zuul.ComponentRegistry: Noticed new merger component zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000000
2026-06-10 10:58:40,461 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f4cb0fc19d0> got event None for merger zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000000
2026-06-10 10:58:40,461 INFO zuul.ComponentRegistry: Component merger zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local0000000000 updated: {'hostname': 'zuul-merger-0.zuul-merger-headless.sf.svc.cluster.local', 'kind': 'merger', 'model_api': 36, 'state': 'running', 'version': '13.1.0'}
2026-06-10 10:58:40,461 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is None
2026-06-10 10:58:40,590 INFO zuul.KeyStorage: Generating a new SSH key for gerrit/config
2026-06-10 10:58:40,666 INFO zuul.KeyStorage: Generating a new SSH key for git-server/system-config
2026-06-10 10:58:40,667 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f4cb0fc19d0> got event CHANGED for executor zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000000
2026-06-10 10:58:40,769 INFO zuul.ComponentRegistry: Component executor zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000000 updated: {'accepting_work': True, 'allow_unzoned': True, 'hostname': 'zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local', 'kind': 'executor', 'model_api': 36, 'process_merge_jobs': True, 'state': 'stopped', 'version': '13.1.0', 'zone': None}
2026-06-10 10:58:40,769 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is None
2026-06-10 10:58:40,770 INFO zuul.KeyStorage: Generating a new SSH key for opendev.org/zuul/zuul-jobs
2026-06-10 10:58:40,794 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f4cb0fc19d0> got event CHANGED for executor zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000000
2026-06-10 10:58:40,794 INFO zuul.ComponentRegistry: Component executor zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local0000000000 updated: {'accepting_work': True, 'allow_unzoned': True, 'hostname': 'zuul-executor-0.zuul-executor-headless.sf.svc.cluster.local', 'kind': 'executor', 'model_api': 36, 'process_merge_jobs': True, 'state': 'running', 'version': '13.1.0', 'zone': None}
2026-06-10 10:58:40,794 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is None
2026-06-10 10:58:40,799 INFO zuul.KeyStorage: Generating a new secrets key for gerrit/demo-tenant-config
2026-06-10 10:58:42,280 INFO zuul.KeyStorage: Generating a new secrets key for gerrit/demo-project
2026-06-10 10:58:43,505 DEBUG zuul.Scheduler: Wait for demo-tenant write tenant lock (id: None)
2026-06-10 10:58:43,540 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:43,544 DEBUG zuul.Scheduler: Aquired demo-tenant write tenant lock (id: None)
2026-06-10 10:58:43,580 DEBUG paramiko.transport: starting thread (client mode): 0xb06f6790
2026-06-10 10:58:43,580 DEBUG paramiko.transport: Local version/idstring: SSH-2.0-paramiko_4.0.0
2026-06-10 10:58:43,581 DEBUG paramiko.transport: Remote version/idstring: SSH-2.0-GerritCodeReview_3.6.4 (APACHE-SSHD-2.9.2)
2026-06-10 10:58:43,581 INFO paramiko.transport: Connected (version 2.0, client GerritCodeReview_3.6.4)
2026-06-10 10:58:43,581 DEBUG paramiko.transport: starting thread (client mode): 0xb06f73d0
2026-06-10 10:58:43,581 DEBUG paramiko.transport: Local version/idstring: SSH-2.0-paramiko_4.0.0
2026-06-10 10:58:43,581 DEBUG paramiko.transport: Remote version/idstring: SSH-2.0-GerritCodeReview_3.6.4 (APACHE-SSHD-2.9.2)
2026-06-10 10:58:43,581 INFO paramiko.transport: Connected (version 2.0, client GerritCodeReview_3.6.4)
2026-06-10 10:58:43,582 INFO zuul.KeyStorage: Generating a new SSH key for demo-tenant
2026-06-10 10:58:43,768 DEBUG paramiko.transport: === Key exchange possibilities ===
2026-06-10 10:58:43,768 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:58:43,768 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:58:43,768 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:58:43,769 DEBUG paramiko.transport: === Key exchange possibilities ===
2026-06-10 10:58:43,769 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:58:43,770 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:58:43,770 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:58:43,771 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:58:43,771 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:58:43,771 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:58:43,771 DEBUG paramiko.transport: client compress: none
2026-06-10 10:58:43,772 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:58:43,772 DEBUG paramiko.transport: server compress: none
2026-06-10 10:58:43,772 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:58:43,772 DEBUG paramiko.transport: client lang: <none>
2026-06-10 10:58:43,772 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:58:43,772 DEBUG paramiko.transport: server lang: <none>
2026-06-10 10:58:43,772 DEBUG paramiko.transport: client compress: none
2026-06-10 10:58:43,772 DEBUG paramiko.transport: kex follows: False
2026-06-10 10:58:43,772 DEBUG paramiko.transport: server compress: none
2026-06-10 10:58:43,773 DEBUG paramiko.transport: === Key exchange agreements ===
2026-06-10 10:58:43,773 DEBUG paramiko.transport: client lang: <none>
2026-06-10 10:58:43,773 DEBUG paramiko.transport: Kex: curve25519-sha256@libssh.org
2026-06-10 10:58:43,773 DEBUG paramiko.transport: server lang: <none>
2026-06-10 10:58:43,773 DEBUG paramiko.transport: HostKey: ssh-ed25519
2026-06-10 10:58:43,773 DEBUG paramiko.transport: kex follows: False
2026-06-10 10:58:43,773 DEBUG paramiko.transport: Cipher: aes128-ctr
2026-06-10 10:58:43,773 DEBUG paramiko.transport: === Key exchange agreements ===
2026-06-10 10:58:43,773 DEBUG paramiko.transport: MAC: hmac-sha2-256
2026-06-10 10:58:43,774 DEBUG paramiko.transport: Kex: curve25519-sha256@libssh.org
2026-06-10 10:58:43,774 DEBUG paramiko.transport: Compression: none
2026-06-10 10:58:43,774 DEBUG paramiko.transport: HostKey: ssh-ed25519
2026-06-10 10:58:43,774 DEBUG paramiko.transport: === End of kex handshake ===
2026-06-10 10:58:43,774 DEBUG paramiko.transport: Cipher: aes128-ctr
2026-06-10 10:58:43,775 DEBUG paramiko.transport: MAC: hmac-sha2-256
2026-06-10 10:58:43,775 DEBUG paramiko.transport: Compression: none
2026-06-10 10:58:43,775 DEBUG paramiko.transport: === End of kex handshake ===
2026-06-10 10:58:43,779 DEBUG paramiko.transport: kex engine KexCurve25519 specified hash_algo <built-in function openssl_sha256>
2026-06-10 10:58:43,779 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'851dae8f2c4ddc8884833ced28098418'
  warnings.warn(
2026-06-10 10:58:44,593 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-10 10:58:44,593 DEBUG paramiko.transport: Trying discovered key b'2ae14fcb8341ce7dbdbebe54394806b5' in /var/lib/zuul-ssh/..data/priv
2026-06-10 10:58:44,595 DEBUG paramiko.transport: kex engine KexCurve25519 specified hash_algo <built-in function openssl_sha256>
2026-06-10 10:58:44,596 DEBUG paramiko.transport: Switch to new keys ...
2026-06-10 10:58:44,597 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'851dae8f2c4ddc8884833ced28098418'
  warnings.warn(
2026-06-10 10:58:44,597 DEBUG paramiko.transport: userauth is OK
2026-06-10 10:58:44,597 DEBUG paramiko.transport: Finalizing pubkey algorithm for key of type 'ssh-rsa'
2026-06-10 10:58:44,597 DEBUG paramiko.transport: Our pubkey algorithm list: ['rsa-sha2-512', 'rsa-sha2-256', 'ssh-rsa']
2026-06-10 10:58:44,598 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:58:44,598 DEBUG paramiko.transport: Agreed upon 'rsa-sha2-512' pubkey algorithm
2026-06-10 10:58:45,402 DEBUG paramiko.transport: Trying discovered key b'2ae14fcb8341ce7dbdbebe54394806b5' in /var/lib/zuul-ssh/..data/priv
2026-06-10 10:58:45,409 INFO paramiko.transport: Authentication (publickey) successful!
2026-06-10 10:58:45,409 DEBUG zuul.GerritConnection: SSH command:
2026-06-10 10:58:45,409 DEBUG zuul.GerritConnection:   git-upload-pack demo-project
2026-06-10 10:58:45,410 DEBUG paramiko.transport: [chan 0] Max packet in: 32768 bytes
2026-06-10 10:58:45,410 DEBUG paramiko.transport: userauth is OK
2026-06-10 10:58:45,411 DEBUG paramiko.transport: Finalizing pubkey algorithm for key of type 'ssh-rsa'
2026-06-10 10:58:45,411 DEBUG paramiko.transport: Our pubkey algorithm list: ['rsa-sha2-512', 'rsa-sha2-256', 'ssh-rsa']
2026-06-10 10:58:45,411 DEBUG paramiko.transport: [chan 0] Max packet out: 32768 bytes
2026-06-10 10:58:45,412 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:58:45,412 DEBUG paramiko.transport: Secsh channel 0 opened.
2026-06-10 10:58:45,413 DEBUG paramiko.transport: Agreed upon 'rsa-sha2-512' pubkey algorithm
2026-06-10 10:58:45,477 DEBUG paramiko.transport: [chan 0] Sesch channel 0 request ok
2026-06-10 10:58:45,479 DEBUG paramiko.transport: [chan 0] EOF received (0)
2026-06-10 10:58:45,480 DEBUG zuul.GerritConnection.io: SSH received stdout:
2026-06-10 10:58:45,480 DEBUG zuul.GerritConnection.io:   00e94846aa7d39c40331dd61f04d9966c60d147551fd 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:58:45,480 DEBUG zuul.GerritConnection.io:   003f4846aa7d39c40331dd61f04d9966c60d147551fd refs/heads/master
2026-06-10 10:58:45,480 DEBUG zuul.GerritConnection.io:   003e8bfe308e85b119a88e34d890b5a73ce48728ef2d refs/meta/config
2026-06-10 10:58:45,480 DEBUG zuul.GerritConnection.io:   0000
2026-06-10 10:58:45,483 INFO paramiko.transport: Authentication (publickey) successful!
2026-06-10 10:58:45,483 DEBUG zuul.GerritConnection: SSH command:
2026-06-10 10:58:45,483 DEBUG zuul.GerritConnection:   git-upload-pack demo-tenant-config
2026-06-10 10:58:45,484 DEBUG paramiko.transport: [chan 0] Max packet in: 32768 bytes
2026-06-10 10:58:45,485 DEBUG paramiko.transport: [chan 0] Max packet out: 32768 bytes
2026-06-10 10:58:45,485 DEBUG paramiko.transport: Secsh channel 0 opened.
2026-06-10 10:58:45,486 DEBUG paramiko.transport: [chan 0] Sesch channel 0 request ok
2026-06-10 10:58:45,520 DEBUG zuul.GerritConnection: SSH exit status: 0
2026-06-10 10:58:45,520 DEBUG paramiko.transport: [chan 0] EOF sent (0)
2026-06-10 10:58:45,520 DEBUG zuul.GerritConnection: Fetched 1 branches for demo-project
2026-06-10 10:58:45,520 INFO zuul.GerritConnection: Got branches for demo-project
2026-06-10 10:58:45,536 DEBUG paramiko.transport: [chan 0] EOF received (0)
2026-06-10 10:58:45,536 DEBUG paramiko.transport: [chan 0] EOF sent (0)
2026-06-10 10:58:45,536 DEBUG zuul.GerritConnection.io: SSH received stdout:
2026-06-10 10:58:45,536 DEBUG zuul.GerritConnection.io:   00e9b890d23388765ccda4adab93015dd82075e1f471 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:58:45,536 DEBUG zuul.GerritConnection.io:   003fb890d23388765ccda4adab93015dd82075e1f471 refs/heads/master
2026-06-10 10:58:45,536 DEBUG zuul.GerritConnection.io:   003ef86c8bd991216409e69a5b223d90dceb3dff1500 refs/meta/config
2026-06-10 10:58:45,536 DEBUG zuul.GerritConnection.io:   0000
2026-06-10 10:58:45,536 DEBUG zuul.GerritConnection: SSH exit status: 0
2026-06-10 10:58:45,536 DEBUG zuul.GerritConnection: Fetched 1 branches for demo-tenant-config
2026-06-10 10:58:45,537 INFO zuul.GerritConnection: Got branches for demo-tenant-config
2026-06-10 10:58:45,695 INFO zuul.GerritConnection: Got default branch for demo-project: master
2026-06-10 10:58:45,975 INFO zuul.GerritConnection: Got default branch for demo-tenant-config: master
2026-06-10 10:58:46,422 DEBUG zuul.MergeClient: Submitting job a8ebdfe4648f4af5b643d2845a53efcf with data {'connection': 'gerrit', 'project': 'demo-tenant-config', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']}
2026-06-10 10:58:46,459 DEBUG zuul.MergeClient: Submitting job 607f9ec41cb7417dbdf510cbad3692ab 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:58:46,461 DEBUG zuul.MergeClient: Submitting job c2c86155a13948e58ef3a19c1a51b06e with data {'connection': 'gerrit', 'project': 'demo-project', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']}
2026-06-10 10:58:46,508 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest a8ebdfe4648f4af5b643d2845a53efcf, job_type=cat, state=requested, path=/zuul/merger/requests/a8ebdfe4648f4af5b643d2845a53efcf>
2026-06-10 10:58:46,510 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest 607f9ec41cb7417dbdf510cbad3692ab, job_type=cat, state=requested, path=/zuul/merger/requests/607f9ec41cb7417dbdf510cbad3692ab>
2026-06-10 10:58:46,511 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest c2c86155a13948e58ef3a19c1a51b06e, job_type=cat, state=requested, path=/zuul/merger/requests/c2c86155a13948e58ef3a19c1a51b06e>
2026-06-10 10:58:46,608 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/607f9ec41cb7417dbdf510cbad3692ab> for opendev.org zuul/zuul-jobs master
2026-06-10 10:58:46,613 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/c2c86155a13948e58ef3a19c1a51b06e> for gerrit demo-project master
2026-06-10 10:58:46,778 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/a8ebdfe4648f4af5b643d2845a53efcf> for gerrit demo-tenant-config master
2026-06-10 10:58:46,779 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/607f9ec41cb7417dbdf510cbad3692ab>
2026-06-10 10:58:50,083 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/607f9ec41cb7417dbdf510cbad3692ab> 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:58:50,084 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/container-jobs.yaml@master
2026-06-10 10:58:50,087 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/dib-jobs.yaml@master
2026-06-10 10:58:50,089 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/docker-jobs.yaml@master
2026-06-10 10:58:50,092 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/general-jobs.yaml@master
2026-06-10 10:58:50,096 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/go-jobs.yaml@master
2026-06-10 10:58:50,098 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/haskell-jobs.yaml@master
2026-06-10 10:58:50,100 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/helm-jobs.yaml@master
2026-06-10 10:58:50,101 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/js-jobs.yaml@master
2026-06-10 10:58:50,105 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/nim-jobs.yaml@master
2026-06-10 10:58:50,105 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/packer-jobs.yaml@master
2026-06-10 10:58:50,106 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/python-jobs.yaml@master
2026-06-10 10:58:50,119 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/system-jobs.yaml@master
2026-06-10 10:58:50,119 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/terraform-jobs.yaml@master
2026-06-10 10:58:50,558 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/c2c86155a13948e58ef3a19c1a51b06e>
2026-06-10 10:58:50,573 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/c2c86155a13948e58ef3a19c1a51b06e> got files dict_keys(['.zuul.d/jobs.yaml', '.zuul.d/project.yaml', '.zuul.yaml', 'zuul.yaml'])
2026-06-10 10:58:50,573 INFO zuul.TenantParser: Loading configuration from demo-project/.zuul.d/jobs.yaml@master
2026-06-10 10:58:50,575 INFO zuul.TenantParser: Loading configuration from demo-project/.zuul.d/project.yaml@master
2026-06-10 10:58:50,610 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/a8ebdfe4648f4af5b643d2845a53efcf>
2026-06-10 10:58:50,623 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/a8ebdfe4648f4af5b643d2845a53efcf> 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-10 10:58:50,624 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-base-jobs.yaml@master
2026-06-10 10:58:50,625 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-pipeline.yaml@master
2026-06-10 10:58:50,630 INFO zuul.TenantParser: Loading configuration from demo-tenant-config/zuul.d/gerrit-project-pipeline.yaml@master
2026-06-10 10:58:50,673 DEBUG zuul.TenantParser: Created layout id e98a48df0dbc42149d2395322c3b72bb
2026-06-10 10:58:50,681 DEBUG zuul.Pipeline.demo-tenant.check: Building relative_priority queues
2026-06-10 10:58:50,691 ERROR zuul.Pipeline.demo-tenant.check: Exception loading ZKObject at /zuul/tenant/demo-tenant/pipeline/check
2026-06-10 10:58:50,691 WARNING zuul.Pipeline.demo-tenant.check: Initializing pipeline state for check; this is expected only for new pipelines
2026-06-10 10:58:50,705 WARNING zuul.Pipeline.demo-tenant.check: Initializing pipeline change list for check; this is expected only for new pipelines
2026-06-10 10:58:50,715 DEBUG zuul.Pipeline.demo-tenant.gate: Building shared change queues
2026-06-10 10:58:50,715 DEBUG zuul.Pipeline.demo-tenant.gate: Created queue: <zuul.manager.shared.ChangeQueueManager object at 0x7f4cb05dfe10>
2026-06-10 10:58:50,715 DEBUG zuul.Pipeline.demo-tenant.gate: Added project demo-tenant-config to queue managers: <zuul.manager.shared.ChangeQueueManager object at 0x7f4cb05dfe10>
2026-06-10 10:58:50,715 DEBUG zuul.Pipeline.demo-tenant.gate: Created queue: <zuul.manager.shared.ChangeQueueManager object at 0x7f4cb06c1250>
2026-06-10 10:58:50,715 DEBUG zuul.Pipeline.demo-tenant.gate: Added project demo-project to queue managers: <zuul.manager.shared.ChangeQueueManager object at 0x7f4cb06c1250>
2026-06-10 10:58:50,721 ERROR zuul.Pipeline.demo-tenant.gate: Exception loading ZKObject at /zuul/tenant/demo-tenant/pipeline/gate
2026-06-10 10:58:50,721 WARNING zuul.Pipeline.demo-tenant.gate: Initializing pipeline state for gate; this is expected only for new pipelines
2026-06-10 10:58:50,724 WARNING zuul.Pipeline.demo-tenant.gate: Initializing pipeline change list for gate; this is expected only for new pipelines
2026-06-10 10:58:50,736 DEBUG zuul.Pipeline.demo-tenant.post: Building relative_priority queues
2026-06-10 10:58:50,744 ERROR zuul.Pipeline.demo-tenant.post: Exception loading ZKObject at /zuul/tenant/demo-tenant/pipeline/post
2026-06-10 10:58:50,744 WARNING zuul.Pipeline.demo-tenant.post: Initializing pipeline state for post; this is expected only for new pipelines
2026-06-10 10:58:50,748 WARNING zuul.Pipeline.demo-tenant.post: Initializing pipeline change list for post; this is expected only for new pipelines
2026-06-10 10:58:50,784 DEBUG zuul.GerritConnection: Setting watched checkers to []
2026-06-10 10:58:50,784 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:58:50,784 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 event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ 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: patchset-created ignore_deletes: True>}
2026-06-10 10:58:50,827 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:50,857 DEBUG zuul.Scheduler: Skipping management event queue for tenant demo-tenant
2026-06-10 10:58:50,869 DEBUG zuul.Scheduler: Skipping locked tenant demo-tenant
2026-06-10 10:58:50,870 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:50,870 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:50,883 DEBUG zuul.Scheduler: Skipping management event queue for tenant demo-tenant
2026-06-10 10:58:50,894 DEBUG zuul.Scheduler: Skipping locked tenant demo-tenant
2026-06-10 10:58:50,895 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:50,895 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:50,911 DEBUG zuul.Scheduler: Skipping management event queue for tenant demo-tenant
2026-06-10 10:58:50,921 DEBUG zuul.Scheduler: Skipping locked tenant demo-tenant
2026-06-10 10:58:50,922 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:50,922 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:50,932 DEBUG zuul.Scheduler: Setting layout state last reconfigure ltime to current ltime 4438
2026-06-10 10:58:50,933 DEBUG zuul.Scheduler: Skipping management event queue for tenant demo-tenant
2026-06-10 10:58:50,941 DEBUG zuul.Scheduler: Skipping locked tenant demo-tenant
2026-06-10 10:58:50,941 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:50,941 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:50,952 DEBUG zuul.GerritConnection: Setting watched checkers to []
2026-06-10 10:58:50,953 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:58:50,953 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 event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ 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: patchset-created ignore_deletes: True>}
2026-06-10 10:58:50,953 DEBUG zuul.Scheduler: Released demo-tenant write tenant lock (id: None)
2026-06-10 10:58:50,955 DEBUG zuul.Scheduler: Wait for internal write tenant lock (id: None)
2026-06-10 10:58:50,955 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant
2026-06-10 10:58:50,970 DEBUG zuul.Scheduler: Aquired internal write tenant lock (id: None)
2026-06-10 10:58:50,982 DEBUG zuul.GerritConnection: SSH command:
2026-06-10 10:58:50,982 DEBUG zuul.GerritConnection:   git-upload-pack config
2026-06-10 10:58:50,983 DEBUG paramiko.transport: [chan 1] Max packet in: 32768 bytes
2026-06-10 10:58:50,985 DEBUG paramiko.transport: [chan 1] Max packet out: 32768 bytes
2026-06-10 10:58:50,985 DEBUG paramiko.transport: Secsh channel 1 opened.
2026-06-10 10:58:50,986 INFO zuul.KeyStorage: Generating a new SSH key for internal
2026-06-10 10:58:50,986 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:58:51,277 DEBUG paramiko.transport: [chan 1] Sesch channel 1 request ok
2026-06-10 10:58:51,282 DEBUG paramiko.transport: [chan 1] EOF received (1)
2026-06-10 10:58:51,282 DEBUG zuul.GerritConnection.io: SSH received stdout:
2026-06-10 10:58:51,282 DEBUG zuul.GerritConnection.io:   00e99d7e48a892e8bb04603d3f9e0c12f3c279696c57 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:58:51,282 DEBUG zuul.GerritConnection.io:   003f9d7e48a892e8bb04603d3f9e0c12f3c279696c57 refs/heads/master
2026-06-10 10:58:51,282 DEBUG zuul.GerritConnection.io:   003edd9b0ac7a4b00e125409025554cb4319a018c6c7 refs/meta/config
2026-06-10 10:58:51,282 DEBUG zuul.GerritConnection.io:   0000
2026-06-10 10:58:51,283 DEBUG zuul.GerritConnection: SSH exit status: 0
2026-06-10 10:58:51,283 DEBUG paramiko.transport: [chan 1] EOF sent (1)
2026-06-10 10:58:51,290 DEBUG zuul.GerritConnection: Fetched 1 branches for config
2026-06-10 10:58:51,290 INFO zuul.GerritConnection: Got branches for config
2026-06-10 10:58:51,328 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant
2026-06-10 10:58:51,340 INFO zuul.GerritConnection: Got default branch for config: master
2026-06-10 10:58:51,373 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant
2026-06-10 10:58:51,378 WARNING zuul.PipelineSummary: No pipeline summary found (may not be created yet)
2026-06-10 10:58:51,382 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f4cb0480bd0>
2026-06-10 10:58:51,388 DEBUG zuul.Pipeline.demo-tenant.check: Starting queue processor: check
2026-06-10 10:58:51,388 DEBUG zuul.Pipeline.demo-tenant.check: Finished queue processor: check (changed: False)
2026-06-10 10:58:51,415 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant
2026-06-10 10:58:51,424 WARNING zuul.PipelineSummary: No pipeline summary found (may not be created yet)
2026-06-10 10:58:51,429 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f4cb0483950>
2026-06-10 10:58:51,434 DEBUG zuul.Pipeline.demo-tenant.gate: Starting queue processor: gate
2026-06-10 10:58:51,434 DEBUG zuul.Pipeline.demo-tenant.gate: Finished queue processor: gate (changed: False)
2026-06-10 10:58:51,454 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant
2026-06-10 10:58:51,472 WARNING zuul.PipelineSummary: No pipeline summary found (may not be created yet)
2026-06-10 10:58:51,479 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f4cb04801d0>
2026-06-10 10:58:51,486 DEBUG zuul.Pipeline.demo-tenant.post: Starting queue processor: post
2026-06-10 10:58:51,486 DEBUG zuul.Pipeline.demo-tenant.post: Finished queue processor: post (changed: False)
2026-06-10 10:58:51,498 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:58:51,501 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:51,501 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:51,509 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant
2026-06-10 10:58:51,520 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:58:51,527 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant
2026-06-10 10:58:51,569 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant
2026-06-10 10:58:51,579 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant
2026-06-10 10:58:51,584 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant
2026-06-10 10:58:51,587 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant
2026-06-10 10:58:51,592 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant
2026-06-10 10:58:51,596 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant
2026-06-10 10:58:51,598 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:58:51,600 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:51,766 DEBUG zuul.MergeClient: Submitting job e95ee6253e7e474ea3929ea83036ca51 with data {'connection': 'gerrit', 'project': 'config', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']}
2026-06-10 10:58:51,774 DEBUG zuul.MergeClient: Submitting job 2d078594e0b349dbba33b5a09bab8052 with data {'connection': 'git-server', 'project': 'system-config', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']}
2026-06-10 10:58:51,782 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest e95ee6253e7e474ea3929ea83036ca51, job_type=cat, state=requested, path=/zuul/merger/requests/e95ee6253e7e474ea3929ea83036ca51>
2026-06-10 10:58:51,787 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest 2d078594e0b349dbba33b5a09bab8052, job_type=cat, state=requested, path=/zuul/merger/requests/2d078594e0b349dbba33b5a09bab8052>
2026-06-10 10:58:51,797 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/e95ee6253e7e474ea3929ea83036ca51> for gerrit config master
2026-06-10 10:58:51,808 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/2d078594e0b349dbba33b5a09bab8052> for git-server system-config master
2026-06-10 10:58:51,809 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/e95ee6253e7e474ea3929ea83036ca51>
2026-06-10 10:58:53,229 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/e95ee6253e7e474ea3929ea83036ca51> got files dict_keys(['.zuul.yaml', 'zuul.yaml'])
2026-06-10 10:58:53,244 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/2d078594e0b349dbba33b5a09bab8052>
2026-06-10 10:58:53,258 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/2d078594e0b349dbba33b5a09bab8052> got files dict_keys(['.zuul.yaml', 'zuul.d/config-project.yaml', 'zuul.d/jobs-base.yaml', 'zuul.yaml'])
2026-06-10 10:58:53,259 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/config-project.yaml@master
2026-06-10 10:58:53,266 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/jobs-base.yaml@master
2026-06-10 10:58:53,310 DEBUG zuul.TenantParser: Created layout id b3acac4cbf2c4fe59125ecab70c78477
2026-06-10 10:58:53,316 DEBUG zuul.Pipeline.internal.check: Building relative_priority queues
2026-06-10 10:58:53,329 ERROR zuul.Pipeline.internal.check: Exception loading ZKObject at /zuul/tenant/internal/pipeline/check
2026-06-10 10:58:53,329 WARNING zuul.Pipeline.internal.check: Initializing pipeline state for check; this is expected only for new pipelines
2026-06-10 10:58:53,342 WARNING zuul.Pipeline.internal.check: Initializing pipeline change list for check; this is expected only for new pipelines
2026-06-10 10:58:53,354 DEBUG zuul.Pipeline.internal.gate: Building shared change queues
2026-06-10 10:58:53,354 DEBUG zuul.Pipeline.internal.gate: Created queue: <zuul.manager.shared.ChangeQueueManager object at 0x7f4cb05af810>
2026-06-10 10:58:53,355 DEBUG zuul.Pipeline.internal.gate: Added project config to queue managers: <zuul.manager.shared.ChangeQueueManager object at 0x7f4cb05af810>
2026-06-10 10:58:53,363 ERROR zuul.Pipeline.internal.gate: Exception loading ZKObject at /zuul/tenant/internal/pipeline/gate
2026-06-10 10:58:53,363 WARNING zuul.Pipeline.internal.gate: Initializing pipeline state for gate; this is expected only for new pipelines
2026-06-10 10:58:53,367 WARNING zuul.Pipeline.internal.gate: Initializing pipeline change list for gate; this is expected only for new pipelines
2026-06-10 10:58:53,383 DEBUG zuul.Pipeline.internal.post: Building relative_priority queues
2026-06-10 10:58:53,392 ERROR zuul.Pipeline.internal.post: Exception loading ZKObject at /zuul/tenant/internal/pipeline/post
2026-06-10 10:58:53,393 WARNING zuul.Pipeline.internal.post: Initializing pipeline state for post; this is expected only for new pipelines
2026-06-10 10:58:53,397 WARNING zuul.Pipeline.internal.post: Initializing pipeline change list for post; this is expected only for new pipelines
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader: 2 errors detected during internal tenant configuration loading
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader: Zuul encountered a syntax error while parsing its
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   configuration in the repo system-config on branch master.  The
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   problem was:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:     The secret "site_sflogs" was not found.
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   The problem appears in the the "base" job stanza:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:     job:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         name: base
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         description: The base job.
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         parent: null
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         post-run:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:             - playbooks/base/post.yaml
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         pre-run:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:             - playbooks/base/pre.yaml
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         roles:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:     ...
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:     in "system-config/zuul.d/jobs-base.yaml@master", line 5
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader: Zuul encountered a syntax error while parsing its
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   configuration in the repo system-config on branch master.  The
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   problem was:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:     The secret "k8s_config" was not found.
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   The problem appears in the the "config-update" job stanza:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:     job:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         name: config-update
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         description: Deploy config repo update.
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         parent: base
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         secrets:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:             - k8s_config
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         final: true
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:         run:
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:             - playbooks/config/update.yaml
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:     ...
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:   
2026-06-10 10:58:53,411 WARNING zuul.ConfigLoader:     in "system-config/zuul.d/jobs-base.yaml@master", line 26
2026-06-10 10:58:53,439 DEBUG zuul.GerritConnection: Setting watched checkers to []
2026-06-10 10:58:53,439 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:58:53,440 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 event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ 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: patchset-created ignore_deletes: True>}
2026-06-10 10:58:53,510 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:53,528 DEBUG zuul.Scheduler: Skipping management event queue for tenant internal
2026-06-10 10:58:53,540 DEBUG zuul.Scheduler: Skipping locked tenant internal
2026-06-10 10:58:53,551 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant
2026-06-10 10:58:53,579 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:58:53,592 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant
2026-06-10 10:58:53,691 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant
2026-06-10 10:58:53,699 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant
2026-06-10 10:58:53,702 DEBUG zuul.Scheduler: Setting layout state last reconfigure ltime to current ltime 4688
2026-06-10 10:58:53,768 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant
2026-06-10 10:58:53,775 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant
2026-06-10 10:58:53,784 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant
2026-06-10 10:58:53,787 DEBUG zuul.GerritConnection: Setting watched checkers to []
2026-06-10 10:58:53,787 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:58:53,788 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 event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ 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: patchset-created ignore_deletes: True>}
2026-06-10 10:58:53,788 DEBUG zuul.Scheduler: Released internal write tenant lock (id: None)
2026-06-10 10:58:53,791 INFO zuul.Scheduler: Config priming complete (duration: 17.296 seconds)
2026-06-10 10:58:53,797 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant
2026-06-10 10:58:53,799 DEBUG zuul.RendezvousElection: Election winner for /zuul/events/connection/gerrit/election is None
2026-06-10 10:58:53,799 DEBUG zuul.RendezvousElection: Did not win election for /zuul/events/connection/gerrit/election
2026-06-10 10:58:53,799 DEBUG zuul.RendezvousElection: Did not win election for /zuul/events/connection/gerrit/election
2026-06-10 10:58:53,800 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:58:53,801 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f4cb0fc19d0> got event CHANGED for scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000000
2026-06-10 10:58:53,801 INFO zuul.ComponentRegistry: Component scheduler zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local0000000000 updated: {'hostname': 'zuul-scheduler-0.zuul-scheduler.sf.svc.cluster.local', 'kind': 'scheduler', 'model_api': 36, 'state': 'running', 'version': '13.1.0'}
2026-06-10 10:58:53,801 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': '13.1.0', 'model_api': 36}>
2026-06-10 10:58:53,802 DEBUG zuul.RendezvousElection: Acquiring lock for /zuul/events/connection/gerrit/election
2026-06-10 10:58:53,869 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:53,869 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:53,875 INFO zuul.RendezvousElection: Won election for /zuul/events/connection/gerrit/election
2026-06-10 10:58:53,880 DEBUG zuul.Scheduler.LayoutUpdate: Aquired demo-tenant read tenant lock
2026-06-10 10:58:53,882 DEBUG zuul.Scheduler.LayoutUpdate: Released demo-tenant read tenant lock
2026-06-10 10:58:53,889 DEBUG zuul.Scheduler: Processing tenant management events in internal
2026-06-10 10:58:53,901 DEBUG zuul.Scheduler: Starting semaphore cleanup
2026-06-10 10:58:53,902 DEBUG zuul.Scheduler: Aquired internal read tenant lock
2026-06-10 10:58:53,903 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock
2026-06-10 10:58:53,904 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock
2026-06-10 10:58:53,978 DEBUG zuul.Scheduler: Starting build request cleanup
2026-06-10 10:58:53,979 DEBUG zuul.Scheduler: Finished build request cleanup
2026-06-10 10:58:53,980 DEBUG zuul.Scheduler: Processing tenant trigger events in internal
2026-06-10 10:58:53,992 DEBUG zuul.Scheduler: Starting node request cleanup
2026-06-10 10:58:54,079 DEBUG zuul.Scheduler: Processing pipeline check in tenant internal
2026-06-10 10:58:54,095 DEBUG zuul.Scheduler.LayoutUpdate: Aquired demo-tenant read tenant lock
2026-06-10 10:58:54,098 DEBUG zuul.Scheduler.LayoutUpdate: Released demo-tenant read tenant lock
2026-06-10 10:58:54,105 WARNING zuul.PipelineSummary: No pipeline summary found (may not be created yet)
2026-06-10 10:58:54,172 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f4cb04ba890>
2026-06-10 10:58:54,178 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock
2026-06-10 10:58:54,180 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock
2026-06-10 10:58:54,191 DEBUG zuul.Pipeline.internal.check: Starting queue processor: check
2026-06-10 10:58:54,191 DEBUG zuul.Pipeline.internal.check: Finished queue processor: check (changed: False)
2026-06-10 10:58:54,267 DEBUG zuul.Scheduler: Processing pipeline gate in tenant internal
2026-06-10 10:58:54,280 WARNING zuul.PipelineSummary: No pipeline summary found (may not be created yet)
2026-06-10 10:58:54,286 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f4cb04b8690>
2026-06-10 10:58:54,294 DEBUG zuul.Pipeline.internal.gate: Starting queue processor: gate
2026-06-10 10:58:54,294 DEBUG zuul.Pipeline.internal.gate: Finished queue processor: gate (changed: False)
2026-06-10 10:58:54,376 DEBUG zuul.Scheduler: Processing pipeline post in tenant internal
2026-06-10 10:58:54,388 WARNING zuul.PipelineSummary: No pipeline summary found (may not be created yet)
2026-06-10 10:58:54,392 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f4cb04b59d0>
2026-06-10 10:58:54,399 DEBUG zuul.Pipeline.internal.post: Starting queue processor: post
2026-06-10 10:58:54,400 DEBUG zuul.Pipeline.internal.post: Finished queue processor: post (changed: False)
2026-06-10 10:58:54,414 DEBUG zuul.Scheduler: Released internal read tenant lock
2026-06-10 10:58:54,424 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant
2026-06-10 10:58:54,429 INFO apscheduler.scheduler: Added job "Scheduler._runSemaphoreCleanup" to job store "default"
2026-06-10 10:58:54,430 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:58:54,430 DEBUG apscheduler.scheduler: Next wakeup is due at 2026-06-10 11:59:06.736702+00:00 (in 3612.306315 seconds)
2026-06-10 10:58:54,431 INFO apscheduler.scheduler: Added job "Scheduler._runBuildRequestCleanup" to job store "default"
2026-06-10 10:58:54,432 INFO apscheduler.scheduler: Added job "Scheduler._runMergeRequestCleanup" to job store "default"
2026-06-10 10:58:54,432 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:58:54,433 INFO apscheduler.scheduler: Added job "Scheduler._runConnectionCleanup" to job store "default"
2026-06-10 10:58:54,433 DEBUG apscheduler.scheduler: Next wakeup is due at 2026-06-10 10:59:36.467733+00:00 (in 42.033842 seconds)
2026-06-10 10:58:54,434 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:58:54,434 INFO apscheduler.scheduler: Added job "Scheduler._runGeneralCleanup" to job store "default"
2026-06-10 10:58:54,434 DEBUG apscheduler.scheduler: Next wakeup is due at 2026-06-10 10:59:36.467733+00:00 (in 42.033074 seconds)
2026-06-10 10:58:54,434 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:58:54,434 DEBUG apscheduler.scheduler: Next wakeup is due at 2026-06-10 10:59:36.467733+00:00 (in 42.032811 seconds)
2026-06-10 10:58:54,439 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:58:54,447 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant
2026-06-10 10:58:54,484 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant
2026-06-10 10:58:54,488 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant
2026-06-10 10:58:54,496 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant
2026-06-10 10:58:54,499 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant
2026-06-10 10:58:54,506 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant
2026-06-10 10:58:54,509 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant
2026-06-10 10:58:54,512 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:58:54,514 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:54,515 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:58:54,527 DEBUG zuul.Scheduler: Processing tenant management events in internal
2026-06-10 10:58:54,536 DEBUG zuul.Scheduler: Aquired internal read tenant lock
2026-06-10 10:58:54,543 DEBUG zuul.Scheduler: Processing tenant trigger events in internal
2026-06-10 10:58:54,575 DEBUG zuul.Scheduler: Processing pipeline check in tenant internal
2026-06-10 10:58:54,579 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant internal
2026-06-10 10:58:54,586 DEBUG zuul.Scheduler: Processing pipeline gate in tenant internal
2026-06-10 10:58:54,591 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant internal
2026-06-10 10:58:54,598 DEBUG zuul.Scheduler: Processing pipeline post in tenant internal
2026-06-10 10:58:54,601 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant internal
2026-06-10 10:58:54,603 DEBUG zuul.Scheduler: Released internal read tenant lock
2026-06-10 10:58:54,612 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant
2026-06-10 10:58:54,619 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:58:54,624 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant
2026-06-10 10:58:54,639 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant
2026-06-10 10:58:54,642 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant
2026-06-10 10:58:54,647 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant
2026-06-10 10:58:54,650 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant
2026-06-10 10:58:54,657 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant
2026-06-10 10:58:54,659 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant
2026-06-10 10:58:54,661 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:58:54,663 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:58:55,018 DEBUG zuul.ComponentRegistry: Registry <zuul.zk.components.ComponentRegistry object at 0x7f4cb0fc19d0> got event CHANGED for web zuul-web-5d44896644-542dr0000000000
2026-06-10 10:58:55,019 INFO zuul.ComponentRegistry: Component web zuul-web-5d44896644-542dr0000000000 updated: {'hostname': 'zuul-web-5d44896644-542dr', 'kind': 'web', 'model_api': 36, 'state': 'running', 'version': '13.1.0'}
2026-06-10 10:58:55,019 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': '13.1.0', 'model_api': 36}>
2026-06-10 10:59:36,468 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:59:36,468 INFO apscheduler.executors.default: Running job "Scheduler._runMergeRequestCleanup (trigger: interval[0:01:00], next run at: 2026-06-10 10:59:36 UTC)" (scheduled at 2026-06-10 10:59:36.467733+00:00)
2026-06-10 10:59:36,469 DEBUG apscheduler.scheduler: Next wakeup is due at 2026-06-10 10:59:38.825014+00:00 (in 2.355554 seconds)
2026-06-10 10:59:36,477 DEBUG zuul.Scheduler: Starting merge request cleanup
2026-06-10 10:59:36,477 DEBUG zuul.Scheduler: Finished merge request cleanup
2026-06-10 10:59:36,479 INFO apscheduler.executors.default: Job "Scheduler._runMergeRequestCleanup (trigger: interval[0:01:00], next run at: 2026-06-10 11:00:39 UTC)" executed successfully
2026-06-10 10:59:36,576 DEBUG paramiko.transport: Sending global request "keepalive@lag.net"
2026-06-10 10:59:38,825 DEBUG apscheduler.scheduler: Looking for jobs to run
2026-06-10 10:59:38,825 DEBUG apscheduler.scheduler: Next wakeup is due at 2026-06-10 11:00:39.630734+00:00 (in 60.804919 seconds)
2026-06-10 10:59:38,826 INFO apscheduler.executors.default: Running job "Scheduler._runBuildRequestCleanup (trigger: interval[0:01:00], next run at: 2026-06-10 11:00:42 UTC)" (scheduled at 2026-06-10 10:59:38.825014+00:00)
2026-06-10 10:59:38,831 DEBUG zuul.Scheduler: Starting build request cleanup
2026-06-10 10:59:38,831 DEBUG zuul.Scheduler: Finished build request cleanup
2026-06-10 10:59:38,835 INFO apscheduler.executors.default: Job "Scheduler._runBuildRequestCleanup (trigger: interval[0:01:00], next run at: 2026-06-10 11:00:42 UTC)" executed successfully
2026-06-10 10:59:45,593 DEBUG paramiko.transport: Sending global request "keepalive@lag.net"
2026-06-10 10:59:50,713 DEBUG zuul.CommandSocket: Accepted socket connection <socket.socket fd=12, family=1, type=1, proto=0, laddr=/var/lib/zuul/scheduler.socket>
2026-06-10 10:59:50,713 DEBUG zuul.CommandSocket: Received b'tenant-reconfigure ["internal"]' from socket
2026-06-10 10:59:50,713 DEBUG zuul.Scheduler: Tenant reconfiguration triggered
2026-06-10 10:59:50,777 DEBUG zuul.Scheduler: Configured logging: 13.1.0
2026-06-10 10:59:50,777 DEBUG zuul.Scheduler: [e: c153ce2a8d634190b44b448428eff115] Submitting reconfiguration event
2026-06-10 10:59:50,784 DEBUG zuul.Scheduler: [e: c153ce2a8d634190b44b448428eff115] Waiting for reconfiguration
2026-06-10 10:59:50,784 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:59:50,784 DEBUG zuul.Scheduler: Fetching reconfiguration event
2026-06-10 10:59:50,784 INFO zuul.Scheduler: Reconfiguration beginning (smart=False, tenants=['internal'])
2026-06-10 10:59:50,785 INFO zuul.ConfigLoader: Loading configuration from /var/lib/zuul/main.yaml
2026-06-10 10:59:50,974 DEBUG zuul.Scheduler: Wait for internal write tenant lock (id: RECONFIG)
2026-06-10 10:59:50,979 DEBUG zuul.Scheduler: Aquired internal write tenant lock (id: RECONFIG)
2026-06-10 10:59:52,172 DEBUG zuul.GerritConnection: SSH command:
2026-06-10 10:59:52,172 DEBUG zuul.GerritConnection:   git-upload-pack config
2026-06-10 10:59:52,222 DEBUG zuul.GerritConnection.io: SSH received stdout:
2026-06-10 10:59:52,222 DEBUG zuul.GerritConnection.io:   00e99d7e48a892e8bb04603d3f9e0c12f3c279696c57 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:59:52,222 DEBUG zuul.GerritConnection.io:   003f9d7e48a892e8bb04603d3f9e0c12f3c279696c57 refs/heads/master
2026-06-10 10:59:52,222 DEBUG zuul.GerritConnection.io:   003edd9b0ac7a4b00e125409025554cb4319a018c6c7 refs/meta/config
2026-06-10 10:59:52,222 DEBUG zuul.GerritConnection.io:   0000
2026-06-10 10:59:52,267 DEBUG zuul.GerritConnection: SSH exit status: 0
2026-06-10 10:59:52,268 DEBUG zuul.GerritConnection: Fetched 1 branches for config
2026-06-10 10:59:52,269 INFO zuul.GerritConnection: Got branches for config
2026-06-10 10:59:52,378 INFO zuul.GerritConnection: Got default branch for config: master
2026-06-10 10:59:53,038 DEBUG zuul.MergeClient: Submitting job a3ecbd16131143fc85f21784f0bc508e with data {'connection': 'gerrit', 'project': 'config', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']}
2026-06-10 10:59:53,039 DEBUG zuul.MergeClient: Submitting job 60b170211e97459ba0ed2d51afc31b8b 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:59:53,040 DEBUG zuul.MergeClient: Submitting job 62c6f05d1a184675bce8cd12e49ba4ee with data {'connection': 'git-server', 'project': 'system-config', 'branch': 'master', 'files': ['zuul.yaml', '.zuul.yaml'], 'dirs': ['zuul.d', '.zuul.d']}
2026-06-10 10:59:53,048 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest a3ecbd16131143fc85f21784f0bc508e, job_type=cat, state=requested, path=/zuul/merger/requests/a3ecbd16131143fc85f21784f0bc508e>
2026-06-10 10:59:53,049 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest 60b170211e97459ba0ed2d51afc31b8b, job_type=cat, state=requested, path=/zuul/merger/requests/60b170211e97459ba0ed2d51afc31b8b>
2026-06-10 10:59:53,050 DEBUG zuul.MergerApi: Submitting job request to ZooKeeper <MergeRequest 62c6f05d1a184675bce8cd12e49ba4ee, job_type=cat, state=requested, path=/zuul/merger/requests/62c6f05d1a184675bce8cd12e49ba4ee>
2026-06-10 10:59:53,071 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/a3ecbd16131143fc85f21784f0bc508e> for gerrit config master
2026-06-10 10:59:53,073 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/60b170211e97459ba0ed2d51afc31b8b> for opendev.org zuul/zuul-jobs master
2026-06-10 10:59:53,074 DEBUG zuul.TenantParser: Submitting cat job <JobResultFuture request_path=/zuul/merger/requests/62c6f05d1a184675bce8cd12e49ba4ee> for git-server system-config master
2026-06-10 10:59:53,075 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/a3ecbd16131143fc85f21784f0bc508e>
2026-06-10 10:59:54,782 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/a3ecbd16131143fc85f21784f0bc508e> got files dict_keys(['.zuul.yaml', 'zuul.yaml'])
2026-06-10 10:59:54,799 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/60b170211e97459ba0ed2d51afc31b8b>
2026-06-10 10:59:56,725 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/60b170211e97459ba0ed2d51afc31b8b> 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:59:56,725 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/container-jobs.yaml@master
2026-06-10 10:59:56,728 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/dib-jobs.yaml@master
2026-06-10 10:59:56,728 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/docker-jobs.yaml@master
2026-06-10 10:59:56,730 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/general-jobs.yaml@master
2026-06-10 10:59:56,734 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/go-jobs.yaml@master
2026-06-10 10:59:56,735 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/haskell-jobs.yaml@master
2026-06-10 10:59:56,737 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/helm-jobs.yaml@master
2026-06-10 10:59:56,739 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/js-jobs.yaml@master
2026-06-10 10:59:56,743 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/nim-jobs.yaml@master
2026-06-10 10:59:56,744 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/packer-jobs.yaml@master
2026-06-10 10:59:56,745 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/python-jobs.yaml@master
2026-06-10 10:59:56,767 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/system-jobs.yaml@master
2026-06-10 10:59:56,767 INFO zuul.TenantParser: Loading configuration from zuul/zuul-jobs/zuul.d/terraform-jobs.yaml@master
2026-06-10 10:59:56,989 DEBUG zuul.TenantParser: Waiting for cat job <JobResultFuture request_path=/zuul/merger/requests/62c6f05d1a184675bce8cd12e49ba4ee>
2026-06-10 10:59:57,003 DEBUG zuul.TenantParser: Cat job <JobResultFuture request_path=/zuul/merger/requests/62c6f05d1a184675bce8cd12e49ba4ee> 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:59:57,003 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/config-project.yaml@master
2026-06-10 10:59:57,007 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/jobs-base.yaml@master
2026-06-10 10:59:57,008 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/k8s-secret.yaml@master
2026-06-10 10:59:57,008 INFO zuul.TenantParser: Loading configuration from system-config/zuul.d/sf-logserver-secret.yaml@master
2026-06-10 10:59:57,119 DEBUG zuul.TenantParser: Created layout id 91b49d7f2a174668b15c76784449bd47
2026-06-10 10:59:57,276 DEBUG zuul.Pipeline.internal.check: Building relative_priority queues
2026-06-10 10:59:57,284 DEBUG zuul.Pipeline.internal.gate: Building shared change queues
2026-06-10 10:59:57,284 DEBUG zuul.Pipeline.internal.gate: Created queue: <zuul.manager.shared.ChangeQueueManager object at 0x7f4cb047bd50>
2026-06-10 10:59:57,284 DEBUG zuul.Pipeline.internal.gate: Added project config to queue managers: <zuul.manager.shared.ChangeQueueManager object at 0x7f4cb047bd50>
2026-06-10 10:59:57,290 DEBUG zuul.Pipeline.internal.post: Building relative_priority queues
2026-06-10 10:59:57,300 DEBUG zuul.GerritConnection: Setting watched checkers to []
2026-06-10 10:59:57,300 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:59:57,300 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 event_approvals: Verified:1 usernames: zuul>, <GerritEventFilter connection: gerrit types: change-restored ignore_deletes: True>, <GerritEventFilter connection: gerrit types: ref-updated refs: ^refs/heads/master$, ^refs/heads/main$ 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: patchset-created ignore_deletes: True>}
2026-06-10 10:59:57,326 DEBUG zuul.Scheduler: Setting layout state last reconfigure ltime to 4829
2026-06-10 10:59:57,343 DEBUG zuul.Scheduler: Released internal write tenant lock (id: RECONFIG)
2026-06-10 10:59:57,402 INFO zuul.Scheduler: Reconfiguration complete (smart: False, tenants: ['internal'], duration: 6.618 seconds)
2026-06-10 10:59:57,403 DEBUG zuul.Scheduler: [e: c153ce2a8d634190b44b448428eff115] Reconfiguration complete
2026-06-10 10:59:57,404 DEBUG zuul.Scheduler.LayoutUpdate: Aquired demo-tenant read tenant lock
2026-06-10 10:59:57,405 DEBUG zuul.Scheduler.LayoutUpdate: Released demo-tenant read tenant lock
2026-06-10 10:59:57,420 DEBUG zuul.Scheduler: Processing tenant management events in internal
2026-06-10 10:59:57,467 DEBUG zuul.Scheduler.LayoutUpdate: Aquired internal read tenant lock
2026-06-10 10:59:57,469 DEBUG zuul.Scheduler.LayoutUpdate: Released internal read tenant lock
2026-06-10 10:59:57,476 DEBUG zuul.Scheduler: Aquired internal read tenant lock
2026-06-10 10:59:57,484 DEBUG zuul.Scheduler: Processing tenant trigger events in internal
2026-06-10 10:59:57,507 DEBUG zuul.Scheduler: Processing pipeline check in tenant internal
2026-06-10 10:59:57,523 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f4cb0339390>
2026-06-10 10:59:57,533 DEBUG zuul.Pipeline.internal.check: Starting queue processor: check
2026-06-10 10:59:57,534 DEBUG zuul.Pipeline.internal.check: Finished queue processor: check (changed: False)
2026-06-10 10:59:57,546 DEBUG zuul.Scheduler: Processing pipeline gate in tenant internal
2026-06-10 10:59:57,568 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f4cb033a510>
2026-06-10 10:59:57,574 DEBUG zuul.Pipeline.internal.gate: Starting queue processor: gate
2026-06-10 10:59:57,574 DEBUG zuul.Pipeline.internal.gate: Finished queue processor: gate (changed: False)
2026-06-10 10:59:57,584 DEBUG zuul.Scheduler: Processing pipeline post in tenant internal
2026-06-10 10:59:57,596 DEBUG zuul.Scheduler: Processing management event <zuul.model.PipelinePostConfigEvent object at 0x7f4cb033b350>
2026-06-10 10:59:57,603 DEBUG zuul.Pipeline.internal.post: Starting queue processor: post
2026-06-10 10:59:57,603 DEBUG zuul.Pipeline.internal.post: Finished queue processor: post (changed: False)
2026-06-10 10:59:57,672 DEBUG zuul.Scheduler: Released internal read tenant lock
2026-06-10 10:59:57,680 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant
2026-06-10 10:59:57,692 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:59:57,701 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant
2026-06-10 10:59:57,720 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant
2026-06-10 10:59:57,724 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant
2026-06-10 10:59:57,730 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant
2026-06-10 10:59:57,734 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant
2026-06-10 10:59:57,739 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant
2026-06-10 10:59:57,743 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant
2026-06-10 10:59:57,745 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:59:57,747 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 10:59:57,747 DEBUG zuul.Scheduler: Run handler awake
2026-06-10 10:59:57,759 DEBUG zuul.Scheduler: Processing tenant management events in internal
2026-06-10 10:59:57,767 DEBUG zuul.Scheduler: Aquired internal read tenant lock
2026-06-10 10:59:57,775 DEBUG zuul.Scheduler: Processing tenant trigger events in internal
2026-06-10 10:59:57,794 DEBUG zuul.Scheduler: Processing pipeline check in tenant internal
2026-06-10 10:59:57,798 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant internal
2026-06-10 10:59:57,803 DEBUG zuul.Scheduler: Processing pipeline gate in tenant internal
2026-06-10 10:59:57,807 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant internal
2026-06-10 10:59:57,811 DEBUG zuul.Scheduler: Processing pipeline post in tenant internal
2026-06-10 10:59:57,812 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant internal
2026-06-10 10:59:57,814 DEBUG zuul.Scheduler: Released internal read tenant lock
2026-06-10 10:59:57,824 DEBUG zuul.Scheduler: Processing tenant management events in demo-tenant
2026-06-10 10:59:57,833 DEBUG zuul.Scheduler: Aquired demo-tenant read tenant lock
2026-06-10 10:59:57,839 DEBUG zuul.Scheduler: Processing tenant trigger events in demo-tenant
2026-06-10 10:59:57,851 DEBUG zuul.Scheduler: Processing pipeline check in tenant demo-tenant
2026-06-10 10:59:57,853 DEBUG zuul.Scheduler: No events to process for pipeline check in tenant demo-tenant
2026-06-10 10:59:57,856 DEBUG zuul.Scheduler: Processing pipeline gate in tenant demo-tenant
2026-06-10 10:59:57,858 DEBUG zuul.Scheduler: No events to process for pipeline gate in tenant demo-tenant
2026-06-10 10:59:57,862 DEBUG zuul.Scheduler: Processing pipeline post in tenant demo-tenant
2026-06-10 10:59:57,863 DEBUG zuul.Scheduler: No events to process for pipeline post in tenant demo-tenant
2026-06-10 10:59:57,865 DEBUG zuul.Scheduler: Released demo-tenant read tenant lock
2026-06-10 10:59:57,867 DEBUG zuul.Scheduler: Run handler sleeping
2026-06-10 11:00:39,663 DEBUG zuul.Scheduler: Starting merge request cleanup
2026-06-10 11:00:39,663 DEBUG zuul.Scheduler: Finished merge request cleanup
2026-06-10 11:00:42,154 DEBUG zuul.Scheduler: Starting build request cleanup
2026-06-10 11:00:42,155 DEBUG zuul.Scheduler: Finished build request cleanup