{"level":"warn","ts":"2026-05-07T00:14:15.423234Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T00:14:15.423337Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-05-07T00:14:15.423415Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T00:14:15.423432Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-07T00:14:15.423454Z","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-05-07T00:14:15.424386Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-05-07T00:14:15.424529Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-05-07T00:14:15.430114Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.33496ms"} {"level":"info","ts":"2026-05-07T00:14:15.436405Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-05-07T00:14:15.436557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T00:14:15.436603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-05-07T00:14:15.436622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T00:14:15.436634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-05-07T00:14:15.43669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-05-07T00:14:15.447812Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T00:14:15.449914Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T00:14:15.451192Z","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-05-07T00:14:15.45304Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T00:14:15.453178Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T00:14:15.453178Z","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-05-07T00:14:15.453332Z","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-05-07T00:14:15.453347Z","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-05-07T00:14:15.456831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-05-07T00:14:15.456952Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-07T00:14:15.457924Z","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-05-07T00:14:15.458121Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T00:14:15.458161Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T00:14:15.458208Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-07T00:14:15.45823Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-07T00:14:16.337044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T00:14:16.337123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T00:14:16.337149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-05-07T00:14:16.337187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-05-07T00:14:16.337199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-07T00:14:16.337216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-05-07T00:14:16.337232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-07T00:14:16.338578Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T00:14:16.338786Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T00:14:16.339258Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T00:14:16.339285Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T00:14:16.339233Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T00:14:16.339462Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T00:14:16.340053Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T00:14:16.34022Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T00:14:16.340267Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T00:14:16.342518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T00:14:16.34797Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-05-07T00:14:22.495316Z","caller":"traceutil/trace.go:171","msg":"trace[1075534674] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:327; }","duration":"145.788008ms","start":"2026-05-07T00:14:22.349458Z","end":"2026-05-07T00:14:22.495246Z","steps":["trace[1075534674] 'read index received' (duration: 116.776286ms)","trace[1075534674] 'applied index is now lower than readState.Index' (duration: 29.010671ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:14:22.495376Z","caller":"traceutil/trace.go:171","msg":"trace[94923337] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"170.328746ms","start":"2026-05-07T00:14:22.324993Z","end":"2026-05-07T00:14:22.495322Z","steps":["trace[94923337] 'process raft request' (duration: 141.304914ms)","trace[94923337] 'compare' (duration: 28.785708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:14:22.495518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.015472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:14:22.495591Z","caller":"traceutil/trace.go:171","msg":"trace[1606565570] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:321; }","duration":"146.180084ms","start":"2026-05-07T00:14:22.349397Z","end":"2026-05-07T00:14:22.495577Z","steps":["trace[1606565570] 'agreement among raft nodes before linearized reading' (duration: 146.038612ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:25.722479Z","caller":"traceutil/trace.go:171","msg":"trace[241744592] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"236.896286ms","start":"2026-05-07T00:14:25.485543Z","end":"2026-05-07T00:14:25.722439Z","steps":["trace[241744592] 'process raft request' (duration: 220.245676ms)","trace[241744592] 'compare' (duration: 16.449977ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:14:25.722885Z","caller":"traceutil/trace.go:171","msg":"trace[1230988338] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:383; }","duration":"178.08879ms","start":"2026-05-07T00:14:25.544764Z","end":"2026-05-07T00:14:25.722853Z","steps":["trace[1230988338] 'read index received' (duration: 160.998781ms)","trace[1230988338] 'applied index is now lower than readState.Index' (duration: 17.088409ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:14:25.722887Z","caller":"traceutil/trace.go:171","msg":"trace[1798241593] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"234.668974ms","start":"2026-05-07T00:14:25.488184Z","end":"2026-05-07T00:14:25.722853Z","steps":["trace[1798241593] 'process raft request' (duration: 234.426979ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:25.722946Z","caller":"traceutil/trace.go:171","msg":"trace[1917517604] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"232.660727ms","start":"2026-05-07T00:14:25.490267Z","end":"2026-05-07T00:14:25.722928Z","steps":["trace[1917517604] 'process raft request' (duration: 232.491094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:25.723073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.289743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-07T00:14:25.723123Z","caller":"traceutil/trace.go:171","msg":"trace[2034605130] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:379; }","duration":"178.404875ms","start":"2026-05-07T00:14:25.544705Z","end":"2026-05-07T00:14:25.72311Z","steps":["trace[2034605130] 'agreement among raft nodes before linearized reading' (duration: 178.273903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:30.668981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.935079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:14:30.669115Z","caller":"traceutil/trace.go:171","msg":"trace[1144724406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"310.148912ms","start":"2026-05-07T00:14:30.358938Z","end":"2026-05-07T00:14:30.669087Z","steps":["trace[1144724406] 'range keys from in-memory index tree' (duration: 309.813887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:30.669114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.266892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-07T00:14:30.669142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.884314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-07T00:14:30.669177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:30.358911Z","time spent":"310.254524ms","remote":"127.0.0.1:44626","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-05-07T00:14:30.669248Z","caller":"traceutil/trace.go:171","msg":"trace[331243270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:401; }","duration":"496.481516ms","start":"2026-05-07T00:14:30.172738Z","end":"2026-05-07T00:14:30.669219Z","steps":["trace[331243270] 'range keys from in-memory index tree' (duration: 496.11415ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:30.669273Z","caller":"traceutil/trace.go:171","msg":"trace[1290608176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"489.101858ms","start":"2026-05-07T00:14:30.180141Z","end":"2026-05-07T00:14:30.669243Z","steps":["trace[1290608176] 'range keys from in-memory index tree' (duration: 488.721841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:30.669297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:30.172706Z","time spent":"496.583378ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T00:14:30.669332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:30.180111Z","time spent":"489.210349ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T00:14:33.013107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.824716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:14:33.013252Z","caller":"traceutil/trace.go:171","msg":"trace[298460649] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"325.424946ms","start":"2026-05-07T00:14:32.687804Z","end":"2026-05-07T00:14:33.013229Z","steps":["trace[298460649] 'process raft request' (duration: 146.499301ms)","trace[298460649] 'compare' (duration: 177.485859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:14:33.013416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:32.68779Z","time spent":"325.502658ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:14:35.651572Z","caller":"traceutil/trace.go:171","msg":"trace[618314090] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"189.763008ms","start":"2026-05-07T00:14:35.461783Z","end":"2026-05-07T00:14:35.651546Z","steps":["trace[618314090] 'process raft request' (duration: 189.204548ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:39.183122Z","caller":"traceutil/trace.go:171","msg":"trace[715826516] linearizableReadLoop","detail":"{readStateIndex:467; appliedIndex:466; }","duration":"147.337117ms","start":"2026-05-07T00:14:39.035769Z","end":"2026-05-07T00:14:39.183106Z","steps":["trace[715826516] 'read index received' (duration: 147.192744ms)","trace[715826516] 'applied index is now lower than readState.Index' (duration: 143.663µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:14:39.183181Z","caller":"traceutil/trace.go:171","msg":"trace[1782990824] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"404.544762ms","start":"2026-05-07T00:14:38.778621Z","end":"2026-05-07T00:14:39.183166Z","steps":["trace[1782990824] 'process raft request' (duration: 404.383489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:39.183253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.478619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-07T00:14:39.183282Z","caller":"traceutil/trace.go:171","msg":"trace[1789004536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:455; }","duration":"147.51205ms","start":"2026-05-07T00:14:39.03576Z","end":"2026-05-07T00:14:39.183272Z","steps":["trace[1789004536] 'agreement among raft nodes before linearized reading' (duration: 147.428058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:39.183287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:38.778601Z","time spent":"404.607562ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:14:39.574994Z","caller":"traceutil/trace.go:171","msg":"trace[420807106] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"386.865973ms","start":"2026-05-07T00:14:39.188104Z","end":"2026-05-07T00:14:39.57497Z","steps":["trace[420807106] 'process raft request' (duration: 385.608029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:39.57516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:39.188021Z","time spent":"387.035395ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:14:41.679568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.693835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-07T00:14:41.679596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.392063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:14:41.679673Z","caller":"traceutil/trace.go:171","msg":"trace[1549049725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:460; }","duration":"100.856337ms","start":"2026-05-07T00:14:41.578791Z","end":"2026-05-07T00:14:41.679647Z","steps":["trace[1549049725] 'range keys from in-memory index tree' (duration: 100.579352ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:41.679715Z","caller":"traceutil/trace.go:171","msg":"trace[1173019147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:460; }","duration":"121.537504ms","start":"2026-05-07T00:14:41.558127Z","end":"2026-05-07T00:14:41.679664Z","steps":["trace[1173019147] 'range keys from in-memory index tree' (duration: 121.237369ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:51.156626Z","caller":"traceutil/trace.go:171","msg":"trace[588436936] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"342.911796ms","start":"2026-05-07T00:14:50.813683Z","end":"2026-05-07T00:14:51.156595Z","steps":["trace[588436936] 'process raft request' (duration: 342.721483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:51.156782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:50.813652Z","time spent":"343.059031ms","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:14:51.167487Z","caller":"traceutil/trace.go:171","msg":"trace[813078372] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"350.03188ms","start":"2026-05-07T00:14:50.81743Z","end":"2026-05-07T00:14:51.167462Z","steps":["trace[813078372] 'process raft request' (duration: 349.897018ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:51.167546Z","caller":"traceutil/trace.go:171","msg":"trace[1208299308] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"341.396119ms","start":"2026-05-07T00:14:50.826104Z","end":"2026-05-07T00:14:51.1675Z","steps":["trace[1208299308] 'process raft request' (duration: 341.265396ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:51.167448Z","caller":"traceutil/trace.go:171","msg":"trace[1279601702] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"352.968035ms","start":"2026-05-07T00:14:50.814442Z","end":"2026-05-07T00:14:51.16741Z","steps":["trace[1279601702] 'process raft request' (duration: 352.764661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:51.167591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:50.817419Z","time spent":"350.130452ms","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:14:51.167711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:50.826082Z","time spent":"341.522682ms","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:14:51.167671Z","caller":"traceutil/trace.go:171","msg":"trace[1630173022] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"341.245996ms","start":"2026-05-07T00:14:50.826361Z","end":"2026-05-07T00:14:51.167606Z","steps":["trace[1630173022] 'process raft request' (duration: 341.049262ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:51.167789Z","caller":"traceutil/trace.go:171","msg":"trace[2051067767] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"333.9635ms","start":"2026-05-07T00:14:50.833814Z","end":"2026-05-07T00:14:51.167777Z","steps":["trace[2051067767] 'process raft request' (duration: 333.635034ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:51.167859Z","caller":"traceutil/trace.go:171","msg":"trace[1968965227] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"315.777749ms","start":"2026-05-07T00:14:50.852071Z","end":"2026-05-07T00:14:51.167849Z","steps":["trace[1968965227] 'process raft request' (duration: 315.570965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:51.167858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:50.833802Z","time spent":"334.024211ms","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:14:51.167879Z","caller":"traceutil/trace.go:171","msg":"trace[1722502897] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"319.625351ms","start":"2026-05-07T00:14:50.848237Z","end":"2026-05-07T00:14:51.167862Z","steps":["trace[1722502897] 'process raft request' (duration: 319.370167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:51.167877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:50.826354Z","time spent":"341.436009ms","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:14:51.16791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:50.852057Z","time spent":"315.83173ms","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:14:51.167947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:50.848229Z","time spent":"319.686353ms","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:14:51.167749Z","caller":"traceutil/trace.go:171","msg":"trace[876558563] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"323.495814ms","start":"2026-05-07T00:14:50.844237Z","end":"2026-05-07T00:14:51.167733Z","steps":["trace[876558563] 'process raft request' (duration: 323.329721ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:51.167974Z","caller":"traceutil/trace.go:171","msg":"trace[876690875] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"311.353547ms","start":"2026-05-07T00:14:50.856611Z","end":"2026-05-07T00:14:51.167964Z","steps":["trace[876690875] 'process raft request' (duration: 311.070251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:51.167993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:50.844213Z","time spent":"323.764119ms","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:14:51.168025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:50.856543Z","time spent":"311.45686ms","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:14:51.168036Z","caller":"traceutil/trace.go:171","msg":"trace[688924090] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"273.173873ms","start":"2026-05-07T00:14:50.894852Z","end":"2026-05-07T00:14:51.168026Z","steps":["trace[688924090] 'process raft request' (duration: 272.895647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:51.168031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:50.81441Z","time spent":"353.212419ms","remote":"127.0.0.1:44692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:14:51.168054Z","caller":"traceutil/trace.go:171","msg":"trace[438200982] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"142.943616ms","start":"2026-05-07T00:14:51.025084Z","end":"2026-05-07T00:14:51.168027Z","steps":["trace[438200982] 'process raft request' (duration: 142.816783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:57.960166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.923431ms","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-05-07T00:14:57.960309Z","caller":"traceutil/trace.go:171","msg":"trace[1943436124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"131.123954ms","start":"2026-05-07T00:14:57.829137Z","end":"2026-05-07T00:14:57.960261Z","steps":["trace[1943436124] 'range keys from in-memory index tree' (duration: 130.664576ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:58.080108Z","caller":"traceutil/trace.go:171","msg":"trace[264940390] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"115.390999ms","start":"2026-05-07T00:14:57.964669Z","end":"2026-05-07T00:14:58.08006Z","steps":["trace[264940390] 'process raft request' (duration: 114.974371ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:14:58.304204Z","caller":"traceutil/trace.go:171","msg":"trace[1188011671] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"174.914814ms","start":"2026-05-07T00:14:58.12925Z","end":"2026-05-07T00:14:58.304165Z","steps":["trace[1188011671] 'read index received' (duration: 173.756473ms)","trace[1188011671] 'applied index is now lower than readState.Index' (duration: 1.156941ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:14:58.304252Z","caller":"traceutil/trace.go:171","msg":"trace[4290867] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"214.983033ms","start":"2026-05-07T00:14:58.089248Z","end":"2026-05-07T00:14:58.304231Z","steps":["trace[4290867] 'process raft request' (duration: 214.159558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:58.304454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.173849ms","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-05-07T00:14:58.304525Z","caller":"traceutil/trace.go:171","msg":"trace[813637529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"175.302151ms","start":"2026-05-07T00:14:58.12921Z","end":"2026-05-07T00:14:58.304512Z","steps":["trace[813637529] 'agreement among raft nodes before linearized reading' (duration: 175.092596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:58.304647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.234855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T00:14:58.304721Z","caller":"traceutil/trace.go:171","msg":"trace[2056078462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"163.340837ms","start":"2026-05-07T00:14:58.141358Z","end":"2026-05-07T00:14:58.304698Z","steps":["trace[2056078462] 'agreement among raft nodes before linearized reading' (duration: 163.144584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:58.850551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303572743164260,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T00:14:58.965878Z","caller":"traceutil/trace.go:171","msg":"trace[1750553567] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"653.893858ms","start":"2026-05-07T00:14:58.311926Z","end":"2026-05-07T00:14:58.96582Z","steps":["trace[1750553567] 'process raft request' (duration: 653.615773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:58.966131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:58.311904Z","time spent":"654.079261ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:14:59.889128Z","caller":"traceutil/trace.go:171","msg":"trace[1558488072] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.576466063s","start":"2026-05-07T00:14:58.312621Z","end":"2026-05-07T00:14:59.889087Z","steps":["trace[1558488072] 'process raft request' (duration: 1.576253129s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:59.889292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:58.31259Z","time spent":"1.576613156s","remote":"127.0.0.1:44944","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-05-07T00:14:59.889152Z","caller":"traceutil/trace.go:171","msg":"trace[2140808917] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.521658077s","start":"2026-05-07T00:14:58.367481Z","end":"2026-05-07T00:14:59.889139Z","steps":["trace[2140808917] 'process raft request' (duration: 1.521540815s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:59.88942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:58.36746Z","time spent":"1.521936612s","remote":"127.0.0.1:44920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:14:59.889474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539256187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gm9lp\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-07T00:14:59.889501Z","caller":"traceutil/trace.go:171","msg":"trace[957253990] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gm9lp; range_end:; response_count:1; response_revision:631; }","duration":"1.539317498s","start":"2026-05-07T00:14:58.350174Z","end":"2026-05-07T00:14:59.889492Z","steps":["trace[957253990] 'agreement among raft nodes before linearized reading' (duration: 1.539147024s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:59.889521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:58.350156Z","time spent":"1.539360319s","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-gm9lp\" "} {"level":"info","ts":"2026-05-07T00:14:59.889305Z","caller":"traceutil/trace.go:171","msg":"trace[621821674] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:649; }","duration":"1.539079332s","start":"2026-05-07T00:14:58.350213Z","end":"2026-05-07T00:14:59.889292Z","steps":["trace[621821674] 'read index received' (duration: 615.343466ms)","trace[621821674] 'applied index is now lower than readState.Index' (duration: 923.734366ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:14:59.889655Z","caller":"traceutil/trace.go:171","msg":"trace[1356547923] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.050221454s","start":"2026-05-07T00:14:58.839425Z","end":"2026-05-07T00:14:59.889647Z","steps":["trace[1356547923] 'process raft request' (duration: 1.049633172s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:59.889649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332252203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:14:59.889687Z","caller":"traceutil/trace.go:171","msg":"trace[740097182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"1.332331934s","start":"2026-05-07T00:14:58.557344Z","end":"2026-05-07T00:14:59.889676Z","steps":["trace[740097182] 'agreement among raft nodes before linearized reading' (duration: 1.332276603s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:59.889699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:58.839392Z","time spent":"1.050278555s","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:14:59.889713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:58.557321Z","time spent":"1.332386005s","remote":"127.0.0.1:44626","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-05-07T00:14:59.889933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.096658ms","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-05-07T00:14:59.889893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.709026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T00:14:59.889965Z","caller":"traceutil/trace.go:171","msg":"trace[792385980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"540.148448ms","start":"2026-05-07T00:14:59.349807Z","end":"2026-05-07T00:14:59.889956Z","steps":["trace[792385980] 'agreement among raft nodes before linearized reading' (duration: 540.037936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:59.889992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:59.349788Z","time spent":"540.19794ms","remote":"127.0.0.1:44944","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-05-07T00:14:59.889993Z","caller":"traceutil/trace.go:171","msg":"trace[2063469769] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:631; }","duration":"309.858649ms","start":"2026-05-07T00:14:59.580112Z","end":"2026-05-07T00:14:59.889971Z","steps":["trace[2063469769] 'agreement among raft nodes before linearized reading' (duration: 309.544823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:14:59.890056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:59.580091Z","time spent":"309.95672ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"warn","ts":"2026-05-07T00:15:00.75609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.923365ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-07T00:15:00.756209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:59.892377Z","time spent":"863.825836ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-07T00:15:00.796576Z","caller":"traceutil/trace.go:171","msg":"trace[824633365] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"902.222684ms","start":"2026-05-07T00:14:59.894332Z","end":"2026-05-07T00:15:00.796554Z","steps":["trace[824633365] 'process raft request' (duration: 901.956529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:00.79672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:59.894314Z","time spent":"902.329067ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:15:00.796827Z","caller":"traceutil/trace.go:171","msg":"trace[956244694] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"900.223777ms","start":"2026-05-07T00:14:59.896539Z","end":"2026-05-07T00:15:00.796763Z","steps":["trace[956244694] 'read index received' (duration: 164.62603ms)","trace[956244694] 'applied index is now lower than readState.Index' (duration: 735.594487ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:15:00.796864Z","caller":"traceutil/trace.go:171","msg":"trace[1007940567] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"890.680798ms","start":"2026-05-07T00:14:59.906148Z","end":"2026-05-07T00:15:00.796828Z","steps":["trace[1007940567] 'process raft request' (duration: 890.327131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:00.796981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.44218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-07T00:15:00.79703Z","caller":"traceutil/trace.go:171","msg":"trace[884704761] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:633; }","duration":"900.529382ms","start":"2026-05-07T00:14:59.896488Z","end":"2026-05-07T00:15:00.797018Z","steps":["trace[884704761] 'agreement among raft nodes before linearized reading' (duration: 900.443031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:00.797067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:59.896481Z","time spent":"900.579563ms","remote":"127.0.0.1:44872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-07T00:15:00.797148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:59.906135Z","time spent":"890.865192ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:15:00.800183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.867821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:15:00.800496Z","caller":"traceutil/trace.go:171","msg":"trace[893063227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"900.206647ms","start":"2026-05-07T00:14:59.900267Z","end":"2026-05-07T00:15:00.800474Z","steps":["trace[893063227] 'agreement among raft nodes before linearized reading' (duration: 899.803059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:00.80055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:14:59.900261Z","time spent":"900.278658ms","remote":"127.0.0.1:44626","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-05-07T00:15:00.801027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.624915ms","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-05-07T00:15:00.801069Z","caller":"traceutil/trace.go:171","msg":"trace[2133298280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"713.694176ms","start":"2026-05-07T00:15:00.087362Z","end":"2026-05-07T00:15:00.801056Z","steps":["trace[2133298280] 'agreement among raft nodes before linearized reading' (duration: 713.567574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:00.801106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:00.087334Z","time spent":"713.762308ms","remote":"127.0.0.1:44944","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":"info","ts":"2026-05-07T00:15:01.975433Z","caller":"traceutil/trace.go:171","msg":"trace[43275567] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"349.166444ms","start":"2026-05-07T00:15:01.62621Z","end":"2026-05-07T00:15:01.975376Z","steps":["trace[43275567] 'process raft request' (duration: 348.92083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:01.975602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:01.626178Z","time spent":"349.346808ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:15:02.61812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.991639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:15:02.618475Z","caller":"traceutil/trace.go:171","msg":"trace[1938121973] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"637.779076ms","start":"2026-05-07T00:15:01.980663Z","end":"2026-05-07T00:15:02.618442Z","steps":["trace[1938121973] 'process raft request' (duration: 217.352408ms)","trace[1938121973] 'compare' (duration: 419.842777ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:15:02.61859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:01.980629Z","time spent":"637.910048ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:15:02.862977Z","caller":"traceutil/trace.go:171","msg":"trace[1567908586] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"495.35739ms","start":"2026-05-07T00:15:02.36759Z","end":"2026-05-07T00:15:02.862948Z","steps":["trace[1567908586] 'read index received' (duration: 486.572436ms)","trace[1567908586] 'applied index is now lower than readState.Index' (duration: 8.783944ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:15:02.863098Z","caller":"traceutil/trace.go:171","msg":"trace[1361641870] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"860.86865ms","start":"2026-05-07T00:15:02.002215Z","end":"2026-05-07T00:15:02.863084Z","steps":["trace[1361641870] 'process raft request' (duration: 851.876242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:02.86323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.953276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:15:02.863291Z","caller":"traceutil/trace.go:171","msg":"trace[389203717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"305.07936ms","start":"2026-05-07T00:15:02.558199Z","end":"2026-05-07T00:15:02.863278Z","steps":["trace[389203717] 'agreement among raft nodes before linearized reading' (duration: 304.958036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:02.863336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:02.558171Z","time spent":"305.15683ms","remote":"127.0.0.1:44626","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-05-07T00:15:02.863623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:02.00219Z","time spent":"860.942341ms","remote":"127.0.0.1:44944","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-05-07T00:15:02.863983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.348758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gm9lp\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-05-07T00:15:02.86414Z","caller":"traceutil/trace.go:171","msg":"trace[1742536583] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gm9lp; range_end:; response_count:1; response_revision:647; }","duration":"496.548483ms","start":"2026-05-07T00:15:02.367532Z","end":"2026-05-07T00:15:02.864081Z","steps":["trace[1742536583] 'agreement among raft nodes before linearized reading' (duration: 495.899811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:02.864233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:02.36751Z","time spent":"496.709796ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17267,"request content":"key:\"/registry/pods/kube-system/cilium-gm9lp\" "} {"level":"info","ts":"2026-05-07T00:15:03.036318Z","caller":"traceutil/trace.go:171","msg":"trace[63282036] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"167.068627ms","start":"2026-05-07T00:15:02.869218Z","end":"2026-05-07T00:15:03.036286Z","steps":["trace[63282036] 'process raft request' (duration: 166.762211ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:03.036376Z","caller":"traceutil/trace.go:171","msg":"trace[1377885761] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"157.848855ms","start":"2026-05-07T00:15:02.878488Z","end":"2026-05-07T00:15:03.036337Z","steps":["trace[1377885761] 'process raft request' (duration: 157.703322ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:03.67464Z","caller":"traceutil/trace.go:171","msg":"trace[262250920] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"115.44824ms","start":"2026-05-07T00:15:03.559145Z","end":"2026-05-07T00:15:03.674594Z","steps":["trace[262250920] 'process raft request' (duration: 63.992297ms)","trace[262250920] 'compare' (duration: 51.291931ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:15:08.253454Z","caller":"traceutil/trace.go:171","msg":"trace[950579245] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"100.373048ms","start":"2026-05-07T00:15:08.153041Z","end":"2026-05-07T00:15:08.253414Z","steps":["trace[950579245] 'process raft request' (duration: 47.354555ms)","trace[950579245] 'compare' (duration: 52.808799ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:15:08.400878Z","caller":"traceutil/trace.go:171","msg":"trace[1078379971] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"115.295329ms","start":"2026-05-07T00:15:08.285557Z","end":"2026-05-07T00:15:08.400852Z","steps":["trace[1078379971] 'process raft request' (duration: 87.471118ms)","trace[1078379971] 'compare' (duration: 27.392872ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:15:08.401218Z","caller":"traceutil/trace.go:171","msg":"trace[30671320] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"112.832062ms","start":"2026-05-07T00:15:08.287984Z","end":"2026-05-07T00:15:08.400816Z","steps":["trace[30671320] 'process raft request' (duration: 112.740019ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:08.677376Z","caller":"traceutil/trace.go:171","msg":"trace[807358945] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"117.148983ms","start":"2026-05-07T00:15:08.560202Z","end":"2026-05-07T00:15:08.677351Z","steps":["trace[807358945] 'read index received' (duration: 116.821137ms)","trace[807358945] 'applied index is now lower than readState.Index' (duration: 326.476µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:15:08.677605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.424157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:15:08.677529Z","caller":"traceutil/trace.go:171","msg":"trace[1929352297] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"168.961683ms","start":"2026-05-07T00:15:08.508513Z","end":"2026-05-07T00:15:08.677475Z","steps":["trace[1929352297] 'process raft request' (duration: 168.628257ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:08.677719Z","caller":"traceutil/trace.go:171","msg":"trace[1820347280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"117.586681ms","start":"2026-05-07T00:15:08.560102Z","end":"2026-05-07T00:15:08.677689Z","steps":["trace[1820347280] 'agreement among raft nodes before linearized reading' (duration: 117.389597ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:08.793347Z","caller":"traceutil/trace.go:171","msg":"trace[1259361288] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"101.230274ms","start":"2026-05-07T00:15:08.692074Z","end":"2026-05-07T00:15:08.793304Z","steps":["trace[1259361288] 'process raft request' (duration: 86.63851ms)","trace[1259361288] 'compare' (duration: 14.39573ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:15:09.222494Z","caller":"traceutil/trace.go:171","msg":"trace[285968347] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"100.876438ms","start":"2026-05-07T00:15:09.121581Z","end":"2026-05-07T00:15:09.222458Z","steps":["trace[285968347] 'read index received' (duration: 93.945778ms)","trace[285968347] 'applied index is now lower than readState.Index' (duration: 6.92921ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:15:09.222606Z","caller":"traceutil/trace.go:171","msg":"trace[1910957353] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"101.494898ms","start":"2026-05-07T00:15:09.121064Z","end":"2026-05-07T00:15:09.222559Z","steps":["trace[1910957353] 'process raft request' (duration: 94.59856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:09.222714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.148573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:15:09.22282Z","caller":"traceutil/trace.go:171","msg":"trace[216352901] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:739; }","duration":"101.302377ms","start":"2026-05-07T00:15:09.121493Z","end":"2026-05-07T00:15:09.222795Z","steps":["trace[216352901] 'agreement among raft nodes before linearized reading' (duration: 101.077802ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:10.195774Z","caller":"traceutil/trace.go:171","msg":"trace[681287669] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"189.569999ms","start":"2026-05-07T00:15:10.006155Z","end":"2026-05-07T00:15:10.195725Z","steps":["trace[681287669] 'process raft request' (duration: 189.226112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:15.626307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303572743164545,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T00:15:15.712863Z","caller":"traceutil/trace.go:171","msg":"trace[1768262014] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"624.584869ms","start":"2026-05-07T00:15:15.088258Z","end":"2026-05-07T00:15:15.712843Z","steps":["trace[1768262014] 'process raft request' (duration: 624.460736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:15.712977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:15.088219Z","time spent":"624.688341ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:15:15.831398Z","caller":"traceutil/trace.go:171","msg":"trace[1797936788] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:801; }","duration":"705.357321ms","start":"2026-05-07T00:15:15.126016Z","end":"2026-05-07T00:15:15.831373Z","steps":["trace[1797936788] 'read index received' (duration: 586.788322ms)","trace[1797936788] 'applied index is now lower than readState.Index' (duration: 118.567389ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:15:15.831632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.477248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:15:15.831724Z","caller":"traceutil/trace.go:171","msg":"trace[1200385945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"273.587131ms","start":"2026-05-07T00:15:15.558091Z","end":"2026-05-07T00:15:15.831678Z","steps":["trace[1200385945] 'agreement among raft nodes before linearized reading' (duration: 273.483639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:15.831852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.026633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T00:15:15.831957Z","caller":"traceutil/trace.go:171","msg":"trace[1434825947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"582.199036ms","start":"2026-05-07T00:15:15.249732Z","end":"2026-05-07T00:15:15.831931Z","steps":["trace[1434825947] 'agreement among raft nodes before linearized reading' (duration: 581.804619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:15.832023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:15.249706Z","time spent":"582.304808ms","remote":"127.0.0.1:44944","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-05-07T00:15:15.831977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.885781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T00:15:15.832182Z","caller":"traceutil/trace.go:171","msg":"trace[2072645888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"706.174755ms","start":"2026-05-07T00:15:15.125988Z","end":"2026-05-07T00:15:15.832162Z","steps":["trace[2072645888] 'agreement among raft nodes before linearized reading' (duration: 705.584035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:15.832273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:15.125974Z","time spent":"706.285297ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T00:15:19.310895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303572743164583,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T00:15:19.391224Z","caller":"traceutil/trace.go:171","msg":"trace[824027188] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"907.414011ms","start":"2026-05-07T00:15:18.483786Z","end":"2026-05-07T00:15:19.3912Z","steps":["trace[824027188] 'process raft request' (duration: 906.973984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:19.391385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:18.483774Z","time spent":"907.529023ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:15:19.391424Z","caller":"traceutil/trace.go:171","msg":"trace[541871508] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"580.501244ms","start":"2026-05-07T00:15:18.810634Z","end":"2026-05-07T00:15:19.391135Z","steps":["trace[541871508] 'read index received' (duration: 580.002935ms)","trace[541871508] 'applied index is now lower than readState.Index' (duration: 496.749µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:15:19.3916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.549707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T00:15:19.391604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.955392ms","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-05-07T00:15:19.391635Z","caller":"traceutil/trace.go:171","msg":"trace[1090672433] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:793; }","duration":"509.607577ms","start":"2026-05-07T00:15:18.882017Z","end":"2026-05-07T00:15:19.391625Z","steps":["trace[1090672433] 'agreement among raft nodes before linearized reading' (duration: 509.544546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:19.391669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:18.882005Z","time spent":"509.656498ms","remote":"127.0.0.1:45110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-07T00:15:19.391723Z","caller":"traceutil/trace.go:171","msg":"trace[40154995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"581.078855ms","start":"2026-05-07T00:15:18.810576Z","end":"2026-05-07T00:15:19.391654Z","steps":["trace[40154995] 'agreement among raft nodes before linearized reading' (duration: 580.82167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:19.391663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.613513ms","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":"warn","ts":"2026-05-07T00:15:19.391785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:18.810555Z","time spent":"581.216407ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T00:15:19.391867Z","caller":"traceutil/trace.go:171","msg":"trace[719758222] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:793; }","duration":"528.871227ms","start":"2026-05-07T00:15:18.862934Z","end":"2026-05-07T00:15:19.391805Z","steps":["trace[719758222] 'agreement among raft nodes before linearized reading' (duration: 528.620452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:19.391945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:18.862911Z","time spent":"529.013421ms","remote":"127.0.0.1:45196","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":"info","ts":"2026-05-07T00:15:23.548533Z","caller":"traceutil/trace.go:171","msg":"trace[685487173] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"114.589954ms","start":"2026-05-07T00:15:23.433902Z","end":"2026-05-07T00:15:23.548492Z","steps":["trace[685487173] 'process raft request' (duration: 111.10966ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:24.739481Z","caller":"traceutil/trace.go:171","msg":"trace[800090768] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"158.966506ms","start":"2026-05-07T00:15:24.580483Z","end":"2026-05-07T00:15:24.73945Z","steps":["trace[800090768] 'process raft request' (duration: 158.747672ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:25.974262Z","caller":"traceutil/trace.go:171","msg":"trace[1699080317] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"115.822658ms","start":"2026-05-07T00:15:25.858374Z","end":"2026-05-07T00:15:25.974197Z","steps":["trace[1699080317] 'read index received' (duration: 110.340556ms)","trace[1699080317] 'applied index is now lower than readState.Index' (duration: 5.479782ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:15:25.974474Z","caller":"traceutil/trace.go:171","msg":"trace[1104976102] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"126.328384ms","start":"2026-05-07T00:15:25.84813Z","end":"2026-05-07T00:15:25.974458Z","steps":["trace[1104976102] 'process raft request' (duration: 120.539096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:25.974781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.288526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:15:25.974951Z","caller":"traceutil/trace.go:171","msg":"trace[1792603234] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:866; }","duration":"116.48928ms","start":"2026-05-07T00:15:25.8584Z","end":"2026-05-07T00:15:25.974889Z","steps":["trace[1792603234] 'agreement among raft nodes before linearized reading' (duration: 116.223155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:25.975088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.642913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:15:25.975177Z","caller":"traceutil/trace.go:171","msg":"trace[588764464] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:866; }","duration":"116.824766ms","start":"2026-05-07T00:15:25.858333Z","end":"2026-05-07T00:15:25.975158Z","steps":["trace[588764464] 'agreement among raft nodes before linearized reading' (duration: 116.230855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:25.975446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.310437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:15:25.975486Z","caller":"traceutil/trace.go:171","msg":"trace[1565086180] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:866; }","duration":"116.369527ms","start":"2026-05-07T00:15:25.859106Z","end":"2026-05-07T00:15:25.975476Z","steps":["trace[1565086180] 'agreement among raft nodes before linearized reading' (duration: 116.308836ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:26.109895Z","caller":"traceutil/trace.go:171","msg":"trace[1446962743] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"127.722291ms","start":"2026-05-07T00:15:25.982139Z","end":"2026-05-07T00:15:26.109861Z","steps":["trace[1446962743] 'process raft request' (duration: 125.290336ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:26.11002Z","caller":"traceutil/trace.go:171","msg":"trace[1438311924] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"127.790192ms","start":"2026-05-07T00:15:25.982181Z","end":"2026-05-07T00:15:26.109971Z","steps":["trace[1438311924] 'process raft request' (duration: 127.568888ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:26.110181Z","caller":"traceutil/trace.go:171","msg":"trace[1839799182] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"123.761897ms","start":"2026-05-07T00:15:25.986402Z","end":"2026-05-07T00:15:26.110164Z","steps":["trace[1839799182] 'process raft request' (duration: 123.519222ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:26.110299Z","caller":"traceutil/trace.go:171","msg":"trace[1925303809] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"121.839821ms","start":"2026-05-07T00:15:25.988428Z","end":"2026-05-07T00:15:26.110268Z","steps":["trace[1925303809] 'process raft request' (duration: 121.579116ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:26.11035Z","caller":"traceutil/trace.go:171","msg":"trace[501334969] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"127.531047ms","start":"2026-05-07T00:15:25.982804Z","end":"2026-05-07T00:15:26.110335Z","steps":["trace[501334969] 'process raft request' (duration: 127.006977ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:26.34499Z","caller":"traceutil/trace.go:171","msg":"trace[1228302900] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"226.195143ms","start":"2026-05-07T00:15:26.118763Z","end":"2026-05-07T00:15:26.344958Z","steps":["trace[1228302900] 'process raft request' (duration: 153.092614ms)","trace[1228302900] 'compare' (duration: 72.763022ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:15:26.348925Z","caller":"traceutil/trace.go:171","msg":"trace[1213964069] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"229.835901ms","start":"2026-05-07T00:15:26.119064Z","end":"2026-05-07T00:15:26.3489Z","steps":["trace[1213964069] 'process raft request' (duration: 229.170119ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:26.349335Z","caller":"traceutil/trace.go:171","msg":"trace[213983608] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"121.678837ms","start":"2026-05-07T00:15:26.227631Z","end":"2026-05-07T00:15:26.34931Z","steps":["trace[213983608] 'process raft request' (duration: 120.76675ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:42.343139Z","caller":"traceutil/trace.go:171","msg":"trace[896005990] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"138.229717ms","start":"2026-05-07T00:15:42.20488Z","end":"2026-05-07T00:15:42.343109Z","steps":["trace[896005990] 'process raft request' (duration: 138.030923ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:42.349764Z","caller":"traceutil/trace.go:171","msg":"trace[1410494359] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"142.385204ms","start":"2026-05-07T00:15:42.207362Z","end":"2026-05-07T00:15:42.349747Z","steps":["trace[1410494359] 'process raft request' (duration: 142.347574ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:42.349853Z","caller":"traceutil/trace.go:171","msg":"trace[809931426] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"142.844813ms","start":"2026-05-07T00:15:42.206964Z","end":"2026-05-07T00:15:42.349809Z","steps":["trace[809931426] 'process raft request' (duration: 142.6815ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:54.169036Z","caller":"traceutil/trace.go:171","msg":"trace[817463732] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"115.55313ms","start":"2026-05-07T00:15:54.053454Z","end":"2026-05-07T00:15:54.169007Z","steps":["trace[817463732] 'process raft request' (duration: 115.369917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:54.896097Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303572743165046,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T00:15:55.164379Z","caller":"traceutil/trace.go:171","msg":"trace[1556621815] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"899.462047ms","start":"2026-05-07T00:15:54.264887Z","end":"2026-05-07T00:15:55.164349Z","steps":["trace[1556621815] 'process raft request' (duration: 899.315564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:55.164581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:54.26486Z","time spent":"899.589489ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:15:55.877843Z","caller":"traceutil/trace.go:171","msg":"trace[587205972] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.342162658s","start":"2026-05-07T00:15:54.535642Z","end":"2026-05-07T00:15:55.877805Z","steps":["trace[587205972] 'process raft request' (duration: 1.341998895s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:55.878039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:54.535618Z","time spent":"1.342316533s","remote":"127.0.0.1:44944","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-05-07T00:15:56.018745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.951251ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-07T00:15:56.018885Z","caller":"traceutil/trace.go:171","msg":"trace[1498614365] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1048; }","duration":"1.623232246s","start":"2026-05-07T00:15:54.395626Z","end":"2026-05-07T00:15:56.018858Z","steps":["trace[1498614365] 'read index received' (duration: 768.642954ms)","trace[1498614365] 'applied index is now lower than readState.Index' (duration: 854.587492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:15:56.019051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.822506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:15:56.019081Z","caller":"traceutil/trace.go:171","msg":"trace[1613728929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"463.902566ms","start":"2026-05-07T00:15:55.555166Z","end":"2026-05-07T00:15:56.019068Z","steps":["trace[1613728929] 'agreement among raft nodes before linearized reading' (duration: 463.827955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:56.019112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:55.555144Z","time spent":"463.961998ms","remote":"127.0.0.1:44626","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-05-07T00:15:56.019105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.623472149s","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-05-07T00:15:56.019167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.612413434s","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-05-07T00:15:56.019194Z","caller":"traceutil/trace.go:171","msg":"trace[2070510110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"1.623624462s","start":"2026-05-07T00:15:54.395545Z","end":"2026-05-07T00:15:56.01917Z","steps":["trace[2070510110] 'agreement among raft nodes before linearized reading' (duration: 1.623373958s)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:15:56.019223Z","caller":"traceutil/trace.go:171","msg":"trace[432292229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"1.612490026s","start":"2026-05-07T00:15:54.406716Z","end":"2026-05-07T00:15:56.019206Z","steps":["trace[432292229] 'agreement among raft nodes before linearized reading' (duration: 1.612271992s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:56.01924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:54.395481Z","time spent":"1.623750675s","remote":"127.0.0.1:44944","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-05-07T00:15:56.019266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:54.406705Z","time spent":"1.612552767s","remote":"127.0.0.1:44944","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-05-07T00:15:56.019296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.152228ms","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-05-07T00:15:56.019317Z","caller":"traceutil/trace.go:171","msg":"trace[367752331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"324.21837ms","start":"2026-05-07T00:15:55.695092Z","end":"2026-05-07T00:15:56.01931Z","steps":["trace[367752331] 'agreement among raft nodes before linearized reading' (duration: 324.144887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:56.019336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:55.695069Z","time spent":"324.262131ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T00:15:56.019435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610975149s","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-05-07T00:15:56.019464Z","caller":"traceutil/trace.go:171","msg":"trace[1082188324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"1.611023709s","start":"2026-05-07T00:15:54.40843Z","end":"2026-05-07T00:15:56.019454Z","steps":["trace[1082188324] 'agreement among raft nodes before linearized reading' (duration: 1.610943898s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:15:56.019496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:15:54.40842Z","time spent":"1.61106889s","remote":"127.0.0.1:44944","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-05-07T00:16:22.1062Z","caller":"traceutil/trace.go:171","msg":"trace[1541201663] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"319.376829ms","start":"2026-05-07T00:16:21.786775Z","end":"2026-05-07T00:16:22.106151Z","steps":["trace[1541201663] 'process raft request' (duration: 319.168796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:22.106487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:21.786748Z","time spent":"319.567782ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:16:22.66019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303572743165282,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T00:16:22.666288Z","caller":"traceutil/trace.go:171","msg":"trace[231773083] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"506.52979ms","start":"2026-05-07T00:16:22.159726Z","end":"2026-05-07T00:16:22.666256Z","steps":["trace[231773083] 'read index received' (duration: 506.315196ms)","trace[231773083] 'applied index is now lower than readState.Index' (duration: 213.464µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:16:22.666444Z","caller":"traceutil/trace.go:171","msg":"trace[853961480] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"698.081691ms","start":"2026-05-07T00:16:21.968321Z","end":"2026-05-07T00:16:22.666402Z","steps":["trace[853961480] 'process raft request' (duration: 697.745925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:22.666596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.837235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T00:16:22.666641Z","caller":"traceutil/trace.go:171","msg":"trace[1289886198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1099; }","duration":"506.984569ms","start":"2026-05-07T00:16:22.159643Z","end":"2026-05-07T00:16:22.666628Z","steps":["trace[1289886198] 'agreement among raft nodes before linearized reading' (duration: 506.765504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:22.66665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:21.968289Z","time spent":"698.253254ms","remote":"127.0.0.1:44944","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-05-07T00:16:22.666673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.35773ms","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-05-07T00:16:22.666683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:22.159616Z","time spent":"507.057371ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T00:16:22.666704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.273975ms","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-05-07T00:16:22.666735Z","caller":"traceutil/trace.go:171","msg":"trace[1586890676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"497.462461ms","start":"2026-05-07T00:16:22.169254Z","end":"2026-05-07T00:16:22.666716Z","steps":["trace[1586890676] 'agreement among raft nodes before linearized reading' (duration: 497.248026ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:16:22.66674Z","caller":"traceutil/trace.go:171","msg":"trace[620671082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"321.361017ms","start":"2026-05-07T00:16:22.345369Z","end":"2026-05-07T00:16:22.66673Z","steps":["trace[620671082] 'agreement among raft nodes before linearized reading' (duration: 321.262465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:22.666717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.37802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T00:16:22.666812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:22.169242Z","time spent":"497.560574ms","remote":"127.0.0.1:44944","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-05-07T00:16:22.666736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.50309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T00:16:22.666812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:22.345339Z","time spent":"321.463309ms","remote":"127.0.0.1:44944","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":"info","ts":"2026-05-07T00:16:22.666859Z","caller":"traceutil/trace.go:171","msg":"trace[1554835261] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1099; }","duration":"490.582063ms","start":"2026-05-07T00:16:22.176251Z","end":"2026-05-07T00:16:22.666833Z","steps":["trace[1554835261] 'agreement among raft nodes before linearized reading' (duration: 490.309928ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:16:22.666882Z","caller":"traceutil/trace.go:171","msg":"trace[2043959490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"106.666053ms","start":"2026-05-07T00:16:22.560195Z","end":"2026-05-07T00:16:22.666861Z","steps":["trace[2043959490] 'agreement among raft nodes before linearized reading' (duration: 106.440839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:22.666921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:22.176241Z","time spent":"490.667815ms","remote":"127.0.0.1:60364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T00:16:22.666982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.044555ms","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-05-07T00:16:22.666903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.333173ms","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-05-07T00:16:22.667019Z","caller":"traceutil/trace.go:171","msg":"trace[995089510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1099; }","duration":"496.124077ms","start":"2026-05-07T00:16:22.170885Z","end":"2026-05-07T00:16:22.667009Z","steps":["trace[995089510] 'agreement among raft nodes before linearized reading' (duration: 496.014635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:22.667051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:22.170859Z","time spent":"496.185798ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T00:16:22.667051Z","caller":"traceutil/trace.go:171","msg":"trace[620186431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1099; }","duration":"494.519686ms","start":"2026-05-07T00:16:22.17251Z","end":"2026-05-07T00:16:22.66703Z","steps":["trace[620186431] 'agreement among raft nodes before linearized reading' (duration: 494.126059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:22.667102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:22.172493Z","time spent":"494.596627ms","remote":"127.0.0.1:44944","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-05-07T00:16:24.854252Z","caller":"traceutil/trace.go:171","msg":"trace[277891834] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"167.668119ms","start":"2026-05-07T00:16:24.686547Z","end":"2026-05-07T00:16:24.854215Z","steps":["trace[277891834] 'read index received' (duration: 167.568557ms)","trace[277891834] 'applied index is now lower than readState.Index' (duration: 98.112µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:16:24.854425Z","caller":"traceutil/trace.go:171","msg":"trace[953424735] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"180.196032ms","start":"2026-05-07T00:16:24.674214Z","end":"2026-05-07T00:16:24.85441Z","steps":["trace[953424735] 'process raft request' (duration: 179.790714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:24.854766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.201458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T00:16:24.85481Z","caller":"traceutil/trace.go:171","msg":"trace[656781404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"168.30684ms","start":"2026-05-07T00:16:24.686492Z","end":"2026-05-07T00:16:24.854799Z","steps":["trace[656781404] 'agreement among raft nodes before linearized reading' (duration: 168.107397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:24.91734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.521014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T00:16:24.917441Z","caller":"traceutil/trace.go:171","msg":"trace[664559425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"211.693047ms","start":"2026-05-07T00:16:24.705724Z","end":"2026-05-07T00:16:24.917417Z","steps":["trace[664559425] 'agreement among raft nodes before linearized reading' (duration: 211.328781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:24.917421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.65762ms","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-05-07T00:16:24.917423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.758751ms","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-05-07T00:16:24.917541Z","caller":"traceutil/trace.go:171","msg":"trace[1020622950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1106; }","duration":"209.914874ms","start":"2026-05-07T00:16:24.707595Z","end":"2026-05-07T00:16:24.91751Z","steps":["trace[1020622950] 'agreement among raft nodes before linearized reading' (duration: 209.589838ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:16:24.91763Z","caller":"traceutil/trace.go:171","msg":"trace[1206365106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"209.938375ms","start":"2026-05-07T00:16:24.707669Z","end":"2026-05-07T00:16:24.917608Z","steps":["trace[1206365106] 'agreement among raft nodes before linearized reading' (duration: 209.573518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:24.91792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.652436ms","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-05-07T00:16:24.918069Z","caller":"traceutil/trace.go:171","msg":"trace[1936426080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1106; }","duration":"211.83367ms","start":"2026-05-07T00:16:24.706205Z","end":"2026-05-07T00:16:24.918039Z","steps":["trace[1936426080] 'agreement among raft nodes before linearized reading' (duration: 211.167367ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:16:26.303274Z","caller":"traceutil/trace.go:171","msg":"trace[1470350994] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"281.24966ms","start":"2026-05-07T00:16:26.021983Z","end":"2026-05-07T00:16:26.303233Z","steps":["trace[1470350994] 'process raft request' (duration: 281.007446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:27.121866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.700193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:16:27.122281Z","caller":"traceutil/trace.go:171","msg":"trace[1273104602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"564.190562ms","start":"2026-05-07T00:16:26.558068Z","end":"2026-05-07T00:16:27.122259Z","steps":["trace[1273104602] 'range keys from in-memory index tree' (duration: 563.611131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:27.122097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.180927ms","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":"warn","ts":"2026-05-07T00:16:27.122705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:26.558039Z","time spent":"564.539908ms","remote":"127.0.0.1:44626","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-05-07T00:16:27.122687Z","caller":"traceutil/trace.go:171","msg":"trace[253992099] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1111; }","duration":"311.791638ms","start":"2026-05-07T00:16:26.810816Z","end":"2026-05-07T00:16:27.122608Z","steps":["trace[253992099] 'count revisions from in-memory index tree' (duration: 311.073236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:27.122971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:26.810791Z","time spent":"312.102114ms","remote":"127.0.0.1:44930","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":"warn","ts":"2026-05-07T00:16:27.122377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.2748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-07T00:16:27.122214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.102692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T00:16:27.123364Z","caller":"traceutil/trace.go:171","msg":"trace[833643014] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"201.822734ms","start":"2026-05-07T00:16:26.921487Z","end":"2026-05-07T00:16:27.12331Z","steps":["trace[833643014] 'read index received' (duration: 4.397522ms)","trace[833643014] 'applied index is now lower than readState.Index' (duration: 197.420732ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:16:27.123386Z","caller":"traceutil/trace.go:171","msg":"trace[928140062] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"261.382012ms","start":"2026-05-07T00:16:26.861968Z","end":"2026-05-07T00:16:27.12335Z","steps":["trace[928140062] 'process raft request' (duration: 64.058292ms)","trace[928140062] 'compare' (duration: 196.076406ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:16:27.123389Z","caller":"traceutil/trace.go:171","msg":"trace[1433847027] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1111; }","duration":"628.289244ms","start":"2026-05-07T00:16:26.495044Z","end":"2026-05-07T00:16:27.123333Z","steps":["trace[1433847027] 'count revisions from in-memory index tree' (duration: 626.991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:27.12353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:16:26.495016Z","time spent":"628.467918ms","remote":"127.0.0.1:45160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-07T00:16:27.123759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.269252ms","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-05-07T00:16:27.123812Z","caller":"traceutil/trace.go:171","msg":"trace[922772350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1112; }","duration":"202.408735ms","start":"2026-05-07T00:16:26.921391Z","end":"2026-05-07T00:16:27.1238Z","steps":["trace[922772350] 'agreement among raft nodes before linearized reading' (duration: 202.13327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:27.124044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.160143ms","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-05-07T00:16:27.124088Z","caller":"traceutil/trace.go:171","msg":"trace[1376026483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"186.231784ms","start":"2026-05-07T00:16:26.937844Z","end":"2026-05-07T00:16:27.124076Z","steps":["trace[1376026483] 'agreement among raft nodes before linearized reading' (duration: 186.077071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:27.124124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.677338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T00:16:27.124153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.015667ms","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-05-07T00:16:27.124174Z","caller":"traceutil/trace.go:171","msg":"trace[820382115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1112; }","duration":"139.748249ms","start":"2026-05-07T00:16:26.984415Z","end":"2026-05-07T00:16:27.124163Z","steps":["trace[820382115] 'agreement among raft nodes before linearized reading' (duration: 139.547816ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:16:27.124211Z","caller":"traceutil/trace.go:171","msg":"trace[1381823174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1112; }","duration":"138.126949ms","start":"2026-05-07T00:16:26.98607Z","end":"2026-05-07T00:16:27.124197Z","steps":["trace[1381823174] 'agreement among raft nodes before linearized reading' (duration: 137.977846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:16:27.124212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.828651ms","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-05-07T00:16:27.124273Z","caller":"traceutil/trace.go:171","msg":"trace[476025351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1112; }","duration":"139.918743ms","start":"2026-05-07T00:16:26.984339Z","end":"2026-05-07T00:16:27.124258Z","steps":["trace[476025351] 'agreement among raft nodes before linearized reading' (duration: 139.732649ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:16:43.284241Z","caller":"traceutil/trace.go:171","msg":"trace[36595286] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"102.635018ms","start":"2026-05-07T00:16:43.181571Z","end":"2026-05-07T00:16:43.284206Z","steps":["trace[36595286] 'process raft request' (duration: 102.022157ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:01.723268Z","caller":"traceutil/trace.go:171","msg":"trace[1661815428] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"327.22734ms","start":"2026-05-07T00:17:01.395997Z","end":"2026-05-07T00:17:01.723225Z","steps":["trace[1661815428] 'read index received' (duration: 326.965706ms)","trace[1661815428] 'applied index is now lower than readState.Index' (duration: 260.154µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:17:01.723301Z","caller":"traceutil/trace.go:171","msg":"trace[480034680] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"328.650447ms","start":"2026-05-07T00:17:01.3946Z","end":"2026-05-07T00:17:01.72325Z","steps":["trace[480034680] 'process raft request' (duration: 328.391032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:01.723525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:01.39456Z","time spent":"328.84492ms","remote":"127.0.0.1:44944","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-05-07T00:17:01.723537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.493666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T00:17:01.723578Z","caller":"traceutil/trace.go:171","msg":"trace[1453547588] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1307; }","duration":"327.575987ms","start":"2026-05-07T00:17:01.39599Z","end":"2026-05-07T00:17:01.723566Z","steps":["trace[1453547588] 'agreement among raft nodes before linearized reading' (duration: 327.431685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:01.723622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:01.395943Z","time spent":"327.668499ms","remote":"127.0.0.1:44864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-07T00:17:01.724084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.577842ms","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-05-07T00:17:01.724138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.369906ms","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-05-07T00:17:01.724192Z","caller":"traceutil/trace.go:171","msg":"trace[685046459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1307; }","duration":"308.742545ms","start":"2026-05-07T00:17:01.415421Z","end":"2026-05-07T00:17:01.724164Z","steps":["trace[685046459] 'agreement among raft nodes before linearized reading' (duration: 308.422899ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:01.724231Z","caller":"traceutil/trace.go:171","msg":"trace[1281755027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"234.486648ms","start":"2026-05-07T00:17:01.489721Z","end":"2026-05-07T00:17:01.724207Z","steps":["trace[1281755027] 'agreement among raft nodes before linearized reading' (duration: 234.183982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:01.72426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:01.415405Z","time spent":"308.844107ms","remote":"127.0.0.1:44944","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-05-07T00:17:01.724276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.986698ms","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-05-07T00:17:01.724334Z","caller":"traceutil/trace.go:171","msg":"trace[962189441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"235.044959ms","start":"2026-05-07T00:17:01.489275Z","end":"2026-05-07T00:17:01.72432Z","steps":["trace[962189441] 'agreement among raft nodes before linearized reading' (duration: 234.833785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:01.724402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.617913ms","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-05-07T00:17:01.724404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.632476ms","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-05-07T00:17:01.724451Z","caller":"traceutil/trace.go:171","msg":"trace[1618526337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"233.739925ms","start":"2026-05-07T00:17:01.4907Z","end":"2026-05-07T00:17:01.72444Z","steps":["trace[1618526337] 'agreement among raft nodes before linearized reading' (duration: 233.313797ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:01.724515Z","caller":"traceutil/trace.go:171","msg":"trace[452865094] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1307; }","duration":"238.784379ms","start":"2026-05-07T00:17:01.4857Z","end":"2026-05-07T00:17:01.724484Z","steps":["trace[452865094] 'agreement among raft nodes before linearized reading' (duration: 238.458403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:02.256423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.445779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:17:02.256554Z","caller":"traceutil/trace.go:171","msg":"trace[1211026152] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"527.534981ms","start":"2026-05-07T00:17:01.728996Z","end":"2026-05-07T00:17:02.256531Z","steps":["trace[1211026152] 'process raft request' (duration: 368.881068ms)","trace[1211026152] 'compare' (duration: 157.979081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:02.256624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:01.728979Z","time spent":"527.611202ms","remote":"127.0.0.1:44944","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-05-07T00:17:02.280282Z","caller":"traceutil/trace.go:171","msg":"trace[173487450] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"551.198383ms","start":"2026-05-07T00:17:01.729063Z","end":"2026-05-07T00:17:02.280261Z","steps":["trace[173487450] 'process raft request' (duration: 551.01964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:02.280418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:01.729037Z","time spent":"551.311495ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:02.280609Z","caller":"traceutil/trace.go:171","msg":"trace[1544044633] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"550.98613ms","start":"2026-05-07T00:17:01.729569Z","end":"2026-05-07T00:17:02.280555Z","steps":["trace[1544044633] 'process raft request' (duration: 550.637293ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:02.280732Z","caller":"traceutil/trace.go:171","msg":"trace[422836481] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"551.043691ms","start":"2026-05-07T00:17:01.729671Z","end":"2026-05-07T00:17:02.280715Z","steps":["trace[422836481] 'process raft request' (duration: 550.95192ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:02.280667Z","caller":"traceutil/trace.go:171","msg":"trace[1982644628] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"550.98271ms","start":"2026-05-07T00:17:01.729671Z","end":"2026-05-07T00:17:02.280654Z","steps":["trace[1982644628] 'process raft request' (duration: 550.720724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:02.280844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:01.729662Z","time spent":"551.155933ms","remote":"127.0.0.1:44944","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-05-07T00:17:02.280926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:01.72956Z","time spent":"551.180934ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:17:02.281434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:01.729653Z","time spent":"551.121062ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:03.863999Z","caller":"traceutil/trace.go:171","msg":"trace[703875264] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"306.238138ms","start":"2026-05-07T00:17:03.557739Z","end":"2026-05-07T00:17:03.863977Z","steps":["trace[703875264] 'read index received' (duration: 306.022794ms)","trace[703875264] 'applied index is now lower than readState.Index' (duration: 214.584µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:03.864134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.375441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:17:03.864176Z","caller":"traceutil/trace.go:171","msg":"trace[299361913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"306.474253ms","start":"2026-05-07T00:17:03.557695Z","end":"2026-05-07T00:17:03.864169Z","steps":["trace[299361913] 'agreement among raft nodes before linearized reading' (duration: 306.371751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:03.864211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:03.557675Z","time spent":"306.530924ms","remote":"127.0.0.1:44626","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-05-07T00:17:03.864623Z","caller":"traceutil/trace.go:171","msg":"trace[1240299917] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"539.870022ms","start":"2026-05-07T00:17:03.324742Z","end":"2026-05-07T00:17:03.864612Z","steps":["trace[1240299917] 'process raft request' (duration: 539.083937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:03.864716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:03.324704Z","time spent":"539.957644ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:17:04.366899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303572743165748,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T00:17:04.892056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.364828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:17:04.892187Z","caller":"traceutil/trace.go:171","msg":"trace[1047289823] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1367; }","duration":"1.026285416s","start":"2026-05-07T00:17:03.865882Z","end":"2026-05-07T00:17:04.892168Z","steps":["trace[1047289823] 'read index received' (duration: 852.715055ms)","trace[1047289823] 'applied index is now lower than readState.Index' (duration: 173.568951ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:04.892303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026416788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:17:04.892273Z","caller":"traceutil/trace.go:171","msg":"trace[333648006] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"1.162351576s","start":"2026-05-07T00:17:03.72988Z","end":"2026-05-07T00:17:04.892232Z","steps":["trace[333648006] 'process raft request' (duration: 988.710693ms)","trace[333648006] 'compare' (duration: 173.155233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:04.892468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.35626ms","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-05-07T00:17:04.892472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:03.729853Z","time spent":"1.162510509s","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:04.892498Z","caller":"traceutil/trace.go:171","msg":"trace[1630457677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"631.434862ms","start":"2026-05-07T00:17:04.261056Z","end":"2026-05-07T00:17:04.892491Z","steps":["trace[1630457677] 'agreement among raft nodes before linearized reading' (duration: 631.302249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:04.892538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.261028Z","time spent":"631.501863ms","remote":"127.0.0.1:44944","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-05-07T00:17:04.892331Z","caller":"traceutil/trace.go:171","msg":"trace[1327186774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"1.026480328s","start":"2026-05-07T00:17:03.86584Z","end":"2026-05-07T00:17:04.892321Z","steps":["trace[1327186774] 'agreement among raft nodes before linearized reading' (duration: 1.026428608s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:04.892704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:03.86582Z","time spent":"1.026877986s","remote":"127.0.0.1:44612","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-05-07T00:17:04.892729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.317502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T00:17:04.89278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.973474ms","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-05-07T00:17:04.892815Z","caller":"traceutil/trace.go:171","msg":"trace[772267253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"606.439195ms","start":"2026-05-07T00:17:04.286355Z","end":"2026-05-07T00:17:04.892794Z","steps":["trace[772267253] 'agreement among raft nodes before linearized reading' (duration: 606.15348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:04.89281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.814732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T00:17:04.892867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.286313Z","time spent":"606.540537ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T00:17:04.892861Z","caller":"traceutil/trace.go:171","msg":"trace[1398718029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"607.092567ms","start":"2026-05-07T00:17:04.285748Z","end":"2026-05-07T00:17:04.892841Z","steps":["trace[1398718029] 'agreement among raft nodes before linearized reading' (duration: 606.841162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:04.892912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.285737Z","time spent":"607.166108ms","remote":"127.0.0.1:44944","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-05-07T00:17:04.892909Z","caller":"traceutil/trace.go:171","msg":"trace[782056045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"606.984085ms","start":"2026-05-07T00:17:04.285901Z","end":"2026-05-07T00:17:04.892885Z","steps":["trace[782056045] 'agreement among raft nodes before linearized reading' (duration: 606.665399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:04.892961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.28587Z","time spent":"607.081386ms","remote":"127.0.0.1:44944","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-05-07T00:17:04.892981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.016298ms","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-05-07T00:17:04.893076Z","caller":"traceutil/trace.go:171","msg":"trace[1417811787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1315; }","duration":"606.190421ms","start":"2026-05-07T00:17:04.286863Z","end":"2026-05-07T00:17:04.893054Z","steps":["trace[1417811787] 'agreement among raft nodes before linearized reading' (duration: 605.868645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:04.893127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.286853Z","time spent":"606.264282ms","remote":"127.0.0.1:44944","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-05-07T00:17:05.244904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.375964ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-07T00:17:05.24579Z","caller":"traceutil/trace.go:171","msg":"trace[1539437148] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1368; }","duration":"343.933083ms","start":"2026-05-07T00:17:04.901836Z","end":"2026-05-07T00:17:05.245769Z","steps":["trace[1539437148] 'read index received' (duration: 136.614951ms)","trace[1539437148] 'applied index is now lower than readState.Index' (duration: 207.316502ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:17:05.246066Z","caller":"traceutil/trace.go:171","msg":"trace[920315939] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"347.891387ms","start":"2026-05-07T00:17:04.898163Z","end":"2026-05-07T00:17:05.246054Z","steps":["trace[920315939] 'process raft request' (duration: 347.037371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:05.246216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.898138Z","time spent":"347.989389ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:05.246418Z","caller":"traceutil/trace.go:171","msg":"trace[5229782] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"348.027209ms","start":"2026-05-07T00:17:04.898381Z","end":"2026-05-07T00:17:05.246408Z","steps":["trace[5229782] 'process raft request' (duration: 347.190583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:05.246474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.898373Z","time spent":"348.073581ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:05.246605Z","caller":"traceutil/trace.go:171","msg":"trace[1625327030] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"347.698283ms","start":"2026-05-07T00:17:04.898897Z","end":"2026-05-07T00:17:05.246595Z","steps":["trace[1625327030] 'process raft request' (duration: 346.728404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:05.246661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.898885Z","time spent":"347.749643ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:05.246777Z","caller":"traceutil/trace.go:171","msg":"trace[247393440] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"347.50186ms","start":"2026-05-07T00:17:04.899265Z","end":"2026-05-07T00:17:05.246767Z","steps":["trace[247393440] 'process raft request' (duration: 346.410519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:05.246835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.899255Z","time spent":"347.553161ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:05.246952Z","caller":"traceutil/trace.go:171","msg":"trace[1476816985] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"346.779846ms","start":"2026-05-07T00:17:04.900161Z","end":"2026-05-07T00:17:05.246941Z","steps":["trace[1476816985] 'process raft request' (duration: 345.571963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:05.247011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.90015Z","time spent":"346.831038ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:17:05.247146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.310798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:17:05.247269Z","caller":"traceutil/trace.go:171","msg":"trace[759108451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"345.42564ms","start":"2026-05-07T00:17:04.901747Z","end":"2026-05-07T00:17:05.247172Z","steps":["trace[759108451] 'agreement among raft nodes before linearized reading' (duration: 345.367379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:05.247305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:04.901736Z","time spent":"345.560082ms","remote":"127.0.0.1:44626","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-05-07T00:17:05.278043Z","caller":"traceutil/trace.go:171","msg":"trace[1259558569] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"131.791691ms","start":"2026-05-07T00:17:05.146204Z","end":"2026-05-07T00:17:05.277996Z","steps":["trace[1259558569] 'process raft request' (duration: 131.545697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:05.278268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.17377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-07T00:17:05.278343Z","caller":"traceutil/trace.go:171","msg":"trace[242322637] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1321; }","duration":"116.311663ms","start":"2026-05-07T00:17:05.162012Z","end":"2026-05-07T00:17:05.278324Z","steps":["trace[242322637] 'agreement among raft nodes before linearized reading' (duration: 116.059428ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:05.421296Z","caller":"traceutil/trace.go:171","msg":"trace[505660264] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"135.173565ms","start":"2026-05-07T00:17:05.28609Z","end":"2026-05-07T00:17:05.421263Z","steps":["trace[505660264] 'process raft request' (duration: 135.061263ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:05.421305Z","caller":"traceutil/trace.go:171","msg":"trace[674012448] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"137.937026ms","start":"2026-05-07T00:17:05.283307Z","end":"2026-05-07T00:17:05.421244Z","steps":["trace[674012448] 'process raft request' (duration: 74.615563ms)","trace[674012448] 'compare' (duration: 62.941105ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:17:05.708039Z","caller":"traceutil/trace.go:171","msg":"trace[1618727865] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"281.256552ms","start":"2026-05-07T00:17:05.426751Z","end":"2026-05-07T00:17:05.708008Z","steps":["trace[1618727865] 'read index received' (duration: 200.495854ms)","trace[1618727865] 'applied index is now lower than readState.Index' (duration: 80.759548ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:17:05.708102Z","caller":"traceutil/trace.go:171","msg":"trace[690978292] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"328.775009ms","start":"2026-05-07T00:17:05.379282Z","end":"2026-05-07T00:17:05.708057Z","steps":["trace[690978292] 'process raft request' (duration: 247.94532ms)","trace[690978292] 'compare' (duration: 80.616345ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:05.708301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:05.379257Z","time spent":"328.948363ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:17:05.708347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.620909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-07T00:17:05.708396Z","caller":"traceutil/trace.go:171","msg":"trace[1236834385] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1324; }","duration":"281.786102ms","start":"2026-05-07T00:17:05.426593Z","end":"2026-05-07T00:17:05.708379Z","steps":["trace[1236834385] 'agreement among raft nodes before linearized reading' (duration: 281.569898ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:06.145068Z","caller":"traceutil/trace.go:171","msg":"trace[1482125073] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"715.513002ms","start":"2026-05-07T00:17:05.429522Z","end":"2026-05-07T00:17:06.145035Z","steps":["trace[1482125073] 'process raft request' (duration: 674.846232ms)","trace[1482125073] 'compare' (duration: 40.488246ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:06.145244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:05.429499Z","time spent":"715.665964ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:17:07.493254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.027468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:17:07.493418Z","caller":"traceutil/trace.go:171","msg":"trace[1018781348] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"1.777392302s","start":"2026-05-07T00:17:05.716012Z","end":"2026-05-07T00:17:07.493405Z","steps":["trace[1018781348] 'process raft request' (duration: 1.777336351s)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:07.493417Z","caller":"traceutil/trace.go:171","msg":"trace[1404967287] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1379; }","duration":"935.346477ms","start":"2026-05-07T00:17:06.558057Z","end":"2026-05-07T00:17:07.493403Z","steps":["trace[1404967287] 'read index received' (duration: 201.033193ms)","trace[1404967287] 'applied index is now lower than readState.Index' (duration: 734.312284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:07.493467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:05.715972Z","time spent":"1.777471034s","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":843,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:17:07.49351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.46185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:17:07.493534Z","caller":"traceutil/trace.go:171","msg":"trace[1763037736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"935.542831ms","start":"2026-05-07T00:17:06.557984Z","end":"2026-05-07T00:17:07.493526Z","steps":["trace[1763037736] 'agreement among raft nodes before linearized reading' (duration: 935.4823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:07.493569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:06.557835Z","time spent":"935.728325ms","remote":"127.0.0.1:44626","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-05-07T00:17:07.493667Z","caller":"traceutil/trace.go:171","msg":"trace[1604901818] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"1.778510723s","start":"2026-05-07T00:17:05.715147Z","end":"2026-05-07T00:17:07.493657Z","steps":["trace[1604901818] 'process raft request' (duration: 1.044001815s)","trace[1604901818] 'compare' (duration: 733.915446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:07.493706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:05.715122Z","time spent":"1.778566863s","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:07.507394Z","caller":"traceutil/trace.go:171","msg":"trace[1147093589] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"604.110432ms","start":"2026-05-07T00:17:06.903259Z","end":"2026-05-07T00:17:07.50737Z","steps":["trace[1147093589] 'process raft request' (duration: 604.00349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:07.507525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:06.90323Z","time spent":"604.227775ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:07.561148Z","caller":"traceutil/trace.go:171","msg":"trace[724737586] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"354.685783ms","start":"2026-05-07T00:17:07.206445Z","end":"2026-05-07T00:17:07.561131Z","steps":["trace[724737586] 'process raft request' (duration: 354.394438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:07.561242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.206419Z","time spent":"354.778675ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:17:07.56148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.943081ms","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-05-07T00:17:07.561521Z","caller":"traceutil/trace.go:171","msg":"trace[1161751955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1329; }","duration":"306.992462ms","start":"2026-05-07T00:17:07.254518Z","end":"2026-05-07T00:17:07.56151Z","steps":["trace[1161751955] 'agreement among raft nodes before linearized reading' (duration: 306.86437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:07.561557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.254512Z","time spent":"307.039423ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T00:17:07.561739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.816097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T00:17:07.561773Z","caller":"traceutil/trace.go:171","msg":"trace[128303440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1329; }","duration":"307.869049ms","start":"2026-05-07T00:17:07.253896Z","end":"2026-05-07T00:17:07.561765Z","steps":["trace[128303440] 'agreement among raft nodes before linearized reading' (duration: 307.784537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:07.561799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.253886Z","time spent":"307.907019ms","remote":"127.0.0.1:44944","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-05-07T00:17:07.561964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.504002ms","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-05-07T00:17:07.561999Z","caller":"traceutil/trace.go:171","msg":"trace[1901215412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1329; }","duration":"308.566993ms","start":"2026-05-07T00:17:07.253424Z","end":"2026-05-07T00:17:07.561991Z","steps":["trace[1901215412] 'agreement among raft nodes before linearized reading' (duration: 308.483471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:07.562025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.253406Z","time spent":"308.613183ms","remote":"127.0.0.1:44944","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-05-07T00:17:07.562187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.806167ms","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-05-07T00:17:07.56222Z","caller":"traceutil/trace.go:171","msg":"trace[1137386842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1329; }","duration":"308.858218ms","start":"2026-05-07T00:17:07.253354Z","end":"2026-05-07T00:17:07.562213Z","steps":["trace[1137386842] 'agreement among raft nodes before linearized reading' (duration: 308.774816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:07.562245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.253347Z","time spent":"308.892178ms","remote":"127.0.0.1:44944","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-05-07T00:17:07.562626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.383568ms","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-05-07T00:17:07.562729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.638691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:17:07.562781Z","caller":"traceutil/trace.go:171","msg":"trace[1646951105] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1329; }","duration":"831.692252ms","start":"2026-05-07T00:17:06.731071Z","end":"2026-05-07T00:17:07.562763Z","steps":["trace[1646951105] 'agreement among raft nodes before linearized reading' (duration: 831.61332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:07.562816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:06.730998Z","time spent":"831.809024ms","remote":"127.0.0.1:60516","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/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-05-07T00:17:07.562731Z","caller":"traceutil/trace.go:171","msg":"trace[1207165415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1329; }","duration":"309.535341ms","start":"2026-05-07T00:17:07.253168Z","end":"2026-05-07T00:17:07.562704Z","steps":["trace[1207165415] 'agreement among raft nodes before linearized reading' (duration: 309.249396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:07.56301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.253152Z","time spent":"309.847756ms","remote":"127.0.0.1:44944","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-05-07T00:17:08.072331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303572743165772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T00:17:08.572911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303572743165772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T00:17:08.624991Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.056545571s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T00:17:08.62578Z","caller":"traceutil/trace.go:171","msg":"trace[608740047] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"1.057302295s","start":"2026-05-07T00:17:07.568337Z","end":"2026-05-07T00:17:08.625639Z","steps":["trace[608740047] 'process raft request' (duration: 1.056970639s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:08.627018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.568319Z","time spent":"1.058626469s","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:17:08.847232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.999339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:17:08.847461Z","caller":"traceutil/trace.go:171","msg":"trace[1371798707] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"1.278868553s","start":"2026-05-07T00:17:07.568563Z","end":"2026-05-07T00:17:08.847431Z","steps":["trace[1371798707] 'process raft request' (duration: 1.119457096s)","trace[1371798707] 'compare' (duration: 158.268986ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:08.847614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.568557Z","time spent":"1.278973945s","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:08.847945Z","caller":"traceutil/trace.go:171","msg":"trace[13657680] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1383; }","duration":"1.275646342s","start":"2026-05-07T00:17:07.572277Z","end":"2026-05-07T00:17:08.847924Z","steps":["trace[13657680] 'read index received' (duration: 1.052919313s)","trace[13657680] 'applied index is now lower than readState.Index' (duration: 222.725699ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:17:08.847994Z","caller":"traceutil/trace.go:171","msg":"trace[648682031] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"1.279356232s","start":"2026-05-07T00:17:07.568625Z","end":"2026-05-07T00:17:08.847981Z","steps":["trace[648682031] 'process raft request' (duration: 1.278722971s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:08.848059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.568619Z","time spent":"1.279405483s","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:08.848066Z","caller":"traceutil/trace.go:171","msg":"trace[1931326737] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"1.279348911s","start":"2026-05-07T00:17:07.568705Z","end":"2026-05-07T00:17:08.848054Z","steps":["trace[1931326737] 'process raft request' (duration: 1.279020776s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:08.848143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.568675Z","time spent":"1.279425023s","remote":"127.0.0.1:44944","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-05-07T00:17:08.848287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276004389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T00:17:08.848309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.05477ms","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-05-07T00:17:08.848324Z","caller":"traceutil/trace.go:171","msg":"trace[955810889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"1.27605449s","start":"2026-05-07T00:17:07.572258Z","end":"2026-05-07T00:17:08.848313Z","steps":["trace[955810889] 'agreement among raft nodes before linearized reading' (duration: 1.275980399s)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:08.848339Z","caller":"traceutil/trace.go:171","msg":"trace[1975988383] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1334; }","duration":"431.08561ms","start":"2026-05-07T00:17:08.417243Z","end":"2026-05-07T00:17:08.848328Z","steps":["trace[1975988383] 'agreement among raft nodes before linearized reading' (duration: 431.028329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:08.848354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.57225Z","time spent":"1.276098621s","remote":"127.0.0.1:44626","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-05-07T00:17:08.84837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:08.417184Z","time spent":"431.178452ms","remote":"127.0.0.1:44848","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":"info","ts":"2026-05-07T00:17:08.848514Z","caller":"traceutil/trace.go:171","msg":"trace[2117450721] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"1.279593605s","start":"2026-05-07T00:17:07.56891Z","end":"2026-05-07T00:17:08.848504Z","steps":["trace[2117450721] 'process raft request' (duration: 1.278960004s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:08.84857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:07.568899Z","time spent":"1.279639056s","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:15.897114Z","caller":"traceutil/trace.go:171","msg":"trace[1595337146] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"895.729637ms","start":"2026-05-07T00:17:15.001331Z","end":"2026-05-07T00:17:15.897061Z","steps":["trace[1595337146] 'process raft request' (duration: 895.414551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:15.897357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:15.001287Z","time spent":"895.930061ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13708,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:17:15.89746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.912312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-07T00:17:15.897564Z","caller":"traceutil/trace.go:171","msg":"trace[547636627] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1376; }","duration":"457.079675ms","start":"2026-05-07T00:17:15.440462Z","end":"2026-05-07T00:17:15.897541Z","steps":["trace[547636627] 'agreement among raft nodes before linearized reading' (duration: 456.736779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:15.897616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:15.44044Z","time spent":"457.167267ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-07T00:17:15.897021Z","caller":"traceutil/trace.go:171","msg":"trace[723928790] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"456.470594ms","start":"2026-05-07T00:17:15.440516Z","end":"2026-05-07T00:17:15.896987Z","steps":["trace[723928790] 'read index received' (duration: 456.363212ms)","trace[723928790] 'applied index is now lower than readState.Index' (duration: 105.762µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:17.106903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159514584s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:17:17.107213Z","caller":"traceutil/trace.go:171","msg":"trace[1982107862] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"1.209280104s","start":"2026-05-07T00:17:15.897726Z","end":"2026-05-07T00:17:17.107006Z","steps":["trace[1982107862] 'read index received' (duration: 49.492364ms)","trace[1982107862] 'applied index is now lower than readState.Index' (duration: 1.15978623s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:17.10743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274285477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T00:17:17.107893Z","caller":"traceutil/trace.go:171","msg":"trace[1714889131] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1377; }","duration":"1.274816827s","start":"2026-05-07T00:17:15.833054Z","end":"2026-05-07T00:17:17.10787Z","steps":["trace[1714889131] 'agreement among raft nodes before linearized reading' (duration: 1.274309428s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.108059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:15.833017Z","time spent":"1.275018812s","remote":"127.0.0.1:57934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-07T00:17:17.107641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550406354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:17:17.10848Z","caller":"traceutil/trace.go:171","msg":"trace[794564817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1377; }","duration":"1.55129688s","start":"2026-05-07T00:17:15.557155Z","end":"2026-05-07T00:17:17.108452Z","steps":["trace[794564817] 'agreement among raft nodes before linearized reading' (duration: 1.550326822s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.108557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:15.557132Z","time spent":"1.551407773s","remote":"127.0.0.1:44626","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-05-07T00:17:17.107583Z","caller":"traceutil/trace.go:171","msg":"trace[1050426095] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"1.527311093s","start":"2026-05-07T00:17:15.580231Z","end":"2026-05-07T00:17:17.107542Z","steps":["trace[1050426095] 'process raft request' (duration: 367.048405ms)","trace[1050426095] 'compare' (duration: 1.158961214s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:17.109087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:15.580212Z","time spent":"1.528471164s","remote":"127.0.0.1:44944","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-05-07T00:17:17.148284Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.201148652s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T00:17:17.148689Z","caller":"traceutil/trace.go:171","msg":"trace[764826743] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"1.243804889s","start":"2026-05-07T00:17:15.904861Z","end":"2026-05-07T00:17:17.148665Z","steps":["trace[764826743] 'process raft request' (duration: 1.243611885s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.148789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:15.904832Z","time spent":"1.243909401s","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:17.14898Z","caller":"traceutil/trace.go:171","msg":"trace[2074732901] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"1.243561034s","start":"2026-05-07T00:17:15.905401Z","end":"2026-05-07T00:17:17.148962Z","steps":["trace[2074732901] 'process raft request' (duration: 1.243206087s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.149099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:15.905392Z","time spent":"1.243641425s","remote":"127.0.0.1:44846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:17.149181Z","caller":"traceutil/trace.go:171","msg":"trace[1062402165] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"1.243699886s","start":"2026-05-07T00:17:15.905466Z","end":"2026-05-07T00:17:17.149165Z","steps":["trace[1062402165] 'process raft request' (duration: 1.243408471s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.151215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:15.90544Z","time spent":"1.245614641s","remote":"127.0.0.1:44958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:17.149456Z","caller":"traceutil/trace.go:171","msg":"trace[1560976806] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"1.240417925s","start":"2026-05-07T00:17:15.909025Z","end":"2026-05-07T00:17:17.149443Z","steps":["trace[1560976806] 'process raft request' (duration: 1.240064099s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.15151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:15.909014Z","time spent":"1.242410093s","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10921,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:17:17.149556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.19454ms","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-05-07T00:17:17.151645Z","caller":"traceutil/trace.go:171","msg":"trace[483883751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1381; }","duration":"261.30244ms","start":"2026-05-07T00:17:16.890326Z","end":"2026-05-07T00:17:17.151628Z","steps":["trace[483883751] 'agreement among raft nodes before linearized reading' (duration: 259.031587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.149555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.816481ms","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-05-07T00:17:17.151873Z","caller":"traceutil/trace.go:171","msg":"trace[1038363191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1381; }","duration":"262.178406ms","start":"2026-05-07T00:17:16.889672Z","end":"2026-05-07T00:17:17.151851Z","steps":["trace[1038363191] 'agreement among raft nodes before linearized reading' (duration: 259.78044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.149579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.382591ms","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-05-07T00:17:17.151988Z","caller":"traceutil/trace.go:171","msg":"trace[1875157489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1381; }","duration":"489.873898ms","start":"2026-05-07T00:17:16.662105Z","end":"2026-05-07T00:17:17.151979Z","steps":["trace[1875157489] 'agreement among raft nodes before linearized reading' (duration: 487.279729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.152024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:16.662083Z","time spent":"489.92788ms","remote":"127.0.0.1:44944","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-05-07T00:17:17.149674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.960991ms","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-05-07T00:17:17.152165Z","caller":"traceutil/trace.go:171","msg":"trace[715842238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1381; }","duration":"265.502179ms","start":"2026-05-07T00:17:16.886653Z","end":"2026-05-07T00:17:17.152155Z","steps":["trace[715842238] 'agreement among raft nodes before linearized reading' (duration: 262.961131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.149675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.330293ms","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-05-07T00:17:17.152318Z","caller":"traceutil/trace.go:171","msg":"trace[541215064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1381; }","duration":"261.017894ms","start":"2026-05-07T00:17:16.891282Z","end":"2026-05-07T00:17:17.1523Z","steps":["trace[541215064] 'agreement among raft nodes before linearized reading' (duration: 258.312753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.14978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14219883s","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-05-07T00:17:17.152451Z","caller":"traceutil/trace.go:171","msg":"trace[1416999628] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1381; }","duration":"1.144929121s","start":"2026-05-07T00:17:16.007502Z","end":"2026-05-07T00:17:17.152431Z","steps":["trace[1416999628] 'agreement among raft nodes before linearized reading' (duration: 1.14222205s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.152519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:16.007442Z","time spent":"1.145052694s","remote":"127.0.0.1:44872","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-05-07T00:17:17.149818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004324535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-07T00:17:17.152617Z","caller":"traceutil/trace.go:171","msg":"trace[1127299682] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1381; }","duration":"1.007191439s","start":"2026-05-07T00:17:16.145407Z","end":"2026-05-07T00:17:17.152599Z","steps":["trace[1127299682] 'agreement among raft nodes before linearized reading' (duration: 1.004168603s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.152672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:16.145361Z","time spent":"1.007293581s","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-07T00:17:17.486241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.079572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:17:17.486575Z","caller":"traceutil/trace.go:171","msg":"trace[1657834420] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"328.159328ms","start":"2026-05-07T00:17:17.158379Z","end":"2026-05-07T00:17:17.486539Z","steps":["trace[1657834420] 'process raft request' (duration: 154.671728ms)","trace[1657834420] 'compare' (duration: 172.619393ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:17:17.486652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:17.158344Z","time spent":"328.269931ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:17.510088Z","caller":"traceutil/trace.go:171","msg":"trace[579869812] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"351.584805ms","start":"2026-05-07T00:17:17.158475Z","end":"2026-05-07T00:17:17.51006Z","steps":["trace[579869812] 'process raft request' (duration: 351.375552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.51027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:17.158466Z","time spent":"351.706318ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:17.510448Z","caller":"traceutil/trace.go:171","msg":"trace[1458416905] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"351.416443ms","start":"2026-05-07T00:17:17.159Z","end":"2026-05-07T00:17:17.510416Z","steps":["trace[1458416905] 'process raft request' (duration: 351.018016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.510668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:17.158992Z","time spent":"351.562635ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:17.511096Z","caller":"traceutil/trace.go:171","msg":"trace[2145771063] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"351.476354ms","start":"2026-05-07T00:17:17.159596Z","end":"2026-05-07T00:17:17.511073Z","steps":["trace[2145771063] 'process raft request' (duration: 350.70955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.511189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:17.159591Z","time spent":"351.550716ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:17.511268Z","caller":"traceutil/trace.go:171","msg":"trace[717864806] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1437; }","duration":"352.684517ms","start":"2026-05-07T00:17:17.158568Z","end":"2026-05-07T00:17:17.511253Z","steps":["trace[717864806] 'read index received' (duration: 154.481145ms)","trace[717864806] 'applied index is now lower than readState.Index' (duration: 198.202062ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:17:17.511326Z","caller":"traceutil/trace.go:171","msg":"trace[1091280530] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"349.433626ms","start":"2026-05-07T00:17:17.161874Z","end":"2026-05-07T00:17:17.511308Z","steps":["trace[1091280530] 'process raft request' (duration: 349.285112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.511382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:17.161868Z","time spent":"349.482298ms","remote":"127.0.0.1:44944","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-05-07T00:17:17.511516Z","caller":"traceutil/trace.go:171","msg":"trace[1687741672] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"348.776843ms","start":"2026-05-07T00:17:17.162719Z","end":"2026-05-07T00:17:17.511496Z","steps":["trace[1687741672] 'process raft request' (duration: 348.479438ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:17:17.511576Z","caller":"traceutil/trace.go:171","msg":"trace[1342029152] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"351.601326ms","start":"2026-05-07T00:17:17.159961Z","end":"2026-05-07T00:17:17.511563Z","steps":["trace[1342029152] 'process raft request' (duration: 350.635198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.51154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.937611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T00:17:17.511604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:17.162713Z","time spent":"348.846424ms","remote":"127.0.0.1:45170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11231,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:17:17.511631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:17.159957Z","time spent":"351.644477ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:17:17.51164Z","caller":"traceutil/trace.go:171","msg":"trace[1295571263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1388; }","duration":"353.085893ms","start":"2026-05-07T00:17:17.15853Z","end":"2026-05-07T00:17:17.511616Z","steps":["trace[1295571263] 'agreement among raft nodes before linearized reading' (duration: 352.89567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:17.51172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:17:17.158514Z","time spent":"353.168126ms","remote":"127.0.0.1:44626","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-05-07T00:17:51.838554Z","caller":"traceutil/trace.go:171","msg":"trace[1583083297] linearizableReadLoop","detail":"{readStateIndex:1605; appliedIndex:1604; }","duration":"112.600153ms","start":"2026-05-07T00:17:51.725911Z","end":"2026-05-07T00:17:51.838511Z","steps":["trace[1583083297] 'read index received' (duration: 105.180544ms)","trace[1583083297] 'applied index is now lower than readState.Index' (duration: 7.418099ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:17:51.838794Z","caller":"traceutil/trace.go:171","msg":"trace[458268995] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"142.450621ms","start":"2026-05-07T00:17:51.69633Z","end":"2026-05-07T00:17:51.83878Z","steps":["trace[458268995] 'process raft request' (duration: 134.830648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:51.83914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.153543ms","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-05-07T00:17:51.839267Z","caller":"traceutil/trace.go:171","msg":"trace[365792487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1542; }","duration":"113.367577ms","start":"2026-05-07T00:17:51.725865Z","end":"2026-05-07T00:17:51.839233Z","steps":["trace[365792487] 'agreement among raft nodes before linearized reading' (duration: 113.034101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:17:51.839276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.345867ms","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-05-07T00:17:51.83936Z","caller":"traceutil/trace.go:171","msg":"trace[1084092460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1542; }","duration":"113.54536ms","start":"2026-05-07T00:17:51.725795Z","end":"2026-05-07T00:17:51.83934Z","steps":["trace[1084092460] 'agreement among raft nodes before linearized reading' (duration: 113.217214ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:18:02.793518Z","caller":"traceutil/trace.go:171","msg":"trace[1492940137] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"104.272425ms","start":"2026-05-07T00:18:02.689215Z","end":"2026-05-07T00:18:02.793487Z","steps":["trace[1492940137] 'process raft request' (duration: 49.063395ms)","trace[1492940137] 'compare' (duration: 54.850393ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:18:04.248373Z","caller":"traceutil/trace.go:171","msg":"trace[1900298464] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"187.036097ms","start":"2026-05-07T00:18:04.061302Z","end":"2026-05-07T00:18:04.248338Z","steps":["trace[1900298464] 'process raft request' (duration: 186.791223ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:18:06.109619Z","caller":"traceutil/trace.go:171","msg":"trace[2100943218] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"132.494939ms","start":"2026-05-07T00:18:05.977091Z","end":"2026-05-07T00:18:06.109586Z","steps":["trace[2100943218] 'process raft request' (duration: 132.258265ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:18:16.348622Z","caller":"traceutil/trace.go:171","msg":"trace[837169076] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"139.519931ms","start":"2026-05-07T00:18:16.209052Z","end":"2026-05-07T00:18:16.348572Z","steps":["trace[837169076] 'process raft request' (duration: 92.066136ms)","trace[837169076] 'compare' (duration: 47.17799ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:18:16.348838Z","caller":"traceutil/trace.go:171","msg":"trace[449016604] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"139.148933ms","start":"2026-05-07T00:18:16.209527Z","end":"2026-05-07T00:18:16.348676Z","steps":["trace[449016604] 'process raft request' (duration: 138.95035ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:18:16.348705Z","caller":"traceutil/trace.go:171","msg":"trace[1549525781] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"138.639194ms","start":"2026-05-07T00:18:16.209979Z","end":"2026-05-07T00:18:16.348618Z","steps":["trace[1549525781] 'process raft request' (duration: 138.567463ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:18:17.463036Z","caller":"traceutil/trace.go:171","msg":"trace[2128726099] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"107.947622ms","start":"2026-05-07T00:18:17.355052Z","end":"2026-05-07T00:18:17.463Z","steps":["trace[2128726099] 'process raft request' (duration: 107.756849ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:18:23.083025Z","caller":"traceutil/trace.go:171","msg":"trace[262354908] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"197.805376ms","start":"2026-05-07T00:18:22.885173Z","end":"2026-05-07T00:18:23.082979Z","steps":["trace[262354908] 'process raft request' (duration: 197.573392ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:18:28.401956Z","caller":"traceutil/trace.go:171","msg":"trace[1637026114] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"127.525396ms","start":"2026-05-07T00:18:28.274402Z","end":"2026-05-07T00:18:28.401927Z","steps":["trace[1637026114] 'process raft request' (duration: 127.370943ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:19:02.677024Z","caller":"traceutil/trace.go:171","msg":"trace[452397985] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"113.709471ms","start":"2026-05-07T00:19:02.563274Z","end":"2026-05-07T00:19:02.676983Z","steps":["trace[452397985] 'process raft request' (duration: 112.904765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:19:02.944787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.467751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:19:02.944903Z","caller":"traceutil/trace.go:171","msg":"trace[2040513238] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"263.511771ms","start":"2026-05-07T00:19:02.681375Z","end":"2026-05-07T00:19:02.944886Z","steps":["trace[2040513238] 'process raft request' (duration: 104.871867ms)","trace[2040513238] 'compare' (duration: 158.241977ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:19:02.96615Z","caller":"traceutil/trace.go:171","msg":"trace[1283441614] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"284.317087ms","start":"2026-05-07T00:19:02.681792Z","end":"2026-05-07T00:19:02.96611Z","steps":["trace[1283441614] 'process raft request' (duration: 284.230866ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:19:02.966411Z","caller":"traceutil/trace.go:171","msg":"trace[1388985063] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1941; }","duration":"278.908067ms","start":"2026-05-07T00:19:02.687473Z","end":"2026-05-07T00:19:02.966381Z","steps":["trace[1388985063] 'read index received' (duration: 98.760374ms)","trace[1388985063] 'applied index is now lower than readState.Index' (duration: 180.146563ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:19:02.966556Z","caller":"traceutil/trace.go:171","msg":"trace[1894650487] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"284.804828ms","start":"2026-05-07T00:19:02.681707Z","end":"2026-05-07T00:19:02.966511Z","steps":["trace[1894650487] 'process raft request' (duration: 284.128394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:19:02.966808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.927547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-07T00:19:02.966854Z","caller":"traceutil/trace.go:171","msg":"trace[1237806026] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1868; }","duration":"113.058669ms","start":"2026-05-07T00:19:02.853776Z","end":"2026-05-07T00:19:02.966835Z","steps":["trace[1237806026] 'agreement among raft nodes before linearized reading' (duration: 112.882106ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:19:02.966844Z","caller":"traceutil/trace.go:171","msg":"trace[1574565814] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"284.774827ms","start":"2026-05-07T00:19:02.682005Z","end":"2026-05-07T00:19:02.96678Z","steps":["trace[1574565814] 'process raft request' (duration: 284.062454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:19:02.967069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.57421ms","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-05-07T00:19:02.96711Z","caller":"traceutil/trace.go:171","msg":"trace[1851248010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1868; }","duration":"279.684482ms","start":"2026-05-07T00:19:02.687414Z","end":"2026-05-07T00:19:02.967099Z","steps":["trace[1851248010] 'agreement among raft nodes before linearized reading' (duration: 279.514279ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:20:39.256885Z","caller":"traceutil/trace.go:171","msg":"trace[937415248] linearizableReadLoop","detail":"{readStateIndex:2435; appliedIndex:2434; }","duration":"361.300769ms","start":"2026-05-07T00:20:38.895558Z","end":"2026-05-07T00:20:39.256859Z","steps":["trace[937415248] 'read index received' (duration: 361.027954ms)","trace[937415248] 'applied index is now lower than readState.Index' (duration: 271.805µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:20:39.257169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.692073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:20:39.257229Z","caller":"traceutil/trace.go:171","msg":"trace[1855190712] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2338; }","duration":"185.805045ms","start":"2026-05-07T00:20:39.07141Z","end":"2026-05-07T00:20:39.257215Z","steps":["trace[1855190712] 'agreement among raft nodes before linearized reading' (duration: 185.719324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:20:39.257273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.839188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:20:39.257314Z","caller":"traceutil/trace.go:171","msg":"trace[1548969150] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2338; }","duration":"150.89993ms","start":"2026-05-07T00:20:39.106402Z","end":"2026-05-07T00:20:39.257302Z","steps":["trace[1548969150] 'agreement among raft nodes before linearized reading' (duration: 150.824589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:20:39.257151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.571784ms","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-05-07T00:20:39.25708Z","caller":"traceutil/trace.go:171","msg":"trace[629001101] transaction","detail":"{read_only:false; response_revision:2338; number_of_response:1; }","duration":"435.756465ms","start":"2026-05-07T00:20:38.821266Z","end":"2026-05-07T00:20:39.257023Z","steps":["trace[629001101] 'process raft request' (duration: 435.429339ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:20:39.25749Z","caller":"traceutil/trace.go:171","msg":"trace[133938028] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2338; }","duration":"361.948741ms","start":"2026-05-07T00:20:38.895502Z","end":"2026-05-07T00:20:39.25745Z","steps":["trace[133938028] 'agreement among raft nodes before linearized reading' (duration: 361.541413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:20:39.257622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:20:38.895488Z","time spent":"362.089113ms","remote":"127.0.0.1:57900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":5,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-07T00:20:39.257796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:20:38.821222Z","time spent":"436.318574ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:20:39.257821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.658803ms","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":"info","ts":"2026-05-07T00:20:39.257906Z","caller":"traceutil/trace.go:171","msg":"trace[1032682487] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2338; }","duration":"178.739655ms","start":"2026-05-07T00:20:39.079124Z","end":"2026-05-07T00:20:39.257864Z","steps":["trace[1032682487] 'agreement among raft nodes before linearized reading' (duration: 178.660184ms)"],"step_count":1}