{"level":"warn","ts":"2026-07-17T15:57:55.414387Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T15:57:55.414516Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.114:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.114:2380","--initial-cluster=instance=https://199.19.213.114:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.114:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.114:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-17T15:57:55.4146Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T15:57:55.414613Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.114:2380"]} {"level":"info","ts":"2026-07-17T15:57:55.414636Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T15:57:55.415355Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.114:2379"]} {"level":"info","ts":"2026-07-17T15:57:55.415437Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.114:2380"],"listen-peer-urls":["https://199.19.213.114:2380"],"advertise-client-urls":["https://199.19.213.114:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.114:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.114:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-17T15:57:55.419271Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.5721ms"} {"level":"info","ts":"2026-07-17T15:57:55.425875Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e1d03a09d7253a9d","cluster-id":"bb9e539fe2ac80b2"} {"level":"info","ts":"2026-07-17T15:57:55.426009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T15:57:55.426131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became follower at term 0"} {"level":"info","ts":"2026-07-17T15:57:55.42617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e1d03a09d7253a9d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T15:57:55.426194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became follower at term 1"} {"level":"info","ts":"2026-07-17T15:57:55.42633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d switched to configuration voters=(16271569267628259997)"} {"level":"warn","ts":"2026-07-17T15:57:55.430695Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T15:57:55.433625Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T15:57:55.436456Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-17T15:57:55.439129Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e1d03a09d7253a9d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T15:57:55.439324Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e1d03a09d7253a9d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T15:57:55.439574Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T15:57:55.439826Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T15:57:55.439849Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T15:57:55.440585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d switched to configuration voters=(16271569267628259997)"} {"level":"info","ts":"2026-07-17T15:57:55.440743Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bb9e539fe2ac80b2","local-member-id":"e1d03a09d7253a9d","added-peer-id":"e1d03a09d7253a9d","added-peer-peer-urls":["https://199.19.213.114:2380"]} {"level":"info","ts":"2026-07-17T15:57:55.442359Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T15:57:55.442477Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.114:2380"} {"level":"info","ts":"2026-07-17T15:57:55.442547Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.114:2380"} {"level":"info","ts":"2026-07-17T15:57:55.442663Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e1d03a09d7253a9d","initial-advertise-peer-urls":["https://199.19.213.114:2380"],"listen-peer-urls":["https://199.19.213.114:2380"],"advertise-client-urls":["https://199.19.213.114:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.114:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T15:57:55.442703Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T15:57:55.927641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T15:57:55.927732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T15:57:55.927796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d received MsgPreVoteResp from e1d03a09d7253a9d at term 1"} {"level":"info","ts":"2026-07-17T15:57:55.927819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became candidate at term 2"} {"level":"info","ts":"2026-07-17T15:57:55.927831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d received MsgVoteResp from e1d03a09d7253a9d at term 2"} {"level":"info","ts":"2026-07-17T15:57:55.927848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became leader at term 2"} {"level":"info","ts":"2026-07-17T15:57:55.927926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e1d03a09d7253a9d elected leader e1d03a09d7253a9d at term 2"} {"level":"info","ts":"2026-07-17T15:57:55.929012Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:57:55.930399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T15:57:55.930401Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e1d03a09d7253a9d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.114:2379]}","request-path":"/0/members/e1d03a09d7253a9d/attributes","cluster-id":"bb9e539fe2ac80b2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T15:57:55.930476Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T15:57:55.93093Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T15:57:55.931046Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T15:57:55.931038Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bb9e539fe2ac80b2","local-member-id":"e1d03a09d7253a9d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:57:55.93128Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:57:55.931357Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:57:55.934042Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T15:57:55.934386Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.114:2379"} {"level":"warn","ts":"2026-07-17T15:58:19.722728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.854498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T15:58:19.722956Z","caller":"traceutil/trace.go:171","msg":"trace[554638362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"202.143033ms","start":"2026-07-17T15:58:19.520777Z","end":"2026-07-17T15:58:19.72292Z","steps":["trace[554638362] 'range keys from in-memory index tree' (duration: 201.713795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:46.755382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.553906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:58:46.755608Z","caller":"traceutil/trace.go:171","msg":"trace[565174302] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"221.788261ms","start":"2026-07-17T15:58:46.533773Z","end":"2026-07-17T15:58:46.755561Z","steps":["trace[565174302] 'process raft request' (duration: 60.337497ms)","trace[565174302] 'compare' (duration: 160.344971ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:47.300113Z","caller":"traceutil/trace.go:171","msg":"trace[869639142] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"439.356457ms","start":"2026-07-17T15:58:46.86071Z","end":"2026-07-17T15:58:47.300066Z","steps":["trace[869639142] 'process raft request' (duration: 400.502883ms)","trace[869639142] 'compare' (duration: 38.66668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:58:47.300993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:46.860675Z","time spent":"439.613872ms","remote":"127.0.0.1:60700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T15:58:47.303795Z","caller":"traceutil/trace.go:171","msg":"trace[914969181] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"145.178834ms","start":"2026-07-17T15:58:47.158586Z","end":"2026-07-17T15:58:47.303765Z","steps":["trace[914969181] 'process raft request' (duration: 144.484551ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:59:35.690932Z","caller":"traceutil/trace.go:171","msg":"trace[1451700633] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"111.396205ms","start":"2026-07-17T15:59:35.5795Z","end":"2026-07-17T15:59:35.690896Z","steps":["trace[1451700633] 'process raft request' (duration: 111.13576ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:26.110528Z","caller":"traceutil/trace.go:171","msg":"trace[19232338] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"149.395528ms","start":"2026-07-17T16:00:25.961106Z","end":"2026-07-17T16:00:26.110502Z","steps":["trace[19232338] 'read index received' (duration: 149.160003ms)","trace[19232338] 'applied index is now lower than readState.Index' (duration: 234.125µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:00:26.110589Z","caller":"traceutil/trace.go:171","msg":"trace[1470051402] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"258.453198ms","start":"2026-07-17T16:00:25.85211Z","end":"2026-07-17T16:00:26.110563Z","steps":["trace[1470051402] 'process raft request' (duration: 258.227994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:26.110761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.632882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T16:00:26.1108Z","caller":"traceutil/trace.go:171","msg":"trace[69264501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1924; }","duration":"149.717083ms","start":"2026-07-17T16:00:25.961074Z","end":"2026-07-17T16:00:26.110791Z","steps":["trace[69264501] 'agreement among raft nodes before linearized reading' (duration: 149.53824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:26.436964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.075876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T16:00:26.437135Z","caller":"traceutil/trace.go:171","msg":"trace[1834612610] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1925; }","duration":"225.33562ms","start":"2026-07-17T16:00:26.211768Z","end":"2026-07-17T16:00:26.437103Z","steps":["trace[1834612610] 'count revisions from in-memory index tree' (duration: 224.814991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:26.437118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.260551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T16:00:26.437217Z","caller":"traceutil/trace.go:171","msg":"trace[1158854269] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:1925; }","duration":"114.429294ms","start":"2026-07-17T16:00:26.322766Z","end":"2026-07-17T16:00:26.437195Z","steps":["trace[1158854269] 'count revisions from in-memory index tree' (duration: 114.137538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:26.437384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.9191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-17T16:00:26.437614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.767779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T16:00:26.437705Z","caller":"traceutil/trace.go:171","msg":"trace[1907844904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1925; }","duration":"211.903371ms","start":"2026-07-17T16:00:26.225776Z","end":"2026-07-17T16:00:26.43768Z","steps":["trace[1907844904] 'range keys from in-memory index tree' (duration: 211.29531ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:26.437715Z","caller":"traceutil/trace.go:171","msg":"trace[1428304054] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1925; }","duration":"188.284416ms","start":"2026-07-17T16:00:26.24939Z","end":"2026-07-17T16:00:26.437674Z","steps":["trace[1428304054] 'range keys from in-memory index tree' (duration: 187.709035ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:42.748354Z","caller":"traceutil/trace.go:171","msg":"trace[1474932480] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"110.447457ms","start":"2026-07-17T16:00:42.637831Z","end":"2026-07-17T16:00:42.748279Z","steps":["trace[1474932480] 'process raft request' (duration: 47.503225ms)","trace[1474932480] 'compare' (duration: 62.653166ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:00:46.28604Z","caller":"traceutil/trace.go:171","msg":"trace[1344832605] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"102.83975ms","start":"2026-07-17T16:00:46.183144Z","end":"2026-07-17T16:00:46.285983Z","steps":["trace[1344832605] 'process raft request' (duration: 102.526184ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:01:19.11725Z","caller":"traceutil/trace.go:171","msg":"trace[340523150] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"129.733462ms","start":"2026-07-17T16:01:18.987486Z","end":"2026-07-17T16:01:19.117219Z","steps":["trace[340523150] 'process raft request' (duration: 129.546179ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:01:24.603729Z","caller":"traceutil/trace.go:171","msg":"trace[567894788] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"127.595003ms","start":"2026-07-17T16:01:24.476097Z","end":"2026-07-17T16:01:24.603692Z","steps":["trace[567894788] 'process raft request' (duration: 127.396698ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:01:25.029696Z","caller":"traceutil/trace.go:171","msg":"trace[421201967] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"178.601842ms","start":"2026-07-17T16:01:24.851062Z","end":"2026-07-17T16:01:25.029664Z","steps":["trace[421201967] 'process raft request' (duration: 178.401849ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:01:25.102203Z","caller":"traceutil/trace.go:171","msg":"trace[1610962574] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"249.506055ms","start":"2026-07-17T16:01:24.852646Z","end":"2026-07-17T16:01:25.102152Z","steps":["trace[1610962574] 'process raft request' (duration: 249.26742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:25.475565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.939652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T16:01:25.475662Z","caller":"traceutil/trace.go:171","msg":"trace[825176496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2294; }","duration":"329.090504ms","start":"2026-07-17T16:01:25.146551Z","end":"2026-07-17T16:01:25.475641Z","steps":["trace[825176496] 'range keys from in-memory index tree' (duration: 328.761668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:25.475707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:25.14653Z","time spent":"329.166816ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T16:01:25.475791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.712981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:01:25.475906Z","caller":"traceutil/trace.go:171","msg":"trace[36909010] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"304.650725ms","start":"2026-07-17T16:01:25.171236Z","end":"2026-07-17T16:01:25.475886Z","steps":["trace[36909010] 'process raft request' (duration: 32.74934ms)","trace[36909010] 'compare' (duration: 271.364335ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:01:25.47597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:25.171204Z","time spent":"304.737126ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:02:09.385001Z","caller":"traceutil/trace.go:171","msg":"trace[835748061] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"104.3084ms","start":"2026-07-17T16:02:09.280641Z","end":"2026-07-17T16:02:09.384949Z","steps":["trace[835748061] 'process raft request' (duration: 104.014465ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:02:29.951325Z","caller":"traceutil/trace.go:171","msg":"trace[1050866252] transaction","detail":"{read_only:false; response_revision:3039; number_of_response:1; }","duration":"102.338693ms","start":"2026-07-17T16:02:29.848954Z","end":"2026-07-17T16:02:29.951292Z","steps":["trace[1050866252] 'process raft request' (duration: 102.16926ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:02:29.96057Z","caller":"traceutil/trace.go:171","msg":"trace[1734461869] transaction","detail":"{read_only:false; response_revision:3040; number_of_response:1; }","duration":"111.468908ms","start":"2026-07-17T16:02:29.849057Z","end":"2026-07-17T16:02:29.960525Z","steps":["trace[1734461869] 'process raft request' (duration: 111.152872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:02:52.54733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.086142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:02:52.547478Z","caller":"traceutil/trace.go:171","msg":"trace[610505617] transaction","detail":"{read_only:false; response_revision:3250; number_of_response:1; }","duration":"477.432217ms","start":"2026-07-17T16:02:52.070016Z","end":"2026-07-17T16:02:52.547449Z","steps":["trace[610505617] 'process raft request' (duration: 258.1145ms)","trace[610505617] 'compare' (duration: 218.928569ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:02:52.547539Z","caller":"traceutil/trace.go:171","msg":"trace[1347952346] transaction","detail":"{read_only:false; response_revision:3251; number_of_response:1; }","duration":"259.080829ms","start":"2026-07-17T16:02:52.28844Z","end":"2026-07-17T16:02:52.547521Z","steps":["trace[1347952346] 'process raft request' (duration: 259.015998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:02:52.547632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:02:52.069992Z","time spent":"477.555629ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:02:52.547752Z","caller":"traceutil/trace.go:171","msg":"trace[594511124] linearizableReadLoop","detail":"{readStateIndex:3372; appliedIndex:3371; }","duration":"432.634288ms","start":"2026-07-17T16:02:52.115077Z","end":"2026-07-17T16:02:52.547712Z","steps":["trace[594511124] 'read index received' (duration: 213.125077ms)","trace[594511124] 'applied index is now lower than readState.Index' (duration: 219.50517ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:02:52.548186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.089006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-17T16:02:52.54826Z","caller":"traceutil/trace.go:171","msg":"trace[491036688] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3251; }","duration":"433.224509ms","start":"2026-07-17T16:02:52.115019Z","end":"2026-07-17T16:02:52.548243Z","steps":["trace[491036688] 'agreement among raft nodes before linearized reading' (duration: 432.892052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:02:52.548313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:02:52.114995Z","time spent":"433.30524ms","remote":"127.0.0.1:60700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-17T16:02:52.548465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.588593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T16:02:52.548534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.861537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:7482"} {"level":"warn","ts":"2026-07-17T16:02:52.548544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.965548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c11ac61-dfd0-40f9-846c-40d8946b5dda\" ","response":"range_response_count:1 size:3599"} {"level":"info","ts":"2026-07-17T16:02:52.54858Z","caller":"traceutil/trace.go:171","msg":"trace[1954577956] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:3251; }","duration":"350.745366ms","start":"2026-07-17T16:02:52.1978Z","end":"2026-07-17T16:02:52.548546Z","steps":["trace[1954577956] 'agreement among raft nodes before linearized reading' (duration: 350.464971ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:02:52.548591Z","caller":"traceutil/trace.go:171","msg":"trace[1599002811] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:3251; }","duration":"389.970909ms","start":"2026-07-17T16:02:52.158604Z","end":"2026-07-17T16:02:52.548574Z","steps":["trace[1599002811] 'agreement among raft nodes before linearized reading' (duration: 389.529711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:02:52.548629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:02:52.19778Z","time spent":"350.837698ms","remote":"127.0.0.1:42662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"info","ts":"2026-07-17T16:02:52.548625Z","caller":"traceutil/trace.go:171","msg":"trace[1227978571] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c11ac61-dfd0-40f9-846c-40d8946b5dda; range_end:; response_count:1; response_revision:3251; }","duration":"306.09961ms","start":"2026-07-17T16:02:52.242506Z","end":"2026-07-17T16:02:52.548606Z","steps":["trace[1227978571] 'agreement among raft nodes before linearized reading' (duration: 305.757204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:02:52.548645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:02:52.158577Z","time spent":"390.05918ms","remote":"127.0.0.1:48612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7506,"request content":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" "} {"level":"warn","ts":"2026-07-17T16:02:52.548673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:02:52.242478Z","time spent":"306.185922ms","remote":"127.0.0.1:60700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c11ac61-dfd0-40f9-846c-40d8946b5dda\" "} {"level":"warn","ts":"2026-07-17T16:02:52.90009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.828073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:02:52.90026Z","caller":"traceutil/trace.go:171","msg":"trace[1543371438] transaction","detail":"{read_only:false; response_revision:3254; number_of_response:1; }","duration":"125.079329ms","start":"2026-07-17T16:02:52.775154Z","end":"2026-07-17T16:02:52.900234Z","steps":["trace[1543371438] 'process raft request' (duration: 23.984801ms)","trace[1543371438] 'compare' (duration: 100.67059ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:03:29.181382Z","caller":"traceutil/trace.go:171","msg":"trace[1569551451] transaction","detail":"{read_only:false; response_revision:3604; number_of_response:1; }","duration":"130.39778ms","start":"2026-07-17T16:03:29.050931Z","end":"2026-07-17T16:03:29.181329Z","steps":["trace[1569551451] 'process raft request' (duration: 124.918945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:04:43.325093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.079321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T16:04:43.325173Z","caller":"traceutil/trace.go:171","msg":"trace[1778053433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4264; }","duration":"172.174823ms","start":"2026-07-17T16:04:43.152983Z","end":"2026-07-17T16:04:43.325158Z","steps":["trace[1778053433] 'range keys from in-memory index tree' (duration: 171.859966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:04:43.325595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.056674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/k8s.keycloak.org/keycloaks/auth-system/keycloak\" ","response":"range_response_count:1 size:2722"} {"level":"info","ts":"2026-07-17T16:04:43.325625Z","caller":"traceutil/trace.go:171","msg":"trace[2076758119] range","detail":"{range_begin:/registry/k8s.keycloak.org/keycloaks/auth-system/keycloak; range_end:; response_count:1; response_revision:4264; }","duration":"130.125866ms","start":"2026-07-17T16:04:43.195491Z","end":"2026-07-17T16:04:43.325617Z","steps":["trace[2076758119] 'range keys from in-memory index tree' (duration: 129.946552ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:07:48.857243Z","caller":"traceutil/trace.go:171","msg":"trace[1324578568] transaction","detail":"{read_only:false; response_revision:6231; number_of_response:1; }","duration":"105.844805ms","start":"2026-07-17T16:07:48.751366Z","end":"2026-07-17T16:07:48.857211Z","steps":["trace[1324578568] 'process raft request' (duration: 105.623051ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:07:48.983841Z","caller":"traceutil/trace.go:171","msg":"trace[197000396] linearizableReadLoop","detail":"{readStateIndex:6521; appliedIndex:6519; }","duration":"107.894873ms","start":"2026-07-17T16:07:48.875915Z","end":"2026-07-17T16:07:48.98381Z","steps":["trace[197000396] 'read index received' (duration: 24.922067ms)","trace[197000396] 'applied index is now lower than readState.Index' (duration: 82.971736ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:07:48.983965Z","caller":"traceutil/trace.go:171","msg":"trace[1214109446] transaction","detail":"{read_only:false; response_revision:6234; number_of_response:1; }","duration":"114.560321ms","start":"2026-07-17T16:07:48.869359Z","end":"2026-07-17T16:07:48.983919Z","steps":["trace[1214109446] 'process raft request' (duration: 98.322572ms)","trace[1214109446] 'compare' (duration: 15.971914ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:07:48.985177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.231619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2209851"} {"level":"info","ts":"2026-07-17T16:07:48.985244Z","caller":"traceutil/trace.go:171","msg":"trace[1340517053] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:6234; }","duration":"109.331591ms","start":"2026-07-17T16:07:48.875895Z","end":"2026-07-17T16:07:48.985226Z","steps":["trace[1340517053] 'agreement among raft nodes before linearized reading' (duration: 108.044766ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:07:55.044745Z","caller":"traceutil/trace.go:171","msg":"trace[1003064185] transaction","detail":"{read_only:false; response_revision:6466; number_of_response:1; }","duration":"161.838676ms","start":"2026-07-17T16:07:54.882882Z","end":"2026-07-17T16:07:55.044721Z","steps":["trace[1003064185] 'process raft request' (duration: 161.643532ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:07:55.178423Z","caller":"traceutil/trace.go:171","msg":"trace[1103176746] transaction","detail":"{read_only:false; response_revision:6467; number_of_response:1; }","duration":"275.545021ms","start":"2026-07-17T16:07:54.902827Z","end":"2026-07-17T16:07:55.178372Z","steps":["trace[1103176746] 'process raft request' (duration: 188.554768ms)","trace[1103176746] 'compare' (duration: 86.711038ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:07:55.178462Z","caller":"traceutil/trace.go:171","msg":"trace[965055549] transaction","detail":"{read_only:false; response_revision:6468; number_of_response:1; }","duration":"242.516439ms","start":"2026-07-17T16:07:54.935912Z","end":"2026-07-17T16:07:55.178428Z","steps":["trace[965055549] 'process raft request' (duration: 242.379197ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:07:55.17892Z","caller":"traceutil/trace.go:171","msg":"trace[1233476736] transaction","detail":"{read_only:false; response_revision:6471; number_of_response:1; }","duration":"100.945701ms","start":"2026-07-17T16:07:55.077951Z","end":"2026-07-17T16:07:55.178896Z","steps":["trace[1233476736] 'process raft request' (duration: 100.90347ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:07:55.179005Z","caller":"traceutil/trace.go:171","msg":"trace[1013875447] transaction","detail":"{read_only:false; response_revision:6470; number_of_response:1; }","duration":"136.885809ms","start":"2026-07-17T16:07:55.042094Z","end":"2026-07-17T16:07:55.17898Z","steps":["trace[1013875447] 'process raft request' (duration: 136.615334ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:07:55.179043Z","caller":"traceutil/trace.go:171","msg":"trace[49535109] transaction","detail":"{read_only:false; response_revision:6469; number_of_response:1; }","duration":"197.346965ms","start":"2026-07-17T16:07:54.981673Z","end":"2026-07-17T16:07:55.17902Z","steps":["trace[49535109] 'process raft request' (duration: 196.712163ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:07:55.179106Z","caller":"traceutil/trace.go:171","msg":"trace[1733364329] linearizableReadLoop","detail":"{readStateIndex:6765; appliedIndex:6761; }","duration":"157.223598ms","start":"2026-07-17T16:07:55.021864Z","end":"2026-07-17T16:07:55.179087Z","steps":["trace[1733364329] 'read index received' (duration: 22.640543ms)","trace[1733364329] 'applied index is now lower than readState.Index' (duration: 134.509923ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:07:55.179295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.423751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44615938-27c5-44c1-b427-4a84982bdcfb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-17T16:07:55.179333Z","caller":"traceutil/trace.go:171","msg":"trace[146151901] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44615938-27c5-44c1-b427-4a84982bdcfb; range_end:; response_count:1; response_revision:6471; }","duration":"157.528153ms","start":"2026-07-17T16:07:55.021792Z","end":"2026-07-17T16:07:55.17932Z","steps":["trace[146151901] 'agreement among raft nodes before linearized reading' (duration: 157.38717ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:07:55.608306Z","caller":"traceutil/trace.go:171","msg":"trace[1614132553] transaction","detail":"{read_only:false; response_revision:6475; number_of_response:1; }","duration":"232.934465ms","start":"2026-07-17T16:07:55.375331Z","end":"2026-07-17T16:07:55.608266Z","steps":["trace[1614132553] 'process raft request' (duration: 176.792072ms)","trace[1614132553] 'compare' (duration: 55.366409ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:07:55.608466Z","caller":"traceutil/trace.go:171","msg":"trace[2084890057] linearizableReadLoop","detail":"{readStateIndex:6771; appliedIndex:6770; }","duration":"226.169316ms","start":"2026-07-17T16:07:55.382083Z","end":"2026-07-17T16:07:55.608253Z","steps":["trace[2084890057] 'read index received' (duration: 170.065653ms)","trace[2084890057] 'applied index is now lower than readState.Index' (duration: 56.101533ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:07:55.608609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.503682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-17T16:07:55.608725Z","caller":"traceutil/trace.go:171","msg":"trace[959366028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6475; }","duration":"226.664136ms","start":"2026-07-17T16:07:55.382033Z","end":"2026-07-17T16:07:55.608697Z","steps":["trace[959366028] 'agreement among raft nodes before linearized reading' (duration: 226.35509ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:07:55.954415Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3279} {"level":"info","ts":"2026-07-17T16:07:56.050131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3279,"took":"93.56706ms","hash":3939780356,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":32452608,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T16:07:56.050208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3939780356,"revision":3279,"compact-revision":-1} {"level":"info","ts":"2026-07-17T16:08:02.556704Z","caller":"traceutil/trace.go:171","msg":"trace[2140027605] transaction","detail":"{read_only:false; response_revision:6651; number_of_response:1; }","duration":"146.535013ms","start":"2026-07-17T16:08:02.410135Z","end":"2026-07-17T16:08:02.55667Z","steps":["trace[2140027605] 'process raft request' (duration: 146.314829ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:08:02.612894Z","caller":"traceutil/trace.go:171","msg":"trace[1297610151] transaction","detail":"{read_only:false; response_revision:6652; number_of_response:1; }","duration":"202.674857ms","start":"2026-07-17T16:08:02.410178Z","end":"2026-07-17T16:08:02.612853Z","steps":["trace[1297610151] 'process raft request' (duration: 202.255379ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:08:02.613313Z","caller":"traceutil/trace.go:171","msg":"trace[1903758525] transaction","detail":"{read_only:false; response_revision:6653; number_of_response:1; }","duration":"203.009834ms","start":"2026-07-17T16:08:02.410284Z","end":"2026-07-17T16:08:02.613294Z","steps":["trace[1903758525] 'process raft request' (duration: 202.496163ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:08:02.613329Z","caller":"traceutil/trace.go:171","msg":"trace[590978751] linearizableReadLoop","detail":"{readStateIndex:6956; appliedIndex:6952; }","duration":"177.634778ms","start":"2026-07-17T16:08:02.435677Z","end":"2026-07-17T16:08:02.613312Z","steps":["trace[590978751] 'read index received' (duration: 120.742349ms)","trace[590978751] 'applied index is now lower than readState.Index' (duration: 56.891229ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:08:02.613702Z","caller":"traceutil/trace.go:171","msg":"trace[427992542] transaction","detail":"{read_only:false; response_revision:6654; number_of_response:1; }","duration":"203.36553ms","start":"2026-07-17T16:08:02.410319Z","end":"2026-07-17T16:08:02.613684Z","steps":["trace[427992542] 'process raft request' (duration: 202.899751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:08:02.613674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.979284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-07-17T16:08:02.61378Z","caller":"traceutil/trace.go:171","msg":"trace[1244845433] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:6654; }","duration":"178.129667ms","start":"2026-07-17T16:08:02.435625Z","end":"2026-07-17T16:08:02.613755Z","steps":["trace[1244845433] 'agreement among raft nodes before linearized reading' (duration: 177.862252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:08:02.613812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.585777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-17T16:08:02.61388Z","caller":"traceutil/trace.go:171","msg":"trace[1562860292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6654; }","duration":"132.731879ms","start":"2026-07-17T16:08:02.481129Z","end":"2026-07-17T16:08:02.613861Z","steps":["trace[1562860292] 'agreement among raft nodes before linearized reading' (duration: 132.465745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:08:08.137633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.921624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:08:08.137771Z","caller":"traceutil/trace.go:171","msg":"trace[1572688825] linearizableReadLoop","detail":"{readStateIndex:7039; appliedIndex:7038; }","duration":"236.982153ms","start":"2026-07-17T16:08:07.900766Z","end":"2026-07-17T16:08:08.137748Z","steps":["trace[1572688825] 'read index received' (duration: 127.837396ms)","trace[1572688825] 'applied index is now lower than readState.Index' (duration: 109.143027ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:08:08.137833Z","caller":"traceutil/trace.go:171","msg":"trace[1649650111] transaction","detail":"{read_only:false; response_revision:6735; number_of_response:1; }","duration":"240.348658ms","start":"2026-07-17T16:08:07.897422Z","end":"2026-07-17T16:08:08.137771Z","steps":["trace[1649650111] 'process raft request' (duration: 131.161189ms)","trace[1649650111] 'compare' (duration: 108.566046ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:08:08.138078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.312959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128303"} {"level":"info","ts":"2026-07-17T16:08:08.138146Z","caller":"traceutil/trace.go:171","msg":"trace[1453316848] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:6735; }","duration":"237.439922ms","start":"2026-07-17T16:08:07.900693Z","end":"2026-07-17T16:08:08.138133Z","steps":["trace[1453316848] 'agreement among raft nodes before linearized reading' (duration: 237.174837ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:08:08.304856Z","caller":"traceutil/trace.go:171","msg":"trace[1294540098] transaction","detail":"{read_only:false; response_revision:6736; number_of_response:1; }","duration":"163.07687ms","start":"2026-07-17T16:08:08.141743Z","end":"2026-07-17T16:08:08.30482Z","steps":["trace[1294540098] 'process raft request' (duration: 139.358306ms)","trace[1294540098] 'compare' (duration: 23.52545ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:08:16.826576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.274553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-17T16:08:16.826701Z","caller":"traceutil/trace.go:171","msg":"trace[512936716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6942; }","duration":"117.489567ms","start":"2026-07-17T16:08:16.709184Z","end":"2026-07-17T16:08:16.826673Z","steps":["trace[512936716] 'range keys from in-memory index tree' (duration: 117.002857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:08:17.048469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.110808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7542"} {"level":"info","ts":"2026-07-17T16:08:17.048583Z","caller":"traceutil/trace.go:171","msg":"trace[1813091210] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:6943; }","duration":"119.73558ms","start":"2026-07-17T16:08:16.928821Z","end":"2026-07-17T16:08:17.048556Z","steps":["trace[1813091210] 'range keys from in-memory index tree' (duration: 118.819602ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:08:17.186633Z","caller":"traceutil/trace.go:171","msg":"trace[2118938385] linearizableReadLoop","detail":"{readStateIndex:7253; appliedIndex:7252; }","duration":"138.775245ms","start":"2026-07-17T16:08:17.04782Z","end":"2026-07-17T16:08:17.186595Z","steps":["trace[2118938385] 'read index received' (duration: 137.883218ms)","trace[2118938385] 'applied index is now lower than readState.Index' (duration: 889.537µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:08:17.186742Z","caller":"traceutil/trace.go:171","msg":"trace[1538887051] transaction","detail":"{read_only:false; response_revision:6944; number_of_response:1; }","duration":"190.575906ms","start":"2026-07-17T16:08:16.996119Z","end":"2026-07-17T16:08:17.186695Z","steps":["trace[1538887051] 'process raft request' (duration: 189.618438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:08:17.187241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.382046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:9044"} {"level":"info","ts":"2026-07-17T16:08:17.187352Z","caller":"traceutil/trace.go:171","msg":"trace[446156148] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:6944; }","duration":"139.5809ms","start":"2026-07-17T16:08:17.047744Z","end":"2026-07-17T16:08:17.187325Z","steps":["trace[446156148] 'agreement among raft nodes before linearized reading' (duration: 139.04099ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:08:17.370523Z","caller":"traceutil/trace.go:171","msg":"trace[738535881] transaction","detail":"{read_only:false; response_revision:6947; number_of_response:1; }","duration":"107.822583ms","start":"2026-07-17T16:08:17.262649Z","end":"2026-07-17T16:08:17.370471Z","steps":["trace[738535881] 'process raft request' (duration: 107.563127ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:08:17.370606Z","caller":"traceutil/trace.go:171","msg":"trace[1401060278] transaction","detail":"{read_only:false; response_revision:6948; number_of_response:1; }","duration":"107.822033ms","start":"2026-07-17T16:08:17.262729Z","end":"2026-07-17T16:08:17.370551Z","steps":["trace[1401060278] 'process raft request' (duration: 107.650539ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:09:06.722758Z","caller":"traceutil/trace.go:171","msg":"trace[1885964399] transaction","detail":"{read_only:false; response_revision:7450; number_of_response:1; }","duration":"101.572123ms","start":"2026-07-17T16:09:06.62114Z","end":"2026-07-17T16:09:06.722713Z","steps":["trace[1885964399] 'process raft request' (duration: 101.322098ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:11:04.163062Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021d4.wal"} {"level":"info","ts":"2026-07-17T16:12:55.961086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6484} {"level":"info","ts":"2026-07-17T16:12:56.063576Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6484,"took":"99.989459ms","hash":325264271,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":26759168,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-17T16:12:56.063666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":325264271,"revision":6484,"compact-revision":3279} {"level":"info","ts":"2026-07-17T16:13:37.572643Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e1d03a09d7253a9d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T16:13:37.578286Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-17T16:13:37.578469Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-07-17T16:14:22.248878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.650022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-17T16:14:22.248925Z","caller":"traceutil/trace.go:171","msg":"trace[274299078] transaction","detail":"{read_only:false; response_revision:10094; number_of_response:1; }","duration":"196.646673ms","start":"2026-07-17T16:14:22.05222Z","end":"2026-07-17T16:14:22.248866Z","steps":["trace[274299078] 'process raft request' (duration: 181.579095ms)","trace[274299078] 'compare' (duration: 14.857064ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:14:22.248991Z","caller":"traceutil/trace.go:171","msg":"trace[912670686] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10093; }","duration":"197.803845ms","start":"2026-07-17T16:14:22.051161Z","end":"2026-07-17T16:14:22.248965Z","steps":["trace[912670686] 'range keys from in-memory index tree' (duration: 197.494819ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:14:34.094739Z","caller":"traceutil/trace.go:171","msg":"trace[1772018236] linearizableReadLoop","detail":"{readStateIndex:10566; appliedIndex:10565; }","duration":"151.95251ms","start":"2026-07-17T16:14:33.94276Z","end":"2026-07-17T16:14:34.094713Z","steps":["trace[1772018236] 'read index received' (duration: 75.027632ms)","trace[1772018236] 'applied index is now lower than readState.Index' (duration: 76.923818ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:14:34.095015Z","caller":"traceutil/trace.go:171","msg":"trace[1844010612] transaction","detail":"{read_only:false; response_revision:10166; number_of_response:1; }","duration":"232.262402ms","start":"2026-07-17T16:14:33.86272Z","end":"2026-07-17T16:14:34.094982Z","steps":["trace[1844010612] 'process raft request' (duration: 155.047158ms)","trace[1844010612] 'compare' (duration: 76.831816ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:14:34.095051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.274977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-17T16:14:34.095133Z","caller":"traceutil/trace.go:171","msg":"trace[1073995111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10166; }","duration":"152.387049ms","start":"2026-07-17T16:14:33.942706Z","end":"2026-07-17T16:14:34.095093Z","steps":["trace[1073995111] 'agreement among raft nodes before linearized reading' (duration: 152.111153ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:14:34.870663Z","caller":"traceutil/trace.go:171","msg":"trace[1638105882] transaction","detail":"{read_only:false; response_revision:10169; number_of_response:1; }","duration":"132.249934ms","start":"2026-07-17T16:14:34.738385Z","end":"2026-07-17T16:14:34.870635Z","steps":["trace[1638105882] 'process raft request' (duration: 132.03933ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:14:34.87067Z","caller":"traceutil/trace.go:171","msg":"trace[733406665] linearizableReadLoop","detail":"{readStateIndex:10569; appliedIndex:10568; }","duration":"184.241785ms","start":"2026-07-17T16:14:34.686365Z","end":"2026-07-17T16:14:34.870607Z","steps":["trace[733406665] 'read index received' (duration: 128.480371ms)","trace[733406665] 'applied index is now lower than readState.Index' (duration: 55.751394ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:14:34.871003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.603663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:14:34.871066Z","caller":"traceutil/trace.go:171","msg":"trace[428777525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10170; }","duration":"184.771345ms","start":"2026-07-17T16:14:34.686282Z","end":"2026-07-17T16:14:34.871054Z","steps":["trace[428777525] 'agreement among raft nodes before linearized reading' (duration: 184.422488ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:15:04.531858Z","caller":"traceutil/trace.go:171","msg":"trace[1857856108] linearizableReadLoop","detail":"{readStateIndex:10823; appliedIndex:10822; }","duration":"354.446644ms","start":"2026-07-17T16:15:04.177375Z","end":"2026-07-17T16:15:04.531821Z","steps":["trace[1857856108] 'read index received' (duration: 264.341014ms)","trace[1857856108] 'applied index is now lower than readState.Index' (duration: 90.10336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:15:04.532081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.951045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T16:15:04.532122Z","caller":"traceutil/trace.go:171","msg":"trace[1577774922] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:10415; }","duration":"223.051386ms","start":"2026-07-17T16:15:04.30906Z","end":"2026-07-17T16:15:04.532112Z","steps":["trace[1577774922] 'agreement among raft nodes before linearized reading' (duration: 222.928144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:15:04.532082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.664349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/poolers/\" range_end:\"/registry/postgresql.cnpg.io/poolers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T16:15:04.532134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.523458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-17T16:15:04.532211Z","caller":"traceutil/trace.go:171","msg":"trace[1210250678] range","detail":"{range_begin:/registry/postgresql.cnpg.io/poolers/; range_end:/registry/postgresql.cnpg.io/poolers0; response_count:0; response_revision:10415; }","duration":"354.859562ms","start":"2026-07-17T16:15:04.17732Z","end":"2026-07-17T16:15:04.53218Z","steps":["trace[1210250678] 'agreement among raft nodes before linearized reading' (duration: 354.631367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:15:04.532293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:15:04.177297Z","time spent":"354.979054ms","remote":"127.0.0.1:48602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/postgresql.cnpg.io/poolers/\" range_end:\"/registry/postgresql.cnpg.io/poolers0\" count_only:true "} {"level":"warn","ts":"2026-07-17T16:15:04.532372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.786844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-17T16:15:04.532407Z","caller":"traceutil/trace.go:171","msg":"trace[236992040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10415; }","duration":"198.875906ms","start":"2026-07-17T16:15:04.333523Z","end":"2026-07-17T16:15:04.532399Z","steps":["trace[236992040] 'agreement among raft nodes before linearized reading' (duration: 198.756064ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:15:04.532222Z","caller":"traceutil/trace.go:171","msg":"trace[331237079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10415; }","duration":"198.629221ms","start":"2026-07-17T16:15:04.333574Z","end":"2026-07-17T16:15:04.532203Z","steps":["trace[331237079] 'agreement among raft nodes before linearized reading' (duration: 198.419707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:15:06.862501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.299196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:15:06.862614Z","caller":"traceutil/trace.go:171","msg":"trace[977753004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10428; }","duration":"175.466809ms","start":"2026-07-17T16:15:06.687122Z","end":"2026-07-17T16:15:06.862589Z","steps":["trace[977753004] 'range keys from in-memory index tree' (duration: 175.159612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:15:06.863134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.233504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-85cf8c784f-gb5qq.18c31fd044632560\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-07-17T16:15:06.863206Z","caller":"traceutil/trace.go:171","msg":"trace[503392320] range","detail":"{range_begin:/registry/events/openstack/barbican-api-85cf8c784f-gb5qq.18c31fd044632560; range_end:; response_count:1; response_revision:10428; }","duration":"131.334156ms","start":"2026-07-17T16:15:06.731852Z","end":"2026-07-17T16:15:06.863186Z","steps":["trace[503392320] 'range keys from in-memory index tree' (duration: 131.084732ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:15:06.976968Z","caller":"traceutil/trace.go:171","msg":"trace[1614505601] transaction","detail":"{read_only:false; response_revision:10429; number_of_response:1; }","duration":"110.940757ms","start":"2026-07-17T16:15:06.866001Z","end":"2026-07-17T16:15:06.976941Z","steps":["trace[1614505601] 'process raft request' (duration: 110.741553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:15:16.865196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.911209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:15:16.865397Z","caller":"traceutil/trace.go:171","msg":"trace[265612843] linearizableReadLoop","detail":"{readStateIndex:10950; appliedIndex:10948; }","duration":"179.798011ms","start":"2026-07-17T16:15:16.68558Z","end":"2026-07-17T16:15:16.865378Z","steps":["trace[265612843] 'read index received' (duration: 71.676368ms)","trace[265612843] 'applied index is now lower than readState.Index' (duration: 108.120423ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:15:16.865499Z","caller":"traceutil/trace.go:171","msg":"trace[983047447] transaction","detail":"{read_only:false; response_revision:10536; number_of_response:1; }","duration":"215.381ms","start":"2026-07-17T16:15:16.650108Z","end":"2026-07-17T16:15:16.865489Z","steps":["trace[983047447] 'process raft request' (duration: 215.203237ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:15:16.86554Z","caller":"traceutil/trace.go:171","msg":"trace[1053124786] transaction","detail":"{read_only:false; response_revision:10535; number_of_response:1; }","duration":"215.94329ms","start":"2026-07-17T16:15:16.64954Z","end":"2026-07-17T16:15:16.865483Z","steps":["trace[1053124786] 'process raft request' (duration: 107.639174ms)","trace[1053124786] 'compare' (duration: 107.760066ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:15:16.86568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.083936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:15:16.865787Z","caller":"traceutil/trace.go:171","msg":"trace[742809020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10536; }","duration":"180.237999ms","start":"2026-07-17T16:15:16.685523Z","end":"2026-07-17T16:15:16.865761Z","steps":["trace[742809020] 'agreement among raft nodes before linearized reading' (duration: 180.043146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:15:16.865956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.717661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-85cf8c784f-gb5qq.18c31fd044632560\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-07-17T16:15:16.866042Z","caller":"traceutil/trace.go:171","msg":"trace[1840708863] range","detail":"{range_begin:/registry/events/openstack/barbican-api-85cf8c784f-gb5qq.18c31fd044632560; range_end:; response_count:1; response_revision:10536; }","duration":"133.852994ms","start":"2026-07-17T16:15:16.732167Z","end":"2026-07-17T16:15:16.86602Z","steps":["trace[1840708863] 'agreement among raft nodes before linearized reading' (duration: 133.366345ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:15:17.664779Z","caller":"traceutil/trace.go:171","msg":"trace[1238983552] transaction","detail":"{read_only:false; response_revision:10545; number_of_response:1; }","duration":"112.826274ms","start":"2026-07-17T16:15:17.551914Z","end":"2026-07-17T16:15:17.66474Z","steps":["trace[1238983552] 'process raft request' (duration: 95.236397ms)","trace[1238983552] 'compare' (duration: 17.410314ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:16:20.193095Z","caller":"traceutil/trace.go:171","msg":"trace[2113112688] transaction","detail":"{read_only:false; response_revision:11274; number_of_response:1; }","duration":"101.317983ms","start":"2026-07-17T16:16:20.091742Z","end":"2026-07-17T16:16:20.19306Z","steps":["trace[2113112688] 'process raft request' (duration: 101.096749ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:16:32.467168Z","caller":"traceutil/trace.go:171","msg":"trace[1607930213] linearizableReadLoop","detail":"{readStateIndex:11791; appliedIndex:11790; }","duration":"182.655785ms","start":"2026-07-17T16:16:32.284488Z","end":"2026-07-17T16:16:32.467144Z","steps":["trace[1607930213] 'read index received' (duration: 182.233467ms)","trace[1607930213] 'applied index is now lower than readState.Index' (duration: 421.348µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:16:32.467188Z","caller":"traceutil/trace.go:171","msg":"trace[1763589201] transaction","detail":"{read_only:false; response_revision:11357; number_of_response:1; }","duration":"214.439052ms","start":"2026-07-17T16:16:32.252721Z","end":"2026-07-17T16:16:32.467161Z","steps":["trace[1763589201] 'process raft request' (duration: 214.234708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:16:32.467431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.881219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/publications/\" range_end:\"/registry/postgresql.cnpg.io/publications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:16:32.467483Z","caller":"traceutil/trace.go:171","msg":"trace[899154271] range","detail":"{range_begin:/registry/postgresql.cnpg.io/publications/; range_end:/registry/postgresql.cnpg.io/publications0; response_count:0; response_revision:11357; }","duration":"183.007032ms","start":"2026-07-17T16:16:32.284463Z","end":"2026-07-17T16:16:32.46747Z","steps":["trace[899154271] 'agreement among raft nodes before linearized reading' (duration: 182.778517ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:16:32.578747Z","caller":"traceutil/trace.go:171","msg":"trace[1747521852] transaction","detail":"{read_only:false; response_revision:11358; number_of_response:1; }","duration":"250.586021ms","start":"2026-07-17T16:16:32.328126Z","end":"2026-07-17T16:16:32.578712Z","steps":["trace[1747521852] 'process raft request' (duration: 237.825977ms)","trace[1747521852] 'compare' (duration: 12.618432ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:17:55.9676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9300} {"level":"info","ts":"2026-07-17T16:17:56.060106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9300,"took":"88.696333ms","hash":252681331,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":29974528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-17T16:17:56.060275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":252681331,"revision":9300,"compact-revision":6484} {"level":"info","ts":"2026-07-17T16:20:18.275027Z","caller":"traceutil/trace.go:171","msg":"trace[427984139] transaction","detail":"{read_only:false; response_revision:13442; number_of_response:1; }","duration":"205.913452ms","start":"2026-07-17T16:20:18.069084Z","end":"2026-07-17T16:20:18.274998Z","steps":["trace[427984139] 'process raft request' (duration: 205.718188ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:20:18.288048Z","caller":"traceutil/trace.go:171","msg":"trace[241178678] transaction","detail":"{read_only:false; response_revision:13444; number_of_response:1; }","duration":"187.616674ms","start":"2026-07-17T16:20:18.100404Z","end":"2026-07-17T16:20:18.288021Z","steps":["trace[241178678] 'process raft request' (duration: 187.535852ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:20:18.28821Z","caller":"traceutil/trace.go:171","msg":"trace[1335000382] transaction","detail":"{read_only:false; response_revision:13443; number_of_response:1; }","duration":"201.323946ms","start":"2026-07-17T16:20:18.08686Z","end":"2026-07-17T16:20:18.288183Z","steps":["trace[1335000382] 'process raft request' (duration: 200.947069ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:22:55.977462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12024} {"level":"info","ts":"2026-07-17T16:22:56.071481Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12024,"took":"91.40638ms","hash":3516671113,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":28622848,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-17T16:22:56.071558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3516671113,"revision":12024,"compact-revision":9300} {"level":"info","ts":"2026-07-17T16:26:11.903649Z","caller":"traceutil/trace.go:171","msg":"trace[1582509909] transaction","detail":"{read_only:false; response_revision:16214; number_of_response:1; }","duration":"129.479404ms","start":"2026-07-17T16:26:11.774141Z","end":"2026-07-17T16:26:11.90362Z","steps":["trace[1582509909] 'process raft request' (duration: 129.219129ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:26:30.352125Z","caller":"traceutil/trace.go:171","msg":"trace[1871455814] transaction","detail":"{read_only:false; response_revision:16324; number_of_response:1; }","duration":"109.610005ms","start":"2026-07-17T16:26:30.242487Z","end":"2026-07-17T16:26:30.352097Z","steps":["trace[1871455814] 'process raft request' (duration: 109.407442ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:26:30.445746Z","caller":"traceutil/trace.go:171","msg":"trace[2016447765] linearizableReadLoop","detail":"{readStateIndex:16903; appliedIndex:16901; }","duration":"166.947057ms","start":"2026-07-17T16:26:30.278778Z","end":"2026-07-17T16:26:30.445725Z","steps":["trace[2016447765] 'read index received' (duration: 73.083651ms)","trace[2016447765] 'applied index is now lower than readState.Index' (duration: 93.862596ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:26:30.445939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.154741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:9044"} {"level":"info","ts":"2026-07-17T16:26:30.445975Z","caller":"traceutil/trace.go:171","msg":"trace[102979366] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:16325; }","duration":"167.243693ms","start":"2026-07-17T16:26:30.27872Z","end":"2026-07-17T16:26:30.445963Z","steps":["trace[102979366] 'agreement among raft nodes before linearized reading' (duration: 167.0886ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:26:30.446208Z","caller":"traceutil/trace.go:171","msg":"trace[431965418] transaction","detail":"{read_only:false; response_revision:16325; number_of_response:1; }","duration":"202.965772ms","start":"2026-07-17T16:26:30.243235Z","end":"2026-07-17T16:26:30.4462Z","steps":["trace[431965418] 'process raft request' (duration: 201.596556ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:26:41.242445Z","caller":"traceutil/trace.go:171","msg":"trace[624696475] transaction","detail":"{read_only:false; response_revision:16391; number_of_response:1; }","duration":"122.002002ms","start":"2026-07-17T16:26:41.120417Z","end":"2026-07-17T16:26:41.242419Z","steps":["trace[624696475] 'process raft request' (duration: 121.828379ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:26:41.246979Z","caller":"traceutil/trace.go:171","msg":"trace[1257698741] transaction","detail":"{read_only:false; response_revision:16392; number_of_response:1; }","duration":"101.423419ms","start":"2026-07-17T16:26:41.145521Z","end":"2026-07-17T16:26:41.246945Z","steps":["trace[1257698741] 'process raft request' (duration: 101.190105ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:26:55.511681Z","caller":"traceutil/trace.go:171","msg":"trace[347990619] transaction","detail":"{read_only:false; response_revision:16608; number_of_response:1; }","duration":"224.91613ms","start":"2026-07-17T16:26:55.286731Z","end":"2026-07-17T16:26:55.511647Z","steps":["trace[347990619] 'process raft request' (duration: 224.563173ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:27:55.984271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14655} {"level":"info","ts":"2026-07-17T16:27:56.086125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14655,"took":"98.220888ms","hash":3381427668,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":32382976,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T16:27:56.08627Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3381427668,"revision":14655,"compact-revision":12024} {"level":"info","ts":"2026-07-17T16:28:20.512927Z","caller":"traceutil/trace.go:171","msg":"trace[703617583] linearizableReadLoop","detail":"{readStateIndex:18326; appliedIndex:18325; }","duration":"114.757131ms","start":"2026-07-17T16:28:20.398137Z","end":"2026-07-17T16:28:20.512894Z","steps":["trace[703617583] 'read index received' (duration: 114.533107ms)","trace[703617583] 'applied index is now lower than readState.Index' (duration: 222.854µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:28:20.513053Z","caller":"traceutil/trace.go:171","msg":"trace[2133140877] transaction","detail":"{read_only:false; response_revision:17709; number_of_response:1; }","duration":"116.694297ms","start":"2026-07-17T16:28:20.396324Z","end":"2026-07-17T16:28:20.513018Z","steps":["trace[2133140877] 'process raft request' (duration: 116.342551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:28:20.513969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.817632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764269"} {"level":"info","ts":"2026-07-17T16:28:20.514036Z","caller":"traceutil/trace.go:171","msg":"trace[1861172320] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:17709; }","duration":"115.901174ms","start":"2026-07-17T16:28:20.39812Z","end":"2026-07-17T16:28:20.514021Z","steps":["trace[1861172320] 'agreement among raft nodes before linearized reading' (duration: 114.887714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:28:20.738499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.850919ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:28:20.738626Z","caller":"traceutil/trace.go:171","msg":"trace[1168112848] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:17709; }","duration":"138.988642ms","start":"2026-07-17T16:28:20.599619Z","end":"2026-07-17T16:28:20.738607Z","steps":["trace[1168112848] 'range keys from in-memory index tree' (duration: 138.837159ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:31:23.725887Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e1d03a09d7253a9d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T16:31:23.729911Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-17T16:31:23.730025Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-17T16:32:55.989525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17549} {"level":"info","ts":"2026-07-17T16:32:56.101974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17549,"took":"109.098518ms","hash":47517971,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":32587776,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-17T16:32:56.102042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":47517971,"revision":17549,"compact-revision":14655} {"level":"info","ts":"2026-07-17T16:34:18.570204Z","caller":"traceutil/trace.go:171","msg":"trace[317010984] linearizableReadLoop","detail":"{readStateIndex:21488; appliedIndex:21487; }","duration":"100.571127ms","start":"2026-07-17T16:34:18.46961Z","end":"2026-07-17T16:34:18.570181Z","steps":["trace[317010984] 'read index received' (duration: 100.368723ms)","trace[317010984] 'applied index is now lower than readState.Index' (duration: 201.564µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:34:18.571013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.382712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:946183"} {"level":"info","ts":"2026-07-17T16:34:18.571058Z","caller":"traceutil/trace.go:171","msg":"trace[1747264820] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:20784; }","duration":"101.482574ms","start":"2026-07-17T16:34:18.469564Z","end":"2026-07-17T16:34:18.571046Z","steps":["trace[1747264820] 'agreement among raft nodes before linearized reading' (duration: 100.715159ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:34:18.571633Z","caller":"traceutil/trace.go:171","msg":"trace[2116385209] transaction","detail":"{read_only:false; response_revision:20784; number_of_response:1; }","duration":"103.505062ms","start":"2026-07-17T16:34:18.468088Z","end":"2026-07-17T16:34:18.571593Z","steps":["trace[2116385209] 'process raft request' (duration: 101.952183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:34:18.983473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.880613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:34:18.983533Z","caller":"traceutil/trace.go:171","msg":"trace[772869965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20786; }","duration":"213.992636ms","start":"2026-07-17T16:34:18.769527Z","end":"2026-07-17T16:34:18.98352Z","steps":["trace[772869965] 'range keys from in-memory index tree' (duration: 213.804742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:34:18.983568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.55155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3298"} {"level":"info","ts":"2026-07-17T16:34:18.983634Z","caller":"traceutil/trace.go:171","msg":"trace[2032093077] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:20786; }","duration":"163.655601ms","start":"2026-07-17T16:34:18.819963Z","end":"2026-07-17T16:34:18.983619Z","steps":["trace[2032093077] 'range keys from in-memory index tree' (duration: 163.416607ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:34:19.150371Z","caller":"traceutil/trace.go:171","msg":"trace[1978491023] linearizableReadLoop","detail":"{readStateIndex:21491; appliedIndex:21490; }","duration":"159.371141ms","start":"2026-07-17T16:34:18.990975Z","end":"2026-07-17T16:34:19.150346Z","steps":["trace[1978491023] 'read index received' (duration: 159.26735ms)","trace[1978491023] 'applied index is now lower than readState.Index' (duration: 102.651µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:34:19.150395Z","caller":"traceutil/trace.go:171","msg":"trace[1789815271] transaction","detail":"{read_only:false; response_revision:20787; number_of_response:1; }","duration":"162.652313ms","start":"2026-07-17T16:34:18.987719Z","end":"2026-07-17T16:34:19.150371Z","steps":["trace[1789815271] 'process raft request' (duration: 162.48415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:34:19.150557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.566695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-heat-nodes\" ","response":"range_response_count:1 size:1248"} {"level":"info","ts":"2026-07-17T16:34:19.150614Z","caller":"traceutil/trace.go:171","msg":"trace[1517477902] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-heat-nodes; range_end:; response_count:1; response_revision:20787; }","duration":"159.666717ms","start":"2026-07-17T16:34:18.990933Z","end":"2026-07-17T16:34:19.150599Z","steps":["trace[1517477902] 'agreement among raft nodes before linearized reading' (duration: 159.510784ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:34:19.295645Z","caller":"traceutil/trace.go:171","msg":"trace[1590621300] linearizableReadLoop","detail":"{readStateIndex:21493; appliedIndex:21491; }","duration":"145.180022ms","start":"2026-07-17T16:34:19.150443Z","end":"2026-07-17T16:34:19.295623Z","steps":["trace[1590621300] 'read index received' (duration: 108.570688ms)","trace[1590621300] 'applied index is now lower than readState.Index' (duration: 36.608414ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:34:19.295898Z","caller":"traceutil/trace.go:171","msg":"trace[2090762951] transaction","detail":"{read_only:false; response_revision:20788; number_of_response:1; }","duration":"224.7407ms","start":"2026-07-17T16:34:19.071144Z","end":"2026-07-17T16:34:19.295885Z","steps":["trace[2090762951] 'process raft request' (duration: 187.872531ms)","trace[2090762951] 'compare' (duration: 36.428191ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:34:19.296216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.877768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T16:34:19.296258Z","caller":"traceutil/trace.go:171","msg":"trace[1345912636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20789; }","duration":"181.972189ms","start":"2026-07-17T16:34:19.114277Z","end":"2026-07-17T16:34:19.296249Z","steps":["trace[1345912636] 'agreement among raft nodes before linearized reading' (duration: 181.830566ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:34:19.296072Z","caller":"traceutil/trace.go:171","msg":"trace[722770310] transaction","detail":"{read_only:false; response_revision:20789; number_of_response:1; }","duration":"160.87513ms","start":"2026-07-17T16:34:19.135184Z","end":"2026-07-17T16:34:19.29606Z","steps":["trace[722770310] 'process raft request' (duration: 160.388171ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:34:19.300648Z","caller":"traceutil/trace.go:171","msg":"trace[1542847894] transaction","detail":"{read_only:false; response_revision:20791; number_of_response:1; }","duration":"123.44233ms","start":"2026-07-17T16:34:19.177175Z","end":"2026-07-17T16:34:19.300618Z","steps":["trace[1542847894] 'process raft request' (duration: 123.393569ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:34:19.300836Z","caller":"traceutil/trace.go:171","msg":"trace[1838592048] transaction","detail":"{read_only:false; response_revision:20790; number_of_response:1; }","duration":"148.620606ms","start":"2026-07-17T16:34:19.152127Z","end":"2026-07-17T16:34:19.300748Z","steps":["trace[1838592048] 'process raft request' (duration: 148.307701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:34:19.361511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.354287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:34:19.361582Z","caller":"traceutil/trace.go:171","msg":"trace[1333131875] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:20792; }","duration":"162.45501ms","start":"2026-07-17T16:34:19.199109Z","end":"2026-07-17T16:34:19.361563Z","steps":["trace[1333131875] 'agreement among raft nodes before linearized reading' (duration: 162.328747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:34:19.3616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.670573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.114\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-17T16:34:19.361683Z","caller":"traceutil/trace.go:171","msg":"trace[1519932329] range","detail":"{range_begin:/registry/masterleases/199.19.213.114; range_end:; response_count:1; response_revision:20792; }","duration":"153.823756ms","start":"2026-07-17T16:34:19.207837Z","end":"2026-07-17T16:34:19.361661Z","steps":["trace[1519932329] 'agreement among raft nodes before linearized reading' (duration: 153.563651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:34:19.361842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.26973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:1436"} {"level":"info","ts":"2026-07-17T16:34:19.361966Z","caller":"traceutil/trace.go:171","msg":"trace[601923982] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:20792; }","duration":"205.411424ms","start":"2026-07-17T16:34:19.156524Z","end":"2026-07-17T16:34:19.361936Z","steps":["trace[601923982] 'agreement among raft nodes before linearized reading' (duration: 205.044427ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:34:19.469955Z","caller":"traceutil/trace.go:171","msg":"trace[432063114] linearizableReadLoop","detail":"{readStateIndex:21497; appliedIndex:21496; }","duration":"107.803974ms","start":"2026-07-17T16:34:19.36213Z","end":"2026-07-17T16:34:19.469934Z","steps":["trace[432063114] 'read index received' (duration: 107.500248ms)","trace[432063114] 'applied index is now lower than readState.Index' (duration: 302.916µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:34:19.47002Z","caller":"traceutil/trace.go:171","msg":"trace[1709773434] transaction","detail":"{read_only:false; response_revision:20793; number_of_response:1; }","duration":"169.357511ms","start":"2026-07-17T16:34:19.300613Z","end":"2026-07-17T16:34:19.46997Z","steps":["trace[1709773434] 'process raft request' (duration: 169.160397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:34:19.470077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.927708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:34:19.470153Z","caller":"traceutil/trace.go:171","msg":"trace[1279167109] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:20793; }","duration":"173.01455ms","start":"2026-07-17T16:34:19.297121Z","end":"2026-07-17T16:34:19.470135Z","steps":["trace[1279167109] 'agreement among raft nodes before linearized reading' (duration: 172.888547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:34:19.476599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.567838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9509"} {"level":"info","ts":"2026-07-17T16:34:19.476631Z","caller":"traceutil/trace.go:171","msg":"trace[845756835] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:20793; }","duration":"108.633899ms","start":"2026-07-17T16:34:19.367989Z","end":"2026-07-17T16:34:19.476623Z","steps":["trace[845756835] 'agreement among raft nodes before linearized reading' (duration: 108.524487ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:37:55.994919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20038} {"level":"info","ts":"2026-07-17T16:37:56.110229Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20038,"took":"111.337421ms","hash":3893938269,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":34766848,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-17T16:37:56.110286Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3893938269,"revision":20038,"compact-revision":17549} {"level":"info","ts":"2026-07-17T16:39:20.723563Z","caller":"traceutil/trace.go:171","msg":"trace[1373868501] transaction","detail":"{read_only:false; response_revision:23434; number_of_response:1; }","duration":"132.864798ms","start":"2026-07-17T16:39:20.590662Z","end":"2026-07-17T16:39:20.723527Z","steps":["trace[1373868501] 'process raft request' (duration: 132.635283ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:39:21.025724Z","caller":"traceutil/trace.go:171","msg":"trace[1566513433] linearizableReadLoop","detail":"{readStateIndex:24218; appliedIndex:24217; }","duration":"242.212091ms","start":"2026-07-17T16:39:20.783481Z","end":"2026-07-17T16:39:21.025693Z","steps":["trace[1566513433] 'read index received' (duration: 241.978427ms)","trace[1566513433] 'applied index is now lower than readState.Index' (duration: 232.874µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:39:21.025801Z","caller":"traceutil/trace.go:171","msg":"trace[88195191] transaction","detail":"{read_only:false; response_revision:23435; number_of_response:1; }","duration":"294.462392ms","start":"2026-07-17T16:39:20.731299Z","end":"2026-07-17T16:39:21.025761Z","steps":["trace[88195191] 'process raft request' (duration: 294.221757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:39:21.025888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.394885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:39:21.025992Z","caller":"traceutil/trace.go:171","msg":"trace[349552183] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23435; }","duration":"242.464107ms","start":"2026-07-17T16:39:20.783464Z","end":"2026-07-17T16:39:21.025928Z","steps":["trace[349552183] 'agreement among raft nodes before linearized reading' (duration: 242.366475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:39:21.026071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.855602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:9045"} {"level":"warn","ts":"2026-07-17T16:39:21.026122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.993875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:9045"} {"level":"info","ts":"2026-07-17T16:39:21.02616Z","caller":"traceutil/trace.go:171","msg":"trace[1728978879] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:23435; }","duration":"225.997135ms","start":"2026-07-17T16:39:20.80014Z","end":"2026-07-17T16:39:21.026137Z","steps":["trace[1728978879] 'agreement among raft nodes before linearized reading' (duration: 225.74184ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:39:21.026213Z","caller":"traceutil/trace.go:171","msg":"trace[1794635912] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:23435; }","duration":"225.120867ms","start":"2026-07-17T16:39:20.801065Z","end":"2026-07-17T16:39:21.026186Z","steps":["trace[1794635912] 'agreement among raft nodes before linearized reading' (duration: 224.901142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:39:21.026366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.98477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T16:39:21.026769Z","caller":"traceutil/trace.go:171","msg":"trace[746378935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23435; }","duration":"181.445809ms","start":"2026-07-17T16:39:20.845291Z","end":"2026-07-17T16:39:21.026736Z","steps":["trace[746378935] 'agreement among raft nodes before linearized reading' (duration: 180.680795ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:39:36.900407Z","caller":"traceutil/trace.go:171","msg":"trace[1919893486] transaction","detail":"{read_only:false; response_revision:23532; number_of_response:1; }","duration":"101.78489ms","start":"2026-07-17T16:39:36.798599Z","end":"2026-07-17T16:39:36.900384Z","steps":["trace[1919893486] 'process raft request' (duration: 101.623287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:39:37.235363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.080854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:9045"} {"level":"info","ts":"2026-07-17T16:39:37.23546Z","caller":"traceutil/trace.go:171","msg":"trace[1008305483] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:23532; }","duration":"189.187836ms","start":"2026-07-17T16:39:37.046252Z","end":"2026-07-17T16:39:37.235439Z","steps":["trace[1008305483] 'range keys from in-memory index tree' (duration: 188.88217ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:39:37.235737Z","caller":"traceutil/trace.go:171","msg":"trace[1857100996] linearizableReadLoop","detail":"{readStateIndex:24319; appliedIndex:24318; }","duration":"117.872124ms","start":"2026-07-17T16:39:37.117822Z","end":"2026-07-17T16:39:37.235694Z","steps":["trace[1857100996] 'read index received' (duration: 75.040093ms)","trace[1857100996] 'applied index is now lower than readState.Index' (duration: 42.830371ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:39:37.235807Z","caller":"traceutil/trace.go:171","msg":"trace[1103081623] transaction","detail":"{read_only:false; response_revision:23533; number_of_response:1; }","duration":"162.245856ms","start":"2026-07-17T16:39:37.073478Z","end":"2026-07-17T16:39:37.235724Z","steps":["trace[1103081623] 'process raft request' (duration: 119.378814ms)","trace[1103081623] 'compare' (duration: 42.504705ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:39:37.235948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.105869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T16:39:37.236021Z","caller":"traceutil/trace.go:171","msg":"trace[1968656702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23533; }","duration":"118.220292ms","start":"2026-07-17T16:39:37.117783Z","end":"2026-07-17T16:39:37.236003Z","steps":["trace[1968656702] 'agreement among raft nodes before linearized reading' (duration: 118.010947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:39:37.497661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.153388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:39:37.497842Z","caller":"traceutil/trace.go:171","msg":"trace[568367150] transaction","detail":"{read_only:false; response_revision:23535; number_of_response:1; }","duration":"256.636014ms","start":"2026-07-17T16:39:37.241186Z","end":"2026-07-17T16:39:37.497822Z","steps":["trace[568367150] 'process raft request' (duration: 256.581723ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:39:37.498009Z","caller":"traceutil/trace.go:171","msg":"trace[689481006] transaction","detail":"{read_only:false; response_revision:23534; number_of_response:1; }","duration":"257.627273ms","start":"2026-07-17T16:39:37.240355Z","end":"2026-07-17T16:39:37.497982Z","steps":["trace[689481006] 'process raft request' (duration: 137.029267ms)","trace[689481006] 'compare' (duration: 120.032536ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:39:37.769265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.945281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:39:37.769701Z","caller":"traceutil/trace.go:171","msg":"trace[162448775] transaction","detail":"{read_only:false; response_revision:23536; number_of_response:1; }","duration":"286.637433ms","start":"2026-07-17T16:39:37.483031Z","end":"2026-07-17T16:39:37.769669Z","steps":["trace[162448775] 'process raft request' (duration: 153.193233ms)","trace[162448775] 'compare' (duration: 132.834149ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:42:56.00129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22792} {"level":"info","ts":"2026-07-17T16:42:56.11303Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22792,"took":"107.673611ms","hash":3179481399,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":34562048,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-17T16:42:56.113104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3179481399,"revision":22792,"compact-revision":20038} {"level":"info","ts":"2026-07-17T16:43:13.827704Z","caller":"traceutil/trace.go:171","msg":"trace[1851848778] transaction","detail":"{read_only:false; response_revision:25984; number_of_response:1; }","duration":"103.629324ms","start":"2026-07-17T16:43:13.724044Z","end":"2026-07-17T16:43:13.827673Z","steps":["trace[1851848778] 'process raft request' (duration: 81.128578ms)","trace[1851848778] 'compare' (duration: 21.991397ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:43:13.83762Z","caller":"traceutil/trace.go:171","msg":"trace[1691470455] transaction","detail":"{read_only:false; response_revision:25985; number_of_response:1; }","duration":"104.407149ms","start":"2026-07-17T16:43:13.733181Z","end":"2026-07-17T16:43:13.837588Z","steps":["trace[1691470455] 'process raft request' (duration: 104.235246ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:43:14.914754Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068f1.wal"} {"level":"warn","ts":"2026-07-17T16:43:20.716442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.747474ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:43:20.716582Z","caller":"traceutil/trace.go:171","msg":"trace[244961559] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26144; }","duration":"116.902326ms","start":"2026-07-17T16:43:20.599662Z","end":"2026-07-17T16:43:20.716564Z","steps":["trace[244961559] 'range keys from in-memory index tree' (duration: 116.717202ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:43:20.728231Z","caller":"traceutil/trace.go:171","msg":"trace[2126168996] linearizableReadLoop","detail":"{readStateIndex:27001; appliedIndex:27000; }","duration":"210.819936ms","start":"2026-07-17T16:43:20.517393Z","end":"2026-07-17T16:43:20.728213Z","steps":["trace[2126168996] 'read index received' (duration: 210.764974ms)","trace[2126168996] 'applied index is now lower than readState.Index' (duration: 53.211µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:43:20.728378Z","caller":"traceutil/trace.go:171","msg":"trace[1783980216] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"248.441838ms","start":"2026-07-17T16:43:20.479877Z","end":"2026-07-17T16:43:20.728318Z","steps":["trace[1783980216] 'process raft request' (duration: 248.195374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:43:20.728848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.407928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-17T16:43:20.728974Z","caller":"traceutil/trace.go:171","msg":"trace[472541384] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26145; }","duration":"211.614432ms","start":"2026-07-17T16:43:20.517333Z","end":"2026-07-17T16:43:20.728948Z","steps":["trace[472541384] 'agreement among raft nodes before linearized reading' (duration: 211.213734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:43:20.729878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.121481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T16:43:20.729854Z","caller":"traceutil/trace.go:171","msg":"trace[1292591735] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"137.730861ms","start":"2026-07-17T16:43:20.592095Z","end":"2026-07-17T16:43:20.729826Z","steps":["trace[1292591735] 'process raft request' (duration: 137.554487ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:43:20.729934Z","caller":"traceutil/trace.go:171","msg":"trace[1909608525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26146; }","duration":"183.222682ms","start":"2026-07-17T16:43:20.546699Z","end":"2026-07-17T16:43:20.729921Z","steps":["trace[1909608525] 'agreement among raft nodes before linearized reading' (duration: 183.037419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:43:20.730027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.809615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-17T16:43:20.730131Z","caller":"traceutil/trace.go:171","msg":"trace[1229275862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26146; }","duration":"182.945888ms","start":"2026-07-17T16:43:20.547158Z","end":"2026-07-17T16:43:20.730104Z","steps":["trace[1229275862] 'agreement among raft nodes before linearized reading' (duration: 182.671683ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:01.766796Z","caller":"traceutil/trace.go:171","msg":"trace[529862150] transaction","detail":"{read_only:false; response_revision:28582; number_of_response:1; }","duration":"265.829259ms","start":"2026-07-17T16:47:01.500935Z","end":"2026-07-17T16:47:01.766764Z","steps":["trace[529862150] 'process raft request' (duration: 265.643976ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:01.870198Z","caller":"traceutil/trace.go:171","msg":"trace[621819780] transaction","detail":"{read_only:false; response_revision:28583; number_of_response:1; }","duration":"368.83823ms","start":"2026-07-17T16:47:01.501335Z","end":"2026-07-17T16:47:01.870173Z","steps":["trace[621819780] 'process raft request' (duration: 368.640437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:01.870414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:01.501313Z","time spent":"369.027015ms","remote":"127.0.0.1:60810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":924,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:47:01.870444Z","caller":"traceutil/trace.go:171","msg":"trace[158916326] transaction","detail":"{read_only:false; response_revision:28584; number_of_response:1; }","duration":"349.668549ms","start":"2026-07-17T16:47:01.520744Z","end":"2026-07-17T16:47:01.870412Z","steps":["trace[158916326] 'process raft request' (duration: 349.374323ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:01.870498Z","caller":"traceutil/trace.go:171","msg":"trace[434896756] linearizableReadLoop","detail":"{readStateIndex:29497; appliedIndex:29492; }","duration":"211.674242ms","start":"2026-07-17T16:47:01.658795Z","end":"2026-07-17T16:47:01.870469Z","steps":["trace[434896756] 'read index received' (duration: 107.827923ms)","trace[434896756] 'applied index is now lower than readState.Index' (duration: 103.845409ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:01.870565Z","caller":"traceutil/trace.go:171","msg":"trace[271732502] transaction","detail":"{read_only:false; response_revision:28585; number_of_response:1; }","duration":"341.86137ms","start":"2026-07-17T16:47:01.528682Z","end":"2026-07-17T16:47:01.870543Z","steps":["trace[271732502] 'process raft request' (duration: 341.666857ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:01.870597Z","caller":"traceutil/trace.go:171","msg":"trace[615677089] transaction","detail":"{read_only:false; response_revision:28586; number_of_response:1; }","duration":"300.803782ms","start":"2026-07-17T16:47:01.569782Z","end":"2026-07-17T16:47:01.870586Z","steps":["trace[615677089] 'process raft request' (duration: 300.643099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:01.870604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:01.520734Z","time spent":"349.786731ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:47:01.870672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:01.528654Z","time spent":"341.961322ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:47:01.870668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:01.569765Z","time spent":"300.858323ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:47:01.870724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.925407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-17T16:47:01.870772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.824204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:47:01.870789Z","caller":"traceutil/trace.go:171","msg":"trace[1100874969] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:28586; }","duration":"212.018398ms","start":"2026-07-17T16:47:01.658762Z","end":"2026-07-17T16:47:01.87078Z","steps":["trace[1100874969] 'agreement among raft nodes before linearized reading' (duration: 211.930766ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:01.870801Z","caller":"traceutil/trace.go:171","msg":"trace[1763693432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28586; }","duration":"184.884715ms","start":"2026-07-17T16:47:01.685905Z","end":"2026-07-17T16:47:01.87079Z","steps":["trace[1763693432] 'agreement among raft nodes before linearized reading' (duration: 184.848684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:01.87107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.883743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18c32196ccef9c21\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-07-17T16:47:01.871404Z","caller":"traceutil/trace.go:171","msg":"trace[1481621209] range","detail":"{range_begin:/registry/events/openstack/container-infra.18c32196ccef9c21; range_end:; response_count:1; response_revision:28586; }","duration":"101.24266ms","start":"2026-07-17T16:47:01.770144Z","end":"2026-07-17T16:47:01.871387Z","steps":["trace[1481621209] 'agreement among raft nodes before linearized reading' (duration: 100.834861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:05.266567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.361464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-17T16:47:05.266673Z","caller":"traceutil/trace.go:171","msg":"trace[1064073536] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28622; }","duration":"118.493157ms","start":"2026-07-17T16:47:05.148138Z","end":"2026-07-17T16:47:05.266631Z","steps":["trace[1064073536] 'range keys from in-memory index tree' (duration: 118.194272ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:05.387494Z","caller":"traceutil/trace.go:171","msg":"trace[395623449] transaction","detail":"{read_only:false; response_revision:28623; number_of_response:1; }","duration":"116.683462ms","start":"2026-07-17T16:47:05.270795Z","end":"2026-07-17T16:47:05.387478Z","steps":["trace[395623449] 'process raft request' (duration: 116.5784ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:06.052422Z","caller":"traceutil/trace.go:171","msg":"trace[161844911] transaction","detail":"{read_only:false; response_revision:28625; number_of_response:1; }","duration":"168.82721ms","start":"2026-07-17T16:47:05.883417Z","end":"2026-07-17T16:47:06.052244Z","steps":["trace[161844911] 'process raft request' (duration: 168.658677ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:06.214378Z","caller":"traceutil/trace.go:171","msg":"trace[2033420755] transaction","detail":"{read_only:false; response_revision:28626; number_of_response:1; }","duration":"330.066737ms","start":"2026-07-17T16:47:05.88428Z","end":"2026-07-17T16:47:06.214346Z","steps":["trace[2033420755] 'process raft request' (duration: 296.140084ms)","trace[2033420755] 'compare' (duration: 33.582036ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:06.2144Z","caller":"traceutil/trace.go:171","msg":"trace[1373551880] transaction","detail":"{read_only:false; response_revision:28627; number_of_response:1; }","duration":"272.060056ms","start":"2026-07-17T16:47:05.94232Z","end":"2026-07-17T16:47:06.21438Z","steps":["trace[1373551880] 'process raft request' (duration: 271.930844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:06.214526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:05.884274Z","time spent":"330.166238ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:47:06.214528Z","caller":"traceutil/trace.go:171","msg":"trace[2061510993] transaction","detail":"{read_only:false; response_revision:28628; number_of_response:1; }","duration":"271.828013ms","start":"2026-07-17T16:47:05.942671Z","end":"2026-07-17T16:47:06.214499Z","steps":["trace[2061510993] 'process raft request' (duration: 271.657839ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:06.21465Z","caller":"traceutil/trace.go:171","msg":"trace[1066884023] transaction","detail":"{read_only:false; response_revision:28629; number_of_response:1; }","duration":"204.970305ms","start":"2026-07-17T16:47:06.009664Z","end":"2026-07-17T16:47:06.214634Z","steps":["trace[1066884023] 'process raft request' (duration: 204.71537ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:06.214697Z","caller":"traceutil/trace.go:171","msg":"trace[309534177] linearizableReadLoop","detail":"{readStateIndex:29538; appliedIndex:29536; }","duration":"327.09895ms","start":"2026-07-17T16:47:05.887592Z","end":"2026-07-17T16:47:06.214691Z","steps":["trace[309534177] 'read index received' (duration: 164.506578ms)","trace[309534177] 'applied index is now lower than readState.Index' (duration: 162.591462ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:06.214698Z","caller":"traceutil/trace.go:171","msg":"trace[1601195320] transaction","detail":"{read_only:false; response_revision:28630; number_of_response:1; }","duration":"204.67668ms","start":"2026-07-17T16:47:06.009993Z","end":"2026-07-17T16:47:06.214669Z","steps":["trace[1601195320] 'process raft request' (duration: 204.555887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:06.214814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.709853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T16:47:06.214829Z","caller":"traceutil/trace.go:171","msg":"trace[379993844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28631; }","duration":"316.768205ms","start":"2026-07-17T16:47:05.898056Z","end":"2026-07-17T16:47:06.214825Z","steps":["trace[379993844] 'agreement among raft nodes before linearized reading' (duration: 316.699433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:06.214843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:05.89805Z","time spent":"316.790495ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T16:47:06.214841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.234373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-17T16:47:06.214833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.722699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:47:06.214872Z","caller":"traceutil/trace.go:171","msg":"trace[684398924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28631; }","duration":"327.295364ms","start":"2026-07-17T16:47:05.887569Z","end":"2026-07-17T16:47:06.214864Z","steps":["trace[684398924] 'agreement among raft nodes before linearized reading' (duration: 327.154012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:06.214892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:05.887561Z","time spent":"327.326945ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T16:47:06.214894Z","caller":"traceutil/trace.go:171","msg":"trace[923211202] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28631; }","duration":"300.801732ms","start":"2026-07-17T16:47:05.914074Z","end":"2026-07-17T16:47:06.214876Z","steps":["trace[923211202] 'agreement among raft nodes before linearized reading' (duration: 300.70883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:06.214936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:05.914067Z","time spent":"300.857022ms","remote":"127.0.0.1:34020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-17T16:47:06.373374Z","caller":"traceutil/trace.go:171","msg":"trace[287852706] transaction","detail":"{read_only:false; response_revision:28632; number_of_response:1; }","duration":"152.888329ms","start":"2026-07-17T16:47:06.220454Z","end":"2026-07-17T16:47:06.373343Z","steps":["trace[287852706] 'process raft request' (duration: 135.720664ms)","trace[287852706] 'compare' (duration: 17.040623ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:06.405108Z","caller":"traceutil/trace.go:171","msg":"trace[837279020] linearizableReadLoop","detail":"{readStateIndex:29545; appliedIndex:29543; }","duration":"161.585793ms","start":"2026-07-17T16:47:06.243496Z","end":"2026-07-17T16:47:06.405082Z","steps":["trace[837279020] 'read index received' (duration: 112.691447ms)","trace[837279020] 'applied index is now lower than readState.Index' (duration: 48.893176ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:06.4054Z","caller":"traceutil/trace.go:171","msg":"trace[361980804] transaction","detail":"{read_only:false; response_revision:28633; number_of_response:1; }","duration":"184.270283ms","start":"2026-07-17T16:47:06.221093Z","end":"2026-07-17T16:47:06.405363Z","steps":["trace[361980804] 'process raft request' (duration: 183.802165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:06.405474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.94698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-17T16:47:06.405534Z","caller":"traceutil/trace.go:171","msg":"trace[1009469675] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:28633; }","duration":"162.056382ms","start":"2026-07-17T16:47:06.24346Z","end":"2026-07-17T16:47:06.405516Z","steps":["trace[1009469675] 'agreement among raft nodes before linearized reading' (duration: 161.727666ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:07.548505Z","caller":"traceutil/trace.go:171","msg":"trace[915461680] transaction","detail":"{read_only:false; response_revision:28639; number_of_response:1; }","duration":"150.821379ms","start":"2026-07-17T16:47:07.397641Z","end":"2026-07-17T16:47:07.548462Z","steps":["trace[915461680] 'process raft request' (duration: 84.037753ms)","trace[915461680] 'compare' (duration: 66.195035ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:08.164572Z","caller":"traceutil/trace.go:171","msg":"trace[1278776962] transaction","detail":"{read_only:false; response_revision:28640; number_of_response:1; }","duration":"106.744864ms","start":"2026-07-17T16:47:08.057798Z","end":"2026-07-17T16:47:08.164543Z","steps":["trace[1278776962] 'process raft request' (duration: 106.485989ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:08.445619Z","caller":"traceutil/trace.go:171","msg":"trace[912516205] transaction","detail":"{read_only:false; response_revision:28641; number_of_response:1; }","duration":"220.127392ms","start":"2026-07-17T16:47:08.225473Z","end":"2026-07-17T16:47:08.445601Z","steps":["trace[912516205] 'process raft request' (duration: 219.9963ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:08.677206Z","caller":"traceutil/trace.go:171","msg":"trace[1090940043] transaction","detail":"{read_only:false; response_revision:28643; number_of_response:1; }","duration":"450.674063ms","start":"2026-07-17T16:47:08.226498Z","end":"2026-07-17T16:47:08.677172Z","steps":["trace[1090940043] 'process raft request' (duration: 450.50429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:08.677325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.444786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:47:08.677395Z","caller":"traceutil/trace.go:171","msg":"trace[1928028152] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:28647; }","duration":"414.557188ms","start":"2026-07-17T16:47:08.262828Z","end":"2026-07-17T16:47:08.677385Z","steps":["trace[1928028152] 'agreement among raft nodes before linearized reading' (duration: 414.432696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:08.677405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:08.22648Z","time spent":"450.803415ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:47:08.677427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:08.262814Z","time spent":"414.602079ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-07-17T16:47:08.677622Z","caller":"traceutil/trace.go:171","msg":"trace[1632447922] transaction","detail":"{read_only:false; response_revision:28644; number_of_response:1; }","duration":"451.05393ms","start":"2026-07-17T16:47:08.226558Z","end":"2026-07-17T16:47:08.677612Z","steps":["trace[1632447922] 'process raft request' (duration: 450.480249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:08.677699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:08.226536Z","time spent":"451.109121ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:47:08.677777Z","caller":"traceutil/trace.go:171","msg":"trace[1249517593] transaction","detail":"{read_only:false; response_revision:28642; number_of_response:1; }","duration":"451.331585ms","start":"2026-07-17T16:47:08.226431Z","end":"2026-07-17T16:47:08.677763Z","steps":["trace[1249517593] 'process raft request' (duration: 450.430518ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:08.677836Z","caller":"traceutil/trace.go:171","msg":"trace[1885965558] transaction","detail":"{read_only:false; response_revision:28645; number_of_response:1; }","duration":"451.248663ms","start":"2026-07-17T16:47:08.226578Z","end":"2026-07-17T16:47:08.677827Z","steps":["trace[1885965558] 'process raft request' (duration: 450.499639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:08.677841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:08.226368Z","time spent":"451.435597ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:47:08.677884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:08.226549Z","time spent":"451.310785ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:47:08.677953Z","caller":"traceutil/trace.go:171","msg":"trace[1403646285] transaction","detail":"{read_only:false; response_revision:28646; number_of_response:1; }","duration":"450.385876ms","start":"2026-07-17T16:47:08.22756Z","end":"2026-07-17T16:47:08.677945Z","steps":["trace[1403646285] 'process raft request' (duration: 449.549681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:08.677996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:08.22755Z","time spent":"450.423097ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:47:08.678064Z","caller":"traceutil/trace.go:171","msg":"trace[1841587575] transaction","detail":"{read_only:false; response_revision:28647; number_of_response:1; }","duration":"239.844065ms","start":"2026-07-17T16:47:08.438211Z","end":"2026-07-17T16:47:08.678055Z","steps":["trace[1841587575] 'process raft request' (duration: 238.931528ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:08.677209Z","caller":"traceutil/trace.go:171","msg":"trace[615183022] linearizableReadLoop","detail":"{readStateIndex:29558; appliedIndex:29552; }","duration":"414.328614ms","start":"2026-07-17T16:47:08.26286Z","end":"2026-07-17T16:47:08.677189Z","steps":["trace[615183022] 'read index received' (duration: 182.651872ms)","trace[615183022] 'applied index is now lower than readState.Index' (duration: 231.675812ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:47:08.678429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.1768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-17T16:47:08.678468Z","caller":"traceutil/trace.go:171","msg":"trace[1801069040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28647; }","duration":"301.314242ms","start":"2026-07-17T16:47:08.377143Z","end":"2026-07-17T16:47:08.678457Z","steps":["trace[1801069040] 'agreement among raft nodes before linearized reading' (duration: 301.182338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:08.678502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:08.37712Z","time spent":"301.375114ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T16:47:08.678902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.786255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T16:47:08.678934Z","caller":"traceutil/trace.go:171","msg":"trace[671267046] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28647; }","duration":"269.849466ms","start":"2026-07-17T16:47:08.409075Z","end":"2026-07-17T16:47:08.678924Z","steps":["trace[671267046] 'agreement among raft nodes before linearized reading' (duration: 269.770085ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:08.995872Z","caller":"traceutil/trace.go:171","msg":"trace[1998534837] linearizableReadLoop","detail":"{readStateIndex:29565; appliedIndex:29563; }","duration":"163.412847ms","start":"2026-07-17T16:47:08.832421Z","end":"2026-07-17T16:47:08.995834Z","steps":["trace[1998534837] 'read index received' (duration: 47.870087ms)","trace[1998534837] 'applied index is now lower than readState.Index' (duration: 115.54117ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:08.995916Z","caller":"traceutil/trace.go:171","msg":"trace[1462358163] transaction","detail":"{read_only:false; response_revision:28653; number_of_response:1; }","duration":"212.59602ms","start":"2026-07-17T16:47:08.783282Z","end":"2026-07-17T16:47:08.995878Z","steps":["trace[1462358163] 'process raft request' (duration: 123.184525ms)","trace[1462358163] 'compare' (duration: 89.091459ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:47:08.996039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.605161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:47:08.99609Z","caller":"traceutil/trace.go:171","msg":"trace[1146673579] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:28653; }","duration":"163.708844ms","start":"2026-07-17T16:47:08.832366Z","end":"2026-07-17T16:47:08.996075Z","steps":["trace[1146673579] 'agreement among raft nodes before linearized reading' (duration: 163.613882ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:09.657191Z","caller":"traceutil/trace.go:171","msg":"trace[1019022122] transaction","detail":"{read_only:false; response_revision:28656; number_of_response:1; }","duration":"103.712865ms","start":"2026-07-17T16:47:09.553447Z","end":"2026-07-17T16:47:09.65716Z","steps":["trace[1019022122] 'process raft request' (duration: 54.36719ms)","trace[1019022122] 'compare' (duration: 49.194603ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:10.352011Z","caller":"traceutil/trace.go:171","msg":"trace[1365161151] transaction","detail":"{read_only:false; response_revision:28658; number_of_response:1; }","duration":"209.829847ms","start":"2026-07-17T16:47:10.142154Z","end":"2026-07-17T16:47:10.351984Z","steps":["trace[1365161151] 'process raft request' (duration: 209.642364ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:10.439791Z","caller":"traceutil/trace.go:171","msg":"trace[2119382069] linearizableReadLoop","detail":"{readStateIndex:29572; appliedIndex:29570; }","duration":"160.346089ms","start":"2026-07-17T16:47:10.279421Z","end":"2026-07-17T16:47:10.439767Z","steps":["trace[2119382069] 'read index received' (duration: 72.327461ms)","trace[2119382069] 'applied index is now lower than readState.Index' (duration: 88.017757ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:10.439872Z","caller":"traceutil/trace.go:171","msg":"trace[1436698951] transaction","detail":"{read_only:false; response_revision:28659; number_of_response:1; }","duration":"269.126791ms","start":"2026-07-17T16:47:10.170714Z","end":"2026-07-17T16:47:10.439841Z","steps":["trace[1436698951] 'process raft request' (duration: 268.921637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:10.440029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.581273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:9045"} {"level":"info","ts":"2026-07-17T16:47:10.440097Z","caller":"traceutil/trace.go:171","msg":"trace[977388386] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:28659; }","duration":"160.700535ms","start":"2026-07-17T16:47:10.279379Z","end":"2026-07-17T16:47:10.440079Z","steps":["trace[977388386] 'agreement among raft nodes before linearized reading' (duration: 160.487741ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:10.551747Z","caller":"traceutil/trace.go:171","msg":"trace[556586916] transaction","detail":"{read_only:false; response_revision:28660; number_of_response:1; }","duration":"101.234019ms","start":"2026-07-17T16:47:10.450477Z","end":"2026-07-17T16:47:10.551711Z","steps":["trace[556586916] 'process raft request' (duration: 69.529538ms)","trace[556586916] 'compare' (duration: 31.534288ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:47:10.790111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.854229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:47:10.790204Z","caller":"traceutil/trace.go:171","msg":"trace[401751131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28660; }","duration":"103.988572ms","start":"2026-07-17T16:47:10.686184Z","end":"2026-07-17T16:47:10.790173Z","steps":["trace[401751131] 'range keys from in-memory index tree' (duration: 103.766797ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:10.790334Z","caller":"traceutil/trace.go:171","msg":"trace[517715962] transaction","detail":"{read_only:false; response_revision:28661; number_of_response:1; }","duration":"103.759767ms","start":"2026-07-17T16:47:10.686544Z","end":"2026-07-17T16:47:10.790304Z","steps":["trace[517715962] 'process raft request' (duration: 84.076564ms)","trace[517715962] 'compare' (duration: 19.430208ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:10.995694Z","caller":"traceutil/trace.go:171","msg":"trace[752615791] transaction","detail":"{read_only:false; response_revision:28662; number_of_response:1; }","duration":"308.08649ms","start":"2026-07-17T16:47:10.687569Z","end":"2026-07-17T16:47:10.995655Z","steps":["trace[752615791] 'process raft request' (duration: 228.680274ms)","trace[752615791] 'compare' (duration: 78.554419ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:10.99581Z","caller":"traceutil/trace.go:171","msg":"trace[1381443693] transaction","detail":"{read_only:false; response_revision:28663; number_of_response:1; }","duration":"308.164821ms","start":"2026-07-17T16:47:10.687621Z","end":"2026-07-17T16:47:10.995786Z","steps":["trace[1381443693] 'process raft request' (duration: 307.959668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:10.995858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:10.687537Z","time spent":"308.230452ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:47:10.995977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:10.68759Z","time spent":"308.293523ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:47:10.996251Z","caller":"traceutil/trace.go:171","msg":"trace[207912273] transaction","detail":"{read_only:false; response_revision:28664; number_of_response:1; }","duration":"308.062049ms","start":"2026-07-17T16:47:10.688149Z","end":"2026-07-17T16:47:10.996211Z","steps":["trace[207912273] 'process raft request' (duration: 307.603931ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:10.996365Z","caller":"traceutil/trace.go:171","msg":"trace[346934912] transaction","detail":"{read_only:false; response_revision:28665; number_of_response:1; }","duration":"308.08112ms","start":"2026-07-17T16:47:10.68823Z","end":"2026-07-17T16:47:10.996311Z","steps":["trace[346934912] 'process raft request' (duration: 307.890467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:10.996442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:10.688076Z","time spent":"308.288553ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:47:10.996456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:10.688212Z","time spent":"308.198762ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:47:10.996441Z","caller":"traceutil/trace.go:171","msg":"trace[1668564562] transaction","detail":"{read_only:false; response_revision:28666; number_of_response:1; }","duration":"110.239159ms","start":"2026-07-17T16:47:10.886077Z","end":"2026-07-17T16:47:10.996316Z","steps":["trace[1668564562] 'process raft request' (duration: 110.179318ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:10.996564Z","caller":"traceutil/trace.go:171","msg":"trace[1772728104] linearizableReadLoop","detail":"{readStateIndex:29578; appliedIndex:29573; }","duration":"283.949682ms","start":"2026-07-17T16:47:10.712599Z","end":"2026-07-17T16:47:10.996548Z","steps":["trace[1772728104] 'read index received' (duration: 58.041529ms)","trace[1772728104] 'applied index is now lower than readState.Index' (duration: 225.905773ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:47:10.996903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.384403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-17T16:47:10.996955Z","caller":"traceutil/trace.go:171","msg":"trace[1088108731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28666; }","duration":"282.458624ms","start":"2026-07-17T16:47:10.714483Z","end":"2026-07-17T16:47:10.996942Z","steps":["trace[1088108731] 'agreement among raft nodes before linearized reading' (duration: 282.296021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:10.996959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.892423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-17T16:47:10.996974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.37562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-17T16:47:10.996998Z","caller":"traceutil/trace.go:171","msg":"trace[66333822] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:28666; }","duration":"166.968284ms","start":"2026-07-17T16:47:10.830018Z","end":"2026-07-17T16:47:10.996986Z","steps":["trace[66333822] 'agreement among raft nodes before linearized reading' (duration: 166.911763ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:10.997033Z","caller":"traceutil/trace.go:171","msg":"trace[1811294277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28666; }","duration":"284.462911ms","start":"2026-07-17T16:47:10.712555Z","end":"2026-07-17T16:47:10.997018Z","steps":["trace[1811294277] 'agreement among raft nodes before linearized reading' (duration: 284.300868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:10.997112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.248498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:9045"} {"level":"info","ts":"2026-07-17T16:47:10.997153Z","caller":"traceutil/trace.go:171","msg":"trace[388146921] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:28666; }","duration":"197.31007ms","start":"2026-07-17T16:47:10.799828Z","end":"2026-07-17T16:47:10.997138Z","steps":["trace[388146921] 'agreement among raft nodes before linearized reading' (duration: 197.201938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:10.996907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.79515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:9045"} {"level":"warn","ts":"2026-07-17T16:47:10.997374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.836461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T16:47:10.997389Z","caller":"traceutil/trace.go:171","msg":"trace[180788475] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:28666; }","duration":"197.305709ms","start":"2026-07-17T16:47:10.800068Z","end":"2026-07-17T16:47:10.997374Z","steps":["trace[180788475] 'agreement among raft nodes before linearized reading' (duration: 196.717848ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:10.997403Z","caller":"traceutil/trace.go:171","msg":"trace[235409072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28666; }","duration":"282.904102ms","start":"2026-07-17T16:47:10.71449Z","end":"2026-07-17T16:47:10.997394Z","steps":["trace[235409072] 'agreement among raft nodes before linearized reading' (duration: 282.818571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:10.997458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.79677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-17T16:47:10.997509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.734819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:47:10.997602Z","caller":"traceutil/trace.go:171","msg":"trace[1797137161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28666; }","duration":"205.853561ms","start":"2026-07-17T16:47:10.791736Z","end":"2026-07-17T16:47:10.997589Z","steps":["trace[1797137161] 'agreement among raft nodes before linearized reading' (duration: 205.737219ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:10.997573Z","caller":"traceutil/trace.go:171","msg":"trace[623128309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28666; }","duration":"283.033365ms","start":"2026-07-17T16:47:10.714523Z","end":"2026-07-17T16:47:10.997556Z","steps":["trace[623128309] 'agreement among raft nodes before linearized reading' (duration: 282.688419ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:11.244149Z","caller":"traceutil/trace.go:171","msg":"trace[583328207] transaction","detail":"{read_only:false; response_revision:28668; number_of_response:1; }","duration":"242.773451ms","start":"2026-07-17T16:47:11.001349Z","end":"2026-07-17T16:47:11.244123Z","steps":["trace[583328207] 'process raft request' (duration: 233.843412ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:11.244509Z","caller":"traceutil/trace.go:171","msg":"trace[1488754409] transaction","detail":"{read_only:false; response_revision:28669; number_of_response:1; }","duration":"243.131729ms","start":"2026-07-17T16:47:11.001349Z","end":"2026-07-17T16:47:11.244481Z","steps":["trace[1488754409] 'process raft request' (duration: 242.686859ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:11.244938Z","caller":"traceutil/trace.go:171","msg":"trace[262487506] transaction","detail":"{read_only:false; response_revision:28670; number_of_response:1; }","duration":"243.519456ms","start":"2026-07-17T16:47:11.001405Z","end":"2026-07-17T16:47:11.244924Z","steps":["trace[262487506] 'process raft request' (duration: 242.956934ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:11.245059Z","caller":"traceutil/trace.go:171","msg":"trace[58996062] transaction","detail":"{read_only:false; response_revision:28671; number_of_response:1; }","duration":"242.812223ms","start":"2026-07-17T16:47:11.002238Z","end":"2026-07-17T16:47:11.24505Z","steps":["trace[58996062] 'process raft request' (duration: 242.63271ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:11.4936Z","caller":"traceutil/trace.go:171","msg":"trace[20633227] transaction","detail":"{read_only:false; response_revision:28672; number_of_response:1; }","duration":"110.179238ms","start":"2026-07-17T16:47:11.383375Z","end":"2026-07-17T16:47:11.493555Z","steps":["trace[20633227] 'process raft request' (duration: 27.004382ms)","trace[20633227] 'compare' (duration: 82.207928ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:11.789629Z","caller":"traceutil/trace.go:171","msg":"trace[1806643869] transaction","detail":"{read_only:false; response_revision:28674; number_of_response:1; }","duration":"122.840878ms","start":"2026-07-17T16:47:11.66677Z","end":"2026-07-17T16:47:11.789611Z","steps":["trace[1806643869] 'process raft request' (duration: 122.710386ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:15.192568Z","caller":"traceutil/trace.go:171","msg":"trace[1913567456] transaction","detail":"{read_only:false; response_revision:28696; number_of_response:1; }","duration":"180.601184ms","start":"2026-07-17T16:47:15.011928Z","end":"2026-07-17T16:47:15.19253Z","steps":["trace[1913567456] 'process raft request' (duration: 180.256017ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:15.214561Z","caller":"traceutil/trace.go:171","msg":"trace[848174143] transaction","detail":"{read_only:false; response_revision:28697; number_of_response:1; }","duration":"201.992698ms","start":"2026-07-17T16:47:15.01255Z","end":"2026-07-17T16:47:15.214543Z","steps":["trace[848174143] 'process raft request' (duration: 201.742424ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:15.2149Z","caller":"traceutil/trace.go:171","msg":"trace[1078220659] transaction","detail":"{read_only:false; response_revision:28698; number_of_response:1; }","duration":"201.57344ms","start":"2026-07-17T16:47:15.013295Z","end":"2026-07-17T16:47:15.214869Z","steps":["trace[1078220659] 'process raft request' (duration: 201.164523ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:15.215165Z","caller":"traceutil/trace.go:171","msg":"trace[582849417] transaction","detail":"{read_only:false; response_revision:28699; number_of_response:1; }","duration":"199.385139ms","start":"2026-07-17T16:47:15.015767Z","end":"2026-07-17T16:47:15.215152Z","steps":["trace[582849417] 'process raft request' (duration: 198.989901ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:15.215409Z","caller":"traceutil/trace.go:171","msg":"trace[1420965776] linearizableReadLoop","detail":"{readStateIndex:29614; appliedIndex:29609; }","duration":"160.997001ms","start":"2026-07-17T16:47:15.0544Z","end":"2026-07-17T16:47:15.215397Z","steps":["trace[1420965776] 'read index received' (duration: 137.844823ms)","trace[1420965776] 'applied index is now lower than readState.Index' (duration: 23.151118ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:15.215501Z","caller":"traceutil/trace.go:171","msg":"trace[1907966734] transaction","detail":"{read_only:false; response_revision:28700; number_of_response:1; }","duration":"199.605753ms","start":"2026-07-17T16:47:15.015886Z","end":"2026-07-17T16:47:15.215491Z","steps":["trace[1907966734] 'process raft request' (duration: 199.209166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:15.215854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.424959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-17T16:47:15.215892Z","caller":"traceutil/trace.go:171","msg":"trace[785898315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28700; }","duration":"161.521771ms","start":"2026-07-17T16:47:15.05436Z","end":"2026-07-17T16:47:15.215882Z","steps":["trace[785898315] 'agreement among raft nodes before linearized reading' (duration: 161.350138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:21.524948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.662217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:47:21.52509Z","caller":"traceutil/trace.go:171","msg":"trace[291191321] linearizableReadLoop","detail":"{readStateIndex:29669; appliedIndex:29668; }","duration":"108.792971ms","start":"2026-07-17T16:47:21.416276Z","end":"2026-07-17T16:47:21.525069Z","steps":["trace[291191321] 'read index received' (duration: 80.312µs)","trace[291191321] 'applied index is now lower than readState.Index' (duration: 108.711379ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:21.525107Z","caller":"traceutil/trace.go:171","msg":"trace[786947712] transaction","detail":"{read_only:false; response_revision:28754; number_of_response:1; }","duration":"180.113183ms","start":"2026-07-17T16:47:21.344954Z","end":"2026-07-17T16:47:21.525067Z","steps":["trace[786947712] 'process raft request' (duration: 41.203461ms)","trace[786947712] 'compare' (duration: 138.24868ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:47:21.52523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.914553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:47:21.525314Z","caller":"traceutil/trace.go:171","msg":"trace[1604942463] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28754; }","duration":"109.060106ms","start":"2026-07-17T16:47:21.416234Z","end":"2026-07-17T16:47:21.525294Z","steps":["trace[1604942463] 'agreement among raft nodes before linearized reading' (duration: 108.903993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:24.820683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.682707ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-07-17T16:47:24.821323Z","caller":"traceutil/trace.go:171","msg":"trace[1363595226] linearizableReadLoop","detail":"{readStateIndex:29752; appliedIndex:29750; }","duration":"135.395486ms","start":"2026-07-17T16:47:24.685909Z","end":"2026-07-17T16:47:24.821304Z","steps":["trace[1363595226] 'read index received' (duration: 4.106197ms)","trace[1363595226] 'applied index is now lower than readState.Index' (duration: 131.288439ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:47:24.821495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.571279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:47:24.821583Z","caller":"traceutil/trace.go:171","msg":"trace[742771730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28835; }","duration":"135.691692ms","start":"2026-07-17T16:47:24.685878Z","end":"2026-07-17T16:47:24.821569Z","steps":["trace[742771730] 'agreement among raft nodes before linearized reading' (duration: 135.57436ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:24.821724Z","caller":"traceutil/trace.go:171","msg":"trace[521497422] transaction","detail":"{read_only:false; response_revision:28835; number_of_response:1; }","duration":"204.333483ms","start":"2026-07-17T16:47:24.617082Z","end":"2026-07-17T16:47:24.821416Z","steps":["trace[521497422] 'process raft request' (duration: 204.087339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:25.066033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.426737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:47:25.066225Z","caller":"traceutil/trace.go:171","msg":"trace[227348586] linearizableReadLoop","detail":"{readStateIndex:29755; appliedIndex:29753; }","duration":"116.676671ms","start":"2026-07-17T16:47:24.949532Z","end":"2026-07-17T16:47:25.066209Z","steps":["trace[227348586] 'read index received' (duration: 28.99µs)","trace[227348586] 'applied index is now lower than readState.Index' (duration: 116.646641ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:47:25.066391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.850255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-099c6928-5e5c-492f-8970-e8846827bbf9\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-17T16:47:25.066427Z","caller":"traceutil/trace.go:171","msg":"trace[738416368] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-099c6928-5e5c-492f-8970-e8846827bbf9; range_end:; response_count:1; response_revision:28838; }","duration":"116.913987ms","start":"2026-07-17T16:47:24.949502Z","end":"2026-07-17T16:47:25.066416Z","steps":["trace[738416368] 'agreement among raft nodes before linearized reading' (duration: 116.759853ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:25.066462Z","caller":"traceutil/trace.go:171","msg":"trace[261683376] transaction","detail":"{read_only:false; response_revision:28837; number_of_response:1; }","duration":"224.386714ms","start":"2026-07-17T16:47:24.842048Z","end":"2026-07-17T16:47:25.066435Z","steps":["trace[261683376] 'process raft request' (duration: 98.485547ms)","trace[261683376] 'compare' (duration: 125.311975ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:25.066463Z","caller":"traceutil/trace.go:171","msg":"trace[1910748977] transaction","detail":"{read_only:false; response_revision:28838; number_of_response:1; }","duration":"202.603571ms","start":"2026-07-17T16:47:24.863825Z","end":"2026-07-17T16:47:25.066429Z","steps":["trace[1910748977] 'process raft request' (duration: 202.311245ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:25.296992Z","caller":"traceutil/trace.go:171","msg":"trace[1305241660] linearizableReadLoop","detail":"{readStateIndex:29756; appliedIndex:29755; }","duration":"209.542593ms","start":"2026-07-17T16:47:25.087417Z","end":"2026-07-17T16:47:25.29696Z","steps":["trace[1305241660] 'read index received' (duration: 133.304428ms)","trace[1305241660] 'applied index is now lower than readState.Index' (duration: 76.236595ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:25.29701Z","caller":"traceutil/trace.go:171","msg":"trace[2022895975] transaction","detail":"{read_only:false; response_revision:28839; number_of_response:1; }","duration":"223.67835ms","start":"2026-07-17T16:47:25.073309Z","end":"2026-07-17T16:47:25.296987Z","steps":["trace[2022895975] 'process raft request' (duration: 147.469546ms)","trace[2022895975] 'compare' (duration: 75.99937ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:47:25.29715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.706986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:47:25.297224Z","caller":"traceutil/trace.go:171","msg":"trace[1518671800] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:28839; }","duration":"209.821127ms","start":"2026-07-17T16:47:25.087383Z","end":"2026-07-17T16:47:25.297204Z","steps":["trace[1518671800] 'agreement among raft nodes before linearized reading' (duration: 209.704175ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:25.553531Z","caller":"traceutil/trace.go:171","msg":"trace[436218394] linearizableReadLoop","detail":"{readStateIndex:29761; appliedIndex:29756; }","duration":"256.450101ms","start":"2026-07-17T16:47:25.297059Z","end":"2026-07-17T16:47:25.553509Z","steps":["trace[436218394] 'read index received' (duration: 11.171132ms)","trace[436218394] 'applied index is now lower than readState.Index' (duration: 245.278099ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:25.553762Z","caller":"traceutil/trace.go:171","msg":"trace[1900968928] transaction","detail":"{read_only:false; response_revision:28844; number_of_response:1; }","duration":"284.3295ms","start":"2026-07-17T16:47:25.269408Z","end":"2026-07-17T16:47:25.553737Z","steps":["trace[1900968928] 'process raft request' (duration: 284.058634ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:25.553791Z","caller":"traceutil/trace.go:171","msg":"trace[1234681601] transaction","detail":"{read_only:false; response_revision:28842; number_of_response:1; }","duration":"284.886511ms","start":"2026-07-17T16:47:25.268883Z","end":"2026-07-17T16:47:25.55377Z","steps":["trace[1234681601] 'process raft request' (duration: 284.491232ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:25.55382Z","caller":"traceutil/trace.go:171","msg":"trace[1593449277] transaction","detail":"{read_only:false; response_revision:28841; number_of_response:1; }","duration":"284.977842ms","start":"2026-07-17T16:47:25.268815Z","end":"2026-07-17T16:47:25.553793Z","steps":["trace[1593449277] 'process raft request' (duration: 252.529977ms)","trace[1593449277] 'compare' (duration: 31.228712ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:25.553881Z","caller":"traceutil/trace.go:171","msg":"trace[827939383] transaction","detail":"{read_only:false; response_revision:28843; number_of_response:1; }","duration":"284.703997ms","start":"2026-07-17T16:47:25.269153Z","end":"2026-07-17T16:47:25.553857Z","steps":["trace[827939383] 'process raft request' (duration: 284.280168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:25.553962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.708231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:47:25.553993Z","caller":"traceutil/trace.go:171","msg":"trace[1621091384] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:28844; }","duration":"405.772952ms","start":"2026-07-17T16:47:25.148212Z","end":"2026-07-17T16:47:25.553985Z","steps":["trace[1621091384] 'agreement among raft nodes before linearized reading' (duration: 405.70057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:25.553986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.482796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-17T16:47:25.55402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:25.148202Z","time spent":"405.811022ms","remote":"127.0.0.1:42650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-17T16:47:25.554081Z","caller":"traceutil/trace.go:171","msg":"trace[562211434] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:28844; }","duration":"406.572287ms","start":"2026-07-17T16:47:25.147443Z","end":"2026-07-17T16:47:25.554016Z","steps":["trace[562211434] 'agreement among raft nodes before linearized reading' (duration: 406.497256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:25.554123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:25.147425Z","time spent":"406.68799ms","remote":"127.0.0.1:50918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-17T16:47:25.55412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.401653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-17T16:47:25.554177Z","caller":"traceutil/trace.go:171","msg":"trace[902350329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28844; }","duration":"264.500654ms","start":"2026-07-17T16:47:25.289662Z","end":"2026-07-17T16:47:25.554163Z","steps":["trace[902350329] 'agreement among raft nodes before linearized reading' (duration: 264.319471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:25.612669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.202284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T16:47:25.612739Z","caller":"traceutil/trace.go:171","msg":"trace[1369928909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28845; }","duration":"259.295015ms","start":"2026-07-17T16:47:25.353429Z","end":"2026-07-17T16:47:25.612724Z","steps":["trace[1369928909] 'agreement among raft nodes before linearized reading' (duration: 259.121521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:25.61273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.469535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-07-17T16:47:25.612822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.979644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T16:47:25.612821Z","caller":"traceutil/trace.go:171","msg":"trace[867347686] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28845; }","duration":"157.578967ms","start":"2026-07-17T16:47:25.45522Z","end":"2026-07-17T16:47:25.612799Z","steps":["trace[867347686] 'agreement among raft nodes before linearized reading' (duration: 157.290151ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:25.612674Z","caller":"traceutil/trace.go:171","msg":"trace[1835226721] transaction","detail":"{read_only:false; response_revision:28845; number_of_response:1; }","duration":"304.482042ms","start":"2026-07-17T16:47:25.30817Z","end":"2026-07-17T16:47:25.612652Z","steps":["trace[1835226721] 'process raft request' (duration: 304.133594ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:25.612889Z","caller":"traceutil/trace.go:171","msg":"trace[479904567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28845; }","duration":"284.076775ms","start":"2026-07-17T16:47:25.328796Z","end":"2026-07-17T16:47:25.612873Z","steps":["trace[479904567] 'agreement among raft nodes before linearized reading' (duration: 283.923192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:25.613004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:47:25.308152Z","time spent":"304.767437ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:47:25.61259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.464748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:47:25.613532Z","caller":"traceutil/trace.go:171","msg":"trace[1044462078] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:28845; }","duration":"222.431586ms","start":"2026-07-17T16:47:25.391066Z","end":"2026-07-17T16:47:25.613498Z","steps":["trace[1044462078] 'agreement among raft nodes before linearized reading' (duration: 221.448137ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:25.80183Z","caller":"traceutil/trace.go:171","msg":"trace[1564851714] linearizableReadLoop","detail":"{readStateIndex:29766; appliedIndex:29763; }","duration":"172.731425ms","start":"2026-07-17T16:47:25.629077Z","end":"2026-07-17T16:47:25.801809Z","steps":["trace[1564851714] 'read index received' (duration: 172.433159ms)","trace[1564851714] 'applied index is now lower than readState.Index' (duration: 297.316µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:25.802124Z","caller":"traceutil/trace.go:171","msg":"trace[46748507] transaction","detail":"{read_only:false; response_revision:28847; number_of_response:1; }","duration":"184.371415ms","start":"2026-07-17T16:47:25.617741Z","end":"2026-07-17T16:47:25.802112Z","steps":["trace[46748507] 'process raft request' (duration: 183.838656ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:25.802314Z","caller":"traceutil/trace.go:171","msg":"trace[1292915658] transaction","detail":"{read_only:false; response_revision:28848; number_of_response:1; }","duration":"184.469267ms","start":"2026-07-17T16:47:25.617836Z","end":"2026-07-17T16:47:25.802305Z","steps":["trace[1292915658] 'process raft request' (duration: 183.897096ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:25.802441Z","caller":"traceutil/trace.go:171","msg":"trace[2097958835] transaction","detail":"{read_only:false; response_revision:28849; number_of_response:1; }","duration":"183.948556ms","start":"2026-07-17T16:47:25.618484Z","end":"2026-07-17T16:47:25.802433Z","steps":["trace[2097958835] 'process raft request' (duration: 183.287144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:25.802704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.070398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-17T16:47:25.802767Z","caller":"traceutil/trace.go:171","msg":"trace[420554956] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28849; }","duration":"185.18141ms","start":"2026-07-17T16:47:25.617572Z","end":"2026-07-17T16:47:25.802753Z","steps":["trace[420554956] 'agreement among raft nodes before linearized reading' (duration: 184.974617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:25.802934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.128081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:47:25.803004Z","caller":"traceutil/trace.go:171","msg":"trace[170044851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28849; }","duration":"116.251624ms","start":"2026-07-17T16:47:25.686737Z","end":"2026-07-17T16:47:25.802989Z","steps":["trace[170044851] 'agreement among raft nodes before linearized reading' (duration: 116.117911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:25.803039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.839584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T16:47:25.80312Z","caller":"traceutil/trace.go:171","msg":"trace[2061931519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28849; }","duration":"165.960537ms","start":"2026-07-17T16:47:25.637139Z","end":"2026-07-17T16:47:25.803099Z","steps":["trace[2061931519] 'agreement among raft nodes before linearized reading' (duration: 165.587919ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:26.621787Z","caller":"traceutil/trace.go:171","msg":"trace[1075936917] transaction","detail":"{read_only:false; response_revision:28853; number_of_response:1; }","duration":"117.165671ms","start":"2026-07-17T16:47:26.504593Z","end":"2026-07-17T16:47:26.621759Z","steps":["trace[1075936917] 'process raft request' (duration: 117.002908ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:26.627392Z","caller":"traceutil/trace.go:171","msg":"trace[537362455] transaction","detail":"{read_only:false; response_revision:28854; number_of_response:1; }","duration":"103.155936ms","start":"2026-07-17T16:47:26.524221Z","end":"2026-07-17T16:47:26.627377Z","steps":["trace[537362455] 'process raft request' (duration: 103.064764ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:31.73837Z","caller":"traceutil/trace.go:171","msg":"trace[1381532427] transaction","detail":"{read_only:false; response_revision:28918; number_of_response:1; }","duration":"105.60531ms","start":"2026-07-17T16:47:31.632713Z","end":"2026-07-17T16:47:31.738319Z","steps":["trace[1381532427] 'process raft request' (duration: 102.260537ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:33.709699Z","caller":"traceutil/trace.go:171","msg":"trace[253448378] transaction","detail":"{read_only:false; response_revision:28936; number_of_response:1; }","duration":"120.126817ms","start":"2026-07-17T16:47:33.589541Z","end":"2026-07-17T16:47:33.709667Z","steps":["trace[253448378] 'process raft request' (duration: 119.913503ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:33.709752Z","caller":"traceutil/trace.go:171","msg":"trace[1888865091] transaction","detail":"{read_only:false; response_revision:28937; number_of_response:1; }","duration":"119.130039ms","start":"2026-07-17T16:47:33.590585Z","end":"2026-07-17T16:47:33.709715Z","steps":["trace[1888865091] 'process raft request' (duration: 118.999637ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:33.709754Z","caller":"traceutil/trace.go:171","msg":"trace[161839341] transaction","detail":"{read_only:false; response_revision:28938; number_of_response:1; }","duration":"118.053598ms","start":"2026-07-17T16:47:33.591676Z","end":"2026-07-17T16:47:33.709729Z","steps":["trace[161839341] 'process raft request' (duration: 117.940776ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:37.884651Z","caller":"traceutil/trace.go:171","msg":"trace[1768418775] transaction","detail":"{read_only:false; response_revision:28969; number_of_response:1; }","duration":"135.043729ms","start":"2026-07-17T16:47:37.749576Z","end":"2026-07-17T16:47:37.88462Z","steps":["trace[1768418775] 'process raft request' (duration: 92.3031ms)","trace[1768418775] 'compare' (duration: 42.401213ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:37.884703Z","caller":"traceutil/trace.go:171","msg":"trace[814981318] transaction","detail":"{read_only:false; response_revision:28971; number_of_response:1; }","duration":"134.654802ms","start":"2026-07-17T16:47:37.75002Z","end":"2026-07-17T16:47:37.884675Z","steps":["trace[814981318] 'process raft request' (duration: 134.574371ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:37.88473Z","caller":"traceutil/trace.go:171","msg":"trace[945380386] transaction","detail":"{read_only:false; response_revision:28970; number_of_response:1; }","duration":"134.935078ms","start":"2026-07-17T16:47:37.749767Z","end":"2026-07-17T16:47:37.884702Z","steps":["trace[945380386] 'process raft request' (duration: 134.763895ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:37.884819Z","caller":"traceutil/trace.go:171","msg":"trace[38633204] linearizableReadLoop","detail":"{readStateIndex:29891; appliedIndex:29886; }","duration":"125.573641ms","start":"2026-07-17T16:47:37.759222Z","end":"2026-07-17T16:47:37.884796Z","steps":["trace[38633204] 'read index received' (duration: 12.184152ms)","trace[38633204] 'applied index is now lower than readState.Index' (duration: 113.388219ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:37.884829Z","caller":"traceutil/trace.go:171","msg":"trace[1236340263] transaction","detail":"{read_only:false; response_revision:28972; number_of_response:1; }","duration":"128.806742ms","start":"2026-07-17T16:47:37.755999Z","end":"2026-07-17T16:47:37.884806Z","steps":["trace[1236340263] 'process raft request' (duration: 128.643039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:37.885036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.793225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-17T16:47:37.885089Z","caller":"traceutil/trace.go:171","msg":"trace[853546664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28972; }","duration":"125.884967ms","start":"2026-07-17T16:47:37.759191Z","end":"2026-07-17T16:47:37.885076Z","steps":["trace[853546664] 'agreement among raft nodes before linearized reading' (duration: 125.700143ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:42.965981Z","caller":"traceutil/trace.go:171","msg":"trace[867620379] transaction","detail":"{read_only:false; response_revision:29019; number_of_response:1; }","duration":"199.151124ms","start":"2026-07-17T16:47:42.766802Z","end":"2026-07-17T16:47:42.965953Z","steps":["trace[867620379] 'process raft request' (duration: 129.302171ms)","trace[867620379] 'compare' (duration: 69.733721ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:45.945452Z","caller":"traceutil/trace.go:171","msg":"trace[814830793] transaction","detail":"{read_only:false; response_revision:29039; number_of_response:1; }","duration":"144.52651ms","start":"2026-07-17T16:47:45.800898Z","end":"2026-07-17T16:47:45.945425Z","steps":["trace[814830793] 'process raft request' (duration: 144.361497ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:46.120034Z","caller":"traceutil/trace.go:171","msg":"trace[288884914] transaction","detail":"{read_only:false; response_revision:29040; number_of_response:1; }","duration":"212.528309ms","start":"2026-07-17T16:47:45.907471Z","end":"2026-07-17T16:47:46.119999Z","steps":["trace[288884914] 'process raft request' (duration: 160.459522ms)","trace[288884914] 'compare' (duration: 51.774751ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:46.120039Z","caller":"traceutil/trace.go:171","msg":"trace[936274529] transaction","detail":"{read_only:false; response_revision:29041; number_of_response:1; }","duration":"209.252517ms","start":"2026-07-17T16:47:45.910757Z","end":"2026-07-17T16:47:46.12001Z","steps":["trace[936274529] 'process raft request' (duration: 209.144735ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:46.120138Z","caller":"traceutil/trace.go:171","msg":"trace[893591859] transaction","detail":"{read_only:false; response_revision:29042; number_of_response:1; }","duration":"206.276351ms","start":"2026-07-17T16:47:45.913826Z","end":"2026-07-17T16:47:46.120102Z","steps":["trace[893591859] 'process raft request' (duration: 206.121708ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:46.120202Z","caller":"traceutil/trace.go:171","msg":"trace[2010230671] linearizableReadLoop","detail":"{readStateIndex:29965; appliedIndex:29960; }","duration":"174.796603ms","start":"2026-07-17T16:47:45.945395Z","end":"2026-07-17T16:47:46.120192Z","steps":["trace[2010230671] 'read index received' (duration: 122.546803ms)","trace[2010230671] 'applied index is now lower than readState.Index' (duration: 52.24912ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:46.12006Z","caller":"traceutil/trace.go:171","msg":"trace[1098113798] transaction","detail":"{read_only:false; response_revision:29043; number_of_response:1; }","duration":"204.048728ms","start":"2026-07-17T16:47:45.916Z","end":"2026-07-17T16:47:46.120049Z","steps":["trace[1098113798] 'process raft request' (duration: 203.976326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:46.120375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.077123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-17T16:47:46.120572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.758117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-17T16:47:46.12061Z","caller":"traceutil/trace.go:171","msg":"trace[379450084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29044; }","duration":"188.819239ms","start":"2026-07-17T16:47:45.93178Z","end":"2026-07-17T16:47:46.1206Z","steps":["trace[379450084] 'agreement among raft nodes before linearized reading' (duration: 188.713737ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:46.120578Z","caller":"traceutil/trace.go:171","msg":"trace[816662638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29044; }","duration":"189.332838ms","start":"2026-07-17T16:47:45.931239Z","end":"2026-07-17T16:47:46.120572Z","steps":["trace[816662638] 'agreement among raft nodes before linearized reading' (duration: 188.998372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:46.120644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.425039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-17T16:47:46.120509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.414479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-17T16:47:46.120648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.189852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T16:47:46.120704Z","caller":"traceutil/trace.go:171","msg":"trace[632849001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29044; }","duration":"181.529402ms","start":"2026-07-17T16:47:45.939159Z","end":"2026-07-17T16:47:46.120688Z","steps":["trace[632849001] 'agreement among raft nodes before linearized reading' (duration: 181.323128ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:46.120756Z","caller":"traceutil/trace.go:171","msg":"trace[962502140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29044; }","duration":"184.302364ms","start":"2026-07-17T16:47:45.93642Z","end":"2026-07-17T16:47:46.120722Z","steps":["trace[962502140] 'agreement among raft nodes before linearized reading' (duration: 184.04822ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:46.120491Z","caller":"traceutil/trace.go:171","msg":"trace[1488309266] transaction","detail":"{read_only:false; response_revision:29044; number_of_response:1; }","duration":"193.959867ms","start":"2026-07-17T16:47:45.926515Z","end":"2026-07-17T16:47:46.120475Z","steps":["trace[1488309266] 'process raft request' (duration: 193.507788ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:46.120779Z","caller":"traceutil/trace.go:171","msg":"trace[1340050195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29044; }","duration":"181.651463ms","start":"2026-07-17T16:47:45.939068Z","end":"2026-07-17T16:47:46.12072Z","steps":["trace[1340050195] 'agreement among raft nodes before linearized reading' (duration: 181.380868ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:46.367694Z","caller":"traceutil/trace.go:171","msg":"trace[1566700926] transaction","detail":"{read_only:false; response_revision:29047; number_of_response:1; }","duration":"242.658139ms","start":"2026-07-17T16:47:46.125014Z","end":"2026-07-17T16:47:46.367672Z","steps":["trace[1566700926] 'process raft request' (duration: 242.560467ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:46.367839Z","caller":"traceutil/trace.go:171","msg":"trace[1593085350] transaction","detail":"{read_only:false; response_revision:29046; number_of_response:1; }","duration":"242.767371ms","start":"2026-07-17T16:47:46.125005Z","end":"2026-07-17T16:47:46.367772Z","steps":["trace[1593085350] 'process raft request' (duration: 156.013968ms)","trace[1593085350] 'compare' (duration: 86.431767ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:46.368014Z","caller":"traceutil/trace.go:171","msg":"trace[1555252287] linearizableReadLoop","detail":"{readStateIndex:29970; appliedIndex:29965; }","duration":"161.377829ms","start":"2026-07-17T16:47:46.206616Z","end":"2026-07-17T16:47:46.367994Z","steps":["trace[1555252287] 'read index received' (duration: 5.671507ms)","trace[1555252287] 'applied index is now lower than readState.Index' (duration: 155.705302ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:46.36802Z","caller":"traceutil/trace.go:171","msg":"trace[1911625461] transaction","detail":"{read_only:false; response_revision:29048; number_of_response:1; }","duration":"242.807963ms","start":"2026-07-17T16:47:46.125187Z","end":"2026-07-17T16:47:46.367995Z","steps":["trace[1911625461] 'process raft request' (duration: 242.445175ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:46.368046Z","caller":"traceutil/trace.go:171","msg":"trace[1682878931] transaction","detail":"{read_only:false; response_revision:29049; number_of_response:1; }","duration":"241.987808ms","start":"2026-07-17T16:47:46.126033Z","end":"2026-07-17T16:47:46.368021Z","steps":["trace[1682878931] 'process raft request' (duration: 241.869745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:46.368295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.659075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-17T16:47:46.368355Z","caller":"traceutil/trace.go:171","msg":"trace[1170009213] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29049; }","duration":"161.90498ms","start":"2026-07-17T16:47:46.206438Z","end":"2026-07-17T16:47:46.368343Z","steps":["trace[1170009213] 'agreement among raft nodes before linearized reading' (duration: 161.659685ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:50.139553Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e1d03a09d7253a9d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T16:47:50.14529Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-17T16:47:50.1454Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-17T16:47:54.253995Z","caller":"traceutil/trace.go:171","msg":"trace[928708342] transaction","detail":"{read_only:false; response_revision:29117; number_of_response:1; }","duration":"104.051022ms","start":"2026-07-17T16:47:54.149924Z","end":"2026-07-17T16:47:54.253975Z","steps":["trace[928708342] 'process raft request' (duration: 103.9183ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:54.40741Z","caller":"traceutil/trace.go:171","msg":"trace[901835195] transaction","detail":"{read_only:false; response_revision:29120; number_of_response:1; }","duration":"253.514455ms","start":"2026-07-17T16:47:54.153883Z","end":"2026-07-17T16:47:54.407397Z","steps":["trace[901835195] 'process raft request' (duration: 253.465754ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:54.407419Z","caller":"traceutil/trace.go:171","msg":"trace[933716424] transaction","detail":"{read_only:false; response_revision:29119; number_of_response:1; }","duration":"256.069834ms","start":"2026-07-17T16:47:54.151315Z","end":"2026-07-17T16:47:54.407385Z","steps":["trace[933716424] 'process raft request' (duration: 256.006433ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:54.4074Z","caller":"traceutil/trace.go:171","msg":"trace[198780284] transaction","detail":"{read_only:false; response_revision:29118; number_of_response:1; }","duration":"256.183516ms","start":"2026-07-17T16:47:54.151193Z","end":"2026-07-17T16:47:54.407376Z","steps":["trace[198780284] 'process raft request' (duration: 229.50283ms)","trace[198780284] 'compare' (duration: 26.537133ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:54.407588Z","caller":"traceutil/trace.go:171","msg":"trace[31227374] transaction","detail":"{read_only:false; response_revision:29121; number_of_response:1; }","duration":"253.697278ms","start":"2026-07-17T16:47:54.153879Z","end":"2026-07-17T16:47:54.407577Z","steps":["trace[31227374] 'process raft request' (duration: 253.488344ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:54.630772Z","caller":"traceutil/trace.go:171","msg":"trace[800546770] linearizableReadLoop","detail":"{readStateIndex:30048; appliedIndex:30043; }","duration":"212.524959ms","start":"2026-07-17T16:47:54.418229Z","end":"2026-07-17T16:47:54.630754Z","steps":["trace[800546770] 'read index received' (duration: 59.464197ms)","trace[800546770] 'applied index is now lower than readState.Index' (duration: 153.060062ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:54.63081Z","caller":"traceutil/trace.go:171","msg":"trace[2121273704] transaction","detail":"{read_only:false; response_revision:29124; number_of_response:1; }","duration":"219.237336ms","start":"2026-07-17T16:47:54.41154Z","end":"2026-07-17T16:47:54.630777Z","steps":["trace[2121273704] 'process raft request' (duration: 219.082943ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:54.630887Z","caller":"traceutil/trace.go:171","msg":"trace[391307182] transaction","detail":"{read_only:false; response_revision:29125; number_of_response:1; }","duration":"218.9599ms","start":"2026-07-17T16:47:54.411891Z","end":"2026-07-17T16:47:54.630851Z","steps":["trace[391307182] 'process raft request' (duration: 218.797197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:54.630936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.689142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-07-17T16:47:54.630961Z","caller":"traceutil/trace.go:171","msg":"trace[690120724] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:29126; }","duration":"212.750043ms","start":"2026-07-17T16:47:54.418203Z","end":"2026-07-17T16:47:54.630953Z","steps":["trace[690120724] 'agreement among raft nodes before linearized reading' (duration: 212.62447ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:54.630948Z","caller":"traceutil/trace.go:171","msg":"trace[1138586218] transaction","detail":"{read_only:false; response_revision:29126; number_of_response:1; }","duration":"217.836859ms","start":"2026-07-17T16:47:54.413075Z","end":"2026-07-17T16:47:54.630912Z","steps":["trace[1138586218] 'process raft request' (duration: 217.642225ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:54.631077Z","caller":"traceutil/trace.go:171","msg":"trace[472569798] transaction","detail":"{read_only:false; response_revision:29123; number_of_response:1; }","duration":"219.610023ms","start":"2026-07-17T16:47:54.411453Z","end":"2026-07-17T16:47:54.631063Z","steps":["trace[472569798] 'process raft request' (duration: 147.366103ms)","trace[472569798] 'compare' (duration: 71.494566ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:56.13499Z","caller":"traceutil/trace.go:171","msg":"trace[291304218] transaction","detail":"{read_only:false; response_revision:29133; number_of_response:1; }","duration":"122.292138ms","start":"2026-07-17T16:47:56.012671Z","end":"2026-07-17T16:47:56.134963Z","steps":["trace[291304218] 'process raft request' (duration: 122.139155ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:56.313481Z","caller":"traceutil/trace.go:171","msg":"trace[213905949] transaction","detail":"{read_only:false; response_revision:29134; number_of_response:1; }","duration":"252.241711ms","start":"2026-07-17T16:47:56.061214Z","end":"2026-07-17T16:47:56.313456Z","steps":["trace[213905949] 'process raft request' (duration: 252.059218ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:56.325435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25377} {"level":"info","ts":"2026-07-17T16:47:56.325571Z","caller":"traceutil/trace.go:171","msg":"trace[179379366] linearizableReadLoop","detail":"{readStateIndex:30058; appliedIndex:30056; }","duration":"119.73225ms","start":"2026-07-17T16:47:56.205821Z","end":"2026-07-17T16:47:56.325554Z","steps":["trace[179379366] 'read index received' (duration: 107.497388ms)","trace[179379366] 'applied index is now lower than readState.Index' (duration: 12.234072ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:47:56.325644Z","caller":"traceutil/trace.go:171","msg":"trace[1759406721] compact","detail":"{revision:25377; response_revision:29135; }","duration":"189.664736ms","start":"2026-07-17T16:47:56.135971Z","end":"2026-07-17T16:47:56.325635Z","steps":["trace[1759406721] 'process raft request' (duration: 183.989598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:47:56.325776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.945524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-17T16:47:56.325806Z","caller":"traceutil/trace.go:171","msg":"trace[93657336] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:29135; }","duration":"120.033406ms","start":"2026-07-17T16:47:56.205765Z","end":"2026-07-17T16:47:56.325799Z","steps":["trace[93657336] 'agreement among raft nodes before linearized reading' (duration: 119.831142ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:56.517689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25377,"took":"188.435472ms","hash":2406778000,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":45985792,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-07-17T16:47:56.517746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2406778000,"revision":25377,"compact-revision":22792} {"level":"info","ts":"2026-07-17T16:47:58.528915Z","caller":"traceutil/trace.go:171","msg":"trace[280148095] transaction","detail":"{read_only:false; response_revision:29153; number_of_response:1; }","duration":"108.718111ms","start":"2026-07-17T16:47:58.420171Z","end":"2026-07-17T16:47:58.528889Z","steps":["trace[280148095] 'process raft request' (duration: 101.410102ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:58.531882Z","caller":"traceutil/trace.go:171","msg":"trace[109736929] transaction","detail":"{read_only:false; response_revision:29155; number_of_response:1; }","duration":"109.917294ms","start":"2026-07-17T16:47:58.421951Z","end":"2026-07-17T16:47:58.531868Z","steps":["trace[109736929] 'process raft request' (duration: 109.871893ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:58.531884Z","caller":"traceutil/trace.go:171","msg":"trace[387325494] transaction","detail":"{read_only:false; response_revision:29154; number_of_response:1; }","duration":"110.426223ms","start":"2026-07-17T16:47:58.421443Z","end":"2026-07-17T16:47:58.531869Z","steps":["trace[387325494] 'process raft request' (duration: 110.298881ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:47:58.532033Z","caller":"traceutil/trace.go:171","msg":"trace[1939080524] transaction","detail":"{read_only:false; response_revision:29156; number_of_response:1; }","duration":"106.761934ms","start":"2026-07-17T16:47:58.425247Z","end":"2026-07-17T16:47:58.532009Z","steps":["trace[1939080524] 'process raft request' (duration: 106.598591ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:01.362207Z","caller":"traceutil/trace.go:171","msg":"trace[4649199] transaction","detail":"{read_only:false; response_revision:29182; number_of_response:1; }","duration":"258.382008ms","start":"2026-07-17T16:48:01.103806Z","end":"2026-07-17T16:48:01.362188Z","steps":["trace[4649199] 'process raft request' (duration: 258.254945ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:01.682678Z","caller":"traceutil/trace.go:171","msg":"trace[638922031] linearizableReadLoop","detail":"{readStateIndex:30108; appliedIndex:30107; }","duration":"249.947268ms","start":"2026-07-17T16:48:01.432705Z","end":"2026-07-17T16:48:01.682652Z","steps":["trace[638922031] 'read index received' (duration: 175.006288ms)","trace[638922031] 'applied index is now lower than readState.Index' (duration: 74.93965ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:48:01.682714Z","caller":"traceutil/trace.go:171","msg":"trace[682395022] transaction","detail":"{read_only:false; response_revision:29183; number_of_response:1; }","duration":"257.535522ms","start":"2026-07-17T16:48:01.425131Z","end":"2026-07-17T16:48:01.682667Z","steps":["trace[682395022] 'process raft request' (duration: 182.571842ms)","trace[682395022] 'compare' (duration: 74.526182ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:48:01.682807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.08812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"info","ts":"2026-07-17T16:48:01.682842Z","caller":"traceutil/trace.go:171","msg":"trace[1061516377] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:29183; }","duration":"250.183612ms","start":"2026-07-17T16:48:01.43265Z","end":"2026-07-17T16:48:01.682834Z","steps":["trace[1061516377] 'agreement among raft nodes before linearized reading' (duration: 250.099711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:01.977659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.075815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:48:01.977779Z","caller":"traceutil/trace.go:171","msg":"trace[728595307] linearizableReadLoop","detail":"{readStateIndex:30109; appliedIndex:30108; }","duration":"204.880863ms","start":"2026-07-17T16:48:01.77288Z","end":"2026-07-17T16:48:01.977761Z","steps":["trace[728595307] 'read index received' (duration: 73.569634ms)","trace[728595307] 'applied index is now lower than readState.Index' (duration: 131.309569ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:48:01.97793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.047646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-17T16:48:01.977957Z","caller":"traceutil/trace.go:171","msg":"trace[1271583864] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:29184; }","duration":"205.109058ms","start":"2026-07-17T16:48:01.772838Z","end":"2026-07-17T16:48:01.977947Z","steps":["trace[1271583864] 'agreement among raft nodes before linearized reading' (duration: 204.969875ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:01.978093Z","caller":"traceutil/trace.go:171","msg":"trace[343379746] transaction","detail":"{read_only:false; response_revision:29184; number_of_response:1; }","duration":"210.937538ms","start":"2026-07-17T16:48:01.767115Z","end":"2026-07-17T16:48:01.978052Z","steps":["trace[343379746] 'process raft request' (duration: 79.342803ms)","trace[343379746] 'compare' (duration: 130.953783ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:48:06.648751Z","caller":"traceutil/trace.go:171","msg":"trace[1944134028] transaction","detail":"{read_only:false; response_revision:29219; number_of_response:1; }","duration":"293.001263ms","start":"2026-07-17T16:48:06.355715Z","end":"2026-07-17T16:48:06.648716Z","steps":["trace[1944134028] 'process raft request' (duration: 260.771973ms)","trace[1944134028] 'compare' (duration: 32.101538ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:48:06.925978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.320158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:48:06.926102Z","caller":"traceutil/trace.go:171","msg":"trace[832116034] linearizableReadLoop","detail":"{readStateIndex:30150; appliedIndex:30149; }","duration":"238.42457ms","start":"2026-07-17T16:48:06.687662Z","end":"2026-07-17T16:48:06.926086Z","steps":["trace[832116034] 'read index received' (duration: 95.924159ms)","trace[832116034] 'applied index is now lower than readState.Index' (duration: 142.498801ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:48:06.926191Z","caller":"traceutil/trace.go:171","msg":"trace[1400836872] transaction","detail":"{read_only:false; response_revision:29224; number_of_response:1; }","duration":"272.623467ms","start":"2026-07-17T16:48:06.653556Z","end":"2026-07-17T16:48:06.926179Z","steps":["trace[1400836872] 'process raft request' (duration: 130.039575ms)","trace[1400836872] 'compare' (duration: 142.008252ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:48:06.926458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.932476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-17T16:48:06.926569Z","caller":"traceutil/trace.go:171","msg":"trace[539120933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29224; }","duration":"232.037229ms","start":"2026-07-17T16:48:06.694486Z","end":"2026-07-17T16:48:06.926524Z","steps":["trace[539120933] 'agreement among raft nodes before linearized reading' (duration: 231.755474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:06.926552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.327359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T16:48:06.926643Z","caller":"traceutil/trace.go:171","msg":"trace[798026189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29224; }","duration":"217.460282ms","start":"2026-07-17T16:48:06.70916Z","end":"2026-07-17T16:48:06.92662Z","steps":["trace[798026189] 'agreement among raft nodes before linearized reading' (duration: 217.173606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:06.926672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.446301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T16:48:06.926729Z","caller":"traceutil/trace.go:171","msg":"trace[950610420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29224; }","duration":"226.540103ms","start":"2026-07-17T16:48:06.700178Z","end":"2026-07-17T16:48:06.926718Z","steps":["trace[950610420] 'agreement among raft nodes before linearized reading' (duration: 226.35845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:06.926803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.140093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:48:06.926843Z","caller":"traceutil/trace.go:171","msg":"trace[1273895889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29224; }","duration":"239.215694ms","start":"2026-07-17T16:48:06.687617Z","end":"2026-07-17T16:48:06.926833Z","steps":["trace[1273895889] 'agreement among raft nodes before linearized reading' (duration: 239.156573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:06.926875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.945946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T16:48:06.92691Z","caller":"traceutil/trace.go:171","msg":"trace[1729123102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29224; }","duration":"232.005817ms","start":"2026-07-17T16:48:06.694895Z","end":"2026-07-17T16:48:06.9269Z","steps":["trace[1729123102] 'agreement among raft nodes before linearized reading' (duration: 231.920405ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:07.191267Z","caller":"traceutil/trace.go:171","msg":"trace[1267972508] transaction","detail":"{read_only:false; response_revision:29225; number_of_response:1; }","duration":"261.058578ms","start":"2026-07-17T16:48:06.930184Z","end":"2026-07-17T16:48:07.191242Z","steps":["trace[1267972508] 'process raft request' (duration: 260.890975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:07.547064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.809563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-17T16:48:07.547145Z","caller":"traceutil/trace.go:171","msg":"trace[510682372] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; }","duration":"500.933615ms","start":"2026-07-17T16:48:07.046194Z","end":"2026-07-17T16:48:07.547128Z","steps":["trace[510682372] 'agreement among raft nodes before linearized reading' (duration: 500.834933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:07.547192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:48:07.046149Z","time spent":"501.033857ms","remote":"127.0.0.1:48612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":0,"request content":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" "} 2026/07/17 16:48:07 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-07-17T16:48:07.562749Z","caller":"traceutil/trace.go:171","msg":"trace[395373999] linearizableReadLoop","detail":"{readStateIndex:30154; appliedIndex:30150; }","duration":"576.202491ms","start":"2026-07-17T16:48:06.986527Z","end":"2026-07-17T16:48:07.562729Z","steps":["trace[395373999] 'read index received' (duration: 204.564997ms)","trace[395373999] 'applied index is now lower than readState.Index' (duration: 371.636524ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:48:07.563007Z","caller":"traceutil/trace.go:171","msg":"trace[25183735] transaction","detail":"{read_only:false; response_revision:29226; number_of_response:1; }","duration":"632.334836ms","start":"2026-07-17T16:48:06.930654Z","end":"2026-07-17T16:48:07.562988Z","steps":["trace[25183735] 'process raft request' (duration: 613.243754ms)","trace[25183735] 'compare' (duration: 18.369968ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:48:07.563136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:48:06.93063Z","time spent":"632.426928ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:48:07.563354Z","caller":"traceutil/trace.go:171","msg":"trace[1287207773] transaction","detail":"{read_only:false; response_revision:29227; number_of_response:1; }","duration":"632.629702ms","start":"2026-07-17T16:48:06.93071Z","end":"2026-07-17T16:48:07.563339Z","steps":["trace[1287207773] 'process raft request' (duration: 631.871647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:07.563435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:48:06.930694Z","time spent":"632.701324ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:48:07.563604Z","caller":"traceutil/trace.go:171","msg":"trace[340966608] transaction","detail":"{read_only:false; response_revision:29228; number_of_response:1; }","duration":"632.705293ms","start":"2026-07-17T16:48:06.930885Z","end":"2026-07-17T16:48:07.563591Z","steps":["trace[340966608] 'process raft request' (duration: 631.741084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:07.563741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:48:06.93086Z","time spent":"632.782634ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:48:07.563918Z","caller":"traceutil/trace.go:171","msg":"trace[1542995017] transaction","detail":"{read_only:false; response_revision:29229; number_of_response:1; }","duration":"518.510438ms","start":"2026-07-17T16:48:07.045394Z","end":"2026-07-17T16:48:07.563904Z","steps":["trace[1542995017] 'process raft request' (duration: 517.265514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:07.564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:48:07.045368Z","time spent":"518.59142ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:48:07.564144Z","caller":"traceutil/trace.go:171","msg":"trace[328395875] transaction","detail":"{read_only:false; response_revision:29230; number_of_response:1; }","duration":"508.589371ms","start":"2026-07-17T16:48:07.055545Z","end":"2026-07-17T16:48:07.564134Z","steps":["trace[328395875] 'process raft request' (duration: 507.148393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:07.564209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:48:07.055523Z","time spent":"508.651752ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":483,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:48:07.565081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.548546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-17T16:48:07.565132Z","caller":"traceutil/trace.go:171","msg":"trace[670879647] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29230; }","duration":"578.654028ms","start":"2026-07-17T16:48:06.986467Z","end":"2026-07-17T16:48:07.565121Z","steps":["trace[670879647] 'agreement among raft nodes before linearized reading' (duration: 577.850263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:07.565166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:48:06.986436Z","time spent":"578.720269ms","remote":"127.0.0.1:60700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-17T16:48:07.618245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.136914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:48:07.61825Z","caller":"traceutil/trace.go:171","msg":"trace[517146569] transaction","detail":"{read_only:false; response_revision:29231; number_of_response:1; }","duration":"241.10921ms","start":"2026-07-17T16:48:07.3771Z","end":"2026-07-17T16:48:07.618209Z","steps":["trace[517146569] 'process raft request' (duration: 240.895856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:07.618286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.136203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:48:07.618324Z","caller":"traceutil/trace.go:171","msg":"trace[1471698210] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:29231; }","duration":"257.235786ms","start":"2026-07-17T16:48:07.361069Z","end":"2026-07-17T16:48:07.618305Z","steps":["trace[1471698210] 'agreement among raft nodes before linearized reading' (duration: 257.099363ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:07.618521Z","caller":"traceutil/trace.go:171","msg":"trace[304158606] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:29231; }","duration":"267.255036ms","start":"2026-07-17T16:48:07.351088Z","end":"2026-07-17T16:48:07.618343Z","steps":["trace[304158606] 'agreement among raft nodes before linearized reading' (duration: 267.108503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:07.618597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.581667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:48:07.61865Z","caller":"traceutil/trace.go:171","msg":"trace[623891793] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:29231; }","duration":"310.662519ms","start":"2026-07-17T16:48:07.307969Z","end":"2026-07-17T16:48:07.618632Z","steps":["trace[623891793] 'agreement among raft nodes before linearized reading' (duration: 310.585768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:07.618683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:48:07.30795Z","time spent":"310.72235ms","remote":"127.0.0.1:43500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-07-17T16:48:07.814034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.933359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:48:07.814117Z","caller":"traceutil/trace.go:171","msg":"trace[678375532] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:29231; }","duration":"134.056221ms","start":"2026-07-17T16:48:07.680042Z","end":"2026-07-17T16:48:07.814098Z","steps":["trace[678375532] 'range keys from in-memory index tree' (duration: 133.859357ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:07.814123Z","caller":"traceutil/trace.go:171","msg":"trace[1755705959] linearizableReadLoop","detail":"{readStateIndex:30158; appliedIndex:30157; }","duration":"111.85214ms","start":"2026-07-17T16:48:07.702248Z","end":"2026-07-17T16:48:07.8141Z","steps":["trace[1755705959] 'read index received' (duration: 72.932673ms)","trace[1755705959] 'applied index is now lower than readState.Index' (duration: 38.916057ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:48:07.814224Z","caller":"traceutil/trace.go:171","msg":"trace[1030659818] transaction","detail":"{read_only:false; response_revision:29232; number_of_response:1; }","duration":"112.754117ms","start":"2026-07-17T16:48:07.701434Z","end":"2026-07-17T16:48:07.814188Z","steps":["trace[1030659818] 'process raft request' (duration: 73.88677ms)","trace[1030659818] 'compare' (duration: 38.662443ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:48:07.814254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.989343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:48:07.814306Z","caller":"traceutil/trace.go:171","msg":"trace[1190403752] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:29232; }","duration":"112.077205ms","start":"2026-07-17T16:48:07.702217Z","end":"2026-07-17T16:48:07.814294Z","steps":["trace[1190403752] 'agreement among raft nodes before linearized reading' (duration: 111.963403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:11.696803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.623431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-17T16:48:11.696808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.108825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T16:48:11.696854Z","caller":"traceutil/trace.go:171","msg":"trace[149811376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29260; }","duration":"115.716043ms","start":"2026-07-17T16:48:11.581125Z","end":"2026-07-17T16:48:11.696841Z","steps":["trace[149811376] 'agreement among raft nodes before linearized reading' (duration: 115.59621ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:11.696866Z","caller":"traceutil/trace.go:171","msg":"trace[1429160739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29260; }","duration":"112.175646ms","start":"2026-07-17T16:48:11.584674Z","end":"2026-07-17T16:48:11.69685Z","steps":["trace[1429160739] 'agreement among raft nodes before linearized reading' (duration: 112.008033ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:11.696956Z","caller":"traceutil/trace.go:171","msg":"trace[347419301] transaction","detail":"{read_only:false; response_revision:29260; number_of_response:1; }","duration":"116.13186ms","start":"2026-07-17T16:48:11.580791Z","end":"2026-07-17T16:48:11.696923Z","steps":["trace[347419301] 'process raft request' (duration: 95.286676ms)","trace[347419301] 'compare' (duration: 20.452877ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:48:11.765029Z","caller":"traceutil/trace.go:171","msg":"trace[1589349382] transaction","detail":"{read_only:false; response_revision:29261; number_of_response:1; }","duration":"148.158348ms","start":"2026-07-17T16:48:11.616835Z","end":"2026-07-17T16:48:11.764993Z","steps":["trace[1589349382] 'process raft request' (duration: 147.888543ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:11.765082Z","caller":"traceutil/trace.go:171","msg":"trace[357047373] transaction","detail":"{read_only:false; response_revision:29262; number_of_response:1; }","duration":"132.33958ms","start":"2026-07-17T16:48:11.632714Z","end":"2026-07-17T16:48:11.765054Z","steps":["trace[357047373] 'process raft request' (duration: 132.184246ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:11.76578Z","caller":"traceutil/trace.go:171","msg":"trace[2035324842] transaction","detail":"{read_only:false; response_revision:29263; number_of_response:1; }","duration":"113.349149ms","start":"2026-07-17T16:48:11.652415Z","end":"2026-07-17T16:48:11.765765Z","steps":["trace[2035324842] 'process raft request' (duration: 112.525603ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:14.161655Z","caller":"traceutil/trace.go:171","msg":"trace[937859061] transaction","detail":"{read_only:false; response_revision:29285; number_of_response:1; }","duration":"233.72995ms","start":"2026-07-17T16:48:13.927899Z","end":"2026-07-17T16:48:14.161629Z","steps":["trace[937859061] 'process raft request' (duration: 233.586298ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:20.639495Z","caller":"traceutil/trace.go:171","msg":"trace[2107451444] transaction","detail":"{read_only:false; response_revision:29334; number_of_response:1; }","duration":"176.509626ms","start":"2026-07-17T16:48:20.462961Z","end":"2026-07-17T16:48:20.63947Z","steps":["trace[2107451444] 'process raft request' (duration: 176.272672ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:48:21.765795Z","caller":"traceutil/trace.go:171","msg":"trace[906980208] linearizableReadLoop","detail":"{readStateIndex:30271; appliedIndex:30270; }","duration":"119.353412ms","start":"2026-07-17T16:48:21.64642Z","end":"2026-07-17T16:48:21.765774Z","steps":["trace[906980208] 'read index received' (duration: 119.21778ms)","trace[906980208] 'applied index is now lower than readState.Index' (duration: 134.732µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:48:21.765852Z","caller":"traceutil/trace.go:171","msg":"trace[638142105] transaction","detail":"{read_only:false; response_revision:29342; number_of_response:1; }","duration":"125.371416ms","start":"2026-07-17T16:48:21.640441Z","end":"2026-07-17T16:48:21.765813Z","steps":["trace[638142105] 'process raft request' (duration: 125.196763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:48:21.766386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.922434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:48:21.76651Z","caller":"traceutil/trace.go:171","msg":"trace[1937785766] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:29342; }","duration":"120.103817ms","start":"2026-07-17T16:48:21.646387Z","end":"2026-07-17T16:48:21.766491Z","steps":["trace[1937785766] 'agreement among raft nodes before linearized reading' (duration: 119.662868ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:52:56.331241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29133} {"level":"info","ts":"2026-07-17T16:52:56.50832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29133,"took":"172.265172ms","hash":1502503318,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":34906112,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-17T16:52:56.508489Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1502503318,"revision":29133,"compact-revision":25377}