{"level":"warn","ts":"2026-04-07T17:50:53.330787Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T17:50:53.330889Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.150:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.150:2380","--initial-cluster=instance=https://199.19.213.150:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.150:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.150: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-04-07T17:50:53.33096Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T17:50:53.330968Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-04-07T17:50:53.330993Z","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-04-07T17:50:53.331618Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"]} {"level":"info","ts":"2026-04-07T17:50:53.33169Z","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.19.213.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.150: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-04-07T17:50:53.336284Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.338334ms"} {"level":"info","ts":"2026-04-07T17:50:53.354557Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dbd0ffa4f8c99812","cluster-id":"98aad832cd49bc30"} {"level":"info","ts":"2026-04-07T17:50:53.35467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T17:50:53.354708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 0"} {"level":"info","ts":"2026-04-07T17:50:53.354721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dbd0ffa4f8c99812 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T17:50:53.354732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 1"} {"level":"info","ts":"2026-04-07T17:50:53.354778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"warn","ts":"2026-04-07T17:50:53.366623Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T17:50:53.370693Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T17:50:53.376479Z","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-04-07T17:50:53.379142Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dbd0ffa4f8c99812","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T17:50:53.379348Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dbd0ffa4f8c99812","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T17:50:53.379437Z","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-04-07T17:50:53.379513Z","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-04-07T17:50:53.379523Z","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-04-07T17:50:53.380863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"info","ts":"2026-04-07T17:50:53.381106Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","added-peer-id":"dbd0ffa4f8c99812","added-peer-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-04-07T17:50:53.383598Z","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-04-07T17:50:53.383806Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dbd0ffa4f8c99812","initial-advertise-peer-urls":["https://199.19.213.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T17:50:53.383846Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T17:50:53.383883Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-04-07T17:50:53.384078Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-04-07T17:50:54.35585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T17:50:54.355971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T17:50:54.356009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgPreVoteResp from dbd0ffa4f8c99812 at term 1"} {"level":"info","ts":"2026-04-07T17:50:54.356046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became candidate at term 2"} {"level":"info","ts":"2026-04-07T17:50:54.35607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgVoteResp from dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-04-07T17:50:54.356091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became leader at term 2"} {"level":"info","ts":"2026-04-07T17:50:54.356115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dbd0ffa4f8c99812 elected leader dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-04-07T17:50:54.357549Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dbd0ffa4f8c99812","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.150:2379]}","request-path":"/0/members/dbd0ffa4f8c99812/attributes","cluster-id":"98aad832cd49bc30","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T17:50:54.357699Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:50:54.357649Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:50:54.357774Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:50:54.361563Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T17:50:54.36158Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T17:50:54.365431Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:50:54.365687Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:50:54.365752Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:50:54.366617Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.150:2379"} {"level":"info","ts":"2026-04-07T17:50:54.367516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-07T17:50:55.822946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.340104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-04-07T17:50:55.823115Z","caller":"traceutil/trace.go:171","msg":"trace[1849668795] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12; }","duration":"102.529698ms","start":"2026-04-07T17:50:55.720553Z","end":"2026-04-07T17:50:55.823083Z","steps":["trace[1849668795] 'agreement among raft nodes before linearized reading' (duration: 102.251421ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:58.473462Z","caller":"traceutil/trace.go:171","msg":"trace[1083034561] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"118.220431ms","start":"2026-04-07T17:50:58.355213Z","end":"2026-04-07T17:50:58.473434Z","steps":["trace[1083034561] 'read index received' (duration: 117.987105ms)","trace[1083034561] 'applied index is now lower than readState.Index' (duration: 232.086µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:50:58.473519Z","caller":"traceutil/trace.go:171","msg":"trace[2086829860] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"284.254201ms","start":"2026-04-07T17:50:58.189228Z","end":"2026-04-07T17:50:58.473482Z","steps":["trace[2086829860] 'process raft request' (duration: 284.068726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:58.473624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.383596ms","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-04-07T17:50:58.47369Z","caller":"traceutil/trace.go:171","msg":"trace[78109833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:245; }","duration":"118.496518ms","start":"2026-04-07T17:50:58.355182Z","end":"2026-04-07T17:50:58.473678Z","steps":["trace[78109833] 'agreement among raft nodes before linearized reading' (duration: 118.388325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:01.373543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.508067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-07T17:51:01.37365Z","caller":"traceutil/trace.go:171","msg":"trace[220012981] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:326; }","duration":"189.672052ms","start":"2026-04-07T17:51:01.183953Z","end":"2026-04-07T17:51:01.373625Z","steps":["trace[220012981] 'range keys from in-memory index tree' (duration: 189.379814ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:01.824029Z","caller":"traceutil/trace.go:171","msg":"trace[1226941211] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"446.279975ms","start":"2026-04-07T17:51:01.377702Z","end":"2026-04-07T17:51:01.823982Z","steps":["trace[1226941211] 'process raft request' (duration: 446.142951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:01.824643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:01.377679Z","time spent":"446.439259ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:51:02.609259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.339464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:51:02.60939Z","caller":"traceutil/trace.go:171","msg":"trace[1819608607] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"734.333735ms","start":"2026-04-07T17:51:01.87504Z","end":"2026-04-07T17:51:02.609374Z","steps":["trace[1819608607] 'read index received' (duration: 134.678523ms)","trace[1819608607] 'applied index is now lower than readState.Index' (duration: 599.653992ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:02.609425Z","caller":"traceutil/trace.go:171","msg":"trace[1719279209] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"1.231631215s","start":"2026-04-07T17:51:01.377758Z","end":"2026-04-07T17:51:02.609389Z","steps":["trace[1719279209] 'process raft request' (duration: 631.914071ms)","trace[1719279209] 'compare' (duration: 599.093797ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:02.609523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:01.377615Z","time spent":"1.231868619s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":219,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T17:51:02.60962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.564691ms","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-04-07T17:51:02.609695Z","caller":"traceutil/trace.go:171","msg":"trace[1653682369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:328; }","duration":"734.675236ms","start":"2026-04-07T17:51:01.875004Z","end":"2026-04-07T17:51:02.609679Z","steps":["trace[1653682369] 'agreement among raft nodes before linearized reading' (duration: 734.494101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:02.609755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:01.874987Z","time spent":"734.757078ms","remote":"127.0.0.1:42548","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":"info","ts":"2026-04-07T17:51:03.030379Z","caller":"traceutil/trace.go:171","msg":"trace[399398270] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"415.832513ms","start":"2026-04-07T17:51:02.614514Z","end":"2026-04-07T17:51:03.030346Z","steps":["trace[399398270] 'process raft request' (duration: 392.12777ms)","trace[399398270] 'compare' (duration: 23.417286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:03.030604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:02.614489Z","time spent":"415.977776ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:51:03.407699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.201535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:51:03.407879Z","caller":"traceutil/trace.go:171","msg":"trace[1206705270] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:337; }","duration":"790.267897ms","start":"2026-04-07T17:51:02.617582Z","end":"2026-04-07T17:51:03.40785Z","steps":["trace[1206705270] 'read index received' (duration: 389.072389ms)","trace[1206705270] 'applied index is now lower than readState.Index' (duration: 401.194628ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:03.407914Z","caller":"traceutil/trace.go:171","msg":"trace[336784093] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"793.026ms","start":"2026-04-07T17:51:02.614852Z","end":"2026-04-07T17:51:03.407878Z","steps":["trace[336784093] 'process raft request' (duration: 591.578848ms)","trace[336784093] 'compare' (duration: 201.091553ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:03.407956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.378071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-07T17:51:03.407985Z","caller":"traceutil/trace.go:171","msg":"trace[733270854] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:331; }","duration":"790.445882ms","start":"2026-04-07T17:51:02.617529Z","end":"2026-04-07T17:51:03.407975Z","steps":["trace[733270854] 'agreement among raft nodes before linearized reading' (duration: 790.393261ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:03.407986Z","caller":"traceutil/trace.go:171","msg":"trace[2139599823] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"792.27712ms","start":"2026-04-07T17:51:02.615688Z","end":"2026-04-07T17:51:03.407965Z","steps":["trace[2139599823] 'process raft request' (duration: 792.100475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:03.408468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:02.617509Z","time spent":"790.507183ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T17:51:03.408459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:02.615675Z","time spent":"792.351521ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:51:03.40871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:02.614835Z","time spent":"793.137383ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:51:05.14966Z","caller":"traceutil/trace.go:171","msg":"trace[133092582] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:400; }","duration":"114.785051ms","start":"2026-04-07T17:51:05.034854Z","end":"2026-04-07T17:51:05.149639Z","steps":["trace[133092582] 'read index received' (duration: 78.980309ms)","trace[133092582] 'applied index is now lower than readState.Index' (duration: 35.803672ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:05.149903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.043298ms","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-04-07T17:51:05.149986Z","caller":"traceutil/trace.go:171","msg":"trace[1722133579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"115.172731ms","start":"2026-04-07T17:51:05.034798Z","end":"2026-04-07T17:51:05.14997Z","steps":["trace[1722133579] 'agreement among raft nodes before linearized reading' (duration: 114.919014ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:05.150396Z","caller":"traceutil/trace.go:171","msg":"trace[805977217] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"138.175297ms","start":"2026-04-07T17:51:05.011496Z","end":"2026-04-07T17:51:05.149672Z","steps":["trace[805977217] 'process raft request' (duration: 102.400815ms)","trace[805977217] 'compare' (duration: 35.570906ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:06.417335Z","caller":"traceutil/trace.go:171","msg":"trace[265193997] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:409; }","duration":"125.676667ms","start":"2026-04-07T17:51:06.291634Z","end":"2026-04-07T17:51:06.41731Z","steps":["trace[265193997] 'read index received' (duration: 79.058569ms)","trace[265193997] 'applied index is now lower than readState.Index' (duration: 46.614628ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:06.417365Z","caller":"traceutil/trace.go:171","msg":"trace[1000040952] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"201.609465ms","start":"2026-04-07T17:51:06.215728Z","end":"2026-04-07T17:51:06.417338Z","steps":["trace[1000040952] 'process raft request' (duration: 154.960467ms)","trace[1000040952] 'compare' (duration: 46.488904ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:06.41748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.824581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:51:06.417573Z","caller":"traceutil/trace.go:171","msg":"trace[1349377423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"125.961354ms","start":"2026-04-07T17:51:06.291587Z","end":"2026-04-07T17:51:06.417549Z","steps":["trace[1349377423] 'agreement among raft nodes before linearized reading' (duration: 125.78511ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:29.22578Z","caller":"traceutil/trace.go:171","msg":"trace[1431373830] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"292.328964ms","start":"2026-04-07T17:51:28.933401Z","end":"2026-04-07T17:51:29.22573Z","steps":["trace[1431373830] 'process raft request' (duration: 292.042576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:29.763257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957993917937987338,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T17:51:30.263398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957993917937987338,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T17:51:30.408067Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.098240672s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T17:51:30.408534Z","caller":"traceutil/trace.go:171","msg":"trace[1205057791] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.177115719s","start":"2026-04-07T17:51:29.231389Z","end":"2026-04-07T17:51:30.408504Z","steps":["trace[1205057791] 'process raft request' (duration: 1.176927055s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.408743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:29.231345Z","time spent":"1.177280243s","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:51:30.434405Z","caller":"traceutil/trace.go:171","msg":"trace[295783905] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"902.121921ms","start":"2026-04-07T17:51:29.532253Z","end":"2026-04-07T17:51:30.434375Z","steps":["trace[295783905] 'process raft request' (duration: 901.914566ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:30.43445Z","caller":"traceutil/trace.go:171","msg":"trace[474779781] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"704.225543ms","start":"2026-04-07T17:51:29.730205Z","end":"2026-04-07T17:51:30.43443Z","steps":["trace[474779781] 'process raft request' (duration: 704.09174ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:30.434492Z","caller":"traceutil/trace.go:171","msg":"trace[956961369] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:643; }","duration":"1.171494401s","start":"2026-04-07T17:51:29.262961Z","end":"2026-04-07T17:51:30.434455Z","steps":["trace[956961369] 'read index received' (duration: 1.145344884s)","trace[956961369] 'applied index is now lower than readState.Index' (duration: 26.148417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:30.434539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:29.532232Z","time spent":"902.239775ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T17:51:30.434733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:29.730175Z","time spent":"704.482759ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T17:51:30.434589Z","caller":"traceutil/trace.go:171","msg":"trace[1729825223] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"156.384936ms","start":"2026-04-07T17:51:30.278192Z","end":"2026-04-07T17:51:30.434577Z","steps":["trace[1729825223] 'process raft request' (duration: 156.206542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.434581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171607164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T17:51:30.435048Z","caller":"traceutil/trace.go:171","msg":"trace[700942366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"1.172089776s","start":"2026-04-07T17:51:29.262916Z","end":"2026-04-07T17:51:30.435006Z","steps":["trace[700942366] 'agreement among raft nodes before linearized reading' (duration: 1.171612064s)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:30.434575Z","caller":"traceutil/trace.go:171","msg":"trace[1498789327] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"433.300843ms","start":"2026-04-07T17:51:30.001242Z","end":"2026-04-07T17:51:30.434542Z","steps":["trace[1498789327] 'process raft request' (duration: 433.090648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.435148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:29.262893Z","time spent":"1.172217899s","remote":"127.0.0.1:42548","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-04-07T17:51:30.435229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:30.001212Z","time spent":"433.930881ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4559,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:51:30.439252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144727097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:51:30.439319Z","caller":"traceutil/trace.go:171","msg":"trace[1185626289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"1.144854189s","start":"2026-04-07T17:51:29.294449Z","end":"2026-04-07T17:51:30.439304Z","steps":["trace[1185626289] 'agreement among raft nodes before linearized reading' (duration: 1.144722496s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.43935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:29.29439Z","time spent":"1.144953593s","remote":"127.0.0.1:42290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T17:51:30.43953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.379177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:51:30.439556Z","caller":"traceutil/trace.go:171","msg":"trace[1089426097] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:629; }","duration":"511.44079ms","start":"2026-04-07T17:51:29.928106Z","end":"2026-04-07T17:51:30.439547Z","steps":["trace[1089426097] 'agreement among raft nodes before linearized reading' (duration: 511.407688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.439573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:29.928084Z","time spent":"511.484621ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-07T17:51:30.439674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111250566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:51:30.439693Z","caller":"traceutil/trace.go:171","msg":"trace[2029977119] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:629; }","duration":"1.111315407s","start":"2026-04-07T17:51:29.328371Z","end":"2026-04-07T17:51:30.439686Z","steps":["trace[2029977119] 'agreement among raft nodes before linearized reading' (duration: 1.111287947s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.439708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:29.328346Z","time spent":"1.111358997s","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-04-07T17:51:30.43976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.618189ms","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-04-07T17:51:30.439805Z","caller":"traceutil/trace.go:171","msg":"trace[908408035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"909.716461ms","start":"2026-04-07T17:51:29.530078Z","end":"2026-04-07T17:51:30.439795Z","steps":["trace[908408035] 'agreement among raft nodes before linearized reading' (duration: 909.483065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.439841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:29.530049Z","time spent":"909.783424ms","remote":"127.0.0.1:42548","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-04-07T17:51:30.439846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.462797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:51:30.439876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.486394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:51:30.439866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.64116ms","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-04-07T17:51:30.439912Z","caller":"traceutil/trace.go:171","msg":"trace[1666191092] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:629; }","duration":"311.55355ms","start":"2026-04-07T17:51:30.128343Z","end":"2026-04-07T17:51:30.439896Z","steps":["trace[1666191092] 'agreement among raft nodes before linearized reading' (duration: 311.477037ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:30.439912Z","caller":"traceutil/trace.go:171","msg":"trace[821640704] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:629; }","duration":"399.550246ms","start":"2026-04-07T17:51:30.040351Z","end":"2026-04-07T17:51:30.439901Z","steps":["trace[821640704] 'agreement among raft nodes before linearized reading' (duration: 399.501534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.439947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:30.04033Z","time spent":"399.611986ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-04-07T17:51:30.439947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:30.12833Z","time spent":"311.61111ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-07T17:51:30.439953Z","caller":"traceutil/trace.go:171","msg":"trace[579164339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"906.768614ms","start":"2026-04-07T17:51:29.533168Z","end":"2026-04-07T17:51:30.439936Z","steps":["trace[579164339] 'agreement among raft nodes before linearized reading' (duration: 906.505088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.439991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:29.533158Z","time spent":"906.824366ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T17:51:30.440076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.388661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-07T17:51:30.440169Z","caller":"traceutil/trace.go:171","msg":"trace[5571261] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:629; }","duration":"111.556046ms","start":"2026-04-07T17:51:30.328595Z","end":"2026-04-07T17:51:30.440152Z","steps":["trace[5571261] 'agreement among raft nodes before linearized reading' (duration: 111.168246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.440247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.250288ms","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-04-07T17:51:30.44028Z","caller":"traceutil/trace.go:171","msg":"trace[1386136427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"464.882094ms","start":"2026-04-07T17:51:29.97539Z","end":"2026-04-07T17:51:30.440272Z","steps":["trace[1386136427] 'agreement among raft nodes before linearized reading' (duration: 464.130144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:30.440303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:29.975326Z","time spent":"464.969087ms","remote":"127.0.0.1:42548","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":"info","ts":"2026-04-07T17:51:36.606402Z","caller":"traceutil/trace.go:171","msg":"trace[323360395] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"119.390482ms","start":"2026-04-07T17:51:36.486805Z","end":"2026-04-07T17:51:36.606196Z","steps":["trace[323360395] 'read index received' (duration: 119.098164ms)","trace[323360395] 'applied index is now lower than readState.Index' (duration: 291.118µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:36.606497Z","caller":"traceutil/trace.go:171","msg":"trace[1643681448] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"133.195005ms","start":"2026-04-07T17:51:36.473253Z","end":"2026-04-07T17:51:36.606448Z","steps":["trace[1643681448] 'process raft request' (duration: 132.776745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:36.606853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.957607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-07T17:51:36.606847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.014089ms","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-04-07T17:51:36.606918Z","caller":"traceutil/trace.go:171","msg":"trace[2069232520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"120.0514ms","start":"2026-04-07T17:51:36.486849Z","end":"2026-04-07T17:51:36.606901Z","steps":["trace[2069232520] 'agreement among raft nodes before linearized reading' (duration: 119.889375ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:36.606921Z","caller":"traceutil/trace.go:171","msg":"trace[395580826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:688; }","duration":"120.136013ms","start":"2026-04-07T17:51:36.486772Z","end":"2026-04-07T17:51:36.606908Z","steps":["trace[395580826] 'agreement among raft nodes before linearized reading' (duration: 119.803614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:36.607031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.663164ms","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-04-07T17:51:36.60715Z","caller":"traceutil/trace.go:171","msg":"trace[1581505809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:688; }","duration":"115.8549ms","start":"2026-04-07T17:51:36.491274Z","end":"2026-04-07T17:51:36.607129Z","steps":["trace[1581505809] 'agreement among raft nodes before linearized reading' (duration: 115.510751ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:36.712645Z","caller":"traceutil/trace.go:171","msg":"trace[1835994365] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"101.043089ms","start":"2026-04-07T17:51:36.611573Z","end":"2026-04-07T17:51:36.712616Z","steps":["trace[1835994365] 'process raft request' (duration: 76.549025ms)","trace[1835994365] 'compare' (duration: 24.354031ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:36.854482Z","caller":"traceutil/trace.go:171","msg":"trace[1863723208] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"241.694901ms","start":"2026-04-07T17:51:36.612762Z","end":"2026-04-07T17:51:36.854457Z","steps":["trace[1863723208] 'process raft request' (duration: 241.62952ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:36.854549Z","caller":"traceutil/trace.go:171","msg":"trace[1898474630] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"242.538003ms","start":"2026-04-07T17:51:36.611977Z","end":"2026-04-07T17:51:36.854515Z","steps":["trace[1898474630] 'process raft request' (duration: 241.738681ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:36.854589Z","caller":"traceutil/trace.go:171","msg":"trace[1959276272] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"242.260745ms","start":"2026-04-07T17:51:36.61167Z","end":"2026-04-07T17:51:36.85393Z","steps":["trace[1959276272] 'process raft request' (duration: 237.930421ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:37.282023Z","caller":"traceutil/trace.go:171","msg":"trace[326772100] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"408.655654ms","start":"2026-04-07T17:51:36.873338Z","end":"2026-04-07T17:51:37.281994Z","steps":["trace[326772100] 'process raft request' (duration: 396.932385ms)","trace[326772100] 'compare' (duration: 11.599796ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:37.282191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:36.873298Z","time spent":"408.807409ms","remote":"127.0.0.1:42482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:51:38.369763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.404836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.150\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T17:51:38.369865Z","caller":"traceutil/trace.go:171","msg":"trace[1790545019] range","detail":"{range_begin:/registry/masterleases/199.19.213.150; range_end:; response_count:1; response_revision:694; }","duration":"946.53041ms","start":"2026-04-07T17:51:37.423294Z","end":"2026-04-07T17:51:38.369824Z","steps":["trace[1790545019] 'range keys from in-memory index tree' (duration: 946.242702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:38.369904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:37.42327Z","time spent":"946.624892ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.150\" "} {"level":"info","ts":"2026-04-07T17:51:39.040181Z","caller":"traceutil/trace.go:171","msg":"trace[782816033] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"429.122754ms","start":"2026-04-07T17:51:38.611033Z","end":"2026-04-07T17:51:39.040156Z","steps":["trace[782816033] 'read index received' (duration: 428.899578ms)","trace[782816033] 'applied index is now lower than readState.Index' (duration: 221.776µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:39.040246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:38.371092Z","time spent":"669.122509ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T17:51:39.0405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.401641ms","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-04-07T17:51:39.040547Z","caller":"traceutil/trace.go:171","msg":"trace[2050483039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"429.557164ms","start":"2026-04-07T17:51:38.610979Z","end":"2026-04-07T17:51:39.040536Z","steps":["trace[2050483039] 'agreement among raft nodes before linearized reading' (duration: 429.304928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:39.040591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:38.610952Z","time spent":"429.625377ms","remote":"127.0.0.1:42548","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-04-07T17:51:39.04065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.192222ms","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-04-07T17:51:39.040728Z","caller":"traceutil/trace.go:171","msg":"trace[966176754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"324.315865ms","start":"2026-04-07T17:51:38.716391Z","end":"2026-04-07T17:51:39.040707Z","steps":["trace[966176754] 'agreement among raft nodes before linearized reading' (duration: 324.076459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:39.040713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.464809ms","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-04-07T17:51:39.040754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.10754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-07T17:51:39.04078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:38.716371Z","time spent":"324.397297ms","remote":"127.0.0.1:42548","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":"info","ts":"2026-04-07T17:51:39.040804Z","caller":"traceutil/trace.go:171","msg":"trace[897184035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"180.606113ms","start":"2026-04-07T17:51:38.860183Z","end":"2026-04-07T17:51:39.040789Z","steps":["trace[897184035] 'agreement among raft nodes before linearized reading' (duration: 180.429698ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:39.040814Z","caller":"traceutil/trace.go:171","msg":"trace[1777629325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"180.225023ms","start":"2026-04-07T17:51:38.860571Z","end":"2026-04-07T17:51:39.040796Z","steps":["trace[1777629325] 'agreement among raft nodes before linearized reading' (duration: 180.012577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:39.040978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.346145ms","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-04-07T17:51:39.041026Z","caller":"traceutil/trace.go:171","msg":"trace[1165836947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"180.395248ms","start":"2026-04-07T17:51:38.860619Z","end":"2026-04-07T17:51:39.041014Z","steps":["trace[1165836947] 'agreement among raft nodes before linearized reading' (duration: 180.286215ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:39.335464Z","caller":"traceutil/trace.go:171","msg":"trace[1165234808] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"293.161615ms","start":"2026-04-07T17:51:39.042087Z","end":"2026-04-07T17:51:39.335248Z","steps":["trace[1165234808] 'process raft request' (duration: 280.210294ms)","trace[1165234808] 'compare' (duration: 12.785236ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:39.344097Z","caller":"traceutil/trace.go:171","msg":"trace[1296801080] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"298.863405ms","start":"2026-04-07T17:51:39.045206Z","end":"2026-04-07T17:51:39.34407Z","steps":["trace[1296801080] 'process raft request' (duration: 298.753663ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:39.344277Z","caller":"traceutil/trace.go:171","msg":"trace[455036423] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"298.461985ms","start":"2026-04-07T17:51:39.045804Z","end":"2026-04-07T17:51:39.344266Z","steps":["trace[455036423] 'process raft request' (duration: 298.347122ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:39.344097Z","caller":"traceutil/trace.go:171","msg":"trace[267372528] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"299.108852ms","start":"2026-04-07T17:51:39.044955Z","end":"2026-04-07T17:51:39.344064Z","steps":["trace[267372528] 'process raft request' (duration: 298.838625ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:39.34441Z","caller":"traceutil/trace.go:171","msg":"trace[946556921] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"297.042017ms","start":"2026-04-07T17:51:39.047357Z","end":"2026-04-07T17:51:39.344399Z","steps":["trace[946556921] 'process raft request' (duration: 296.882292ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:39.344771Z","caller":"traceutil/trace.go:171","msg":"trace[1085128198] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"299.219056ms","start":"2026-04-07T17:51:39.045514Z","end":"2026-04-07T17:51:39.344733Z","steps":["trace[1085128198] 'process raft request' (duration: 298.499456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:44.65712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.112899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:51:44.657238Z","caller":"traceutil/trace.go:171","msg":"trace[38898136] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"297.231753ms","start":"2026-04-07T17:51:44.359984Z","end":"2026-04-07T17:51:44.657216Z","steps":["trace[38898136] 'process raft request' (duration: 18.916888ms)","trace[38898136] 'compare' (duration: 277.981076ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:56.313041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.71705ms","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-04-07T17:51:56.313156Z","caller":"traceutil/trace.go:171","msg":"trace[1086533128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"823.842083ms","start":"2026-04-07T17:51:55.489293Z","end":"2026-04-07T17:51:56.313135Z","steps":["trace[1086533128] 'range keys from in-memory index tree' (duration: 823.602226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:56.313198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:55.489287Z","time spent":"823.900804ms","remote":"127.0.0.1:42548","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-04-07T17:51:56.313485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.938494ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:51:56.313563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.917187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-07T17:51:56.31357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.367104ms","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-04-07T17:51:56.313627Z","caller":"traceutil/trace.go:171","msg":"trace[1041442305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"859.449465ms","start":"2026-04-07T17:51:55.454163Z","end":"2026-04-07T17:51:56.313612Z","steps":["trace[1041442305] 'range keys from in-memory index tree' (duration: 859.154598ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:56.31362Z","caller":"traceutil/trace.go:171","msg":"trace[1431855323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"827.004979ms","start":"2026-04-07T17:51:55.486602Z","end":"2026-04-07T17:51:56.313607Z","steps":["trace[1431855323] 'range keys from in-memory index tree' (duration: 826.69768ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:56.313629Z","caller":"traceutil/trace.go:171","msg":"trace[1358676792] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:809; }","duration":"875.110789ms","start":"2026-04-07T17:51:55.438492Z","end":"2026-04-07T17:51:56.313603Z","steps":["trace[1358676792] 'range keys from in-memory index tree' (duration: 874.921373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:56.313658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:55.454148Z","time spent":"859.502957ms","remote":"127.0.0.1:42548","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-04-07T17:51:56.313665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:55.486588Z","time spent":"827.06925ms","remote":"127.0.0.1:42548","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-04-07T17:51:56.313743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.015807ms","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-04-07T17:51:56.31385Z","caller":"traceutil/trace.go:171","msg":"trace[1796601459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"878.183011ms","start":"2026-04-07T17:51:55.435641Z","end":"2026-04-07T17:51:56.313824Z","steps":["trace[1796601459] 'range keys from in-memory index tree' (duration: 877.775221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:56.313905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:55.435615Z","time spent":"878.277074ms","remote":"127.0.0.1:42548","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":"warn","ts":"2026-04-07T17:51:56.314079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.295852ms","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-04-07T17:51:56.314125Z","caller":"traceutil/trace.go:171","msg":"trace[878624358] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:809; }","duration":"591.556706ms","start":"2026-04-07T17:51:55.722552Z","end":"2026-04-07T17:51:56.314109Z","steps":["trace[878624358] 'count revisions from in-memory index tree' (duration: 590.173589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:56.314162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:55.722525Z","time spent":"591.622337ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:51:56.314157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.081953ms","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-04-07T17:51:56.314568Z","caller":"traceutil/trace.go:171","msg":"trace[1325279321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"853.153665ms","start":"2026-04-07T17:51:55.461036Z","end":"2026-04-07T17:51:56.31419Z","steps":["trace[1325279321] 'range keys from in-memory index tree' (duration: 852.917488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:56.314618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:55.461026Z","time spent":"853.578487ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T17:51:56.603729Z","caller":"traceutil/trace.go:171","msg":"trace[1046978849] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"309.675478ms","start":"2026-04-07T17:51:56.294027Z","end":"2026-04-07T17:51:56.603702Z","steps":["trace[1046978849] 'read index received' (duration: 309.553155ms)","trace[1046978849] 'applied index is now lower than readState.Index' (duration: 121.043µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:56.603783Z","caller":"traceutil/trace.go:171","msg":"trace[1806252593] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"614.602868ms","start":"2026-04-07T17:51:55.989146Z","end":"2026-04-07T17:51:56.603748Z","steps":["trace[1806252593] 'process raft request' (duration: 614.383802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:56.603918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.850754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:51:56.604033Z","caller":"traceutil/trace.go:171","msg":"trace[682445019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"310.072329ms","start":"2026-04-07T17:51:56.293937Z","end":"2026-04-07T17:51:56.604009Z","steps":["trace[682445019] 'agreement among raft nodes before linearized reading' (duration: 309.865453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:56.604052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:55.989097Z","time spent":"614.773893ms","remote":"127.0.0.1:42524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:51:56.604098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:56.293908Z","time spent":"310.169003ms","remote":"127.0.0.1:42290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T17:51:56.612507Z","caller":"traceutil/trace.go:171","msg":"trace[727400422] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"293.748249ms","start":"2026-04-07T17:51:56.318731Z","end":"2026-04-07T17:51:56.612479Z","steps":["trace[727400422] 'process raft request' (duration: 293.464471ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:56.612646Z","caller":"traceutil/trace.go:171","msg":"trace[699874903] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"293.187223ms","start":"2026-04-07T17:51:56.319441Z","end":"2026-04-07T17:51:56.612628Z","steps":["trace[699874903] 'process raft request' (duration: 293.038159ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:56.612715Z","caller":"traceutil/trace.go:171","msg":"trace[993205400] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"293.613906ms","start":"2026-04-07T17:51:56.319078Z","end":"2026-04-07T17:51:56.612692Z","steps":["trace[993205400] 'process raft request' (duration: 293.310747ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:56.612821Z","caller":"traceutil/trace.go:171","msg":"trace[735409270] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"293.575364ms","start":"2026-04-07T17:51:56.319231Z","end":"2026-04-07T17:51:56.612806Z","steps":["trace[735409270] 'process raft request' (duration: 293.201463ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:56.612885Z","caller":"traceutil/trace.go:171","msg":"trace[1566250330] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"293.900542ms","start":"2026-04-07T17:51:56.318977Z","end":"2026-04-07T17:51:56.612877Z","steps":["trace[1566250330] 'process raft request' (duration: 293.359737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:56.61316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.855941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:51:56.613227Z","caller":"traceutil/trace.go:171","msg":"trace[1674949633] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:815; }","duration":"283.952233ms","start":"2026-04-07T17:51:56.32926Z","end":"2026-04-07T17:51:56.613212Z","steps":["trace[1674949633] 'agreement among raft nodes before linearized reading' (duration: 283.786169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:56.613059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.148803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:51:56.613333Z","caller":"traceutil/trace.go:171","msg":"trace[1060003493] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:815; }","duration":"202.44171ms","start":"2026-04-07T17:51:56.410883Z","end":"2026-04-07T17:51:56.613324Z","steps":["trace[1060003493] 'agreement among raft nodes before linearized reading' (duration: 202.122631ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:11.25626Z","caller":"traceutil/trace.go:171","msg":"trace[1012861795] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"202.670097ms","start":"2026-04-07T17:52:11.053563Z","end":"2026-04-07T17:52:11.256233Z","steps":["trace[1012861795] 'process raft request' (duration: 202.472442ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:11.341579Z","caller":"traceutil/trace.go:171","msg":"trace[2076690469] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"101.716519ms","start":"2026-04-07T17:52:11.239835Z","end":"2026-04-07T17:52:11.341551Z","steps":["trace[2076690469] 'process raft request' (duration: 101.629367ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:11.341592Z","caller":"traceutil/trace.go:171","msg":"trace[1812710645] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"222.007509ms","start":"2026-04-07T17:52:11.119551Z","end":"2026-04-07T17:52:11.341559Z","steps":["trace[1812710645] 'process raft request' (duration: 212.503072ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:30.170914Z","caller":"traceutil/trace.go:171","msg":"trace[1401989080] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"115.554892ms","start":"2026-04-07T17:52:30.055332Z","end":"2026-04-07T17:52:30.170887Z","steps":["trace[1401989080] 'read index received' (duration: 115.332386ms)","trace[1401989080] 'applied index is now lower than readState.Index' (duration: 221.076µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:30.170977Z","caller":"traceutil/trace.go:171","msg":"trace[460064229] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"154.214277ms","start":"2026-04-07T17:52:30.016724Z","end":"2026-04-07T17:52:30.170939Z","steps":["trace[460064229] 'process raft request' (duration: 153.988811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:30.171103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.738188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:52:30.171195Z","caller":"traceutil/trace.go:171","msg":"trace[1855372350] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1019; }","duration":"115.906652ms","start":"2026-04-07T17:52:30.055269Z","end":"2026-04-07T17:52:30.171176Z","steps":["trace[1855372350] 'agreement among raft nodes before linearized reading' (duration: 115.714677ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:31.148678Z","caller":"traceutil/trace.go:171","msg":"trace[2025876815] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"381.984563ms","start":"2026-04-07T17:52:30.766663Z","end":"2026-04-07T17:52:31.148648Z","steps":["trace[2025876815] 'read index received' (duration: 381.738117ms)","trace[2025876815] 'applied index is now lower than readState.Index' (duration: 245.156µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:31.148747Z","caller":"traceutil/trace.go:171","msg":"trace[292507820] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"382.568009ms","start":"2026-04-07T17:52:30.766141Z","end":"2026-04-07T17:52:31.148709Z","steps":["trace[292507820] 'process raft request' (duration: 382.326042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:31.148924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:30.766116Z","time spent":"382.708462ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:31.148977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.280171ms","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-04-07T17:52:31.149034Z","caller":"traceutil/trace.go:171","msg":"trace[39779686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"382.392744ms","start":"2026-04-07T17:52:30.766625Z","end":"2026-04-07T17:52:31.149018Z","steps":["trace[39779686] 'agreement among raft nodes before linearized reading' (duration: 382.180738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:31.149113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:30.766616Z","time spent":"382.485895ms","remote":"127.0.0.1:42548","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-04-07T17:52:31.149119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.907642ms","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-04-07T17:52:31.149215Z","caller":"traceutil/trace.go:171","msg":"trace[748568640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"378.057216ms","start":"2026-04-07T17:52:30.77113Z","end":"2026-04-07T17:52:31.149187Z","steps":["trace[748568640] 'agreement among raft nodes before linearized reading' (duration: 377.754807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:31.149304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:30.771123Z","time spent":"378.151069ms","remote":"127.0.0.1:42548","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-04-07T17:52:31.149303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.784239ms","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-04-07T17:52:31.149374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.240442ms","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-04-07T17:52:31.149486Z","caller":"traceutil/trace.go:171","msg":"trace[2064468687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"361.387906ms","start":"2026-04-07T17:52:30.78807Z","end":"2026-04-07T17:52:31.149458Z","steps":["trace[2064468687] 'agreement among raft nodes before linearized reading' (duration: 360.865672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:31.14954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:30.788064Z","time spent":"361.466808ms","remote":"127.0.0.1:42548","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-04-07T17:52:31.149417Z","caller":"traceutil/trace.go:171","msg":"trace[1012320890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"377.937313ms","start":"2026-04-07T17:52:30.771465Z","end":"2026-04-07T17:52:31.149403Z","steps":["trace[1012320890] 'agreement among raft nodes before linearized reading' (duration: 377.543643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:31.149609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:30.771458Z","time spent":"378.136469ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T17:52:31.266698Z","caller":"traceutil/trace.go:171","msg":"trace[871208404] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"113.177579ms","start":"2026-04-07T17:52:31.153497Z","end":"2026-04-07T17:52:31.266675Z","steps":["trace[871208404] 'process raft request' (duration: 38.677915ms)","trace[871208404] 'compare' (duration: 74.35795ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:31.266889Z","caller":"traceutil/trace.go:171","msg":"trace[1949353974] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"112.380177ms","start":"2026-04-07T17:52:31.154497Z","end":"2026-04-07T17:52:31.266877Z","steps":["trace[1949353974] 'process raft request' (duration: 112.299015ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:31.266913Z","caller":"traceutil/trace.go:171","msg":"trace[474740427] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"112.393207ms","start":"2026-04-07T17:52:31.154513Z","end":"2026-04-07T17:52:31.266906Z","steps":["trace[474740427] 'process raft request' (duration: 112.321755ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:31.267006Z","caller":"traceutil/trace.go:171","msg":"trace[1670374313] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"113.320342ms","start":"2026-04-07T17:52:31.153678Z","end":"2026-04-07T17:52:31.266998Z","steps":["trace[1670374313] 'process raft request' (duration: 112.973123ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:31.844131Z","caller":"traceutil/trace.go:171","msg":"trace[576171706] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"281.952829ms","start":"2026-04-07T17:52:31.562155Z","end":"2026-04-07T17:52:31.844108Z","steps":["trace[576171706] 'read index received' (duration: 275.519845ms)","trace[576171706] 'applied index is now lower than readState.Index' (duration: 6.431944ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:31.844236Z","caller":"traceutil/trace.go:171","msg":"trace[1884308884] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"407.009269ms","start":"2026-04-07T17:52:31.437213Z","end":"2026-04-07T17:52:31.844222Z","steps":["trace[1884308884] 'process raft request' (duration: 400.535804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:31.844329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:31.437185Z","time spent":"407.074251ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:31.844341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.178295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:52:31.844375Z","caller":"traceutil/trace.go:171","msg":"trace[1044852207] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1028; }","duration":"282.258047ms","start":"2026-04-07T17:52:31.562107Z","end":"2026-04-07T17:52:31.844365Z","steps":["trace[1044852207] 'agreement among raft nodes before linearized reading' (duration: 282.087033ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:32.816073Z","caller":"traceutil/trace.go:171","msg":"trace[466170078] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"521.863483ms","start":"2026-04-07T17:52:32.294174Z","end":"2026-04-07T17:52:32.816037Z","steps":["trace[466170078] 'read index received' (duration: 460.120994ms)","trace[466170078] 'applied index is now lower than readState.Index' (duration: 61.740929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:32.816284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.070709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:32.816324Z","caller":"traceutil/trace.go:171","msg":"trace[1974131671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"522.17905ms","start":"2026-04-07T17:52:32.294131Z","end":"2026-04-07T17:52:32.81631Z","steps":["trace[1974131671] 'agreement among raft nodes before linearized reading' (duration: 522.032117ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:32.81651Z","caller":"traceutil/trace.go:171","msg":"trace[1576668248] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"934.971897ms","start":"2026-04-07T17:52:31.88152Z","end":"2026-04-07T17:52:32.816492Z","steps":["trace[1576668248] 'process raft request' (duration: 872.780636ms)","trace[1576668248] 'compare' (duration: 61.296928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:32.816633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:31.881497Z","time spent":"935.06688ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:32.816362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:32.294112Z","time spent":"522.243324ms","remote":"127.0.0.1:42290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T17:52:33.481703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.000314ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T17:52:33.481836Z","caller":"traceutil/trace.go:171","msg":"trace[1869050162] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"326.209775ms","start":"2026-04-07T17:52:33.155608Z","end":"2026-04-07T17:52:33.481817Z","steps":["trace[1869050162] 'read index received' (duration: 38.018137ms)","trace[1869050162] 'applied index is now lower than readState.Index' (duration: 288.190088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:33.482064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.185507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T17:52:33.48209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.468522ms","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-04-07T17:52:33.482157Z","caller":"traceutil/trace.go:171","msg":"trace[62515521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1029; }","duration":"326.598336ms","start":"2026-04-07T17:52:33.155544Z","end":"2026-04-07T17:52:33.482142Z","steps":["trace[62515521] 'agreement among raft nodes before linearized reading' (duration: 326.36557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:33.482168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.243731ms","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-04-07T17:52:33.482191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.463308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-07T17:52:33.482198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:33.155519Z","time spent":"326.672158ms","remote":"127.0.0.1:42548","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":"info","ts":"2026-04-07T17:52:33.482221Z","caller":"traceutil/trace.go:171","msg":"trace[1206114775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1029; }","duration":"210.546341ms","start":"2026-04-07T17:52:33.271668Z","end":"2026-04-07T17:52:33.482214Z","steps":["trace[1206114775] 'agreement among raft nodes before linearized reading' (duration: 210.467159ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:33.482225Z","caller":"traceutil/trace.go:171","msg":"trace[151601465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1029; }","duration":"210.313853ms","start":"2026-04-07T17:52:33.271897Z","end":"2026-04-07T17:52:33.482211Z","steps":["trace[151601465] 'agreement among raft nodes before linearized reading' (duration: 210.155729ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:33.482103Z","caller":"traceutil/trace.go:171","msg":"trace[1737213480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1029; }","duration":"211.280699ms","start":"2026-04-07T17:52:33.27081Z","end":"2026-04-07T17:52:33.48209Z","steps":["trace[1737213480] 'agreement among raft nodes before linearized reading' (duration: 211.118465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:33.482331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.52627ms","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-04-07T17:52:33.482412Z","caller":"traceutil/trace.go:171","msg":"trace[390320093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1029; }","duration":"210.638422ms","start":"2026-04-07T17:52:33.271753Z","end":"2026-04-07T17:52:33.482391Z","steps":["trace[390320093] 'agreement among raft nodes before linearized reading' (duration: 210.364325ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:39.765366Z","caller":"traceutil/trace.go:171","msg":"trace[1543894041] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1087; }","duration":"190.368044ms","start":"2026-04-07T17:52:39.574974Z","end":"2026-04-07T17:52:39.765342Z","steps":["trace[1543894041] 'read index received' (duration: 181.455264ms)","trace[1543894041] 'applied index is now lower than readState.Index' (duration: 8.91187ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:39.765404Z","caller":"traceutil/trace.go:171","msg":"trace[537667578] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"217.621171ms","start":"2026-04-07T17:52:39.547752Z","end":"2026-04-07T17:52:39.765373Z","steps":["trace[537667578] 'process raft request' (duration: 217.537919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:39.765646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.153495ms","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-04-07T17:52:39.7657Z","caller":"traceutil/trace.go:171","msg":"trace[1671473083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1052; }","duration":"188.269968ms","start":"2026-04-07T17:52:39.57742Z","end":"2026-04-07T17:52:39.76569Z","steps":["trace[1671473083] 'agreement among raft nodes before linearized reading' (duration: 188.084593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:39.765752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.761245ms","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-04-07T17:52:39.765804Z","caller":"traceutil/trace.go:171","msg":"trace[1033372905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"190.874089ms","start":"2026-04-07T17:52:39.574917Z","end":"2026-04-07T17:52:39.765791Z","steps":["trace[1033372905] 'agreement among raft nodes before linearized reading' (duration: 190.55363ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:39.765569Z","caller":"traceutil/trace.go:171","msg":"trace[1699404767] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"218.485215ms","start":"2026-04-07T17:52:39.546906Z","end":"2026-04-07T17:52:39.765391Z","steps":["trace[1699404767] 'process raft request' (duration: 209.465351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:40.06437Z","caller":"traceutil/trace.go:171","msg":"trace[837920954] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"293.42703ms","start":"2026-04-07T17:52:39.770915Z","end":"2026-04-07T17:52:40.064342Z","steps":["trace[837920954] 'process raft request' (duration: 293.183763ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:40.06904Z","caller":"traceutil/trace.go:171","msg":"trace[100425645] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"148.074541ms","start":"2026-04-07T17:52:39.92093Z","end":"2026-04-07T17:52:40.069005Z","steps":["trace[100425645] 'process raft request' (duration: 147.880126ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:56.507447Z","caller":"traceutil/trace.go:171","msg":"trace[1308928228] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"281.917032ms","start":"2026-04-07T17:52:56.22551Z","end":"2026-04-07T17:52:56.507427Z","steps":["trace[1308928228] 'read index received' (duration: 281.689206ms)","trace[1308928228] 'applied index is now lower than readState.Index' (duration: 227.106µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:56.507533Z","caller":"traceutil/trace.go:171","msg":"trace[686332395] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"289.466445ms","start":"2026-04-07T17:52:56.218041Z","end":"2026-04-07T17:52:56.507507Z","steps":["trace[686332395] 'process raft request' (duration: 289.206357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:56.507619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.104977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-07T17:52:56.50762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.880887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:56.507647Z","caller":"traceutil/trace.go:171","msg":"trace[398663124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"282.184769ms","start":"2026-04-07T17:52:56.225455Z","end":"2026-04-07T17:52:56.507639Z","steps":["trace[398663124] 'agreement among raft nodes before linearized reading' (duration: 282.069186ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:56.507676Z","caller":"traceutil/trace.go:171","msg":"trace[891682637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1106; }","duration":"214.973098ms","start":"2026-04-07T17:52:56.292688Z","end":"2026-04-07T17:52:56.507661Z","steps":["trace[891682637] 'agreement among raft nodes before linearized reading' (duration: 214.878366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:56.507679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.825509ms","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-04-07T17:52:56.507735Z","caller":"traceutil/trace.go:171","msg":"trace[2064360400] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1106; }","duration":"131.931712ms","start":"2026-04-07T17:52:56.375788Z","end":"2026-04-07T17:52:56.50772Z","steps":["trace[2064360400] 'agreement among raft nodes before linearized reading' (duration: 131.84296ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:57.767825Z","caller":"traceutil/trace.go:171","msg":"trace[163812613] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"112.353765ms","start":"2026-04-07T17:52:57.655442Z","end":"2026-04-07T17:52:57.767796Z","steps":["trace[163812613] 'process raft request' (duration: 112.103219ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:58.336172Z","caller":"traceutil/trace.go:171","msg":"trace[1242578514] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"392.212141ms","start":"2026-04-07T17:52:57.943937Z","end":"2026-04-07T17:52:58.336149Z","steps":["trace[1242578514] 'process raft request' (duration: 392.027245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:58.336318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:57.94393Z","time spent":"392.316434ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:52:58.33638Z","caller":"traceutil/trace.go:171","msg":"trace[1185836119] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1151; }","duration":"355.963675ms","start":"2026-04-07T17:52:57.980392Z","end":"2026-04-07T17:52:58.336356Z","steps":["trace[1185836119] 'read index received' (duration: 355.480802ms)","trace[1185836119] 'applied index is now lower than readState.Index' (duration: 481.673µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:58.33658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.17218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:52:58.33665Z","caller":"traceutil/trace.go:171","msg":"trace[115184065] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1112; }","duration":"356.287743ms","start":"2026-04-07T17:52:57.980345Z","end":"2026-04-07T17:52:58.336633Z","steps":["trace[115184065] 'agreement among raft nodes before linearized reading' (duration: 356.16838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:58.336703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:57.980326Z","time spent":"356.363075ms","remote":"127.0.0.1:40964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:52:58.336776Z","caller":"traceutil/trace.go:171","msg":"trace[1289084415] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"392.623543ms","start":"2026-04-07T17:52:57.943989Z","end":"2026-04-07T17:52:58.336613Z","steps":["trace[1289084415] 'process raft request' (duration: 392.122189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:58.336938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:57.943973Z","time spent":"392.890329ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:58.641959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.780843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:58.642051Z","caller":"traceutil/trace.go:171","msg":"trace[896089308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1112; }","duration":"136.927436ms","start":"2026-04-07T17:52:58.505105Z","end":"2026-04-07T17:52:58.642032Z","steps":["trace[896089308] 'range keys from in-memory index tree' (duration: 136.68342ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:58.642295Z","caller":"traceutil/trace.go:171","msg":"trace[1089362096] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"129.545659ms","start":"2026-04-07T17:52:58.512726Z","end":"2026-04-07T17:52:58.642272Z","steps":["trace[1089362096] 'process raft request' (duration: 95.02503ms)","trace[1089362096] 'compare' (duration: 34.136369ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:59.342811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.363438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:52:59.342976Z","caller":"traceutil/trace.go:171","msg":"trace[252202009] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"403.495504ms","start":"2026-04-07T17:52:58.93946Z","end":"2026-04-07T17:52:59.342955Z","steps":["trace[252202009] 'process raft request' (duration: 403.439483ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:59.343246Z","caller":"traceutil/trace.go:171","msg":"trace[1656746185] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"696.130825ms","start":"2026-04-07T17:52:58.647098Z","end":"2026-04-07T17:52:59.343229Z","steps":["trace[1656746185] 'process raft request' (duration: 109.279843ms)","trace[1656746185] 'compare' (duration: 586.112262ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:59.343306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:58.647072Z","time spent":"696.203297ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:59.343401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:58.939417Z","time spent":"403.647829ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:00.584688Z","caller":"traceutil/trace.go:171","msg":"trace[1619160013] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"239.50335ms","start":"2026-04-07T17:53:00.345157Z","end":"2026-04-07T17:53:00.58466Z","steps":["trace[1619160013] 'process raft request' (duration: 239.353156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:01.382078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.194785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:53:01.382168Z","caller":"traceutil/trace.go:171","msg":"trace[1924665572] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1158; }","duration":"964.842231ms","start":"2026-04-07T17:53:00.417311Z","end":"2026-04-07T17:53:01.382153Z","steps":["trace[1924665572] 'read index received' (duration: 167.297286ms)","trace[1924665572] 'applied index is now lower than readState.Index' (duration: 797.543835ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:01.382454Z","caller":"traceutil/trace.go:171","msg":"trace[1590382926] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"1.037061386s","start":"2026-04-07T17:53:00.345357Z","end":"2026-04-07T17:53:01.382419Z","steps":["trace[1590382926] 'process raft request' (duration: 909.449779ms)","trace[1590382926] 'compare' (duration: 126.582208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:01.38261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:00.345348Z","time spent":"1.03720053s","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:53:01.382594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.261172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:53:01.38277Z","caller":"traceutil/trace.go:171","msg":"trace[381703566] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1119; }","duration":"965.505859ms","start":"2026-04-07T17:53:00.417247Z","end":"2026-04-07T17:53:01.382753Z","steps":["trace[381703566] 'agreement among raft nodes before linearized reading' (duration: 965.251322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:01.382826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:00.417226Z","time spent":"965.583581ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:53:01.88225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957993917937988500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T17:53:02.383364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957993917937988500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T17:53:02.864247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.816425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:53:02.864678Z","caller":"traceutil/trace.go:171","msg":"trace[1977938196] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1160; }","duration":"1.482466109s","start":"2026-04-07T17:53:01.382191Z","end":"2026-04-07T17:53:02.864657Z","steps":["trace[1977938196] 'read index received' (duration: 1.104065s)","trace[1977938196] 'applied index is now lower than readState.Index' (duration: 378.398439ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:02.864683Z","caller":"traceutil/trace.go:171","msg":"trace[802387956] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"2.215837978s","start":"2026-04-07T17:53:00.648815Z","end":"2026-04-07T17:53:02.864653Z","steps":["trace[802387956] 'process raft request' (duration: 1.837539931s)","trace[802387956] 'compare' (duration: 377.596688ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:02.86511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:00.648793Z","time spent":"2.216216907s","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:53:03.329819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.465984ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-07T17:53:03.32988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.826237324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:03.329924Z","caller":"traceutil/trace.go:171","msg":"trace[1277939627] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"465.150215ms","start":"2026-04-07T17:53:02.864754Z","end":"2026-04-07T17:53:03.329905Z","steps":["trace[1277939627] 'read index received' (duration: 69.572µs)","trace[1277939627] 'applied index is now lower than readState.Index' (duration: 465.079142ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:03.329963Z","caller":"traceutil/trace.go:171","msg":"trace[1103363033] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1120; }","duration":"2.826388198s","start":"2026-04-07T17:53:00.503552Z","end":"2026-04-07T17:53:03.329941Z","steps":["trace[1103363033] 'agreement among raft nodes before linearized reading' (duration: 2.361258634s)","trace[1103363033] 'count revisions from in-memory index tree' (duration: 464.97697ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:03.330018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:00.503532Z","time spent":"2.82647629s","remote":"127.0.0.1:42442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:53:03.330097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.653864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:53:03.330155Z","caller":"traceutil/trace.go:171","msg":"trace[516795106] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1120; }","duration":"665.765448ms","start":"2026-04-07T17:53:02.664374Z","end":"2026-04-07T17:53:03.33014Z","steps":["trace[516795106] 'agreement among raft nodes before linearized reading' (duration: 665.649315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:03.330199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:02.66435Z","time spent":"665.83933ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:53:03.330275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.104077ms","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-04-07T17:53:03.330325Z","caller":"traceutil/trace.go:171","msg":"trace[1268900551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1120; }","duration":"741.191928ms","start":"2026-04-07T17:53:02.589117Z","end":"2026-04-07T17:53:03.330309Z","steps":["trace[1268900551] 'agreement among raft nodes before linearized reading' (duration: 741.025383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:03.330378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:02.589066Z","time spent":"741.300772ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T17:53:03.330472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.538687822s","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-04-07T17:53:03.330526Z","caller":"traceutil/trace.go:171","msg":"trace[1690683610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1120; }","duration":"1.538766735s","start":"2026-04-07T17:53:01.791741Z","end":"2026-04-07T17:53:03.330508Z","steps":["trace[1690683610] 'agreement among raft nodes before linearized reading' (duration: 1.538617351s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:03.330568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:01.791723Z","time spent":"1.538837337s","remote":"127.0.0.1:42548","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-04-07T17:53:03.330577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.983801388s","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-04-07T17:53:03.330639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737011383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:53:03.330695Z","caller":"traceutil/trace.go:171","msg":"trace[1070982862] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1120; }","duration":"1.737109815s","start":"2026-04-07T17:53:01.593574Z","end":"2026-04-07T17:53:03.330684Z","steps":["trace[1070982862] 'agreement among raft nodes before linearized reading' (duration: 1.736980112s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:03.330728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:01.593552Z","time spent":"1.737166548s","remote":"127.0.0.1:42632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:53:03.330737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.588638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:03.330785Z","caller":"traceutil/trace.go:171","msg":"trace[631859523] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1120; }","duration":"530.675989ms","start":"2026-04-07T17:53:02.800099Z","end":"2026-04-07T17:53:03.330775Z","steps":["trace[631859523] 'agreement among raft nodes before linearized reading' (duration: 530.605308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:03.33082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:02.800079Z","time spent":"530.732922ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:53:03.330887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.98783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:53:03.330912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038516126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:03.330926Z","caller":"traceutil/trace.go:171","msg":"trace[344601061] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1120; }","duration":"358.065361ms","start":"2026-04-07T17:53:02.972849Z","end":"2026-04-07T17:53:03.330915Z","steps":["trace[344601061] 'agreement among raft nodes before linearized reading' (duration: 358.011999ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:03.330952Z","caller":"traceutil/trace.go:171","msg":"trace[1955101800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1120; }","duration":"1.038590767s","start":"2026-04-07T17:53:02.292349Z","end":"2026-04-07T17:53:03.330939Z","steps":["trace[1955101800] 'agreement among raft nodes before linearized reading' (duration: 1.038534085s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:03.330313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681837238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:53:03.330964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:02.972826Z","time spent":"358.129463ms","remote":"127.0.0.1:41088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:53:03.330988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:02.29233Z","time spent":"1.038650008s","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T17:53:03.330999Z","caller":"traceutil/trace.go:171","msg":"trace[2125552958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1120; }","duration":"1.682573868s","start":"2026-04-07T17:53:01.648409Z","end":"2026-04-07T17:53:03.330983Z","steps":["trace[2125552958] 'agreement among raft nodes before linearized reading' (duration: 1.681844629s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:03.331048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:01.648385Z","time spent":"1.682645069s","remote":"127.0.0.1:42290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T17:53:03.330649Z","caller":"traceutil/trace.go:171","msg":"trace[507374169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1120; }","duration":"1.983932653s","start":"2026-04-07T17:53:01.346698Z","end":"2026-04-07T17:53:03.330631Z","steps":["trace[507374169] 'agreement among raft nodes before linearized reading' (duration: 1.518012748s)","trace[507374169] 'range keys from in-memory index tree' (duration: 465.70545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:03.331157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:01.346674Z","time spent":"1.984468827s","remote":"127.0.0.1:42548","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":"warn","ts":"2026-04-07T17:53:03.330742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29569527s","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-04-07T17:53:03.332033Z","caller":"traceutil/trace.go:171","msg":"trace[1612777582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1120; }","duration":"1.297033396s","start":"2026-04-07T17:53:02.03498Z","end":"2026-04-07T17:53:03.332013Z","steps":["trace[1612777582] 'agreement among raft nodes before linearized reading' (duration: 1.29532733s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:03.332097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:02.034956Z","time spent":"1.297123958s","remote":"127.0.0.1:42548","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":"info","ts":"2026-04-07T17:53:03.43996Z","caller":"traceutil/trace.go:171","msg":"trace[577971661] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1162; }","duration":"101.825053ms","start":"2026-04-07T17:53:03.338112Z","end":"2026-04-07T17:53:03.439937Z","steps":["trace[577971661] 'read index received' (duration: 59.695559ms)","trace[577971661] 'applied index is now lower than readState.Index' (duration: 42.128634ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:03.440059Z","caller":"traceutil/trace.go:171","msg":"trace[569685746] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"102.803899ms","start":"2026-04-07T17:53:03.337205Z","end":"2026-04-07T17:53:03.440009Z","steps":["trace[569685746] 'process raft request' (duration: 102.691756ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:03.439996Z","caller":"traceutil/trace.go:171","msg":"trace[578074450] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"102.926452ms","start":"2026-04-07T17:53:03.337033Z","end":"2026-04-07T17:53:03.439959Z","steps":["trace[578074450] 'process raft request' (duration: 102.703146ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:03.440027Z","caller":"traceutil/trace.go:171","msg":"trace[1224135209] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"103.021554ms","start":"2026-04-07T17:53:03.33697Z","end":"2026-04-07T17:53:03.439992Z","steps":["trace[1224135209] 'process raft request' (duration: 102.88965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:03.440156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.019668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:03.440412Z","caller":"traceutil/trace.go:171","msg":"trace[2070918796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"102.281904ms","start":"2026-04-07T17:53:03.338088Z","end":"2026-04-07T17:53:03.44037Z","steps":["trace[2070918796] 'agreement among raft nodes before linearized reading' (duration: 101.999706ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:15.975898Z","caller":"traceutil/trace.go:171","msg":"trace[1971569095] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"487.447025ms","start":"2026-04-07T17:53:15.488426Z","end":"2026-04-07T17:53:15.975873Z","steps":["trace[1971569095] 'read index received' (duration: 487.272101ms)","trace[1971569095] 'applied index is now lower than readState.Index' (duration: 174.034µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:15.976235Z","caller":"traceutil/trace.go:171","msg":"trace[1022381935] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"519.822308ms","start":"2026-04-07T17:53:15.456401Z","end":"2026-04-07T17:53:15.976223Z","steps":["trace[1022381935] 'process raft request' (duration: 519.355174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:15.976342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:15.456381Z","time spent":"519.89486ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:53:15.976676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.213727ms","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-04-07T17:53:15.97672Z","caller":"traceutil/trace.go:171","msg":"trace[921787442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1213; }","duration":"488.321859ms","start":"2026-04-07T17:53:15.488387Z","end":"2026-04-07T17:53:15.976709Z","steps":["trace[921787442] 'agreement among raft nodes before linearized reading' (duration: 488.136044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:15.976751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:15.488373Z","time spent":"488.371521ms","remote":"127.0.0.1:42548","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-04-07T17:53:15.976925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.224293ms","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-04-07T17:53:15.976998Z","caller":"traceutil/trace.go:171","msg":"trace[959186959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"486.319796ms","start":"2026-04-07T17:53:15.49066Z","end":"2026-04-07T17:53:15.97698Z","steps":["trace[959186959] 'agreement among raft nodes before linearized reading' (duration: 486.10242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:15.977046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:15.490653Z","time spent":"486.384637ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T17:53:15.977167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.845897ms","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-04-07T17:53:15.977249Z","caller":"traceutil/trace.go:171","msg":"trace[361173622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"441.963942ms","start":"2026-04-07T17:53:15.535274Z","end":"2026-04-07T17:53:15.977238Z","steps":["trace[361173622] 'agreement among raft nodes before linearized reading' (duration: 441.790736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:15.97728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:15.535256Z","time spent":"442.016362ms","remote":"127.0.0.1:42548","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-04-07T17:53:15.977353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.828964ms","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-04-07T17:53:15.977393Z","caller":"traceutil/trace.go:171","msg":"trace[1005140721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"481.911126ms","start":"2026-04-07T17:53:15.495472Z","end":"2026-04-07T17:53:15.977383Z","steps":["trace[1005140721] 'agreement among raft nodes before linearized reading' (duration: 481.817064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:15.977422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:15.495445Z","time spent":"481.97025ms","remote":"127.0.0.1:42548","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-04-07T17:53:15.977469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.878302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:15.977527Z","caller":"traceutil/trace.go:171","msg":"trace[1896237712] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1213; }","duration":"147.967034ms","start":"2026-04-07T17:53:15.829545Z","end":"2026-04-07T17:53:15.977512Z","steps":["trace[1896237712] 'agreement among raft nodes before linearized reading' (duration: 147.886292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:16.854342Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957993917937988696,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T17:53:17.010445Z","caller":"traceutil/trace.go:171","msg":"trace[624421004] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"1.029271865s","start":"2026-04-07T17:53:15.981159Z","end":"2026-04-07T17:53:17.010431Z","steps":["trace[624421004] 'process raft request' (duration: 1.029190523s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:17.010545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:15.981138Z","time spent":"1.029354478s","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:17.011318Z","caller":"traceutil/trace.go:171","msg":"trace[96339479] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"1.028492975s","start":"2026-04-07T17:53:15.982815Z","end":"2026-04-07T17:53:17.011308Z","steps":["trace[96339479] 'process raft request' (duration: 1.028452504s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:17.011369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:15.982805Z","time spent":"1.028543975s","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:17.011371Z","caller":"traceutil/trace.go:171","msg":"trace[410979083] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"1.029979645s","start":"2026-04-07T17:53:15.981347Z","end":"2026-04-07T17:53:17.011327Z","steps":["trace[410979083] 'process raft request' (duration: 1.029876512s)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:17.011468Z","caller":"traceutil/trace.go:171","msg":"trace[2106614675] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"1.028587627s","start":"2026-04-07T17:53:15.982875Z","end":"2026-04-07T17:53:17.011463Z","steps":["trace[2106614675] 'process raft request' (duration: 1.028411273s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:17.011498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:15.982868Z","time spent":"1.028614049s","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:53:17.011522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:15.981337Z","time spent":"1.030103698s","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:17.01159Z","caller":"traceutil/trace.go:171","msg":"trace[675582617] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1260; }","duration":"657.480946ms","start":"2026-04-07T17:53:16.354103Z","end":"2026-04-07T17:53:17.011584Z","steps":["trace[675582617] 'read index received' (duration: 656.678843ms)","trace[675582617] 'applied index is now lower than readState.Index' (duration: 801.373µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:17.011631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.535646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:17.011648Z","caller":"traceutil/trace.go:171","msg":"trace[2002034983] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1218; }","duration":"657.594308ms","start":"2026-04-07T17:53:16.354048Z","end":"2026-04-07T17:53:17.011642Z","steps":["trace[2002034983] 'agreement among raft nodes before linearized reading' (duration: 657.556147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:17.011666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:16.354027Z","time spent":"657.635928ms","remote":"127.0.0.1:41028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:53:28.154727Z","caller":"traceutil/trace.go:171","msg":"trace[216561656] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"119.706104ms","start":"2026-04-07T17:53:28.034991Z","end":"2026-04-07T17:53:28.154697Z","steps":["trace[216561656] 'process raft request' (duration: 119.517308ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:29.184932Z","caller":"traceutil/trace.go:171","msg":"trace[2008977454] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"113.431834ms","start":"2026-04-07T17:53:29.071477Z","end":"2026-04-07T17:53:29.184909Z","steps":["trace[2008977454] 'process raft request' (duration: 113.28994ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:29.188868Z","caller":"traceutil/trace.go:171","msg":"trace[748101872] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"114.224205ms","start":"2026-04-07T17:53:29.07462Z","end":"2026-04-07T17:53:29.188844Z","steps":["trace[748101872] 'read index received' (duration: 110.388113ms)","trace[748101872] 'applied index is now lower than readState.Index' (duration: 3.835002ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:29.188922Z","caller":"traceutil/trace.go:171","msg":"trace[1823808718] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"115.275333ms","start":"2026-04-07T17:53:29.073633Z","end":"2026-04-07T17:53:29.188908Z","steps":["trace[1823808718] 'process raft request' (duration: 115.102418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:29.189096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.446571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T17:53:29.189236Z","caller":"traceutil/trace.go:171","msg":"trace[697719327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"114.634036ms","start":"2026-04-07T17:53:29.074593Z","end":"2026-04-07T17:53:29.189227Z","steps":["trace[697719327] 'agreement among raft nodes before linearized reading' (duration: 114.365039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:29.189234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.8615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T17:53:29.189272Z","caller":"traceutil/trace.go:171","msg":"trace[3704480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1256; }","duration":"102.957003ms","start":"2026-04-07T17:53:29.086306Z","end":"2026-04-07T17:53:29.189263Z","steps":["trace[3704480] 'agreement among raft nodes before linearized reading' (duration: 102.802109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:30.555922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.086885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:30.555993Z","caller":"traceutil/trace.go:171","msg":"trace[2122409246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"266.205388ms","start":"2026-04-07T17:53:30.289772Z","end":"2026-04-07T17:53:30.555977Z","steps":["trace[2122409246] 'range keys from in-memory index tree' (duration: 265.971172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:32.742056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.241039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:53:32.742215Z","caller":"traceutil/trace.go:171","msg":"trace[1705349060] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"328.837015ms","start":"2026-04-07T17:53:32.413347Z","end":"2026-04-07T17:53:32.742184Z","steps":["trace[1705349060] 'process raft request' (duration: 215.248366ms)","trace[1705349060] 'compare' (duration: 113.083705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:32.742361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:32.413325Z","time spent":"328.932978ms","remote":"127.0.0.1:42556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:32.795091Z","caller":"traceutil/trace.go:171","msg":"trace[1904026174] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"381.651488ms","start":"2026-04-07T17:53:32.413414Z","end":"2026-04-07T17:53:32.795065Z","steps":["trace[1904026174] 'process raft request' (duration: 381.464572ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:32.795274Z","caller":"traceutil/trace.go:171","msg":"trace[1692997043] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1332; }","duration":"255.790498ms","start":"2026-04-07T17:53:32.539455Z","end":"2026-04-07T17:53:32.795245Z","steps":["trace[1692997043] 'read index received' (duration: 89.217292ms)","trace[1692997043] 'applied index is now lower than readState.Index' (duration: 166.571146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:32.795282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:32.413324Z","time spent":"381.843852ms","remote":"127.0.0.1:42462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:32.795343Z","caller":"traceutil/trace.go:171","msg":"trace[802185722] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"377.637559ms","start":"2026-04-07T17:53:32.417671Z","end":"2026-04-07T17:53:32.795309Z","steps":["trace[802185722] 'process raft request' (duration: 377.336642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:32.795516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.030195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:53:32.795693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:32.417655Z","time spent":"377.836794ms","remote":"127.0.0.1:42768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:32.795875Z","caller":"traceutil/trace.go:171","msg":"trace[611015963] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1286; }","duration":"256.337553ms","start":"2026-04-07T17:53:32.5394Z","end":"2026-04-07T17:53:32.795737Z","steps":["trace[611015963] 'agreement among raft nodes before linearized reading' (duration: 255.930042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:34.613834Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.1962167s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T17:53:34.641953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224366459s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T17:53:34.642406Z","caller":"traceutil/trace.go:171","msg":"trace[790689344] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1335; }","duration":"1.443531321s","start":"2026-04-07T17:53:33.198826Z","end":"2026-04-07T17:53:34.642357Z","steps":["trace[790689344] 'read index received' (duration: 218.767331ms)","trace[790689344] 'applied index is now lower than readState.Index' (duration: 1.22475937s)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:34.642506Z","caller":"traceutil/trace.go:171","msg":"trace[124108154] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"1.560579282s","start":"2026-04-07T17:53:33.081911Z","end":"2026-04-07T17:53:34.642491Z","steps":["trace[124108154] 'process raft request' (duration: 1.56012519s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:34.642615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:33.081885Z","time spent":"1.560655285s","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:34.848598Z","caller":"traceutil/trace.go:171","msg":"trace[1379721639] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"206.11784ms","start":"2026-04-07T17:53:34.642455Z","end":"2026-04-07T17:53:34.848573Z","steps":["trace[1379721639] 'read index received' (duration: 125.386686ms)","trace[1379721639] 'applied index is now lower than readState.Index' (duration: 80.730214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:34.84865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644378339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-07T17:53:34.848657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413261235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:53:34.848687Z","caller":"traceutil/trace.go:171","msg":"trace[1292216220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"1.644451182s","start":"2026-04-07T17:53:33.204224Z","end":"2026-04-07T17:53:34.848676Z","steps":["trace[1292216220] 'agreement among raft nodes before linearized reading' (duration: 1.644368719s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:34.848686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.249648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:53:34.848475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.649621631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T17:53:34.848714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:33.204205Z","time spent":"1.644503982s","remote":"127.0.0.1:42548","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":"info","ts":"2026-04-07T17:53:34.848719Z","caller":"traceutil/trace.go:171","msg":"trace[1302559947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"556.309089ms","start":"2026-04-07T17:53:34.292398Z","end":"2026-04-07T17:53:34.848708Z","steps":["trace[1302559947] 'agreement among raft nodes before linearized reading' (duration: 556.273479ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:34.848714Z","caller":"traceutil/trace.go:171","msg":"trace[49034033] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1288; }","duration":"1.413355449s","start":"2026-04-07T17:53:33.435343Z","end":"2026-04-07T17:53:34.848698Z","steps":["trace[49034033] 'agreement among raft nodes before linearized reading' (duration: 1.413258147s)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:34.84873Z","caller":"traceutil/trace.go:171","msg":"trace[443337858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"1.649926349s","start":"2026-04-07T17:53:33.198792Z","end":"2026-04-07T17:53:34.848719Z","steps":["trace[443337858] 'agreement among raft nodes before linearized reading' (duration: 1.444004954s)","trace[443337858] 'range keys from in-memory index tree' (duration: 205.547605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:34.848739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:34.29238Z","time spent":"556.353691ms","remote":"127.0.0.1:42290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T17:53:34.848755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:33.198776Z","time spent":"1.64997265s","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T17:53:34.848735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.883775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T17:53:34.848753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:33.435321Z","time spent":"1.41342125s","remote":"127.0.0.1:42536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:53:34.848795Z","caller":"traceutil/trace.go:171","msg":"trace[399782642] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1288; }","duration":"410.987197ms","start":"2026-04-07T17:53:34.437791Z","end":"2026-04-07T17:53:34.848779Z","steps":["trace[399782642] 'agreement among raft nodes before linearized reading' (duration: 410.843762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:34.848809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645774137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T17:53:34.848841Z","caller":"traceutil/trace.go:171","msg":"trace[381191893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1288; }","duration":"1.645856929s","start":"2026-04-07T17:53:33.202974Z","end":"2026-04-07T17:53:34.848831Z","steps":["trace[381191893] 'agreement among raft nodes before linearized reading' (duration: 1.645735625s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:34.848841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:34.437771Z","time spent":"411.05716ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:53:34.848874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:33.20295Z","time spent":"1.645916881s","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T17:53:34.848994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.649773954s","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-04-07T17:53:34.849019Z","caller":"traceutil/trace.go:171","msg":"trace[1580314268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"1.649800645s","start":"2026-04-07T17:53:33.19921Z","end":"2026-04-07T17:53:34.849011Z","steps":["trace[1580314268] 'agreement among raft nodes before linearized reading' (duration: 1.649730053s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:34.849048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:33.19919Z","time spent":"1.649851955s","remote":"127.0.0.1:42548","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-04-07T17:53:34.848654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.739504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T17:53:34.849086Z","caller":"traceutil/trace.go:171","msg":"trace[832034990] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"524.02114ms","start":"2026-04-07T17:53:34.32504Z","end":"2026-04-07T17:53:34.849061Z","steps":["trace[832034990] 'process raft request' (duration: 442.912596ms)","trace[832034990] 'compare' (duration: 80.440876ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:34.849119Z","caller":"traceutil/trace.go:171","msg":"trace[490877391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"670.246428ms","start":"2026-04-07T17:53:34.178861Z","end":"2026-04-07T17:53:34.849108Z","steps":["trace[490877391] 'agreement among raft nodes before linearized reading' (duration: 669.723704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:34.849138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:34.325017Z","time spent":"524.088102ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:53:34.849158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:34.178837Z","time spent":"670.310439ms","remote":"127.0.0.1:42548","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-04-07T17:53:35.105477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.394564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:53:35.105603Z","caller":"traceutil/trace.go:171","msg":"trace[1855560986] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"252.93837ms","start":"2026-04-07T17:53:34.85265Z","end":"2026-04-07T17:53:35.105589Z","steps":["trace[1855560986] 'process raft request' (duration: 97.364122ms)","trace[1855560986] 'compare' (duration: 155.295952ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:35.105745Z","caller":"traceutil/trace.go:171","msg":"trace[515054338] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"252.968322ms","start":"2026-04-07T17:53:34.852769Z","end":"2026-04-07T17:53:35.105738Z","steps":["trace[515054338] 'process raft request' (duration: 252.783227ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:35.105907Z","caller":"traceutil/trace.go:171","msg":"trace[1309066501] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"253.096955ms","start":"2026-04-07T17:53:34.852802Z","end":"2026-04-07T17:53:35.105898Z","steps":["trace[1309066501] 'process raft request' (duration: 252.910421ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:35.106017Z","caller":"traceutil/trace.go:171","msg":"trace[214183714] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"252.971662ms","start":"2026-04-07T17:53:34.853038Z","end":"2026-04-07T17:53:35.10601Z","steps":["trace[214183714] 'process raft request' (duration: 252.828548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:36.364766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.446131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:36.364821Z","caller":"traceutil/trace.go:171","msg":"trace[1223059940] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1294; }","duration":"677.512804ms","start":"2026-04-07T17:53:35.687296Z","end":"2026-04-07T17:53:36.364808Z","steps":["trace[1223059940] 'count revisions from in-memory index tree' (duration: 677.356719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:36.364848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:35.687276Z","time spent":"677.564244ms","remote":"127.0.0.1:41044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:53:38.276392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.898769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:53:38.276473Z","caller":"traceutil/trace.go:171","msg":"trace[1261990935] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"820.698921ms","start":"2026-04-07T17:53:37.455758Z","end":"2026-04-07T17:53:38.276457Z","steps":["trace[1261990935] 'process raft request' (duration: 355.667657ms)","trace[1261990935] 'compare' (duration: 464.573101ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:38.276524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:37.455741Z","time spent":"820.756741ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:38.811439Z","caller":"traceutil/trace.go:171","msg":"trace[579920407] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"100.395223ms","start":"2026-04-07T17:53:38.711019Z","end":"2026-04-07T17:53:38.811414Z","steps":["trace[579920407] 'process raft request' (duration: 100.233368ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:39.023433Z","caller":"traceutil/trace.go:171","msg":"trace[1224020469] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"108.235174ms","start":"2026-04-07T17:53:38.915174Z","end":"2026-04-07T17:53:39.023409Z","steps":["trace[1224020469] 'process raft request' (duration: 108.107161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:48.426435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.876267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:53:48.426563Z","caller":"traceutil/trace.go:171","msg":"trace[1351882651] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"831.743147ms","start":"2026-04-07T17:53:47.594795Z","end":"2026-04-07T17:53:48.426538Z","steps":["trace[1351882651] 'read index received' (duration: 141.606833ms)","trace[1351882651] 'applied index is now lower than readState.Index' (duration: 690.135044ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:48.426666Z","caller":"traceutil/trace.go:171","msg":"trace[570422436] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"986.140075ms","start":"2026-04-07T17:53:47.440509Z","end":"2026-04-07T17:53:48.426649Z","steps":["trace[570422436] 'process raft request' (duration: 295.975191ms)","trace[570422436] 'compare' (duration: 689.605649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:48.426736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:47.44049Z","time spent":"986.206717ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:53:48.427003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.671681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:48.427112Z","caller":"traceutil/trace.go:171","msg":"trace[1348872241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1379; }","duration":"133.881085ms","start":"2026-04-07T17:53:48.293206Z","end":"2026-04-07T17:53:48.427087Z","steps":["trace[1348872241] 'agreement among raft nodes before linearized reading' (duration: 133.586167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:48.427297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.487497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-07T17:53:48.427459Z","caller":"traceutil/trace.go:171","msg":"trace[714444471] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1379; }","duration":"832.643532ms","start":"2026-04-07T17:53:47.594757Z","end":"2026-04-07T17:53:48.427401Z","steps":["trace[714444471] 'agreement among raft nodes before linearized reading' (duration: 832.293312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:48.427517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:47.594742Z","time spent":"832.757665ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-07T17:54:19.558998Z","caller":"traceutil/trace.go:171","msg":"trace[1148760386] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"140.00056ms","start":"2026-04-07T17:54:19.418963Z","end":"2026-04-07T17:54:19.558963Z","steps":["trace[1148760386] 'process raft request' (duration: 139.813865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:19.559074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.99484ms","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-04-07T17:54:19.559151Z","caller":"traceutil/trace.go:171","msg":"trace[719409840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1524; }","duration":"140.119683ms","start":"2026-04-07T17:54:19.41901Z","end":"2026-04-07T17:54:19.55913Z","steps":["trace[719409840] 'agreement among raft nodes before linearized reading' (duration: 139.904037ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:54:19.605975Z","caller":"traceutil/trace.go:171","msg":"trace[881856890] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"141.295174ms","start":"2026-04-07T17:54:19.464658Z","end":"2026-04-07T17:54:19.605954Z","steps":["trace[881856890] 'process raft request' (duration: 141.169171ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:54:25.141512Z","caller":"traceutil/trace.go:171","msg":"trace[539551001] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"133.776333ms","start":"2026-04-07T17:54:25.007689Z","end":"2026-04-07T17:54:25.141465Z","steps":["trace[539551001] 'process raft request' (duration: 56.191285ms)","trace[539551001] 'compare' (duration: 77.393943ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:54:27.804516Z","caller":"traceutil/trace.go:171","msg":"trace[74988211] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"140.625656ms","start":"2026-04-07T17:54:27.663861Z","end":"2026-04-07T17:54:27.804487Z","steps":["trace[74988211] 'process raft request' (duration: 97.688436ms)","trace[74988211] 'compare' (duration: 42.806097ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:54:37.244524Z","caller":"traceutil/trace.go:171","msg":"trace[1858109348] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"143.114254ms","start":"2026-04-07T17:54:37.101393Z","end":"2026-04-07T17:54:37.244508Z","steps":["trace[1858109348] 'process raft request' (duration: 143.012721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:55:12.147206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.019795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T17:55:12.147315Z","caller":"traceutil/trace.go:171","msg":"trace[1978366100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1764; }","duration":"101.169208ms","start":"2026-04-07T17:55:12.046117Z","end":"2026-04-07T17:55:12.147287Z","steps":["trace[1978366100] 'range keys from in-memory index tree' (duration: 100.825179ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:55:12.292654Z","caller":"traceutil/trace.go:171","msg":"trace[1055257449] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"141.509887ms","start":"2026-04-07T17:55:12.151122Z","end":"2026-04-07T17:55:12.292632Z","steps":["trace[1055257449] 'process raft request' (duration: 141.320622ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:55:21.838413Z","caller":"traceutil/trace.go:171","msg":"trace[338920582] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1875; }","duration":"152.693469ms","start":"2026-04-07T17:55:21.6857Z","end":"2026-04-07T17:55:21.838393Z","steps":["trace[338920582] 'read index received' (duration: 8.553901ms)","trace[338920582] 'applied index is now lower than readState.Index' (duration: 144.138657ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:55:21.838481Z","caller":"traceutil/trace.go:171","msg":"trace[576226861] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"185.757251ms","start":"2026-04-07T17:55:21.652694Z","end":"2026-04-07T17:55:21.838452Z","steps":["trace[576226861] 'process raft request' (duration: 177.109377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:55:21.838589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.876174ms","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-04-07T17:55:21.838624Z","caller":"traceutil/trace.go:171","msg":"trace[1103370568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1806; }","duration":"152.949255ms","start":"2026-04-07T17:55:21.685664Z","end":"2026-04-07T17:55:21.838613Z","steps":["trace[1103370568] 'agreement among raft nodes before linearized reading' (duration: 152.804562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:55:22.057482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.81903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:55:22.057646Z","caller":"traceutil/trace.go:171","msg":"trace[785033774] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"166.739558ms","start":"2026-04-07T17:55:21.890846Z","end":"2026-04-07T17:55:22.057585Z","steps":["trace[785033774] 'process raft request' (duration: 62.744393ms)","trace[785033774] 'compare' (duration: 103.694727ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:55:22.47244Z","caller":"traceutil/trace.go:171","msg":"trace[998389015] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"135.040932ms","start":"2026-04-07T17:55:22.337364Z","end":"2026-04-07T17:55:22.472405Z","steps":["trace[998389015] 'process raft request' (duration: 134.266332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:55:22.930939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.00224ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-07T17:56:02.624657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.286205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:56:02.62498Z","caller":"traceutil/trace.go:171","msg":"trace[1362275456] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2072; }","duration":"176.904533ms","start":"2026-04-07T17:56:02.448036Z","end":"2026-04-07T17:56:02.624941Z","steps":["trace[1362275456] 'read index received' (duration: 33.276518ms)","trace[1362275456] 'applied index is now lower than readState.Index' (duration: 143.623025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:56:02.625173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.123028ms","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-04-07T17:56:02.625237Z","caller":"traceutil/trace.go:171","msg":"trace[430078651] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1995; }","duration":"177.242161ms","start":"2026-04-07T17:56:02.447982Z","end":"2026-04-07T17:56:02.625224Z","steps":["trace[430078651] 'agreement among raft nodes before linearized reading' (duration: 177.130358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:56:02.625272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.060804ms","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-04-07T17:56:02.625312Z","caller":"traceutil/trace.go:171","msg":"trace[1419622979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1995; }","duration":"131.155498ms","start":"2026-04-07T17:56:02.494144Z","end":"2026-04-07T17:56:02.625299Z","steps":["trace[1419622979] 'agreement among raft nodes before linearized reading' (duration: 130.982293ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:56:02.624912Z","caller":"traceutil/trace.go:171","msg":"trace[1486522778] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"251.423702ms","start":"2026-04-07T17:56:02.373459Z","end":"2026-04-07T17:56:02.624883Z","steps":["trace[1486522778] 'process raft request' (duration: 107.811418ms)","trace[1486522778] 'compare' (duration: 143.025498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:56:02.959905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.974223ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T17:56:20.941246Z","caller":"traceutil/trace.go:171","msg":"trace[421995284] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"100.430352ms","start":"2026-04-07T17:56:20.840782Z","end":"2026-04-07T17:56:20.941212Z","steps":["trace[421995284] 'compare' (duration: 97.500812ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:56:20.943682Z","caller":"traceutil/trace.go:171","msg":"trace[1646142129] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"102.464257ms","start":"2026-04-07T17:56:20.84119Z","end":"2026-04-07T17:56:20.943654Z","steps":["trace[1646142129] 'process raft request' (duration: 102.304843ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:56:55.895794Z","caller":"traceutil/trace.go:171","msg":"trace[1716110373] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"106.23152ms","start":"2026-04-07T17:56:55.789534Z","end":"2026-04-07T17:56:55.895766Z","steps":["trace[1716110373] 'process raft request' (duration: 106.091837ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:57:00.738903Z","caller":"traceutil/trace.go:171","msg":"trace[1666500131] linearizableReadLoop","detail":"{readStateIndex:2376; appliedIndex:2375; }","duration":"113.833516ms","start":"2026-04-07T17:57:00.625044Z","end":"2026-04-07T17:57:00.738878Z","steps":["trace[1666500131] 'read index received' (duration: 113.601349ms)","trace[1666500131] 'applied index is now lower than readState.Index' (duration: 231.177µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:57:00.73895Z","caller":"traceutil/trace.go:171","msg":"trace[765417932] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"174.129207ms","start":"2026-04-07T17:57:00.564792Z","end":"2026-04-07T17:57:00.738922Z","steps":["trace[765417932] 'process raft request' (duration: 173.917871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:57:00.739406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.305741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-04-07T17:57:00.739502Z","caller":"traceutil/trace.go:171","msg":"trace[1134084903] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2284; }","duration":"114.485284ms","start":"2026-04-07T17:57:00.62499Z","end":"2026-04-07T17:57:00.739475Z","steps":["trace[1134084903] 'agreement among raft nodes before linearized reading' (duration: 114.010602ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:57:01.366866Z","caller":"traceutil/trace.go:171","msg":"trace[2084649866] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"139.646558ms","start":"2026-04-07T17:57:01.227185Z","end":"2026-04-07T17:57:01.366831Z","steps":["trace[2084649866] 'process raft request' (duration: 139.429052ms)"],"step_count":1}