{"level":"warn","ts":"2026-07-09T16:36:21.262258Z","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-09T16:36:21.262431Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-09T16:36:21.262539Z","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-09T16:36:21.26256Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-09T16:36:21.262588Z","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-09T16:36:21.263565Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-07-09T16:36:21.263682Z","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":8,"max-cpu-available":8,"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.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-09T16:36:21.268289Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.305655ms"} {"level":"info","ts":"2026-07-09T16:36:21.274365Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-07-09T16:36:21.274442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T16:36:21.274469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-07-09T16:36:21.274482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T16:36:21.274496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-07-09T16:36:21.274534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-07-09T16:36:21.287617Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T16:36:21.289058Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T16:36:21.290405Z","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-09T16:36:21.292545Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T16:36:21.293504Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T16:36:21.293588Z","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-09T16:36:21.293626Z","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-09T16:36:21.293635Z","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-09T16:36:21.294325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-07-09T16:36:21.2945Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-09T16:36:21.296718Z","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-09T16:36:21.296868Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-09T16:36:21.29692Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-09T16:36:21.297155Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T16:36:21.2972Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T16:36:21.674897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T16:36:21.675048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T16:36:21.675086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-07-09T16:36:21.675137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-07-09T16:36:21.675276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-09T16:36:21.675317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-07-09T16:36:21.675367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-09T16:36:21.67694Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T16:36:21.67701Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:36:21.677285Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:36:21.677381Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:36:21.677919Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T16:36:21.677943Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T16:36:21.680287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T16:36:21.680382Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:36:21.680456Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:36:21.680491Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:36:21.681664Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-07-09T16:36:25.936799Z","caller":"traceutil/trace.go:171","msg":"trace[1142704575] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"323.778925ms","start":"2026-07-09T16:36:25.612957Z","end":"2026-07-09T16:36:25.936736Z","steps":["trace[1142704575] 'process raft request' (duration: 323.61973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:25.937615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:25.612939Z","time spent":"323.963342ms","remote":"127.0.0.1:53410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:36:26.939857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.543752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:36:26.940012Z","caller":"traceutil/trace.go:171","msg":"trace[1719741276] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"883.33243ms","start":"2026-07-09T16:36:26.056656Z","end":"2026-07-09T16:36:26.939989Z","steps":["trace[1719741276] 'read index received' (duration: 195.234438ms)","trace[1719741276] 'applied index is now lower than readState.Index' (duration: 688.096062ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:36:26.940035Z","caller":"traceutil/trace.go:171","msg":"trace[2083166663] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"995.44177ms","start":"2026-07-09T16:36:25.94456Z","end":"2026-07-09T16:36:26.940001Z","steps":["trace[2083166663] 'process raft request' (duration: 307.398931ms)","trace[2083166663] 'compare' (duration: 687.313404ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:36:26.94018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:25.944539Z","time spent":"995.563095ms","remote":"127.0.0.1:53318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:36:26.940217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.575989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:36:26.940254Z","caller":"traceutil/trace.go:171","msg":"trace[760105038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:205; }","duration":"883.660301ms","start":"2026-07-09T16:36:26.056581Z","end":"2026-07-09T16:36:26.940242Z","steps":["trace[760105038] 'agreement among raft nodes before linearized reading' (duration: 883.535167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:26.940305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:26.056563Z","time spent":"883.732393ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:36:26.940448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.119308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:36:26.940487Z","caller":"traceutil/trace.go:171","msg":"trace[722098409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:205; }","duration":"468.2025ms","start":"2026-07-09T16:36:26.472272Z","end":"2026-07-09T16:36:26.940474Z","steps":["trace[722098409] 'agreement among raft nodes before linearized reading' (duration: 468.124217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:26.94052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:26.472243Z","time spent":"468.265723ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:36:26.940625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.761746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:36:26.9408Z","caller":"traceutil/trace.go:171","msg":"trace[1433041841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:205; }","duration":"144.961484ms","start":"2026-07-09T16:36:26.795775Z","end":"2026-07-09T16:36:26.940737Z","steps":["trace[1433041841] 'agreement among raft nodes before linearized reading' (duration: 144.665823ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:03.26397Z","caller":"traceutil/trace.go:171","msg":"trace[169410158] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"148.30192ms","start":"2026-07-09T16:37:03.115646Z","end":"2026-07-09T16:37:03.263948Z","steps":["trace[169410158] 'read index received' (duration: 147.594193ms)","trace[169410158] 'applied index is now lower than readState.Index' (duration: 706.467µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:03.264039Z","caller":"traceutil/trace.go:171","msg":"trace[1353612711] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"207.886347ms","start":"2026-07-09T16:37:03.056122Z","end":"2026-07-09T16:37:03.264008Z","steps":["trace[1353612711] 'process raft request' (duration: 207.342946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:03.264166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.492627ms","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-09T16:37:03.264207Z","caller":"traceutil/trace.go:171","msg":"trace[21467669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"148.598761ms","start":"2026-07-09T16:37:03.115599Z","end":"2026-07-09T16:37:03.264197Z","steps":["trace[21467669] 'agreement among raft nodes before linearized reading' (duration: 148.471986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:03.264363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.557067ms","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-09T16:37:03.264417Z","caller":"traceutil/trace.go:171","msg":"trace[1438180380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"132.63903ms","start":"2026-07-09T16:37:03.131765Z","end":"2026-07-09T16:37:03.264404Z","steps":["trace[1438180380] 'agreement among raft nodes before linearized reading' (duration: 132.545517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:03.264363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.197198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:37:03.26465Z","caller":"traceutil/trace.go:171","msg":"trace[1834405670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"136.550282ms","start":"2026-07-09T16:37:03.128089Z","end":"2026-07-09T16:37:03.264639Z","steps":["trace[1834405670] 'agreement among raft nodes before linearized reading' (duration: 136.178667ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:05.506785Z","caller":"traceutil/trace.go:171","msg":"trace[1444617463] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"476.058092ms","start":"2026-07-09T16:37:05.030695Z","end":"2026-07-09T16:37:05.506753Z","steps":["trace[1444617463] 'process raft request' (duration: 472.91689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.506942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.030675Z","time spent":"476.192507ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:05.512001Z","caller":"traceutil/trace.go:171","msg":"trace[453070655] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"473.079275ms","start":"2026-07-09T16:37:05.038883Z","end":"2026-07-09T16:37:05.511962Z","steps":["trace[453070655] 'process raft request' (duration: 472.904569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.512149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.038873Z","time spent":"473.20284ms","remote":"127.0.0.1:53760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:05.512203Z","caller":"traceutil/trace.go:171","msg":"trace[1847885632] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"473.235681ms","start":"2026-07-09T16:37:05.038949Z","end":"2026-07-09T16:37:05.512185Z","steps":["trace[1847885632] 'process raft request' (duration: 472.859317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.512268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.038943Z","time spent":"473.298814ms","remote":"127.0.0.1:53760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:05.512268Z","caller":"traceutil/trace.go:171","msg":"trace[2138864350] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"466.892334ms","start":"2026-07-09T16:37:05.045329Z","end":"2026-07-09T16:37:05.512221Z","steps":["trace[2138864350] 'process raft request' (duration: 466.55236ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:05.512428Z","caller":"traceutil/trace.go:171","msg":"trace[1946256007] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"463.071524ms","start":"2026-07-09T16:37:05.049345Z","end":"2026-07-09T16:37:05.512416Z","steps":["trace[1946256007] 'process raft request' (duration: 462.589526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.512485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.045315Z","time spent":"467.05452ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:37:05.512533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.049207Z","time spent":"463.270513ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:05.512175Z","caller":"traceutil/trace.go:171","msg":"trace[169265614] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"480.876599ms","start":"2026-07-09T16:37:05.031279Z","end":"2026-07-09T16:37:05.512156Z","steps":["trace[169265614] 'process raft request' (duration: 480.422142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.512857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.031271Z","time spent":"481.557386ms","remote":"127.0.0.1:53760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:05.513035Z","caller":"traceutil/trace.go:171","msg":"trace[1122169857] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"462.819554ms","start":"2026-07-09T16:37:05.050203Z","end":"2026-07-09T16:37:05.513023Z","steps":["trace[1122169857] 'process raft request' (duration: 462.45149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.513105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.050195Z","time spent":"462.874676ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:05.513146Z","caller":"traceutil/trace.go:171","msg":"trace[1743433041] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"458.743936ms","start":"2026-07-09T16:37:05.054395Z","end":"2026-07-09T16:37:05.513139Z","steps":["trace[1743433041] 'process raft request' (duration: 458.419373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.513192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.054371Z","time spent":"458.795477ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:05.51285Z","caller":"traceutil/trace.go:171","msg":"trace[655510332] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"463.432208ms","start":"2026-07-09T16:37:05.049384Z","end":"2026-07-09T16:37:05.512816Z","steps":["trace[655510332] 'process raft request' (duration: 462.599835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.514276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.049348Z","time spent":"464.801871ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:05.513105Z","caller":"traceutil/trace.go:171","msg":"trace[1661771029] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"472.463201ms","start":"2026-07-09T16:37:05.040622Z","end":"2026-07-09T16:37:05.513085Z","steps":["trace[1661771029] 'process raft request' (duration: 471.215802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.516788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.040608Z","time spent":"476.103483ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:05.518255Z","caller":"traceutil/trace.go:171","msg":"trace[37293708] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"439.595146ms","start":"2026-07-09T16:37:05.078641Z","end":"2026-07-09T16:37:05.518236Z","steps":["trace[37293708] 'process raft request' (duration: 434.238518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.518334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.078609Z","time spent":"439.69088ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:05.518649Z","caller":"traceutil/trace.go:171","msg":"trace[879422924] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"357.454238ms","start":"2026-07-09T16:37:05.161163Z","end":"2026-07-09T16:37:05.518618Z","steps":["trace[879422924] 'process raft request' (duration: 356.568903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.518778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:05.161129Z","time spent":"357.588513ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:05.518533Z","caller":"traceutil/trace.go:171","msg":"trace[2087731172] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:568; }","duration":"232.675741ms","start":"2026-07-09T16:37:05.285842Z","end":"2026-07-09T16:37:05.518518Z","steps":["trace[2087731172] 'read index received' (duration: 217.685065ms)","trace[2087731172] 'applied index is now lower than readState.Index' (duration: 14.989606ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:05.518974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.11798ms","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-09T16:37:05.519037Z","caller":"traceutil/trace.go:171","msg":"trace[1492951962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"233.225734ms","start":"2026-07-09T16:37:05.285796Z","end":"2026-07-09T16:37:05.519022Z","steps":["trace[1492951962] 'agreement among raft nodes before linearized reading' (duration: 233.070667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.521183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.231113ms","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-09T16:37:05.521216Z","caller":"traceutil/trace.go:171","msg":"trace[896388357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"235.305585ms","start":"2026-07-09T16:37:05.285903Z","end":"2026-07-09T16:37:05.521208Z","steps":["trace[896388357] 'agreement among raft nodes before linearized reading' (duration: 235.229032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:05.521405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.080446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:37:05.521425Z","caller":"traceutil/trace.go:171","msg":"trace[115964261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"235.152679ms","start":"2026-07-09T16:37:05.286267Z","end":"2026-07-09T16:37:05.52142Z","steps":["trace[115964261] 'agreement among raft nodes before linearized reading' (duration: 235.118998ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:11.679433Z","caller":"traceutil/trace.go:171","msg":"trace[1759776957] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"166.477296ms","start":"2026-07-09T16:37:11.51292Z","end":"2026-07-09T16:37:11.679397Z","steps":["trace[1759776957] 'process raft request' (duration: 165.133143ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:11.819483Z","caller":"traceutil/trace.go:171","msg":"trace[926255085] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"134.383746ms","start":"2026-07-09T16:37:11.685075Z","end":"2026-07-09T16:37:11.819458Z","steps":["trace[926255085] 'process raft request' (duration: 134.200399ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:11.81986Z","caller":"traceutil/trace.go:171","msg":"trace[1584688317] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"133.21838ms","start":"2026-07-09T16:37:11.686575Z","end":"2026-07-09T16:37:11.819793Z","steps":["trace[1584688317] 'process raft request' (duration: 132.847555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:13.198762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266666385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:13.198875Z","caller":"traceutil/trace.go:171","msg":"trace[354580144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"1.266794939s","start":"2026-07-09T16:37:11.932036Z","end":"2026-07-09T16:37:13.198831Z","steps":["trace[354580144] 'range keys from in-memory index tree' (duration: 1.266584122s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:13.19922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:11.93202Z","time spent":"1.267183795s","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:37:13.201414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203311299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pl67x\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-09T16:37:13.201558Z","caller":"traceutil/trace.go:171","msg":"trace[588006740] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pl67x; range_end:; response_count:1; response_revision:649; }","duration":"1.203423543s","start":"2026-07-09T16:37:11.998047Z","end":"2026-07-09T16:37:13.201471Z","steps":["trace[588006740] 'range keys from in-memory index tree' (duration: 1.203032738s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:13.201668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:11.998025Z","time spent":"1.203575659s","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-pl67x\" "} {"level":"warn","ts":"2026-07-09T16:37:13.204941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.63155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:37:13.205125Z","caller":"traceutil/trace.go:171","msg":"trace[1165742248] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"942.573535ms","start":"2026-07-09T16:37:12.262518Z","end":"2026-07-09T16:37:13.205091Z","steps":["trace[1165742248] 'process raft request' (duration: 200.657153ms)","trace[1165742248] 'compare' (duration: 739.787437ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:13.205242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:12.262479Z","time spent":"942.70172ms","remote":"127.0.0.1:53318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:13.657663Z","caller":"traceutil/trace.go:171","msg":"trace[1137982258] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"441.389978ms","start":"2026-07-09T16:37:13.216241Z","end":"2026-07-09T16:37:13.657631Z","steps":["trace[1137982258] 'process raft request' (duration: 441.166599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:13.657903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:13.216218Z","time spent":"441.573645ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:13.660802Z","caller":"traceutil/trace.go:171","msg":"trace[257316586] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"391.10721ms","start":"2026-07-09T16:37:13.269668Z","end":"2026-07-09T16:37:13.660775Z","steps":["trace[257316586] 'process raft request' (duration: 390.942073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:13.660964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:13.269633Z","time spent":"391.249756ms","remote":"127.0.0.1:53456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:29.333811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.1856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:29.333905Z","caller":"traceutil/trace.go:171","msg":"trace[365721694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"400.341947ms","start":"2026-07-09T16:37:28.933542Z","end":"2026-07-09T16:37:29.333884Z","steps":["trace[365721694] 'range keys from in-memory index tree' (duration: 400.077114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.333952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.933521Z","time spent":"400.421679ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:37:29.333889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.215041ms","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-09T16:37:29.334034Z","caller":"traceutil/trace.go:171","msg":"trace[1541215366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:807; }","duration":"401.41824ms","start":"2026-07-09T16:37:28.932589Z","end":"2026-07-09T16:37:29.334008Z","steps":["trace[1541215366] 'range keys from in-memory index tree' (duration: 401.018923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.334191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.932568Z","time spent":"401.569456ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T16:37:29.452565Z","caller":"traceutil/trace.go:171","msg":"trace[2058140281] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"466.675953ms","start":"2026-07-09T16:37:28.985861Z","end":"2026-07-09T16:37:29.452537Z","steps":["trace[2058140281] 'read index received' (duration: 466.58756ms)","trace[2058140281] 'applied index is now lower than readState.Index' (duration: 86.753µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:29.452827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.939404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:29.452918Z","caller":"traceutil/trace.go:171","msg":"trace[88141989] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:808; }","duration":"467.094961ms","start":"2026-07-09T16:37:28.985808Z","end":"2026-07-09T16:37:29.452903Z","steps":["trace[88141989] 'agreement among raft nodes before linearized reading' (duration: 466.886872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.452961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.98579Z","time spent":"467.159062ms","remote":"127.0.0.1:53638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:37:29.452816Z","caller":"traceutil/trace.go:171","msg":"trace[1699427493] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"467.070659ms","start":"2026-07-09T16:37:28.985625Z","end":"2026-07-09T16:37:29.452695Z","steps":["trace[1699427493] 'process raft request' (duration: 466.745886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.453458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.985601Z","time spent":"467.671463ms","remote":"127.0.0.1:53410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4904,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:29.458626Z","caller":"traceutil/trace.go:171","msg":"trace[478243320] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"354.271423ms","start":"2026-07-09T16:37:29.104319Z","end":"2026-07-09T16:37:29.45859Z","steps":["trace[478243320] 'process raft request' (duration: 354.039683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.458731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.342948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:29.458771Z","caller":"traceutil/trace.go:171","msg":"trace[261498130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"123.437551ms","start":"2026-07-09T16:37:29.335324Z","end":"2026-07-09T16:37:29.458761Z","steps":["trace[261498130] 'agreement among raft nodes before linearized reading' (duration: 123.358929ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:29.458625Z","caller":"traceutil/trace.go:171","msg":"trace[209338642] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"119.683269ms","start":"2026-07-09T16:37:29.338916Z","end":"2026-07-09T16:37:29.4586Z","steps":["trace[209338642] 'process raft request' (duration: 119.572875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.458838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:29.104309Z","time spent":"354.392617ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:37:29.458954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.319799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9b2gz\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-09T16:37:29.459077Z","caller":"traceutil/trace.go:171","msg":"trace[1003765473] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9b2gz; range_end:; response_count:1; response_revision:810; }","duration":"361.736267ms","start":"2026-07-09T16:37:29.097322Z","end":"2026-07-09T16:37:29.459059Z","steps":["trace[1003765473] 'agreement among raft nodes before linearized reading' (duration: 361.210055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.459181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:29.097311Z","time spent":"361.855671ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9b2gz\" "} {"level":"info","ts":"2026-07-09T16:38:07.885227Z","caller":"traceutil/trace.go:171","msg":"trace[234652654] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"124.078723ms","start":"2026-07-09T16:38:07.761115Z","end":"2026-07-09T16:38:07.885194Z","steps":["trace[234652654] 'process raft request' (duration: 123.902505ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:22.660438Z","caller":"traceutil/trace.go:171","msg":"trace[587889422] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"181.765239ms","start":"2026-07-09T16:38:22.478645Z","end":"2026-07-09T16:38:22.66041Z","steps":["trace[587889422] 'process raft request' (duration: 181.582371ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:53.677163Z","caller":"traceutil/trace.go:171","msg":"trace[421259708] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"146.177984ms","start":"2026-07-09T16:38:53.530951Z","end":"2026-07-09T16:38:53.677129Z","steps":["trace[421259708] 'process raft request' (duration: 145.63243ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:53.913741Z","caller":"traceutil/trace.go:171","msg":"trace[247453642] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"145.261833ms","start":"2026-07-09T16:38:53.76842Z","end":"2026-07-09T16:38:53.913682Z","steps":["trace[247453642] 'process raft request' (duration: 133.875269ms)","trace[247453642] 'compare' (duration: 11.082542ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:39:00.865743Z","caller":"traceutil/trace.go:171","msg":"trace[93566151] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"128.78102ms","start":"2026-07-09T16:39:00.736932Z","end":"2026-07-09T16:39:00.865713Z","steps":["trace[93566151] 'process raft request' (duration: 128.53456ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:02.040354Z","caller":"traceutil/trace.go:171","msg":"trace[1293016537] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"101.569587ms","start":"2026-07-09T16:39:01.93876Z","end":"2026-07-09T16:39:02.04033Z","steps":["trace[1293016537] 'process raft request' (duration: 101.309866ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:06.352278Z","caller":"traceutil/trace.go:171","msg":"trace[1238700527] linearizableReadLoop","detail":"{readStateIndex:2090; appliedIndex:2089; }","duration":"281.402563ms","start":"2026-07-09T16:39:06.070854Z","end":"2026-07-09T16:39:06.352257Z","steps":["trace[1238700527] 'read index received' (duration: 281.194074ms)","trace[1238700527] 'applied index is now lower than readState.Index' (duration: 207.649µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:39:06.352412Z","caller":"traceutil/trace.go:171","msg":"trace[1652218628] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"299.242661ms","start":"2026-07-09T16:39:06.053153Z","end":"2026-07-09T16:39:06.352396Z","steps":["trace[1652218628] 'process raft request' (duration: 298.974459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:06.352946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.080202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-09T16:39:06.352992Z","caller":"traceutil/trace.go:171","msg":"trace[1878790617] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2014; }","duration":"282.226569ms","start":"2026-07-09T16:39:06.07075Z","end":"2026-07-09T16:39:06.352977Z","steps":["trace[1878790617] 'agreement among raft nodes before linearized reading' (duration: 282.082022ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:22.685163Z","caller":"traceutil/trace.go:171","msg":"trace[1476914836] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2197; }","duration":"225.736715ms","start":"2026-07-09T16:39:22.459402Z","end":"2026-07-09T16:39:22.685139Z","steps":["trace[1476914836] 'read index received' (duration: 216.143723ms)","trace[1476914836] 'applied index is now lower than readState.Index' (duration: 9.592032ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:39:22.685402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.305591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-09T16:39:22.685452Z","caller":"traceutil/trace.go:171","msg":"trace[1503262790] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2119; }","duration":"223.396833ms","start":"2026-07-09T16:39:22.462041Z","end":"2026-07-09T16:39:22.685438Z","steps":["trace[1503262790] 'agreement among raft nodes before linearized reading' (duration: 223.239912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:22.685656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.591615ms","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-09T16:39:22.685691Z","caller":"traceutil/trace.go:171","msg":"trace[1222893630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2119; }","duration":"141.662235ms","start":"2026-07-09T16:39:22.54402Z","end":"2026-07-09T16:39:22.685682Z","steps":["trace[1222893630] 'agreement among raft nodes before linearized reading' (duration: 141.577214ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:22.685327Z","caller":"traceutil/trace.go:171","msg":"trace[45834271] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"232.744934ms","start":"2026-07-09T16:39:22.452517Z","end":"2026-07-09T16:39:22.685262Z","steps":["trace[45834271] 'process raft request' (duration: 223.116572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:22.686104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.667907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-09T16:39:22.686175Z","caller":"traceutil/trace.go:171","msg":"trace[779239864] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2119; }","duration":"226.776297ms","start":"2026-07-09T16:39:22.45938Z","end":"2026-07-09T16:39:22.686156Z","steps":["trace[779239864] 'agreement among raft nodes before linearized reading' (duration: 225.879605ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:28.017113Z","caller":"traceutil/trace.go:171","msg":"trace[1805913608] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2228; }","duration":"173.645136ms","start":"2026-07-09T16:39:27.843433Z","end":"2026-07-09T16:39:28.017078Z","steps":["trace[1805913608] 'read index received' (duration: 173.213985ms)","trace[1805913608] 'applied index is now lower than readState.Index' (duration: 429.721µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:39:28.017298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.828348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:39:28.017338Z","caller":"traceutil/trace.go:171","msg":"trace[1878282021] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2149; }","duration":"173.962437ms","start":"2026-07-09T16:39:27.843363Z","end":"2026-07-09T16:39:28.017325Z","steps":["trace[1878282021] 'agreement among raft nodes before linearized reading' (duration: 173.845866ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:28.01756Z","caller":"traceutil/trace.go:171","msg":"trace[1328026225] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"192.806839ms","start":"2026-07-09T16:39:27.824722Z","end":"2026-07-09T16:39:28.017529Z","steps":["trace[1328026225] 'process raft request' (duration: 192.122717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:29.499708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.600862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:39:29.49999Z","caller":"traceutil/trace.go:171","msg":"trace[2023119313] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"452.895546ms","start":"2026-07-09T16:39:29.047076Z","end":"2026-07-09T16:39:29.499972Z","steps":["trace[2023119313] 'process raft request' (duration: 452.857445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:29.500075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:29.047053Z","time spent":"452.973395ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:39:29.500434Z","caller":"traceutil/trace.go:171","msg":"trace[105886626] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"784.009933ms","start":"2026-07-09T16:39:28.716409Z","end":"2026-07-09T16:39:29.500419Z","steps":["trace[105886626] 'process raft request' (duration: 250.597948ms)","trace[105886626] 'compare' (duration: 532.446492ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:39:29.500497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:28.716398Z","time spent":"784.066103ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:39:29.500572Z","caller":"traceutil/trace.go:171","msg":"trace[1030827717] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"507.246335ms","start":"2026-07-09T16:39:28.993251Z","end":"2026-07-09T16:39:29.500497Z","steps":["trace[1030827717] 'process raft request' (duration: 506.646483ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:29.500791Z","caller":"traceutil/trace.go:171","msg":"trace[738753666] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"784.298834ms","start":"2026-07-09T16:39:28.716479Z","end":"2026-07-09T16:39:29.500778Z","steps":["trace[738753666] 'process raft request' (duration: 783.330861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:29.500794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:28.993214Z","time spent":"507.440536ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:39:29.500827Z","caller":"traceutil/trace.go:171","msg":"trace[302253142] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2231; }","duration":"775.520408ms","start":"2026-07-09T16:39:28.725282Z","end":"2026-07-09T16:39:29.500803Z","steps":["trace[302253142] 'read index received' (duration: 241.67692ms)","trace[302253142] 'applied index is now lower than readState.Index' (duration: 533.840947ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:39:29.500869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:28.716459Z","time spent":"784.366414ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:39:29.501042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.597425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T16:39:29.501045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.739118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-09T16:39:29.501046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.419981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-07-09T16:39:29.501028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.149151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:39:29.501077Z","caller":"traceutil/trace.go:171","msg":"trace[98883759] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2155; }","duration":"775.802808ms","start":"2026-07-09T16:39:28.725264Z","end":"2026-07-09T16:39:29.501067Z","steps":["trace[98883759] 'agreement among raft nodes before linearized reading' (duration: 775.657908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:29.501065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.449479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T16:39:29.50109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.401722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:39:29.501108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:28.725256Z","time spent":"775.844748ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-09T16:39:29.501118Z","caller":"traceutil/trace.go:171","msg":"trace[773817106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2155; }","duration":"568.457552ms","start":"2026-07-09T16:39:28.932655Z","end":"2026-07-09T16:39:29.501112Z","steps":["trace[773817106] 'agreement among raft nodes before linearized reading' (duration: 568.422072ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:29.501117Z","caller":"traceutil/trace.go:171","msg":"trace[1902158543] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2155; }","duration":"307.541518ms","start":"2026-07-09T16:39:29.193564Z","end":"2026-07-09T16:39:29.501105Z","steps":["trace[1902158543] 'agreement among raft nodes before linearized reading' (duration: 307.426388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:29.501141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:28.932639Z","time spent":"568.498193ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:39:29.501154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:29.193543Z","time spent":"307.60222ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":22,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:39:29.501162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.112469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:39:29.501103Z","caller":"traceutil/trace.go:171","msg":"trace[1647473109] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2155; }","duration":"356.237982ms","start":"2026-07-09T16:39:29.144848Z","end":"2026-07-09T16:39:29.501086Z","steps":["trace[1647473109] 'agreement among raft nodes before linearized reading' (duration: 356.090902ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:29.501189Z","caller":"traceutil/trace.go:171","msg":"trace[1210917286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2155; }","duration":"727.160538ms","start":"2026-07-09T16:39:28.77402Z","end":"2026-07-09T16:39:29.501181Z","steps":["trace[1210917286] 'agreement among raft nodes before linearized reading' (duration: 727.088008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:29.501222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:28.773985Z","time spent":"727.229989ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:39:29.501227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:29.144782Z","time spent":"356.432682ms","remote":"127.0.0.1:32920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:39:29.501239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.83677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T16:39:29.501064Z","caller":"traceutil/trace.go:171","msg":"trace[1057003845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2155; }","duration":"722.644774ms","start":"2026-07-09T16:39:28.778411Z","end":"2026-07-09T16:39:29.501056Z","steps":["trace[1057003845] 'agreement among raft nodes before linearized reading' (duration: 722.573524ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:29.501273Z","caller":"traceutil/trace.go:171","msg":"trace[1715939935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2155; }","duration":"756.87996ms","start":"2026-07-09T16:39:28.744382Z","end":"2026-07-09T16:39:29.501262Z","steps":["trace[1715939935] 'agreement among raft nodes before linearized reading' (duration: 756.75253ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:29.501087Z","caller":"traceutil/trace.go:171","msg":"trace[1105494161] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2155; }","duration":"770.515222ms","start":"2026-07-09T16:39:28.730558Z","end":"2026-07-09T16:39:29.501073Z","steps":["trace[1105494161] 'agreement among raft nodes before linearized reading' (duration: 770.385332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:29.501322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:28.730545Z","time spent":"770.771503ms","remote":"127.0.0.1:59722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-09T16:39:29.501341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:28.744376Z","time spent":"756.94845ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:39:29.501371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:28.778397Z","time spent":"722.917586ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:39:30.288089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.678252ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:39:30.288211Z","caller":"traceutil/trace.go:171","msg":"trace[241472083] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2155; }","duration":"624.812622ms","start":"2026-07-09T16:39:29.663381Z","end":"2026-07-09T16:39:30.288194Z","steps":["trace[241472083] 'range keys from in-memory index tree' (duration: 624.659762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:30.289007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.965212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:39:30.289523Z","caller":"traceutil/trace.go:171","msg":"trace[1191074950] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"783.043392ms","start":"2026-07-09T16:39:29.506443Z","end":"2026-07-09T16:39:30.289486Z","steps":["trace[1191074950] 'process raft request' (duration: 172.487478ms)","trace[1191074950] 'compare' (duration: 609.667851ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:39:30.289546Z","caller":"traceutil/trace.go:171","msg":"trace[2115767354] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"783.067201ms","start":"2026-07-09T16:39:29.506449Z","end":"2026-07-09T16:39:30.289516Z","steps":["trace[2115767354] 'process raft request' (duration: 782.855951ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:30.289548Z","caller":"traceutil/trace.go:171","msg":"trace[226380123] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"782.234578ms","start":"2026-07-09T16:39:29.507283Z","end":"2026-07-09T16:39:30.289518Z","steps":["trace[226380123] 'process raft request' (duration: 782.107848ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:30.289584Z","caller":"traceutil/trace.go:171","msg":"trace[1363801365] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"783.121522ms","start":"2026-07-09T16:39:29.506441Z","end":"2026-07-09T16:39:30.289563Z","steps":["trace[1363801365] 'process raft request' (duration: 782.71587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:30.289667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:29.506427Z","time spent":"783.155671ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:39:30.289676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:29.506419Z","time spent":"783.205931ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:39:30.289688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:29.50643Z","time spent":"783.179401ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:39:30.2897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:29.507271Z","time spent":"782.347038ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:40:01.874387Z","caller":"traceutil/trace.go:171","msg":"trace[1591130063] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"152.608774ms","start":"2026-07-09T16:40:01.721708Z","end":"2026-07-09T16:40:01.874317Z","steps":["trace[1591130063] 'process raft request' (duration: 135.453288ms)","trace[1591130063] 'compare' (duration: 17.030253ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:01.874561Z","caller":"traceutil/trace.go:171","msg":"trace[720328717] linearizableReadLoop","detail":"{readStateIndex:2419; appliedIndex:2416; }","duration":"135.924094ms","start":"2026-07-09T16:40:01.738611Z","end":"2026-07-09T16:40:01.874535Z","steps":["trace[720328717] 'read index received' (duration: 3.309167ms)","trace[720328717] 'applied index is now lower than readState.Index' (duration: 132.613897ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:01.874618Z","caller":"traceutil/trace.go:171","msg":"trace[971436455] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"152.829346ms","start":"2026-07-09T16:40:01.721773Z","end":"2026-07-09T16:40:01.874602Z","steps":["trace[971436455] 'process raft request' (duration: 152.601754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:01.874921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.263759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-09T16:40:01.875042Z","caller":"traceutil/trace.go:171","msg":"trace[843649870] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2331; }","duration":"136.46714ms","start":"2026-07-09T16:40:01.738556Z","end":"2026-07-09T16:40:01.875023Z","steps":["trace[843649870] 'agreement among raft nodes before linearized reading' (duration: 136.058495ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:21.563527Z","caller":"traceutil/trace.go:171","msg":"trace[599608635] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"117.952701ms","start":"2026-07-09T16:40:21.445548Z","end":"2026-07-09T16:40:21.563501Z","steps":["trace[599608635] 'process raft request' (duration: 117.790169ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:21.897877Z","caller":"traceutil/trace.go:171","msg":"trace[865721802] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"108.846951ms","start":"2026-07-09T16:40:21.788995Z","end":"2026-07-09T16:40:21.897842Z","steps":["trace[865721802] 'process raft request' (duration: 108.661818ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:22.093819Z","caller":"traceutil/trace.go:171","msg":"trace[348468933] linearizableReadLoop","detail":"{readStateIndex:2555; appliedIndex:2552; }","duration":"100.140804ms","start":"2026-07-09T16:40:21.993648Z","end":"2026-07-09T16:40:22.093789Z","steps":["trace[348468933] 'read index received' (duration: 20.583801ms)","trace[348468933] 'applied index is now lower than readState.Index' (duration: 79.555363ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:22.09396Z","caller":"traceutil/trace.go:171","msg":"trace[1757342354] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"102.774045ms","start":"2026-07-09T16:40:21.991174Z","end":"2026-07-09T16:40:22.093949Z","steps":["trace[1757342354] 'process raft request' (duration: 102.51727ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:22.094164Z","caller":"traceutil/trace.go:171","msg":"trace[1320457481] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"105.572127ms","start":"2026-07-09T16:40:21.988562Z","end":"2026-07-09T16:40:22.094134Z","steps":["trace[1320457481] 'process raft request' (duration: 69.700737ms)","trace[1320457481] 'compare' (duration: 35.246601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:22.094509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.828864ms","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-09T16:40:22.094675Z","caller":"traceutil/trace.go:171","msg":"trace[926727145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2462; }","duration":"101.058148ms","start":"2026-07-09T16:40:21.993586Z","end":"2026-07-09T16:40:22.094644Z","steps":["trace[926727145] 'agreement among raft nodes before linearized reading' (duration: 100.515149ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:22.683926Z","caller":"traceutil/trace.go:171","msg":"trace[115715854] transaction","detail":"{read_only:false; response_revision:2478; number_of_response:1; }","duration":"103.368305ms","start":"2026-07-09T16:40:22.580522Z","end":"2026-07-09T16:40:22.68389Z","steps":["trace[115715854] 'process raft request' (duration: 45.347149ms)","trace[115715854] 'compare' (duration: 57.67109ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:28.288899Z","caller":"traceutil/trace.go:171","msg":"trace[682718696] transaction","detail":"{read_only:false; response_revision:2526; number_of_response:1; }","duration":"100.053602ms","start":"2026-07-09T16:40:28.188684Z","end":"2026-07-09T16:40:28.288738Z","steps":["trace[682718696] 'process raft request' (duration: 99.91561ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:28.919908Z","caller":"traceutil/trace.go:171","msg":"trace[1802647429] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"102.495644ms","start":"2026-07-09T16:40:28.817389Z","end":"2026-07-09T16:40:28.919884Z","steps":["trace[1802647429] 'process raft request' (duration: 102.346901ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:30.220805Z","caller":"traceutil/trace.go:171","msg":"trace[1010848809] linearizableReadLoop","detail":"{readStateIndex:2634; appliedIndex:2633; }","duration":"100.341533ms","start":"2026-07-09T16:40:30.120445Z","end":"2026-07-09T16:40:30.220786Z","steps":["trace[1010848809] 'read index received' (duration: 11.68118ms)","trace[1010848809] 'applied index is now lower than readState.Index' (duration: 88.658893ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:30.220923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.457835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:40:30.220953Z","caller":"traceutil/trace.go:171","msg":"trace[736840891] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2539; }","duration":"100.507317ms","start":"2026-07-09T16:40:30.120439Z","end":"2026-07-09T16:40:30.220947Z","steps":["trace[736840891] 'agreement among raft nodes before linearized reading' (duration: 100.421765ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:33.926611Z","caller":"traceutil/trace.go:171","msg":"trace[1353911276] linearizableReadLoop","detail":"{readStateIndex:2698; appliedIndex:2697; }","duration":"346.62227ms","start":"2026-07-09T16:40:33.579956Z","end":"2026-07-09T16:40:33.926578Z","steps":["trace[1353911276] 'read index received' (duration: 346.257762ms)","trace[1353911276] 'applied index is now lower than readState.Index' (duration: 363.417µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:33.926663Z","caller":"traceutil/trace.go:171","msg":"trace[1900350307] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"478.377977ms","start":"2026-07-09T16:40:33.448262Z","end":"2026-07-09T16:40:33.92664Z","steps":["trace[1900350307] 'process raft request' (duration: 477.943899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:33.926817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:33.448232Z","time spent":"478.491559ms","remote":"127.0.0.1:53410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8868,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:40:33.926943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.974095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12208"} {"level":"info","ts":"2026-07-09T16:40:33.927006Z","caller":"traceutil/trace.go:171","msg":"trace[2115053882] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2602; }","duration":"347.062396ms","start":"2026-07-09T16:40:33.579929Z","end":"2026-07-09T16:40:33.926991Z","steps":["trace[2115053882] 'agreement among raft nodes before linearized reading' (duration: 346.777402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:33.92705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:33.579915Z","time spent":"347.123487ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12231,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-09T16:40:33.929503Z","caller":"traceutil/trace.go:171","msg":"trace[323093506] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"110.389433ms","start":"2026-07-09T16:40:33.819093Z","end":"2026-07-09T16:40:33.929482Z","steps":["trace[323093506] 'process raft request' (duration: 109.884444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:40.504995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.584002ms","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-09T16:40:40.505118Z","caller":"traceutil/trace.go:171","msg":"trace[1493720692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2645; }","duration":"153.804076ms","start":"2026-07-09T16:40:40.35129Z","end":"2026-07-09T16:40:40.505094Z","steps":["trace[1493720692] 'range keys from in-memory index tree' (duration: 153.233455ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:49.15207Z","caller":"traceutil/trace.go:171","msg":"trace[698732766] linearizableReadLoop","detail":"{readStateIndex:2791; appliedIndex:2790; }","duration":"123.473661ms","start":"2026-07-09T16:40:49.028575Z","end":"2026-07-09T16:40:49.152049Z","steps":["trace[698732766] 'read index received' (duration: 39.280773ms)","trace[698732766] 'applied index is now lower than readState.Index' (duration: 84.191998ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:49.152256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.661915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T16:40:49.152308Z","caller":"traceutil/trace.go:171","msg":"trace[1043267895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2692; }","duration":"123.756396ms","start":"2026-07-09T16:40:49.028535Z","end":"2026-07-09T16:40:49.152291Z","steps":["trace[1043267895] 'agreement among raft nodes before linearized reading' (duration: 123.591873ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:49.152097Z","caller":"traceutil/trace.go:171","msg":"trace[2098520479] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"143.528175ms","start":"2026-07-09T16:40:49.008542Z","end":"2026-07-09T16:40:49.15207Z","steps":["trace[2098520479] 'process raft request' (duration: 59.313266ms)","trace[2098520479] 'compare' (duration: 84.104996ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:55.397151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.132453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:40:55.397342Z","caller":"traceutil/trace.go:171","msg":"trace[209532650] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"123.257996ms","start":"2026-07-09T16:40:55.274069Z","end":"2026-07-09T16:40:55.397327Z","steps":["trace[209532650] 'process raft request' (duration: 123.195284ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:55.397342Z","caller":"traceutil/trace.go:171","msg":"trace[1102834917] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"384.529927ms","start":"2026-07-09T16:40:55.012784Z","end":"2026-07-09T16:40:55.397314Z","steps":["trace[1102834917] 'process raft request' (duration: 166.027547ms)","trace[1102834917] 'compare' (duration: 218.00729ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:55.397645Z","caller":"traceutil/trace.go:171","msg":"trace[1387619358] linearizableReadLoop","detail":"{readStateIndex:2823; appliedIndex:2822; }","duration":"227.071422ms","start":"2026-07-09T16:40:55.170548Z","end":"2026-07-09T16:40:55.39762Z","steps":["trace[1387619358] 'read index received' (duration: 8.208483ms)","trace[1387619358] 'applied index is now lower than readState.Index' (duration: 218.859768ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:55.397647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:55.012744Z","time spent":"384.801353ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:40:55.397852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.299507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:40:55.39792Z","caller":"traceutil/trace.go:171","msg":"trace[1225626048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2723; }","duration":"227.408567ms","start":"2026-07-09T16:40:55.170496Z","end":"2026-07-09T16:40:55.397904Z","steps":["trace[1225626048] 'agreement among raft nodes before linearized reading' (duration: 227.233244ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:55.597557Z","caller":"traceutil/trace.go:171","msg":"trace[860388840] linearizableReadLoop","detail":"{readStateIndex:2826; appliedIndex:2824; }","duration":"193.835523ms","start":"2026-07-09T16:40:55.403699Z","end":"2026-07-09T16:40:55.597535Z","steps":["trace[860388840] 'read index received' (duration: 8.812716ms)","trace[860388840] 'applied index is now lower than readState.Index' (duration: 185.021797ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:55.597598Z","caller":"traceutil/trace.go:171","msg":"trace[458885225] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"194.732142ms","start":"2026-07-09T16:40:55.402824Z","end":"2026-07-09T16:40:55.597556Z","steps":["trace[458885225] 'process raft request' (duration: 129.896427ms)","trace[458885225] 'compare' (duration: 64.630221ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:55.597821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.096478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"warn","ts":"2026-07-09T16:40:55.597931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.884808ms","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:7"} {"level":"info","ts":"2026-07-09T16:40:55.597957Z","caller":"traceutil/trace.go:171","msg":"trace[550388725] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2726; }","duration":"194.21887ms","start":"2026-07-09T16:40:55.403683Z","end":"2026-07-09T16:40:55.597902Z","steps":["trace[550388725] 'agreement among raft nodes before linearized reading' (duration: 193.998636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:55.597975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.59019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:40:55.597979Z","caller":"traceutil/trace.go:171","msg":"trace[1047315160] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2726; }","duration":"169.981521ms","start":"2026-07-09T16:40:55.427985Z","end":"2026-07-09T16:40:55.597966Z","steps":["trace[1047315160] 'agreement among raft nodes before linearized reading' (duration: 169.885149ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:55.598231Z","caller":"traceutil/trace.go:171","msg":"trace[169681336] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2726; }","duration":"139.851867ms","start":"2026-07-09T16:40:55.458347Z","end":"2026-07-09T16:40:55.598199Z","steps":["trace[169681336] 'agreement among raft nodes before linearized reading' (duration: 139.583362ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:00.208086Z","caller":"traceutil/trace.go:171","msg":"trace[498737755] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"123.689922ms","start":"2026-07-09T16:41:00.084372Z","end":"2026-07-09T16:41:00.208062Z","steps":["trace[498737755] 'process raft request' (duration: 123.526449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:00.914084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.414999ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:41:00.915502Z","caller":"traceutil/trace.go:171","msg":"trace[1363653355] linearizableReadLoop","detail":"{readStateIndex:2851; appliedIndex:2849; }","duration":"260.636932ms","start":"2026-07-09T16:41:00.65423Z","end":"2026-07-09T16:41:00.914867Z","steps":["trace[1363653355] 'read index received' (duration: 27.78µs)","trace[1363653355] 'applied index is now lower than readState.Index' (duration: 260.607872ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:00.915839Z","caller":"traceutil/trace.go:171","msg":"trace[1340136322] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"728.236521ms","start":"2026-07-09T16:41:00.187567Z","end":"2026-07-09T16:41:00.915803Z","steps":["trace[1340136322] 'process raft request' (duration: 726.8167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:00.91607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:00.187542Z","time spent":"728.434845ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:00.916186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.938371ms","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-09T16:41:00.916369Z","caller":"traceutil/trace.go:171","msg":"trace[1420654415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2749; }","duration":"262.153475ms","start":"2026-07-09T16:41:00.654201Z","end":"2026-07-09T16:41:00.916354Z","steps":["trace[1420654415] 'agreement among raft nodes before linearized reading' (duration: 261.637014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:01.812782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.384838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:41:01.812986Z","caller":"traceutil/trace.go:171","msg":"trace[1713254175] linearizableReadLoop","detail":"{readStateIndex:2853; appliedIndex:2851; }","duration":"882.803967ms","start":"2026-07-09T16:41:00.930165Z","end":"2026-07-09T16:41:01.812969Z","steps":["trace[1713254175] 'read index received' (duration: 115.115683ms)","trace[1713254175] 'applied index is now lower than readState.Index' (duration: 767.687224ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:01.813195Z","caller":"traceutil/trace.go:171","msg":"trace[412992624] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"893.124832ms","start":"2026-07-09T16:41:00.920056Z","end":"2026-07-09T16:41:01.813181Z","steps":["trace[412992624] 'process raft request' (duration: 125.230023ms)","trace[412992624] 'compare' (duration: 767.280125ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:41:01.81327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:00.920027Z","time spent":"893.206763ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:01.813273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.060873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:41:01.813377Z","caller":"traceutil/trace.go:171","msg":"trace[1183938531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2751; }","duration":"883.231095ms","start":"2026-07-09T16:41:00.93013Z","end":"2026-07-09T16:41:01.813361Z","steps":["trace[1183938531] 'agreement among raft nodes before linearized reading' (duration: 883.050562ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:01.813351Z","caller":"traceutil/trace.go:171","msg":"trace[1815238680] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"892.257782ms","start":"2026-07-09T16:41:00.92104Z","end":"2026-07-09T16:41:01.813298Z","steps":["trace[1815238680] 'process raft request' (duration: 891.878103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:01.813455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:00.930115Z","time spent":"883.307377ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:41:01.813736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:00.921019Z","time spent":"892.432746ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:02.465878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.587984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:41:02.466013Z","caller":"traceutil/trace.go:171","msg":"trace[490937116] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"252.751161ms","start":"2026-07-09T16:41:02.213249Z","end":"2026-07-09T16:41:02.466001Z","steps":["trace[490937116] 'process raft request' (duration: 252.710591ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:02.466143Z","caller":"traceutil/trace.go:171","msg":"trace[152567879] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"1.017961561s","start":"2026-07-09T16:41:01.448154Z","end":"2026-07-09T16:41:02.466116Z","steps":["trace[152567879] 'process raft request' (duration: 723.073991ms)","trace[152567879] 'compare' (duration: 294.168505ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:02.466164Z","caller":"traceutil/trace.go:171","msg":"trace[879878549] linearizableReadLoop","detail":"{readStateIndex:2854; appliedIndex:2853; }","duration":"653.11615ms","start":"2026-07-09T16:41:01.813022Z","end":"2026-07-09T16:41:02.466139Z","steps":["trace[879878549] 'read index received' (duration: 358.263561ms)","trace[879878549] 'applied index is now lower than readState.Index' (duration: 294.850119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:41:02.466216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.448138Z","time spent":"1.018055393s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:02.466304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.484157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c0ac9de6bf5450\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-09T16:41:02.466332Z","caller":"traceutil/trace.go:171","msg":"trace[472148376] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c0ac9de6bf5450; range_end:; response_count:1; response_revision:2753; }","duration":"842.544998ms","start":"2026-07-09T16:41:01.62378Z","end":"2026-07-09T16:41:02.466325Z","steps":["trace[472148376] 'agreement among raft nodes before linearized reading' (duration: 842.445666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.466334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.035322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:41:02.466356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.623767Z","time spent":"842.58417ms","remote":"127.0.0.1:53300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c0ac9de6bf5450\" "} {"level":"info","ts":"2026-07-09T16:41:02.466381Z","caller":"traceutil/trace.go:171","msg":"trace[1700467618] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2753; }","duration":"845.139576ms","start":"2026-07-09T16:41:01.621229Z","end":"2026-07-09T16:41:02.466368Z","steps":["trace[1700467618] 'agreement among raft nodes before linearized reading' (duration: 845.051554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.466425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.621168Z","time spent":"845.246698ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:41:02.466539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.817889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T16:41:02.466587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.792162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:41:02.466608Z","caller":"traceutil/trace.go:171","msg":"trace[215155922] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2753; }","duration":"839.8885ms","start":"2026-07-09T16:41:01.626671Z","end":"2026-07-09T16:41:02.46656Z","steps":["trace[215155922] 'agreement among raft nodes before linearized reading' (duration: 839.826119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.46664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.626651Z","time spent":"839.981032ms","remote":"127.0.0.1:53760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":36,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:41:02.466637Z","caller":"traceutil/trace.go:171","msg":"trace[1209535060] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2753; }","duration":"678.871424ms","start":"2026-07-09T16:41:01.787753Z","end":"2026-07-09T16:41:02.466624Z","steps":["trace[1209535060] 'agreement among raft nodes before linearized reading' (duration: 678.805723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.466674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.268426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-09T16:41:02.466676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.787733Z","time spent":"678.933106ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:41:02.466714Z","caller":"traceutil/trace.go:171","msg":"trace[1384877731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2753; }","duration":"980.352977ms","start":"2026-07-09T16:41:01.486351Z","end":"2026-07-09T16:41:02.466704Z","steps":["trace[1384877731] 'agreement among raft nodes before linearized reading' (duration: 980.251315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.466529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.050962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-09T16:41:02.466748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.486325Z","time spent":"980.416018ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:41:02.466757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380659465s","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-09T16:41:02.466798Z","caller":"traceutil/trace.go:171","msg":"trace[1607157384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2753; }","duration":"1.380758366s","start":"2026-07-09T16:41:01.086034Z","end":"2026-07-09T16:41:02.466792Z","steps":["trace[1607157384] 'agreement among raft nodes before linearized reading' (duration: 1.380649054s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.466828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.086014Z","time spent":"1.380808939s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:41:02.466896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.503697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:41:02.466932Z","caller":"traceutil/trace.go:171","msg":"trace[2074317254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2753; }","duration":"652.564788ms","start":"2026-07-09T16:41:01.814358Z","end":"2026-07-09T16:41:02.466923Z","steps":["trace[2074317254] 'agreement among raft nodes before linearized reading' (duration: 652.517436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.466963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.814344Z","time spent":"652.612589ms","remote":"127.0.0.1:56554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:41:02.46698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.178514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-09T16:41:02.467028Z","caller":"traceutil/trace.go:171","msg":"trace[43107562] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2753; }","duration":"996.274746ms","start":"2026-07-09T16:41:01.470744Z","end":"2026-07-09T16:41:02.467018Z","steps":["trace[43107562] 'agreement among raft nodes before linearized reading' (duration: 996.163814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.467056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.47068Z","time spent":"996.371328ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-09T16:41:02.466774Z","caller":"traceutil/trace.go:171","msg":"trace[2040599385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2753; }","duration":"839.302087ms","start":"2026-07-09T16:41:01.627464Z","end":"2026-07-09T16:41:02.466766Z","steps":["trace[2040599385] 'agreement among raft nodes before linearized reading' (duration: 838.95771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.467122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.627423Z","time spent":"839.688156ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:41:02.467181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.756183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:41:02.467233Z","caller":"traceutil/trace.go:171","msg":"trace[1149007773] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2753; }","duration":"521.836785ms","start":"2026-07-09T16:41:01.945382Z","end":"2026-07-09T16:41:02.467218Z","steps":["trace[1149007773] 'agreement among raft nodes before linearized reading' (duration: 521.762313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.467273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.945359Z","time spent":"521.905595ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":6,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:41:02.46728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128629165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-09T16:41:02.467309Z","caller":"traceutil/trace.go:171","msg":"trace[405832765] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2753; }","duration":"1.128730246s","start":"2026-07-09T16:41:01.338571Z","end":"2026-07-09T16:41:02.467301Z","steps":["trace[405832765] 'agreement among raft nodes before linearized reading' (duration: 1.128606533s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.467355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:01.338545Z","time spent":"1.128804s","remote":"127.0.0.1:53428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-09T16:41:02.467447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.5586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:41:02.467493Z","caller":"traceutil/trace.go:171","msg":"trace[1894544970] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2753; }","duration":"430.817395ms","start":"2026-07-09T16:41:02.036663Z","end":"2026-07-09T16:41:02.467481Z","steps":["trace[1894544970] 'agreement among raft nodes before linearized reading' (duration: 430.723963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.467531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:02.036636Z","time spent":"430.886477ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":15,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:41:03.086487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.097305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:41:03.086848Z","caller":"traceutil/trace.go:171","msg":"trace[993290332] linearizableReadLoop","detail":"{readStateIndex:2860; appliedIndex:2855; }","duration":"613.302159ms","start":"2026-07-09T16:41:02.473526Z","end":"2026-07-09T16:41:03.086828Z","steps":["trace[993290332] 'read index received' (duration: 92.776866ms)","trace[993290332] 'applied index is now lower than readState.Index' (duration: 520.524183ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:03.086929Z","caller":"traceutil/trace.go:171","msg":"trace[768757613] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"615.233271ms","start":"2026-07-09T16:41:02.471684Z","end":"2026-07-09T16:41:03.086918Z","steps":["trace[768757613] 'process raft request' (duration: 94.594214ms)","trace[768757613] 'compare' (duration: 519.890121ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:41:03.08701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:02.471668Z","time spent":"615.292082ms","remote":"127.0.0.1:53300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:41:03.08712Z","caller":"traceutil/trace.go:171","msg":"trace[25277843] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"615.295183ms","start":"2026-07-09T16:41:02.47179Z","end":"2026-07-09T16:41:03.087085Z","steps":["trace[25277843] 'process raft request' (duration: 614.894124ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:03.087225Z","caller":"traceutil/trace.go:171","msg":"trace[1166372712] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"614.461855ms","start":"2026-07-09T16:41:02.472741Z","end":"2026-07-09T16:41:03.087203Z","steps":["trace[1166372712] 'process raft request' (duration: 614.036636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:03.087322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:02.471776Z","time spent":"615.453436ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:03.087423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.887542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-07-09T16:41:03.087485Z","caller":"traceutil/trace.go:171","msg":"trace[2088101919] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2758; }","duration":"613.967614ms","start":"2026-07-09T16:41:02.473501Z","end":"2026-07-09T16:41:03.087469Z","steps":["trace[2088101919] 'agreement among raft nodes before linearized reading' (duration: 613.763549ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:03.087495Z","caller":"traceutil/trace.go:171","msg":"trace[550207431] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"615.688832ms","start":"2026-07-09T16:41:02.47179Z","end":"2026-07-09T16:41:03.087479Z","steps":["trace[550207431] 'process raft request' (duration: 614.812122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:03.087476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:02.472733Z","time spent":"614.610878ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:03.087581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:02.471773Z","time spent":"615.758373ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:41:03.087787Z","caller":"traceutil/trace.go:171","msg":"trace[619552791] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"615.927726ms","start":"2026-07-09T16:41:02.471847Z","end":"2026-07-09T16:41:03.087774Z","steps":["trace[619552791] 'process raft request' (duration: 614.887663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:03.087944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:02.471839Z","time spent":"616.068179ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:03.087538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:02.473495Z","time spent":"614.028955ms","remote":"127.0.0.1:53428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1342,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-07-09T16:41:03.125912Z","caller":"traceutil/trace.go:171","msg":"trace[204649020] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"204.606477ms","start":"2026-07-09T16:41:02.921271Z","end":"2026-07-09T16:41:03.125877Z","steps":["trace[204649020] 'process raft request' (duration: 204.348061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:03.126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.842653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:41:03.126186Z","caller":"traceutil/trace.go:171","msg":"trace[1292491484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2759; }","duration":"196.077418ms","start":"2026-07-09T16:41:02.93009Z","end":"2026-07-09T16:41:03.126167Z","steps":["trace[1292491484] 'agreement among raft nodes before linearized reading' (duration: 195.837363ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:03.404937Z","caller":"traceutil/trace.go:171","msg":"trace[784623418] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"187.862567ms","start":"2026-07-09T16:41:03.217051Z","end":"2026-07-09T16:41:03.404914Z","steps":["trace[784623418] 'process raft request' (duration: 154.514046ms)","trace[784623418] 'compare' (duration: 33.030954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:41:04.865666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.265891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-09T16:41:04.865739Z","caller":"traceutil/trace.go:171","msg":"trace[1299726234] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2767; }","duration":"106.414065ms","start":"2026-07-09T16:41:04.759302Z","end":"2026-07-09T16:41:04.865716Z","steps":["trace[1299726234] 'range keys from in-memory index tree' (duration: 106.121817ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:08.216707Z","caller":"traceutil/trace.go:171","msg":"trace[173891242] linearizableReadLoop","detail":"{readStateIndex:2887; appliedIndex:2886; }","duration":"323.2268ms","start":"2026-07-09T16:41:07.893462Z","end":"2026-07-09T16:41:08.216689Z","steps":["trace[173891242] 'read index received' (duration: 323.095536ms)","trace[173891242] 'applied index is now lower than readState.Index' (duration: 130.714µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:08.21683Z","caller":"traceutil/trace.go:171","msg":"trace[329324632] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"368.368125ms","start":"2026-07-09T16:41:07.848439Z","end":"2026-07-09T16:41:08.216807Z","steps":["trace[329324632] 'process raft request' (duration: 368.13769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:08.216904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.445014ms","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":"warn","ts":"2026-07-09T16:41:08.216898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.866948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:41:08.216927Z","caller":"traceutil/trace.go:171","msg":"trace[1564863654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2784; }","duration":"323.505616ms","start":"2026-07-09T16:41:07.893415Z","end":"2026-07-09T16:41:08.21692Z","steps":["trace[1564863654] 'agreement among raft nodes before linearized reading' (duration: 323.413213ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:08.216936Z","caller":"traceutil/trace.go:171","msg":"trace[342462722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2784; }","duration":"284.94973ms","start":"2026-07-09T16:41:07.931977Z","end":"2026-07-09T16:41:08.216927Z","steps":["trace[342462722] 'agreement among raft nodes before linearized reading' (duration: 284.876798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:08.216948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:07.893396Z","time spent":"323.546466ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:41:08.216954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:07.848419Z","time spent":"368.465496ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:09.285557Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063761464s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T16:41:09.612005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.967218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:41:09.612161Z","caller":"traceutil/trace.go:171","msg":"trace[1244161452] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"1.390432198s","start":"2026-07-09T16:41:08.2217Z","end":"2026-07-09T16:41:09.612132Z","steps":["trace[1244161452] 'process raft request' (duration: 1.064192614s)","trace[1244161452] 'compare' (duration: 324.29601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:41:09.612322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:08.221683Z","time spent":"1.390599832s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:09.870092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.044962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:41:09.870357Z","caller":"traceutil/trace.go:171","msg":"trace[405677514] linearizableReadLoop","detail":"{readStateIndex:2890; appliedIndex:2887; }","duration":"937.68535ms","start":"2026-07-09T16:41:08.932655Z","end":"2026-07-09T16:41:09.87034Z","steps":["trace[405677514] 'read index received' (duration: 353.232035ms)","trace[405677514] 'applied index is now lower than readState.Index' (duration: 584.451925ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:09.870416Z","caller":"traceutil/trace.go:171","msg":"trace[993270281] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"1.326639695s","start":"2026-07-09T16:41:08.543736Z","end":"2026-07-09T16:41:09.870376Z","steps":["trace[993270281] 'process raft request' (duration: 1.326502102s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:09.870497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.845823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:41:09.870564Z","caller":"traceutil/trace.go:171","msg":"trace[1793153041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2788; }","duration":"937.980377ms","start":"2026-07-09T16:41:08.932565Z","end":"2026-07-09T16:41:09.870545Z","steps":["trace[1793153041] 'agreement among raft nodes before linearized reading' (duration: 937.889755ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:09.870458Z","caller":"traceutil/trace.go:171","msg":"trace[1921202924] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"610.610026ms","start":"2026-07-09T16:41:09.259806Z","end":"2026-07-09T16:41:09.870416Z","steps":["trace[1921202924] 'process raft request' (duration: 610.492573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:09.870602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:08.543715Z","time spent":"1.326789978s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:09.870636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:08.932542Z","time spent":"938.078069ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:41:09.870547Z","caller":"traceutil/trace.go:171","msg":"trace[92179403] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"1.377677851s","start":"2026-07-09T16:41:08.492794Z","end":"2026-07-09T16:41:09.870472Z","steps":["trace[92179403] 'process raft request' (duration: 1.262165348s)","trace[92179403] 'compare' (duration: 114.915999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:41:09.870862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:08.492774Z","time spent":"1.377970197s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:09.870863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.259785Z","time spent":"610.915033ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:09.870961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.411413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T16:41:09.871138Z","caller":"traceutil/trace.go:171","msg":"trace[1677026807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2788; }","duration":"627.554985ms","start":"2026-07-09T16:41:09.243487Z","end":"2026-07-09T16:41:09.871042Z","steps":["trace[1677026807] 'agreement among raft nodes before linearized reading' (duration: 627.29514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:09.871163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.551148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12208"} {"level":"warn","ts":"2026-07-09T16:41:09.871198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.387064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:41:09.871208Z","caller":"traceutil/trace.go:171","msg":"trace[1692827776] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2788; }","duration":"187.595039ms","start":"2026-07-09T16:41:09.683601Z","end":"2026-07-09T16:41:09.871196Z","steps":["trace[1692827776] 'agreement among raft nodes before linearized reading' (duration: 187.421145ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:09.871249Z","caller":"traceutil/trace.go:171","msg":"trace[414476313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2788; }","duration":"574.527268ms","start":"2026-07-09T16:41:09.296709Z","end":"2026-07-09T16:41:09.871237Z","steps":["trace[414476313] 'agreement among raft nodes before linearized reading' (duration: 574.364534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:09.871285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.296688Z","time spent":"574.590008ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:41:09.871319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.117245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T16:41:09.871364Z","caller":"traceutil/trace.go:171","msg":"trace[1663285238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2788; }","duration":"593.200916ms","start":"2026-07-09T16:41:09.278153Z","end":"2026-07-09T16:41:09.871354Z","steps":["trace[1663285238] 'agreement among raft nodes before linearized reading' (duration: 593.081874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:09.871401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.278133Z","time spent":"593.261607ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:41:09.871329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.476034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T16:41:09.871204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.243468Z","time spent":"627.722819ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:41:09.871567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.488993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-09T16:41:09.871609Z","caller":"traceutil/trace.go:171","msg":"trace[575980635] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2788; }","duration":"573.556515ms","start":"2026-07-09T16:41:09.29804Z","end":"2026-07-09T16:41:09.871597Z","steps":["trace[575980635] 'agreement among raft nodes before linearized reading' (duration: 573.419382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:09.871636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.29803Z","time spent":"573.601287ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-09T16:41:09.874794Z","caller":"traceutil/trace.go:171","msg":"trace[549756125] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2788; }","duration":"331.706001ms","start":"2026-07-09T16:41:09.539809Z","end":"2026-07-09T16:41:09.871514Z","steps":["trace[549756125] 'agreement among raft nodes before linearized reading' (duration: 331.302011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:09.874911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.539793Z","time spent":"335.078748ms","remote":"127.0.0.1:53584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:41:09.87144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.300864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-09T16:41:09.875365Z","caller":"traceutil/trace.go:171","msg":"trace[381671078] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2788; }","duration":"538.206044ms","start":"2026-07-09T16:41:09.337129Z","end":"2026-07-09T16:41:09.875335Z","steps":["trace[381671078] 'agreement among raft nodes before linearized reading' (duration: 533.850284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:09.875417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.337069Z","time spent":"538.326427ms","remote":"127.0.0.1:53428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-09T16:41:10.378702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394590145720693,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:41:10.879876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394590145720693,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:41:11.029715Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.152696381s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T16:41:12.930619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999878691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T16:41:12.930851Z","caller":"traceutil/trace.go:171","msg":"trace[567729634] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000118987s","start":"2026-07-09T16:41:10.930664Z","end":"2026-07-09T16:41:12.930783Z","steps":["trace[567729634] 'agreement among raft nodes before linearized reading' (duration: 1.999910803s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:12.931071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:10.930646Z","time spent":"2.000341322s","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:41:13.530403Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.713931369s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T16:41:13.664392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.63427321s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:41:13.664589Z","caller":"traceutil/trace.go:171","msg":"trace[657572263] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"3.788169631s","start":"2026-07-09T16:41:09.876378Z","end":"2026-07-09T16:41:13.664548Z","steps":["trace[657572263] 'process raft request' (duration: 1.153645054s)","trace[657572263] 'compare' (duration: 2.632989491s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:41:13.664631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.876361Z","time spent":"3.788251662s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:41:13.664818Z","caller":"traceutil/trace.go:171","msg":"trace[1587125498] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"3.786356039s","start":"2026-07-09T16:41:09.878427Z","end":"2026-07-09T16:41:13.664783Z","steps":["trace[1587125498] 'process raft request' (duration: 3.786071532s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.664958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.878414Z","time spent":"3.786473012s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:41:13.665169Z","caller":"traceutil/trace.go:171","msg":"trace[463667857] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"3.786648326s","start":"2026-07-09T16:41:09.8785Z","end":"2026-07-09T16:41:13.665148Z","steps":["trace[463667857] 'process raft request' (duration: 3.786287528s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.665328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.878493Z","time spent":"3.786760509s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:41:13.665555Z","caller":"traceutil/trace.go:171","msg":"trace[1746843992] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"3.786399211s","start":"2026-07-09T16:41:09.878446Z","end":"2026-07-09T16:41:13.664846Z","steps":["trace[1746843992] 'process raft request' (duration: 3.786236627s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.666367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.878436Z","time spent":"3.787154759s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:13.861817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.710258ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:41:13.862198Z","caller":"traceutil/trace.go:171","msg":"trace[1613085870] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"3.868780554s","start":"2026-07-09T16:41:09.993392Z","end":"2026-07-09T16:41:13.862173Z","steps":["trace[1613085870] 'process raft request' (duration: 3.868534688s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.862353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.993365Z","time spent":"3.868906116s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:41:13.908439Z","caller":"traceutil/trace.go:171","msg":"trace[185037365] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"3.255495287s","start":"2026-07-09T16:41:10.652895Z","end":"2026-07-09T16:41:13.90839Z","steps":["trace[185037365] 'process raft request' (duration: 3.209262812s)","trace[185037365] 'compare' (duration: 45.963989ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:13.908462Z","caller":"traceutil/trace.go:171","msg":"trace[1043937218] linearizableReadLoop","detail":"{readStateIndex:2898; appliedIndex:2891; }","duration":"4.030218394s","start":"2026-07-09T16:41:09.878205Z","end":"2026-07-09T16:41:13.908423Z","steps":["trace[1043937218] 'read index received' (duration: 1.15174933s)","trace[1043937218] 'applied index is now lower than readState.Index' (duration: 2.878464644s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:41:13.908622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:10.652871Z","time spent":"3.25566637s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:41:13.908669Z","caller":"traceutil/trace.go:171","msg":"trace[1842260840] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"2.0301723s","start":"2026-07-09T16:41:11.878425Z","end":"2026-07-09T16:41:13.908597Z","steps":["trace[1842260840] 'process raft request' (duration: 2.029971895s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.908888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:11.878416Z","time spent":"2.030384305s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:41:13.908898Z","caller":"traceutil/trace.go:171","msg":"trace[1344489399] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"2.029776932s","start":"2026-07-09T16:41:11.879098Z","end":"2026-07-09T16:41:13.908875Z","steps":["trace[1344489399] 'process raft request' (duration: 2.029420023s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.908952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.030720756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9280"} {"level":"info","ts":"2026-07-09T16:41:13.908459Z","caller":"traceutil/trace.go:171","msg":"trace[81499106] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"2.031319178s","start":"2026-07-09T16:41:11.877115Z","end":"2026-07-09T16:41:13.908434Z","steps":["trace[81499106] 'process raft request' (duration: 2.031229785s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.909011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:11.879091Z","time spent":"2.029853433s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:41:13.909023Z","caller":"traceutil/trace.go:171","msg":"trace[2088442783] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2797; }","duration":"4.03082178s","start":"2026-07-09T16:41:09.878186Z","end":"2026-07-09T16:41:13.909008Z","steps":["trace[2088442783] 'agreement among raft nodes before linearized reading' (duration: 4.0303912s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.909069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.878139Z","time spent":"4.030919161s","remote":"127.0.0.1:53410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9303,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-09T16:41:13.909111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:11.877092Z","time spent":"2.031934362s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:13.909235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.030960153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-07-09T16:41:13.909264Z","caller":"traceutil/trace.go:171","msg":"trace[1386582694] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2798; }","duration":"4.031012623s","start":"2026-07-09T16:41:09.878243Z","end":"2026-07-09T16:41:13.909256Z","steps":["trace[1386582694] 'agreement among raft nodes before linearized reading' (duration: 4.030909741s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.909286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:09.87823Z","time spent":"4.031050145s","remote":"127.0.0.1:53428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1468,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"warn","ts":"2026-07-09T16:41:13.90945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.291085171s","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":"warn","ts":"2026-07-09T16:41:13.909446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.286447634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c0ac9de6bf5450\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-09T16:41:13.909471Z","caller":"traceutil/trace.go:171","msg":"trace[1817021006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2798; }","duration":"2.291127902s","start":"2026-07-09T16:41:11.618338Z","end":"2026-07-09T16:41:13.909466Z","steps":["trace[1817021006] 'agreement among raft nodes before linearized reading' (duration: 2.291066561s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.909491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:11.618326Z","time spent":"2.291161522s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T16:41:13.909495Z","caller":"traceutil/trace.go:171","msg":"trace[600399344] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c0ac9de6bf5450; range_end:; response_count:1; response_revision:2798; }","duration":"2.286518904s","start":"2026-07-09T16:41:11.622963Z","end":"2026-07-09T16:41:13.909482Z","steps":["trace[600399344] 'agreement among raft nodes before linearized reading' (duration: 2.286345441s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.90953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:11.622952Z","time spent":"2.286570026s","remote":"127.0.0.1:53300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c0ac9de6bf5450\" "} {"level":"warn","ts":"2026-07-09T16:41:13.909525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.325360858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:41:13.909576Z","caller":"traceutil/trace.go:171","msg":"trace[978226213] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2798; }","duration":"2.32545623s","start":"2026-07-09T16:41:11.584106Z","end":"2026-07-09T16:41:13.909562Z","steps":["trace[978226213] 'agreement among raft nodes before linearized reading' (duration: 2.325360747s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.909608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.686929303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T16:41:13.909615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.397713158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-07-09T16:41:13.909623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:11.584086Z","time spent":"2.32551974s","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":24,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:41:13.909651Z","caller":"traceutil/trace.go:171","msg":"trace[641063894] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2798; }","duration":"2.39777647s","start":"2026-07-09T16:41:11.511863Z","end":"2026-07-09T16:41:13.909639Z","steps":["trace[641063894] 'agreement among raft nodes before linearized reading' (duration: 2.397693447s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.909673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.776051612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:41:13.909685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:11.511844Z","time spent":"2.397834481s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-09T16:41:13.909696Z","caller":"traceutil/trace.go:171","msg":"trace[1216341713] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2798; }","duration":"3.776099612s","start":"2026-07-09T16:41:10.133589Z","end":"2026-07-09T16:41:13.909689Z","steps":["trace[1216341713] 'agreement among raft nodes before linearized reading' (duration: 3.776055512s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.909717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:10.133575Z","time spent":"3.776136094s","remote":"127.0.0.1:51648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:41:13.909532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.019347113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-09T16:41:13.9098Z","caller":"traceutil/trace.go:171","msg":"trace[1452468232] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2798; }","duration":"3.01963774s","start":"2026-07-09T16:41:10.89015Z","end":"2026-07-09T16:41:13.909788Z","steps":["trace[1452468232] 'agreement among raft nodes before linearized reading' (duration: 3.019300182s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.90984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:10.890137Z","time spent":"3.01969182s","remote":"127.0.0.1:53386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-09T16:41:13.909877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511938233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:41:13.909905Z","caller":"traceutil/trace.go:171","msg":"trace[1320955549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2798; }","duration":"1.512001485s","start":"2026-07-09T16:41:12.397895Z","end":"2026-07-09T16:41:13.909896Z","steps":["trace[1320955549] 'agreement among raft nodes before linearized reading' (duration: 1.511960605s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.909932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:12.397876Z","time spent":"1.512050906s","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:41:13.909629Z","caller":"traceutil/trace.go:171","msg":"trace[1339931207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2798; }","duration":"3.686986474s","start":"2026-07-09T16:41:10.222637Z","end":"2026-07-09T16:41:13.909623Z","steps":["trace[1339931207] 'agreement among raft nodes before linearized reading' (duration: 3.686930913s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.909985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:10.222612Z","time spent":"3.687363703s","remote":"127.0.0.1:53492","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":"info","ts":"2026-07-09T16:41:13.909759Z","caller":"traceutil/trace.go:171","msg":"trace[1858901219] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"185.827273ms","start":"2026-07-09T16:41:13.723917Z","end":"2026-07-09T16:41:13.909744Z","steps":["trace[1858901219] 'process raft request' (duration: 184.8612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.910195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.662089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:41:13.910235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.820526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T16:41:13.910266Z","caller":"traceutil/trace.go:171","msg":"trace[723618309] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2798; }","duration":"295.886916ms","start":"2026-07-09T16:41:13.614371Z","end":"2026-07-09T16:41:13.910258Z","steps":["trace[723618309] 'agreement among raft nodes before linearized reading' (duration: 295.677711ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:13.910258Z","caller":"traceutil/trace.go:171","msg":"trace[1909418566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2798; }","duration":"978.73925ms","start":"2026-07-09T16:41:12.931502Z","end":"2026-07-09T16:41:13.910241Z","steps":["trace[1909418566] 'agreement among raft nodes before linearized reading' (duration: 978.660339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.910313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:12.93149Z","time spent":"978.808312ms","remote":"127.0.0.1:56554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:42:54.446299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.122378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:42:54.446447Z","caller":"traceutil/trace.go:171","msg":"trace[1579409783] transaction","detail":"{read_only:false; response_revision:3326; number_of_response:1; }","duration":"179.234865ms","start":"2026-07-09T16:42:54.267182Z","end":"2026-07-09T16:42:54.446417Z","steps":["trace[1579409783] 'process raft request' (duration: 77.844208ms)","trace[1579409783] 'compare' (duration: 100.986114ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:42:54.447554Z","caller":"traceutil/trace.go:171","msg":"trace[1416906072] transaction","detail":"{read_only:false; response_revision:3329; number_of_response:1; }","duration":"120.291645ms","start":"2026-07-09T16:42:54.327239Z","end":"2026-07-09T16:42:54.44753Z","steps":["trace[1416906072] 'process raft request' (duration: 120.261924ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:54.447606Z","caller":"traceutil/trace.go:171","msg":"trace[1350464444] transaction","detail":"{read_only:false; response_revision:3327; number_of_response:1; }","duration":"180.190825ms","start":"2026-07-09T16:42:54.26738Z","end":"2026-07-09T16:42:54.44757Z","steps":["trace[1350464444] 'process raft request' (duration: 179.968298ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:54.447708Z","caller":"traceutil/trace.go:171","msg":"trace[484376635] transaction","detail":"{read_only:false; response_revision:3328; number_of_response:1; }","duration":"180.220616ms","start":"2026-07-09T16:42:54.267467Z","end":"2026-07-09T16:42:54.447687Z","steps":["trace[484376635] 'process raft request' (duration: 180.00627ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:54.176431Z","caller":"traceutil/trace.go:171","msg":"trace[935546772] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"128.06354ms","start":"2026-07-09T16:44:54.048336Z","end":"2026-07-09T16:44:54.1764Z","steps":["trace[935546772] 'process raft request' (duration: 127.811671ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:57.509893Z","caller":"traceutil/trace.go:171","msg":"trace[1926409188] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"100.02881ms","start":"2026-07-09T16:44:57.40983Z","end":"2026-07-09T16:44:57.509859Z","steps":["trace[1926409188] 'process raft request' (duration: 99.788761ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:58.192135Z","caller":"traceutil/trace.go:171","msg":"trace[340402490] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"105.679519ms","start":"2026-07-09T16:44:58.086403Z","end":"2026-07-09T16:44:58.192082Z","steps":["trace[340402490] 'process raft request' (duration: 105.069147ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:58.533496Z","caller":"traceutil/trace.go:171","msg":"trace[2013775026] linearizableReadLoop","detail":"{readStateIndex:4860; appliedIndex:4859; }","duration":"104.957154ms","start":"2026-07-09T16:44:58.428523Z","end":"2026-07-09T16:44:58.53348Z","steps":["trace[2013775026] 'read index received' (duration: 104.85589ms)","trace[2013775026] 'applied index is now lower than readState.Index' (duration: 100.564µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:58.533652Z","caller":"traceutil/trace.go:171","msg":"trace[1054324096] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"152.588772ms","start":"2026-07-09T16:44:58.38103Z","end":"2026-07-09T16:44:58.533619Z","steps":["trace[1054324096] 'process raft request' (duration: 152.324532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:58.533738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.203932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-09T16:44:58.533782Z","caller":"traceutil/trace.go:171","msg":"trace[60092299] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4681; }","duration":"105.298586ms","start":"2026-07-09T16:44:58.428471Z","end":"2026-07-09T16:44:58.53377Z","steps":["trace[60092299] 'agreement among raft nodes before linearized reading' (duration: 105.077638ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:04.370651Z","caller":"traceutil/trace.go:171","msg":"trace[1915678975] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"193.450278ms","start":"2026-07-09T16:45:04.177177Z","end":"2026-07-09T16:45:04.370627Z","steps":["trace[1915678975] 'process raft request' (duration: 193.298661ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:04.634786Z","caller":"traceutil/trace.go:171","msg":"trace[1738180078] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"188.030847ms","start":"2026-07-09T16:45:04.446727Z","end":"2026-07-09T16:45:04.634758Z","steps":["trace[1738180078] 'process raft request' (duration: 187.857931ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:04.634933Z","caller":"traceutil/trace.go:171","msg":"trace[1141628188] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"188.283267ms","start":"2026-07-09T16:45:04.446616Z","end":"2026-07-09T16:45:04.634899Z","steps":["trace[1141628188] 'process raft request' (duration: 131.165663ms)","trace[1141628188] 'compare' (duration: 56.531523ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:04.635174Z","caller":"traceutil/trace.go:171","msg":"trace[270506693] linearizableReadLoop","detail":"{readStateIndex:4979; appliedIndex:4975; }","duration":"163.509554ms","start":"2026-07-09T16:45:04.471652Z","end":"2026-07-09T16:45:04.635162Z","steps":["trace[270506693] 'read index received' (duration: 106.102359ms)","trace[270506693] 'applied index is now lower than readState.Index' (duration: 57.406165ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:04.635194Z","caller":"traceutil/trace.go:171","msg":"trace[1112486832] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"187.975135ms","start":"2026-07-09T16:45:04.447211Z","end":"2026-07-09T16:45:04.635186Z","steps":["trace[1112486832] 'process raft request' (duration: 187.464626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:04.63531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.645599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-07-09T16:45:04.635343Z","caller":"traceutil/trace.go:171","msg":"trace[1194429876] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4798; }","duration":"163.721982ms","start":"2026-07-09T16:45:04.471612Z","end":"2026-07-09T16:45:04.635334Z","steps":["trace[1194429876] 'agreement among raft nodes before linearized reading' (duration: 163.594227ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:04.635456Z","caller":"traceutil/trace.go:171","msg":"trace[808015537] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"188.166283ms","start":"2026-07-09T16:45:04.447282Z","end":"2026-07-09T16:45:04.635448Z","steps":["trace[808015537] 'process raft request' (duration: 187.83058ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:05.373305Z","caller":"traceutil/trace.go:171","msg":"trace[1613358578] linearizableReadLoop","detail":"{readStateIndex:4993; appliedIndex:4990; }","duration":"119.205394ms","start":"2026-07-09T16:45:05.254065Z","end":"2026-07-09T16:45:05.373271Z","steps":["trace[1613358578] 'read index received' (duration: 94.108828ms)","trace[1613358578] 'applied index is now lower than readState.Index' (duration: 25.095395ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:05.373426Z","caller":"traceutil/trace.go:171","msg":"trace[10853030] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"161.13247ms","start":"2026-07-09T16:45:05.212254Z","end":"2026-07-09T16:45:05.373387Z","steps":["trace[10853030] 'process raft request' (duration: 135.939091ms)","trace[10853030] 'compare' (duration: 24.706041ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:05.373495Z","caller":"traceutil/trace.go:171","msg":"trace[1412420790] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"160.678373ms","start":"2026-07-09T16:45:05.212801Z","end":"2026-07-09T16:45:05.373479Z","steps":["trace[1412420790] 'process raft request' (duration: 160.32857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:05.373837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.751054ms","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-09T16:45:05.373945Z","caller":"traceutil/trace.go:171","msg":"trace[889085855] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4811; }","duration":"119.921071ms","start":"2026-07-09T16:45:05.254002Z","end":"2026-07-09T16:45:05.373923Z","steps":["trace[889085855] 'agreement among raft nodes before linearized reading' (duration: 119.554627ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:05.374277Z","caller":"traceutil/trace.go:171","msg":"trace[1094775791] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"160.385922ms","start":"2026-07-09T16:45:05.213879Z","end":"2026-07-09T16:45:05.374265Z","steps":["trace[1094775791] 'process raft request' (duration: 159.338143ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:07.512059Z","caller":"traceutil/trace.go:171","msg":"trace[1374638219] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"131.082087ms","start":"2026-07-09T16:45:07.380944Z","end":"2026-07-09T16:45:07.512026Z","steps":["trace[1374638219] 'process raft request' (duration: 130.948081ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:07.513889Z","caller":"traceutil/trace.go:171","msg":"trace[92940181] linearizableReadLoop","detail":"{readStateIndex:5018; appliedIndex:5016; }","duration":"122.500931ms","start":"2026-07-09T16:45:07.391371Z","end":"2026-07-09T16:45:07.513872Z","steps":["trace[92940181] 'read index received' (duration: 120.608841ms)","trace[92940181] 'applied index is now lower than readState.Index' (duration: 1.89105ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:07.513913Z","caller":"traceutil/trace.go:171","msg":"trace[224147527] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"131.484012ms","start":"2026-07-09T16:45:07.382418Z","end":"2026-07-09T16:45:07.513902Z","steps":["trace[224147527] 'process raft request' (duration: 131.359088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:07.514043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.659096ms","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-09T16:45:07.514084Z","caller":"traceutil/trace.go:171","msg":"trace[1423045456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4836; }","duration":"122.72662ms","start":"2026-07-09T16:45:07.391347Z","end":"2026-07-09T16:45:07.514074Z","steps":["trace[1423045456] 'agreement among raft nodes before linearized reading' (duration: 122.582204ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:08.786096Z","caller":"traceutil/trace.go:171","msg":"trace[2059448224] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"241.300429ms","start":"2026-07-09T16:45:08.544778Z","end":"2026-07-09T16:45:08.786079Z","steps":["trace[2059448224] 'process raft request' (duration: 241.181575ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:08.795409Z","caller":"traceutil/trace.go:171","msg":"trace[493299384] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"213.324027ms","start":"2026-07-09T16:45:08.582061Z","end":"2026-07-09T16:45:08.795385Z","steps":["trace[493299384] 'process raft request' (duration: 213.181492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:18.163096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.939713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-09T16:45:18.16316Z","caller":"traceutil/trace.go:171","msg":"trace[1977871597] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4972; }","duration":"608.046307ms","start":"2026-07-09T16:45:17.555098Z","end":"2026-07-09T16:45:18.163145Z","steps":["trace[1977871597] 'range keys from in-memory index tree' (duration: 607.797808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:18.163187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:17.555081Z","time spent":"608.099429ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-09T16:45:18.16327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.891419ms","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:7"} {"level":"info","ts":"2026-07-09T16:45:18.163327Z","caller":"traceutil/trace.go:171","msg":"trace[351847828] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4972; }","duration":"278.999982ms","start":"2026-07-09T16:45:17.884313Z","end":"2026-07-09T16:45:18.163313Z","steps":["trace[351847828] 'count revisions from in-memory index tree' (duration: 278.801535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:18.163346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.12217ms","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-09T16:45:18.163365Z","caller":"traceutil/trace.go:171","msg":"trace[203560264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4972; }","duration":"601.174122ms","start":"2026-07-09T16:45:17.562185Z","end":"2026-07-09T16:45:18.163359Z","steps":["trace[203560264] 'range keys from in-memory index tree' (duration: 600.994736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:18.163382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:17.562166Z","time spent":"601.212074ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:18.163487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.360751ms","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":"warn","ts":"2026-07-09T16:45:18.163471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.23179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:18.163503Z","caller":"traceutil/trace.go:171","msg":"trace[1048401038] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4972; }","duration":"597.405423ms","start":"2026-07-09T16:45:17.566093Z","end":"2026-07-09T16:45:18.163498Z","steps":["trace[1048401038] 'range keys from in-memory index tree' (duration: 597.29405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:18.16353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:17.566077Z","time spent":"597.449084ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T16:45:18.163539Z","caller":"traceutil/trace.go:171","msg":"trace[35706287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4972; }","duration":"231.348844ms","start":"2026-07-09T16:45:17.932172Z","end":"2026-07-09T16:45:18.163521Z","steps":["trace[35706287] 'range keys from in-memory index tree' (duration: 231.147327ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:19.242388Z","caller":"traceutil/trace.go:171","msg":"trace[1254582963] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"119.998166ms","start":"2026-07-09T16:45:19.122354Z","end":"2026-07-09T16:45:19.242352Z","steps":["trace[1254582963] 'process raft request' (duration: 42.528419ms)","trace[1254582963] 'compare' (duration: 77.230968ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:19.242965Z","caller":"traceutil/trace.go:171","msg":"trace[1033362413] linearizableReadLoop","detail":"{readStateIndex:5176; appliedIndex:5175; }","duration":"116.773676ms","start":"2026-07-09T16:45:19.126168Z","end":"2026-07-09T16:45:19.242941Z","steps":["trace[1033362413] 'read index received' (duration: 38.806361ms)","trace[1033362413] 'applied index is now lower than readState.Index' (duration: 77.966205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:19.243621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.44282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T16:45:19.243706Z","caller":"traceutil/trace.go:171","msg":"trace[429891192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4989; }","duration":"117.572086ms","start":"2026-07-09T16:45:19.126117Z","end":"2026-07-09T16:45:19.243689Z","steps":["trace[429891192] 'agreement among raft nodes before linearized reading' (duration: 116.910081ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:19.507699Z","caller":"traceutil/trace.go:171","msg":"trace[45392292] linearizableReadLoop","detail":"{readStateIndex:5178; appliedIndex:5177; }","duration":"238.511345ms","start":"2026-07-09T16:45:19.269165Z","end":"2026-07-09T16:45:19.507676Z","steps":["trace[45392292] 'read index received' (duration: 237.737116ms)","trace[45392292] 'applied index is now lower than readState.Index' (duration: 773.319µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:19.507828Z","caller":"traceutil/trace.go:171","msg":"trace[736268523] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"260.17042ms","start":"2026-07-09T16:45:19.247643Z","end":"2026-07-09T16:45:19.507814Z","steps":["trace[736268523] 'process raft request' (duration: 259.203574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:19.507947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.626477ms","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-09T16:45:19.508023Z","caller":"traceutil/trace.go:171","msg":"trace[513089972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4991; }","duration":"222.7394ms","start":"2026-07-09T16:45:19.285267Z","end":"2026-07-09T16:45:19.508006Z","steps":["trace[513089972] 'agreement among raft nodes before linearized reading' (duration: 222.561994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:19.508234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.064175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:45:19.508272Z","caller":"traceutil/trace.go:171","msg":"trace[381649388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4991; }","duration":"239.130039ms","start":"2026-07-09T16:45:19.269132Z","end":"2026-07-09T16:45:19.508262Z","steps":["trace[381649388] 'agreement among raft nodes before linearized reading' (duration: 239.042616ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:05.874759Z","caller":"traceutil/trace.go:171","msg":"trace[488217803] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"115.377655ms","start":"2026-07-09T16:46:05.759361Z","end":"2026-07-09T16:46:05.874739Z","steps":["trace[488217803] 'process raft request' (duration: 67.120079ms)","trace[488217803] 'compare' (duration: 48.09875ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:05.875192Z","caller":"traceutil/trace.go:171","msg":"trace[1527330946] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"115.473609ms","start":"2026-07-09T16:46:05.759681Z","end":"2026-07-09T16:46:05.875155Z","steps":["trace[1527330946] 'process raft request' (duration: 115.028542ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:21.801857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2840} {"level":"info","ts":"2026-07-09T16:46:21.881946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2840,"took":"79.023984ms","hash":747229553,"current-db-size-bytes":53317632,"current-db-size":"53 MB","current-db-size-in-use-bytes":28667904,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-09T16:46:21.882057Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":747229553,"revision":2840,"compact-revision":-1} {"level":"info","ts":"2026-07-09T16:49:21.247195Z","caller":"traceutil/trace.go:171","msg":"trace[174115732] linearizableReadLoop","detail":"{readStateIndex:6989; appliedIndex:6988; }","duration":"228.06637ms","start":"2026-07-09T16:49:21.019112Z","end":"2026-07-09T16:49:21.247179Z","steps":["trace[174115732] 'read index received' (duration: 227.936767ms)","trace[174115732] 'applied index is now lower than readState.Index' (duration: 129.113µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:49:21.247348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.220443ms","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-09T16:49:21.247407Z","caller":"traceutil/trace.go:171","msg":"trace[147978973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6743; }","duration":"228.321855ms","start":"2026-07-09T16:49:21.019078Z","end":"2026-07-09T16:49:21.247399Z","steps":["trace[147978973] 'agreement among raft nodes before linearized reading' (duration: 228.177042ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:21.247596Z","caller":"traceutil/trace.go:171","msg":"trace[912169920] transaction","detail":"{read_only:false; response_revision:6743; number_of_response:1; }","duration":"387.087747ms","start":"2026-07-09T16:49:20.860498Z","end":"2026-07-09T16:49:21.247586Z","steps":["trace[912169920] 'process raft request' (duration: 386.600356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:21.247668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:20.860486Z","time spent":"387.134528ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:49:21.625102Z","caller":"traceutil/trace.go:171","msg":"trace[40590870] linearizableReadLoop","detail":"{readStateIndex:6990; appliedIndex:6989; }","duration":"132.200189ms","start":"2026-07-09T16:49:21.49288Z","end":"2026-07-09T16:49:21.62508Z","steps":["trace[40590870] 'read index received' (duration: 123.552307ms)","trace[40590870] 'applied index is now lower than readState.Index' (duration: 8.646912ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:49:21.625211Z","caller":"traceutil/trace.go:171","msg":"trace[1661485772] transaction","detail":"{read_only:false; response_revision:6744; number_of_response:1; }","duration":"372.785407ms","start":"2026-07-09T16:49:21.252403Z","end":"2026-07-09T16:49:21.625189Z","steps":["trace[1661485772] 'process raft request' (duration: 364.071843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:21.625261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.357522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:21.625299Z","caller":"traceutil/trace.go:171","msg":"trace[283611139] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:6744; }","duration":"132.440974ms","start":"2026-07-09T16:49:21.492847Z","end":"2026-07-09T16:49:21.625288Z","steps":["trace[283611139] 'agreement among raft nodes before linearized reading' (duration: 132.359412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:21.625336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.252376Z","time spent":"372.89429ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:49:22.334485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394590145730630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:49:22.835612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394590145730630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:49:23.336688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394590145730630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:49:23.837509Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394590145730630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:49:23.936436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.004905082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T16:49:23.936495Z","caller":"traceutil/trace.go:171","msg":"trace[105082517] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.005008105s","start":"2026-07-09T16:49:21.931473Z","end":"2026-07-09T16:49:23.936481Z","steps":["trace[105082517] 'agreement among raft nodes before linearized reading' (duration: 2.004925123s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:23.936527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.931462Z","time spent":"2.005059585s","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:49:24.161653Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.449679543s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T16:49:24.383397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:23.845645Z","time spent":"537.745627ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-09T16:49:24.384194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.142006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:49:24.384374Z","caller":"traceutil/trace.go:171","msg":"trace[1922229694] transaction","detail":"{read_only:false; response_revision:6746; number_of_response:1; }","duration":"2.75281077s","start":"2026-07-09T16:49:21.631539Z","end":"2026-07-09T16:49:24.38435Z","steps":["trace[1922229694] 'process raft request' (duration: 2.530438118s)","trace[1922229694] 'compare' (duration: 221.927222ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:49:24.384489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.631519Z","time spent":"2.752906872s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:49:24.386362Z","caller":"traceutil/trace.go:171","msg":"trace[362587412] transaction","detail":"{read_only:false; response_revision:6747; number_of_response:1; }","duration":"2.754729773s","start":"2026-07-09T16:49:21.631613Z","end":"2026-07-09T16:49:24.386343Z","steps":["trace[362587412] 'process raft request' (duration: 2.752668387s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.386942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.631607Z","time spent":"2.755262775s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:49:24.471212Z","caller":"traceutil/trace.go:171","msg":"trace[2010668855] linearizableReadLoop","detail":"{readStateIndex:6998; appliedIndex:6991; }","duration":"2.636756055s","start":"2026-07-09T16:49:21.83444Z","end":"2026-07-09T16:49:24.471196Z","steps":["trace[2010668855] 'read index received' (duration: 2.32757166s)","trace[2010668855] 'applied index is now lower than readState.Index' (duration: 309.183685ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:49:24.47122Z","caller":"traceutil/trace.go:171","msg":"trace[1099546088] transaction","detail":"{read_only:false; response_revision:6748; number_of_response:1; }","duration":"2.543612003s","start":"2026-07-09T16:49:21.927565Z","end":"2026-07-09T16:49:24.471177Z","steps":["trace[1099546088] 'process raft request' (duration: 2.54342071s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.471611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.927543Z","time spent":"2.543891732s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:49:24.471721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.637254396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/cert-manager-selfsigned-ca\" ","response":"range_response_count:1 size:2479"} {"level":"info","ts":"2026-07-09T16:49:24.471793Z","caller":"traceutil/trace.go:171","msg":"trace[1201738719] range","detail":"{range_begin:/registry/secrets/cert-manager/cert-manager-selfsigned-ca; range_end:; response_count:1; response_revision:6752; }","duration":"2.637371828s","start":"2026-07-09T16:49:21.834408Z","end":"2026-07-09T16:49:24.47178Z","steps":["trace[1201738719] 'agreement among raft nodes before linearized reading' (duration: 2.636945138s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.471883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.834394Z","time spent":"2.63747713s","remote":"127.0.0.1:53318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":2502,"request content":"key:\"/registry/secrets/cert-manager/cert-manager-selfsigned-ca\" "} {"level":"warn","ts":"2026-07-09T16:49:24.472078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.879885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:49:24.472086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.51824777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-09T16:49:24.472108Z","caller":"traceutil/trace.go:171","msg":"trace[2078733726] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:6752; }","duration":"686.938106ms","start":"2026-07-09T16:49:23.785162Z","end":"2026-07-09T16:49:24.4721Z","steps":["trace[2078733726] 'agreement among raft nodes before linearized reading' (duration: 686.886026ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.472156Z","caller":"traceutil/trace.go:171","msg":"trace[1637507893] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6752; }","duration":"2.518325422s","start":"2026-07-09T16:49:21.953787Z","end":"2026-07-09T16:49:24.472113Z","steps":["trace[1637507893] 'agreement among raft nodes before linearized reading' (duration: 2.51819137s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.472127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:23.785149Z","time spent":"686.973308ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:49:24.472195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.953766Z","time spent":"2.518419024s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-09T16:49:24.47251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.079055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-09T16:49:24.47269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.90299ms","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-09T16:49:24.472826Z","caller":"traceutil/trace.go:171","msg":"trace[1130121792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6752; }","duration":"830.079005ms","start":"2026-07-09T16:49:23.642735Z","end":"2026-07-09T16:49:24.472814Z","steps":["trace[1130121792] 'agreement among raft nodes before linearized reading' (duration: 829.794018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.473113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:23.642706Z","time spent":"830.39288ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:49:24.472842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839372634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12208"} {"level":"info","ts":"2026-07-09T16:49:24.473288Z","caller":"traceutil/trace.go:171","msg":"trace[604314682] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6752; }","duration":"1.839844565s","start":"2026-07-09T16:49:22.63343Z","end":"2026-07-09T16:49:24.473275Z","steps":["trace[604314682] 'agreement among raft nodes before linearized reading' (duration: 1.839283652s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.47335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.633416Z","time spent":"1.839923376s","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12231,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-09T16:49:24.473396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.216771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:24.473436Z","caller":"traceutil/trace.go:171","msg":"trace[2059083610] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:6752; }","duration":"111.299453ms","start":"2026-07-09T16:49:24.362126Z","end":"2026-07-09T16:49:24.473425Z","steps":["trace[2059083610] 'agreement among raft nodes before linearized reading' (duration: 111.235932ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.47129Z","caller":"traceutil/trace.go:171","msg":"trace[949899507] transaction","detail":"{read_only:false; response_revision:6750; number_of_response:1; }","duration":"2.233639109s","start":"2026-07-09T16:49:22.237641Z","end":"2026-07-09T16:49:24.47128Z","steps":["trace[949899507] 'process raft request' (duration: 2.233484716s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.473583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.237623Z","time spent":"2.235928812s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:49:24.472548Z","caller":"traceutil/trace.go:171","msg":"trace[463831150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6752; }","duration":"749.150186ms","start":"2026-07-09T16:49:23.723389Z","end":"2026-07-09T16:49:24.472539Z","steps":["trace[463831150] 'agreement among raft nodes before linearized reading' (duration: 749.044784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.473674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:23.723375Z","time spent":"750.287122ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T16:49:24.471314Z","caller":"traceutil/trace.go:171","msg":"trace[1628570701] transaction","detail":"{read_only:false; response_revision:6749; number_of_response:1; }","duration":"2.292568474s","start":"2026-07-09T16:49:22.178741Z","end":"2026-07-09T16:49:24.47131Z","steps":["trace[1628570701] 'process raft request' (duration: 2.292351829s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.471297Z","caller":"traceutil/trace.go:171","msg":"trace[643471287] transaction","detail":"{read_only:false; response_revision:6751; number_of_response:1; }","duration":"2.204631615s","start":"2026-07-09T16:49:22.266656Z","end":"2026-07-09T16:49:24.471287Z","steps":["trace[643471287] 'process raft request' (duration: 2.204493011s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.47134Z","caller":"traceutil/trace.go:171","msg":"trace[2078527169] transaction","detail":"{read_only:false; response_revision:6752; number_of_response:1; }","duration":"1.29985048s","start":"2026-07-09T16:49:23.171486Z","end":"2026-07-09T16:49:24.471336Z","steps":["trace[2078527169] 'process raft request' (duration: 1.299688426s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.473873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:23.171462Z","time spent":"1.302386836s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:49:24.474396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223516685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-09T16:49:24.474422Z","caller":"traceutil/trace.go:171","msg":"trace[252134790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6752; }","duration":"1.223562947s","start":"2026-07-09T16:49:23.250852Z","end":"2026-07-09T16:49:24.474415Z","steps":["trace[252134790] 'agreement among raft nodes before linearized reading' (duration: 1.223473584s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.474441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:23.250842Z","time spent":"1.223594477s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:49:24.474838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570505642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:24.474862Z","caller":"traceutil/trace.go:171","msg":"trace[22916429] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:6752; }","duration":"1.570561793s","start":"2026-07-09T16:49:22.904294Z","end":"2026-07-09T16:49:24.474856Z","steps":["trace[22916429] 'agreement among raft nodes before linearized reading' (duration: 1.570519532s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.474883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.904279Z","time spent":"1.570598804s","remote":"127.0.0.1:53544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:49:24.475075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.983935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:24.475108Z","caller":"traceutil/trace.go:171","msg":"trace[1152946346] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:6752; }","duration":"639.032617ms","start":"2026-07-09T16:49:23.836066Z","end":"2026-07-09T16:49:24.475099Z","steps":["trace[1152946346] 'agreement among raft nodes before linearized reading' (duration: 638.984105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.475131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:23.836055Z","time spent":"639.069337ms","remote":"127.0.0.1:59528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:49:24.475328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.580978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:24.475359Z","caller":"traceutil/trace.go:171","msg":"trace[1637420773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6752; }","duration":"537.629269ms","start":"2026-07-09T16:49:23.937721Z","end":"2026-07-09T16:49:24.47535Z","steps":["trace[1637420773] 'agreement among raft nodes before linearized reading' (duration: 537.583678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.475382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:23.936731Z","time spent":"538.645551ms","remote":"127.0.0.1:56554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:49:24.472933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.934871063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T16:49:24.475704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.178724Z","time spent":"2.296949181s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:49:24.475452Z","caller":"traceutil/trace.go:171","msg":"trace[422072235] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:6752; }","duration":"1.93741886s","start":"2026-07-09T16:49:22.538018Z","end":"2026-07-09T16:49:24.475437Z","steps":["trace[422072235] 'agreement among raft nodes before linearized reading' (duration: 1.934864122s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.47251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746511115s","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-09T16:49:24.475899Z","caller":"traceutil/trace.go:171","msg":"trace[1966373195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6752; }","duration":"1.749932951s","start":"2026-07-09T16:49:22.725957Z","end":"2026-07-09T16:49:24.47589Z","steps":["trace[1966373195] 'agreement among raft nodes before linearized reading' (duration: 1.746496114s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.475928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.725942Z","time spent":"1.749976302s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:49:24.475941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.538005Z","time spent":"1.937815739s","remote":"127.0.0.1:36016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":11,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:49:24.473835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.266642Z","time spent":"2.20713372s","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:49:24.656714Z","caller":"traceutil/trace.go:171","msg":"trace[1765099995] linearizableReadLoop","detail":"{readStateIndex:6999; appliedIndex:6998; }","duration":"177.495096ms","start":"2026-07-09T16:49:24.479198Z","end":"2026-07-09T16:49:24.656693Z","steps":["trace[1765099995] 'read index received' (duration: 177.402774ms)","trace[1765099995] 'applied index is now lower than readState.Index' (duration: 91.732µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:49:24.656798Z","caller":"traceutil/trace.go:171","msg":"trace[61533256] transaction","detail":"{read_only:false; response_revision:6753; number_of_response:1; }","duration":"177.956015ms","start":"2026-07-09T16:49:24.478767Z","end":"2026-07-09T16:49:24.656723Z","steps":["trace[61533256] 'process raft request' (duration: 177.801032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.656923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.709211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c0aca4418084cb\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-07-09T16:49:24.656949Z","caller":"traceutil/trace.go:171","msg":"trace[286058272] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18c0aca4418084cb; range_end:; response_count:1; response_revision:6753; }","duration":"177.765791ms","start":"2026-07-09T16:49:24.479176Z","end":"2026-07-09T16:49:24.656942Z","steps":["trace[286058272] 'agreement among raft nodes before linearized reading' (duration: 177.653009ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.705545Z","caller":"traceutil/trace.go:171","msg":"trace[1365566341] transaction","detail":"{read_only:false; response_revision:6757; number_of_response:1; }","duration":"219.094438ms","start":"2026-07-09T16:49:24.486426Z","end":"2026-07-09T16:49:24.70552Z","steps":["trace[1365566341] 'process raft request' (duration: 218.967654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.705537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.144826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12169"} {"level":"info","ts":"2026-07-09T16:49:24.70563Z","caller":"traceutil/trace.go:171","msg":"trace[1217259610] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6757; }","duration":"226.276538ms","start":"2026-07-09T16:49:24.479333Z","end":"2026-07-09T16:49:24.70561Z","steps":["trace[1217259610] 'agreement among raft nodes before linearized reading' (duration: 226.099495ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.705779Z","caller":"traceutil/trace.go:171","msg":"trace[298006457] transaction","detail":"{read_only:false; response_revision:6756; number_of_response:1; }","duration":"219.764722ms","start":"2026-07-09T16:49:24.486008Z","end":"2026-07-09T16:49:24.705772Z","steps":["trace[298006457] 'process raft request' (duration: 219.339363ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.705771Z","caller":"traceutil/trace.go:171","msg":"trace[858480753] transaction","detail":"{read_only:false; response_revision:6754; number_of_response:1; }","duration":"226.118855ms","start":"2026-07-09T16:49:24.479636Z","end":"2026-07-09T16:49:24.705755Z","steps":["trace[858480753] 'process raft request' (duration: 225.451759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.705989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.849249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:24.706016Z","caller":"traceutil/trace.go:171","msg":"trace[620271854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6757; }","duration":"221.893359ms","start":"2026-07-09T16:49:24.484117Z","end":"2026-07-09T16:49:24.70601Z","steps":["trace[620271854] 'agreement among raft nodes before linearized reading' (duration: 221.826848ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.705557Z","caller":"traceutil/trace.go:171","msg":"trace[1277790100] transaction","detail":"{read_only:false; response_revision:6755; number_of_response:1; }","duration":"219.798272ms","start":"2026-07-09T16:49:24.48572Z","end":"2026-07-09T16:49:24.705518Z","steps":["trace[1277790100] 'process raft request' (duration: 219.577437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.946174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.940994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:49:24.946248Z","caller":"traceutil/trace.go:171","msg":"trace[603169469] linearizableReadLoop","detail":"{readStateIndex:7004; appliedIndex:7003; }","duration":"201.008173ms","start":"2026-07-09T16:49:24.74523Z","end":"2026-07-09T16:49:24.946239Z","steps":["trace[603169469] 'read index received' (duration: 77.896756ms)","trace[603169469] 'applied index is now lower than readState.Index' (duration: 123.110547ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:49:24.9463Z","caller":"traceutil/trace.go:171","msg":"trace[66175287] transaction","detail":"{read_only:false; response_revision:6758; number_of_response:1; }","duration":"287.675634ms","start":"2026-07-09T16:49:24.658618Z","end":"2026-07-09T16:49:24.946294Z","steps":["trace[66175287] 'process raft request' (duration: 164.557116ms)","trace[66175287] 'compare' (duration: 122.706098ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:49:24.946442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.213698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-09T16:49:24.94646Z","caller":"traceutil/trace.go:171","msg":"trace[718421293] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:6758; }","duration":"201.263039ms","start":"2026-07-09T16:49:24.745193Z","end":"2026-07-09T16:49:24.946456Z","steps":["trace[718421293] 'agreement among raft nodes before linearized reading' (duration: 201.210877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.946534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.464657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:24.946707Z","caller":"traceutil/trace.go:171","msg":"trace[1291824386] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6758; }","duration":"161.545288ms","start":"2026-07-09T16:49:24.785023Z","end":"2026-07-09T16:49:24.946568Z","steps":["trace[1291824386] 'agreement among raft nodes before linearized reading' (duration: 161.469616ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:51:21.807706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5476} {"level":"info","ts":"2026-07-09T16:51:21.884234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5476,"took":"74.993767ms","hash":1831777705,"current-db-size-bytes":53317632,"current-db-size":"53 MB","current-db-size-in-use-bytes":18518016,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-09T16:51:21.884281Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1831777705,"revision":5476,"compact-revision":2840}